504 Gateway timeout between remote nginx server and docker upstream nginx server

docker

(Sascha Endlicher, M.A.) #1

Hello,

I have set up a docker instance of discourse for the domain ${discourse.mydomain.com} on ${DOCKERHOSTPUBLICIP}:8081. I can access ${DOCKERHOSTPUBLICIP}:8081 directly via the public internet from my IP ${REALIP} just fine.

The remote nginx server for ${discourse.mydomain.com} is sitting on a different physical host node.
When I try to access the docker instance via the server name, I always see a “504 Gateway Time-out” in the browser.
The remote nginx server’s error log says:

2015/11/24 10:42:48 [error] 18426#0: *82 upstream timed out (110: Connection timed out) while connecting to upstream, client: ${REALIP}, server: ${discourse.mydomain.com}, request: “GET / HTTP/1.1”, upstream: “http://${DOCKERHOSTPUBLICIP}:8081/”, host: “${discourse.mydomain.com}”

I have also enabled debug mode on the docker instance nginx. The log shows:

2015/11/24 10:42:05 [debug] 14664#14664: *1 post event 000055D7F2FD48A0
2015/11/24 10:42:05 [debug] 14664#14664: *1 post event 000055D7F2FE68B0
2015/11/24 10:42:05 [debug] 14664#14664: *1 delete posted event 000055D7F2FD48A0
2015/11/24 10:42:05 [debug] 14664#14664: *1 http keepalive handler
2015/11/24 10:42:05 [debug] 14664#14664: *1 malloc: 000055D7F2F9D9E0:1024
2015/11/24 10:42:05 [debug] 14664#14664: *1 recv: fd:11 1024 of 1024
2015/11/24 10:42:05 [debug] 14664#14664: *1 reusable connection: 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 posix_memalign: 000055D7F2F5E3B0:4096 @16
2015/11/24 10:42:05 [debug] 14664#14664: *1 event timer del: 11: 1448361765403
2015/11/24 10:42:05 [debug] 14664#14664: *1 http process request line
2015/11/24 10:42:05 [debug] 14664#14664: *1 http request line: "POST /message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll?dlp=t HTTP/1.1"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http uri: "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http args: "dlp=t"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http exten: ""
2015/11/24 10:42:05 [debug] 14664#14664: *1 posix_memalign: 000055D7F2FA2B60:4096 @16
2015/11/24 10:42:05 [debug] 14664#14664: *1 http process request header line
2015/11/24 10:42:05 [debug] 14664#14664: *1 http header: "Host: ${DOCKERHOSTPUBLICIP}:8081"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http header: "X-SILENCE-LOGGER: true"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http header: "Accept: application/json, text/javascript, */*; q=0.01"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http header: "X-Requested-With: XMLHttpRequest"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http header: "Accept-Encoding: gzip, deflate"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http header: "Accept-Language: de-de"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http header: "Content-Type: application/x-www-form-urlencoded; charset=UTF-8"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http header: "Origin: http://${DOCKERHOSTPUBLICIP}:8081"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http header: "User-Agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_1) AppleWebKit/601.2.7 (KHTML, like Gecko) Version/9.0.1 Safari/601.2.7"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http header: "Referer: http://${DOCKERHOSTPUBLICIP}:8081/"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http header: "Content-Length: 273"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http header: "Connection: keep-alive"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http header: "X-CSRF-Token: +xWuY6h0tt+ZImftVMAF6mNxGqRjYaf7BXmGqBVeE05qymB9d5B+1RZOvgiGGOEvcX99ZwGI2kXevvhnfcydcw=="
2015/11/24 10:42:05 [debug] 14664#14664: *1 http alloc large header buffer
2015/11/24 10:42:05 [debug] 14664#14664: *1 posix_memalign: 000055D7F2F71F50:256 @16
2015/11/24 10:42:05 [debug] 14664#14664: *1 malloc: 000055D7F2FA3B70:8192
2015/11/24 10:42:05 [debug] 14664#14664: *1 http large header alloc: 000055D7F2FA3B70 8192
2015/11/24 10:42:05 [debug] 14664#14664: *1 http large header copy: 344
2015/11/24 10:42:05 [debug] 14664#14664: *1 recv: fd:11 553 of 7848
2015/11/24 10:42:05 [debug] 14664#14664: *1 http header: "Cookie: __profilin=p%3Dt; _forum_session=c010T0VhaUp3ZXhqeHdTRjdlUEY5WjZiZHZhWlVLeStkTllMbTl6SE4xSVVjRW52U1JIRENHdXVWeW5LZjRvRTNjcThlZTFkaEx3YmJpd3hYQXRaRVFFMnhsc0t5cnB5SWJ3L2QvWU51TWlLQW9ZUjdlVUYwTUxDUlV3MCtCWkFIdkk3MWVBNmZtbkFIK3pYdmU3eXJQQ0FmdkFLV1J1cHk3Z0YwNjdxNmtBR0xSYndVZloyQWlLWDFHMDh4bDlJaDJwclJDZnNWNUxydjJNelIyajhBZmYyYXg4WVVEOEl6bkpWWTBKdUp6UmUvSmc1VGNHVEZIYWxZZlZqY2swek1RSTdpVFl0Z3hkT0FTdHJOODlaeW1RNllaaDdzUE5NdGZsRktjUllDc1duaEdMZUhIZ3lvQzk3ekVLUC9NUmlmdE1qenllZisyalhxVGVSSW5GbjdRPT0tLVlkd0lFK1MrV1lGT2lybXNOR21kMVE9PQ%3D%3D--972318ebd22d8dc061539b1d27296131701780df; _t=6dc76295818925ff8e536e7c985d2303"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http header done
2015/11/24 10:42:05 [debug] 14664#14664: *1 generic phase: 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 rewrite phase: 1
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script value: "/var/www/discourse/public"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script set $public
2015/11/24 10:42:05 [debug] 14664#14664: *1 test location: "/"
2015/11/24 10:42:05 [debug] 14664#14664: *1 test location: "downloads/"
2015/11/24 10:42:05 [debug] 14664#14664: *1 test location: "favicon.ico"
2015/11/24 10:42:05 [debug] 14664#14664: *1 test location: "/srv/status"
2015/11/24 10:42:05 [debug] 14664#14664: *1 test location: "/letter_avatar_proxy/"
2015/11/24 10:42:05 [debug] 14664#14664: *1 test location: ~ "assets/.*\.(eot|ttf|woff|woff2|ico)$"
2015/11/24 10:42:05 [debug] 14664#14664: *1 test location: ~ "^/assets/"
2015/11/24 10:42:05 [debug] 14664#14664: *1 test location: ~ "^/plugins/"
2015/11/24 10:42:05 [debug] 14664#14664: *1 test location: ~ "/_?emoji.*\.(png|gif|jpg|jpeg)$/"
2015/11/24 10:42:05 [debug] 14664#14664: *1 test location: ~ "^/uploads/"
2015/11/24 10:42:05 [debug] 14664#14664: *1 test location: ~ "^/admin/backups/"
2015/11/24 10:42:05 [debug] 14664#14664: *1 test location: ~ "^/(letter_avatar/|user_avatar|highlight-js|stylesheets|favicon/proxied)"
2015/11/24 10:42:05 [debug] 14664#14664: *1 using configuration "/"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http cl:273 max:10485760
2015/11/24 10:42:05 [debug] 14664#14664: *1 rewrite phase: 3
2015/11/24 10:42:05 [debug] 14664#14664: *1 post rewrite phase: 4
2015/11/24 10:42:05 [debug] 14664#14664: *1 generic phase: 5
2015/11/24 10:42:05 [debug] 14664#14664: *1 generic phase: 6
2015/11/24 10:42:05 [debug] 14664#14664: *1 generic phase: 7
2015/11/24 10:42:05 [debug] 14664#14664: *1 access phase: 8
2015/11/24 10:42:05 [debug] 14664#14664: *1 access phase: 9
2015/11/24 10:42:05 [debug] 14664#14664: *1 access phase: 10
2015/11/24 10:42:05 [debug] 14664#14664: *1 access phase: 11
2015/11/24 10:42:05 [debug] 14664#14664: *1 post access phase: 12
2015/11/24 10:42:05 [debug] 14664#14664: *1 try files phase: 13
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script var: "/var/www/discourse/public"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script var: "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll"
2015/11/24 10:42:05 [debug] 14664#14664: *1 trying to use file: "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll" "/var/www/discourse/public/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll"
2015/11/24 10:42:05 [debug] 14664#14664: *1 trying to use file: "@discourse" "/var/www/discourse/public@discourse"
2015/11/24 10:42:05 [debug] 14664#14664: *1 test location: "@discourse"
2015/11/24 10:42:05 [debug] 14664#14664: *1 using location: @discourse "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll?dlp=t"
2015/11/24 10:42:05 [debug] 14664#14664: *1 rewrite phase: 3
2015/11/24 10:42:05 [debug] 14664#14664: *1 post rewrite phase: 4
2015/11/24 10:42:05 [debug] 14664#14664: *1 generic phase: 5
2015/11/24 10:42:05 [debug] 14664#14664: *1 generic phase: 6
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script var: "<B2><C8><CD>1"
2015/11/24 10:42:05 [debug] 14664#14664: *1 limit_req[0]: -2 0.000
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script var: "<B2><C8><CD>1"
2015/11/24 10:42:05 [debug] 14664#14664: *1 limit_req[1]: 0 0.000
2015/11/24 10:42:05 [debug] 14664#14664: *1 generic phase: 7
2015/11/24 10:42:05 [debug] 14664#14664: *1 access phase: 8
2015/11/24 10:42:05 [debug] 14664#14664: *1 access phase: 9
2015/11/24 10:42:05 [debug] 14664#14664: *1 access phase: 10
2015/11/24 10:42:05 [debug] 14664#14664: *1 access phase: 11
2015/11/24 10:42:05 [debug] 14664#14664: *1 post access phase: 12
2015/11/24 10:42:05 [debug] 14664#14664: *1 try files phase: 13
2015/11/24 10:42:05 [debug] 14664#14664: *1 http client request body preread 273
2015/11/24 10:42:05 [debug] 14664#14664: *1 http request body content length filter
2015/11/24 10:42:05 [debug] 14664#14664: *1 http body new buf t:1 f:0 000055D7F2FA3DE0, pos 000055D7F2FA3DE0, size: 273 file: 0, size: 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 http init upstream, client timer: 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 http map started
2015/11/24 10:42:05 [debug] 14664#14664: *1 http map: "" "http"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script copy: "Host: "
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script var: "${DOCKERHOSTPUBLICIP}:8081"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script copy: "
"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script copy: "X-Real-IP: "
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script var: "${REALIP}"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script copy: "
"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script copy: "X-Forwarded-For: "
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script var: "${REALIP}"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script copy: "
"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script copy: "X-Forwarded-Proto: "
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script var: "http"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script copy: "
"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script copy: "Connection: close
"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script copy: "Content-Length: "
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script var: "273"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script copy: "
"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script copy: ""
2015/11/24 10:42:05 [debug] 14664#14664: *1 http script copy: ""
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "X-SILENCE-LOGGER: true"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "Accept: application/json, text/javascript, */*; q=0.01"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "X-Requested-With: XMLHttpRequest"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "Accept-Encoding: gzip, deflate"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "Accept-Language: de-de"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "Content-Type: application/x-www-form-urlencoded; charset=UTF-8"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "Origin: http://${DOCKERHOSTPUBLICIP}:8081"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "User-Agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_1) AppleWebKit/601.2.7 (KHTML, like Gecko) Version/9.0.1 Safari/601.2.7"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "Referer: http://${DOCKERHOSTPUBLICIP}:8081/"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "X-CSRF-Token: +xWuY6h0tt+ZImftVMAF6mNxGqRjYaf7BXmGqBVeE05qymB9d5B+1RZOvgiGGOEvcX99ZwGI2kXevvhnfcydcw=="
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "Cookie: __profilin=p%3Dt; _forum_session=c010T0VhaUp3ZXhqeHdTRjdlUEY5WjZiZHZhWlVLeStkTllMbTl6SE4xSVVjRW52U1JIRENHdXVWeW5LZjRvRTNjcThlZTFkaEx3YmJpd3hYQXRaRVFFMnhsc0t5cnB5SWJ3L2QvWU51TWlLQW9ZUjdlVUYwTUxDUlV3MCtCWkFIdkk3MWVBNmZtbkFIK3pYdmU3eXJQQ0FmdkFLV1J1cHk3Z0YwNjdxNmtBR0xSYndVZloyQWlLWDFHMDh4bDlJaDJwclJDZnNWNUxydjJNelIyajhBZmYyYXg4WVVEOEl6bkpWWTBKdUp6UmUvSmc1VGNHVEZIYWxZZlZqY2swek1RSTdpVFl0Z3hkT0FTdHJOODlaeW1RNllaaDdzUE5NdGZsRktjUllDc1duaEdMZUhIZ3lvQzk3ekVLUC9NUmlmdE1qenllZisyalhxVGVSSW5GbjdRPT0tLVlkd0lFK1MrV1lGT2lybXNOR21kMVE9PQ%3D%3D--972318ebd22d8dc061539b1d27296131701780df; _t=6dc76295818925ff8e536e7c985d2303"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header:
"POST /message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll?dlp=t HTTP/1.0
Host: ${DOCKERHOSTPUBLICIP}:8081
X-Real-IP: ${REALIP}
X-Forwarded-For: ${REALIP}
X-Forwarded-Proto: http
Connection: close
Content-Length: 273
X-SILENCE-LOGGER: true
Accept: application/json, text/javascript, */*; q=0.01
X-Requested-With: XMLHttpRequest
Accept-Encoding: gzip, deflate
Accept-Language: de-de
Content-Type: application/x-www-form-urlencoded; charset=UTF-8
Origin: http://${DOCKERHOSTPUBLICIP}:8081
User-Agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_1) AppleWebKit/601.2.7 (KHTML, like Gecko) Version/9.0.1 Safari/601.2.7
Referer: http://${DOCKERHOSTPUBLICIP}:8081/
X-CSRF-Token: +xWuY6h0tt+ZImftVMAF6mNxGqRjYaf7BXmGqBVeE05qymB9d5B+1RZOvgiGGOEvcX99ZwGI2kXevvhnfcydcw==
Cookie: __profilin=p%3Dt; _forum_session=c010T0VhaUp3ZXhqeHdTRjdlUEY5WjZiZHZhWlVLeStkTllMbTl6SE4xSVVjRW52U1JIRENHdXVWeW5LZjRvRTNjcThlZTFkaEx3YmJpd3hYQXRaRVFFMnhsc0t5cnB5SWJ3L2QvWU51TWlLQW9ZUjdlVUYwTUxDUlV3MCtCWkFIdkk3MWVBNmZtbkFIK3pYdmU3eXJQQ0FmdkFLV1J1cHk3Z0YwNjdxNmtBR0xSYndVZloyQWlLWDFHMDh4bDlJaDJwclJDZnNWNUxydjJNelIyajhBZmYyYXg4WVVEOEl6bkpWWTBKdUp6UmUvSmc1VGNHVEZIYWxZZlZqY2swek1RSTdpVFl0Z3hkT0FTdHJOODlaeW1RNllaaDdzUE5NdGZsRktjUllDc1duaEdMZUhIZ3lvQzk3ekVLUC9NUmlmdE1qenllZisyalhxVGVSSW5GbjdRPT0tLVlkd0lFK1MrV1lGT2lybXNOR21kMVE9PQ%3D%3D--972318ebd22d8dc061539b1d27296131701780df; _t=6dc76295818925ff8e536e7c985d2303

"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http cleanup add: 000055D7F2FA3B30
2015/11/24 10:42:05 [debug] 14664#14664: *1 posix_memalign: 000055D7F2F54ED0:4096 @16
2015/11/24 10:42:05 [debug] 14664#14664: *1 get rr peer, try: 1
2015/11/24 10:42:05 [debug] 14664#14664: *1 socket 9
2015/11/24 10:42:05 [debug] 14664#14664: *1 epoll add connection: fd:9 ev:80002005
2015/11/24 10:42:05 [debug] 14664#14664: *1 connect to 127.0.0.1:3000, fd:9 #12
2015/11/24 10:42:05 [debug] 14664#14664: *1 http upstream connect: -2
2015/11/24 10:42:05 [debug] 14664#14664: *1 posix_memalign: 000055D7F2F72270:128 @16
2015/11/24 10:42:05 [debug] 14664#14664: *1 event timer add: 9: 60000:1448361785375
2015/11/24 10:42:05 [debug] 14664#14664: *1 http finalize request: -4, "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll?dlp=t" a:1, c:3
2015/11/24 10:42:05 [debug] 14664#14664: *1 http request count:3 blk:0
2015/11/24 10:42:05 [debug] 14664#14664: *1 http finalize request: -4, "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll?dlp=t" a:1, c:2
2015/11/24 10:42:05 [debug] 14664#14664: *1 http request count:2 blk:0
2015/11/24 10:42:05 [debug] 14664#14664: *1 delete posted event 000055D7F2FE68B0
2015/11/24 10:42:05 [debug] 14664#14664: *1 http run request: "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll?dlp=t"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http upstream check client, write event:1, "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http upstream recv(): -1 (11: Resource temporarily unavailable)
2015/11/24 10:42:05 [debug] 14664#14664: *1 post event 000055D7F2FE6970
2015/11/24 10:42:05 [debug] 14664#14664: *1 delete posted event 000055D7F2FE6970
2015/11/24 10:42:05 [debug] 14664#14664: *1 http upstream request: "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll?dlp=t"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http upstream send request handler
2015/11/24 10:42:05 [debug] 14664#14664: *1 http upstream send request
2015/11/24 10:42:05 [debug] 14664#14664: *1 http upstream send request body
2015/11/24 10:42:05 [debug] 14664#14664: *1 chain writer buf fl:0 s:1384
2015/11/24 10:42:05 [debug] 14664#14664: *1 chain writer buf fl:1 s:273
2015/11/24 10:42:05 [debug] 14664#14664: *1 chain writer in: 000055D7F2F54F20
2015/11/24 10:42:05 [debug] 14664#14664: *1 writev: 1657 of 1657
2015/11/24 10:42:05 [debug] 14664#14664: *1 chain writer out: 0000000000000000
2015/11/24 10:42:05 [debug] 14664#14664: *1 event timer del: 9: 1448361785375
2015/11/24 10:42:05 [debug] 14664#14664: *1 event timer add: 9: 60000:1448361785375
2015/11/24 10:42:05 [debug] 14664#14664: *1 post event 000055D7F2FD4960
2015/11/24 10:42:05 [debug] 14664#14664: *1 post event 000055D7F2FE6970
2015/11/24 10:42:05 [debug] 14664#14664: *1 delete posted event 000055D7F2FD4960
2015/11/24 10:42:05 [debug] 14664#14664: *1 http upstream request: "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll?dlp=t"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http upstream process header
2015/11/24 10:42:05 [debug] 14664#14664: *1 malloc: 000055D7F2F55EE0:4096
2015/11/24 10:42:05 [debug] 14664#14664: *1 recv: fd:9 470 of 4096
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy status 200 "200 OK"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "Date: Tue, 24 Nov 2015 10:42:05 GMT"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "Status: 200 OK"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "Connection: close"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "Cache-Control: must-revalidate, private, max-age=0"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "Content-Type: application/json; charset=utf-8"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "Pragma: no-cache"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "Expires: 0"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "Access-Control-Allow-Origin: http://${discourse.mydomain.com}"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "Access-Control-Allow-Methods: GET, POST"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "Access-Control-Allow-Headers: X-SILENCE-LOGGER, X-Shared-Session-Key"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "X-Request-Id: 0d5ef992-3b54-4901-9605-41f5e01e9617"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header: "X-Runtime: 0.023964"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy header done
2015/11/24 10:42:05 [debug] 14664#14664: *1 xslt filter header
2015/11/24 10:42:05 [debug] 14664#14664: *1 HTTP/1.1 200 OK
Server: nginx
Date: Tue, 24 Nov 2015 10:42:05 GMT
Content-Type: application/json; charset=utf-8
Transfer-Encoding: chunked
Connection: keep-alive
Vary: Accept-Encoding
Status: 200 OK
Cache-Control: must-revalidate, private, max-age=0
Pragma: no-cache
Expires: 0
Access-Control-Allow-Origin: http://${discourse.mydomain.com}
Access-Control-Allow-Methods: GET, POST
Access-Control-Allow-Headers: X-SILENCE-LOGGER, X-Shared-Session-Key
X-Request-Id: 0d5ef992-3b54-4901-9605-41f5e01e9617
X-Runtime: 0.023964
X-UA-Compatible: IE=edge
Content-Encoding: gzip

2015/11/24 10:42:05 [debug] 14664#14664: *1 write new buf t:1 f:0 000055D7F2F55658, pos 000055D7F2F55658, size: 589 file: 0, size: 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 http write filter: l:0 f:0 s:589
2015/11/24 10:42:05 [debug] 14664#14664: *1 http cacheable: 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 http proxy filter init s:200 h:0 c:0 l:-1
2015/11/24 10:42:05 [debug] 14664#14664: *1 http upstream process upstream
2015/11/24 10:42:05 [debug] 14664#14664: *1 pipe read upstream: 1
2015/11/24 10:42:05 [debug] 14664#14664: *1 pipe preread: 2
2015/11/24 10:42:05 [debug] 14664#14664: *1 readv: 1, last:3626
2015/11/24 10:42:05 [debug] 14664#14664: *1 readv() not ready (11: Resource temporarily unavailable)
2015/11/24 10:42:05 [debug] 14664#14664: *1 pipe recv chain: -2
2015/11/24 10:42:05 [debug] 14664#14664: *1 pipe buf free s:0 t:1 f:0 000055D7F2F55EE0, pos 000055D7F2F560B4, size: 2 file: 0, size: 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 pipe length: -1
2015/11/24 10:42:05 [debug] 14664#14664: *1 pipe write downstream: 1
2015/11/24 10:42:05 [debug] 14664#14664: *1 pipe write busy: 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 pipe write: out:0000000000000000, f:0
2015/11/24 10:42:05 [debug] 14664#14664: *1 pipe read upstream: 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 pipe buf free s:0 t:1 f:0 000055D7F2F55EE0, pos 000055D7F2F560B4, size: 2 file: 0, size: 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 pipe length: -1
2015/11/24 10:42:05 [debug] 14664#14664: *1 event timer: 9, old: 1448361785375, new: 1448361785401
2015/11/24 10:42:05 [debug] 14664#14664: *1 delete posted event 000055D7F2FE6970
2015/11/24 10:42:05 [debug] 14664#14664: *1 http upstream request: "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll?dlp=t"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http upstream dummy handler
2015/11/24 10:42:05 [debug] 14664#14664: *1 post event 000055D7F2FD4960
2015/11/24 10:42:05 [debug] 14664#14664: *1 post event 000055D7F2FE6970
2015/11/24 10:42:05 [debug] 14664#14664: *1 delete posted event 000055D7F2FD4960
2015/11/24 10:42:05 [debug] 14664#14664: *1 http upstream request: "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll?dlp=t"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http upstream process upstream
2015/11/24 10:42:05 [debug] 14664#14664: *1 pipe read upstream: 1
2015/11/24 10:42:05 [debug] 14664#14664: *1 readv: 1, last:3626
2015/11/24 10:42:05 [debug] 14664#14664: *1 pipe recv chain: 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 pipe buf free s:0 t:1 f:0 000055D7F2F55EE0, pos 000055D7F2F560B4, size: 2 file: 0, size: 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 pipe length: -1
2015/11/24 10:42:05 [debug] 14664#14664: *1 input buf #0
2015/11/24 10:42:05 [debug] 14664#14664: *1 pipe write downstream: 1
2015/11/24 10:42:05 [debug] 14664#14664: *1 pipe write downstream flush in
2015/11/24 10:42:05 [debug] 14664#14664: *1 http output filter "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll?dlp=t"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http copy filter: "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll?dlp=t"
2015/11/24 10:42:05 [debug] 14664#14664: *1 image filter
2015/11/24 10:42:05 [debug] 14664#14664: *1 xslt filter body
2015/11/24 10:42:05 [debug] 14664#14664: *1 http postpone filter "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll?dlp=t" 000055D7F2F54F30
2015/11/24 10:42:05 [debug] 14664#14664: *1 http gzip filter
2015/11/24 10:42:05 [debug] 14664#14664: *1 malloc: 000055D7F2FF8800:270336
2015/11/24 10:42:05 [debug] 14664#14664: *1 gzip alloc: n:1 s:5936 a:8192 p:000055D7F2FF8800
2015/11/24 10:42:05 [debug] 14664#14664: *1 gzip alloc: n:32768 s:2 a:65536 p:000055D7F2FFA800
2015/11/24 10:42:05 [debug] 14664#14664: *1 gzip alloc: n:32768 s:2 a:65536 p:000055D7F300A800
2015/11/24 10:42:05 [debug] 14664#14664: *1 gzip alloc: n:32768 s:2 a:65536 p:000055D7F301A800
2015/11/24 10:42:05 [debug] 14664#14664: *1 gzip alloc: n:16384 s:4 a:65536 p:000055D7F302A800
2015/11/24 10:42:05 [debug] 14664#14664: *1 gzip in: 000055D7F2F54F20
2015/11/24 10:42:05 [debug] 14664#14664: *1 gzip in_buf:000055D7F2F559B0 ni:000055D7F2F560B4 ai:2
2015/11/24 10:42:05 [debug] 14664#14664: *1 malloc: 000055D7F2F56EF0:4096
2015/11/24 10:42:05 [debug] 14664#14664: *1 deflate in: ni:000055D7F2F560B4 no:000055D7F2F56EF0 ai:2 ao:4096 fl:0 redo:0
2015/11/24 10:42:05 [debug] 14664#14664: *1 deflate out: ni:000055D7F2F560B6 no:000055D7F2F56EF0 ai:0 ao:4096 rc:0
2015/11/24 10:42:05 [debug] 14664#14664: *1 gzip in_buf:000055D7F2F559B0 pos:000055D7F2F560B4
2015/11/24 10:42:05 [debug] 14664#14664: *1 gzip in: 0000000000000000
2015/11/24 10:42:05 [debug] 14664#14664: *1 http copy filter: 0 "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll?dlp=t"
2015/11/24 10:42:05 [debug] 14664#14664: *1 pipe write downstream done
2015/11/24 10:42:05 [debug] 14664#14664: *1 event timer del: 9: 1448361785375
2015/11/24 10:42:05 [debug] 14664#14664: *1 http upstream exit: 0000000000000000
2015/11/24 10:42:05 [debug] 14664#14664: *1 finalize http upstream request: 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 finalize http proxy request
2015/11/24 10:42:05 [debug] 14664#14664: *1 free rr peer 1 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 close http upstream connection: 9
2015/11/24 10:42:05 [debug] 14664#14664: *1 free: 000055D7F2F72270, unused: 48
2015/11/24 10:42:05 [debug] 14664#14664: *1 delete posted event 000055D7F2FE6970
2015/11/24 10:42:05 [debug] 14664#14664: *1 reusable connection: 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 http upstream temp fd: -1
2015/11/24 10:42:05 [debug] 14664#14664: *1 http output filter "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll?dlp=t"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http copy filter: "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll?dlp=t"
2015/11/24 10:42:05 [debug] 14664#14664: *1 image filter
2015/11/24 10:42:05 [debug] 14664#14664: *1 xslt filter body
2015/11/24 10:42:05 [debug] 14664#14664: *1 http postpone filter "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll?dlp=t" 00007FFF635524E0
2015/11/24 10:42:05 [debug] 14664#14664: *1 http gzip filter
2015/11/24 10:42:05 [debug] 14664#14664: *1 gzip in: 000055D7F2F55B38
2015/11/24 10:42:05 [debug] 14664#14664: *1 gzip in_buf:000055D7F2F55AE8 ni:0000000000000000 ai:0
2015/11/24 10:42:05 [debug] 14664#14664: *1 deflate in: ni:0000000000000000 no:000055D7F2F56EF0 ai:0 ao:4096 fl:4 redo:0
2015/11/24 10:42:05 [debug] 14664#14664: *1 deflate out: ni:0000000000000000 no:000055D7F2F56EF4 ai:0 ao:4092 rc:1
2015/11/24 10:42:05 [debug] 14664#14664: *1 gzip in_buf:000055D7F2F55AE8 pos:0000000000000000
2015/11/24 10:42:05 [debug] 14664#14664: *1 free: 000055D7F2FF8800
2015/11/24 10:42:05 [debug] 14664#14664: *1 http chunk: 10
2015/11/24 10:42:05 [debug] 14664#14664: *1 http chunk: 12
2015/11/24 10:42:05 [debug] 14664#14664: *1 write old buf t:1 f:0 000055D7F2F55658, pos 000055D7F2F55658, size: 589 file: 0, size: 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 write new buf t:1 f:0 000055D7F2F55C38, pos 000055D7F2F55C38, size: 4 file: 0, size: 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 write new buf t:0 f:0 0000000000000000, pos 000055D7F2BCBFD0, size: 10 file: 0, size: 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 write new buf t:1 f:0 000055D7F2F56EF0, pos 000055D7F2F56EF0, size: 12 file: 0, size: 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 write new buf t:0 f:0 0000000000000000, pos 000055D7F2999C00, size: 7 file: 0, size: 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 http write filter: l:1 f:1 s:622
2015/11/24 10:42:05 [debug] 14664#14664: *1 http write filter limit 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 writev: 622 of 622
2015/11/24 10:42:05 [debug] 14664#14664: *1 http write filter 0000000000000000
2015/11/24 10:42:05 [debug] 14664#14664: *1 http copy filter: 0 "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll?dlp=t"
2015/11/24 10:42:05 [debug] 14664#14664: *1 http finalize request: 0, "/message-bus/6a791588ba034ff8b4a67b6cd1baf8b0/poll?dlp=t" a:1, c:1
2015/11/24 10:42:05 [debug] 14664#14664: *1 set http keepalive handler
2015/11/24 10:42:05 [debug] 14664#14664: *1 http close request
2015/11/24 10:42:05 [debug] 14664#14664: *1 http log handler
2015/11/24 10:42:05 [debug] 14664#14664: *1 free: 000055D7F2F56EF0
2015/11/24 10:42:05 [debug] 14664#14664: *1 free: 0000000000000000
2015/11/24 10:42:05 [debug] 14664#14664: *1 free: 000055D7F2F55EE0
2015/11/24 10:42:05 [debug] 14664#14664: *1 free: 000055D7F2F5E3B0, unused: 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 free: 000055D7F2FA2B60, unused: 2
2015/11/24 10:42:05 [debug] 14664#14664: *1 free: 000055D7F2F54ED0, unused: 109
2015/11/24 10:42:05 [debug] 14664#14664: *1 free: 000055D7F2F9D9E0
2015/11/24 10:42:05 [debug] 14664#14664: *1 hc free: 0000000000000000 0
2015/11/24 10:42:05 [debug] 14664#14664: *1 hc busy: 000055D7F2F9D278 1
2015/11/24 10:42:05 [debug] 14664#14664: *1 free: 000055D7F2FA3B70
2015/11/24 10:42:05 [debug] 14664#14664: *1 reusable connection: 1
2015/11/24 10:42:05 [debug] 14664#14664: *1 event timer add: 11: 65000:1448361790401
2015/11/24 10:42:05 [debug] 14664#14664: *1 post event 000055D7F2FD48A0
2015/11/24 10:42:05 [debug] 14664#14664: *1 delete posted event 000055D7F2FD48A0
2015/11/24 10:42:05 [debug] 14664#14664: *1 http keepalive handler
2015/11/24 10:42:05 [debug] 14664#14664: *1 malloc: 000055D7F2F9D9E0:1024
2015/11/24 10:42:05 [debug] 14664#14664: *1 recv: fd:11 -1 of 1024
2015/11/24 10:42:05 [debug] 14664#14664: *1 recv() not ready (11: Resource temporarily unavailable)
2015/11/24 10:42:05 [debug] 14664#14664: *1 free: 000055D7F2F9D9E0

My nginx remote config looks like this:

server {

  listen    ${PUBLIC_IP}:80;
  server_name  ${discourse.mydomain.com};

location / {

proxy_set_header X-Real-IP $remote_addr;
proxy_set_header X-Forwarded-For $proxy_add_x_forwarded_for;
proxy_set_header Host $http_host;
proxy_set_header X-NginX-Proxy true;

proxy_pass http://${DOCKERHOSTPUBLICIP}:8081/;
proxy_redirect off;

# Socket.IO Support
proxy_http_version 1.1;
proxy_set_header Upgrade $http_upgrade;
proxy_set_header Connection "upgrade";
}

Does anyone see what’s wrong? I don’t. :confused:


(Kurt Werle) #2

You ever figure this out? I seem to be seeing the same kind of thing.


(Yuvaraja Balamurugan) #3

I am getting the same too.

But I suspect that it has something to do with message_bus and not related to nginx or docker or anything as it is happening in my local machine too (which just has webrick server).