Ticket #1128: app-error-log1.txt

File app-error-log1.txt, 143.0 KB (added by kcannon.gaikai.com@…, 10 years ago)
Line 
12016/11/15 19:11:23 [debug] 30741#30741: *251 post event 00006EFC4C5081F0
22016/11/15 19:11:23 [debug] 30741#30741: *251 post event 00006EFC4C2071F0
32016/11/15 19:11:23 [debug] 30741#30741: *251 delete posted event 00006EFC4C5081F0
42016/11/15 19:11:23 [debug] 30741#30741: *251 http run request: "/appname/socket/?EIO=3&transport=websocket&sid=UJyFqU73bEe42MVkAAAB"
52016/11/15 19:11:23 [debug] 30741#30741: *251 http upstream process upgraded, fu:0
62016/11/15 19:11:23 [debug] 30741#30741: *251 SSL_read: 9
72016/11/15 19:11:23 [debug] 30741#30741: *251 SSL_read: -1
82016/11/15 19:11:23 [debug] 30741#30741: *251 SSL_get_error: 2
92016/11/15 19:11:23 [debug] 30741#30741: *251 send: fd:14 9 of 9
102016/11/15 19:11:23 [debug] 30741#30741: *251 event timer del: 14: 1479237118051
112016/11/15 19:11:23 [debug] 30741#30741: *251 event timer add: 14: 60000:1479237143065
122016/11/15 19:11:23 [debug] 30741#30741: *251 delete posted event 00006EFC4C2071F0
132016/11/15 19:11:23 [debug] 30741#30741: *251 http run request: "/appname/socket/?EIO=3&transport=websocket&sid=UJyFqU73bEe42MVkAAAB"
142016/11/15 19:11:23 [debug] 30741#30741: *251 http upstream process upgraded, fu:1
152016/11/15 19:11:23 [debug] 30741#30741: *251 event timer: 14, old: 1479237143065, new: 1479237143065
162016/11/15 19:11:23 [debug] 30741#30741: *251 post event 00006EFC4C508250
172016/11/15 19:11:23 [debug] 30741#30741: *251 post event 00006EFC4C207250
182016/11/15 19:11:23 [debug] 30741#30741: *251 delete posted event 00006EFC4C508250
192016/11/15 19:11:23 [debug] 30741#30741: *251 http upstream request: "/appname/socket/?EIO=3&transport=websocket&sid=UJyFqU73bEe42MVkAAAB"
202016/11/15 19:11:23 [debug] 30741#30741: *251 http upstream process upgraded, fu:1
212016/11/15 19:11:23 [debug] 30741#30741: *251 recv: fd:14 3 of 4096
222016/11/15 19:11:23 [debug] 30741#30741: *251 SSL to write: 3
232016/11/15 19:11:23 [debug] 30741#30741: *251 SSL_write: 3
242016/11/15 19:11:23 [debug] 30741#30741: *251 recv: fd:14 -1 of 4096
252016/11/15 19:11:23 [debug] 30741#30741: *251 recv() not ready (11: Resource temporarily unavailable)
262016/11/15 19:11:23 [debug] 30741#30741: *251 event timer: 14, old: 1479237143065, new: 1479237143069
272016/11/15 19:11:23 [debug] 30741#30741: *251 delete posted event 00006EFC4C207250
282016/11/15 19:11:23 [debug] 30741#30741: *251 http upstream request: "/appname/socket/?EIO=3&transport=websocket&sid=UJyFqU73bEe42MVkAAAB"
292016/11/15 19:11:23 [debug] 30741#30741: *251 http upstream process upgraded, fu:0
302016/11/15 19:11:23 [debug] 30741#30741: *251 event timer: 14, old: 1479237143065, new: 1479237143069
312016/11/15 19:11:26 [debug] 30743#30743: *162 http2 idle handler
322016/11/15 19:11:26 [debug] 30743#30743: *162 reusable connection: 0
332016/11/15 19:11:26 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E03BC0:4096 @16
342016/11/15 19:11:26 [debug] 30743#30743: *162 http2 read handler
352016/11/15 19:11:26 [debug] 30743#30743: *162 SSL_read: 55
362016/11/15 19:11:26 [debug] 30743#30743: *162 SSL_read: 2861
372016/11/15 19:11:26 [debug] 30743#30743: *162 SSL_read: 2861
382016/11/15 19:11:26 [debug] 30743#30743: *162 SSL_read: 2861
392016/11/15 19:11:26 [debug] 30743#30743: *162 SSL_read: 2861
402016/11/15 19:11:26 [debug] 30743#30743: *162 SSL_read: -1
412016/11/15 19:11:26 [debug] 30743#30743: *162 SSL_get_error: 2
422016/11/15 19:11:26 [debug] 30743#30743: *162 process http2 frame type:1 f:24 l:46 sid:423
432016/11/15 19:11:26 [debug] 30743#30743: *162 http2 HEADERS frame sid:423 on 0 excl:0 weight:183
442016/11/15 19:11:26 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E61530:1024 @16
452016/11/15 19:11:26 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E120F0:4096 @16
462016/11/15 19:11:26 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E63480:4096 @16
472016/11/15 19:11:26 [debug] 30743#30743: *162 http2 get indexed header name: 3
482016/11/15 19:11:26 [debug] 30743#30743: *162 http2 get indexed header name: 92
492016/11/15 19:11:26 [debug] 30743#30743: *162 http2 get indexed header name: 7
502016/11/15 19:11:26 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
512016/11/15 19:11:26 [debug] 30743#30743: *162 http2 hpack encoded string length: 13
522016/11/15 19:11:26 [debug] 30743#30743: *162 http uri: "/appname/v2/heartbeat"
532016/11/15 19:11:26 [debug] 30743#30743: *162 http args: ""
542016/11/15 19:11:26 [debug] 30743#30743: *162 http exten: ""
552016/11/15 19:11:26 [debug] 30743#30743: *162 http2 get indexed header: 28
562016/11/15 19:11:26 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
572016/11/15 19:11:26 [debug] 30743#30743: *162 http2 add header to hpack table: "content-length: 16175"
582016/11/15 19:11:26 [debug] 30743#30743: *162 http2 hpack table account: 51 free:43
592016/11/15 19:11:26 [debug] 30743#30743: *162 http2 http header: "content-length: 16175"
602016/11/15 19:11:26 [debug] 30743#30743: *162 http2 get indexed header name: 92
612016/11/15 19:11:26 [debug] 30743#30743: *162 http2 http header: "accept: application/json, text/plain, */*"
622016/11/15 19:11:26 [debug] 30743#30743: *162 http2 get indexed header name: 71
632016/11/15 19:11:26 [debug] 30743#30743: *162 http2 http header: "origin: https://app3.mydomain.local"
642016/11/15 19:11:26 [debug] 30743#30743: *162 http2 get indexed header name: 87
652016/11/15 19:11:26 [debug] 30743#30743: *162 http2 http header: "appname-csrf-token: YYY
662016/11/15 19:11:26 [debug] 30743#30743: *162 http2 get indexed header name: 90
672016/11/15 19:11:26 [debug] 30743#30743: *162 http2 http header: "user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36"
682016/11/15 19:11:26 [debug] 30743#30743: *162 http2 get indexed header name: 70
692016/11/15 19:11:26 [debug] 30743#30743: *162 http2 http header: "content-type: application/json;charset=UTF-8"
702016/11/15 19:11:26 [debug] 30743#30743: *162 http2 get indexed header name: 68
712016/11/15 19:11:26 [debug] 30743#30743: *162 http2 http header: "accept-encoding: gzip, deflate, br"
722016/11/15 19:11:26 [debug] 30743#30743: *162 http2 get indexed header name: 84
732016/11/15 19:11:26 [debug] 30743#30743: *162 http2 http header: "accept-language: en-US,en;q=0.8"
742016/11/15 19:11:26 [debug] 30743#30743: *162 http2 get indexed header name: 83
752016/11/15 19:11:26 [debug] 30743#30743: *162 http2 get indexed header name: 82
762016/11/15 19:11:26 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E16F00:1024 @16
772016/11/15 19:11:26 [debug] 30743#30743: *162 http2 get indexed header name: 81
782016/11/15 19:11:26 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DC4930:1024 @16
792016/11/15 19:11:26 [debug] 30743#30743: *162 http2 get indexed header name: 80
802016/11/15 19:11:26 [debug] 30743#30743: *162 http2 get indexed header name: 86
812016/11/15 19:11:26 [debug] 30743#30743: *162 http2 http request line: "POST /appname/v2/heartbeat HTTP/2.0"
822016/11/15 19:11:26 [debug] 30743#30743: *162 generic phase: 0
832016/11/15 19:11:26 [debug] 30743#30743: *162 rewrite phase: 1
842016/11/15 19:11:26 [debug] 30743#30743: *162 test location: "^/widgets/$"
852016/11/15 19:11:26 [debug] 30743#30743: *162 test location: "/"
862016/11/15 19:11:26 [debug] 30743#30743: *162 test location: "docs/"
872016/11/15 19:11:26 [debug] 30743#30743: *162 test location: "appname/socket"
882016/11/15 19:11:26 [debug] 30743#30743: *162 test location: ~ "^/(index.html)?$"
892016/11/15 19:11:26 [debug] 30743#30743: *162 test location: ~ "^/login/?$"
902016/11/15 19:11:26 [debug] 30743#30743: *162 test location: ~ "^/(appname)"
912016/11/15 19:11:26 [debug] 30743#30743: *162 test location: ~ "/components/(.*)"
922016/11/15 19:11:26 [debug] 30743#30743: *162 test location: ~ "^/(auth|logout|status|widgetTypes|index.appname.html|login.appname.html|favicon.ico|(.*)\/vendor\/flags\/.*\.(?:jpg|png)|(.*)\/assets\/images\/company_logo.png)$"
932016/11/15 19:11:26 [debug] 30743#30743: *162 using configuration "/"
942016/11/15 19:11:26 [debug] 30743#30743: *162 http cl:16175 max:1073741824
952016/11/15 19:11:26 [debug] 30743#30743: *162 rewrite phase: 3
962016/11/15 19:11:26 [debug] 30743#30743: *162 post rewrite phase: 4
972016/11/15 19:11:26 [debug] 30743#30743: *162 generic phase: 5
982016/11/15 19:11:26 [debug] 30743#30743: *162 generic phase: 6
992016/11/15 19:11:26 [debug] 30743#30743: *162 generic phase: 7
1002016/11/15 19:11:26 [debug] 30743#30743: *162 access phase: 8
1012016/11/15 19:11:26 [debug] 30743#30743: *162 access phase: 9
1022016/11/15 19:11:26 [debug] 30743#30743: *162 access phase: 10
1032016/11/15 19:11:26 [debug] 30743#30743: *162 auth request handler
1042016/11/15 19:11:26 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB6570:4096 @16
1052016/11/15 19:11:26 [debug] 30743#30743: *162 http subrequest "/authorize?"
1062016/11/15 19:11:26 [debug] 30743#30743: *162 http posted request: "/authorize?"
1072016/11/15 19:11:26 [debug] 30743#30743: *162 rewrite phase: 1
1082016/11/15 19:11:26 [debug] 30743#30743: *162 test location: "^/widgets/$"
1092016/11/15 19:11:26 [debug] 30743#30743: *162 test location: "/"
1102016/11/15 19:11:26 [debug] 30743#30743: *162 test location: "docs/"
1112016/11/15 19:11:26 [debug] 30743#30743: *162 test location: "dashboard"
1122016/11/15 19:11:26 [debug] 30743#30743: *162 test location: "authorize"
1132016/11/15 19:11:26 [debug] 30743#30743: *162 using configuration "=/authorize"
1142016/11/15 19:11:26 [debug] 30743#30743: *162 http cl:16175 max:1073741824
1152016/11/15 19:11:26 [debug] 30743#30743: *162 rewrite phase: 3
1162016/11/15 19:11:26 [debug] 30743#30743: *162 post rewrite phase: 4
1172016/11/15 19:11:26 [debug] 30743#30743: *162 generic phase: 5
1182016/11/15 19:11:26 [debug] 30743#30743: *162 generic phase: 6
1192016/11/15 19:11:26 [debug] 30743#30743: *162 generic phase: 7
1202016/11/15 19:11:26 [debug] 30743#30743: *162 http init upstream, client timer: 0
1212016/11/15 19:11:26 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB7580:4096 @16
1222016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
1232016/11/15 19:11:26 [debug] 30743#30743: *162 http script var: "192.168.123.11"
1242016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "
125"
1262016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
1272016/11/15 19:11:26 [debug] 30743#30743: *162 http script var: "192.168.123.11"
1282016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "
129"
1302016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "Host: "
1312016/11/15 19:11:26 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
1322016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "
133"
1342016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
135"
1362016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
1372016/11/15 19:11:26 [debug] 30743#30743: *162 http script var: "XXX"
1382016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "
139"
1402016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
1412016/11/15 19:11:26 [debug] 30743#30743: *162 http script var: "XXXX"
1422016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "
143"
1442016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "x-appname-uri: "
1452016/11/15 19:11:26 [debug] 30743#30743: *162 http script var: "/appname/v2/heartbeat"
1462016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "
147"
1482016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "x-appname-method: "
1492016/11/15 19:11:26 [debug] 30743#30743: *162 http script var: "POST"
1502016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "
151"
1522016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "Connection: close
153"
1542016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: ""
1552016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: ""
1562016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
1572016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
1582016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
1592016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36"
1602016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
1612016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
1622016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
1632016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
1642016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header:
165"GET /authorize HTTP/1.1
166X-Real-IP: 192.168.123.11
167X-Forwarded-For: 192.168.123.11
168Host: app3.mydomain.local
169X-NginX-Proxy: true
170
171
172x-appname-uri: /appname/v2/heartbeat
173x-appname-method: POST
174Connection: close
175accept: application/json, text/plain, */*
176origin: https://app3.mydomain.local
177appname-csrf-token: YYY
178user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
179content-type: application/json;charset=UTF-8
180accept-encoding: gzip, deflate, br
181accept-language: en-US,en;q=0.8
1822016/11/15 19:11:26 [debug] 30743#30743: *162 http cleanup add: 00000F0D34E64460
1832016/11/15 19:11:26 [debug] 30743#30743: *162 get rr peer, try: 1
1842016/11/15 19:11:26 [debug] 30743#30743: *162 stream socket 7
1852016/11/15 19:11:26 [debug] 30743#30743: *162 epoll add connection: fd:7 ev:80002005
1862016/11/15 19:11:26 [debug] 30743#30743: *162 connect to 127.0.0.1:8008, fd:7 #565
1872016/11/15 19:11:26 [debug] 30743#30743: *162 http upstream connect: -2
1882016/11/15 19:11:26 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBBA30:128 @16
1892016/11/15 19:11:26 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237146566
1902016/11/15 19:11:26 [debug] 30743#30743: *162 http finalize request: -4, "/authorize?" a:1, c:3
1912016/11/15 19:11:26 [debug] 30743#30743: *162 http request count:3 blk:0
1922016/11/15 19:11:26 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AC047 end:00006EFC4F5AECFB
1932016/11/15 19:11:26 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:423
1942016/11/15 19:11:26 [debug] 30743#30743: *162 http2 DATA frame
1952016/11/15 19:11:26 [debug] 30743#30743: *162 malloc: 00000F0D34D89720:65536
1962016/11/15 19:11:26 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5ACB74 end:00006EFC4F5AECFB
1972016/11/15 19:11:26 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:423
1982016/11/15 19:11:26 [debug] 30743#30743: *162 http2 DATA frame
1992016/11/15 19:11:26 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AD6A1 end:00006EFC4F5AECFB
2002016/11/15 19:11:26 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:423
2012016/11/15 19:11:26 [debug] 30743#30743: *162 http2 DATA frame
2022016/11/15 19:11:26 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AE1CE end:00006EFC4F5AECFB
2032016/11/15 19:11:26 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:423
2042016/11/15 19:11:26 [debug] 30743#30743: *162 http2 DATA frame
2052016/11/15 19:11:26 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AECFB end:00006EFC4F5AECFB
2062016/11/15 19:11:26 [debug] 30743#30743: *162 event timer del: 3: 1479237258370
2072016/11/15 19:11:26 [debug] 30743#30743: *162 http2 write handler
2082016/11/15 19:11:26 [debug] 30743#30743: *162 http2 read handler
2092016/11/15 19:11:26 [debug] 30743#30743: *162 SSL_read: 2861
2102016/11/15 19:11:26 [debug] 30743#30743: *162 SSL_read: 1924
2112016/11/15 19:11:26 [debug] 30743#30743: *162 SSL_read: -1
2122016/11/15 19:11:26 [debug] 30743#30743: *162 SSL_get_error: 2
2132016/11/15 19:11:26 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:423
2142016/11/15 19:11:26 [debug] 30743#30743: *162 http2 DATA frame
2152016/11/15 19:11:26 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5ACB3D end:00006EFC4F5AD2C1
2162016/11/15 19:11:26 [debug] 30743#30743: *162 process http2 frame type:0 f:1 l:1915 sid:423
2172016/11/15 19:11:26 [debug] 30743#30743: *162 http2 DATA frame
2182016/11/15 19:11:26 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AD2C1 end:00006EFC4F5AD2C1
2192016/11/15 19:11:26 [debug] 30743#30743: *162 http2 write handler
2202016/11/15 19:11:26 [debug] 30743#30743: *162 http upstream request: "/authorize?"
2212016/11/15 19:11:26 [debug] 30743#30743: *162 http upstream send request handler
2222016/11/15 19:11:26 [debug] 30743#30743: *162 http upstream send request
2232016/11/15 19:11:26 [debug] 30743#30743: *162 http upstream send request body
2242016/11/15 19:11:26 [debug] 30743#30743: *162 chain writer buf fl:1 s:2711
2252016/11/15 19:11:26 [debug] 30743#30743: *162 chain writer in: 00000F0D34EB7550
2262016/11/15 19:11:26 [debug] 30743#30743: *162 writev: 2711 of 2711
2272016/11/15 19:11:26 [debug] 30743#30743: *162 chain writer out: 0000000000000000
2282016/11/15 19:11:26 [debug] 30743#30743: *162 event timer del: 7: 1479237146566
2292016/11/15 19:11:26 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237146567
2302016/11/15 19:11:26 [debug] 30743#30743: *162 http upstream request: "/authorize?"
2312016/11/15 19:11:26 [debug] 30743#30743: *162 http upstream process header
2322016/11/15 19:11:26 [debug] 30743#30743: *162 malloc: 00000F0D34DBC950:4096
2332016/11/15 19:11:26 [debug] 30743#30743: *162 recv: fd:7 172 of 4096
2342016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy status 200 "200 OK"
2352016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "X-Powered-By: Express"
2362016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "x-appname-origin: appname3"
2372016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "content-type: application/json"
2382016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "content-length: 0"
2392016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "Date: Tue, 15 Nov 2016 19:11:26 GMT"
2402016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "Connection: close"
2412016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header done
2422016/11/15 19:11:26 [debug] 30743#30743: *162 lua capture header filter, uri "/authorize"
2432016/11/15 19:11:26 [debug] 30743#30743: *162 http2 header filter
2442016/11/15 19:11:26 [debug] 30743#30743: *162 finalize http upstream request: 0
2452016/11/15 19:11:26 [debug] 30743#30743: *162 finalize http proxy request
2462016/11/15 19:11:26 [debug] 30743#30743: *162 free rr peer 1 0
2472016/11/15 19:11:26 [debug] 30743#30743: *162 close http upstream connection: 7
2482016/11/15 19:11:26 [debug] 30743#30743: *162 free: 00000F0D34DBBA30, unused: 48
2492016/11/15 19:11:26 [debug] 30743#30743: *162 event timer del: 7: 1479237146567
2502016/11/15 19:11:26 [debug] 30743#30743: *162 reusable connection: 0
2512016/11/15 19:11:26 [debug] 30743#30743: *162 http finalize request: 0, "/authorize?" a:1, c:2
2522016/11/15 19:11:26 [debug] 30743#30743: *162 auth request done s:200
2532016/11/15 19:11:26 [debug] 30743#30743: *162 http wake parent request: "/appname/v2/heartbeat?"
2542016/11/15 19:11:26 [debug] 30743#30743: *162 http posted request: "/appname/v2/heartbeat?"
2552016/11/15 19:11:26 [debug] 30743#30743: *162 access phase: 10
2562016/11/15 19:11:26 [debug] 30743#30743: *162 auth request handler
2572016/11/15 19:11:26 [debug] 30743#30743: *162 auth request set variables
2582016/11/15 19:11:26 [debug] 30743#30743: *162 access phase: 11
2592016/11/15 19:11:26 [debug] 30743#30743: *162 lua access handler, uri:"/appname/v2/heartbeat" c:1
2602016/11/15 19:11:26 [debug] 30743#30743: *162 post access phase: 12
2612016/11/15 19:11:26 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBD960:4096 @16
2622016/11/15 19:11:26 [debug] 30743#30743: *162 http body new buf t:1 f:0 00000F0D34D89720, pos 00000F0D34D89720, size: 16175 file: 0, size: 0
2632016/11/15 19:11:26 [debug] 30743#30743: *162 http init upstream, client timer: 0
2642016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
2652016/11/15 19:11:26 [debug] 30743#30743: *162 http script var: "192.168.123.11"
2662016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "
267"
2682016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
2692016/11/15 19:11:26 [debug] 30743#30743: *162 http script var: "192.168.123.11"
2702016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "
271"
2722016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "Host: "
2732016/11/15 19:11:26 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
2742016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "
275"
2762016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
277"
2782016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
2792016/11/15 19:11:26 [debug] 30743#30743: *162 http script var: "XXX"
2802016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "
281"
2822016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
2832016/11/15 19:11:26 [debug] 30743#30743: *162 http script var: "XXXX"
2842016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "
285"
2862016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "Connection: close
287"
2882016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "Content-Length: "
2892016/11/15 19:11:26 [debug] 30743#30743: *162 http script var: "16175"
2902016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: "
291"
2922016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: ""
2932016/11/15 19:11:26 [debug] 30743#30743: *162 http script copy: ""
2942016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
2952016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
2962016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
2972016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36"
2982016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
2992016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
3002016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
3012016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
3022016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header:
303"POST /appname/v2/heartbeat HTTP/1.1
304X-Real-IP: 192.168.123.11
305X-Forwarded-For: 192.168.123.11
306Host: app3.mydomain.local
307X-NginX-Proxy: true
308
309
310Connection: close
311Content-Length: 16175
312accept: application/json, text/plain, */*
313origin: https://app3.mydomain.local
314appname-csrf-token: YYY
315user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
316content-type: application/json;charset=UTF-8
317accept-encoding: gzip, deflate, br
318accept-language: en-US,en;q=0.8
3192016/11/15 19:11:26 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBE970:4096 @16
3202016/11/15 19:11:26 [debug] 30743#30743: *162 http cleanup add: 00000F0D34DBE938
3212016/11/15 19:11:26 [debug] 30743#30743: *162 get rr peer, try: 1
3222016/11/15 19:11:26 [debug] 30743#30743: *162 stream socket 7
3232016/11/15 19:11:26 [debug] 30743#30743: *162 epoll add connection: fd:7 ev:80002005
3242016/11/15 19:11:26 [debug] 30743#30743: *162 connect to 182.168.128.69:8080, fd:7 #566
3252016/11/15 19:11:26 [debug] 30743#30743: *162 http upstream connect: -2
3262016/11/15 19:11:26 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBBA30:128 @16
3272016/11/15 19:11:26 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237146611
3282016/11/15 19:11:26 [debug] 30743#30743: *162 free: 00000F0D34D89720
3292016/11/15 19:11:26 [debug] 30743#30743: *162 http finalize request: -4, "/appname/v2/heartbeat?" a:1, c:2
3302016/11/15 19:11:26 [debug] 30743#30743: *162 http request count:2 blk:0
3312016/11/15 19:11:26 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
3322016/11/15 19:11:26 [debug] 30743#30743: *162 http upstream send request handler
3332016/11/15 19:11:26 [debug] 30743#30743: *162 http upstream send request
3342016/11/15 19:11:26 [debug] 30743#30743: *162 http upstream send request body
3352016/11/15 19:11:26 [debug] 30743#30743: *162 chain writer buf fl:0 s:2690
3362016/11/15 19:11:26 [debug] 30743#30743: *162 chain writer buf fl:1 s:16175
3372016/11/15 19:11:26 [debug] 30743#30743: *162 chain writer in: 00000F0D34DBEA00
3382016/11/15 19:11:26 [debug] 30743#30743: *162 writev: 18865 of 18865
3392016/11/15 19:11:26 [debug] 30743#30743: *162 chain writer out: 0000000000000000
3402016/11/15 19:11:26 [debug] 30743#30743: *162 event timer del: 7: 1479237146611
3412016/11/15 19:11:26 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237146611
3422016/11/15 19:11:26 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
3432016/11/15 19:11:26 [debug] 30743#30743: *162 http upstream process header
3442016/11/15 19:11:26 [debug] 30743#30743: *162 malloc: 00000F0D34D69D00:4096
3452016/11/15 19:11:26 [debug] 30743#30743: *162 recv: fd:7 1056 of 4096
3462016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy status 400 "400 Bad Request"
3472016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "Server: nginx"
3482016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "Date: Tue, 15 Nov 2016 19:11:24 GMT"
3492016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "Content-Type: text/html; charset=utf-8"
3502016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "Content-Length: 840"
3512016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "Connection: close"
3522016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "X-Powered-By: Express"
3532016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header: "X-Content-Type-Options: nosniff"
3542016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy header done
3552016/11/15 19:11:26 [debug] 30743#30743: *162 lua capture header filter, uri "/appname/v2/heartbeat"
3562016/11/15 19:11:26 [debug] 30743#30743: *162 http2 header filter
3572016/11/15 19:11:26 [debug] 30743#30743: *162 http2 output header: ":status: 400"
3582016/11/15 19:11:26 [debug] 30743#30743: *162 http2 output header: "server: nginx"
3592016/11/15 19:11:26 [debug] 30743#30743: *162 http2 output header: "date: Tue, 15 Nov 2016 19:11:26 GMT"
3602016/11/15 19:11:26 [debug] 30743#30743: *162 http2 output header: "content-type: text/html; charset=utf-8"
3612016/11/15 19:11:26 [debug] 30743#30743: *162 http2 output header: "content-length: 840"
3622016/11/15 19:11:26 [debug] 30743#30743: *162 http2 output header: "x-powered-by: Express"
3632016/11/15 19:11:26 [debug] 30743#30743: *162 http2 output header: "x-content-type-options: nosniff"
3642016/11/15 19:11:26 [debug] 30743#30743: *162 http2:423 create HEADERS frame 00000F0D34DBEDA8: len:98
3652016/11/15 19:11:26 [debug] 30743#30743: *162 http cleanup add: 00000F0D34DBEE90
3662016/11/15 19:11:26 [debug] 30743#30743: *162 http2 frame out: 00000F0D34DBEDA8 sid:423 bl:1 len:98
3672016/11/15 19:11:26 [debug] 30743#30743: *162 malloc: 00000F0D34D83A10:16384
3682016/11/15 19:11:26 [debug] 30743#30743: *162 SSL buf copy: 9
3692016/11/15 19:11:26 [debug] 30743#30743: *162 SSL buf copy: 98
3702016/11/15 19:11:26 [debug] 30743#30743: *162 http2:423 HEADERS frame 00000F0D34DBEDA8 was sent
3712016/11/15 19:11:26 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34DBEDA8 sid:423 bl:1 len:98
3722016/11/15 19:11:26 [debug] 30743#30743: *162 http cacheable: 0
3732016/11/15 19:11:26 [debug] 30743#30743: *162 http proxy filter init s:400 h:0 c:0 l:840
3742016/11/15 19:11:26 [debug] 30743#30743: *162 http upstream process upstream
3752016/11/15 19:11:26 [debug] 30743#30743: *162 pipe read upstream: 1
3762016/11/15 19:11:26 [debug] 30743#30743: *162 pipe preread: 840
3772016/11/15 19:11:26 [debug] 30743#30743: *162 readv: 1, last:3040
3782016/11/15 19:11:26 [debug] 30743#30743: *162 pipe recv chain: 0
3792016/11/15 19:11:26 [debug] 30743#30743: *162 pipe buf free s:0 t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
3802016/11/15 19:11:26 [debug] 30743#30743: *162 pipe length: 840
3812016/11/15 19:11:26 [debug] 30743#30743: *162 input buf #0
3822016/11/15 19:11:26 [debug] 30743#30743: *162 pipe write downstream: 1
3832016/11/15 19:11:26 [debug] 30743#30743: *162 pipe write downstream flush in
3842016/11/15 19:11:26 [debug] 30743#30743: *162 http output filter "/appname/v2/heartbeat?"
3852016/11/15 19:11:26 [debug] 30743#30743: *162 http copy filter: "/appname/v2/heartbeat?"
3862016/11/15 19:11:26 [debug] 30743#30743: *162 lua capture body filter, uri "/appname/v2/heartbeat"
3872016/11/15 19:11:26 [debug] 30743#30743: *162 http postpone filter "/appname/v2/heartbeat?" 00000F0D34DBEA00
3882016/11/15 19:11:26 [debug] 30743#30743: *162 write new buf t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
3892016/11/15 19:11:26 [debug] 30743#30743: *162 http write filter: l:0 f:0 s:840
3902016/11/15 19:11:26 [debug] 30743#30743: *162 http copy filter: 0 "/appname/v2/heartbeat?"
3912016/11/15 19:11:26 [debug] 30743#30743: *162 pipe write downstream done
3922016/11/15 19:11:26 [debug] 30743#30743: *162 event timer: 7, old: 1479237146611, new: 1479237146644
3932016/11/15 19:11:26 [debug] 30743#30743: *162 http upstream exit: 0000000000000000
3942016/11/15 19:11:26 [debug] 30743#30743: *162 finalize http upstream request: 0
3952016/11/15 19:11:26 [debug] 30743#30743: *162 finalize http proxy request
3962016/11/15 19:11:26 [debug] 30743#30743: *162 free rr peer 1 0
3972016/11/15 19:11:26 [debug] 30743#30743: *162 close http upstream connection: 7
3982016/11/15 19:11:26 [debug] 30743#30743: *162 free: 00000F0D34DBBA30, unused: 48
3992016/11/15 19:11:26 [debug] 30743#30743: *162 event timer del: 7: 1479237146611
4002016/11/15 19:11:26 [debug] 30743#30743: *162 reusable connection: 0
4012016/11/15 19:11:26 [debug] 30743#30743: *162 http upstream temp fd: -1
4022016/11/15 19:11:26 [debug] 30743#30743: *162 http output filter "/appname/v2/heartbeat?"
4032016/11/15 19:11:26 [debug] 30743#30743: *162 http copy filter: "/appname/v2/heartbeat?"
4042016/11/15 19:11:26 [debug] 30743#30743: *162 lua capture body filter, uri "/appname/v2/heartbeat"
4052016/11/15 19:11:26 [debug] 30743#30743: *162 http postpone filter "/appname/v2/heartbeat?" 00007A9E70E05A50
4062016/11/15 19:11:26 [debug] 30743#30743: *162 write old buf t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
4072016/11/15 19:11:26 [debug] 30743#30743: *162 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
4082016/11/15 19:11:26 [debug] 30743#30743: *162 http write filter: l:1 f:0 s:840
4092016/11/15 19:11:26 [debug] 30743#30743: *162 http write filter limit 0
4102016/11/15 19:11:26 [debug] 30743#30743: *162 http2:423 create DATA frame 00000F0D34DBEDA8: len:840 flags:1
4112016/11/15 19:11:26 [debug] 30743#30743: *162 http2 frame out: 00000F0D34DBEDA8 sid:423 bl:0 len:840
4122016/11/15 19:11:26 [debug] 30743#30743: *162 SSL buf copy: 9
4132016/11/15 19:11:26 [debug] 30743#30743: *162 SSL buf copy: 840
4142016/11/15 19:11:26 [debug] 30743#30743: *162 SSL to write: 956
4152016/11/15 19:11:26 [debug] 30743#30743: *162 SSL_write: 956
4162016/11/15 19:11:26 [debug] 30743#30743: *162 http2:423 DATA frame 00000F0D34DBEDA8 was sent
4172016/11/15 19:11:26 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34DBEDA8 sid:423 bl:0 len:840
4182016/11/15 19:11:26 [debug] 30743#30743: *162 http write filter 0000000000000000
4192016/11/15 19:11:26 [debug] 30743#30743: *162 http copy filter: 0 "/appname/v2/heartbeat?"
4202016/11/15 19:11:26 [debug] 30743#30743: *162 http finalize request: 0, "/appname/v2/heartbeat?" a:1, c:1
4212016/11/15 19:11:26 [debug] 30743#30743: *162 http request count:1 blk:0
4222016/11/15 19:11:26 [debug] 30743#30743: *162 http2 close stream 423, queued 0, processing 1
4232016/11/15 19:11:26 [debug] 30743#30743: *162 http close request
4242016/11/15 19:11:26 [debug] 30743#30743: *162 http log handler
4252016/11/15 19:11:26 [debug] 30743#30743: *162 free: 00000F0D34DBC950
4262016/11/15 19:11:26 [debug] 30743#30743: *162 free: 00000F0D34D69D00
4272016/11/15 19:11:26 [debug] 30743#30743: *162 free: 00000F0D34E120F0, unused: 0
4282016/11/15 19:11:26 [debug] 30743#30743: *162 free: 00000F0D34E63480, unused: 2
4292016/11/15 19:11:26 [debug] 30743#30743: *162 free: 00000F0D34EB6570, unused: 0
4302016/11/15 19:11:26 [debug] 30743#30743: *162 free: 00000F0D34EB7580, unused: 8
4312016/11/15 19:11:26 [debug] 30743#30743: *162 free: 00000F0D34DBD960, unused: 0
4322016/11/15 19:11:26 [debug] 30743#30743: *162 free: 00000F0D34DBE970, unused: 1926
4332016/11/15 19:11:26 [debug] 30743#30743: *162 free: 00000F0D34E61530, unused: 162
4342016/11/15 19:11:26 [debug] 30743#30743: *162 free: 00000F0D34E16F00, unused: 156
4352016/11/15 19:11:26 [debug] 30743#30743: *162 free: 00000F0D34DC4930, unused: 111
4362016/11/15 19:11:26 [debug] 30743#30743: *162 post event 00006EFC4C5081F0
4372016/11/15 19:11:26 [debug] 30743#30743: *162 delete posted event 00006EFC4C5081F0
4382016/11/15 19:11:26 [debug] 30743#30743: *162 http2 handle connection handler
4392016/11/15 19:11:26 [debug] 30743#30743: *162 free: 00000F0D34E03BC0, unused: 3488
4402016/11/15 19:11:26 [debug] 30743#30743: *162 free: 00000F0D34D83A10
4412016/11/15 19:11:26 [debug] 30743#30743: *162 reusable connection: 1
4422016/11/15 19:11:26 [debug] 30743#30743: *162 event timer add: 3: 180000:1479237266644
4432016/11/15 19:11:28 [debug] 30743#30743: *162 http2 idle handler
4442016/11/15 19:11:28 [debug] 30743#30743: *162 reusable connection: 0
4452016/11/15 19:11:28 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E120F0:4096 @16
4462016/11/15 19:11:28 [debug] 30743#30743: *162 http2 read handler
4472016/11/15 19:11:28 [debug] 30743#30743: *162 SSL_read: 103
4482016/11/15 19:11:28 [debug] 30743#30743: *162 SSL_read: 2861
4492016/11/15 19:11:28 [debug] 30743#30743: *162 SSL_read: 2861
4502016/11/15 19:11:28 [debug] 30743#30743: *162 SSL_read: -1
4512016/11/15 19:11:28 [debug] 30743#30743: *162 SSL_get_error: 2
4522016/11/15 19:11:28 [debug] 30743#30743: *162 process http2 frame type:1 f:24 l:94 sid:425
4532016/11/15 19:11:28 [debug] 30743#30743: *162 http2 HEADERS frame sid:425 on 0 excl:0 weight:183
4542016/11/15 19:11:28 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DC4930:1024 @16
4552016/11/15 19:11:28 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E03BC0:4096 @16
4562016/11/15 19:11:28 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E63480:4096 @16
4572016/11/15 19:11:28 [debug] 30743#30743: *162 http2 get indexed header name: 3
4582016/11/15 19:11:28 [debug] 30743#30743: *162 http2 get indexed header: 1
4592016/11/15 19:11:28 [debug] 30743#30743: *162 http2 hpack encoded string length: 21
4602016/11/15 19:11:28 [debug] 30743#30743: *162 http2 add header to hpack table: ":authority: app3.mydomain.local"
4612016/11/15 19:11:28 [debug] 30743#30743: *162 http2 hpack table account: 71 free:63
4622016/11/15 19:11:28 [debug] 30743#30743: *162 http2 get indexed header name: 7
4632016/11/15 19:11:28 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
4642016/11/15 19:11:28 [debug] 30743#30743: *162 http2 hpack encoded string length: 13
4652016/11/15 19:11:28 [debug] 30743#30743: *162 http uri: "/appname/v2/heartbeat"
4662016/11/15 19:11:28 [debug] 30743#30743: *162 http args: ""
4672016/11/15 19:11:28 [debug] 30743#30743: *162 http exten: ""
4682016/11/15 19:11:28 [debug] 30743#30743: *162 http2 get indexed header: 28
4692016/11/15 19:11:28 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
4702016/11/15 19:11:28 [debug] 30743#30743: *162 http2 add header to hpack table: "content-length: 26148"
4712016/11/15 19:11:28 [debug] 30743#30743: *162 http2 hpack table account: 51 free:63
4722016/11/15 19:11:28 [debug] 30743#30743: *162 http2 http header: "content-length: 26148"
4732016/11/15 19:11:28 [debug] 30743#30743: *162 http2 get indexed header: 19
4742016/11/15 19:11:28 [debug] 30743#30743: *162 http2 hpack encoded string length: 25
4752016/11/15 19:11:28 [debug] 30743#30743: *162 http2 add header to hpack table: "accept: application/json, text/plain, */*"
4762016/11/15 19:11:28 [debug] 30743#30743: *162 http2 hpack table account: 71 free:12
4772016/11/15 19:11:28 [debug] 30743#30743: *162 http2 http header: "accept: application/json, text/plain, */*"
4782016/11/15 19:11:28 [debug] 30743#30743: *162 http2 get indexed header name: 74
4792016/11/15 19:11:28 [debug] 30743#30743: *162 http2 http header: "origin: https://app3.mydomain.local"
4802016/11/15 19:11:28 [debug] 30743#30743: *162 http2 get indexed header name: 90
4812016/11/15 19:11:28 [debug] 30743#30743: *162 http2 http header: "appname-csrf-token: YYY
4822016/11/15 19:11:28 [debug] 30743#30743: *162 http2 get indexed header name: 93
4832016/11/15 19:11:28 [debug] 30743#30743: *162 http2 http header: "user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36"
4842016/11/15 19:11:28 [debug] 30743#30743: *162 http2 get indexed header name: 73
4852016/11/15 19:11:28 [debug] 30743#30743: *162 http2 http header: "content-type: application/json;charset=UTF-8"
4862016/11/15 19:11:28 [debug] 30743#30743: *162 http2 get indexed header name: 71
4872016/11/15 19:11:28 [debug] 30743#30743: *162 http2 http header: "accept-encoding: gzip, deflate, br"
4882016/11/15 19:11:28 [debug] 30743#30743: *162 http2 get indexed header name: 87
4892016/11/15 19:11:28 [debug] 30743#30743: *162 http2 http header: "accept-language: en-US,en;q=0.8"
4902016/11/15 19:11:28 [debug] 30743#30743: *162 http2 get indexed header name: 86
4912016/11/15 19:11:28 [debug] 30743#30743: *162 http2 get indexed header name: 85
4922016/11/15 19:11:28 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E16F00:1024 @16
4932016/11/15 19:11:28 [debug] 30743#30743: *162 http2 get indexed header name: 84
4942016/11/15 19:11:28 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E61530:1024 @16
4952016/11/15 19:11:28 [debug] 30743#30743: *162 http2 get indexed header name: 83
4962016/11/15 19:11:28 [debug] 30743#30743: *162 http2 get indexed header name: 89
4972016/11/15 19:11:28 [debug] 30743#30743: *162 http2 http request line: "POST /appname/v2/heartbeat HTTP/2.0"
4982016/11/15 19:11:28 [debug] 30743#30743: *162 generic phase: 0
4992016/11/15 19:11:28 [debug] 30743#30743: *162 rewrite phase: 1
5002016/11/15 19:11:28 [debug] 30743#30743: *162 test location: "^/widgets/$"
5012016/11/15 19:11:28 [debug] 30743#30743: *162 test location: "/"
5022016/11/15 19:11:28 [debug] 30743#30743: *162 test location: "docs/"
5032016/11/15 19:11:28 [debug] 30743#30743: *162 test location: "appname/socket"
5042016/11/15 19:11:28 [debug] 30743#30743: *162 test location: ~ "^/(index.html)?$"
5052016/11/15 19:11:28 [debug] 30743#30743: *162 test location: ~ "^/login/?$"
5062016/11/15 19:11:28 [debug] 30743#30743: *162 test location: ~ "^/(appname)"
5072016/11/15 19:11:28 [debug] 30743#30743: *162 test location: ~ "/components/(.*)"
5082016/11/15 19:11:28 [debug] 30743#30743: *162 test location: ~ "^/(auth|logout|status|widgetTypes|index.appname.html|login.appname.html|favicon.ico|(.*)\/vendor\/flags\/.*\.(?:jpg|png)|(.*)\/assets\/images\/company_logo.png)$"
5092016/11/15 19:11:28 [debug] 30743#30743: *162 using configuration "/"
5102016/11/15 19:11:28 [debug] 30743#30743: *162 http cl:26148 max:1073741824
5112016/11/15 19:11:28 [debug] 30743#30743: *162 rewrite phase: 3
5122016/11/15 19:11:28 [debug] 30743#30743: *162 post rewrite phase: 4
5132016/11/15 19:11:28 [debug] 30743#30743: *162 generic phase: 5
5142016/11/15 19:11:28 [debug] 30743#30743: *162 generic phase: 6
5152016/11/15 19:11:28 [debug] 30743#30743: *162 generic phase: 7
5162016/11/15 19:11:28 [debug] 30743#30743: *162 access phase: 8
5172016/11/15 19:11:28 [debug] 30743#30743: *162 access phase: 9
5182016/11/15 19:11:28 [debug] 30743#30743: *162 access phase: 10
5192016/11/15 19:11:28 [debug] 30743#30743: *162 auth request handler
5202016/11/15 19:11:28 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB6570:4096 @16
5212016/11/15 19:11:28 [debug] 30743#30743: *162 http subrequest "/authorize?"
5222016/11/15 19:11:28 [debug] 30743#30743: *162 http posted request: "/authorize?"
5232016/11/15 19:11:28 [debug] 30743#30743: *162 rewrite phase: 1
5242016/11/15 19:11:28 [debug] 30743#30743: *162 test location: "^/widgets/$"
5252016/11/15 19:11:28 [debug] 30743#30743: *162 test location: "/"
5262016/11/15 19:11:28 [debug] 30743#30743: *162 test location: "docs/"
5272016/11/15 19:11:28 [debug] 30743#30743: *162 test location: "dashboard"
5282016/11/15 19:11:28 [debug] 30743#30743: *162 test location: "authorize"
5292016/11/15 19:11:28 [debug] 30743#30743: *162 using configuration "=/authorize"
5302016/11/15 19:11:28 [debug] 30743#30743: *162 http cl:26148 max:1073741824
5312016/11/15 19:11:28 [debug] 30743#30743: *162 rewrite phase: 3
5322016/11/15 19:11:28 [debug] 30743#30743: *162 post rewrite phase: 4
5332016/11/15 19:11:28 [debug] 30743#30743: *162 generic phase: 5
5342016/11/15 19:11:28 [debug] 30743#30743: *162 generic phase: 6
5352016/11/15 19:11:28 [debug] 30743#30743: *162 generic phase: 7
5362016/11/15 19:11:28 [debug] 30743#30743: *162 http init upstream, client timer: 0
5372016/11/15 19:11:28 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB7580:4096 @16
5382016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
5392016/11/15 19:11:28 [debug] 30743#30743: *162 http script var: "192.168.123.11"
5402016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "
541"
5422016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
5432016/11/15 19:11:28 [debug] 30743#30743: *162 http script var: "192.168.123.11"
5442016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "
545"
5462016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "Host: "
5472016/11/15 19:11:28 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
5482016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "
549"
5502016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
551"
5522016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
5532016/11/15 19:11:28 [debug] 30743#30743: *162 http script var: "XXX"
5542016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "
555"
5562016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
5572016/11/15 19:11:28 [debug] 30743#30743: *162 http script var: "XXXX"
5582016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "
559"
5602016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "x-appname-uri: "
5612016/11/15 19:11:28 [debug] 30743#30743: *162 http script var: "/appname/v2/heartbeat"
5622016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "
563"
5642016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "x-appname-method: "
5652016/11/15 19:11:28 [debug] 30743#30743: *162 http script var: "POST"
5662016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "
567"
5682016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "Connection: close
569"
5702016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: ""
5712016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: ""
5722016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
5732016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
5742016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
5752016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36"
5762016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
5772016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
5782016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
5792016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
5802016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header:
581"GET /authorize HTTP/1.1
582X-Real-IP: 192.168.123.11
583X-Forwarded-For: 192.168.123.11
584Host: app3.mydomain.local
585X-NginX-Proxy: true
586
587
588x-appname-uri: /appname/v2/heartbeat
589x-appname-method: POST
590Connection: close
591accept: application/json, text/plain, */*
592origin: https://app3.mydomain.local
593appname-csrf-token: YYY
594user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
595content-type: application/json;charset=UTF-8
596accept-encoding: gzip, deflate, br
597accept-language: en-US,en;q=0.8
5982016/11/15 19:11:28 [debug] 30743#30743: *162 http cleanup add: 00000F0D34E64460
5992016/11/15 19:11:28 [debug] 30743#30743: *162 get rr peer, try: 1
6002016/11/15 19:11:28 [debug] 30743#30743: *162 stream socket 7
6012016/11/15 19:11:28 [debug] 30743#30743: *162 epoll add connection: fd:7 ev:80002005
6022016/11/15 19:11:28 [debug] 30743#30743: *162 connect to 127.0.0.1:8008, fd:7 #567
6032016/11/15 19:11:28 [debug] 30743#30743: *162 http upstream connect: -2
6042016/11/15 19:11:28 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBBA30:128 @16
6052016/11/15 19:11:28 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237148378
6062016/11/15 19:11:28 [debug] 30743#30743: *162 http finalize request: -4, "/authorize?" a:1, c:3
6072016/11/15 19:11:28 [debug] 30743#30743: *162 http request count:3 blk:0
6082016/11/15 19:11:28 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AC077 end:00006EFC4F5AD6D1
6092016/11/15 19:11:28 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:425
6102016/11/15 19:11:28 [debug] 30743#30743: *162 http2 DATA frame
6112016/11/15 19:11:28 [debug] 30743#30743: *162 malloc: 00000F0D34D89720:65536
6122016/11/15 19:11:28 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5ACBA4 end:00006EFC4F5AD6D1
6132016/11/15 19:11:28 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:425
6142016/11/15 19:11:28 [debug] 30743#30743: *162 http2 DATA frame
6152016/11/15 19:11:28 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AD6D1 end:00006EFC4F5AD6D1
6162016/11/15 19:11:28 [debug] 30743#30743: *162 event timer del: 3: 1479237266644
6172016/11/15 19:11:28 [debug] 30743#30743: *162 http2 write handler
6182016/11/15 19:11:28 [debug] 30743#30743: *162 http2 read handler
6192016/11/15 19:11:28 [debug] 30743#30743: *162 SSL_read: 2861
6202016/11/15 19:11:28 [debug] 30743#30743: *162 SSL_read: 2861
6212016/11/15 19:11:28 [debug] 30743#30743: *162 SSL_read: -1
6222016/11/15 19:11:28 [debug] 30743#30743: *162 SSL_get_error: 2
6232016/11/15 19:11:28 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:425
6242016/11/15 19:11:28 [debug] 30743#30743: *162 http2 DATA frame
6252016/11/15 19:11:28 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5ACB3D end:00006EFC4F5AD66A
6262016/11/15 19:11:28 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:425
6272016/11/15 19:11:28 [debug] 30743#30743: *162 http2 DATA frame
6282016/11/15 19:11:28 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AD66A end:00006EFC4F5AD66A
6292016/11/15 19:11:28 [debug] 30743#30743: *162 http2 write handler
6302016/11/15 19:11:28 [debug] 30743#30743: *162 http upstream request: "/authorize?"
6312016/11/15 19:11:28 [debug] 30743#30743: *162 http upstream send request handler
6322016/11/15 19:11:28 [debug] 30743#30743: *162 http upstream send request
6332016/11/15 19:11:28 [debug] 30743#30743: *162 http upstream send request body
6342016/11/15 19:11:28 [debug] 30743#30743: *162 chain writer buf fl:1 s:2711
6352016/11/15 19:11:28 [debug] 30743#30743: *162 chain writer in: 00000F0D34EB7550
6362016/11/15 19:11:28 [debug] 30743#30743: *162 writev: 2711 of 2711
6372016/11/15 19:11:28 [debug] 30743#30743: *162 chain writer out: 0000000000000000
6382016/11/15 19:11:28 [debug] 30743#30743: *162 event timer del: 7: 1479237148378
6392016/11/15 19:11:28 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237148379
6402016/11/15 19:11:28 [debug] 30743#30743: *162 http2 read handler
6412016/11/15 19:11:28 [debug] 30743#30743: *162 SSL_read: 2861
6422016/11/15 19:11:28 [debug] 30743#30743: *162 SSL_read: 2861
6432016/11/15 19:11:28 [debug] 30743#30743: *162 SSL_read: 2861
6442016/11/15 19:11:28 [debug] 30743#30743: *162 SSL_read: 2861
6452016/11/15 19:11:28 [debug] 30743#30743: *162 SSL_read: 2861
6462016/11/15 19:11:28 [debug] 30743#30743: *162 SSL_read: 489
6472016/11/15 19:11:28 [debug] 30743#30743: *162 SSL_read: -1
6482016/11/15 19:11:28 [debug] 30743#30743: *162 SSL_get_error: 2
6492016/11/15 19:11:28 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:425
6502016/11/15 19:11:28 [debug] 30743#30743: *162 http2 DATA frame
6512016/11/15 19:11:28 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5ACB3D end:00006EFC4F5AF9DA
6522016/11/15 19:11:28 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:425
6532016/11/15 19:11:28 [debug] 30743#30743: *162 http2 DATA frame
6542016/11/15 19:11:28 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AD66A end:00006EFC4F5AF9DA
6552016/11/15 19:11:28 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:425
6562016/11/15 19:11:28 [debug] 30743#30743: *162 http2 DATA frame
6572016/11/15 19:11:28 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AE197 end:00006EFC4F5AF9DA
6582016/11/15 19:11:28 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:425
6592016/11/15 19:11:28 [debug] 30743#30743: *162 http2 DATA frame
6602016/11/15 19:11:28 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AECC4 end:00006EFC4F5AF9DA
6612016/11/15 19:11:28 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:425
6622016/11/15 19:11:28 [debug] 30743#30743: *162 http2 DATA frame
6632016/11/15 19:11:28 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AF7F1 end:00006EFC4F5AF9DA
6642016/11/15 19:11:28 [debug] 30743#30743: *162 process http2 frame type:0 f:1 l:480 sid:425
6652016/11/15 19:11:28 [debug] 30743#30743: *162 http2 DATA frame
6662016/11/15 19:11:28 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AF9DA end:00006EFC4F5AF9DA
6672016/11/15 19:11:28 [debug] 30743#30743: *162 http2 write handler
6682016/11/15 19:11:28 [debug] 30743#30743: *162 http2 write handler
6692016/11/15 19:11:28 [debug] 30743#30743: *162 http upstream request: "/authorize?"
6702016/11/15 19:11:28 [debug] 30743#30743: *162 http upstream process header
6712016/11/15 19:11:28 [debug] 30743#30743: *162 malloc: 00000F0D34DBC950:4096
6722016/11/15 19:11:28 [debug] 30743#30743: *162 recv: fd:7 172 of 4096
6732016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy status 200 "200 OK"
6742016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "X-Powered-By: Express"
6752016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "x-appname-origin: appname3"
6762016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "content-type: application/json"
6772016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "content-length: 0"
6782016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "Date: Tue, 15 Nov 2016 19:11:28 GMT"
6792016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "Connection: close"
6802016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header done
6812016/11/15 19:11:28 [debug] 30743#30743: *162 lua capture header filter, uri "/authorize"
6822016/11/15 19:11:28 [debug] 30743#30743: *162 http2 header filter
6832016/11/15 19:11:28 [debug] 30743#30743: *162 finalize http upstream request: 0
6842016/11/15 19:11:28 [debug] 30743#30743: *162 finalize http proxy request
6852016/11/15 19:11:28 [debug] 30743#30743: *162 free rr peer 1 0
6862016/11/15 19:11:28 [debug] 30743#30743: *162 close http upstream connection: 7
6872016/11/15 19:11:28 [debug] 30743#30743: *162 free: 00000F0D34DBBA30, unused: 48
6882016/11/15 19:11:28 [debug] 30743#30743: *162 event timer del: 7: 1479237148379
6892016/11/15 19:11:28 [debug] 30743#30743: *162 reusable connection: 0
6902016/11/15 19:11:28 [debug] 30743#30743: *162 http finalize request: 0, "/authorize?" a:1, c:2
6912016/11/15 19:11:28 [debug] 30743#30743: *162 auth request done s:200
6922016/11/15 19:11:28 [debug] 30743#30743: *162 http wake parent request: "/appname/v2/heartbeat?"
6932016/11/15 19:11:28 [debug] 30743#30743: *162 http posted request: "/appname/v2/heartbeat?"
6942016/11/15 19:11:28 [debug] 30743#30743: *162 access phase: 10
6952016/11/15 19:11:28 [debug] 30743#30743: *162 auth request handler
6962016/11/15 19:11:28 [debug] 30743#30743: *162 auth request set variables
6972016/11/15 19:11:28 [debug] 30743#30743: *162 access phase: 11
6982016/11/15 19:11:28 [debug] 30743#30743: *162 lua access handler, uri:"/appname/v2/heartbeat" c:1
6992016/11/15 19:11:28 [debug] 30743#30743: *162 post access phase: 12
7002016/11/15 19:11:28 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBD960:4096 @16
7012016/11/15 19:11:28 [debug] 30743#30743: *162 http body new buf t:1 f:0 00000F0D34D89720, pos 00000F0D34D89720, size: 26148 file: 0, size: 0
7022016/11/15 19:11:28 [debug] 30743#30743: *162 http init upstream, client timer: 0
7032016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
7042016/11/15 19:11:28 [debug] 30743#30743: *162 http script var: "192.168.123.11"
7052016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "
706"
7072016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
7082016/11/15 19:11:28 [debug] 30743#30743: *162 http script var: "192.168.123.11"
7092016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "
710"
7112016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "Host: "
7122016/11/15 19:11:28 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
7132016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "
714"
7152016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
716"
7172016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
7182016/11/15 19:11:28 [debug] 30743#30743: *162 http script var: "XXX"
7192016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "
720"
7212016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
7222016/11/15 19:11:28 [debug] 30743#30743: *162 http script var: "XXXX"
7232016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "
724"
7252016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "Connection: close
726"
7272016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "Content-Length: "
7282016/11/15 19:11:28 [debug] 30743#30743: *162 http script var: "26148"
7292016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: "
730"
7312016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: ""
7322016/11/15 19:11:28 [debug] 30743#30743: *162 http script copy: ""
7332016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
7342016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
7352016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
7362016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36"
7372016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
7382016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
7392016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
7402016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
7412016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header:
742"POST /appname/v2/heartbeat HTTP/1.1
743X-Real-IP: 192.168.123.11
744X-Forwarded-For: 192.168.123.11
745Host: app3.mydomain.local
746X-NginX-Proxy: true
747
748
749Connection: close
750Content-Length: 26148
751accept: application/json, text/plain, */*
752origin: https://app3.mydomain.local
753appname-csrf-token: YYY
754user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
755content-type: application/json;charset=UTF-8
756accept-encoding: gzip, deflate, br
757accept-language: en-US,en;q=0.8
7582016/11/15 19:11:28 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBE970:4096 @16
7592016/11/15 19:11:28 [debug] 30743#30743: *162 http cleanup add: 00000F0D34DBE938
7602016/11/15 19:11:28 [debug] 30743#30743: *162 get rr peer, try: 1
7612016/11/15 19:11:28 [debug] 30743#30743: *162 stream socket 7
7622016/11/15 19:11:28 [debug] 30743#30743: *162 epoll add connection: fd:7 ev:80002005
7632016/11/15 19:11:28 [debug] 30743#30743: *162 connect to 182.168.128.69:8080, fd:7 #568
7642016/11/15 19:11:28 [debug] 30743#30743: *162 http upstream connect: -2
7652016/11/15 19:11:28 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBBA30:128 @16
7662016/11/15 19:11:28 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237148406
7672016/11/15 19:11:28 [debug] 30743#30743: *162 free: 00000F0D34D89720
7682016/11/15 19:11:28 [debug] 30743#30743: *162 http finalize request: -4, "/appname/v2/heartbeat?" a:1, c:2
7692016/11/15 19:11:28 [debug] 30743#30743: *162 http request count:2 blk:0
7702016/11/15 19:11:28 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
7712016/11/15 19:11:28 [debug] 30743#30743: *162 http upstream send request handler
7722016/11/15 19:11:28 [debug] 30743#30743: *162 http upstream send request
7732016/11/15 19:11:28 [debug] 30743#30743: *162 http upstream send request body
7742016/11/15 19:11:28 [debug] 30743#30743: *162 chain writer buf fl:0 s:2690
7752016/11/15 19:11:28 [debug] 30743#30743: *162 chain writer buf fl:1 s:26148
7762016/11/15 19:11:28 [debug] 30743#30743: *162 chain writer in: 00000F0D34DBEA00
7772016/11/15 19:11:28 [debug] 30743#30743: *162 writev: 28838 of 28838
7782016/11/15 19:11:28 [debug] 30743#30743: *162 chain writer out: 0000000000000000
7792016/11/15 19:11:28 [debug] 30743#30743: *162 event timer del: 7: 1479237148406
7802016/11/15 19:11:28 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237148406
7812016/11/15 19:11:28 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
7822016/11/15 19:11:28 [debug] 30743#30743: *162 http upstream process header
7832016/11/15 19:11:28 [debug] 30743#30743: *162 malloc: 00000F0D34D69D00:4096
7842016/11/15 19:11:28 [debug] 30743#30743: *162 recv: fd:7 1056 of 4096
7852016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy status 400 "400 Bad Request"
7862016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "Server: nginx"
7872016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "Date: Tue, 15 Nov 2016 19:11:29 GMT"
7882016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "Content-Type: text/html; charset=utf-8"
7892016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "Content-Length: 840"
7902016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "Connection: close"
7912016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "X-Powered-By: Express"
7922016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header: "X-Content-Type-Options: nosniff"
7932016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy header done
7942016/11/15 19:11:28 [debug] 30743#30743: *162 lua capture header filter, uri "/appname/v2/heartbeat"
7952016/11/15 19:11:28 [debug] 30743#30743: *162 http2 header filter
7962016/11/15 19:11:28 [debug] 30743#30743: *162 http2 output header: ":status: 400"
7972016/11/15 19:11:28 [debug] 30743#30743: *162 http2 output header: "server: nginx"
7982016/11/15 19:11:28 [debug] 30743#30743: *162 http2 output header: "date: Tue, 15 Nov 2016 19:11:28 GMT"
7992016/11/15 19:11:28 [debug] 30743#30743: *162 http2 output header: "content-type: text/html; charset=utf-8"
8002016/11/15 19:11:28 [debug] 30743#30743: *162 http2 output header: "content-length: 840"
8012016/11/15 19:11:28 [debug] 30743#30743: *162 http2 output header: "x-powered-by: Express"
8022016/11/15 19:11:28 [debug] 30743#30743: *162 http2 output header: "x-content-type-options: nosniff"
8032016/11/15 19:11:28 [debug] 30743#30743: *162 http2:425 create HEADERS frame 00000F0D34DBEDA8: len:98
8042016/11/15 19:11:28 [debug] 30743#30743: *162 http cleanup add: 00000F0D34DBEE90
8052016/11/15 19:11:28 [debug] 30743#30743: *162 http2 frame out: 00000F0D34DBEDA8 sid:425 bl:1 len:98
8062016/11/15 19:11:28 [debug] 30743#30743: *162 malloc: 00000F0D34D83A10:16384
8072016/11/15 19:11:28 [debug] 30743#30743: *162 SSL buf copy: 9
8082016/11/15 19:11:28 [debug] 30743#30743: *162 SSL buf copy: 98
8092016/11/15 19:11:28 [debug] 30743#30743: *162 http2:425 HEADERS frame 00000F0D34DBEDA8 was sent
8102016/11/15 19:11:28 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34DBEDA8 sid:425 bl:1 len:98
8112016/11/15 19:11:28 [debug] 30743#30743: *162 http cacheable: 0
8122016/11/15 19:11:28 [debug] 30743#30743: *162 http proxy filter init s:400 h:0 c:0 l:840
8132016/11/15 19:11:28 [debug] 30743#30743: *162 http upstream process upstream
8142016/11/15 19:11:28 [debug] 30743#30743: *162 pipe read upstream: 1
8152016/11/15 19:11:28 [debug] 30743#30743: *162 pipe preread: 840
8162016/11/15 19:11:28 [debug] 30743#30743: *162 readv: 1, last:3040
8172016/11/15 19:11:28 [debug] 30743#30743: *162 pipe recv chain: 0
8182016/11/15 19:11:28 [debug] 30743#30743: *162 pipe buf free s:0 t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
8192016/11/15 19:11:28 [debug] 30743#30743: *162 pipe length: 840
8202016/11/15 19:11:28 [debug] 30743#30743: *162 input buf #0
8212016/11/15 19:11:28 [debug] 30743#30743: *162 pipe write downstream: 1
8222016/11/15 19:11:28 [debug] 30743#30743: *162 pipe write downstream flush in
8232016/11/15 19:11:28 [debug] 30743#30743: *162 http output filter "/appname/v2/heartbeat?"
8242016/11/15 19:11:28 [debug] 30743#30743: *162 http copy filter: "/appname/v2/heartbeat?"
8252016/11/15 19:11:28 [debug] 30743#30743: *162 lua capture body filter, uri "/appname/v2/heartbeat"
8262016/11/15 19:11:28 [debug] 30743#30743: *162 http postpone filter "/appname/v2/heartbeat?" 00000F0D34DBEA00
8272016/11/15 19:11:28 [debug] 30743#30743: *162 write new buf t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
8282016/11/15 19:11:28 [debug] 30743#30743: *162 http write filter: l:0 f:0 s:840
8292016/11/15 19:11:28 [debug] 30743#30743: *162 http copy filter: 0 "/appname/v2/heartbeat?"
8302016/11/15 19:11:28 [debug] 30743#30743: *162 pipe write downstream done
8312016/11/15 19:11:28 [debug] 30743#30743: *162 event timer: 7, old: 1479237148406, new: 1479237148418
8322016/11/15 19:11:28 [debug] 30743#30743: *162 http upstream exit: 0000000000000000
8332016/11/15 19:11:28 [debug] 30743#30743: *162 finalize http upstream request: 0
8342016/11/15 19:11:28 [debug] 30743#30743: *162 finalize http proxy request
8352016/11/15 19:11:28 [debug] 30743#30743: *162 free rr peer 1 0
8362016/11/15 19:11:28 [debug] 30743#30743: *162 close http upstream connection: 7
8372016/11/15 19:11:28 [debug] 30743#30743: *162 free: 00000F0D34DBBA30, unused: 48
8382016/11/15 19:11:28 [debug] 30743#30743: *162 event timer del: 7: 1479237148406
8392016/11/15 19:11:28 [debug] 30743#30743: *162 reusable connection: 0
8402016/11/15 19:11:28 [debug] 30743#30743: *162 http upstream temp fd: -1
8412016/11/15 19:11:28 [debug] 30743#30743: *162 http output filter "/appname/v2/heartbeat?"
8422016/11/15 19:11:28 [debug] 30743#30743: *162 http copy filter: "/appname/v2/heartbeat?"
8432016/11/15 19:11:28 [debug] 30743#30743: *162 lua capture body filter, uri "/appname/v2/heartbeat"
8442016/11/15 19:11:28 [debug] 30743#30743: *162 http postpone filter "/appname/v2/heartbeat?" 00007A9E70E05A50
8452016/11/15 19:11:28 [debug] 30743#30743: *162 write old buf t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
8462016/11/15 19:11:28 [debug] 30743#30743: *162 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
8472016/11/15 19:11:28 [debug] 30743#30743: *162 http write filter: l:1 f:0 s:840
8482016/11/15 19:11:28 [debug] 30743#30743: *162 http write filter limit 0
8492016/11/15 19:11:28 [debug] 30743#30743: *162 http2:425 create DATA frame 00000F0D34DBEDA8: len:840 flags:1
8502016/11/15 19:11:28 [debug] 30743#30743: *162 http2 frame out: 00000F0D34DBEDA8 sid:425 bl:0 len:840
8512016/11/15 19:11:28 [debug] 30743#30743: *162 SSL buf copy: 9
8522016/11/15 19:11:28 [debug] 30743#30743: *162 SSL buf copy: 840
8532016/11/15 19:11:28 [debug] 30743#30743: *162 SSL to write: 956
8542016/11/15 19:11:28 [debug] 30743#30743: *162 SSL_write: 956
8552016/11/15 19:11:28 [debug] 30743#30743: *162 http2:425 DATA frame 00000F0D34DBEDA8 was sent
8562016/11/15 19:11:28 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34DBEDA8 sid:425 bl:0 len:840
8572016/11/15 19:11:28 [debug] 30743#30743: *162 http write filter 0000000000000000
8582016/11/15 19:11:28 [debug] 30743#30743: *162 http copy filter: 0 "/appname/v2/heartbeat?"
8592016/11/15 19:11:28 [debug] 30743#30743: *162 http finalize request: 0, "/appname/v2/heartbeat?" a:1, c:1
8602016/11/15 19:11:28 [debug] 30743#30743: *162 http request count:1 blk:0
8612016/11/15 19:11:28 [debug] 30743#30743: *162 http2 close stream 425, queued 0, processing 1
8622016/11/15 19:11:28 [debug] 30743#30743: *162 http close request
8632016/11/15 19:11:28 [debug] 30743#30743: *162 http log handler
8642016/11/15 19:11:28 [debug] 30743#30743: *162 free: 00000F0D34DBC950
8652016/11/15 19:11:28 [debug] 30743#30743: *162 free: 00000F0D34D69D00
8662016/11/15 19:11:28 [debug] 30743#30743: *162 free: 00000F0D34E03BC0, unused: 0
8672016/11/15 19:11:28 [debug] 30743#30743: *162 free: 00000F0D34E63480, unused: 2
8682016/11/15 19:11:28 [debug] 30743#30743: *162 free: 00000F0D34EB6570, unused: 0
8692016/11/15 19:11:28 [debug] 30743#30743: *162 free: 00000F0D34EB7580, unused: 8
8702016/11/15 19:11:28 [debug] 30743#30743: *162 free: 00000F0D34DBD960, unused: 0
8712016/11/15 19:11:28 [debug] 30743#30743: *162 free: 00000F0D34DBE970, unused: 1926
8722016/11/15 19:11:28 [debug] 30743#30743: *162 free: 00000F0D34DC4930, unused: 169
8732016/11/15 19:11:28 [debug] 30743#30743: *162 free: 00000F0D34E16F00, unused: 156
8742016/11/15 19:11:28 [debug] 30743#30743: *162 free: 00000F0D34E61530, unused: 111
8752016/11/15 19:11:28 [debug] 30743#30743: *162 post event 00006EFC4C5081F0
8762016/11/15 19:11:28 [debug] 30743#30743: *162 delete posted event 00006EFC4C5081F0
8772016/11/15 19:11:28 [debug] 30743#30743: *162 http2 handle connection handler
8782016/11/15 19:11:28 [debug] 30743#30743: *162 free: 00000F0D34E120F0, unused: 3488
8792016/11/15 19:11:28 [debug] 30743#30743: *162 free: 00000F0D34D83A10
8802016/11/15 19:11:28 [debug] 30743#30743: *162 reusable connection: 1
8812016/11/15 19:11:28 [debug] 30743#30743: *162 event timer add: 3: 180000:1479237268418
8822016/11/15 19:11:31 [debug] 30741#30741: *300 post event 00006EFC4C5082B0
8832016/11/15 19:11:31 [debug] 30741#30741: *300 post event 00006EFC4C2072B0
8842016/11/15 19:11:31 [debug] 30741#30741: *300 delete posted event 00006EFC4C5082B0
8852016/11/15 19:11:31 [debug] 30741#30741: *300 http run request: "/appname/socket/?EIO=3&transport=websocket&sid=fJvr3YafvdqAy4F3AAAC"
8862016/11/15 19:11:31 [debug] 30741#30741: *300 http upstream process upgraded, fu:0
8872016/11/15 19:11:31 [debug] 30741#30741: *300 SSL_read: 9
8882016/11/15 19:11:31 [debug] 30741#30741: *300 SSL_read: -1
8892016/11/15 19:11:31 [debug] 30741#30741: *300 SSL_get_error: 2
8902016/11/15 19:11:31 [debug] 30741#30741: *300 send: fd:16 9 of 9
8912016/11/15 19:11:31 [debug] 30741#30741: *300 event timer del: 16: 1479237126558
8922016/11/15 19:11:31 [debug] 30741#30741: *300 event timer add: 16: 60000:1479237151562
8932016/11/15 19:11:31 [debug] 30741#30741: *300 delete posted event 00006EFC4C2072B0
8942016/11/15 19:11:31 [debug] 30741#30741: *300 http run request: "/appname/socket/?EIO=3&transport=websocket&sid=fJvr3YafvdqAy4F3AAAC"
8952016/11/15 19:11:31 [debug] 30741#30741: *300 http upstream process upgraded, fu:1
8962016/11/15 19:11:31 [debug] 30741#30741: *300 event timer: 16, old: 1479237151562, new: 1479237151562
8972016/11/15 19:11:31 [debug] 30741#30741: *300 post event 00006EFC4C508310
8982016/11/15 19:11:31 [debug] 30741#30741: *300 post event 00006EFC4C207310
8992016/11/15 19:11:31 [debug] 30741#30741: *300 delete posted event 00006EFC4C508310
9002016/11/15 19:11:31 [debug] 30741#30741: *300 http upstream request: "/appname/socket/?EIO=3&transport=websocket&sid=fJvr3YafvdqAy4F3AAAC"
9012016/11/15 19:11:31 [debug] 30741#30741: *300 http upstream process upgraded, fu:1
9022016/11/15 19:11:31 [debug] 30741#30741: *300 recv: fd:16 3 of 4096
9032016/11/15 19:11:31 [debug] 30741#30741: *300 SSL to write: 3
9042016/11/15 19:11:31 [debug] 30741#30741: *300 SSL_write: 3
9052016/11/15 19:11:31 [debug] 30741#30741: *300 recv: fd:16 -1 of 4096
9062016/11/15 19:11:31 [debug] 30741#30741: *300 recv() not ready (11: Resource temporarily unavailable)
9072016/11/15 19:11:31 [debug] 30741#30741: *300 event timer: 16, old: 1479237151562, new: 1479237151564
9082016/11/15 19:11:31 [debug] 30741#30741: *300 delete posted event 00006EFC4C207310
9092016/11/15 19:11:31 [debug] 30741#30741: *300 http upstream request: "/appname/socket/?EIO=3&transport=websocket&sid=fJvr3YafvdqAy4F3AAAC"
9102016/11/15 19:11:31 [debug] 30741#30741: *300 http upstream process upgraded, fu:0
9112016/11/15 19:11:31 [debug] 30741#30741: *300 event timer: 16, old: 1479237151562, new: 1479237151564
9122016/11/15 19:11:38 [debug] 30743#30743: *162 http2 idle handler
9132016/11/15 19:11:38 [debug] 30743#30743: *162 reusable connection: 0
9142016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E03BC0:4096 @16
9152016/11/15 19:11:38 [debug] 30743#30743: *162 http2 read handler
9162016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: 55
9172016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: 17
9182016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: 2861
9192016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: 2861
9202016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: 2861
9212016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: 2861
9222016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: -1
9232016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_get_error: 2
9242016/11/15 19:11:38 [debug] 30743#30743: *162 process http2 frame type:1 f:24 l:46 sid:427
9252016/11/15 19:11:38 [debug] 30743#30743: *162 http2 HEADERS frame sid:427 on 0 excl:0 weight:183
9262016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E61530:1024 @16
9272016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E120F0:4096 @16
9282016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E63480:4096 @16
9292016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 3
9302016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 64
9312016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 7
9322016/11/15 19:11:38 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
9332016/11/15 19:11:38 [debug] 30743#30743: *162 http2 hpack encoded string length: 13
9342016/11/15 19:11:38 [debug] 30743#30743: *162 http uri: "/appname/v2/heartbeat"
9352016/11/15 19:11:38 [debug] 30743#30743: *162 http args: ""
9362016/11/15 19:11:38 [debug] 30743#30743: *162 http exten: ""
9372016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header: 28
9382016/11/15 19:11:38 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
9392016/11/15 19:11:38 [debug] 30743#30743: *162 http2 add header to hpack table: "content-length: 26358"
9402016/11/15 19:11:38 [debug] 30743#30743: *162 http2 hpack table account: 51 free:132
9412016/11/15 19:11:38 [debug] 30743#30743: *162 http2 http header: "content-length: 26358"
9422016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 63
9432016/11/15 19:11:38 [debug] 30743#30743: *162 http2 http header: "accept: application/json, text/plain, */*"
9442016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 75
9452016/11/15 19:11:38 [debug] 30743#30743: *162 http2 http header: "origin: https://app3.mydomain.local"
9462016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 91
9472016/11/15 19:11:38 [debug] 30743#30743: *162 http2 http header: "appname-csrf-token: YYY
9482016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 94
9492016/11/15 19:11:38 [debug] 30743#30743: *162 http2 http header: "user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36"
9502016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 74
9512016/11/15 19:11:38 [debug] 30743#30743: *162 http2 http header: "content-type: application/json;charset=UTF-8"
9522016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 72
9532016/11/15 19:11:38 [debug] 30743#30743: *162 http2 http header: "accept-encoding: gzip, deflate, br"
9542016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 88
9552016/11/15 19:11:38 [debug] 30743#30743: *162 http2 http header: "accept-language: en-US,en;q=0.8"
9562016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 87
9572016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 86
9582016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E16F00:1024 @16
9592016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 85
9602016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DC4930:1024 @16
9612016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 84
9622016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 90
9632016/11/15 19:11:38 [debug] 30743#30743: *162 http2 http request line: "POST /appname/v2/heartbeat HTTP/2.0"
9642016/11/15 19:11:38 [debug] 30743#30743: *162 generic phase: 0
9652016/11/15 19:11:38 [debug] 30743#30743: *162 rewrite phase: 1
9662016/11/15 19:11:38 [debug] 30743#30743: *162 test location: "^/widgets/$"
9672016/11/15 19:11:38 [debug] 30743#30743: *162 test location: "/"
9682016/11/15 19:11:38 [debug] 30743#30743: *162 test location: "docs/"
9692016/11/15 19:11:38 [debug] 30743#30743: *162 test location: "appname/socket"
9702016/11/15 19:11:38 [debug] 30743#30743: *162 test location: ~ "^/(index.html)?$"
9712016/11/15 19:11:38 [debug] 30743#30743: *162 test location: ~ "^/login/?$"
9722016/11/15 19:11:38 [debug] 30743#30743: *162 test location: ~ "^/(appname)"
9732016/11/15 19:11:38 [debug] 30743#30743: *162 test location: ~ "/components/(.*)"
9742016/11/15 19:11:38 [debug] 30743#30743: *162 test location: ~ "^/(auth|logout|status|widgetTypes|index.appname.html|login.appname.html|favicon.ico|(.*)\/vendor\/flags\/.*\.(?:jpg|png)|(.*)\/assets\/images\/company_logo.png)$"
9752016/11/15 19:11:38 [debug] 30743#30743: *162 using configuration "/"
9762016/11/15 19:11:38 [debug] 30743#30743: *162 http cl:26358 max:1073741824
9772016/11/15 19:11:38 [debug] 30743#30743: *162 rewrite phase: 3
9782016/11/15 19:11:38 [debug] 30743#30743: *162 post rewrite phase: 4
9792016/11/15 19:11:38 [debug] 30743#30743: *162 generic phase: 5
9802016/11/15 19:11:38 [debug] 30743#30743: *162 generic phase: 6
9812016/11/15 19:11:38 [debug] 30743#30743: *162 generic phase: 7
9822016/11/15 19:11:38 [debug] 30743#30743: *162 access phase: 8
9832016/11/15 19:11:38 [debug] 30743#30743: *162 access phase: 9
9842016/11/15 19:11:38 [debug] 30743#30743: *162 access phase: 10
9852016/11/15 19:11:38 [debug] 30743#30743: *162 auth request handler
9862016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB6570:4096 @16
9872016/11/15 19:11:38 [debug] 30743#30743: *162 http subrequest "/authorize?"
9882016/11/15 19:11:38 [debug] 30743#30743: *162 http posted request: "/authorize?"
9892016/11/15 19:11:38 [debug] 30743#30743: *162 rewrite phase: 1
9902016/11/15 19:11:38 [debug] 30743#30743: *162 test location: "^/widgets/$"
9912016/11/15 19:11:38 [debug] 30743#30743: *162 test location: "/"
9922016/11/15 19:11:38 [debug] 30743#30743: *162 test location: "docs/"
9932016/11/15 19:11:38 [debug] 30743#30743: *162 test location: "dashboard"
9942016/11/15 19:11:38 [debug] 30743#30743: *162 test location: "authorize"
9952016/11/15 19:11:38 [debug] 30743#30743: *162 using configuration "=/authorize"
9962016/11/15 19:11:38 [debug] 30743#30743: *162 http cl:26358 max:1073741824
9972016/11/15 19:11:38 [debug] 30743#30743: *162 rewrite phase: 3
9982016/11/15 19:11:38 [debug] 30743#30743: *162 post rewrite phase: 4
9992016/11/15 19:11:38 [debug] 30743#30743: *162 generic phase: 5
10002016/11/15 19:11:38 [debug] 30743#30743: *162 generic phase: 6
10012016/11/15 19:11:38 [debug] 30743#30743: *162 generic phase: 7
10022016/11/15 19:11:38 [debug] 30743#30743: *162 http init upstream, client timer: 0
10032016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB7580:4096 @16
10042016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
10052016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "192.168.123.11"
10062016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1007"
10082016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
10092016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "192.168.123.11"
10102016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1011"
10122016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "Host: "
10132016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
10142016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1015"
10162016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
1017"
10182016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
10192016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "XXX"
10202016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1021"
10222016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
10232016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "XXXX"
10242016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1025"
10262016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "x-appname-uri: "
10272016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "/appname/v2/heartbeat"
10282016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1029"
10302016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "x-appname-method: "
10312016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "POST"
10322016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1033"
10342016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "Connection: close
1035"
10362016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: ""
10372016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: ""
10382016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
10392016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
10402016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
10412016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36"
10422016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
10432016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
10442016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
10452016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
10462016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header:
1047"GET /authorize HTTP/1.1
1048X-Real-IP: 192.168.123.11
1049X-Forwarded-For: 192.168.123.11
1050Host: app3.mydomain.local
1051X-NginX-Proxy: true
1052
1053
1054x-appname-uri: /appname/v2/heartbeat
1055x-appname-method: POST
1056Connection: close
1057accept: application/json, text/plain, */*
1058origin: https://app3.mydomain.local
1059appname-csrf-token: YYY
1060user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
1061content-type: application/json;charset=UTF-8
1062accept-encoding: gzip, deflate, br
1063accept-language: en-US,en;q=0.8
10642016/11/15 19:11:38 [debug] 30743#30743: *162 http cleanup add: 00000F0D34E64460
10652016/11/15 19:11:38 [debug] 30743#30743: *162 get rr peer, try: 1
10662016/11/15 19:11:38 [debug] 30743#30743: *162 stream socket 7
10672016/11/15 19:11:38 [debug] 30743#30743: *162 epoll add connection: fd:7 ev:80002005
10682016/11/15 19:11:38 [debug] 30743#30743: *162 connect to 127.0.0.1:8008, fd:7 #569
10692016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream connect: -2
10702016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBBA30:128 @16
10712016/11/15 19:11:38 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237158428
10722016/11/15 19:11:38 [debug] 30743#30743: *162 http finalize request: -4, "/authorize?" a:1, c:3
10732016/11/15 19:11:38 [debug] 30743#30743: *162 http request count:3 blk:0
10742016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AC047 end:00006EFC4F5AED0C
10752016/11/15 19:11:38 [debug] 30743#30743: *162 process http2 frame type:6 f:0 l:8 sid:0
10762016/11/15 19:11:38 [debug] 30743#30743: *162 http2 PING frame, flags: 0
10772016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AC058 end:00006EFC4F5AED0C
10782016/11/15 19:11:38 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:427
10792016/11/15 19:11:38 [debug] 30743#30743: *162 http2 DATA frame
10802016/11/15 19:11:38 [debug] 30743#30743: *162 malloc: 00000F0D34D89720:65536
10812016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5ACB85 end:00006EFC4F5AED0C
10822016/11/15 19:11:38 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:427
10832016/11/15 19:11:38 [debug] 30743#30743: *162 http2 DATA frame
10842016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AD6B2 end:00006EFC4F5AED0C
10852016/11/15 19:11:38 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:427
10862016/11/15 19:11:38 [debug] 30743#30743: *162 http2 DATA frame
10872016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AE1DF end:00006EFC4F5AED0C
10882016/11/15 19:11:38 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:427
10892016/11/15 19:11:38 [debug] 30743#30743: *162 http2 DATA frame
10902016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AED0C end:00006EFC4F5AED0C
10912016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame out: 00000F0D34E03E20 sid:0 bl:0 len:8
10922016/11/15 19:11:38 [debug] 30743#30743: *162 malloc: 00000F0D34D83A10:16384
10932016/11/15 19:11:38 [debug] 30743#30743: *162 SSL buf copy: 17
10942016/11/15 19:11:38 [debug] 30743#30743: *162 SSL to write: 17
10952016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_write: 17
10962016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34E03E20 sid:0 bl:0 len:8
10972016/11/15 19:11:38 [debug] 30743#30743: *162 event timer del: 3: 1479237268418
10982016/11/15 19:11:38 [debug] 30743#30743: *162 http2 write handler
10992016/11/15 19:11:38 [debug] 30743#30743: *162 http2 read handler
11002016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: 2861
11012016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: 2861
11022016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: 2861
11032016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: 2861
11042016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: 2861
11052016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: 699
11062016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: -1
11072016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_get_error: 2
11082016/11/15 19:11:38 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:427
11092016/11/15 19:11:38 [debug] 30743#30743: *162 http2 DATA frame
11102016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5ACB3D end:00006EFC4F5AFAAC
11112016/11/15 19:11:38 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:427
11122016/11/15 19:11:38 [debug] 30743#30743: *162 http2 DATA frame
11132016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AD66A end:00006EFC4F5AFAAC
11142016/11/15 19:11:38 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:427
11152016/11/15 19:11:38 [debug] 30743#30743: *162 http2 DATA frame
11162016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AE197 end:00006EFC4F5AFAAC
11172016/11/15 19:11:38 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:427
11182016/11/15 19:11:38 [debug] 30743#30743: *162 http2 DATA frame
11192016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AECC4 end:00006EFC4F5AFAAC
11202016/11/15 19:11:38 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:427
11212016/11/15 19:11:38 [debug] 30743#30743: *162 http2 DATA frame
11222016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AF7F1 end:00006EFC4F5AFAAC
11232016/11/15 19:11:38 [debug] 30743#30743: *162 process http2 frame type:0 f:1 l:690 sid:427
11242016/11/15 19:11:38 [debug] 30743#30743: *162 http2 DATA frame
11252016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AFAAC end:00006EFC4F5AFAAC
11262016/11/15 19:11:38 [debug] 30743#30743: *162 http2 write handler
11272016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream request: "/authorize?"
11282016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream send request handler
11292016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream send request
11302016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream send request body
11312016/11/15 19:11:38 [debug] 30743#30743: *162 chain writer buf fl:1 s:2711
11322016/11/15 19:11:38 [debug] 30743#30743: *162 chain writer in: 00000F0D34EB7550
11332016/11/15 19:11:38 [debug] 30743#30743: *162 writev: 2711 of 2711
11342016/11/15 19:11:38 [debug] 30743#30743: *162 chain writer out: 0000000000000000
11352016/11/15 19:11:38 [debug] 30743#30743: *162 event timer del: 7: 1479237158428
11362016/11/15 19:11:38 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237158429
11372016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream request: "/authorize?"
11382016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream process header
11392016/11/15 19:11:38 [debug] 30743#30743: *162 malloc: 00000F0D34DBC950:4096
11402016/11/15 19:11:38 [debug] 30743#30743: *162 recv: fd:7 172 of 4096
11412016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy status 200 "200 OK"
11422016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "X-Powered-By: Express"
11432016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "x-appname-origin: appname3"
11442016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "content-type: application/json"
11452016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "content-length: 0"
11462016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "Date: Tue, 15 Nov 2016 19:11:38 GMT"
11472016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "Connection: close"
11482016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header done
11492016/11/15 19:11:38 [debug] 30743#30743: *162 lua capture header filter, uri "/authorize"
11502016/11/15 19:11:38 [debug] 30743#30743: *162 http2 header filter
11512016/11/15 19:11:38 [debug] 30743#30743: *162 finalize http upstream request: 0
11522016/11/15 19:11:38 [debug] 30743#30743: *162 finalize http proxy request
11532016/11/15 19:11:38 [debug] 30743#30743: *162 free rr peer 1 0
11542016/11/15 19:11:38 [debug] 30743#30743: *162 close http upstream connection: 7
11552016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34DBBA30, unused: 48
11562016/11/15 19:11:38 [debug] 30743#30743: *162 event timer del: 7: 1479237158429
11572016/11/15 19:11:38 [debug] 30743#30743: *162 reusable connection: 0
11582016/11/15 19:11:38 [debug] 30743#30743: *162 http finalize request: 0, "/authorize?" a:1, c:2
11592016/11/15 19:11:38 [debug] 30743#30743: *162 auth request done s:200
11602016/11/15 19:11:38 [debug] 30743#30743: *162 http wake parent request: "/appname/v2/heartbeat?"
11612016/11/15 19:11:38 [debug] 30743#30743: *162 http posted request: "/appname/v2/heartbeat?"
11622016/11/15 19:11:38 [debug] 30743#30743: *162 access phase: 10
11632016/11/15 19:11:38 [debug] 30743#30743: *162 auth request handler
11642016/11/15 19:11:38 [debug] 30743#30743: *162 auth request set variables
11652016/11/15 19:11:38 [debug] 30743#30743: *162 access phase: 11
11662016/11/15 19:11:38 [debug] 30743#30743: *162 lua access handler, uri:"/appname/v2/heartbeat" c:1
11672016/11/15 19:11:38 [debug] 30743#30743: *162 post access phase: 12
11682016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBD960:4096 @16
11692016/11/15 19:11:38 [debug] 30743#30743: *162 http body new buf t:1 f:0 00000F0D34D89720, pos 00000F0D34D89720, size: 26358 file: 0, size: 0
11702016/11/15 19:11:38 [debug] 30743#30743: *162 http init upstream, client timer: 0
11712016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
11722016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "192.168.123.11"
11732016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1174"
11752016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
11762016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "192.168.123.11"
11772016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1178"
11792016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "Host: "
11802016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
11812016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1182"
11832016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
1184"
11852016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
11862016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "XXX"
11872016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1188"
11892016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
11902016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "XXXX"
11912016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1192"
11932016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "Connection: close
1194"
11952016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "Content-Length: "
11962016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "26358"
11972016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1198"
11992016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: ""
12002016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: ""
12012016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
12022016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
12032016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
12042016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36"
12052016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
12062016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
12072016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
12082016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
12092016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header:
1210"POST /appname/v2/heartbeat HTTP/1.1
1211X-Real-IP: 192.168.123.11
1212X-Forwarded-For: 192.168.123.11
1213Host: app3.mydomain.local
1214X-NginX-Proxy: true
1215
1216
1217Connection: close
1218Content-Length: 26358
1219accept: application/json, text/plain, */*
1220origin: https://app3.mydomain.local
1221appname-csrf-token: YYY
1222user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
1223content-type: application/json;charset=UTF-8
1224accept-encoding: gzip, deflate, br
1225accept-language: en-US,en;q=0.8
12262016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBE970:4096 @16
12272016/11/15 19:11:38 [debug] 30743#30743: *162 http cleanup add: 00000F0D34DBE938
12282016/11/15 19:11:38 [debug] 30743#30743: *162 get rr peer, try: 1
12292016/11/15 19:11:38 [debug] 30743#30743: *162 stream socket 7
12302016/11/15 19:11:38 [debug] 30743#30743: *162 epoll add connection: fd:7 ev:80002005
12312016/11/15 19:11:38 [debug] 30743#30743: *162 connect to 182.168.128.69:8080, fd:7 #570
12322016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream connect: -2
12332016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBBA30:128 @16
12342016/11/15 19:11:38 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237158454
12352016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34D89720
12362016/11/15 19:11:38 [debug] 30743#30743: *162 http finalize request: -4, "/appname/v2/heartbeat?" a:1, c:2
12372016/11/15 19:11:38 [debug] 30743#30743: *162 http request count:2 blk:0
12382016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
12392016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream send request handler
12402016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream send request
12412016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream send request body
12422016/11/15 19:11:38 [debug] 30743#30743: *162 chain writer buf fl:0 s:2690
12432016/11/15 19:11:38 [debug] 30743#30743: *162 chain writer buf fl:1 s:26358
12442016/11/15 19:11:38 [debug] 30743#30743: *162 chain writer in: 00000F0D34DBEA00
12452016/11/15 19:11:38 [debug] 30743#30743: *162 writev: 28960 of 29048
12462016/11/15 19:11:38 [debug] 30743#30743: *162 chain writer out: 00000F0D34DBEA10
12472016/11/15 19:11:38 [debug] 30743#30743: *162 event timer: 7, old: 1479237158454, new: 1479237158455
12482016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
12492016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream send request handler
12502016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream send request
12512016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream send request body
12522016/11/15 19:11:38 [debug] 30743#30743: *162 chain writer in: 00000F0D34DBEA10
12532016/11/15 19:11:38 [debug] 30743#30743: *162 writev: 88 of 88
12542016/11/15 19:11:38 [debug] 30743#30743: *162 chain writer out: 0000000000000000
12552016/11/15 19:11:38 [debug] 30743#30743: *162 event timer del: 7: 1479237158454
12562016/11/15 19:11:38 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237158456
12572016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
12582016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream process header
12592016/11/15 19:11:38 [debug] 30743#30743: *162 malloc: 00000F0D34D69D00:4096
12602016/11/15 19:11:38 [debug] 30743#30743: *162 recv: fd:7 1056 of 4096
12612016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy status 400 "400 Bad Request"
12622016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "Server: nginx"
12632016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "Date: Tue, 15 Nov 2016 19:11:39 GMT"
12642016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "Content-Type: text/html; charset=utf-8"
12652016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "Content-Length: 840"
12662016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "Connection: close"
12672016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "X-Powered-By: Express"
12682016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "X-Content-Type-Options: nosniff"
12692016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header done
12702016/11/15 19:11:38 [debug] 30743#30743: *162 lua capture header filter, uri "/appname/v2/heartbeat"
12712016/11/15 19:11:38 [debug] 30743#30743: *162 http2 header filter
12722016/11/15 19:11:38 [debug] 30743#30743: *162 http2 output header: ":status: 400"
12732016/11/15 19:11:38 [debug] 30743#30743: *162 http2 output header: "server: nginx"
12742016/11/15 19:11:38 [debug] 30743#30743: *162 http2 output header: "date: Tue, 15 Nov 2016 19:11:38 GMT"
12752016/11/15 19:11:38 [debug] 30743#30743: *162 http2 output header: "content-type: text/html; charset=utf-8"
12762016/11/15 19:11:38 [debug] 30743#30743: *162 http2 output header: "content-length: 840"
12772016/11/15 19:11:38 [debug] 30743#30743: *162 http2 output header: "x-powered-by: Express"
12782016/11/15 19:11:38 [debug] 30743#30743: *162 http2 output header: "x-content-type-options: nosniff"
12792016/11/15 19:11:38 [debug] 30743#30743: *162 http2:427 create HEADERS frame 00000F0D34DBEDA8: len:98
12802016/11/15 19:11:38 [debug] 30743#30743: *162 http cleanup add: 00000F0D34DBEE90
12812016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame out: 00000F0D34DBEDA8 sid:427 bl:1 len:98
12822016/11/15 19:11:38 [debug] 30743#30743: *162 SSL buf copy: 9
12832016/11/15 19:11:38 [debug] 30743#30743: *162 SSL buf copy: 98
12842016/11/15 19:11:38 [debug] 30743#30743: *162 http2:427 HEADERS frame 00000F0D34DBEDA8 was sent
12852016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34DBEDA8 sid:427 bl:1 len:98
12862016/11/15 19:11:38 [debug] 30743#30743: *162 http cacheable: 0
12872016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy filter init s:400 h:0 c:0 l:840
12882016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream process upstream
12892016/11/15 19:11:38 [debug] 30743#30743: *162 pipe read upstream: 1
12902016/11/15 19:11:38 [debug] 30743#30743: *162 pipe preread: 840
12912016/11/15 19:11:38 [debug] 30743#30743: *162 readv: 1, last:3040
12922016/11/15 19:11:38 [debug] 30743#30743: *162 pipe recv chain: 0
12932016/11/15 19:11:38 [debug] 30743#30743: *162 pipe buf free s:0 t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
12942016/11/15 19:11:38 [debug] 30743#30743: *162 pipe length: 840
12952016/11/15 19:11:38 [debug] 30743#30743: *162 input buf #0
12962016/11/15 19:11:38 [debug] 30743#30743: *162 pipe write downstream: 1
12972016/11/15 19:11:38 [debug] 30743#30743: *162 pipe write downstream flush in
12982016/11/15 19:11:38 [debug] 30743#30743: *162 http output filter "/appname/v2/heartbeat?"
12992016/11/15 19:11:38 [debug] 30743#30743: *162 http copy filter: "/appname/v2/heartbeat?"
13002016/11/15 19:11:38 [debug] 30743#30743: *162 lua capture body filter, uri "/appname/v2/heartbeat"
13012016/11/15 19:11:38 [debug] 30743#30743: *162 http postpone filter "/appname/v2/heartbeat?" 00000F0D34DBEA00
13022016/11/15 19:11:38 [debug] 30743#30743: *162 write new buf t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
13032016/11/15 19:11:38 [debug] 30743#30743: *162 http write filter: l:0 f:0 s:840
13042016/11/15 19:11:38 [debug] 30743#30743: *162 http copy filter: 0 "/appname/v2/heartbeat?"
13052016/11/15 19:11:38 [debug] 30743#30743: *162 pipe write downstream done
13062016/11/15 19:11:38 [debug] 30743#30743: *162 event timer: 7, old: 1479237158456, new: 1479237158485
13072016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream exit: 0000000000000000
13082016/11/15 19:11:38 [debug] 30743#30743: *162 finalize http upstream request: 0
13092016/11/15 19:11:38 [debug] 30743#30743: *162 finalize http proxy request
13102016/11/15 19:11:38 [debug] 30743#30743: *162 free rr peer 1 0
13112016/11/15 19:11:38 [debug] 30743#30743: *162 close http upstream connection: 7
13122016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34DBBA30, unused: 48
13132016/11/15 19:11:38 [debug] 30743#30743: *162 event timer del: 7: 1479237158456
13142016/11/15 19:11:38 [debug] 30743#30743: *162 reusable connection: 0
13152016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream temp fd: -1
13162016/11/15 19:11:38 [debug] 30743#30743: *162 http output filter "/appname/v2/heartbeat?"
13172016/11/15 19:11:38 [debug] 30743#30743: *162 http copy filter: "/appname/v2/heartbeat?"
13182016/11/15 19:11:38 [debug] 30743#30743: *162 lua capture body filter, uri "/appname/v2/heartbeat"
13192016/11/15 19:11:38 [debug] 30743#30743: *162 http postpone filter "/appname/v2/heartbeat?" 00007A9E70E05A50
13202016/11/15 19:11:38 [debug] 30743#30743: *162 write old buf t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
13212016/11/15 19:11:38 [debug] 30743#30743: *162 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
13222016/11/15 19:11:38 [debug] 30743#30743: *162 http write filter: l:1 f:0 s:840
13232016/11/15 19:11:38 [debug] 30743#30743: *162 http write filter limit 0
13242016/11/15 19:11:38 [debug] 30743#30743: *162 http2:427 create DATA frame 00000F0D34DBEDA8: len:840 flags:1
13252016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame out: 00000F0D34DBEDA8 sid:427 bl:0 len:840
13262016/11/15 19:11:38 [debug] 30743#30743: *162 SSL buf copy: 9
13272016/11/15 19:11:38 [debug] 30743#30743: *162 SSL buf copy: 840
13282016/11/15 19:11:38 [debug] 30743#30743: *162 SSL to write: 956
13292016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_write: 956
13302016/11/15 19:11:38 [debug] 30743#30743: *162 http2:427 DATA frame 00000F0D34DBEDA8 was sent
13312016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34DBEDA8 sid:427 bl:0 len:840
13322016/11/15 19:11:38 [debug] 30743#30743: *162 http write filter 0000000000000000
13332016/11/15 19:11:38 [debug] 30743#30743: *162 http copy filter: 0 "/appname/v2/heartbeat?"
13342016/11/15 19:11:38 [debug] 30743#30743: *162 http finalize request: 0, "/appname/v2/heartbeat?" a:1, c:1
13352016/11/15 19:11:38 [debug] 30743#30743: *162 http request count:1 blk:0
13362016/11/15 19:11:38 [debug] 30743#30743: *162 http2 close stream 427, queued 0, processing 1
13372016/11/15 19:11:38 [debug] 30743#30743: *162 http close request
13382016/11/15 19:11:38 [debug] 30743#30743: *162 http log handler
13392016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34DBC950
13402016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34D69D00
13412016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34E120F0, unused: 0
13422016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34E63480, unused: 2
13432016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34EB6570, unused: 0
13442016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34EB7580, unused: 8
13452016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34DBD960, unused: 0
13462016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34DBE970, unused: 1926
13472016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34E61530, unused: 162
13482016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34E16F00, unused: 156
13492016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34DC4930, unused: 111
13502016/11/15 19:11:38 [debug] 30743#30743: *162 post event 00006EFC4C5081F0
13512016/11/15 19:11:38 [debug] 30743#30743: *162 delete posted event 00006EFC4C5081F0
13522016/11/15 19:11:38 [debug] 30743#30743: *162 http2 handle connection handler
13532016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34E03BC0, unused: 3312
13542016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34D83A10
13552016/11/15 19:11:38 [debug] 30743#30743: *162 reusable connection: 1
13562016/11/15 19:11:38 [debug] 30743#30743: *162 event timer add: 3: 180000:1479237278485
13572016/11/15 19:11:38 [debug] 30743#30743: *162 http2 idle handler
13582016/11/15 19:11:38 [debug] 30743#30743: *162 reusable connection: 0
13592016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E120F0:4096 @16
13602016/11/15 19:11:38 [debug] 30743#30743: *162 http2 read handler
13612016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: 55
13622016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: -1
13632016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_get_error: 2
13642016/11/15 19:11:38 [debug] 30743#30743: *162 process http2 frame type:1 f:24 l:46 sid:429
13652016/11/15 19:11:38 [debug] 30743#30743: *162 http2 HEADERS frame sid:429 on 0 excl:0 weight:183
13662016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DC4930:1024 @16
13672016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E03BC0:4096 @16
13682016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E63480:4096 @16
13692016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 3
13702016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 65
13712016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 7
13722016/11/15 19:11:38 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
13732016/11/15 19:11:38 [debug] 30743#30743: *162 http2 hpack encoded string length: 13
13742016/11/15 19:11:38 [debug] 30743#30743: *162 http uri: "/appname/v2/heartbeat"
13752016/11/15 19:11:38 [debug] 30743#30743: *162 http args: ""
13762016/11/15 19:11:38 [debug] 30743#30743: *162 http exten: ""
13772016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header: 28
13782016/11/15 19:11:38 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
13792016/11/15 19:11:38 [debug] 30743#30743: *162 http2 add header to hpack table: "content-length: 16385"
13802016/11/15 19:11:38 [debug] 30743#30743: *162 http2 hpack table account: 51 free:81
13812016/11/15 19:11:38 [debug] 30743#30743: *162 http2 http header: "content-length: 16385"
13822016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 64
13832016/11/15 19:11:38 [debug] 30743#30743: *162 http2 http header: "accept: application/json, text/plain, */*"
13842016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 76
13852016/11/15 19:11:38 [debug] 30743#30743: *162 http2 http header: "origin: https://app3.mydomain.local"
13862016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 92
13872016/11/15 19:11:38 [debug] 30743#30743: *162 http2 http header: "appname-csrf-token: YYY
13882016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 95
13892016/11/15 19:11:38 [debug] 30743#30743: *162 http2 http header: "user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36"
13902016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 75
13912016/11/15 19:11:38 [debug] 30743#30743: *162 http2 http header: "content-type: application/json;charset=UTF-8"
13922016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 73
13932016/11/15 19:11:38 [debug] 30743#30743: *162 http2 http header: "accept-encoding: gzip, deflate, br"
13942016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 89
13952016/11/15 19:11:38 [debug] 30743#30743: *162 http2 http header: "accept-language: en-US,en;q=0.8"
13962016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 88
13972016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 87
13982016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E16F00:1024 @16
13992016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 86
14002016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E61530:1024 @16
14012016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 85
14022016/11/15 19:11:38 [debug] 30743#30743: *162 http2 get indexed header name: 91
14032016/11/15 19:11:38 [debug] 30743#30743: *162 http2 http request line: "POST /appname/v2/heartbeat HTTP/2.0"
14042016/11/15 19:11:38 [debug] 30743#30743: *162 generic phase: 0
14052016/11/15 19:11:38 [debug] 30743#30743: *162 rewrite phase: 1
14062016/11/15 19:11:38 [debug] 30743#30743: *162 test location: "^/widgets/$"
14072016/11/15 19:11:38 [debug] 30743#30743: *162 test location: "/"
14082016/11/15 19:11:38 [debug] 30743#30743: *162 test location: "docs/"
14092016/11/15 19:11:38 [debug] 30743#30743: *162 test location: "appname/socket"
14102016/11/15 19:11:38 [debug] 30743#30743: *162 test location: ~ "^/(index.html)?$"
14112016/11/15 19:11:38 [debug] 30743#30743: *162 test location: ~ "^/login/?$"
14122016/11/15 19:11:38 [debug] 30743#30743: *162 test location: ~ "^/(appname)"
14132016/11/15 19:11:38 [debug] 30743#30743: *162 test location: ~ "/components/(.*)"
14142016/11/15 19:11:38 [debug] 30743#30743: *162 test location: ~ "^/(auth|logout|status|widgetTypes|index.appname.html|login.appname.html|favicon.ico|(.*)\/vendor\/flags\/.*\.(?:jpg|png)|(.*)\/assets\/images\/company_logo.png)$"
14152016/11/15 19:11:38 [debug] 30743#30743: *162 using configuration "/"
14162016/11/15 19:11:38 [debug] 30743#30743: *162 http cl:16385 max:1073741824
14172016/11/15 19:11:38 [debug] 30743#30743: *162 rewrite phase: 3
14182016/11/15 19:11:38 [debug] 30743#30743: *162 post rewrite phase: 4
14192016/11/15 19:11:38 [debug] 30743#30743: *162 generic phase: 5
14202016/11/15 19:11:38 [debug] 30743#30743: *162 generic phase: 6
14212016/11/15 19:11:38 [debug] 30743#30743: *162 generic phase: 7
14222016/11/15 19:11:38 [debug] 30743#30743: *162 access phase: 8
14232016/11/15 19:11:38 [debug] 30743#30743: *162 access phase: 9
14242016/11/15 19:11:38 [debug] 30743#30743: *162 access phase: 10
14252016/11/15 19:11:38 [debug] 30743#30743: *162 auth request handler
14262016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB6570:4096 @16
14272016/11/15 19:11:38 [debug] 30743#30743: *162 http subrequest "/authorize?"
14282016/11/15 19:11:38 [debug] 30743#30743: *162 http posted request: "/authorize?"
14292016/11/15 19:11:38 [debug] 30743#30743: *162 rewrite phase: 1
14302016/11/15 19:11:38 [debug] 30743#30743: *162 test location: "^/widgets/$"
14312016/11/15 19:11:38 [debug] 30743#30743: *162 test location: "/"
14322016/11/15 19:11:38 [debug] 30743#30743: *162 test location: "docs/"
14332016/11/15 19:11:38 [debug] 30743#30743: *162 test location: "dashboard"
14342016/11/15 19:11:38 [debug] 30743#30743: *162 test location: "authorize"
14352016/11/15 19:11:38 [debug] 30743#30743: *162 using configuration "=/authorize"
14362016/11/15 19:11:38 [debug] 30743#30743: *162 http cl:16385 max:1073741824
14372016/11/15 19:11:38 [debug] 30743#30743: *162 rewrite phase: 3
14382016/11/15 19:11:38 [debug] 30743#30743: *162 post rewrite phase: 4
14392016/11/15 19:11:38 [debug] 30743#30743: *162 generic phase: 5
14402016/11/15 19:11:38 [debug] 30743#30743: *162 generic phase: 6
14412016/11/15 19:11:38 [debug] 30743#30743: *162 generic phase: 7
14422016/11/15 19:11:38 [debug] 30743#30743: *162 http init upstream, client timer: 0
14432016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB7580:4096 @16
14442016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
14452016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "192.168.123.11"
14462016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1447"
14482016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
14492016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "192.168.123.11"
14502016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1451"
14522016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "Host: "
14532016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
14542016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1455"
14562016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
1457"
14582016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
14592016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "XXX"
14602016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1461"
14622016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
14632016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "XXXX"
14642016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1465"
14662016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "x-appname-uri: "
14672016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "/appname/v2/heartbeat"
14682016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1469"
14702016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "x-appname-method: "
14712016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "POST"
14722016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1473"
14742016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "Connection: close
1475"
14762016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: ""
14772016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: ""
14782016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
14792016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
14802016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
14812016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36"
14822016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
14832016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
14842016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
14852016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
14862016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header:
1487"GET /authorize HTTP/1.1
1488X-Real-IP: 192.168.123.11
1489X-Forwarded-For: 192.168.123.11
1490Host: app3.mydomain.local
1491X-NginX-Proxy: true
1492
1493
1494x-appname-uri: /appname/v2/heartbeat
1495x-appname-method: POST
1496Connection: close
1497accept: application/json, text/plain, */*
1498origin: https://app3.mydomain.local
1499appname-csrf-token: YYY
1500user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
1501content-type: application/json;charset=UTF-8
1502accept-encoding: gzip, deflate, br
1503accept-language: en-US,en;q=0.8
15042016/11/15 19:11:38 [debug] 30743#30743: *162 http cleanup add: 00000F0D34E64460
15052016/11/15 19:11:38 [debug] 30743#30743: *162 get rr peer, try: 1
15062016/11/15 19:11:38 [debug] 30743#30743: *162 stream socket 7
15072016/11/15 19:11:38 [debug] 30743#30743: *162 epoll add connection: fd:7 ev:80002005
15082016/11/15 19:11:38 [debug] 30743#30743: *162 connect to 127.0.0.1:8008, fd:7 #571
15092016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream connect: -2
15102016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBBA30:128 @16
15112016/11/15 19:11:38 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237158567
15122016/11/15 19:11:38 [debug] 30743#30743: *162 http finalize request: -4, "/authorize?" a:1, c:3
15132016/11/15 19:11:38 [debug] 30743#30743: *162 http request count:3 blk:0
15142016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AC047 end:00006EFC4F5AC047
15152016/11/15 19:11:38 [debug] 30743#30743: *162 event timer del: 3: 1479237278485
15162016/11/15 19:11:38 [debug] 30743#30743: *162 http2 write handler
15172016/11/15 19:11:38 [debug] 30743#30743: *162 http2 read handler
15182016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: 2861
15192016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: 2861
15202016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: 2861
15212016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: 2861
15222016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: 2861
15232016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: 2134
15242016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_read: -1
15252016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_get_error: 2
15262016/11/15 19:11:38 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:429
15272016/11/15 19:11:38 [debug] 30743#30743: *162 http2 DATA frame
15282016/11/15 19:11:38 [debug] 30743#30743: *162 malloc: 00000F0D34D89720:65536
15292016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5ACB3D end:00006EFC4F5B0047
15302016/11/15 19:11:38 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:429
15312016/11/15 19:11:38 [debug] 30743#30743: *162 http2 DATA frame
15322016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AD66A end:00006EFC4F5B0047
15332016/11/15 19:11:38 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:429
15342016/11/15 19:11:38 [debug] 30743#30743: *162 http2 DATA frame
15352016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AE197 end:00006EFC4F5B0047
15362016/11/15 19:11:38 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:429
15372016/11/15 19:11:38 [debug] 30743#30743: *162 http2 DATA frame
15382016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AECC4 end:00006EFC4F5B0047
15392016/11/15 19:11:38 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:429
15402016/11/15 19:11:38 [debug] 30743#30743: *162 http2 DATA frame
15412016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AF7F1 end:00006EFC4F5B0047
15422016/11/15 19:11:38 [debug] 30743#30743: *162 process http2 frame type:0 f:1 l:2125 sid:429
15432016/11/15 19:11:38 [debug] 30743#30743: *162 http2 DATA frame
15442016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5B0047 end:00006EFC4F5B0047
15452016/11/15 19:11:38 [debug] 30743#30743: *162 http2 write handler
15462016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream request: "/authorize?"
15472016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream send request handler
15482016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream send request
15492016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream send request body
15502016/11/15 19:11:38 [debug] 30743#30743: *162 chain writer buf fl:1 s:2711
15512016/11/15 19:11:38 [debug] 30743#30743: *162 chain writer in: 00000F0D34EB7550
15522016/11/15 19:11:38 [debug] 30743#30743: *162 writev: 2711 of 2711
15532016/11/15 19:11:38 [debug] 30743#30743: *162 chain writer out: 0000000000000000
15542016/11/15 19:11:38 [debug] 30743#30743: *162 event timer del: 7: 1479237158567
15552016/11/15 19:11:38 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237158567
15562016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream request: "/authorize?"
15572016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream process header
15582016/11/15 19:11:38 [debug] 30743#30743: *162 malloc: 00000F0D34DBC950:4096
15592016/11/15 19:11:38 [debug] 30743#30743: *162 recv: fd:7 172 of 4096
15602016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy status 200 "200 OK"
15612016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "X-Powered-By: Express"
15622016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "x-appname-origin: appname3"
15632016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "content-type: application/json"
15642016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "content-length: 0"
15652016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "Date: Tue, 15 Nov 2016 19:11:38 GMT"
15662016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "Connection: close"
15672016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header done
15682016/11/15 19:11:38 [debug] 30743#30743: *162 lua capture header filter, uri "/authorize"
15692016/11/15 19:11:38 [debug] 30743#30743: *162 http2 header filter
15702016/11/15 19:11:38 [debug] 30743#30743: *162 finalize http upstream request: 0
15712016/11/15 19:11:38 [debug] 30743#30743: *162 finalize http proxy request
15722016/11/15 19:11:38 [debug] 30743#30743: *162 free rr peer 1 0
15732016/11/15 19:11:38 [debug] 30743#30743: *162 close http upstream connection: 7
15742016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34DBBA30, unused: 48
15752016/11/15 19:11:38 [debug] 30743#30743: *162 event timer del: 7: 1479237158567
15762016/11/15 19:11:38 [debug] 30743#30743: *162 reusable connection: 0
15772016/11/15 19:11:38 [debug] 30743#30743: *162 http finalize request: 0, "/authorize?" a:1, c:2
15782016/11/15 19:11:38 [debug] 30743#30743: *162 auth request done s:200
15792016/11/15 19:11:38 [debug] 30743#30743: *162 http wake parent request: "/appname/v2/heartbeat?"
15802016/11/15 19:11:38 [debug] 30743#30743: *162 http posted request: "/appname/v2/heartbeat?"
15812016/11/15 19:11:38 [debug] 30743#30743: *162 access phase: 10
15822016/11/15 19:11:38 [debug] 30743#30743: *162 auth request handler
15832016/11/15 19:11:38 [debug] 30743#30743: *162 auth request set variables
15842016/11/15 19:11:38 [debug] 30743#30743: *162 access phase: 11
15852016/11/15 19:11:38 [debug] 30743#30743: *162 lua access handler, uri:"/appname/v2/heartbeat" c:1
15862016/11/15 19:11:38 [debug] 30743#30743: *162 post access phase: 12
15872016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBD960:4096 @16
15882016/11/15 19:11:38 [debug] 30743#30743: *162 http body new buf t:1 f:0 00000F0D34D89720, pos 00000F0D34D89720, size: 16385 file: 0, size: 0
15892016/11/15 19:11:38 [debug] 30743#30743: *162 http init upstream, client timer: 0
15902016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
15912016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "192.168.123.11"
15922016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1593"
15942016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
15952016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "192.168.123.11"
15962016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1597"
15982016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "Host: "
15992016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
16002016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1601"
16022016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
1603"
16042016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
16052016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "XXX"
16062016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1607"
16082016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
16092016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "XXXX"
16102016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1611"
16122016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "Connection: close
1613"
16142016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "Content-Length: "
16152016/11/15 19:11:38 [debug] 30743#30743: *162 http script var: "16385"
16162016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: "
1617"
16182016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: ""
16192016/11/15 19:11:38 [debug] 30743#30743: *162 http script copy: ""
16202016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
16212016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
16222016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
16232016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36"
16242016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
16252016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
16262016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
16272016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
16282016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header:
1629"POST /appname/v2/heartbeat HTTP/1.1
1630X-Real-IP: 192.168.123.11
1631X-Forwarded-For: 192.168.123.11
1632Host: app3.mydomain.local
1633X-NginX-Proxy: true
1634
1635
1636Connection: close
1637Content-Length: 16385
1638accept: application/json, text/plain, */*
1639origin: https://app3.mydomain.local
1640appname-csrf-token: YYY
1641user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
1642content-type: application/json;charset=UTF-8
1643accept-encoding: gzip, deflate, br
1644accept-language: en-US,en;q=0.8
16452016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBE970:4096 @16
16462016/11/15 19:11:38 [debug] 30743#30743: *162 http cleanup add: 00000F0D34DBE938
16472016/11/15 19:11:38 [debug] 30743#30743: *162 get rr peer, try: 1
16482016/11/15 19:11:38 [debug] 30743#30743: *162 stream socket 7
16492016/11/15 19:11:38 [debug] 30743#30743: *162 epoll add connection: fd:7 ev:80002005
16502016/11/15 19:11:38 [debug] 30743#30743: *162 connect to 182.168.128.69:8080, fd:7 #572
16512016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream connect: -2
16522016/11/15 19:11:38 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBBA30:128 @16
16532016/11/15 19:11:38 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237158590
16542016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34D89720
16552016/11/15 19:11:38 [debug] 30743#30743: *162 http finalize request: -4, "/appname/v2/heartbeat?" a:1, c:2
16562016/11/15 19:11:38 [debug] 30743#30743: *162 http request count:2 blk:0
16572016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
16582016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream send request handler
16592016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream send request
16602016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream send request body
16612016/11/15 19:11:38 [debug] 30743#30743: *162 chain writer buf fl:0 s:2690
16622016/11/15 19:11:38 [debug] 30743#30743: *162 chain writer buf fl:1 s:16385
16632016/11/15 19:11:38 [debug] 30743#30743: *162 chain writer in: 00000F0D34DBEA00
16642016/11/15 19:11:38 [debug] 30743#30743: *162 writev: 19075 of 19075
16652016/11/15 19:11:38 [debug] 30743#30743: *162 chain writer out: 0000000000000000
16662016/11/15 19:11:38 [debug] 30743#30743: *162 event timer del: 7: 1479237158590
16672016/11/15 19:11:38 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237158591
16682016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
16692016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream process header
16702016/11/15 19:11:38 [debug] 30743#30743: *162 malloc: 00000F0D34D69D00:4096
16712016/11/15 19:11:38 [debug] 30743#30743: *162 recv: fd:7 1056 of 4096
16722016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy status 400 "400 Bad Request"
16732016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "Server: nginx"
16742016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "Date: Tue, 15 Nov 2016 19:11:38 GMT"
16752016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "Content-Type: text/html; charset=utf-8"
16762016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "Content-Length: 840"
16772016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "Connection: close"
16782016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "X-Powered-By: Express"
16792016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header: "X-Content-Type-Options: nosniff"
16802016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy header done
16812016/11/15 19:11:38 [debug] 30743#30743: *162 lua capture header filter, uri "/appname/v2/heartbeat"
16822016/11/15 19:11:38 [debug] 30743#30743: *162 http2 header filter
16832016/11/15 19:11:38 [debug] 30743#30743: *162 http2 output header: ":status: 400"
16842016/11/15 19:11:38 [debug] 30743#30743: *162 http2 output header: "server: nginx"
16852016/11/15 19:11:38 [debug] 30743#30743: *162 http2 output header: "date: Tue, 15 Nov 2016 19:11:38 GMT"
16862016/11/15 19:11:38 [debug] 30743#30743: *162 http2 output header: "content-type: text/html; charset=utf-8"
16872016/11/15 19:11:38 [debug] 30743#30743: *162 http2 output header: "content-length: 840"
16882016/11/15 19:11:38 [debug] 30743#30743: *162 http2 output header: "x-powered-by: Express"
16892016/11/15 19:11:38 [debug] 30743#30743: *162 http2 output header: "x-content-type-options: nosniff"
16902016/11/15 19:11:38 [debug] 30743#30743: *162 http2:429 create HEADERS frame 00000F0D34DBEDA8: len:98
16912016/11/15 19:11:38 [debug] 30743#30743: *162 http cleanup add: 00000F0D34DBEE90
16922016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame out: 00000F0D34DBEDA8 sid:429 bl:1 len:98
16932016/11/15 19:11:38 [debug] 30743#30743: *162 malloc: 00000F0D34D83A10:16384
16942016/11/15 19:11:38 [debug] 30743#30743: *162 SSL buf copy: 9
16952016/11/15 19:11:38 [debug] 30743#30743: *162 SSL buf copy: 98
16962016/11/15 19:11:38 [debug] 30743#30743: *162 http2:429 HEADERS frame 00000F0D34DBEDA8 was sent
16972016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34DBEDA8 sid:429 bl:1 len:98
16982016/11/15 19:11:38 [debug] 30743#30743: *162 http cacheable: 0
16992016/11/15 19:11:38 [debug] 30743#30743: *162 http proxy filter init s:400 h:0 c:0 l:840
17002016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream process upstream
17012016/11/15 19:11:38 [debug] 30743#30743: *162 pipe read upstream: 1
17022016/11/15 19:11:38 [debug] 30743#30743: *162 pipe preread: 840
17032016/11/15 19:11:38 [debug] 30743#30743: *162 readv: 1, last:3040
17042016/11/15 19:11:38 [debug] 30743#30743: *162 pipe recv chain: 0
17052016/11/15 19:11:38 [debug] 30743#30743: *162 pipe buf free s:0 t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
17062016/11/15 19:11:38 [debug] 30743#30743: *162 pipe length: 840
17072016/11/15 19:11:38 [debug] 30743#30743: *162 input buf #0
17082016/11/15 19:11:38 [debug] 30743#30743: *162 pipe write downstream: 1
17092016/11/15 19:11:38 [debug] 30743#30743: *162 pipe write downstream flush in
17102016/11/15 19:11:38 [debug] 30743#30743: *162 http output filter "/appname/v2/heartbeat?"
17112016/11/15 19:11:38 [debug] 30743#30743: *162 http copy filter: "/appname/v2/heartbeat?"
17122016/11/15 19:11:38 [debug] 30743#30743: *162 lua capture body filter, uri "/appname/v2/heartbeat"
17132016/11/15 19:11:38 [debug] 30743#30743: *162 http postpone filter "/appname/v2/heartbeat?" 00000F0D34DBEA00
17142016/11/15 19:11:38 [debug] 30743#30743: *162 write new buf t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
17152016/11/15 19:11:38 [debug] 30743#30743: *162 http write filter: l:0 f:0 s:840
17162016/11/15 19:11:38 [debug] 30743#30743: *162 http copy filter: 0 "/appname/v2/heartbeat?"
17172016/11/15 19:11:38 [debug] 30743#30743: *162 pipe write downstream done
17182016/11/15 19:11:38 [debug] 30743#30743: *162 event timer: 7, old: 1479237158591, new: 1479237158599
17192016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream exit: 0000000000000000
17202016/11/15 19:11:38 [debug] 30743#30743: *162 finalize http upstream request: 0
17212016/11/15 19:11:38 [debug] 30743#30743: *162 finalize http proxy request
17222016/11/15 19:11:38 [debug] 30743#30743: *162 free rr peer 1 0
17232016/11/15 19:11:38 [debug] 30743#30743: *162 close http upstream connection: 7
17242016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34DBBA30, unused: 48
17252016/11/15 19:11:38 [debug] 30743#30743: *162 event timer del: 7: 1479237158591
17262016/11/15 19:11:38 [debug] 30743#30743: *162 reusable connection: 0
17272016/11/15 19:11:38 [debug] 30743#30743: *162 http upstream temp fd: -1
17282016/11/15 19:11:38 [debug] 30743#30743: *162 http output filter "/appname/v2/heartbeat?"
17292016/11/15 19:11:38 [debug] 30743#30743: *162 http copy filter: "/appname/v2/heartbeat?"
17302016/11/15 19:11:38 [debug] 30743#30743: *162 lua capture body filter, uri "/appname/v2/heartbeat"
17312016/11/15 19:11:38 [debug] 30743#30743: *162 http postpone filter "/appname/v2/heartbeat?" 00007A9E70E05A50
17322016/11/15 19:11:38 [debug] 30743#30743: *162 write old buf t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
17332016/11/15 19:11:38 [debug] 30743#30743: *162 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
17342016/11/15 19:11:38 [debug] 30743#30743: *162 http write filter: l:1 f:0 s:840
17352016/11/15 19:11:38 [debug] 30743#30743: *162 http write filter limit 0
17362016/11/15 19:11:38 [debug] 30743#30743: *162 http2:429 create DATA frame 00000F0D34DBEDA8: len:840 flags:1
17372016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame out: 00000F0D34DBEDA8 sid:429 bl:0 len:840
17382016/11/15 19:11:38 [debug] 30743#30743: *162 SSL buf copy: 9
17392016/11/15 19:11:38 [debug] 30743#30743: *162 SSL buf copy: 840
17402016/11/15 19:11:38 [debug] 30743#30743: *162 SSL to write: 956
17412016/11/15 19:11:38 [debug] 30743#30743: *162 SSL_write: 956
17422016/11/15 19:11:38 [debug] 30743#30743: *162 http2:429 DATA frame 00000F0D34DBEDA8 was sent
17432016/11/15 19:11:38 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34DBEDA8 sid:429 bl:0 len:840
17442016/11/15 19:11:38 [debug] 30743#30743: *162 http write filter 0000000000000000
17452016/11/15 19:11:38 [debug] 30743#30743: *162 http copy filter: 0 "/appname/v2/heartbeat?"
17462016/11/15 19:11:38 [debug] 30743#30743: *162 http finalize request: 0, "/appname/v2/heartbeat?" a:1, c:1
17472016/11/15 19:11:38 [debug] 30743#30743: *162 http request count:1 blk:0
17482016/11/15 19:11:38 [debug] 30743#30743: *162 http2 close stream 429, queued 0, processing 1
17492016/11/15 19:11:38 [debug] 30743#30743: *162 http close request
17502016/11/15 19:11:38 [debug] 30743#30743: *162 http log handler
17512016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34DBC950
17522016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34D69D00
17532016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34E03BC0, unused: 0
17542016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34E63480, unused: 2
17552016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34EB6570, unused: 0
17562016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34EB7580, unused: 8
17572016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34DBD960, unused: 0
17582016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34DBE970, unused: 1926
17592016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34DC4930, unused: 162
17602016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34E16F00, unused: 156
17612016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34E61530, unused: 111
17622016/11/15 19:11:38 [debug] 30743#30743: *162 post event 00006EFC4C5081F0
17632016/11/15 19:11:38 [debug] 30743#30743: *162 delete posted event 00006EFC4C5081F0
17642016/11/15 19:11:38 [debug] 30743#30743: *162 http2 handle connection handler
17652016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34E120F0, unused: 3488
17662016/11/15 19:11:38 [debug] 30743#30743: *162 free: 00000F0D34D83A10
17672016/11/15 19:11:38 [debug] 30743#30743: *162 reusable connection: 1
17682016/11/15 19:11:38 [debug] 30743#30743: *162 event timer add: 3: 180000:1479237278599
17692016/11/15 19:11:48 [debug] 30741#30741: *251 post event 00006EFC4C5081F0
17702016/11/15 19:11:48 [debug] 30741#30741: *251 post event 00006EFC4C2071F0
17712016/11/15 19:11:48 [debug] 30741#30741: *251 delete posted event 00006EFC4C5081F0
17722016/11/15 19:11:48 [debug] 30741#30741: *251 http run request: "/appname/socket/?EIO=3&transport=websocket&sid=UJyFqU73bEe42MVkAAAB"
17732016/11/15 19:11:48 [debug] 30741#30741: *251 http upstream process upgraded, fu:0
17742016/11/15 19:11:48 [debug] 30741#30741: *251 SSL_read: 9
17752016/11/15 19:11:48 [debug] 30741#30741: *251 SSL_read: -1
17762016/11/15 19:11:48 [debug] 30741#30741: *251 SSL_get_error: 2
17772016/11/15 19:11:48 [debug] 30741#30741: *251 send: fd:14 9 of 9
17782016/11/15 19:11:48 [debug] 30741#30741: *251 event timer del: 14: 1479237143065
17792016/11/15 19:11:48 [debug] 30741#30741: *251 event timer add: 14: 60000:1479237168070
17802016/11/15 19:11:48 [debug] 30741#30741: *251 delete posted event 00006EFC4C2071F0
17812016/11/15 19:11:48 [debug] 30741#30741: *251 http run request: "/appname/socket/?EIO=3&transport=websocket&sid=UJyFqU73bEe42MVkAAAB"
17822016/11/15 19:11:48 [debug] 30741#30741: *251 http upstream process upgraded, fu:1
17832016/11/15 19:11:48 [debug] 30741#30741: *251 event timer: 14, old: 1479237168070, new: 1479237168070
17842016/11/15 19:11:48 [debug] 30741#30741: *251 post event 00006EFC4C508250
17852016/11/15 19:11:48 [debug] 30741#30741: *251 post event 00006EFC4C207250
17862016/11/15 19:11:48 [debug] 30741#30741: *251 delete posted event 00006EFC4C508250
17872016/11/15 19:11:48 [debug] 30741#30741: *251 http upstream request: "/appname/socket/?EIO=3&transport=websocket&sid=UJyFqU73bEe42MVkAAAB"
17882016/11/15 19:11:48 [debug] 30741#30741: *251 http upstream process upgraded, fu:1
17892016/11/15 19:11:48 [debug] 30741#30741: *251 recv: fd:14 3 of 4096
17902016/11/15 19:11:48 [debug] 30741#30741: *251 SSL to write: 3
17912016/11/15 19:11:48 [debug] 30741#30741: *251 SSL_write: 3
17922016/11/15 19:11:48 [debug] 30741#30741: *251 recv: fd:14 -1 of 4096
17932016/11/15 19:11:48 [debug] 30741#30741: *251 recv() not ready (11: Resource temporarily unavailable)
17942016/11/15 19:11:48 [debug] 30741#30741: *251 event timer: 14, old: 1479237168070, new: 1479237168073
17952016/11/15 19:11:48 [debug] 30741#30741: *251 delete posted event 00006EFC4C207250
17962016/11/15 19:11:48 [debug] 30741#30741: *251 http upstream request: "/appname/socket/?EIO=3&transport=websocket&sid=UJyFqU73bEe42MVkAAAB"
17972016/11/15 19:11:48 [debug] 30741#30741: *251 http upstream process upgraded, fu:0
17982016/11/15 19:11:48 [debug] 30741#30741: *251 event timer: 14, old: 1479237168070, new: 1479237168073
17992016/11/15 19:11:48 [debug] 30743#30743: *162 http2 idle handler
18002016/11/15 19:11:48 [debug] 30743#30743: *162 reusable connection: 0
18012016/11/15 19:11:48 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E03BC0:4096 @16
18022016/11/15 19:11:48 [debug] 30743#30743: *162 http2 read handler
18032016/11/15 19:11:48 [debug] 30743#30743: *162 SSL_read: 136
18042016/11/15 19:11:48 [debug] 30743#30743: *162 SSL_read: 2861
18052016/11/15 19:11:48 [debug] 30743#30743: *162 SSL_read: 2861
18062016/11/15 19:11:48 [debug] 30743#30743: *162 SSL_read: 2861
18072016/11/15 19:11:48 [debug] 30743#30743: *162 SSL_read: 2861
18082016/11/15 19:11:48 [debug] 30743#30743: *162 SSL_read: -1
18092016/11/15 19:11:48 [debug] 30743#30743: *162 SSL_get_error: 2
18102016/11/15 19:11:48 [debug] 30743#30743: *162 process http2 frame type:1 f:24 l:127 sid:431
18112016/11/15 19:11:48 [debug] 30743#30743: *162 http2 HEADERS frame sid:431 on 0 excl:0 weight:183
18122016/11/15 19:11:48 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E61530:1024 @16
18132016/11/15 19:11:48 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E120F0:4096 @16
18142016/11/15 19:11:48 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E63480:4096 @16
18152016/11/15 19:11:48 [debug] 30743#30743: *162 http2 get indexed header name: 3
18162016/11/15 19:11:48 [debug] 30743#30743: *162 http2 get indexed header name: 66
18172016/11/15 19:11:48 [debug] 30743#30743: *162 http2 get indexed header name: 7
18182016/11/15 19:11:48 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
18192016/11/15 19:11:48 [debug] 30743#30743: *162 http2 hpack encoded string length: 13
18202016/11/15 19:11:48 [debug] 30743#30743: *162 http uri: "/appname/v2/heartbeat"
18212016/11/15 19:11:48 [debug] 30743#30743: *162 http args: ""
18222016/11/15 19:11:48 [debug] 30743#30743: *162 http exten: ""
18232016/11/15 19:11:48 [debug] 30743#30743: *162 http2 get indexed header: 28
18242016/11/15 19:11:48 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
18252016/11/15 19:11:48 [debug] 30743#30743: *162 http2 add header to hpack table: "content-length: 26568"
18262016/11/15 19:11:48 [debug] 30743#30743: *162 http2 hpack table account: 51 free:30
18272016/11/15 19:11:48 [debug] 30743#30743: *162 http2 http header: "content-length: 26568"
18282016/11/15 19:11:48 [debug] 30743#30743: *162 http2 get indexed header name: 65
18292016/11/15 19:11:48 [debug] 30743#30743: *162 http2 http header: "accept: application/json, text/plain, */*"
18302016/11/15 19:11:48 [debug] 30743#30743: *162 http2 get indexed header name: 77
18312016/11/15 19:11:48 [debug] 30743#30743: *162 http2 http header: "origin: https://app3.mydomain.local"
18322016/11/15 19:11:48 [debug] 30743#30743: *162 http2 get indexed header name: 93
18332016/11/15 19:11:48 [debug] 30743#30743: *162 http2 http header: "appname-csrf-token: YYY
18342016/11/15 19:11:48 [debug] 30743#30743: *162 http2 get indexed header: 58
18352016/11/15 19:11:48 [debug] 30743#30743: *162 http2 hpack encoded string length: 80
18362016/11/15 19:11:48 [debug] 30743#30743: *162 http2 add header to hpack table: "user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36"
18372016/11/15 19:11:48 [debug] 30743#30743: *162 http2 hpack table account: 146 free:125
18382016/11/15 19:11:48 [debug] 30743#30743: *162 http2 http header: "user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36"
18392016/11/15 19:11:48 [debug] 30743#30743: *162 http2 get indexed header name: 77
18402016/11/15 19:11:48 [debug] 30743#30743: *162 http2 http header: "content-type: application/json;charset=UTF-8"
18412016/11/15 19:11:48 [debug] 30743#30743: *162 http2 get indexed header name: 75
18422016/11/15 19:11:48 [debug] 30743#30743: *162 http2 http header: "accept-encoding: gzip, deflate, br"
18432016/11/15 19:11:48 [debug] 30743#30743: *162 http2 get indexed header name: 91
18442016/11/15 19:11:48 [debug] 30743#30743: *162 http2 http header: "accept-language: en-US,en;q=0.8"
18452016/11/15 19:11:48 [debug] 30743#30743: *162 http2 get indexed header name: 90
18462016/11/15 19:11:48 [debug] 30743#30743: *162 http2 get indexed header name: 89
18472016/11/15 19:11:48 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E16F00:1024 @16
18482016/11/15 19:11:48 [debug] 30743#30743: *162 http2 get indexed header name: 88
18492016/11/15 19:11:48 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DC4930:1024 @16
18502016/11/15 19:11:48 [debug] 30743#30743: *162 http2 get indexed header name: 87
18512016/11/15 19:11:48 [debug] 30743#30743: *162 http2 get indexed header name: 93
18522016/11/15 19:11:48 [debug] 30743#30743: *162 http2 http request line: "POST /appname/v2/heartbeat HTTP/2.0"
18532016/11/15 19:11:48 [debug] 30743#30743: *162 generic phase: 0
18542016/11/15 19:11:48 [debug] 30743#30743: *162 rewrite phase: 1
18552016/11/15 19:11:48 [debug] 30743#30743: *162 test location: "^/widgets/$"
18562016/11/15 19:11:48 [debug] 30743#30743: *162 test location: "/"
18572016/11/15 19:11:48 [debug] 30743#30743: *162 test location: "docs/"
18582016/11/15 19:11:48 [debug] 30743#30743: *162 test location: "appname/socket"
18592016/11/15 19:11:48 [debug] 30743#30743: *162 test location: ~ "^/(index.html)?$"
18602016/11/15 19:11:48 [debug] 30743#30743: *162 test location: ~ "^/login/?$"
18612016/11/15 19:11:48 [debug] 30743#30743: *162 test location: ~ "^/(appname)"
18622016/11/15 19:11:48 [debug] 30743#30743: *162 test location: ~ "/components/(.*)"
18632016/11/15 19:11:48 [debug] 30743#30743: *162 test location: ~ "^/(auth|logout|status|widgetTypes|index.appname.html|login.appname.html|favicon.ico|(.*)\/vendor\/flags\/.*\.(?:jpg|png)|(.*)\/assets\/images\/company_logo.png)$"
18642016/11/15 19:11:48 [debug] 30743#30743: *162 using configuration "/"
18652016/11/15 19:11:48 [debug] 30743#30743: *162 http cl:26568 max:1073741824
18662016/11/15 19:11:48 [debug] 30743#30743: *162 rewrite phase: 3
18672016/11/15 19:11:48 [debug] 30743#30743: *162 post rewrite phase: 4
18682016/11/15 19:11:48 [debug] 30743#30743: *162 generic phase: 5
18692016/11/15 19:11:48 [debug] 30743#30743: *162 generic phase: 6
18702016/11/15 19:11:48 [debug] 30743#30743: *162 generic phase: 7
18712016/11/15 19:11:48 [debug] 30743#30743: *162 access phase: 8
18722016/11/15 19:11:48 [debug] 30743#30743: *162 access phase: 9
18732016/11/15 19:11:48 [debug] 30743#30743: *162 access phase: 10
18742016/11/15 19:11:48 [debug] 30743#30743: *162 auth request handler
18752016/11/15 19:11:48 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB6570:4096 @16
18762016/11/15 19:11:48 [debug] 30743#30743: *162 http subrequest "/authorize?"
18772016/11/15 19:11:48 [debug] 30743#30743: *162 http posted request: "/authorize?"
18782016/11/15 19:11:48 [debug] 30743#30743: *162 rewrite phase: 1
18792016/11/15 19:11:48 [debug] 30743#30743: *162 test location: "^/widgets/$"
18802016/11/15 19:11:48 [debug] 30743#30743: *162 test location: "/"
18812016/11/15 19:11:48 [debug] 30743#30743: *162 test location: "docs/"
18822016/11/15 19:11:48 [debug] 30743#30743: *162 test location: "dashboard"
18832016/11/15 19:11:48 [debug] 30743#30743: *162 test location: "authorize"
18842016/11/15 19:11:48 [debug] 30743#30743: *162 using configuration "=/authorize"
18852016/11/15 19:11:48 [debug] 30743#30743: *162 http cl:26568 max:1073741824
18862016/11/15 19:11:48 [debug] 30743#30743: *162 rewrite phase: 3
18872016/11/15 19:11:48 [debug] 30743#30743: *162 post rewrite phase: 4
18882016/11/15 19:11:48 [debug] 30743#30743: *162 generic phase: 5
18892016/11/15 19:11:48 [debug] 30743#30743: *162 generic phase: 6
18902016/11/15 19:11:48 [debug] 30743#30743: *162 generic phase: 7
18912016/11/15 19:11:48 [debug] 30743#30743: *162 http init upstream, client timer: 0
18922016/11/15 19:11:48 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB7580:4096 @16
18932016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
18942016/11/15 19:11:48 [debug] 30743#30743: *162 http script var: "192.168.123.11"
18952016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "
1896"
18972016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
18982016/11/15 19:11:48 [debug] 30743#30743: *162 http script var: "192.168.123.11"
18992016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "
1900"
19012016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "Host: "
19022016/11/15 19:11:48 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
19032016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "
1904"
19052016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
1906"
19072016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
19082016/11/15 19:11:48 [debug] 30743#30743: *162 http script var: "XXX"
19092016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "
1910"
19112016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
19122016/11/15 19:11:48 [debug] 30743#30743: *162 http script var: "XXXX"
19132016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "
1914"
19152016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "x-appname-uri: "
19162016/11/15 19:11:48 [debug] 30743#30743: *162 http script var: "/appname/v2/heartbeat"
19172016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "
1918"
19192016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "x-appname-method: "
19202016/11/15 19:11:48 [debug] 30743#30743: *162 http script var: "POST"
19212016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "
1922"
19232016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "Connection: close
1924"
19252016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: ""
19262016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: ""
19272016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
19282016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
19292016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
19302016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36"
19312016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
19322016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
19332016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
19342016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
19352016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header:
1936"GET /authorize HTTP/1.1
1937X-Real-IP: 192.168.123.11
1938X-Forwarded-For: 192.168.123.11
1939Host: app3.mydomain.local
1940X-NginX-Proxy: true
1941
1942
1943x-appname-uri: /appname/v2/heartbeat
1944x-appname-method: POST
1945Connection: close
1946accept: application/json, text/plain, */*
1947origin: https://app3.mydomain.local
1948appname-csrf-token: YYY
1949user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
1950content-type: application/json;charset=UTF-8
1951accept-encoding: gzip, deflate, br
1952accept-language: en-US,en;q=0.8
1953