Ticket #1128: app-error-log2.txt

File app-error-log2.txt, 192.8 KB (added by kcannon.gaikai.com@…, 10 years ago)
Line 
12016/11/15 19:11:48 [debug] 30743#30743: *162 http cleanup add: 00000F0D34E64460
22016/11/15 19:11:48 [debug] 30743#30743: *162 get rr peer, try: 1
32016/11/15 19:11:48 [debug] 30743#30743: *162 stream socket 7
42016/11/15 19:11:48 [debug] 30743#30743: *162 epoll add connection: fd:7 ev:80002005
52016/11/15 19:11:48 [debug] 30743#30743: *162 connect to 127.0.0.1:8008, fd:7 #573
62016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream connect: -2
72016/11/15 19:11:48 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBBA30:128 @16
82016/11/15 19:11:48 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237168493
92016/11/15 19:11:48 [debug] 30743#30743: *162 http finalize request: -4, "/authorize?" a:1, c:3
102016/11/15 19:11:48 [debug] 30743#30743: *162 http request count:3 blk:0
112016/11/15 19:11:48 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AC098 end:00006EFC4F5AED4C
122016/11/15 19:11:48 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:431
132016/11/15 19:11:48 [debug] 30743#30743: *162 http2 DATA frame
142016/11/15 19:11:48 [debug] 30743#30743: *162 malloc: 00000F0D34D89720:65536
152016/11/15 19:11:48 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5ACBC5 end:00006EFC4F5AED4C
162016/11/15 19:11:48 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:431
172016/11/15 19:11:48 [debug] 30743#30743: *162 http2 DATA frame
182016/11/15 19:11:48 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AD6F2 end:00006EFC4F5AED4C
192016/11/15 19:11:48 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:431
202016/11/15 19:11:48 [debug] 30743#30743: *162 http2 DATA frame
212016/11/15 19:11:48 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AE21F end:00006EFC4F5AED4C
222016/11/15 19:11:48 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:431
232016/11/15 19:11:48 [debug] 30743#30743: *162 http2 DATA frame
242016/11/15 19:11:48 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AED4C end:00006EFC4F5AED4C
252016/11/15 19:11:48 [debug] 30743#30743: *162 event timer del: 3: 1479237278599
262016/11/15 19:11:48 [debug] 30743#30743: *162 http2 write handler
272016/11/15 19:11:48 [debug] 30743#30743: *162 http2 read handler
282016/11/15 19:11:48 [debug] 30743#30743: *162 SSL_read: 2861
292016/11/15 19:11:48 [debug] 30743#30743: *162 SSL_read: 2861
302016/11/15 19:11:48 [debug] 30743#30743: *162 SSL_read: 2861
312016/11/15 19:11:48 [debug] 30743#30743: *162 SSL_read: 2861
322016/11/15 19:11:48 [debug] 30743#30743: *162 SSL_read: -1
332016/11/15 19:11:48 [debug] 30743#30743: *162 SSL_get_error: 2
342016/11/15 19:11:48 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:431
352016/11/15 19:11:48 [debug] 30743#30743: *162 http2 DATA frame
362016/11/15 19:11:48 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5ACB3D end:00006EFC4F5AECC4
372016/11/15 19:11:48 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:431
382016/11/15 19:11:48 [debug] 30743#30743: *162 http2 DATA frame
392016/11/15 19:11:48 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AD66A end:00006EFC4F5AECC4
402016/11/15 19:11:48 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:431
412016/11/15 19:11:48 [debug] 30743#30743: *162 http2 DATA frame
422016/11/15 19:11:48 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AE197 end:00006EFC4F5AECC4
432016/11/15 19:11:48 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:431
442016/11/15 19:11:48 [debug] 30743#30743: *162 http2 DATA frame
452016/11/15 19:11:48 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AECC4 end:00006EFC4F5AECC4
462016/11/15 19:11:48 [debug] 30743#30743: *162 http2 write handler
472016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream request: "/authorize?"
482016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream send request handler
492016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream send request
502016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream send request body
512016/11/15 19:11:48 [debug] 30743#30743: *162 chain writer buf fl:1 s:2711
522016/11/15 19:11:48 [debug] 30743#30743: *162 chain writer in: 00000F0D34EB7550
532016/11/15 19:11:48 [debug] 30743#30743: *162 writev: 2711 of 2711
542016/11/15 19:11:48 [debug] 30743#30743: *162 chain writer out: 0000000000000000
552016/11/15 19:11:48 [debug] 30743#30743: *162 event timer del: 7: 1479237168493
562016/11/15 19:11:48 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237168495
572016/11/15 19:11:48 [debug] 30743#30743: *162 http2 read handler
582016/11/15 19:11:48 [debug] 30743#30743: *162 SSL_read: 2861
592016/11/15 19:11:48 [debug] 30743#30743: *162 SSL_read: 909
602016/11/15 19:11:48 [debug] 30743#30743: *162 SSL_read: -1
612016/11/15 19:11:48 [debug] 30743#30743: *162 SSL_get_error: 2
622016/11/15 19:11:48 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:431
632016/11/15 19:11:48 [debug] 30743#30743: *162 http2 DATA frame
642016/11/15 19:11:48 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5ACB3D end:00006EFC4F5ACECA
652016/11/15 19:11:48 [debug] 30743#30743: *162 process http2 frame type:0 f:1 l:900 sid:431
662016/11/15 19:11:48 [debug] 30743#30743: *162 http2 DATA frame
672016/11/15 19:11:48 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5ACECA end:00006EFC4F5ACECA
682016/11/15 19:11:48 [debug] 30743#30743: *162 http2 write handler
692016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream request: "/authorize?"
702016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream process header
712016/11/15 19:11:48 [debug] 30743#30743: *162 malloc: 00000F0D34DBC950:4096
722016/11/15 19:11:48 [debug] 30743#30743: *162 recv: fd:7 172 of 4096
732016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy status 200 "200 OK"
742016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "X-Powered-By: Express"
752016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "x-appname-origin: appname3"
762016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "content-type: application/json"
772016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "content-length: 0"
782016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "Date: Tue, 15 Nov 2016 19:11:48 GMT"
792016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "Connection: close"
802016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header done
812016/11/15 19:11:48 [debug] 30743#30743: *162 lua capture header filter, uri "/authorize"
822016/11/15 19:11:48 [debug] 30743#30743: *162 http2 header filter
832016/11/15 19:11:48 [debug] 30743#30743: *162 finalize http upstream request: 0
842016/11/15 19:11:48 [debug] 30743#30743: *162 finalize http proxy request
852016/11/15 19:11:48 [debug] 30743#30743: *162 free rr peer 1 0
862016/11/15 19:11:48 [debug] 30743#30743: *162 close http upstream connection: 7
872016/11/15 19:11:48 [debug] 30743#30743: *162 free: 00000F0D34DBBA30, unused: 48
882016/11/15 19:11:48 [debug] 30743#30743: *162 event timer del: 7: 1479237168495
892016/11/15 19:11:48 [debug] 30743#30743: *162 reusable connection: 0
902016/11/15 19:11:48 [debug] 30743#30743: *162 http finalize request: 0, "/authorize?" a:1, c:2
912016/11/15 19:11:48 [debug] 30743#30743: *162 auth request done s:200
922016/11/15 19:11:48 [debug] 30743#30743: *162 http wake parent request: "/appname/v2/heartbeat?"
932016/11/15 19:11:48 [debug] 30743#30743: *162 http posted request: "/appname/v2/heartbeat?"
942016/11/15 19:11:48 [debug] 30743#30743: *162 access phase: 10
952016/11/15 19:11:48 [debug] 30743#30743: *162 auth request handler
962016/11/15 19:11:48 [debug] 30743#30743: *162 auth request set variables
972016/11/15 19:11:48 [debug] 30743#30743: *162 access phase: 11
982016/11/15 19:11:48 [debug] 30743#30743: *162 lua access handler, uri:"/appname/v2/heartbeat" c:1
992016/11/15 19:11:48 [debug] 30743#30743: *162 post access phase: 12
1002016/11/15 19:11:48 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBD960:4096 @16
1012016/11/15 19:11:48 [debug] 30743#30743: *162 http body new buf t:1 f:0 00000F0D34D89720, pos 00000F0D34D89720, size: 26568 file: 0, size: 0
1022016/11/15 19:11:48 [debug] 30743#30743: *162 http init upstream, client timer: 0
1032016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
1042016/11/15 19:11:48 [debug] 30743#30743: *162 http script var: "192.168.123.11"
1052016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "
106"
1072016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
1082016/11/15 19:11:48 [debug] 30743#30743: *162 http script var: "192.168.123.11"
1092016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "
110"
1112016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "Host: "
1122016/11/15 19:11:48 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
1132016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "
114"
1152016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
116"
1172016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
1182016/11/15 19:11:48 [debug] 30743#30743: *162 http script var: "XXX"
1192016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "
120"
1212016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
1222016/11/15 19:11:48 [debug] 30743#30743: *162 http script var: "XXXX"
1232016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "
124"
1252016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "Connection: close
126"
1272016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "Content-Length: "
1282016/11/15 19:11:48 [debug] 30743#30743: *162 http script var: "26568"
1292016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: "
130"
1312016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: ""
1322016/11/15 19:11:48 [debug] 30743#30743: *162 http script copy: ""
1332016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
1342016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
1352016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
1362016/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"
1372016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
1382016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
1392016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
1402016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
1412016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header:
142"POST /appname/v2/heartbeat HTTP/1.1
143X-Real-IP: 192.168.123.11
144X-Forwarded-For: 192.168.123.11
145Host: app3.mydomain.local
146X-NginX-Proxy: true
147
148
149Connection: close
150Content-Length: 26568
151accept: application/json, text/plain, */*
152origin: https://app3.mydomain.local
153appname-csrf-token: YYY
154user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
155content-type: application/json;charset=UTF-8
156accept-encoding: gzip, deflate, br
157accept-language: en-US,en;q=0.8
1582016/11/15 19:11:48 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBE970:4096 @16
1592016/11/15 19:11:48 [debug] 30743#30743: *162 http cleanup add: 00000F0D34DBE938
1602016/11/15 19:11:48 [debug] 30743#30743: *162 get rr peer, try: 1
1612016/11/15 19:11:48 [debug] 30743#30743: *162 stream socket 7
1622016/11/15 19:11:48 [debug] 30743#30743: *162 epoll add connection: fd:7 ev:80002005
1632016/11/15 19:11:48 [debug] 30743#30743: *162 connect to 182.168.128.69:8080, fd:7 #574
1642016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream connect: -2
1652016/11/15 19:11:48 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBBA30:128 @16
1662016/11/15 19:11:48 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237168525
1672016/11/15 19:11:48 [debug] 30743#30743: *162 free: 00000F0D34D89720
1682016/11/15 19:11:48 [debug] 30743#30743: *162 http finalize request: -4, "/appname/v2/heartbeat?" a:1, c:2
1692016/11/15 19:11:48 [debug] 30743#30743: *162 http request count:2 blk:0
1702016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
1712016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream send request handler
1722016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream send request
1732016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream send request body
1742016/11/15 19:11:48 [debug] 30743#30743: *162 chain writer buf fl:0 s:2690
1752016/11/15 19:11:48 [debug] 30743#30743: *162 chain writer buf fl:1 s:26568
1762016/11/15 19:11:48 [debug] 30743#30743: *162 chain writer in: 00000F0D34DBEA00
1772016/11/15 19:11:48 [debug] 30743#30743: *162 writev: 28960 of 29258
1782016/11/15 19:11:48 [debug] 30743#30743: *162 chain writer out: 00000F0D34DBEA10
1792016/11/15 19:11:48 [debug] 30743#30743: *162 event timer: 7, old: 1479237168525, new: 1479237168526
1802016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
1812016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream send request handler
1822016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream send request
1832016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream send request body
1842016/11/15 19:11:48 [debug] 30743#30743: *162 chain writer in: 00000F0D34DBEA10
1852016/11/15 19:11:48 [debug] 30743#30743: *162 writev: 298 of 298
1862016/11/15 19:11:48 [debug] 30743#30743: *162 chain writer out: 0000000000000000
1872016/11/15 19:11:48 [debug] 30743#30743: *162 event timer del: 7: 1479237168525
1882016/11/15 19:11:48 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237168527
1892016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
1902016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream process header
1912016/11/15 19:11:48 [debug] 30743#30743: *162 malloc: 00000F0D34D69D00:4096
1922016/11/15 19:11:48 [debug] 30743#30743: *162 recv: fd:7 1056 of 4096
1932016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy status 400 "400 Bad Request"
1942016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "Server: nginx"
1952016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "Date: Tue, 15 Nov 2016 19:11:48 GMT"
1962016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "Content-Type: text/html; charset=utf-8"
1972016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "Content-Length: 840"
1982016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "Connection: close"
1992016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "X-Powered-By: Express"
2002016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header: "X-Content-Type-Options: nosniff"
2012016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy header done
2022016/11/15 19:11:48 [debug] 30743#30743: *162 lua capture header filter, uri "/appname/v2/heartbeat"
2032016/11/15 19:11:48 [debug] 30743#30743: *162 http2 header filter
2042016/11/15 19:11:48 [debug] 30743#30743: *162 http2 output header: ":status: 400"
2052016/11/15 19:11:48 [debug] 30743#30743: *162 http2 output header: "server: nginx"
2062016/11/15 19:11:48 [debug] 30743#30743: *162 http2 output header: "date: Tue, 15 Nov 2016 19:11:48 GMT"
2072016/11/15 19:11:48 [debug] 30743#30743: *162 http2 output header: "content-type: text/html; charset=utf-8"
2082016/11/15 19:11:48 [debug] 30743#30743: *162 http2 output header: "content-length: 840"
2092016/11/15 19:11:48 [debug] 30743#30743: *162 http2 output header: "x-powered-by: Express"
2102016/11/15 19:11:48 [debug] 30743#30743: *162 http2 output header: "x-content-type-options: nosniff"
2112016/11/15 19:11:48 [debug] 30743#30743: *162 http2:431 create HEADERS frame 00000F0D34DBEDA8: len:98
2122016/11/15 19:11:48 [debug] 30743#30743: *162 http cleanup add: 00000F0D34DBEE90
2132016/11/15 19:11:48 [debug] 30743#30743: *162 http2 frame out: 00000F0D34DBEDA8 sid:431 bl:1 len:98
2142016/11/15 19:11:48 [debug] 30743#30743: *162 malloc: 00000F0D34D83A10:16384
2152016/11/15 19:11:48 [debug] 30743#30743: *162 SSL buf copy: 9
2162016/11/15 19:11:48 [debug] 30743#30743: *162 SSL buf copy: 98
2172016/11/15 19:11:48 [debug] 30743#30743: *162 http2:431 HEADERS frame 00000F0D34DBEDA8 was sent
2182016/11/15 19:11:48 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34DBEDA8 sid:431 bl:1 len:98
2192016/11/15 19:11:48 [debug] 30743#30743: *162 http cacheable: 0
2202016/11/15 19:11:48 [debug] 30743#30743: *162 http proxy filter init s:400 h:0 c:0 l:840
2212016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream process upstream
2222016/11/15 19:11:48 [debug] 30743#30743: *162 pipe read upstream: 1
2232016/11/15 19:11:48 [debug] 30743#30743: *162 pipe preread: 840
2242016/11/15 19:11:48 [debug] 30743#30743: *162 readv: 1, last:3040
2252016/11/15 19:11:48 [debug] 30743#30743: *162 pipe recv chain: 0
2262016/11/15 19:11:48 [debug] 30743#30743: *162 pipe buf free s:0 t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
2272016/11/15 19:11:48 [debug] 30743#30743: *162 pipe length: 840
2282016/11/15 19:11:48 [debug] 30743#30743: *162 input buf #0
2292016/11/15 19:11:48 [debug] 30743#30743: *162 pipe write downstream: 1
2302016/11/15 19:11:48 [debug] 30743#30743: *162 pipe write downstream flush in
2312016/11/15 19:11:48 [debug] 30743#30743: *162 http output filter "/appname/v2/heartbeat?"
2322016/11/15 19:11:48 [debug] 30743#30743: *162 http copy filter: "/appname/v2/heartbeat?"
2332016/11/15 19:11:48 [debug] 30743#30743: *162 lua capture body filter, uri "/appname/v2/heartbeat"
2342016/11/15 19:11:48 [debug] 30743#30743: *162 http postpone filter "/appname/v2/heartbeat?" 00000F0D34DBEA00
2352016/11/15 19:11:48 [debug] 30743#30743: *162 write new buf t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
2362016/11/15 19:11:48 [debug] 30743#30743: *162 http write filter: l:0 f:0 s:840
2372016/11/15 19:11:48 [debug] 30743#30743: *162 http copy filter: 0 "/appname/v2/heartbeat?"
2382016/11/15 19:11:48 [debug] 30743#30743: *162 pipe write downstream done
2392016/11/15 19:11:48 [debug] 30743#30743: *162 event timer: 7, old: 1479237168527, new: 1479237168546
2402016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream exit: 0000000000000000
2412016/11/15 19:11:48 [debug] 30743#30743: *162 finalize http upstream request: 0
2422016/11/15 19:11:48 [debug] 30743#30743: *162 finalize http proxy request
2432016/11/15 19:11:48 [debug] 30743#30743: *162 free rr peer 1 0
2442016/11/15 19:11:48 [debug] 30743#30743: *162 close http upstream connection: 7
2452016/11/15 19:11:48 [debug] 30743#30743: *162 free: 00000F0D34DBBA30, unused: 48
2462016/11/15 19:11:48 [debug] 30743#30743: *162 event timer del: 7: 1479237168527
2472016/11/15 19:11:48 [debug] 30743#30743: *162 reusable connection: 0
2482016/11/15 19:11:48 [debug] 30743#30743: *162 http upstream temp fd: -1
2492016/11/15 19:11:48 [debug] 30743#30743: *162 http output filter "/appname/v2/heartbeat?"
2502016/11/15 19:11:48 [debug] 30743#30743: *162 http copy filter: "/appname/v2/heartbeat?"
2512016/11/15 19:11:48 [debug] 30743#30743: *162 lua capture body filter, uri "/appname/v2/heartbeat"
2522016/11/15 19:11:48 [debug] 30743#30743: *162 http postpone filter "/appname/v2/heartbeat?" 00007A9E70E05A50
2532016/11/15 19:11:48 [debug] 30743#30743: *162 write old buf t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
2542016/11/15 19:11:48 [debug] 30743#30743: *162 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
2552016/11/15 19:11:48 [debug] 30743#30743: *162 http write filter: l:1 f:0 s:840
2562016/11/15 19:11:48 [debug] 30743#30743: *162 http write filter limit 0
2572016/11/15 19:11:48 [debug] 30743#30743: *162 http2:431 create DATA frame 00000F0D34DBEDA8: len:840 flags:1
2582016/11/15 19:11:48 [debug] 30743#30743: *162 http2 frame out: 00000F0D34DBEDA8 sid:431 bl:0 len:840
2592016/11/15 19:11:48 [debug] 30743#30743: *162 SSL buf copy: 9
2602016/11/15 19:11:48 [debug] 30743#30743: *162 SSL buf copy: 840
2612016/11/15 19:11:48 [debug] 30743#30743: *162 SSL to write: 956
2622016/11/15 19:11:48 [debug] 30743#30743: *162 SSL_write: 956
2632016/11/15 19:11:48 [debug] 30743#30743: *162 http2:431 DATA frame 00000F0D34DBEDA8 was sent
2642016/11/15 19:11:48 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34DBEDA8 sid:431 bl:0 len:840
2652016/11/15 19:11:48 [debug] 30743#30743: *162 http write filter 0000000000000000
2662016/11/15 19:11:48 [debug] 30743#30743: *162 http copy filter: 0 "/appname/v2/heartbeat?"
2672016/11/15 19:11:48 [debug] 30743#30743: *162 http finalize request: 0, "/appname/v2/heartbeat?" a:1, c:1
2682016/11/15 19:11:48 [debug] 30743#30743: *162 http request count:1 blk:0
2692016/11/15 19:11:48 [debug] 30743#30743: *162 http2 close stream 431, queued 0, processing 1
2702016/11/15 19:11:48 [debug] 30743#30743: *162 http close request
2712016/11/15 19:11:48 [debug] 30743#30743: *162 http log handler
2722016/11/15 19:11:48 [debug] 30743#30743: *162 free: 00000F0D34DBC950
2732016/11/15 19:11:48 [debug] 30743#30743: *162 free: 00000F0D34D69D00
2742016/11/15 19:11:48 [debug] 30743#30743: *162 free: 00000F0D34E120F0, unused: 0
2752016/11/15 19:11:48 [debug] 30743#30743: *162 free: 00000F0D34E63480, unused: 2
2762016/11/15 19:11:48 [debug] 30743#30743: *162 free: 00000F0D34EB6570, unused: 0
2772016/11/15 19:11:48 [debug] 30743#30743: *162 free: 00000F0D34EB7580, unused: 8
2782016/11/15 19:11:48 [debug] 30743#30743: *162 free: 00000F0D34DBD960, unused: 0
2792016/11/15 19:11:48 [debug] 30743#30743: *162 free: 00000F0D34DBE970, unused: 1926
2802016/11/15 19:11:48 [debug] 30743#30743: *162 free: 00000F0D34E61530, unused: 149
2812016/11/15 19:11:48 [debug] 30743#30743: *162 free: 00000F0D34E16F00, unused: 156
2822016/11/15 19:11:48 [debug] 30743#30743: *162 free: 00000F0D34DC4930, unused: 111
2832016/11/15 19:11:48 [debug] 30743#30743: *162 post event 00006EFC4C5081F0
2842016/11/15 19:11:48 [debug] 30743#30743: *162 delete posted event 00006EFC4C5081F0
2852016/11/15 19:11:48 [debug] 30743#30743: *162 http2 handle connection handler
2862016/11/15 19:11:48 [debug] 30743#30743: *162 free: 00000F0D34E03BC0, unused: 3488
2872016/11/15 19:11:48 [debug] 30743#30743: *162 free: 00000F0D34D83A10
2882016/11/15 19:11:48 [debug] 30743#30743: *162 reusable connection: 1
2892016/11/15 19:11:48 [debug] 30743#30743: *162 event timer add: 3: 180000:1479237288546
2902016/11/15 19:11:50 [debug] 30743#30743: *162 http2 idle handler
2912016/11/15 19:11:50 [debug] 30743#30743: *162 reusable connection: 0
2922016/11/15 19:11:50 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E120F0:4096 @16
2932016/11/15 19:11:50 [debug] 30743#30743: *162 http2 read handler
2942016/11/15 19:11:50 [debug] 30743#30743: *162 SSL_read: 55
2952016/11/15 19:11:50 [debug] 30743#30743: *162 SSL_read: 2861
2962016/11/15 19:11:50 [debug] 30743#30743: *162 SSL_read: 2861
2972016/11/15 19:11:50 [debug] 30743#30743: *162 SSL_read: 2861
2982016/11/15 19:11:50 [debug] 30743#30743: *162 SSL_read: 2861
2992016/11/15 19:11:50 [debug] 30743#30743: *162 SSL_read: -1
3002016/11/15 19:11:50 [debug] 30743#30743: *162 SSL_get_error: 2
3012016/11/15 19:11:50 [debug] 30743#30743: *162 process http2 frame type:1 f:24 l:46 sid:433
3022016/11/15 19:11:50 [debug] 30743#30743: *162 http2 HEADERS frame sid:433 on 0 excl:0 weight:183
3032016/11/15 19:11:50 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DC4930:1024 @16
3042016/11/15 19:11:50 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E03BC0:4096 @16
3052016/11/15 19:11:50 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E63480:4096 @16
3062016/11/15 19:11:50 [debug] 30743#30743: *162 http2 get indexed header name: 3
3072016/11/15 19:11:50 [debug] 30743#30743: *162 http2 get indexed header name: 68
3082016/11/15 19:11:50 [debug] 30743#30743: *162 http2 get indexed header name: 7
3092016/11/15 19:11:50 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
3102016/11/15 19:11:50 [debug] 30743#30743: *162 http2 hpack encoded string length: 13
3112016/11/15 19:11:50 [debug] 30743#30743: *162 http uri: "/appname/v2/heartbeat"
3122016/11/15 19:11:50 [debug] 30743#30743: *162 http args: ""
3132016/11/15 19:11:50 [debug] 30743#30743: *162 http exten: ""
3142016/11/15 19:11:50 [debug] 30743#30743: *162 http2 get indexed header: 28
3152016/11/15 19:11:50 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
3162016/11/15 19:11:50 [debug] 30743#30743: *162 http2 add header to hpack table: "content-length: 16595"
3172016/11/15 19:11:50 [debug] 30743#30743: *162 http2 hpack table account: 51 free:177
3182016/11/15 19:11:50 [debug] 30743#30743: *162 http2 http header: "content-length: 16595"
3192016/11/15 19:11:50 [debug] 30743#30743: *162 http2 get indexed header name: 67
3202016/11/15 19:11:50 [debug] 30743#30743: *162 http2 http header: "accept: application/json, text/plain, */*"
3212016/11/15 19:11:50 [debug] 30743#30743: *162 http2 get indexed header name: 79
3222016/11/15 19:11:50 [debug] 30743#30743: *162 http2 http header: "origin: https://app3.mydomain.local"
3232016/11/15 19:11:50 [debug] 30743#30743: *162 http2 get indexed header name: 95
3242016/11/15 19:11:50 [debug] 30743#30743: *162 http2 http header: "appname-csrf-token: YYY
3252016/11/15 19:11:50 [debug] 30743#30743: *162 http2 get indexed header name: 63
3262016/11/15 19:11:50 [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"
3272016/11/15 19:11:50 [debug] 30743#30743: *162 http2 get indexed header name: 78
3282016/11/15 19:11:50 [debug] 30743#30743: *162 http2 http header: "content-type: application/json;charset=UTF-8"
3292016/11/15 19:11:50 [debug] 30743#30743: *162 http2 get indexed header name: 76
3302016/11/15 19:11:50 [debug] 30743#30743: *162 http2 http header: "accept-encoding: gzip, deflate, br"
3312016/11/15 19:11:50 [debug] 30743#30743: *162 http2 get indexed header name: 92
3322016/11/15 19:11:50 [debug] 30743#30743: *162 http2 http header: "accept-language: en-US,en;q=0.8"
3332016/11/15 19:11:50 [debug] 30743#30743: *162 http2 get indexed header name: 91
3342016/11/15 19:11:50 [debug] 30743#30743: *162 http2 get indexed header name: 90
3352016/11/15 19:11:50 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E16F00:1024 @16
3362016/11/15 19:11:50 [debug] 30743#30743: *162 http2 get indexed header name: 89
3372016/11/15 19:11:50 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E61530:1024 @16
3382016/11/15 19:11:50 [debug] 30743#30743: *162 http2 get indexed header name: 88
3392016/11/15 19:11:50 [debug] 30743#30743: *162 http2 get indexed header name: 94
3402016/11/15 19:11:50 [debug] 30743#30743: *162 http2 http request line: "POST /appname/v2/heartbeat HTTP/2.0"
3412016/11/15 19:11:50 [debug] 30743#30743: *162 generic phase: 0
3422016/11/15 19:11:50 [debug] 30743#30743: *162 rewrite phase: 1
3432016/11/15 19:11:50 [debug] 30743#30743: *162 test location: "^/widgets/$"
3442016/11/15 19:11:50 [debug] 30743#30743: *162 test location: "/"
3452016/11/15 19:11:50 [debug] 30743#30743: *162 test location: "docs/"
3462016/11/15 19:11:50 [debug] 30743#30743: *162 test location: "appname/socket"
3472016/11/15 19:11:50 [debug] 30743#30743: *162 test location: ~ "^/(index.html)?$"
3482016/11/15 19:11:50 [debug] 30743#30743: *162 test location: ~ "^/login/?$"
3492016/11/15 19:11:50 [debug] 30743#30743: *162 test location: ~ "^/(appname)"
3502016/11/15 19:11:50 [debug] 30743#30743: *162 test location: ~ "/components/(.*)"
3512016/11/15 19:11:50 [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)$"
3522016/11/15 19:11:50 [debug] 30743#30743: *162 using configuration "/"
3532016/11/15 19:11:50 [debug] 30743#30743: *162 http cl:16595 max:1073741824
3542016/11/15 19:11:50 [debug] 30743#30743: *162 rewrite phase: 3
3552016/11/15 19:11:50 [debug] 30743#30743: *162 post rewrite phase: 4
3562016/11/15 19:11:50 [debug] 30743#30743: *162 generic phase: 5
3572016/11/15 19:11:50 [debug] 30743#30743: *162 generic phase: 6
3582016/11/15 19:11:50 [debug] 30743#30743: *162 generic phase: 7
3592016/11/15 19:11:50 [debug] 30743#30743: *162 access phase: 8
3602016/11/15 19:11:50 [debug] 30743#30743: *162 access phase: 9
3612016/11/15 19:11:50 [debug] 30743#30743: *162 access phase: 10
3622016/11/15 19:11:50 [debug] 30743#30743: *162 auth request handler
3632016/11/15 19:11:50 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB6570:4096 @16
3642016/11/15 19:11:50 [debug] 30743#30743: *162 http subrequest "/authorize?"
3652016/11/15 19:11:50 [debug] 30743#30743: *162 http posted request: "/authorize?"
3662016/11/15 19:11:50 [debug] 30743#30743: *162 rewrite phase: 1
3672016/11/15 19:11:50 [debug] 30743#30743: *162 test location: "^/widgets/$"
3682016/11/15 19:11:50 [debug] 30743#30743: *162 test location: "/"
3692016/11/15 19:11:50 [debug] 30743#30743: *162 test location: "docs/"
3702016/11/15 19:11:50 [debug] 30743#30743: *162 test location: "dashboard"
3712016/11/15 19:11:50 [debug] 30743#30743: *162 test location: "authorize"
3722016/11/15 19:11:50 [debug] 30743#30743: *162 using configuration "=/authorize"
3732016/11/15 19:11:50 [debug] 30743#30743: *162 http cl:16595 max:1073741824
3742016/11/15 19:11:50 [debug] 30743#30743: *162 rewrite phase: 3
3752016/11/15 19:11:50 [debug] 30743#30743: *162 post rewrite phase: 4
3762016/11/15 19:11:50 [debug] 30743#30743: *162 generic phase: 5
3772016/11/15 19:11:50 [debug] 30743#30743: *162 generic phase: 6
3782016/11/15 19:11:50 [debug] 30743#30743: *162 generic phase: 7
3792016/11/15 19:11:50 [debug] 30743#30743: *162 http init upstream, client timer: 0
3802016/11/15 19:11:50 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB7580:4096 @16
3812016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
3822016/11/15 19:11:50 [debug] 30743#30743: *162 http script var: "192.168.123.11"
3832016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "
384"
3852016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
3862016/11/15 19:11:50 [debug] 30743#30743: *162 http script var: "192.168.123.11"
3872016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "
388"
3892016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "Host: "
3902016/11/15 19:11:50 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
3912016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "
392"
3932016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
394"
3952016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
3962016/11/15 19:11:50 [debug] 30743#30743: *162 http script var: "XXX"
3972016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "
398"
3992016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
4002016/11/15 19:11:50 [debug] 30743#30743: *162 http script var: "XXXX"
4012016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "
402"
4032016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "x-appname-uri: "
4042016/11/15 19:11:50 [debug] 30743#30743: *162 http script var: "/appname/v2/heartbeat"
4052016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "
406"
4072016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "x-appname-method: "
4082016/11/15 19:11:50 [debug] 30743#30743: *162 http script var: "POST"
4092016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "
410"
4112016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "Connection: close
412"
4132016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: ""
4142016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: ""
4152016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
4162016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
4172016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
4182016/11/15 19:11:50 [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"
4192016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
4202016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
4212016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
4222016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
4232016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header:
424"GET /authorize HTTP/1.1
425X-Real-IP: 192.168.123.11
426X-Forwarded-For: 192.168.123.11
427Host: app3.mydomain.local
428X-NginX-Proxy: true
429
430
431x-appname-uri: /appname/v2/heartbeat
432x-appname-method: POST
433Connection: close
434accept: application/json, text/plain, */*
435origin: https://app3.mydomain.local
436appname-csrf-token: YYY
437user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
438content-type: application/json;charset=UTF-8
439accept-encoding: gzip, deflate, br
440accept-language: en-US,en;q=0.8
4412016/11/15 19:11:50 [debug] 30743#30743: *162 http cleanup add: 00000F0D34E64460
4422016/11/15 19:11:50 [debug] 30743#30743: *162 get rr peer, try: 1
4432016/11/15 19:11:50 [debug] 30743#30743: *162 stream socket 7
4442016/11/15 19:11:50 [debug] 30743#30743: *162 epoll add connection: fd:7 ev:80002005
4452016/11/15 19:11:50 [debug] 30743#30743: *162 connect to 127.0.0.1:8008, fd:7 #575
4462016/11/15 19:11:50 [debug] 30743#30743: *162 http upstream connect: -2
4472016/11/15 19:11:50 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBBA30:128 @16
4482016/11/15 19:11:50 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237170563
4492016/11/15 19:11:50 [debug] 30743#30743: *162 http finalize request: -4, "/authorize?" a:1, c:3
4502016/11/15 19:11:50 [debug] 30743#30743: *162 http request count:3 blk:0
4512016/11/15 19:11:50 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AC047 end:00006EFC4F5AECFB
4522016/11/15 19:11:50 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:433
4532016/11/15 19:11:50 [debug] 30743#30743: *162 http2 DATA frame
4542016/11/15 19:11:50 [debug] 30743#30743: *162 malloc: 00000F0D34D89720:65536
4552016/11/15 19:11:50 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5ACB74 end:00006EFC4F5AECFB
4562016/11/15 19:11:50 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:433
4572016/11/15 19:11:50 [debug] 30743#30743: *162 http2 DATA frame
4582016/11/15 19:11:50 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AD6A1 end:00006EFC4F5AECFB
4592016/11/15 19:11:50 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:433
4602016/11/15 19:11:50 [debug] 30743#30743: *162 http2 DATA frame
4612016/11/15 19:11:50 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AE1CE end:00006EFC4F5AECFB
4622016/11/15 19:11:50 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:433
4632016/11/15 19:11:50 [debug] 30743#30743: *162 http2 DATA frame
4642016/11/15 19:11:50 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AECFB end:00006EFC4F5AECFB
4652016/11/15 19:11:50 [debug] 30743#30743: *162 event timer del: 3: 1479237288546
4662016/11/15 19:11:50 [debug] 30743#30743: *162 http2 write handler
4672016/11/15 19:11:50 [debug] 30743#30743: *162 http2 read handler
4682016/11/15 19:11:50 [debug] 30743#30743: *162 SSL_read: 2861
4692016/11/15 19:11:50 [debug] 30743#30743: *162 SSL_read: 2344
4702016/11/15 19:11:50 [debug] 30743#30743: *162 SSL_read: -1
4712016/11/15 19:11:50 [debug] 30743#30743: *162 SSL_get_error: 2
4722016/11/15 19:11:50 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:433
4732016/11/15 19:11:50 [debug] 30743#30743: *162 http2 DATA frame
4742016/11/15 19:11:50 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5ACB3D end:00006EFC4F5AD465
4752016/11/15 19:11:50 [debug] 30743#30743: *162 process http2 frame type:0 f:1 l:2335 sid:433
4762016/11/15 19:11:50 [debug] 30743#30743: *162 http2 DATA frame
4772016/11/15 19:11:50 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AD465 end:00006EFC4F5AD465
4782016/11/15 19:11:50 [debug] 30743#30743: *162 http2 write handler
4792016/11/15 19:11:50 [debug] 30743#30743: *162 http upstream request: "/authorize?"
4802016/11/15 19:11:50 [debug] 30743#30743: *162 http upstream send request handler
4812016/11/15 19:11:50 [debug] 30743#30743: *162 http upstream send request
4822016/11/15 19:11:50 [debug] 30743#30743: *162 http upstream send request body
4832016/11/15 19:11:50 [debug] 30743#30743: *162 chain writer buf fl:1 s:2711
4842016/11/15 19:11:50 [debug] 30743#30743: *162 chain writer in: 00000F0D34EB7550
4852016/11/15 19:11:50 [debug] 30743#30743: *162 writev: 2711 of 2711
4862016/11/15 19:11:50 [debug] 30743#30743: *162 chain writer out: 0000000000000000
4872016/11/15 19:11:50 [debug] 30743#30743: *162 event timer del: 7: 1479237170563
4882016/11/15 19:11:50 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237170564
4892016/11/15 19:11:50 [debug] 30743#30743: *162 http upstream request: "/authorize?"
4902016/11/15 19:11:50 [debug] 30743#30743: *162 http upstream process header
4912016/11/15 19:11:50 [debug] 30743#30743: *162 malloc: 00000F0D34DBC950:4096
4922016/11/15 19:11:50 [debug] 30743#30743: *162 recv: fd:7 172 of 4096
4932016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy status 200 "200 OK"
4942016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "X-Powered-By: Express"
4952016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "x-appname-origin: appname3"
4962016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "content-type: application/json"
4972016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "content-length: 0"
4982016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "Date: Tue, 15 Nov 2016 19:11:50 GMT"
4992016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "Connection: close"
5002016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header done
5012016/11/15 19:11:50 [debug] 30743#30743: *162 lua capture header filter, uri "/authorize"
5022016/11/15 19:11:50 [debug] 30743#30743: *162 http2 header filter
5032016/11/15 19:11:50 [debug] 30743#30743: *162 finalize http upstream request: 0
5042016/11/15 19:11:50 [debug] 30743#30743: *162 finalize http proxy request
5052016/11/15 19:11:50 [debug] 30743#30743: *162 free rr peer 1 0
5062016/11/15 19:11:50 [debug] 30743#30743: *162 close http upstream connection: 7
5072016/11/15 19:11:50 [debug] 30743#30743: *162 free: 00000F0D34DBBA30, unused: 48
5082016/11/15 19:11:50 [debug] 30743#30743: *162 event timer del: 7: 1479237170564
5092016/11/15 19:11:50 [debug] 30743#30743: *162 reusable connection: 0
5102016/11/15 19:11:50 [debug] 30743#30743: *162 http finalize request: 0, "/authorize?" a:1, c:2
5112016/11/15 19:11:50 [debug] 30743#30743: *162 auth request done s:200
5122016/11/15 19:11:50 [debug] 30743#30743: *162 http wake parent request: "/appname/v2/heartbeat?"
5132016/11/15 19:11:50 [debug] 30743#30743: *162 http posted request: "/appname/v2/heartbeat?"
5142016/11/15 19:11:50 [debug] 30743#30743: *162 access phase: 10
5152016/11/15 19:11:50 [debug] 30743#30743: *162 auth request handler
5162016/11/15 19:11:50 [debug] 30743#30743: *162 auth request set variables
5172016/11/15 19:11:50 [debug] 30743#30743: *162 access phase: 11
5182016/11/15 19:11:50 [debug] 30743#30743: *162 lua access handler, uri:"/appname/v2/heartbeat" c:1
5192016/11/15 19:11:50 [debug] 30743#30743: *162 post access phase: 12
5202016/11/15 19:11:50 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBD960:4096 @16
5212016/11/15 19:11:50 [debug] 30743#30743: *162 http body new buf t:1 f:0 00000F0D34D89720, pos 00000F0D34D89720, size: 16595 file: 0, size: 0
5222016/11/15 19:11:50 [debug] 30743#30743: *162 http init upstream, client timer: 0
5232016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
5242016/11/15 19:11:50 [debug] 30743#30743: *162 http script var: "192.168.123.11"
5252016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "
526"
5272016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
5282016/11/15 19:11:50 [debug] 30743#30743: *162 http script var: "192.168.123.11"
5292016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "
530"
5312016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "Host: "
5322016/11/15 19:11:50 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
5332016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "
534"
5352016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
536"
5372016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
5382016/11/15 19:11:50 [debug] 30743#30743: *162 http script var: "XXX"
5392016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "
540"
5412016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
5422016/11/15 19:11:50 [debug] 30743#30743: *162 http script var: "XXXX"
5432016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "
544"
5452016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "Connection: close
546"
5472016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "Content-Length: "
5482016/11/15 19:11:50 [debug] 30743#30743: *162 http script var: "16595"
5492016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: "
550"
5512016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: ""
5522016/11/15 19:11:50 [debug] 30743#30743: *162 http script copy: ""
5532016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
5542016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
5552016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
5562016/11/15 19:11:50 [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"
5572016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
5582016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
5592016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
5602016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
5612016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header:
562"POST /appname/v2/heartbeat HTTP/1.1
563X-Real-IP: 192.168.123.11
564X-Forwarded-For: 192.168.123.11
565Host: app3.mydomain.local
566X-NginX-Proxy: true
567
568
569Connection: close
570Content-Length: 16595
571accept: application/json, text/plain, */*
572origin: https://app3.mydomain.local
573appname-csrf-token: YYY
574user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
575content-type: application/json;charset=UTF-8
576accept-encoding: gzip, deflate, br
577accept-language: en-US,en;q=0.8
5782016/11/15 19:11:50 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBE970:4096 @16
5792016/11/15 19:11:50 [debug] 30743#30743: *162 http cleanup add: 00000F0D34DBE938
5802016/11/15 19:11:50 [debug] 30743#30743: *162 get rr peer, try: 1
5812016/11/15 19:11:50 [debug] 30743#30743: *162 stream socket 7
5822016/11/15 19:11:50 [debug] 30743#30743: *162 epoll add connection: fd:7 ev:80002005
5832016/11/15 19:11:50 [debug] 30743#30743: *162 connect to 182.168.128.69:8080, fd:7 #576
5842016/11/15 19:11:50 [debug] 30743#30743: *162 http upstream connect: -2
5852016/11/15 19:11:50 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBBA30:128 @16
5862016/11/15 19:11:50 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237170593
5872016/11/15 19:11:50 [debug] 30743#30743: *162 free: 00000F0D34D89720
5882016/11/15 19:11:50 [debug] 30743#30743: *162 http finalize request: -4, "/appname/v2/heartbeat?" a:1, c:2
5892016/11/15 19:11:50 [debug] 30743#30743: *162 http request count:2 blk:0
5902016/11/15 19:11:50 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
5912016/11/15 19:11:50 [debug] 30743#30743: *162 http upstream send request handler
5922016/11/15 19:11:50 [debug] 30743#30743: *162 http upstream send request
5932016/11/15 19:11:50 [debug] 30743#30743: *162 http upstream send request body
5942016/11/15 19:11:50 [debug] 30743#30743: *162 chain writer buf fl:0 s:2690
5952016/11/15 19:11:50 [debug] 30743#30743: *162 chain writer buf fl:1 s:16595
5962016/11/15 19:11:50 [debug] 30743#30743: *162 chain writer in: 00000F0D34DBEA00
5972016/11/15 19:11:50 [debug] 30743#30743: *162 writev: 19285 of 19285
5982016/11/15 19:11:50 [debug] 30743#30743: *162 chain writer out: 0000000000000000
5992016/11/15 19:11:50 [debug] 30743#30743: *162 event timer del: 7: 1479237170593
6002016/11/15 19:11:50 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237170594
6012016/11/15 19:11:50 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
6022016/11/15 19:11:50 [debug] 30743#30743: *162 http upstream process header
6032016/11/15 19:11:50 [debug] 30743#30743: *162 malloc: 00000F0D34D69D00:4096
6042016/11/15 19:11:50 [debug] 30743#30743: *162 recv: fd:7 1056 of 4096
6052016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy status 400 "400 Bad Request"
6062016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "Server: nginx"
6072016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "Date: Tue, 15 Nov 2016 19:11:48 GMT"
6082016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "Content-Type: text/html; charset=utf-8"
6092016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "Content-Length: 840"
6102016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "Connection: close"
6112016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "X-Powered-By: Express"
6122016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header: "X-Content-Type-Options: nosniff"
6132016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy header done
6142016/11/15 19:11:50 [debug] 30743#30743: *162 lua capture header filter, uri "/appname/v2/heartbeat"
6152016/11/15 19:11:50 [debug] 30743#30743: *162 http2 header filter
6162016/11/15 19:11:50 [debug] 30743#30743: *162 http2 output header: ":status: 400"
6172016/11/15 19:11:50 [debug] 30743#30743: *162 http2 output header: "server: nginx"
6182016/11/15 19:11:50 [debug] 30743#30743: *162 http2 output header: "date: Tue, 15 Nov 2016 19:11:50 GMT"
6192016/11/15 19:11:50 [debug] 30743#30743: *162 http2 output header: "content-type: text/html; charset=utf-8"
6202016/11/15 19:11:50 [debug] 30743#30743: *162 http2 output header: "content-length: 840"
6212016/11/15 19:11:50 [debug] 30743#30743: *162 http2 output header: "x-powered-by: Express"
6222016/11/15 19:11:50 [debug] 30743#30743: *162 http2 output header: "x-content-type-options: nosniff"
6232016/11/15 19:11:50 [debug] 30743#30743: *162 http2:433 create HEADERS frame 00000F0D34DBEDA8: len:98
6242016/11/15 19:11:50 [debug] 30743#30743: *162 http cleanup add: 00000F0D34DBEE90
6252016/11/15 19:11:50 [debug] 30743#30743: *162 http2 frame out: 00000F0D34DBEDA8 sid:433 bl:1 len:98
6262016/11/15 19:11:50 [debug] 30743#30743: *162 malloc: 00000F0D34D83A10:16384
6272016/11/15 19:11:50 [debug] 30743#30743: *162 SSL buf copy: 9
6282016/11/15 19:11:50 [debug] 30743#30743: *162 SSL buf copy: 98
6292016/11/15 19:11:50 [debug] 30743#30743: *162 http2:433 HEADERS frame 00000F0D34DBEDA8 was sent
6302016/11/15 19:11:50 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34DBEDA8 sid:433 bl:1 len:98
6312016/11/15 19:11:50 [debug] 30743#30743: *162 http cacheable: 0
6322016/11/15 19:11:50 [debug] 30743#30743: *162 http proxy filter init s:400 h:0 c:0 l:840
6332016/11/15 19:11:50 [debug] 30743#30743: *162 http upstream process upstream
6342016/11/15 19:11:50 [debug] 30743#30743: *162 pipe read upstream: 1
6352016/11/15 19:11:50 [debug] 30743#30743: *162 pipe preread: 840
6362016/11/15 19:11:50 [debug] 30743#30743: *162 readv: 1, last:3040
6372016/11/15 19:11:50 [debug] 30743#30743: *162 pipe recv chain: 0
6382016/11/15 19:11:50 [debug] 30743#30743: *162 pipe buf free s:0 t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
6392016/11/15 19:11:50 [debug] 30743#30743: *162 pipe length: 840
6402016/11/15 19:11:50 [debug] 30743#30743: *162 input buf #0
6412016/11/15 19:11:50 [debug] 30743#30743: *162 pipe write downstream: 1
6422016/11/15 19:11:50 [debug] 30743#30743: *162 pipe write downstream flush in
6432016/11/15 19:11:50 [debug] 30743#30743: *162 http output filter "/appname/v2/heartbeat?"
6442016/11/15 19:11:50 [debug] 30743#30743: *162 http copy filter: "/appname/v2/heartbeat?"
6452016/11/15 19:11:50 [debug] 30743#30743: *162 lua capture body filter, uri "/appname/v2/heartbeat"
6462016/11/15 19:11:50 [debug] 30743#30743: *162 http postpone filter "/appname/v2/heartbeat?" 00000F0D34DBEA00
6472016/11/15 19:11:50 [debug] 30743#30743: *162 write new buf t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
6482016/11/15 19:11:50 [debug] 30743#30743: *162 http write filter: l:0 f:0 s:840
6492016/11/15 19:11:50 [debug] 30743#30743: *162 http copy filter: 0 "/appname/v2/heartbeat?"
6502016/11/15 19:11:50 [debug] 30743#30743: *162 pipe write downstream done
6512016/11/15 19:11:50 [debug] 30743#30743: *162 event timer: 7, old: 1479237170594, new: 1479237170608
6522016/11/15 19:11:50 [debug] 30743#30743: *162 http upstream exit: 0000000000000000
6532016/11/15 19:11:50 [debug] 30743#30743: *162 finalize http upstream request: 0
6542016/11/15 19:11:50 [debug] 30743#30743: *162 finalize http proxy request
6552016/11/15 19:11:50 [debug] 30743#30743: *162 free rr peer 1 0
6562016/11/15 19:11:50 [debug] 30743#30743: *162 close http upstream connection: 7
6572016/11/15 19:11:50 [debug] 30743#30743: *162 free: 00000F0D34DBBA30, unused: 48
6582016/11/15 19:11:50 [debug] 30743#30743: *162 event timer del: 7: 1479237170594
6592016/11/15 19:11:50 [debug] 30743#30743: *162 reusable connection: 0
6602016/11/15 19:11:50 [debug] 30743#30743: *162 http upstream temp fd: -1
6612016/11/15 19:11:50 [debug] 30743#30743: *162 http output filter "/appname/v2/heartbeat?"
6622016/11/15 19:11:50 [debug] 30743#30743: *162 http copy filter: "/appname/v2/heartbeat?"
6632016/11/15 19:11:50 [debug] 30743#30743: *162 lua capture body filter, uri "/appname/v2/heartbeat"
6642016/11/15 19:11:50 [debug] 30743#30743: *162 http postpone filter "/appname/v2/heartbeat?" 00007A9E70E05A50
6652016/11/15 19:11:50 [debug] 30743#30743: *162 write old buf t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
6662016/11/15 19:11:50 [debug] 30743#30743: *162 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
6672016/11/15 19:11:50 [debug] 30743#30743: *162 http write filter: l:1 f:0 s:840
6682016/11/15 19:11:50 [debug] 30743#30743: *162 http write filter limit 0
6692016/11/15 19:11:50 [debug] 30743#30743: *162 http2:433 create DATA frame 00000F0D34DBEDA8: len:840 flags:1
6702016/11/15 19:11:50 [debug] 30743#30743: *162 http2 frame out: 00000F0D34DBEDA8 sid:433 bl:0 len:840
6712016/11/15 19:11:50 [debug] 30743#30743: *162 SSL buf copy: 9
6722016/11/15 19:11:50 [debug] 30743#30743: *162 SSL buf copy: 840
6732016/11/15 19:11:50 [debug] 30743#30743: *162 SSL to write: 956
6742016/11/15 19:11:50 [debug] 30743#30743: *162 SSL_write: 956
6752016/11/15 19:11:50 [debug] 30743#30743: *162 http2:433 DATA frame 00000F0D34DBEDA8 was sent
6762016/11/15 19:11:50 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34DBEDA8 sid:433 bl:0 len:840
6772016/11/15 19:11:50 [debug] 30743#30743: *162 http write filter 0000000000000000
6782016/11/15 19:11:50 [debug] 30743#30743: *162 http copy filter: 0 "/appname/v2/heartbeat?"
6792016/11/15 19:11:50 [debug] 30743#30743: *162 http finalize request: 0, "/appname/v2/heartbeat?" a:1, c:1
6802016/11/15 19:11:50 [debug] 30743#30743: *162 http request count:1 blk:0
6812016/11/15 19:11:50 [debug] 30743#30743: *162 http2 close stream 433, queued 0, processing 1
6822016/11/15 19:11:50 [debug] 30743#30743: *162 http close request
6832016/11/15 19:11:50 [debug] 30743#30743: *162 http log handler
6842016/11/15 19:11:50 [debug] 30743#30743: *162 free: 00000F0D34DBC950
6852016/11/15 19:11:50 [debug] 30743#30743: *162 free: 00000F0D34D69D00
6862016/11/15 19:11:50 [debug] 30743#30743: *162 free: 00000F0D34E03BC0, unused: 0
6872016/11/15 19:11:50 [debug] 30743#30743: *162 free: 00000F0D34E63480, unused: 2
6882016/11/15 19:11:50 [debug] 30743#30743: *162 free: 00000F0D34EB6570, unused: 0
6892016/11/15 19:11:50 [debug] 30743#30743: *162 free: 00000F0D34EB7580, unused: 8
6902016/11/15 19:11:50 [debug] 30743#30743: *162 free: 00000F0D34DBD960, unused: 0
6912016/11/15 19:11:50 [debug] 30743#30743: *162 free: 00000F0D34DBE970, unused: 1926
6922016/11/15 19:11:50 [debug] 30743#30743: *162 free: 00000F0D34DC4930, unused: 162
6932016/11/15 19:11:50 [debug] 30743#30743: *162 free: 00000F0D34E16F00, unused: 156
6942016/11/15 19:11:50 [debug] 30743#30743: *162 free: 00000F0D34E61530, unused: 111
6952016/11/15 19:11:50 [debug] 30743#30743: *162 post event 00006EFC4C5081F0
6962016/11/15 19:11:50 [debug] 30743#30743: *162 delete posted event 00006EFC4C5081F0
6972016/11/15 19:11:50 [debug] 30743#30743: *162 http2 handle connection handler
6982016/11/15 19:11:50 [debug] 30743#30743: *162 free: 00000F0D34E120F0, unused: 3488
6992016/11/15 19:11:50 [debug] 30743#30743: *162 free: 00000F0D34D83A10
7002016/11/15 19:11:50 [debug] 30743#30743: *162 reusable connection: 1
7012016/11/15 19:11:50 [debug] 30743#30743: *162 event timer add: 3: 180000:1479237290608
7022016/11/15 19:11:57 [debug] 30741#30741: *300 post event 00006EFC4C5082B0
7032016/11/15 19:11:57 [debug] 30741#30741: *300 post event 00006EFC4C2072B0
7042016/11/15 19:11:57 [debug] 30741#30741: *300 delete posted event 00006EFC4C5082B0
7052016/11/15 19:11:57 [debug] 30741#30741: *300 http run request: "/appname/socket/?EIO=3&transport=websocket&sid=fJvr3YafvdqAy4F3AAAC"
7062016/11/15 19:11:57 [debug] 30741#30741: *300 http upstream process upgraded, fu:0
7072016/11/15 19:11:57 [debug] 30741#30741: *300 SSL_read: 9
7082016/11/15 19:11:57 [debug] 30741#30741: *300 SSL_read: -1
7092016/11/15 19:11:57 [debug] 30741#30741: *300 SSL_get_error: 2
7102016/11/15 19:11:57 [debug] 30741#30741: *300 send: fd:16 9 of 9
7112016/11/15 19:11:57 [debug] 30741#30741: *300 event timer del: 16: 1479237151562
7122016/11/15 19:11:57 [debug] 30741#30741: *300 event timer add: 16: 60000:1479237177561
7132016/11/15 19:11:57 [debug] 30741#30741: *300 delete posted event 00006EFC4C2072B0
7142016/11/15 19:11:57 [debug] 30741#30741: *300 http run request: "/appname/socket/?EIO=3&transport=websocket&sid=fJvr3YafvdqAy4F3AAAC"
7152016/11/15 19:11:57 [debug] 30741#30741: *300 http upstream process upgraded, fu:1
7162016/11/15 19:11:57 [debug] 30741#30741: *300 event timer: 16, old: 1479237177561, new: 1479237177561
7172016/11/15 19:11:57 [debug] 30741#30741: *300 post event 00006EFC4C508310
7182016/11/15 19:11:57 [debug] 30741#30741: *300 post event 00006EFC4C207310
7192016/11/15 19:11:57 [debug] 30741#30741: *300 delete posted event 00006EFC4C508310
7202016/11/15 19:11:57 [debug] 30741#30741: *300 http upstream request: "/appname/socket/?EIO=3&transport=websocket&sid=fJvr3YafvdqAy4F3AAAC"
7212016/11/15 19:11:57 [debug] 30741#30741: *300 http upstream process upgraded, fu:1
7222016/11/15 19:11:57 [debug] 30741#30741: *300 recv: fd:16 3 of 4096
7232016/11/15 19:11:57 [debug] 30741#30741: *300 SSL to write: 3
7242016/11/15 19:11:57 [debug] 30741#30741: *300 SSL_write: 3
7252016/11/15 19:11:57 [debug] 30741#30741: *300 recv: fd:16 -1 of 4096
7262016/11/15 19:11:57 [debug] 30741#30741: *300 recv() not ready (11: Resource temporarily unavailable)
7272016/11/15 19:11:57 [debug] 30741#30741: *300 event timer: 16, old: 1479237177561, new: 1479237177563
7282016/11/15 19:11:57 [debug] 30741#30741: *300 delete posted event 00006EFC4C207310
7292016/11/15 19:11:57 [debug] 30741#30741: *300 http upstream request: "/appname/socket/?EIO=3&transport=websocket&sid=fJvr3YafvdqAy4F3AAAC"
7302016/11/15 19:11:57 [debug] 30741#30741: *300 http upstream process upgraded, fu:0
7312016/11/15 19:11:57 [debug] 30741#30741: *300 event timer: 16, old: 1479237177561, new: 1479237177563
7322016/11/15 19:11:58 [debug] 30743#30743: *162 http2 idle handler
7332016/11/15 19:11:58 [debug] 30743#30743: *162 reusable connection: 0
7342016/11/15 19:11:58 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E03BC0:4096 @16
7352016/11/15 19:11:58 [debug] 30743#30743: *162 http2 read handler
7362016/11/15 19:11:58 [debug] 30743#30743: *162 SSL_read: 55
7372016/11/15 19:11:58 [debug] 30743#30743: *162 SSL_read: -1
7382016/11/15 19:11:58 [debug] 30743#30743: *162 SSL_get_error: 2
7392016/11/15 19:11:58 [debug] 30743#30743: *162 process http2 frame type:1 f:24 l:46 sid:435
7402016/11/15 19:11:58 [debug] 30743#30743: *162 http2 HEADERS frame sid:435 on 0 excl:0 weight:183
7412016/11/15 19:11:58 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E61530:1024 @16
7422016/11/15 19:11:58 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E120F0:4096 @16
7432016/11/15 19:11:58 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E63480:4096 @16
7442016/11/15 19:11:58 [debug] 30743#30743: *162 http2 get indexed header name: 3
7452016/11/15 19:11:58 [debug] 30743#30743: *162 http2 get indexed header name: 69
7462016/11/15 19:11:58 [debug] 30743#30743: *162 http2 get indexed header name: 7
7472016/11/15 19:11:58 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
7482016/11/15 19:11:58 [debug] 30743#30743: *162 http2 hpack encoded string length: 13
7492016/11/15 19:11:58 [debug] 30743#30743: *162 http uri: "/appname/v2/heartbeat"
7502016/11/15 19:11:58 [debug] 30743#30743: *162 http args: ""
7512016/11/15 19:11:58 [debug] 30743#30743: *162 http exten: ""
7522016/11/15 19:11:58 [debug] 30743#30743: *162 http2 get indexed header: 28
7532016/11/15 19:11:58 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
7542016/11/15 19:11:58 [debug] 30743#30743: *162 http2 add header to hpack table: "content-length: 26778"
7552016/11/15 19:11:58 [debug] 30743#30743: *162 http2 hpack table account: 51 free:126
7562016/11/15 19:11:58 [debug] 30743#30743: *162 http2 http header: "content-length: 26778"
7572016/11/15 19:11:58 [debug] 30743#30743: *162 http2 get indexed header name: 68
7582016/11/15 19:11:58 [debug] 30743#30743: *162 http2 http header: "accept: application/json, text/plain, */*"
7592016/11/15 19:11:58 [debug] 30743#30743: *162 http2 get indexed header name: 80
7602016/11/15 19:11:58 [debug] 30743#30743: *162 http2 http header: "origin: https://app3.mydomain.local"
7612016/11/15 19:11:58 [debug] 30743#30743: *162 http2 get indexed header name: 96
7622016/11/15 19:11:58 [debug] 30743#30743: *162 http2 http header: "appname-csrf-token: YYY
7632016/11/15 19:11:58 [debug] 30743#30743: *162 http2 get indexed header name: 64
7642016/11/15 19:11:58 [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"
7652016/11/15 19:11:58 [debug] 30743#30743: *162 http2 get indexed header name: 79
7662016/11/15 19:11:58 [debug] 30743#30743: *162 http2 http header: "content-type: application/json;charset=UTF-8"
7672016/11/15 19:11:58 [debug] 30743#30743: *162 http2 get indexed header name: 77
7682016/11/15 19:11:58 [debug] 30743#30743: *162 http2 http header: "accept-encoding: gzip, deflate, br"
7692016/11/15 19:11:58 [debug] 30743#30743: *162 http2 get indexed header name: 93
7702016/11/15 19:11:58 [debug] 30743#30743: *162 http2 http header: "accept-language: en-US,en;q=0.8"
7712016/11/15 19:11:58 [debug] 30743#30743: *162 http2 get indexed header name: 92
7722016/11/15 19:11:58 [debug] 30743#30743: *162 http2 get indexed header name: 91
7732016/11/15 19:11:58 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E16F00:1024 @16
7742016/11/15 19:11:58 [debug] 30743#30743: *162 http2 get indexed header name: 90
7752016/11/15 19:11:58 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DC4930:1024 @16
7762016/11/15 19:11:58 [debug] 30743#30743: *162 http2 get indexed header name: 89
7772016/11/15 19:11:58 [debug] 30743#30743: *162 http2 get indexed header name: 95
7782016/11/15 19:11:58 [debug] 30743#30743: *162 http2 http request line: "POST /appname/v2/heartbeat HTTP/2.0"
7792016/11/15 19:11:58 [debug] 30743#30743: *162 generic phase: 0
7802016/11/15 19:11:58 [debug] 30743#30743: *162 rewrite phase: 1
7812016/11/15 19:11:58 [debug] 30743#30743: *162 test location: "^/widgets/$"
7822016/11/15 19:11:58 [debug] 30743#30743: *162 test location: "/"
7832016/11/15 19:11:58 [debug] 30743#30743: *162 test location: "docs/"
7842016/11/15 19:11:58 [debug] 30743#30743: *162 test location: "appname/socket"
7852016/11/15 19:11:58 [debug] 30743#30743: *162 test location: ~ "^/(index.html)?$"
7862016/11/15 19:11:58 [debug] 30743#30743: *162 test location: ~ "^/login/?$"
7872016/11/15 19:11:58 [debug] 30743#30743: *162 test location: ~ "^/(appname)"
7882016/11/15 19:11:58 [debug] 30743#30743: *162 test location: ~ "/components/(.*)"
7892016/11/15 19:11:58 [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)$"
7902016/11/15 19:11:58 [debug] 30743#30743: *162 using configuration "/"
7912016/11/15 19:11:58 [debug] 30743#30743: *162 http cl:26778 max:1073741824
7922016/11/15 19:11:58 [debug] 30743#30743: *162 rewrite phase: 3
7932016/11/15 19:11:58 [debug] 30743#30743: *162 post rewrite phase: 4
7942016/11/15 19:11:58 [debug] 30743#30743: *162 generic phase: 5
7952016/11/15 19:11:58 [debug] 30743#30743: *162 generic phase: 6
7962016/11/15 19:11:58 [debug] 30743#30743: *162 generic phase: 7
7972016/11/15 19:11:58 [debug] 30743#30743: *162 access phase: 8
7982016/11/15 19:11:58 [debug] 30743#30743: *162 access phase: 9
7992016/11/15 19:11:58 [debug] 30743#30743: *162 access phase: 10
8002016/11/15 19:11:58 [debug] 30743#30743: *162 auth request handler
8012016/11/15 19:11:58 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB6570:4096 @16
8022016/11/15 19:11:58 [debug] 30743#30743: *162 http subrequest "/authorize?"
8032016/11/15 19:11:58 [debug] 30743#30743: *162 http posted request: "/authorize?"
8042016/11/15 19:11:58 [debug] 30743#30743: *162 rewrite phase: 1
8052016/11/15 19:11:58 [debug] 30743#30743: *162 test location: "^/widgets/$"
8062016/11/15 19:11:58 [debug] 30743#30743: *162 test location: "/"
8072016/11/15 19:11:58 [debug] 30743#30743: *162 test location: "docs/"
8082016/11/15 19:11:58 [debug] 30743#30743: *162 test location: "dashboard"
8092016/11/15 19:11:58 [debug] 30743#30743: *162 test location: "authorize"
8102016/11/15 19:11:58 [debug] 30743#30743: *162 using configuration "=/authorize"
8112016/11/15 19:11:58 [debug] 30743#30743: *162 http cl:26778 max:1073741824
8122016/11/15 19:11:58 [debug] 30743#30743: *162 rewrite phase: 3
8132016/11/15 19:11:58 [debug] 30743#30743: *162 post rewrite phase: 4
8142016/11/15 19:11:58 [debug] 30743#30743: *162 generic phase: 5
8152016/11/15 19:11:58 [debug] 30743#30743: *162 generic phase: 6
8162016/11/15 19:11:58 [debug] 30743#30743: *162 generic phase: 7
8172016/11/15 19:11:58 [debug] 30743#30743: *162 http init upstream, client timer: 0
8182016/11/15 19:11:58 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB7580:4096 @16
8192016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
8202016/11/15 19:11:58 [debug] 30743#30743: *162 http script var: "192.168.123.11"
8212016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "
822"
8232016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
8242016/11/15 19:11:58 [debug] 30743#30743: *162 http script var: "192.168.123.11"
8252016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "
826"
8272016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "Host: "
8282016/11/15 19:11:58 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
8292016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "
830"
8312016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
832"
8332016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
8342016/11/15 19:11:58 [debug] 30743#30743: *162 http script var: "XXX"
8352016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "
836"
8372016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
8382016/11/15 19:11:58 [debug] 30743#30743: *162 http script var: "XXXX"
8392016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "
840"
8412016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "x-appname-uri: "
8422016/11/15 19:11:58 [debug] 30743#30743: *162 http script var: "/appname/v2/heartbeat"
8432016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "
844"
8452016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "x-appname-method: "
8462016/11/15 19:11:58 [debug] 30743#30743: *162 http script var: "POST"
8472016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "
848"
8492016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "Connection: close
850"
8512016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: ""
8522016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: ""
8532016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
8542016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
8552016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
8562016/11/15 19:11:58 [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"
8572016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
8582016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
8592016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
8602016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
8612016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header:
862"GET /authorize HTTP/1.1
863X-Real-IP: 192.168.123.11
864X-Forwarded-For: 192.168.123.11
865Host: app3.mydomain.local
866X-NginX-Proxy: true
867
868
869x-appname-uri: /appname/v2/heartbeat
870x-appname-method: POST
871Connection: close
872accept: application/json, text/plain, */*
873origin: https://app3.mydomain.local
874appname-csrf-token: YYY
875user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
876content-type: application/json;charset=UTF-8
877accept-encoding: gzip, deflate, br
878accept-language: en-US,en;q=0.8
8792016/11/15 19:11:58 [debug] 30743#30743: *162 http cleanup add: 00000F0D34E64460
8802016/11/15 19:11:58 [debug] 30743#30743: *162 get rr peer, try: 1
8812016/11/15 19:11:58 [debug] 30743#30743: *162 stream socket 7
8822016/11/15 19:11:58 [debug] 30743#30743: *162 epoll add connection: fd:7 ev:80002005
8832016/11/15 19:11:58 [debug] 30743#30743: *162 connect to 127.0.0.1:8008, fd:7 #577
8842016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream connect: -2
8852016/11/15 19:11:58 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBBA30:128 @16
8862016/11/15 19:11:58 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237178556
8872016/11/15 19:11:58 [debug] 30743#30743: *162 http finalize request: -4, "/authorize?" a:1, c:3
8882016/11/15 19:11:58 [debug] 30743#30743: *162 http request count:3 blk:0
8892016/11/15 19:11:58 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AC047 end:00006EFC4F5AC047
8902016/11/15 19:11:58 [debug] 30743#30743: *162 event timer del: 3: 1479237290608
8912016/11/15 19:11:58 [debug] 30743#30743: *162 http2 write handler
8922016/11/15 19:11:58 [debug] 30743#30743: *162 http2 read handler
8932016/11/15 19:11:58 [debug] 30743#30743: *162 SSL_read: 2861
8942016/11/15 19:11:58 [debug] 30743#30743: *162 SSL_read: 2861
8952016/11/15 19:11:58 [debug] 30743#30743: *162 SSL_read: 2861
8962016/11/15 19:11:58 [debug] 30743#30743: *162 SSL_read: 2861
8972016/11/15 19:11:58 [debug] 30743#30743: *162 SSL_read: 2861
8982016/11/15 19:11:58 [debug] 30743#30743: *162 SSL_read: 2861
8992016/11/15 19:11:58 [debug] 30743#30743: *162 SSL_read: 2861
9002016/11/15 19:11:58 [debug] 30743#30743: *162 SSL_read: 2861
9012016/11/15 19:11:58 [debug] 30743#30743: *162 SSL_read: 2861
9022016/11/15 19:11:58 [debug] 30743#30743: *162 SSL_read: 1119
9032016/11/15 19:11:58 [debug] 30743#30743: *162 SSL_read: -1
9042016/11/15 19:11:58 [debug] 30743#30743: *162 SSL_get_error: 2
9052016/11/15 19:11:58 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:435
9062016/11/15 19:11:58 [debug] 30743#30743: *162 http2 DATA frame
9072016/11/15 19:11:58 [debug] 30743#30743: *162 malloc: 00000F0D34D89720:65536
9082016/11/15 19:11:58 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5ACB3D end:00006EFC4F5B2904
9092016/11/15 19:11:58 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:435
9102016/11/15 19:11:58 [debug] 30743#30743: *162 http2 DATA frame
9112016/11/15 19:11:58 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AD66A end:00006EFC4F5B2904
9122016/11/15 19:11:58 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:435
9132016/11/15 19:11:58 [debug] 30743#30743: *162 http2 DATA frame
9142016/11/15 19:11:58 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AE197 end:00006EFC4F5B2904
9152016/11/15 19:11:58 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:435
9162016/11/15 19:11:58 [debug] 30743#30743: *162 http2 DATA frame
9172016/11/15 19:11:58 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AECC4 end:00006EFC4F5B2904
9182016/11/15 19:11:58 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:435
9192016/11/15 19:11:58 [debug] 30743#30743: *162 http2 DATA frame
9202016/11/15 19:11:58 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AF7F1 end:00006EFC4F5B2904
9212016/11/15 19:11:58 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:435
9222016/11/15 19:11:58 [debug] 30743#30743: *162 http2 DATA frame
9232016/11/15 19:11:58 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5B031E end:00006EFC4F5B2904
9242016/11/15 19:11:58 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:435
9252016/11/15 19:11:58 [debug] 30743#30743: *162 http2 DATA frame
9262016/11/15 19:11:58 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5B0E4B end:00006EFC4F5B2904
9272016/11/15 19:11:58 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:435
9282016/11/15 19:11:58 [debug] 30743#30743: *162 http2 DATA frame
9292016/11/15 19:11:58 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5B1978 end:00006EFC4F5B2904
9302016/11/15 19:11:58 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:435
9312016/11/15 19:11:58 [debug] 30743#30743: *162 http2 DATA frame
9322016/11/15 19:11:58 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5B24A5 end:00006EFC4F5B2904
9332016/11/15 19:11:58 [debug] 30743#30743: *162 process http2 frame type:0 f:1 l:1110 sid:435
9342016/11/15 19:11:58 [debug] 30743#30743: *162 http2 DATA frame
9352016/11/15 19:11:58 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5B2904 end:00006EFC4F5B2904
9362016/11/15 19:11:58 [debug] 30743#30743: *162 http2 write handler
9372016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream request: "/authorize?"
9382016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream send request handler
9392016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream send request
9402016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream send request body
9412016/11/15 19:11:58 [debug] 30743#30743: *162 chain writer buf fl:1 s:2711
9422016/11/15 19:11:58 [debug] 30743#30743: *162 chain writer in: 00000F0D34EB7550
9432016/11/15 19:11:58 [debug] 30743#30743: *162 writev: 2711 of 2711
9442016/11/15 19:11:58 [debug] 30743#30743: *162 chain writer out: 0000000000000000
9452016/11/15 19:11:58 [debug] 30743#30743: *162 event timer del: 7: 1479237178556
9462016/11/15 19:11:58 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237178559
9472016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream request: "/authorize?"
9482016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream process header
9492016/11/15 19:11:58 [debug] 30743#30743: *162 malloc: 00000F0D34DBC950:4096
9502016/11/15 19:11:58 [debug] 30743#30743: *162 recv: fd:7 172 of 4096
9512016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy status 200 "200 OK"
9522016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "X-Powered-By: Express"
9532016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "x-appname-origin: appname3"
9542016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "content-type: application/json"
9552016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "content-length: 0"
9562016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "Date: Tue, 15 Nov 2016 19:11:58 GMT"
9572016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "Connection: close"
9582016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header done
9592016/11/15 19:11:58 [debug] 30743#30743: *162 lua capture header filter, uri "/authorize"
9602016/11/15 19:11:58 [debug] 30743#30743: *162 http2 header filter
9612016/11/15 19:11:58 [debug] 30743#30743: *162 finalize http upstream request: 0
9622016/11/15 19:11:58 [debug] 30743#30743: *162 finalize http proxy request
9632016/11/15 19:11:58 [debug] 30743#30743: *162 free rr peer 1 0
9642016/11/15 19:11:58 [debug] 30743#30743: *162 close http upstream connection: 7
9652016/11/15 19:11:58 [debug] 30743#30743: *162 free: 00000F0D34DBBA30, unused: 48
9662016/11/15 19:11:58 [debug] 30743#30743: *162 event timer del: 7: 1479237178559
9672016/11/15 19:11:58 [debug] 30743#30743: *162 reusable connection: 0
9682016/11/15 19:11:58 [debug] 30743#30743: *162 http finalize request: 0, "/authorize?" a:1, c:2
9692016/11/15 19:11:58 [debug] 30743#30743: *162 auth request done s:200
9702016/11/15 19:11:58 [debug] 30743#30743: *162 http wake parent request: "/appname/v2/heartbeat?"
9712016/11/15 19:11:58 [debug] 30743#30743: *162 http posted request: "/appname/v2/heartbeat?"
9722016/11/15 19:11:58 [debug] 30743#30743: *162 access phase: 10
9732016/11/15 19:11:58 [debug] 30743#30743: *162 auth request handler
9742016/11/15 19:11:58 [debug] 30743#30743: *162 auth request set variables
9752016/11/15 19:11:58 [debug] 30743#30743: *162 access phase: 11
9762016/11/15 19:11:58 [debug] 30743#30743: *162 lua access handler, uri:"/appname/v2/heartbeat" c:1
9772016/11/15 19:11:58 [debug] 30743#30743: *162 post access phase: 12
9782016/11/15 19:11:58 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBD960:4096 @16
9792016/11/15 19:11:58 [debug] 30743#30743: *162 http body new buf t:1 f:0 00000F0D34D89720, pos 00000F0D34D89720, size: 26778 file: 0, size: 0
9802016/11/15 19:11:58 [debug] 30743#30743: *162 http init upstream, client timer: 0
9812016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
9822016/11/15 19:11:58 [debug] 30743#30743: *162 http script var: "192.168.123.11"
9832016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "
984"
9852016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
9862016/11/15 19:11:58 [debug] 30743#30743: *162 http script var: "192.168.123.11"
9872016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "
988"
9892016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "Host: "
9902016/11/15 19:11:58 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
9912016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "
992"
9932016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
994"
9952016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
9962016/11/15 19:11:58 [debug] 30743#30743: *162 http script var: "XXX"
9972016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "
998"
9992016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
10002016/11/15 19:11:58 [debug] 30743#30743: *162 http script var: "XXXX"
10012016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "
1002"
10032016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "Connection: close
1004"
10052016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "Content-Length: "
10062016/11/15 19:11:58 [debug] 30743#30743: *162 http script var: "26778"
10072016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: "
1008"
10092016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: ""
10102016/11/15 19:11:58 [debug] 30743#30743: *162 http script copy: ""
10112016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
10122016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
10132016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
10142016/11/15 19:11:58 [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"
10152016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
10162016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
10172016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
10182016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
10192016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header:
1020"POST /appname/v2/heartbeat HTTP/1.1
1021X-Real-IP: 192.168.123.11
1022X-Forwarded-For: 192.168.123.11
1023Host: app3.mydomain.local
1024X-NginX-Proxy: true
1025
1026
1027Connection: close
1028Content-Length: 26778
1029accept: application/json, text/plain, */*
1030origin: https://app3.mydomain.local
1031appname-csrf-token: YYY
1032user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
1033content-type: application/json;charset=UTF-8
1034accept-encoding: gzip, deflate, br
1035accept-language: en-US,en;q=0.8
10362016/11/15 19:11:58 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBE970:4096 @16
10372016/11/15 19:11:58 [debug] 30743#30743: *162 http cleanup add: 00000F0D34DBE938
10382016/11/15 19:11:58 [debug] 30743#30743: *162 get rr peer, try: 1
10392016/11/15 19:11:58 [debug] 30743#30743: *162 stream socket 7
10402016/11/15 19:11:58 [debug] 30743#30743: *162 epoll add connection: fd:7 ev:80002005
10412016/11/15 19:11:58 [debug] 30743#30743: *162 connect to 182.168.128.69:8080, fd:7 #578
10422016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream connect: -2
10432016/11/15 19:11:58 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBBA30:128 @16
10442016/11/15 19:11:58 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237178619
10452016/11/15 19:11:58 [debug] 30743#30743: *162 free: 00000F0D34D89720
10462016/11/15 19:11:58 [debug] 30743#30743: *162 http finalize request: -4, "/appname/v2/heartbeat?" a:1, c:2
10472016/11/15 19:11:58 [debug] 30743#30743: *162 http request count:2 blk:0
10482016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
10492016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream send request handler
10502016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream send request
10512016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream send request body
10522016/11/15 19:11:58 [debug] 30743#30743: *162 chain writer buf fl:0 s:2690
10532016/11/15 19:11:58 [debug] 30743#30743: *162 chain writer buf fl:1 s:26778
10542016/11/15 19:11:58 [debug] 30743#30743: *162 chain writer in: 00000F0D34DBEA00
10552016/11/15 19:11:58 [debug] 30743#30743: *162 writev: 28960 of 29468
10562016/11/15 19:11:58 [debug] 30743#30743: *162 chain writer out: 00000F0D34DBEA10
10572016/11/15 19:11:58 [debug] 30743#30743: *162 event timer: 7, old: 1479237178619, new: 1479237178619
10582016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
10592016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream send request handler
10602016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream send request
10612016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream send request body
10622016/11/15 19:11:58 [debug] 30743#30743: *162 chain writer in: 00000F0D34DBEA10
10632016/11/15 19:11:58 [debug] 30743#30743: *162 writev: 508 of 508
10642016/11/15 19:11:58 [debug] 30743#30743: *162 chain writer out: 0000000000000000
10652016/11/15 19:11:58 [debug] 30743#30743: *162 event timer del: 7: 1479237178619
10662016/11/15 19:11:58 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237178620
10672016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
10682016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream process header
10692016/11/15 19:11:58 [debug] 30743#30743: *162 malloc: 00000F0D34D69D00:4096
10702016/11/15 19:11:58 [debug] 30743#30743: *162 recv: fd:7 1056 of 4096
10712016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy status 400 "400 Bad Request"
10722016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "Server: nginx"
10732016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "Date: Tue, 15 Nov 2016 19:11:59 GMT"
10742016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "Content-Type: text/html; charset=utf-8"
10752016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "Content-Length: 840"
10762016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "Connection: close"
10772016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "X-Powered-By: Express"
10782016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header: "X-Content-Type-Options: nosniff"
10792016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy header done
10802016/11/15 19:11:58 [debug] 30743#30743: *162 lua capture header filter, uri "/appname/v2/heartbeat"
10812016/11/15 19:11:58 [debug] 30743#30743: *162 http2 header filter
10822016/11/15 19:11:58 [debug] 30743#30743: *162 http2 output header: ":status: 400"
10832016/11/15 19:11:58 [debug] 30743#30743: *162 http2 output header: "server: nginx"
10842016/11/15 19:11:58 [debug] 30743#30743: *162 http2 output header: "date: Tue, 15 Nov 2016 19:11:58 GMT"
10852016/11/15 19:11:58 [debug] 30743#30743: *162 http2 output header: "content-type: text/html; charset=utf-8"
10862016/11/15 19:11:58 [debug] 30743#30743: *162 http2 output header: "content-length: 840"
10872016/11/15 19:11:58 [debug] 30743#30743: *162 http2 output header: "x-powered-by: Express"
10882016/11/15 19:11:58 [debug] 30743#30743: *162 http2 output header: "x-content-type-options: nosniff"
10892016/11/15 19:11:58 [debug] 30743#30743: *162 http2:435 create HEADERS frame 00000F0D34DBEDA8: len:98
10902016/11/15 19:11:58 [debug] 30743#30743: *162 http cleanup add: 00000F0D34DBEE90
10912016/11/15 19:11:58 [debug] 30743#30743: *162 http2 frame out: 00000F0D34DBEDA8 sid:435 bl:1 len:98
10922016/11/15 19:11:58 [debug] 30743#30743: *162 malloc: 00000F0D34D83A10:16384
10932016/11/15 19:11:58 [debug] 30743#30743: *162 SSL buf copy: 9
10942016/11/15 19:11:58 [debug] 30743#30743: *162 SSL buf copy: 98
10952016/11/15 19:11:58 [debug] 30743#30743: *162 http2:435 HEADERS frame 00000F0D34DBEDA8 was sent
10962016/11/15 19:11:58 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34DBEDA8 sid:435 bl:1 len:98
10972016/11/15 19:11:58 [debug] 30743#30743: *162 http cacheable: 0
10982016/11/15 19:11:58 [debug] 30743#30743: *162 http proxy filter init s:400 h:0 c:0 l:840
10992016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream process upstream
11002016/11/15 19:11:58 [debug] 30743#30743: *162 pipe read upstream: 1
11012016/11/15 19:11:58 [debug] 30743#30743: *162 pipe preread: 840
11022016/11/15 19:11:58 [debug] 30743#30743: *162 readv: 1, last:3040
11032016/11/15 19:11:58 [debug] 30743#30743: *162 pipe recv chain: 0
11042016/11/15 19:11:58 [debug] 30743#30743: *162 pipe buf free s:0 t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
11052016/11/15 19:11:58 [debug] 30743#30743: *162 pipe length: 840
11062016/11/15 19:11:58 [debug] 30743#30743: *162 input buf #0
11072016/11/15 19:11:58 [debug] 30743#30743: *162 pipe write downstream: 1
11082016/11/15 19:11:58 [debug] 30743#30743: *162 pipe write downstream flush in
11092016/11/15 19:11:58 [debug] 30743#30743: *162 http output filter "/appname/v2/heartbeat?"
11102016/11/15 19:11:58 [debug] 30743#30743: *162 http copy filter: "/appname/v2/heartbeat?"
11112016/11/15 19:11:58 [debug] 30743#30743: *162 lua capture body filter, uri "/appname/v2/heartbeat"
11122016/11/15 19:11:58 [debug] 30743#30743: *162 http postpone filter "/appname/v2/heartbeat?" 00000F0D34DBEA00
11132016/11/15 19:11:58 [debug] 30743#30743: *162 write new buf t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
11142016/11/15 19:11:58 [debug] 30743#30743: *162 http write filter: l:0 f:0 s:840
11152016/11/15 19:11:58 [debug] 30743#30743: *162 http copy filter: 0 "/appname/v2/heartbeat?"
11162016/11/15 19:11:58 [debug] 30743#30743: *162 pipe write downstream done
11172016/11/15 19:11:58 [debug] 30743#30743: *162 event timer: 7, old: 1479237178620, new: 1479237178649
11182016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream exit: 0000000000000000
11192016/11/15 19:11:58 [debug] 30743#30743: *162 finalize http upstream request: 0
11202016/11/15 19:11:58 [debug] 30743#30743: *162 finalize http proxy request
11212016/11/15 19:11:58 [debug] 30743#30743: *162 free rr peer 1 0
11222016/11/15 19:11:58 [debug] 30743#30743: *162 close http upstream connection: 7
11232016/11/15 19:11:58 [debug] 30743#30743: *162 free: 00000F0D34DBBA30, unused: 48
11242016/11/15 19:11:58 [debug] 30743#30743: *162 event timer del: 7: 1479237178620
11252016/11/15 19:11:58 [debug] 30743#30743: *162 reusable connection: 0
11262016/11/15 19:11:58 [debug] 30743#30743: *162 http upstream temp fd: -1
11272016/11/15 19:11:58 [debug] 30743#30743: *162 http output filter "/appname/v2/heartbeat?"
11282016/11/15 19:11:58 [debug] 30743#30743: *162 http copy filter: "/appname/v2/heartbeat?"
11292016/11/15 19:11:58 [debug] 30743#30743: *162 lua capture body filter, uri "/appname/v2/heartbeat"
11302016/11/15 19:11:58 [debug] 30743#30743: *162 http postpone filter "/appname/v2/heartbeat?" 00007A9E70E05A50
11312016/11/15 19:11:58 [debug] 30743#30743: *162 write old buf t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
11322016/11/15 19:11:58 [debug] 30743#30743: *162 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
11332016/11/15 19:11:58 [debug] 30743#30743: *162 http write filter: l:1 f:0 s:840
11342016/11/15 19:11:58 [debug] 30743#30743: *162 http write filter limit 0
11352016/11/15 19:11:58 [debug] 30743#30743: *162 http2:435 create DATA frame 00000F0D34DBEDA8: len:840 flags:1
11362016/11/15 19:11:58 [debug] 30743#30743: *162 http2 frame out: 00000F0D34DBEDA8 sid:435 bl:0 len:840
11372016/11/15 19:11:58 [debug] 30743#30743: *162 SSL buf copy: 9
11382016/11/15 19:11:58 [debug] 30743#30743: *162 SSL buf copy: 840
11392016/11/15 19:11:58 [debug] 30743#30743: *162 SSL to write: 956
11402016/11/15 19:11:58 [debug] 30743#30743: *162 SSL_write: 956
11412016/11/15 19:11:58 [debug] 30743#30743: *162 http2:435 DATA frame 00000F0D34DBEDA8 was sent
11422016/11/15 19:11:58 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34DBEDA8 sid:435 bl:0 len:840
11432016/11/15 19:11:58 [debug] 30743#30743: *162 http write filter 0000000000000000
11442016/11/15 19:11:58 [debug] 30743#30743: *162 http copy filter: 0 "/appname/v2/heartbeat?"
11452016/11/15 19:11:58 [debug] 30743#30743: *162 http finalize request: 0, "/appname/v2/heartbeat?" a:1, c:1
11462016/11/15 19:11:58 [debug] 30743#30743: *162 http request count:1 blk:0
11472016/11/15 19:11:58 [debug] 30743#30743: *162 http2 close stream 435, queued 0, processing 1
11482016/11/15 19:11:58 [debug] 30743#30743: *162 http close request
11492016/11/15 19:11:58 [debug] 30743#30743: *162 http log handler
11502016/11/15 19:11:58 [debug] 30743#30743: *162 free: 00000F0D34DBC950
11512016/11/15 19:11:58 [debug] 30743#30743: *162 free: 00000F0D34D69D00
11522016/11/15 19:11:58 [debug] 30743#30743: *162 free: 00000F0D34E120F0, unused: 0
11532016/11/15 19:11:58 [debug] 30743#30743: *162 free: 00000F0D34E63480, unused: 2
11542016/11/15 19:11:58 [debug] 30743#30743: *162 free: 00000F0D34EB6570, unused: 0
11552016/11/15 19:11:58 [debug] 30743#30743: *162 free: 00000F0D34EB7580, unused: 8
11562016/11/15 19:11:58 [debug] 30743#30743: *162 free: 00000F0D34DBD960, unused: 0
11572016/11/15 19:11:58 [debug] 30743#30743: *162 free: 00000F0D34DBE970, unused: 1926
11582016/11/15 19:11:58 [debug] 30743#30743: *162 free: 00000F0D34E61530, unused: 162
11592016/11/15 19:11:58 [debug] 30743#30743: *162 free: 00000F0D34E16F00, unused: 156
11602016/11/15 19:11:58 [debug] 30743#30743: *162 free: 00000F0D34DC4930, unused: 111
11612016/11/15 19:11:58 [debug] 30743#30743: *162 post event 00006EFC4C5081F0
11622016/11/15 19:11:58 [debug] 30743#30743: *162 delete posted event 00006EFC4C5081F0
11632016/11/15 19:11:58 [debug] 30743#30743: *162 http2 handle connection handler
11642016/11/15 19:11:58 [debug] 30743#30743: *162 free: 00000F0D34E03BC0, unused: 3488
11652016/11/15 19:11:58 [debug] 30743#30743: *162 free: 00000F0D34D83A10
11662016/11/15 19:11:58 [debug] 30743#30743: *162 reusable connection: 1
11672016/11/15 19:11:58 [debug] 30743#30743: *162 event timer add: 3: 180000:1479237298649
11682016/11/15 19:12:02 [debug] 30743#30743: *162 http2 idle handler
11692016/11/15 19:12:02 [debug] 30743#30743: *162 reusable connection: 0
11702016/11/15 19:12:02 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E120F0:4096 @16
11712016/11/15 19:12:02 [debug] 30743#30743: *162 http2 read handler
11722016/11/15 19:12:02 [debug] 30743#30743: *162 SSL_read: 55
11732016/11/15 19:12:02 [debug] 30743#30743: *162 SSL_read: -1
11742016/11/15 19:12:02 [debug] 30743#30743: *162 SSL_get_error: 2
11752016/11/15 19:12:02 [debug] 30743#30743: *162 process http2 frame type:1 f:24 l:46 sid:437
11762016/11/15 19:12:02 [debug] 30743#30743: *162 http2 HEADERS frame sid:437 on 0 excl:0 weight:183
11772016/11/15 19:12:02 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DC4930:1024 @16
11782016/11/15 19:12:02 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E03BC0:4096 @16
11792016/11/15 19:12:02 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E63480:4096 @16
11802016/11/15 19:12:02 [debug] 30743#30743: *162 http2 get indexed header name: 3
11812016/11/15 19:12:02 [debug] 30743#30743: *162 http2 get indexed header name: 70
11822016/11/15 19:12:02 [debug] 30743#30743: *162 http2 get indexed header name: 7
11832016/11/15 19:12:02 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
11842016/11/15 19:12:02 [debug] 30743#30743: *162 http2 hpack encoded string length: 13
11852016/11/15 19:12:02 [debug] 30743#30743: *162 http uri: "/appname/v2/heartbeat"
11862016/11/15 19:12:02 [debug] 30743#30743: *162 http args: ""
11872016/11/15 19:12:02 [debug] 30743#30743: *162 http exten: ""
11882016/11/15 19:12:02 [debug] 30743#30743: *162 http2 get indexed header: 28
11892016/11/15 19:12:02 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
11902016/11/15 19:12:02 [debug] 30743#30743: *162 http2 add header to hpack table: "content-length: 16805"
11912016/11/15 19:12:02 [debug] 30743#30743: *162 http2 hpack table account: 51 free:75
11922016/11/15 19:12:02 [debug] 30743#30743: *162 http2 http header: "content-length: 16805"
11932016/11/15 19:12:02 [debug] 30743#30743: *162 http2 get indexed header name: 69
11942016/11/15 19:12:02 [debug] 30743#30743: *162 http2 http header: "accept: application/json, text/plain, */*"
11952016/11/15 19:12:02 [debug] 30743#30743: *162 http2 get indexed header name: 81
11962016/11/15 19:12:02 [debug] 30743#30743: *162 http2 http header: "origin: https://app3.mydomain.local"
11972016/11/15 19:12:02 [debug] 30743#30743: *162 http2 get indexed header name: 97
11982016/11/15 19:12:02 [debug] 30743#30743: *162 http2 http header: "appname-csrf-token: YYY
11992016/11/15 19:12:02 [debug] 30743#30743: *162 http2 get indexed header name: 65
12002016/11/15 19:12:02 [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"
12012016/11/15 19:12:02 [debug] 30743#30743: *162 http2 get indexed header name: 80
12022016/11/15 19:12:02 [debug] 30743#30743: *162 http2 http header: "content-type: application/json;charset=UTF-8"
12032016/11/15 19:12:02 [debug] 30743#30743: *162 http2 get indexed header name: 78
12042016/11/15 19:12:02 [debug] 30743#30743: *162 http2 http header: "accept-encoding: gzip, deflate, br"
12052016/11/15 19:12:02 [debug] 30743#30743: *162 http2 get indexed header name: 94
12062016/11/15 19:12:02 [debug] 30743#30743: *162 http2 http header: "accept-language: en-US,en;q=0.8"
12072016/11/15 19:12:02 [debug] 30743#30743: *162 http2 get indexed header name: 93
12082016/11/15 19:12:02 [debug] 30743#30743: *162 http2 get indexed header name: 92
12092016/11/15 19:12:02 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E16F00:1024 @16
12102016/11/15 19:12:02 [debug] 30743#30743: *162 http2 get indexed header name: 91
12112016/11/15 19:12:02 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E61530:1024 @16
12122016/11/15 19:12:02 [debug] 30743#30743: *162 http2 get indexed header name: 90
12132016/11/15 19:12:02 [debug] 30743#30743: *162 http2 get indexed header name: 96
12142016/11/15 19:12:02 [debug] 30743#30743: *162 http2 http request line: "POST /appname/v2/heartbeat HTTP/2.0"
12152016/11/15 19:12:02 [debug] 30743#30743: *162 generic phase: 0
12162016/11/15 19:12:02 [debug] 30743#30743: *162 rewrite phase: 1
12172016/11/15 19:12:02 [debug] 30743#30743: *162 test location: "^/widgets/$"
12182016/11/15 19:12:02 [debug] 30743#30743: *162 test location: "/"
12192016/11/15 19:12:02 [debug] 30743#30743: *162 test location: "docs/"
12202016/11/15 19:12:02 [debug] 30743#30743: *162 test location: "appname/socket"
12212016/11/15 19:12:02 [debug] 30743#30743: *162 test location: ~ "^/(index.html)?$"
12222016/11/15 19:12:02 [debug] 30743#30743: *162 test location: ~ "^/login/?$"
12232016/11/15 19:12:02 [debug] 30743#30743: *162 test location: ~ "^/(appname)"
12242016/11/15 19:12:02 [debug] 30743#30743: *162 test location: ~ "/components/(.*)"
12252016/11/15 19:12:02 [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)$"
12262016/11/15 19:12:02 [debug] 30743#30743: *162 using configuration "/"
12272016/11/15 19:12:02 [debug] 30743#30743: *162 http cl:16805 max:1073741824
12282016/11/15 19:12:02 [debug] 30743#30743: *162 rewrite phase: 3
12292016/11/15 19:12:02 [debug] 30743#30743: *162 post rewrite phase: 4
12302016/11/15 19:12:02 [debug] 30743#30743: *162 generic phase: 5
12312016/11/15 19:12:02 [debug] 30743#30743: *162 generic phase: 6
12322016/11/15 19:12:02 [debug] 30743#30743: *162 generic phase: 7
12332016/11/15 19:12:02 [debug] 30743#30743: *162 access phase: 8
12342016/11/15 19:12:02 [debug] 30743#30743: *162 access phase: 9
12352016/11/15 19:12:02 [debug] 30743#30743: *162 access phase: 10
12362016/11/15 19:12:02 [debug] 30743#30743: *162 auth request handler
12372016/11/15 19:12:02 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB6570:4096 @16
12382016/11/15 19:12:02 [debug] 30743#30743: *162 http subrequest "/authorize?"
12392016/11/15 19:12:02 [debug] 30743#30743: *162 http posted request: "/authorize?"
12402016/11/15 19:12:02 [debug] 30743#30743: *162 rewrite phase: 1
12412016/11/15 19:12:02 [debug] 30743#30743: *162 test location: "^/widgets/$"
12422016/11/15 19:12:02 [debug] 30743#30743: *162 test location: "/"
12432016/11/15 19:12:02 [debug] 30743#30743: *162 test location: "docs/"
12442016/11/15 19:12:02 [debug] 30743#30743: *162 test location: "dashboard"
12452016/11/15 19:12:02 [debug] 30743#30743: *162 test location: "authorize"
12462016/11/15 19:12:02 [debug] 30743#30743: *162 using configuration "=/authorize"
12472016/11/15 19:12:02 [debug] 30743#30743: *162 http cl:16805 max:1073741824
12482016/11/15 19:12:02 [debug] 30743#30743: *162 rewrite phase: 3
12492016/11/15 19:12:02 [debug] 30743#30743: *162 post rewrite phase: 4
12502016/11/15 19:12:02 [debug] 30743#30743: *162 generic phase: 5
12512016/11/15 19:12:02 [debug] 30743#30743: *162 generic phase: 6
12522016/11/15 19:12:02 [debug] 30743#30743: *162 generic phase: 7
12532016/11/15 19:12:02 [debug] 30743#30743: *162 http init upstream, client timer: 0
12542016/11/15 19:12:02 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB7580:4096 @16
12552016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
12562016/11/15 19:12:02 [debug] 30743#30743: *162 http script var: "192.168.123.11"
12572016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "
1258"
12592016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
12602016/11/15 19:12:02 [debug] 30743#30743: *162 http script var: "192.168.123.11"
12612016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "
1262"
12632016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "Host: "
12642016/11/15 19:12:02 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
12652016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "
1266"
12672016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
1268"
12692016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
12702016/11/15 19:12:02 [debug] 30743#30743: *162 http script var: "XXX"
12712016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "
1272"
12732016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
12742016/11/15 19:12:02 [debug] 30743#30743: *162 http script var: "XXXX"
12752016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "
1276"
12772016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "x-appname-uri: "
12782016/11/15 19:12:02 [debug] 30743#30743: *162 http script var: "/appname/v2/heartbeat"
12792016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "
1280"
12812016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "x-appname-method: "
12822016/11/15 19:12:02 [debug] 30743#30743: *162 http script var: "POST"
12832016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "
1284"
12852016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "Connection: close
1286"
12872016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: ""
12882016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: ""
12892016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
12902016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
12912016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
12922016/11/15 19:12:02 [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"
12932016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
12942016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
12952016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
12962016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
12972016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header:
1298"GET /authorize HTTP/1.1
1299X-Real-IP: 192.168.123.11
1300X-Forwarded-For: 192.168.123.11
1301Host: app3.mydomain.local
1302X-NginX-Proxy: true
1303
1304
1305x-appname-uri: /appname/v2/heartbeat
1306x-appname-method: POST
1307Connection: close
1308accept: application/json, text/plain, */*
1309origin: https://app3.mydomain.local
1310appname-csrf-token: YYY
1311user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
1312content-type: application/json;charset=UTF-8
1313accept-encoding: gzip, deflate, br
1314accept-language: en-US,en;q=0.8
13152016/11/15 19:12:02 [debug] 30743#30743: *162 http cleanup add: 00000F0D34E64460
13162016/11/15 19:12:02 [debug] 30743#30743: *162 get rr peer, try: 1
13172016/11/15 19:12:02 [debug] 30743#30743: *162 stream socket 7
13182016/11/15 19:12:02 [debug] 30743#30743: *162 epoll add connection: fd:7 ev:80002005
13192016/11/15 19:12:02 [debug] 30743#30743: *162 connect to 127.0.0.1:8008, fd:7 #579
13202016/11/15 19:12:02 [debug] 30743#30743: *162 http upstream connect: -2
13212016/11/15 19:12:02 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBBA30:128 @16
13222016/11/15 19:12:02 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237182563
13232016/11/15 19:12:02 [debug] 30743#30743: *162 http finalize request: -4, "/authorize?" a:1, c:3
13242016/11/15 19:12:02 [debug] 30743#30743: *162 http request count:3 blk:0
13252016/11/15 19:12:02 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AC047 end:00006EFC4F5AC047
13262016/11/15 19:12:02 [debug] 30743#30743: *162 event timer del: 3: 1479237298649
13272016/11/15 19:12:02 [debug] 30743#30743: *162 http2 write handler
13282016/11/15 19:12:02 [debug] 30743#30743: *162 http2 read handler
13292016/11/15 19:12:02 [debug] 30743#30743: *162 SSL_read: 2861
13302016/11/15 19:12:02 [debug] 30743#30743: *162 SSL_read: 2861
13312016/11/15 19:12:02 [debug] 30743#30743: *162 SSL_read: 2861
13322016/11/15 19:12:02 [debug] 30743#30743: *162 SSL_read: 2861
13332016/11/15 19:12:02 [debug] 30743#30743: *162 SSL_read: 2861
13342016/11/15 19:12:02 [debug] 30743#30743: *162 SSL_read: 2554
13352016/11/15 19:12:02 [debug] 30743#30743: *162 SSL_read: -1
13362016/11/15 19:12:02 [debug] 30743#30743: *162 SSL_get_error: 2
13372016/11/15 19:12:02 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:437
13382016/11/15 19:12:02 [debug] 30743#30743: *162 http2 DATA frame
13392016/11/15 19:12:02 [debug] 30743#30743: *162 malloc: 00000F0D34D89720:65536
13402016/11/15 19:12:02 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5ACB3D end:00006EFC4F5B01EB
13412016/11/15 19:12:02 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:437
13422016/11/15 19:12:02 [debug] 30743#30743: *162 http2 DATA frame
13432016/11/15 19:12:02 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AD66A end:00006EFC4F5B01EB
13442016/11/15 19:12:02 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:437
13452016/11/15 19:12:02 [debug] 30743#30743: *162 http2 DATA frame
13462016/11/15 19:12:02 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AE197 end:00006EFC4F5B01EB
13472016/11/15 19:12:02 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:437
13482016/11/15 19:12:02 [debug] 30743#30743: *162 http2 DATA frame
13492016/11/15 19:12:02 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AECC4 end:00006EFC4F5B01EB
13502016/11/15 19:12:02 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:437
13512016/11/15 19:12:02 [debug] 30743#30743: *162 http2 DATA frame
13522016/11/15 19:12:02 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AF7F1 end:00006EFC4F5B01EB
13532016/11/15 19:12:02 [debug] 30743#30743: *162 process http2 frame type:0 f:1 l:2545 sid:437
13542016/11/15 19:12:02 [debug] 30743#30743: *162 http2 DATA frame
13552016/11/15 19:12:02 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5B01EB end:00006EFC4F5B01EB
13562016/11/15 19:12:02 [debug] 30743#30743: *162 http2 write handler
13572016/11/15 19:12:02 [debug] 30743#30743: *162 http upstream request: "/authorize?"
13582016/11/15 19:12:02 [debug] 30743#30743: *162 http upstream send request handler
13592016/11/15 19:12:02 [debug] 30743#30743: *162 http upstream send request
13602016/11/15 19:12:02 [debug] 30743#30743: *162 http upstream send request body
13612016/11/15 19:12:02 [debug] 30743#30743: *162 chain writer buf fl:1 s:2711
13622016/11/15 19:12:02 [debug] 30743#30743: *162 chain writer in: 00000F0D34EB7550
13632016/11/15 19:12:02 [debug] 30743#30743: *162 writev: 2711 of 2711
13642016/11/15 19:12:02 [debug] 30743#30743: *162 chain writer out: 0000000000000000
13652016/11/15 19:12:02 [debug] 30743#30743: *162 event timer del: 7: 1479237182563
13662016/11/15 19:12:02 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237182564
13672016/11/15 19:12:02 [debug] 30743#30743: *162 http upstream request: "/authorize?"
13682016/11/15 19:12:02 [debug] 30743#30743: *162 http upstream process header
13692016/11/15 19:12:02 [debug] 30743#30743: *162 malloc: 00000F0D34DBC950:4096
13702016/11/15 19:12:02 [debug] 30743#30743: *162 recv: fd:7 172 of 4096
13712016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy status 200 "200 OK"
13722016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "X-Powered-By: Express"
13732016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "x-appname-origin: appname3"
13742016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "content-type: application/json"
13752016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "content-length: 0"
13762016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "Date: Tue, 15 Nov 2016 19:12:02 GMT"
13772016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "Connection: close"
13782016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header done
13792016/11/15 19:12:02 [debug] 30743#30743: *162 lua capture header filter, uri "/authorize"
13802016/11/15 19:12:02 [debug] 30743#30743: *162 http2 header filter
13812016/11/15 19:12:02 [debug] 30743#30743: *162 finalize http upstream request: 0
13822016/11/15 19:12:02 [debug] 30743#30743: *162 finalize http proxy request
13832016/11/15 19:12:02 [debug] 30743#30743: *162 free rr peer 1 0
13842016/11/15 19:12:02 [debug] 30743#30743: *162 close http upstream connection: 7
13852016/11/15 19:12:02 [debug] 30743#30743: *162 free: 00000F0D34DBBA30, unused: 48
13862016/11/15 19:12:02 [debug] 30743#30743: *162 event timer del: 7: 1479237182564
13872016/11/15 19:12:02 [debug] 30743#30743: *162 reusable connection: 0
13882016/11/15 19:12:02 [debug] 30743#30743: *162 http finalize request: 0, "/authorize?" a:1, c:2
13892016/11/15 19:12:02 [debug] 30743#30743: *162 auth request done s:200
13902016/11/15 19:12:02 [debug] 30743#30743: *162 http wake parent request: "/appname/v2/heartbeat?"
13912016/11/15 19:12:02 [debug] 30743#30743: *162 http posted request: "/appname/v2/heartbeat?"
13922016/11/15 19:12:02 [debug] 30743#30743: *162 access phase: 10
13932016/11/15 19:12:02 [debug] 30743#30743: *162 auth request handler
13942016/11/15 19:12:02 [debug] 30743#30743: *162 auth request set variables
13952016/11/15 19:12:02 [debug] 30743#30743: *162 access phase: 11
13962016/11/15 19:12:02 [debug] 30743#30743: *162 lua access handler, uri:"/appname/v2/heartbeat" c:1
13972016/11/15 19:12:02 [debug] 30743#30743: *162 post access phase: 12
13982016/11/15 19:12:02 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBD960:4096 @16
13992016/11/15 19:12:02 [debug] 30743#30743: *162 http body new buf t:1 f:0 00000F0D34D89720, pos 00000F0D34D89720, size: 16805 file: 0, size: 0
14002016/11/15 19:12:02 [debug] 30743#30743: *162 http init upstream, client timer: 0
14012016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
14022016/11/15 19:12:02 [debug] 30743#30743: *162 http script var: "192.168.123.11"
14032016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "
1404"
14052016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
14062016/11/15 19:12:02 [debug] 30743#30743: *162 http script var: "192.168.123.11"
14072016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "
1408"
14092016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "Host: "
14102016/11/15 19:12:02 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
14112016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "
1412"
14132016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
1414"
14152016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
14162016/11/15 19:12:02 [debug] 30743#30743: *162 http script var: "XXX"
14172016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "
1418"
14192016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
14202016/11/15 19:12:02 [debug] 30743#30743: *162 http script var: "XXXX"
14212016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "
1422"
14232016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "Connection: close
1424"
14252016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "Content-Length: "
14262016/11/15 19:12:02 [debug] 30743#30743: *162 http script var: "16805"
14272016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: "
1428"
14292016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: ""
14302016/11/15 19:12:02 [debug] 30743#30743: *162 http script copy: ""
14312016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
14322016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
14332016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
14342016/11/15 19:12:02 [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"
14352016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
14362016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
14372016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
14382016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
14392016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header:
1440"POST /appname/v2/heartbeat HTTP/1.1
1441X-Real-IP: 192.168.123.11
1442X-Forwarded-For: 192.168.123.11
1443Host: app3.mydomain.local
1444X-NginX-Proxy: true
1445
1446
1447Connection: close
1448Content-Length: 16805
1449accept: application/json, text/plain, */*
1450origin: https://app3.mydomain.local
1451appname-csrf-token: YYY
1452user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
1453content-type: application/json;charset=UTF-8
1454accept-encoding: gzip, deflate, br
1455accept-language: en-US,en;q=0.8
14562016/11/15 19:12:02 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBE970:4096 @16
14572016/11/15 19:12:02 [debug] 30743#30743: *162 http cleanup add: 00000F0D34DBE938
14582016/11/15 19:12:02 [debug] 30743#30743: *162 get rr peer, try: 1
14592016/11/15 19:12:02 [debug] 30743#30743: *162 stream socket 7
14602016/11/15 19:12:02 [debug] 30743#30743: *162 epoll add connection: fd:7 ev:80002005
14612016/11/15 19:12:02 [debug] 30743#30743: *162 connect to 182.168.128.69:8080, fd:7 #580
14622016/11/15 19:12:02 [debug] 30743#30743: *162 http upstream connect: -2
14632016/11/15 19:12:02 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBBA30:128 @16
14642016/11/15 19:12:02 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237182594
14652016/11/15 19:12:02 [debug] 30743#30743: *162 free: 00000F0D34D89720
14662016/11/15 19:12:02 [debug] 30743#30743: *162 http finalize request: -4, "/appname/v2/heartbeat?" a:1, c:2
14672016/11/15 19:12:02 [debug] 30743#30743: *162 http request count:2 blk:0
14682016/11/15 19:12:02 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
14692016/11/15 19:12:02 [debug] 30743#30743: *162 http upstream send request handler
14702016/11/15 19:12:02 [debug] 30743#30743: *162 http upstream send request
14712016/11/15 19:12:02 [debug] 30743#30743: *162 http upstream send request body
14722016/11/15 19:12:02 [debug] 30743#30743: *162 chain writer buf fl:0 s:2690
14732016/11/15 19:12:02 [debug] 30743#30743: *162 chain writer buf fl:1 s:16805
14742016/11/15 19:12:02 [debug] 30743#30743: *162 chain writer in: 00000F0D34DBEA00
14752016/11/15 19:12:02 [debug] 30743#30743: *162 writev: 19495 of 19495
14762016/11/15 19:12:02 [debug] 30743#30743: *162 chain writer out: 0000000000000000
14772016/11/15 19:12:02 [debug] 30743#30743: *162 event timer del: 7: 1479237182594
14782016/11/15 19:12:02 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237182594
14792016/11/15 19:12:02 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
14802016/11/15 19:12:02 [debug] 30743#30743: *162 http upstream process header
14812016/11/15 19:12:02 [debug] 30743#30743: *162 malloc: 00000F0D34D69D00:4096
14822016/11/15 19:12:02 [debug] 30743#30743: *162 recv: fd:7 1056 of 4096
14832016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy status 400 "400 Bad Request"
14842016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "Server: nginx"
14852016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "Date: Tue, 15 Nov 2016 19:12:02 GMT"
14862016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "Content-Type: text/html; charset=utf-8"
14872016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "Content-Length: 840"
14882016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "Connection: close"
14892016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "X-Powered-By: Express"
14902016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header: "X-Content-Type-Options: nosniff"
14912016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy header done
14922016/11/15 19:12:02 [debug] 30743#30743: *162 lua capture header filter, uri "/appname/v2/heartbeat"
14932016/11/15 19:12:02 [debug] 30743#30743: *162 http2 header filter
14942016/11/15 19:12:02 [debug] 30743#30743: *162 http2 output header: ":status: 400"
14952016/11/15 19:12:02 [debug] 30743#30743: *162 http2 output header: "server: nginx"
14962016/11/15 19:12:02 [debug] 30743#30743: *162 http2 output header: "date: Tue, 15 Nov 2016 19:12:02 GMT"
14972016/11/15 19:12:02 [debug] 30743#30743: *162 http2 output header: "content-type: text/html; charset=utf-8"
14982016/11/15 19:12:02 [debug] 30743#30743: *162 http2 output header: "content-length: 840"
14992016/11/15 19:12:02 [debug] 30743#30743: *162 http2 output header: "x-powered-by: Express"
15002016/11/15 19:12:02 [debug] 30743#30743: *162 http2 output header: "x-content-type-options: nosniff"
15012016/11/15 19:12:02 [debug] 30743#30743: *162 http2:437 create HEADERS frame 00000F0D34DBEDA8: len:98
15022016/11/15 19:12:02 [debug] 30743#30743: *162 http cleanup add: 00000F0D34DBEE90
15032016/11/15 19:12:02 [debug] 30743#30743: *162 http2 frame out: 00000F0D34DBEDA8 sid:437 bl:1 len:98
15042016/11/15 19:12:02 [debug] 30743#30743: *162 malloc: 00000F0D34D83A10:16384
15052016/11/15 19:12:02 [debug] 30743#30743: *162 SSL buf copy: 9
15062016/11/15 19:12:02 [debug] 30743#30743: *162 SSL buf copy: 98
15072016/11/15 19:12:02 [debug] 30743#30743: *162 http2:437 HEADERS frame 00000F0D34DBEDA8 was sent
15082016/11/15 19:12:02 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34DBEDA8 sid:437 bl:1 len:98
15092016/11/15 19:12:02 [debug] 30743#30743: *162 http cacheable: 0
15102016/11/15 19:12:02 [debug] 30743#30743: *162 http proxy filter init s:400 h:0 c:0 l:840
15112016/11/15 19:12:02 [debug] 30743#30743: *162 http upstream process upstream
15122016/11/15 19:12:02 [debug] 30743#30743: *162 pipe read upstream: 1
15132016/11/15 19:12:02 [debug] 30743#30743: *162 pipe preread: 840
15142016/11/15 19:12:02 [debug] 30743#30743: *162 readv: 1, last:3040
15152016/11/15 19:12:02 [debug] 30743#30743: *162 pipe recv chain: 0
15162016/11/15 19:12:02 [debug] 30743#30743: *162 pipe buf free s:0 t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
15172016/11/15 19:12:02 [debug] 30743#30743: *162 pipe length: 840
15182016/11/15 19:12:02 [debug] 30743#30743: *162 input buf #0
15192016/11/15 19:12:02 [debug] 30743#30743: *162 pipe write downstream: 1
15202016/11/15 19:12:02 [debug] 30743#30743: *162 pipe write downstream flush in
15212016/11/15 19:12:02 [debug] 30743#30743: *162 http output filter "/appname/v2/heartbeat?"
15222016/11/15 19:12:02 [debug] 30743#30743: *162 http copy filter: "/appname/v2/heartbeat?"
15232016/11/15 19:12:02 [debug] 30743#30743: *162 lua capture body filter, uri "/appname/v2/heartbeat"
15242016/11/15 19:12:02 [debug] 30743#30743: *162 http postpone filter "/appname/v2/heartbeat?" 00000F0D34DBEA00
15252016/11/15 19:12:02 [debug] 30743#30743: *162 write new buf t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
15262016/11/15 19:12:02 [debug] 30743#30743: *162 http write filter: l:0 f:0 s:840
15272016/11/15 19:12:02 [debug] 30743#30743: *162 http copy filter: 0 "/appname/v2/heartbeat?"
15282016/11/15 19:12:02 [debug] 30743#30743: *162 pipe write downstream done
15292016/11/15 19:12:02 [debug] 30743#30743: *162 event timer: 7, old: 1479237182594, new: 1479237182602
15302016/11/15 19:12:02 [debug] 30743#30743: *162 http upstream exit: 0000000000000000
15312016/11/15 19:12:02 [debug] 30743#30743: *162 finalize http upstream request: 0
15322016/11/15 19:12:02 [debug] 30743#30743: *162 finalize http proxy request
15332016/11/15 19:12:02 [debug] 30743#30743: *162 free rr peer 1 0
15342016/11/15 19:12:02 [debug] 30743#30743: *162 close http upstream connection: 7
15352016/11/15 19:12:02 [debug] 30743#30743: *162 free: 00000F0D34DBBA30, unused: 48
15362016/11/15 19:12:02 [debug] 30743#30743: *162 event timer del: 7: 1479237182594
15372016/11/15 19:12:02 [debug] 30743#30743: *162 reusable connection: 0
15382016/11/15 19:12:02 [debug] 30743#30743: *162 http upstream temp fd: -1
15392016/11/15 19:12:02 [debug] 30743#30743: *162 http output filter "/appname/v2/heartbeat?"
15402016/11/15 19:12:02 [debug] 30743#30743: *162 http copy filter: "/appname/v2/heartbeat?"
15412016/11/15 19:12:02 [debug] 30743#30743: *162 lua capture body filter, uri "/appname/v2/heartbeat"
15422016/11/15 19:12:02 [debug] 30743#30743: *162 http postpone filter "/appname/v2/heartbeat?" 00007A9E70E05A50
15432016/11/15 19:12:02 [debug] 30743#30743: *162 write old buf t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
15442016/11/15 19:12:02 [debug] 30743#30743: *162 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
15452016/11/15 19:12:02 [debug] 30743#30743: *162 http write filter: l:1 f:0 s:840
15462016/11/15 19:12:02 [debug] 30743#30743: *162 http write filter limit 0
15472016/11/15 19:12:02 [debug] 30743#30743: *162 http2:437 create DATA frame 00000F0D34DBEDA8: len:840 flags:1
15482016/11/15 19:12:02 [debug] 30743#30743: *162 http2 frame out: 00000F0D34DBEDA8 sid:437 bl:0 len:840
15492016/11/15 19:12:02 [debug] 30743#30743: *162 SSL buf copy: 9
15502016/11/15 19:12:02 [debug] 30743#30743: *162 SSL buf copy: 840
15512016/11/15 19:12:02 [debug] 30743#30743: *162 SSL to write: 956
15522016/11/15 19:12:02 [debug] 30743#30743: *162 SSL_write: 956
15532016/11/15 19:12:02 [debug] 30743#30743: *162 http2:437 DATA frame 00000F0D34DBEDA8 was sent
15542016/11/15 19:12:02 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34DBEDA8 sid:437 bl:0 len:840
15552016/11/15 19:12:02 [debug] 30743#30743: *162 http write filter 0000000000000000
15562016/11/15 19:12:02 [debug] 30743#30743: *162 http copy filter: 0 "/appname/v2/heartbeat?"
15572016/11/15 19:12:02 [debug] 30743#30743: *162 http finalize request: 0, "/appname/v2/heartbeat?" a:1, c:1
15582016/11/15 19:12:02 [debug] 30743#30743: *162 http request count:1 blk:0
15592016/11/15 19:12:02 [debug] 30743#30743: *162 http2 close stream 437, queued 0, processing 1
15602016/11/15 19:12:02 [debug] 30743#30743: *162 http close request
15612016/11/15 19:12:02 [debug] 30743#30743: *162 http log handler
15622016/11/15 19:12:02 [debug] 30743#30743: *162 free: 00000F0D34DBC950
15632016/11/15 19:12:02 [debug] 30743#30743: *162 free: 00000F0D34D69D00
15642016/11/15 19:12:02 [debug] 30743#30743: *162 free: 00000F0D34E03BC0, unused: 0
15652016/11/15 19:12:02 [debug] 30743#30743: *162 free: 00000F0D34E63480, unused: 2
15662016/11/15 19:12:02 [debug] 30743#30743: *162 free: 00000F0D34EB6570, unused: 0
15672016/11/15 19:12:02 [debug] 30743#30743: *162 free: 00000F0D34EB7580, unused: 8
15682016/11/15 19:12:02 [debug] 30743#30743: *162 free: 00000F0D34DBD960, unused: 0
15692016/11/15 19:12:02 [debug] 30743#30743: *162 free: 00000F0D34DBE970, unused: 1926
15702016/11/15 19:12:02 [debug] 30743#30743: *162 free: 00000F0D34DC4930, unused: 162
15712016/11/15 19:12:02 [debug] 30743#30743: *162 free: 00000F0D34E16F00, unused: 156
15722016/11/15 19:12:02 [debug] 30743#30743: *162 free: 00000F0D34E61530, unused: 111
15732016/11/15 19:12:02 [debug] 30743#30743: *162 post event 00006EFC4C5081F0
15742016/11/15 19:12:02 [debug] 30743#30743: *162 delete posted event 00006EFC4C5081F0
15752016/11/15 19:12:02 [debug] 30743#30743: *162 http2 handle connection handler
15762016/11/15 19:12:02 [debug] 30743#30743: *162 free: 00000F0D34E120F0, unused: 3488
15772016/11/15 19:12:02 [debug] 30743#30743: *162 free: 00000F0D34D83A10
15782016/11/15 19:12:02 [debug] 30743#30743: *162 reusable connection: 1
15792016/11/15 19:12:02 [debug] 30743#30743: *162 event timer add: 3: 180000:1479237302602
15802016/11/15 19:12:08 [debug] 30743#30743: *162 http2 idle handler
15812016/11/15 19:12:08 [debug] 30743#30743: *162 reusable connection: 0
15822016/11/15 19:12:08 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E03BC0:4096 @16
15832016/11/15 19:12:08 [debug] 30743#30743: *162 http2 read handler
15842016/11/15 19:12:08 [debug] 30743#30743: *162 SSL_read: 55
15852016/11/15 19:12:08 [debug] 30743#30743: *162 SSL_read: 2861
15862016/11/15 19:12:08 [debug] 30743#30743: *162 SSL_read: 2861
15872016/11/15 19:12:08 [debug] 30743#30743: *162 SSL_read: 2861
15882016/11/15 19:12:08 [debug] 30743#30743: *162 SSL_read: 2861
15892016/11/15 19:12:08 [debug] 30743#30743: *162 SSL_read: -1
15902016/11/15 19:12:08 [debug] 30743#30743: *162 SSL_get_error: 2
15912016/11/15 19:12:08 [debug] 30743#30743: *162 process http2 frame type:1 f:24 l:46 sid:439
15922016/11/15 19:12:08 [debug] 30743#30743: *162 http2 HEADERS frame sid:439 on 0 excl:0 weight:183
15932016/11/15 19:12:08 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E61530:1024 @16
15942016/11/15 19:12:08 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E120F0:4096 @16
15952016/11/15 19:12:08 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E63480:4096 @16
15962016/11/15 19:12:08 [debug] 30743#30743: *162 http2 get indexed header name: 3
15972016/11/15 19:12:08 [debug] 30743#30743: *162 http2 get indexed header name: 71
15982016/11/15 19:12:08 [debug] 30743#30743: *162 http2 get indexed header name: 7
15992016/11/15 19:12:08 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
16002016/11/15 19:12:08 [debug] 30743#30743: *162 http2 hpack encoded string length: 13
16012016/11/15 19:12:08 [debug] 30743#30743: *162 http uri: "/appname/v2/heartbeat"
16022016/11/15 19:12:08 [debug] 30743#30743: *162 http args: ""
16032016/11/15 19:12:08 [debug] 30743#30743: *162 http exten: ""
16042016/11/15 19:12:08 [debug] 30743#30743: *162 http2 get indexed header: 28
16052016/11/15 19:12:08 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
16062016/11/15 19:12:08 [debug] 30743#30743: *162 http2 add header to hpack table: "content-length: 26988"
16072016/11/15 19:12:08 [debug] 30743#30743: *162 http2 hpack table account: 51 free:24
16082016/11/15 19:12:08 [debug] 30743#30743: *162 http2 http header: "content-length: 26988"
16092016/11/15 19:12:08 [debug] 30743#30743: *162 http2 get indexed header name: 70
16102016/11/15 19:12:08 [debug] 30743#30743: *162 http2 http header: "accept: application/json, text/plain, */*"
16112016/11/15 19:12:08 [debug] 30743#30743: *162 http2 get indexed header name: 82
16122016/11/15 19:12:08 [debug] 30743#30743: *162 http2 http header: "origin: https://app3.mydomain.local"
16132016/11/15 19:12:08 [debug] 30743#30743: *162 http2 get indexed header name: 98
16142016/11/15 19:12:08 [debug] 30743#30743: *162 http2 http header: "appname-csrf-token: YYY
16152016/11/15 19:12:08 [debug] 30743#30743: *162 http2 get indexed header name: 66
16162016/11/15 19:12:08 [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"
16172016/11/15 19:12:08 [debug] 30743#30743: *162 http2 get indexed header name: 81
16182016/11/15 19:12:08 [debug] 30743#30743: *162 http2 http header: "content-type: application/json;charset=UTF-8"
16192016/11/15 19:12:08 [debug] 30743#30743: *162 http2 get indexed header name: 79
16202016/11/15 19:12:08 [debug] 30743#30743: *162 http2 http header: "accept-encoding: gzip, deflate, br"
16212016/11/15 19:12:08 [debug] 30743#30743: *162 http2 get indexed header name: 95
16222016/11/15 19:12:08 [debug] 30743#30743: *162 http2 http header: "accept-language: en-US,en;q=0.8"
16232016/11/15 19:12:08 [debug] 30743#30743: *162 http2 get indexed header name: 94
16242016/11/15 19:12:08 [debug] 30743#30743: *162 http2 get indexed header name: 93
16252016/11/15 19:12:08 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E16F00:1024 @16
16262016/11/15 19:12:08 [debug] 30743#30743: *162 http2 get indexed header name: 92
16272016/11/15 19:12:08 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DC4930:1024 @16
16282016/11/15 19:12:08 [debug] 30743#30743: *162 http2 get indexed header name: 91
16292016/11/15 19:12:08 [debug] 30743#30743: *162 http2 get indexed header name: 97
16302016/11/15 19:12:08 [debug] 30743#30743: *162 http2 http request line: "POST /appname/v2/heartbeat HTTP/2.0"
16312016/11/15 19:12:08 [debug] 30743#30743: *162 generic phase: 0
16322016/11/15 19:12:08 [debug] 30743#30743: *162 rewrite phase: 1
16332016/11/15 19:12:08 [debug] 30743#30743: *162 test location: "^/widgets/$"
16342016/11/15 19:12:08 [debug] 30743#30743: *162 test location: "/"
16352016/11/15 19:12:08 [debug] 30743#30743: *162 test location: "docs/"
16362016/11/15 19:12:08 [debug] 30743#30743: *162 test location: "appname/socket"
16372016/11/15 19:12:08 [debug] 30743#30743: *162 test location: ~ "^/(index.html)?$"
16382016/11/15 19:12:08 [debug] 30743#30743: *162 test location: ~ "^/login/?$"
16392016/11/15 19:12:08 [debug] 30743#30743: *162 test location: ~ "^/(appname)"
16402016/11/15 19:12:08 [debug] 30743#30743: *162 test location: ~ "/components/(.*)"
16412016/11/15 19:12:08 [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)$"
16422016/11/15 19:12:08 [debug] 30743#30743: *162 using configuration "/"
16432016/11/15 19:12:08 [debug] 30743#30743: *162 http cl:26988 max:1073741824
16442016/11/15 19:12:08 [debug] 30743#30743: *162 rewrite phase: 3
16452016/11/15 19:12:08 [debug] 30743#30743: *162 post rewrite phase: 4
16462016/11/15 19:12:08 [debug] 30743#30743: *162 generic phase: 5
16472016/11/15 19:12:08 [debug] 30743#30743: *162 generic phase: 6
16482016/11/15 19:12:08 [debug] 30743#30743: *162 generic phase: 7
16492016/11/15 19:12:08 [debug] 30743#30743: *162 access phase: 8
16502016/11/15 19:12:08 [debug] 30743#30743: *162 access phase: 9
16512016/11/15 19:12:08 [debug] 30743#30743: *162 access phase: 10
16522016/11/15 19:12:08 [debug] 30743#30743: *162 auth request handler
16532016/11/15 19:12:08 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB6570:4096 @16
16542016/11/15 19:12:08 [debug] 30743#30743: *162 http subrequest "/authorize?"
16552016/11/15 19:12:08 [debug] 30743#30743: *162 http posted request: "/authorize?"
16562016/11/15 19:12:08 [debug] 30743#30743: *162 rewrite phase: 1
16572016/11/15 19:12:08 [debug] 30743#30743: *162 test location: "^/widgets/$"
16582016/11/15 19:12:08 [debug] 30743#30743: *162 test location: "/"
16592016/11/15 19:12:08 [debug] 30743#30743: *162 test location: "docs/"
16602016/11/15 19:12:08 [debug] 30743#30743: *162 test location: "dashboard"
16612016/11/15 19:12:08 [debug] 30743#30743: *162 test location: "authorize"
16622016/11/15 19:12:08 [debug] 30743#30743: *162 using configuration "=/authorize"
16632016/11/15 19:12:08 [debug] 30743#30743: *162 http cl:26988 max:1073741824
16642016/11/15 19:12:08 [debug] 30743#30743: *162 rewrite phase: 3
16652016/11/15 19:12:08 [debug] 30743#30743: *162 post rewrite phase: 4
16662016/11/15 19:12:08 [debug] 30743#30743: *162 generic phase: 5
16672016/11/15 19:12:08 [debug] 30743#30743: *162 generic phase: 6
16682016/11/15 19:12:08 [debug] 30743#30743: *162 generic phase: 7
16692016/11/15 19:12:08 [debug] 30743#30743: *162 http init upstream, client timer: 0
16702016/11/15 19:12:08 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB7580:4096 @16
16712016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
16722016/11/15 19:12:08 [debug] 30743#30743: *162 http script var: "192.168.123.11"
16732016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "
1674"
16752016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
16762016/11/15 19:12:08 [debug] 30743#30743: *162 http script var: "192.168.123.11"
16772016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "
1678"
16792016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "Host: "
16802016/11/15 19:12:08 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
16812016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "
1682"
16832016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
1684"
16852016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
16862016/11/15 19:12:08 [debug] 30743#30743: *162 http script var: "XXX"
16872016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "
1688"
16892016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
16902016/11/15 19:12:08 [debug] 30743#30743: *162 http script var: "XXXX"
16912016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "
1692"
16932016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "x-appname-uri: "
16942016/11/15 19:12:08 [debug] 30743#30743: *162 http script var: "/appname/v2/heartbeat"
16952016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "
1696"
16972016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "x-appname-method: "
16982016/11/15 19:12:08 [debug] 30743#30743: *162 http script var: "POST"
16992016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "
1700"
17012016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "Connection: close
1702"
17032016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: ""
17042016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: ""
17052016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
17062016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
17072016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
17082016/11/15 19:12:08 [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"
17092016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
17102016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
17112016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
17122016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
17132016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header:
1714"GET /authorize HTTP/1.1
1715X-Real-IP: 192.168.123.11
1716X-Forwarded-For: 192.168.123.11
1717Host: app3.mydomain.local
1718X-NginX-Proxy: true
1719
1720
1721x-appname-uri: /appname/v2/heartbeat
1722x-appname-method: POST
1723Connection: close
1724accept: application/json, text/plain, */*
1725origin: https://app3.mydomain.local
1726appname-csrf-token: YYY
1727user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
1728content-type: application/json;charset=UTF-8
1729accept-encoding: gzip, deflate, br
1730accept-language: en-US,en;q=0.8
17312016/11/15 19:12:08 [debug] 30743#30743: *162 http cleanup add: 00000F0D34E64460
17322016/11/15 19:12:08 [debug] 30743#30743: *162 get rr peer, try: 1
17332016/11/15 19:12:08 [debug] 30743#30743: *162 stream socket 7
17342016/11/15 19:12:08 [debug] 30743#30743: *162 epoll add connection: fd:7 ev:80002005
17352016/11/15 19:12:08 [debug] 30743#30743: *162 connect to 127.0.0.1:8008, fd:7 #581
17362016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream connect: -2
17372016/11/15 19:12:08 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBBA30:128 @16
17382016/11/15 19:12:08 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237188658
17392016/11/15 19:12:08 [debug] 30743#30743: *162 http finalize request: -4, "/authorize?" a:1, c:3
17402016/11/15 19:12:08 [debug] 30743#30743: *162 http request count:3 blk:0
17412016/11/15 19:12:08 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AC047 end:00006EFC4F5AECFB
17422016/11/15 19:12:08 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:439
17432016/11/15 19:12:08 [debug] 30743#30743: *162 http2 DATA frame
17442016/11/15 19:12:08 [debug] 30743#30743: *162 malloc: 00000F0D34D89720:65536
17452016/11/15 19:12:08 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5ACB74 end:00006EFC4F5AECFB
17462016/11/15 19:12:08 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:439
17472016/11/15 19:12:08 [debug] 30743#30743: *162 http2 DATA frame
17482016/11/15 19:12:08 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AD6A1 end:00006EFC4F5AECFB
17492016/11/15 19:12:08 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:439
17502016/11/15 19:12:08 [debug] 30743#30743: *162 http2 DATA frame
17512016/11/15 19:12:08 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AE1CE end:00006EFC4F5AECFB
17522016/11/15 19:12:08 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:439
17532016/11/15 19:12:08 [debug] 30743#30743: *162 http2 DATA frame
17542016/11/15 19:12:08 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AECFB end:00006EFC4F5AECFB
17552016/11/15 19:12:08 [debug] 30743#30743: *162 event timer del: 3: 1479237302602
17562016/11/15 19:12:08 [debug] 30743#30743: *162 http2 write handler
17572016/11/15 19:12:08 [debug] 30743#30743: *162 http2 read handler
17582016/11/15 19:12:08 [debug] 30743#30743: *162 SSL_read: 2861
17592016/11/15 19:12:08 [debug] 30743#30743: *162 SSL_read: 2861
17602016/11/15 19:12:08 [debug] 30743#30743: *162 SSL_read: 2861
17612016/11/15 19:12:08 [debug] 30743#30743: *162 SSL_read: 2861
17622016/11/15 19:12:08 [debug] 30743#30743: *162 SSL_read: 2861
17632016/11/15 19:12:08 [debug] 30743#30743: *162 SSL_read: 1329
17642016/11/15 19:12:08 [debug] 30743#30743: *162 SSL_read: -1
17652016/11/15 19:12:08 [debug] 30743#30743: *162 SSL_get_error: 2
17662016/11/15 19:12:08 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:439
17672016/11/15 19:12:08 [debug] 30743#30743: *162 http2 DATA frame
17682016/11/15 19:12:08 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5ACB3D end:00006EFC4F5AFD22
17692016/11/15 19:12:08 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:439
17702016/11/15 19:12:08 [debug] 30743#30743: *162 http2 DATA frame
17712016/11/15 19:12:08 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AD66A end:00006EFC4F5AFD22
17722016/11/15 19:12:08 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:439
17732016/11/15 19:12:08 [debug] 30743#30743: *162 http2 DATA frame
17742016/11/15 19:12:08 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AE197 end:00006EFC4F5AFD22
17752016/11/15 19:12:08 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:439
17762016/11/15 19:12:08 [debug] 30743#30743: *162 http2 DATA frame
17772016/11/15 19:12:08 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AECC4 end:00006EFC4F5AFD22
17782016/11/15 19:12:08 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:439
17792016/11/15 19:12:08 [debug] 30743#30743: *162 http2 DATA frame
17802016/11/15 19:12:08 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AF7F1 end:00006EFC4F5AFD22
17812016/11/15 19:12:08 [debug] 30743#30743: *162 process http2 frame type:0 f:1 l:1320 sid:439
17822016/11/15 19:12:08 [debug] 30743#30743: *162 http2 DATA frame
17832016/11/15 19:12:08 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AFD22 end:00006EFC4F5AFD22
17842016/11/15 19:12:08 [debug] 30743#30743: *162 http2 write handler
17852016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream request: "/authorize?"
17862016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream send request handler
17872016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream send request
17882016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream send request body
17892016/11/15 19:12:08 [debug] 30743#30743: *162 chain writer buf fl:1 s:2711
17902016/11/15 19:12:08 [debug] 30743#30743: *162 chain writer in: 00000F0D34EB7550
17912016/11/15 19:12:08 [debug] 30743#30743: *162 writev: 2711 of 2711
17922016/11/15 19:12:08 [debug] 30743#30743: *162 chain writer out: 0000000000000000
17932016/11/15 19:12:08 [debug] 30743#30743: *162 event timer del: 7: 1479237188658
17942016/11/15 19:12:08 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237188659
17952016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream request: "/authorize?"
17962016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream process header
17972016/11/15 19:12:08 [debug] 30743#30743: *162 malloc: 00000F0D34DBC950:4096
17982016/11/15 19:12:08 [debug] 30743#30743: *162 recv: fd:7 172 of 4096
17992016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy status 200 "200 OK"
18002016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "X-Powered-By: Express"
18012016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "x-appname-origin: appname3"
18022016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "content-type: application/json"
18032016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "content-length: 0"
18042016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "Date: Tue, 15 Nov 2016 19:12:08 GMT"
18052016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "Connection: close"
18062016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header done
18072016/11/15 19:12:08 [debug] 30743#30743: *162 lua capture header filter, uri "/authorize"
18082016/11/15 19:12:08 [debug] 30743#30743: *162 http2 header filter
18092016/11/15 19:12:08 [debug] 30743#30743: *162 finalize http upstream request: 0
18102016/11/15 19:12:08 [debug] 30743#30743: *162 finalize http proxy request
18112016/11/15 19:12:08 [debug] 30743#30743: *162 free rr peer 1 0
18122016/11/15 19:12:08 [debug] 30743#30743: *162 close http upstream connection: 7
18132016/11/15 19:12:08 [debug] 30743#30743: *162 free: 00000F0D34DBBA30, unused: 48
18142016/11/15 19:12:08 [debug] 30743#30743: *162 event timer del: 7: 1479237188659
18152016/11/15 19:12:08 [debug] 30743#30743: *162 reusable connection: 0
18162016/11/15 19:12:08 [debug] 30743#30743: *162 http finalize request: 0, "/authorize?" a:1, c:2
18172016/11/15 19:12:08 [debug] 30743#30743: *162 auth request done s:200
18182016/11/15 19:12:08 [debug] 30743#30743: *162 http wake parent request: "/appname/v2/heartbeat?"
18192016/11/15 19:12:08 [debug] 30743#30743: *162 http posted request: "/appname/v2/heartbeat?"
18202016/11/15 19:12:08 [debug] 30743#30743: *162 access phase: 10
18212016/11/15 19:12:08 [debug] 30743#30743: *162 auth request handler
18222016/11/15 19:12:08 [debug] 30743#30743: *162 auth request set variables
18232016/11/15 19:12:08 [debug] 30743#30743: *162 access phase: 11
18242016/11/15 19:12:08 [debug] 30743#30743: *162 lua access handler, uri:"/appname/v2/heartbeat" c:1
18252016/11/15 19:12:08 [debug] 30743#30743: *162 post access phase: 12
18262016/11/15 19:12:08 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBD960:4096 @16
18272016/11/15 19:12:08 [debug] 30743#30743: *162 http body new buf t:1 f:0 00000F0D34D89720, pos 00000F0D34D89720, size: 26988 file: 0, size: 0
18282016/11/15 19:12:08 [debug] 30743#30743: *162 http init upstream, client timer: 0
18292016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
18302016/11/15 19:12:08 [debug] 30743#30743: *162 http script var: "192.168.123.11"
18312016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "
1832"
18332016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
18342016/11/15 19:12:08 [debug] 30743#30743: *162 http script var: "192.168.123.11"
18352016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "
1836"
18372016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "Host: "
18382016/11/15 19:12:08 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
18392016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "
1840"
18412016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
1842"
18432016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
18442016/11/15 19:12:08 [debug] 30743#30743: *162 http script var: "XXX"
18452016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "
1846"
18472016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
18482016/11/15 19:12:08 [debug] 30743#30743: *162 http script var: "XXXX"
18492016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "
1850"
18512016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "Connection: close
1852"
18532016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "Content-Length: "
18542016/11/15 19:12:08 [debug] 30743#30743: *162 http script var: "26988"
18552016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: "
1856"
18572016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: ""
18582016/11/15 19:12:08 [debug] 30743#30743: *162 http script copy: ""
18592016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
18602016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
18612016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
18622016/11/15 19:12:08 [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"
18632016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
18642016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
18652016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
18662016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
18672016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header:
1868"POST /appname/v2/heartbeat HTTP/1.1
1869X-Real-IP: 192.168.123.11
1870X-Forwarded-For: 192.168.123.11
1871Host: app3.mydomain.local
1872X-NginX-Proxy: true
1873
1874
1875Connection: close
1876Content-Length: 26988
1877accept: application/json, text/plain, */*
1878origin: https://app3.mydomain.local
1879appname-csrf-token: YYY
1880user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
1881content-type: application/json;charset=UTF-8
1882accept-encoding: gzip, deflate, br
1883accept-language: en-US,en;q=0.8
18842016/11/15 19:12:08 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBE970:4096 @16
18852016/11/15 19:12:08 [debug] 30743#30743: *162 http cleanup add: 00000F0D34DBE938
18862016/11/15 19:12:08 [debug] 30743#30743: *162 get rr peer, try: 1
18872016/11/15 19:12:08 [debug] 30743#30743: *162 stream socket 7
18882016/11/15 19:12:08 [debug] 30743#30743: *162 epoll add connection: fd:7 ev:80002005
18892016/11/15 19:12:08 [debug] 30743#30743: *162 connect to 182.168.128.69:8080, fd:7 #582
18902016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream connect: -2
18912016/11/15 19:12:08 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBBA30:128 @16
18922016/11/15 19:12:08 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237188684
18932016/11/15 19:12:08 [debug] 30743#30743: *162 free: 00000F0D34D89720
18942016/11/15 19:12:08 [debug] 30743#30743: *162 http finalize request: -4, "/appname/v2/heartbeat?" a:1, c:2
18952016/11/15 19:12:08 [debug] 30743#30743: *162 http request count:2 blk:0
18962016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
18972016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream send request handler
18982016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream send request
18992016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream send request body
19002016/11/15 19:12:08 [debug] 30743#30743: *162 chain writer buf fl:0 s:2690
19012016/11/15 19:12:08 [debug] 30743#30743: *162 chain writer buf fl:1 s:26988
19022016/11/15 19:12:08 [debug] 30743#30743: *162 chain writer in: 00000F0D34DBEA00
19032016/11/15 19:12:08 [debug] 30743#30743: *162 writev: 28960 of 29678
19042016/11/15 19:12:08 [debug] 30743#30743: *162 chain writer out: 00000F0D34DBEA10
19052016/11/15 19:12:08 [debug] 30743#30743: *162 event timer: 7, old: 1479237188684, new: 1479237188684
19062016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
19072016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream send request handler
19082016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream send request
19092016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream send request body
19102016/11/15 19:12:08 [debug] 30743#30743: *162 chain writer in: 00000F0D34DBEA10
19112016/11/15 19:12:08 [debug] 30743#30743: *162 writev: 718 of 718
19122016/11/15 19:12:08 [debug] 30743#30743: *162 chain writer out: 0000000000000000
19132016/11/15 19:12:08 [debug] 30743#30743: *162 event timer del: 7: 1479237188684
19142016/11/15 19:12:08 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237188685
19152016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
19162016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream process header
19172016/11/15 19:12:08 [debug] 30743#30743: *162 malloc: 00000F0D34D69D00:4096
19182016/11/15 19:12:08 [debug] 30743#30743: *162 recv: fd:7 1056 of 4096
19192016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy status 400 "400 Bad Request"
19202016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "Server: nginx"
19212016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "Date: Tue, 15 Nov 2016 19:12:08 GMT"
19222016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "Content-Type: text/html; charset=utf-8"
19232016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "Content-Length: 840"
19242016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "Connection: close"
19252016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "X-Powered-By: Express"
19262016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header: "X-Content-Type-Options: nosniff"
19272016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy header done
19282016/11/15 19:12:08 [debug] 30743#30743: *162 lua capture header filter, uri "/appname/v2/heartbeat"
19292016/11/15 19:12:08 [debug] 30743#30743: *162 http2 header filter
19302016/11/15 19:12:08 [debug] 30743#30743: *162 http2 output header: ":status: 400"
19312016/11/15 19:12:08 [debug] 30743#30743: *162 http2 output header: "server: nginx"
19322016/11/15 19:12:08 [debug] 30743#30743: *162 http2 output header: "date: Tue, 15 Nov 2016 19:12:08 GMT"
19332016/11/15 19:12:08 [debug] 30743#30743: *162 http2 output header: "content-type: text/html; charset=utf-8"
19342016/11/15 19:12:08 [debug] 30743#30743: *162 http2 output header: "content-length: 840"
19352016/11/15 19:12:08 [debug] 30743#30743: *162 http2 output header: "x-powered-by: Express"
19362016/11/15 19:12:08 [debug] 30743#30743: *162 http2 output header: "x-content-type-options: nosniff"
19372016/11/15 19:12:08 [debug] 30743#30743: *162 http2:439 create HEADERS frame 00000F0D34DBEDA8: len:98
19382016/11/15 19:12:08 [debug] 30743#30743: *162 http cleanup add: 00000F0D34DBEE90
19392016/11/15 19:12:08 [debug] 30743#30743: *162 http2 frame out: 00000F0D34DBEDA8 sid:439 bl:1 len:98
19402016/11/15 19:12:08 [debug] 30743#30743: *162 malloc: 00000F0D34D83A10:16384
19412016/11/15 19:12:08 [debug] 30743#30743: *162 SSL buf copy: 9
19422016/11/15 19:12:08 [debug] 30743#30743: *162 SSL buf copy: 98
19432016/11/15 19:12:08 [debug] 30743#30743: *162 http2:439 HEADERS frame 00000F0D34DBEDA8 was sent
19442016/11/15 19:12:08 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34DBEDA8 sid:439 bl:1 len:98
19452016/11/15 19:12:08 [debug] 30743#30743: *162 http cacheable: 0
19462016/11/15 19:12:08 [debug] 30743#30743: *162 http proxy filter init s:400 h:0 c:0 l:840
19472016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream process upstream
19482016/11/15 19:12:08 [debug] 30743#30743: *162 pipe read upstream: 1
19492016/11/15 19:12:08 [debug] 30743#30743: *162 pipe preread: 840
19502016/11/15 19:12:08 [debug] 30743#30743: *162 readv: 1, last:3040
19512016/11/15 19:12:08 [debug] 30743#30743: *162 pipe recv chain: 0
19522016/11/15 19:12:08 [debug] 30743#30743: *162 pipe buf free s:0 t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
19532016/11/15 19:12:08 [debug] 30743#30743: *162 pipe length: 840
19542016/11/15 19:12:08 [debug] 30743#30743: *162 input buf #0
19552016/11/15 19:12:08 [debug] 30743#30743: *162 pipe write downstream: 1
19562016/11/15 19:12:08 [debug] 30743#30743: *162 pipe write downstream flush in
19572016/11/15 19:12:08 [debug] 30743#30743: *162 http output filter "/appname/v2/heartbeat?"
19582016/11/15 19:12:08 [debug] 30743#30743: *162 http copy filter: "/appname/v2/heartbeat?"
19592016/11/15 19:12:08 [debug] 30743#30743: *162 lua capture body filter, uri "/appname/v2/heartbeat"
19602016/11/15 19:12:08 [debug] 30743#30743: *162 http postpone filter "/appname/v2/heartbeat?" 00000F0D34DBEA00
19612016/11/15 19:12:08 [debug] 30743#30743: *162 write new buf t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
19622016/11/15 19:12:08 [debug] 30743#30743: *162 http write filter: l:0 f:0 s:840
19632016/11/15 19:12:08 [debug] 30743#30743: *162 http copy filter: 0 "/appname/v2/heartbeat?"
19642016/11/15 19:12:08 [debug] 30743#30743: *162 pipe write downstream done
19652016/11/15 19:12:08 [debug] 30743#30743: *162 event timer: 7, old: 1479237188685, new: 1479237188696
19662016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream exit: 0000000000000000
19672016/11/15 19:12:08 [debug] 30743#30743: *162 finalize http upstream request: 0
19682016/11/15 19:12:08 [debug] 30743#30743: *162 finalize http proxy request
19692016/11/15 19:12:08 [debug] 30743#30743: *162 free rr peer 1 0
19702016/11/15 19:12:08 [debug] 30743#30743: *162 close http upstream connection: 7
19712016/11/15 19:12:08 [debug] 30743#30743: *162 free: 00000F0D34DBBA30, unused: 48
19722016/11/15 19:12:08 [debug] 30743#30743: *162 event timer del: 7: 1479237188685
19732016/11/15 19:12:08 [debug] 30743#30743: *162 reusable connection: 0
19742016/11/15 19:12:08 [debug] 30743#30743: *162 http upstream temp fd: -1
19752016/11/15 19:12:08 [debug] 30743#30743: *162 http output filter "/appname/v2/heartbeat?"
19762016/11/15 19:12:08 [debug] 30743#30743: *162 http copy filter: "/appname/v2/heartbeat?"
19772016/11/15 19:12:08 [debug] 30743#30743: *162 lua capture body filter, uri "/appname/v2/heartbeat"
19782016/11/15 19:12:08 [debug] 30743#30743: *162 http postpone filter "/appname/v2/heartbeat?" 00007A9E70E05A50
19792016/11/15 19:12:08 [debug] 30743#30743: *162 write old buf t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
19802016/11/15 19:12:08 [debug] 30743#30743: *162 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
19812016/11/15 19:12:08 [debug] 30743#30743: *162 http write filter: l:1 f:0 s:840
19822016/11/15 19:12:08 [debug] 30743#30743: *162 http write filter limit 0
19832016/11/15 19:12:08 [debug] 30743#30743: *162 http2:439 create DATA frame 00000F0D34DBEDA8: len:840 flags:1
19842016/11/15 19:12:08 [debug] 30743#30743: *162 http2 frame out: 00000F0D34DBEDA8 sid:439 bl:0 len:840
19852016/11/15 19:12:08 [debug] 30743#30743: *162 SSL buf copy: 9
19862016/11/15 19:12:08 [debug] 30743#30743: *162 SSL buf copy: 840
19872016/11/15 19:12:08 [debug] 30743#30743: *162 SSL to write: 956
19882016/11/15 19:12:08 [debug] 30743#30743: *162 SSL_write: 956
19892016/11/15 19:12:08 [debug] 30743#30743: *162 http2:439 DATA frame 00000F0D34DBEDA8 was sent
19902016/11/15 19:12:08 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34DBEDA8 sid:439 bl:0 len:840
19912016/11/15 19:12:08 [debug] 30743#30743: *162 http write filter 0000000000000000
19922016/11/15 19:12:08 [debug] 30743#30743: *162 http copy filter: 0 "/appname/v2/heartbeat?"
19932016/11/15 19:12:08 [debug] 30743#30743: *162 http finalize request: 0, "/appname/v2/heartbeat?" a:1, c:1
19942016/11/15 19:12:08 [debug] 30743#30743: *162 http request count:1 blk:0
19952016/11/15 19:12:08 [debug] 30743#30743: *162 http2 close stream 439, queued 0, processing 1
19962016/11/15 19:12:08 [debug] 30743#30743: *162 http close request
19972016/11/15 19:12:08 [debug] 30743#30743: *162 http log handler
19982016/11/15 19:12:08 [debug] 30743#30743: *162 free: 00000F0D34DBC950
19992016/11/15 19:12:08 [debug] 30743#30743: *162 free: 00000F0D34D69D00
20002016/11/15 19:12:08 [debug] 30743#30743: *162 free: 00000F0D34E120F0, unused: 0
20012016/11/15 19:12:08 [debug] 30743#30743: *162 free: 00000F0D34E63480, unused: 2
20022016/11/15 19:12:08 [debug] 30743#30743: *162 free: 00000F0D34EB6570, unused: 0
20032016/11/15 19:12:08 [debug] 30743#30743: *162 free: 00000F0D34EB7580, unused: 8
20042016/11/15 19:12:08 [debug] 30743#30743: *162 free: 00000F0D34DBD960, unused: 0
20052016/11/15 19:12:08 [debug] 30743#30743: *162 free: 00000F0D34DBE970, unused: 1926
20062016/11/15 19:12:08 [debug] 30743#30743: *162 free: 00000F0D34E61530, unused: 162
20072016/11/15 19:12:08 [debug] 30743#30743: *162 free: 00000F0D34E16F00, unused: 156
20082016/11/15 19:12:08 [debug] 30743#30743: *162 free: 00000F0D34DC4930, unused: 111
20092016/11/15 19:12:08 [debug] 30743#30743: *162 post event 00006EFC4C5081F0
20102016/11/15 19:12:08 [debug] 30743#30743: *162 delete posted event 00006EFC4C5081F0
20112016/11/15 19:12:08 [debug] 30743#30743: *162 http2 handle connection handler
20122016/11/15 19:12:08 [debug] 30743#30743: *162 free: 00000F0D34E03BC0, unused: 3488
20132016/11/15 19:12:08 [debug] 30743#30743: *162 free: 00000F0D34D83A10
20142016/11/15 19:12:08 [debug] 30743#30743: *162 reusable connection: 1
20152016/11/15 19:12:08 [debug] 30743#30743: *162 event timer add: 3: 180000:1479237308696
20162016/11/15 19:12:13 [debug] 30741#30741: *251 post event 00006EFC4C5081F0
20172016/11/15 19:12:13 [debug] 30741#30741: *251 post event 00006EFC4C2071F0
20182016/11/15 19:12:13 [debug] 30741#30741: *251 delete posted event 00006EFC4C5081F0
20192016/11/15 19:12:13 [debug] 30741#30741: *251 http run request: "/appname/socket/?EIO=3&transport=websocket&sid=UJyFqU73bEe42MVkAAAB"
20202016/11/15 19:12:13 [debug] 30741#30741: *251 http upstream process upgraded, fu:0
20212016/11/15 19:12:13 [debug] 30741#30741: *251 SSL_read: 9
20222016/11/15 19:12:13 [debug] 30741#30741: *251 SSL_read: -1
20232016/11/15 19:12:13 [debug] 30741#30741: *251 SSL_get_error: 2
20242016/11/15 19:12:13 [debug] 30741#30741: *251 send: fd:14 9 of 9
20252016/11/15 19:12:13 [debug] 30741#30741: *251 event timer del: 14: 1479237168070
20262016/11/15 19:12:13 [debug] 30741#30741: *251 event timer add: 14: 60000:1479237193075
20272016/11/15 19:12:13 [debug] 30741#30741: *251 delete posted event 00006EFC4C2071F0
20282016/11/15 19:12:13 [debug] 30741#30741: *251 http run request: "/appname/socket/?EIO=3&transport=websocket&sid=UJyFqU73bEe42MVkAAAB"
20292016/11/15 19:12:13 [debug] 30741#30741: *251 http upstream process upgraded, fu:1
20302016/11/15 19:12:13 [debug] 30741#30741: *251 event timer: 14, old: 1479237193075, new: 1479237193075
20312016/11/15 19:12:13 [debug] 30741#30741: *251 post event 00006EFC4C508250
20322016/11/15 19:12:13 [debug] 30741#30741: *251 post event 00006EFC4C207250
20332016/11/15 19:12:13 [debug] 30741#30741: *251 delete posted event 00006EFC4C508250
20342016/11/15 19:12:13 [debug] 30741#30741: *251 http upstream request: "/appname/socket/?EIO=3&transport=websocket&sid=UJyFqU73bEe42MVkAAAB"
20352016/11/15 19:12:13 [debug] 30741#30741: *251 http upstream process upgraded, fu:1
20362016/11/15 19:12:13 [debug] 30741#30741: *251 recv: fd:14 3 of 4096
20372016/11/15 19:12:13 [debug] 30741#30741: *251 SSL to write: 3
20382016/11/15 19:12:13 [debug] 30741#30741: *251 SSL_write: 3
20392016/11/15 19:12:13 [debug] 30741#30741: *251 recv: fd:14 -1 of 4096
20402016/11/15 19:12:13 [debug] 30741#30741: *251 recv() not ready (11: Resource temporarily unavailable)
20412016/11/15 19:12:13 [debug] 30741#30741: *251 event timer: 14, old: 1479237193075, new: 1479237193080
20422016/11/15 19:12:13 [debug] 30741#30741: *251 delete posted event 00006EFC4C207250
20432016/11/15 19:12:13 [debug] 30741#30741: *251 http upstream request: "/appname/socket/?EIO=3&transport=websocket&sid=UJyFqU73bEe42MVkAAAB"
20442016/11/15 19:12:13 [debug] 30741#30741: *251 http upstream process upgraded, fu:0
20452016/11/15 19:12:13 [debug] 30741#30741: *251 event timer: 14, old: 1479237193075, new: 1479237193080
20462016/11/15 19:12:14 [debug] 30743#30743: *162 http2 idle handler
20472016/11/15 19:12:14 [debug] 30743#30743: *162 reusable connection: 0
20482016/11/15 19:12:14 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E120F0:4096 @16
20492016/11/15 19:12:14 [debug] 30743#30743: *162 http2 read handler
20502016/11/15 19:12:14 [debug] 30743#30743: *162 SSL_read: 314
20512016/11/15 19:12:14 [debug] 30743#30743: *162 SSL_read: 2861
20522016/11/15 19:12:14 [debug] 30743#30743: *162 SSL_read: 2861
20532016/11/15 19:12:14 [debug] 30743#30743: *162 SSL_read: 2861
20542016/11/15 19:12:14 [debug] 30743#30743: *162 SSL_read: 2861
20552016/11/15 19:12:14 [debug] 30743#30743: *162 SSL_read: -1
20562016/11/15 19:12:14 [debug] 30743#30743: *162 SSL_get_error: 2
20572016/11/15 19:12:14 [debug] 30743#30743: *162 process http2 frame type:1 f:24 l:305 sid:441
20582016/11/15 19:12:14 [debug] 30743#30743: *162 http2 HEADERS frame sid:441 on 0 excl:0 weight:183
20592016/11/15 19:12:14 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DC4930:1024 @16
20602016/11/15 19:12:14 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E03BC0:4096 @16
20612016/11/15 19:12:14 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E63480:4096 @16
20622016/11/15 19:12:14 [debug] 30743#30743: *162 http2 get indexed header name: 3
20632016/11/15 19:12:14 [debug] 30743#30743: *162 http2 get indexed header name: 72
20642016/11/15 19:12:14 [debug] 30743#30743: *162 http2 get indexed header name: 7
20652016/11/15 19:12:14 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
20662016/11/15 19:12:14 [debug] 30743#30743: *162 http2 hpack encoded string length: 13
20672016/11/15 19:12:14 [debug] 30743#30743: *162 http uri: "/appname/v2/heartbeat"
20682016/11/15 19:12:14 [debug] 30743#30743: *162 http args: ""
20692016/11/15 19:12:14 [debug] 30743#30743: *162 http exten: ""
20702016/11/15 19:12:14 [debug] 30743#30743: *162 http2 get indexed header: 28
20712016/11/15 19:12:14 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
20722016/11/15 19:12:14 [debug] 30743#30743: *162 http2 add header to hpack table: "content-length: 17015"
20732016/11/15 19:12:14 [debug] 30743#30743: *162 http2 hpack table account: 51 free:22
20742016/11/15 19:12:14 [debug] 30743#30743: *162 http2 http header: "content-length: 17015"
20752016/11/15 19:12:14 [debug] 30743#30743: *162 http2 get indexed header name: 71
20762016/11/15 19:12:14 [debug] 30743#30743: *162 http2 http header: "accept: application/json, text/plain, */*"
20772016/11/15 19:12:14 [debug] 30743#30743: *162 http2 get indexed header name: 83
20782016/11/15 19:12:14 [debug] 30743#30743: *162 http2 http header: "origin: https://app3.mydomain.local"
20792016/11/15 19:12:14 [debug] 30743#30743: *162 http2 hpack encoded string length: 11
20802016/11/15 19:12:14 [debug] 30743#30743: *162 http2 hpack encoded string length: 116
20812016/11/15 19:12:14 [debug] 30743#30743: *162 http2 add header to hpack table: "appname-csrf-token: YYY
20822016/11/15 19:12:14 [debug] 30743#30743: *162 http2 hpack table account: 191 free:162
20832016/11/15 19:12:14 [debug] 30743#30743: *162 http2 http header: "appname-csrf-token: YYY
20842016/11/15 19:12:14 [debug] 30743#30743: *162 http2 get indexed header name: 68
20852016/11/15 19:12:14 [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"
20862016/11/15 19:12:14 [debug] 30743#30743: *162 http2 get indexed header name: 83
20872016/11/15 19:12:14 [debug] 30743#30743: *162 http2 http header: "content-type: application/json;charset=UTF-8"
20882016/11/15 19:12:14 [debug] 30743#30743: *162 http2 get indexed header name: 81
20892016/11/15 19:12:14 [debug] 30743#30743: *162 http2 http header: "accept-encoding: gzip, deflate, br"
20902016/11/15 19:12:14 [debug] 30743#30743: *162 http2 get indexed header name: 97
20912016/11/15 19:12:14 [debug] 30743#30743: *162 http2 http header: "accept-language: en-US,en;q=0.8"
20922016/11/15 19:12:14 [debug] 30743#30743: *162 http2 get indexed header name: 96
20932016/11/15 19:12:14 [debug] 30743#30743: *162 http2 get indexed header name: 95
20942016/11/15 19:12:14 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E16F00:1024 @16
20952016/11/15 19:12:14 [debug] 30743#30743: *162 http2 get indexed header name: 94
20962016/11/15 19:12:14 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E61530:1024 @16
20972016/11/15 19:12:14 [debug] 30743#30743: *162 http2 get indexed header name: 93
20982016/11/15 19:12:14 [debug] 30743#30743: *162 http2 get indexed header: 32
20992016/11/15 19:12:14 [debug] 30743#30743: *162 http2 hpack encoded string length: 128
21002016/11/15 19:12:14 [debug] 30743#30743: *162 http2 add header to hpack table: "cookie: XXX"
21012016/11/15 19:12:14 [debug] 30743#30743: *162 http2 hpack table account: 198 free:169
21022016/11/15 19:12:14 [debug] 30743#30743: *162 http2 http request line: "POST /appname/v2/heartbeat HTTP/2.0"
21032016/11/15 19:12:14 [debug] 30743#30743: *162 generic phase: 0
21042016/11/15 19:12:14 [debug] 30743#30743: *162 rewrite phase: 1
21052016/11/15 19:12:14 [debug] 30743#30743: *162 test location: "^/widgets/$"
21062016/11/15 19:12:14 [debug] 30743#30743: *162 test location: "/"
21072016/11/15 19:12:14 [debug] 30743#30743: *162 test location: "docs/"
21082016/11/15 19:12:14 [debug] 30743#30743: *162 test location: "appname/socket"
21092016/11/15 19:12:14 [debug] 30743#30743: *162 test location: ~ "^/(index.html)?$"
21102016/11/15 19:12:14 [debug] 30743#30743: *162 test location: ~ "^/login/?$"
21112016/11/15 19:12:14 [debug] 30743#30743: *162 test location: ~ "^/(appname)"
21122016/11/15 19:12:14 [debug] 30743#30743: *162 test location: ~ "/components/(.*)"
21132016/11/15 19:12:14 [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)$"
21142016/11/15 19:12:14 [debug] 30743#30743: *162 using configuration "/"
21152016/11/15 19:12:14 [debug] 30743#30743: *162 http cl:17015 max:1073741824
21162016/11/15 19:12:14 [debug] 30743#30743: *162 rewrite phase: 3
21172016/11/15 19:12:14 [debug] 30743#30743: *162 post rewrite phase: 4
21182016/11/15 19:12:14 [debug] 30743#30743: *162 generic phase: 5
21192016/11/15 19:12:14 [debug] 30743#30743: *162 generic phase: 6
21202016/11/15 19:12:14 [debug] 30743#30743: *162 generic phase: 7
21212016/11/15 19:12:14 [debug] 30743#30743: *162 access phase: 8
21222016/11/15 19:12:14 [debug] 30743#30743: *162 access phase: 9
21232016/11/15 19:12:14 [debug] 30743#30743: *162 access phase: 10
21242016/11/15 19:12:14 [debug] 30743#30743: *162 auth request handler
21252016/11/15 19:12:14 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB6570:4096 @16
21262016/11/15 19:12:14 [debug] 30743#30743: *162 http subrequest "/authorize?"
21272016/11/15 19:12:14 [debug] 30743#30743: *162 http posted request: "/authorize?"
21282016/11/15 19:12:14 [debug] 30743#30743: *162 rewrite phase: 1
21292016/11/15 19:12:14 [debug] 30743#30743: *162 test location: "^/widgets/$"
21302016/11/15 19:12:14 [debug] 30743#30743: *162 test location: "/"
21312016/11/15 19:12:14 [debug] 30743#30743: *162 test location: "docs/"
21322016/11/15 19:12:14 [debug] 30743#30743: *162 test location: "dashboard"
21332016/11/15 19:12:14 [debug] 30743#30743: *162 test location: "authorize"
21342016/11/15 19:12:14 [debug] 30743#30743: *162 using configuration "=/authorize"
21352016/11/15 19:12:14 [debug] 30743#30743: *162 http cl:17015 max:1073741824
21362016/11/15 19:12:14 [debug] 30743#30743: *162 rewrite phase: 3
21372016/11/15 19:12:14 [debug] 30743#30743: *162 post rewrite phase: 4
21382016/11/15 19:12:14 [debug] 30743#30743: *162 generic phase: 5
21392016/11/15 19:12:14 [debug] 30743#30743: *162 generic phase: 6
21402016/11/15 19:12:14 [debug] 30743#30743: *162 generic phase: 7
21412016/11/15 19:12:14 [debug] 30743#30743: *162 http init upstream, client timer: 0
21422016/11/15 19:12:14 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB7580:4096 @16
21432016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
21442016/11/15 19:12:14 [debug] 30743#30743: *162 http script var: "192.168.123.11"
21452016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "
2146"
21472016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
21482016/11/15 19:12:14 [debug] 30743#30743: *162 http script var: "192.168.123.11"
21492016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "
2150"
21512016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "Host: "
21522016/11/15 19:12:14 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
21532016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "
2154"
21552016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
2156"
21572016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
21582016/11/15 19:12:14 [debug] 30743#30743: *162 http script var: "XXX"
21592016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "
2160"
21612016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
21622016/11/15 19:12:14 [debug] 30743#30743: *162 http script var: "XXXX"
21632016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "
2164"
21652016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "x-appname-uri: "
21662016/11/15 19:12:14 [debug] 30743#30743: *162 http script var: "/appname/v2/heartbeat"
21672016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "
2168"
21692016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "x-appname-method: "
21702016/11/15 19:12:14 [debug] 30743#30743: *162 http script var: "POST"
21712016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "
2172"
21732016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "Connection: close
2174"
21752016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: ""
21762016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: ""
21772016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
21782016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
21792016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
21802016/11/15 19:12:14 [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"
21812016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
21822016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
21832016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
21842016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
21852016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header:
2186"GET /authorize HTTP/1.1
2187X-Real-IP: 192.168.123.11
2188X-Forwarded-For: 192.168.123.11
2189Host: app3.mydomain.local
2190X-NginX-Proxy: true
2191
2192
2193x-appname-uri: /appname/v2/heartbeat
2194x-appname-method: POST
2195Connection: close
2196accept: application/json, text/plain, */*
2197origin: https://app3.mydomain.local
2198appname-csrf-token: YYY
2199user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
2200content-type: application/json;charset=UTF-8
2201accept-encoding: gzip, deflate, br
2202accept-language: en-US,en;q=0.8
22032016/11/15 19:12:14 [debug] 30743#30743: *162 http cleanup add: 00000F0D34E64460
22042016/11/15 19:12:14 [debug] 30743#30743: *162 get rr peer, try: 1
22052016/11/15 19:12:14 [debug] 30743#30743: *162 stream socket 7
22062016/11/15 19:12:14 [debug] 30743#30743: *162 epoll add connection: fd:7 ev:80002005
22072016/11/15 19:12:14 [debug] 30743#30743: *162 connect to 127.0.0.1:8008, fd:7 #583
22082016/11/15 19:12:14 [debug] 30743#30743: *162 http upstream connect: -2
22092016/11/15 19:12:14 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBBA30:128 @16
22102016/11/15 19:12:14 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237194568
22112016/11/15 19:12:14 [debug] 30743#30743: *162 http finalize request: -4, "/authorize?" a:1, c:3
22122016/11/15 19:12:14 [debug] 30743#30743: *162 http request count:3 blk:0
22132016/11/15 19:12:14 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AC14A end:00006EFC4F5AEDFE
22142016/11/15 19:12:14 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:441
22152016/11/15 19:12:14 [debug] 30743#30743: *162 http2 DATA frame
22162016/11/15 19:12:14 [debug] 30743#30743: *162 malloc: 00000F0D34D89720:65536
22172016/11/15 19:12:14 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5ACC77 end:00006EFC4F5AEDFE
22182016/11/15 19:12:14 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:441
22192016/11/15 19:12:14 [debug] 30743#30743: *162 http2 DATA frame
22202016/11/15 19:12:14 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AD7A4 end:00006EFC4F5AEDFE
22212016/11/15 19:12:14 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:441
22222016/11/15 19:12:14 [debug] 30743#30743: *162 http2 DATA frame
22232016/11/15 19:12:14 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AE2D1 end:00006EFC4F5AEDFE
22242016/11/15 19:12:14 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:441
22252016/11/15 19:12:14 [debug] 30743#30743: *162 http2 DATA frame
22262016/11/15 19:12:14 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AEDFE end:00006EFC4F5AEDFE
22272016/11/15 19:12:14 [debug] 30743#30743: *162 event timer del: 3: 1479237308696
22282016/11/15 19:12:14 [debug] 30743#30743: *162 http2 write handler
22292016/11/15 19:12:14 [debug] 30743#30743: *162 http2 read handler
22302016/11/15 19:12:14 [debug] 30743#30743: *162 SSL_read: 2861
22312016/11/15 19:12:14 [debug] 30743#30743: *162 SSL_read: 2764
22322016/11/15 19:12:14 [debug] 30743#30743: *162 SSL_read: -1
22332016/11/15 19:12:14 [debug] 30743#30743: *162 SSL_get_error: 2
22342016/11/15 19:12:14 [debug] 30743#30743: *162 process http2 frame type:0 f:0 l:2852 sid:441
22352016/11/15 19:12:14 [debug] 30743#30743: *162 http2 DATA frame
22362016/11/15 19:12:14 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5ACB3D end:00006EFC4F5AD609
22372016/11/15 19:12:14 [debug] 30743#30743: *162 process http2 frame type:0 f:1 l:2755 sid:441
22382016/11/15 19:12:14 [debug] 30743#30743: *162 http2 DATA frame
22392016/11/15 19:12:14 [debug] 30743#30743: *162 http2 frame complete pos:00006EFC4F5AD609 end:00006EFC4F5AD609
22402016/11/15 19:12:14 [debug] 30743#30743: *162 http2 write handler
22412016/11/15 19:12:14 [debug] 30743#30743: *162 http upstream request: "/authorize?"
22422016/11/15 19:12:14 [debug] 30743#30743: *162 http upstream send request handler
22432016/11/15 19:12:14 [debug] 30743#30743: *162 http upstream send request
22442016/11/15 19:12:14 [debug] 30743#30743: *162 http upstream send request body
22452016/11/15 19:12:14 [debug] 30743#30743: *162 chain writer buf fl:1 s:2711
22462016/11/15 19:12:14 [debug] 30743#30743: *162 chain writer in: 00000F0D34EB7550
22472016/11/15 19:12:14 [debug] 30743#30743: *162 writev: 2711 of 2711
22482016/11/15 19:12:14 [debug] 30743#30743: *162 chain writer out: 0000000000000000
22492016/11/15 19:12:14 [debug] 30743#30743: *162 event timer del: 7: 1479237194568
22502016/11/15 19:12:14 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237194569
22512016/11/15 19:12:14 [debug] 30743#30743: *162 http upstream request: "/authorize?"
22522016/11/15 19:12:14 [debug] 30743#30743: *162 http upstream process header
22532016/11/15 19:12:14 [debug] 30743#30743: *162 malloc: 00000F0D34DBC950:4096
22542016/11/15 19:12:14 [debug] 30743#30743: *162 recv: fd:7 172 of 4096
22552016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy status 200 "200 OK"
22562016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "X-Powered-By: Express"
22572016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "x-appname-origin: appname3"
22582016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "content-type: application/json"
22592016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "content-length: 0"
22602016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "Date: Tue, 15 Nov 2016 19:12:14 GMT"
22612016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "Connection: close"
22622016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header done
22632016/11/15 19:12:14 [debug] 30743#30743: *162 lua capture header filter, uri "/authorize"
22642016/11/15 19:12:14 [debug] 30743#30743: *162 http2 header filter
22652016/11/15 19:12:14 [debug] 30743#30743: *162 finalize http upstream request: 0
22662016/11/15 19:12:14 [debug] 30743#30743: *162 finalize http proxy request
22672016/11/15 19:12:14 [debug] 30743#30743: *162 free rr peer 1 0
22682016/11/15 19:12:14 [debug] 30743#30743: *162 close http upstream connection: 7
22692016/11/15 19:12:14 [debug] 30743#30743: *162 free: 00000F0D34DBBA30, unused: 48
22702016/11/15 19:12:14 [debug] 30743#30743: *162 event timer del: 7: 1479237194569
22712016/11/15 19:12:14 [debug] 30743#30743: *162 reusable connection: 0
22722016/11/15 19:12:14 [debug] 30743#30743: *162 http finalize request: 0, "/authorize?" a:1, c:2
22732016/11/15 19:12:14 [debug] 30743#30743: *162 auth request done s:200
22742016/11/15 19:12:14 [debug] 30743#30743: *162 http wake parent request: "/appname/v2/heartbeat?"
22752016/11/15 19:12:14 [debug] 30743#30743: *162 http posted request: "/appname/v2/heartbeat?"
22762016/11/15 19:12:14 [debug] 30743#30743: *162 access phase: 10
22772016/11/15 19:12:14 [debug] 30743#30743: *162 auth request handler
22782016/11/15 19:12:14 [debug] 30743#30743: *162 auth request set variables
22792016/11/15 19:12:14 [debug] 30743#30743: *162 access phase: 11
22802016/11/15 19:12:14 [debug] 30743#30743: *162 lua access handler, uri:"/appname/v2/heartbeat" c:1
22812016/11/15 19:12:14 [debug] 30743#30743: *162 post access phase: 12
22822016/11/15 19:12:14 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBD960:4096 @16
22832016/11/15 19:12:14 [debug] 30743#30743: *162 http body new buf t:1 f:0 00000F0D34D89720, pos 00000F0D34D89720, size: 17015 file: 0, size: 0
22842016/11/15 19:12:14 [debug] 30743#30743: *162 http init upstream, client timer: 0
22852016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
22862016/11/15 19:12:14 [debug] 30743#30743: *162 http script var: "192.168.123.11"
22872016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "
2288"
22892016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
22902016/11/15 19:12:14 [debug] 30743#30743: *162 http script var: "192.168.123.11"
22912016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "
2292"
22932016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "Host: "
22942016/11/15 19:12:14 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
22952016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "
2296"
22972016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
2298"
22992016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
23002016/11/15 19:12:14 [debug] 30743#30743: *162 http script var: "XXX"
23012016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "
2302"
23032016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
23042016/11/15 19:12:14 [debug] 30743#30743: *162 http script var: "XXXX"
23052016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "
2306"
23072016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "Connection: close
2308"
23092016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "Content-Length: "
23102016/11/15 19:12:14 [debug] 30743#30743: *162 http script var: "17015"
23112016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: "
2312"
23132016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: ""
23142016/11/15 19:12:14 [debug] 30743#30743: *162 http script copy: ""
23152016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
23162016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
23172016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
23182016/11/15 19:12:14 [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"
23192016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
23202016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
23212016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
23222016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
23232016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header:
2324"POST /appname/v2/heartbeat HTTP/1.1
2325X-Real-IP: 192.168.123.11
2326X-Forwarded-For: 192.168.123.11
2327Host: app3.mydomain.local
2328X-NginX-Proxy: true
2329
2330
2331Connection: close
2332Content-Length: 17015
2333accept: application/json, text/plain, */*
2334origin: https://app3.mydomain.local
2335appname-csrf-token: YYY
2336user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
2337content-type: application/json;charset=UTF-8
2338accept-encoding: gzip, deflate, br
2339accept-language: en-US,en;q=0.8
23402016/11/15 19:12:14 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBE970:4096 @16
23412016/11/15 19:12:14 [debug] 30743#30743: *162 http cleanup add: 00000F0D34DBE938
23422016/11/15 19:12:14 [debug] 30743#30743: *162 get rr peer, try: 1
23432016/11/15 19:12:14 [debug] 30743#30743: *162 stream socket 7
23442016/11/15 19:12:14 [debug] 30743#30743: *162 epoll add connection: fd:7 ev:80002005
23452016/11/15 19:12:14 [debug] 30743#30743: *162 connect to 182.168.128.69:8080, fd:7 #584
23462016/11/15 19:12:14 [debug] 30743#30743: *162 http upstream connect: -2
23472016/11/15 19:12:14 [debug] 30743#30743: *162 posix_memalign: 00000F0D34DBBA30:128 @16
23482016/11/15 19:12:14 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237194591
23492016/11/15 19:12:14 [debug] 30743#30743: *162 free: 00000F0D34D89720
23502016/11/15 19:12:14 [debug] 30743#30743: *162 http finalize request: -4, "/appname/v2/heartbeat?" a:1, c:2
23512016/11/15 19:12:14 [debug] 30743#30743: *162 http request count:2 blk:0
23522016/11/15 19:12:14 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
23532016/11/15 19:12:14 [debug] 30743#30743: *162 http upstream send request handler
23542016/11/15 19:12:14 [debug] 30743#30743: *162 http upstream send request
23552016/11/15 19:12:14 [debug] 30743#30743: *162 http upstream send request body
23562016/11/15 19:12:14 [debug] 30743#30743: *162 chain writer buf fl:0 s:2690
23572016/11/15 19:12:14 [debug] 30743#30743: *162 chain writer buf fl:1 s:17015
23582016/11/15 19:12:14 [debug] 30743#30743: *162 chain writer in: 00000F0D34DBEA00
23592016/11/15 19:12:14 [debug] 30743#30743: *162 writev: 19705 of 19705
23602016/11/15 19:12:14 [debug] 30743#30743: *162 chain writer out: 0000000000000000
23612016/11/15 19:12:14 [debug] 30743#30743: *162 event timer del: 7: 1479237194591
23622016/11/15 19:12:14 [debug] 30743#30743: *162 event timer add: 7: 60000:1479237194592
23632016/11/15 19:12:14 [debug] 30743#30743: *162 http upstream request: "/appname/v2/heartbeat?"
23642016/11/15 19:12:14 [debug] 30743#30743: *162 http upstream process header
23652016/11/15 19:12:14 [debug] 30743#30743: *162 malloc: 00000F0D34D69D00:4096
23662016/11/15 19:12:14 [debug] 30743#30743: *162 recv: fd:7 1056 of 4096
23672016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy status 400 "400 Bad Request"
23682016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "Server: nginx"
23692016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "Date: Tue, 15 Nov 2016 19:12:12 GMT"
23702016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "Content-Type: text/html; charset=utf-8"
23712016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "Content-Length: 840"
23722016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "Connection: close"
23732016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "X-Powered-By: Express"
23742016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header: "X-Content-Type-Options: nosniff"
23752016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy header done
23762016/11/15 19:12:14 [debug] 30743#30743: *162 lua capture header filter, uri "/appname/v2/heartbeat"
23772016/11/15 19:12:14 [debug] 30743#30743: *162 http2 header filter
23782016/11/15 19:12:14 [debug] 30743#30743: *162 http2 output header: ":status: 400"
23792016/11/15 19:12:14 [debug] 30743#30743: *162 http2 output header: "server: nginx"
23802016/11/15 19:12:14 [debug] 30743#30743: *162 http2 output header: "date: Tue, 15 Nov 2016 19:12:14 GMT"
23812016/11/15 19:12:14 [debug] 30743#30743: *162 http2 output header: "content-type: text/html; charset=utf-8"
23822016/11/15 19:12:14 [debug] 30743#30743: *162 http2 output header: "content-length: 840"
23832016/11/15 19:12:14 [debug] 30743#30743: *162 http2 output header: "x-powered-by: Express"
23842016/11/15 19:12:14 [debug] 30743#30743: *162 http2 output header: "x-content-type-options: nosniff"
23852016/11/15 19:12:14 [debug] 30743#30743: *162 http2:441 create HEADERS frame 00000F0D34DBEDA8: len:98
23862016/11/15 19:12:14 [debug] 30743#30743: *162 http cleanup add: 00000F0D34DBEE90
23872016/11/15 19:12:14 [debug] 30743#30743: *162 http2 frame out: 00000F0D34DBEDA8 sid:441 bl:1 len:98
23882016/11/15 19:12:14 [debug] 30743#30743: *162 malloc: 00000F0D34D83A10:16384
23892016/11/15 19:12:14 [debug] 30743#30743: *162 SSL buf copy: 9
23902016/11/15 19:12:14 [debug] 30743#30743: *162 SSL buf copy: 98
23912016/11/15 19:12:14 [debug] 30743#30743: *162 http2:441 HEADERS frame 00000F0D34DBEDA8 was sent
23922016/11/15 19:12:14 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34DBEDA8 sid:441 bl:1 len:98
23932016/11/15 19:12:14 [debug] 30743#30743: *162 http cacheable: 0
23942016/11/15 19:12:14 [debug] 30743#30743: *162 http proxy filter init s:400 h:0 c:0 l:840
23952016/11/15 19:12:14 [debug] 30743#30743: *162 http upstream process upstream
23962016/11/15 19:12:14 [debug] 30743#30743: *162 pipe read upstream: 1
23972016/11/15 19:12:14 [debug] 30743#30743: *162 pipe preread: 840
23982016/11/15 19:12:14 [debug] 30743#30743: *162 readv: 1, last:3040
23992016/11/15 19:12:14 [debug] 30743#30743: *162 pipe recv chain: 0
24002016/11/15 19:12:14 [debug] 30743#30743: *162 pipe buf free s:0 t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
24012016/11/15 19:12:14 [debug] 30743#30743: *162 pipe length: 840
24022016/11/15 19:12:14 [debug] 30743#30743: *162 input buf #0
24032016/11/15 19:12:14 [debug] 30743#30743: *162 pipe write downstream: 1
24042016/11/15 19:12:14 [debug] 30743#30743: *162 pipe write downstream flush in
24052016/11/15 19:12:14 [debug] 30743#30743: *162 http output filter "/appname/v2/heartbeat?"
24062016/11/15 19:12:14 [debug] 30743#30743: *162 http copy filter: "/appname/v2/heartbeat?"
24072016/11/15 19:12:14 [debug] 30743#30743: *162 lua capture body filter, uri "/appname/v2/heartbeat"
24082016/11/15 19:12:14 [debug] 30743#30743: *162 http postpone filter "/appname/v2/heartbeat?" 00000F0D34DBEA00
24092016/11/15 19:12:14 [debug] 30743#30743: *162 write new buf t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
24102016/11/15 19:12:14 [debug] 30743#30743: *162 http write filter: l:0 f:0 s:840
24112016/11/15 19:12:14 [debug] 30743#30743: *162 http copy filter: 0 "/appname/v2/heartbeat?"
24122016/11/15 19:12:14 [debug] 30743#30743: *162 pipe write downstream done
24132016/11/15 19:12:14 [debug] 30743#30743: *162 event timer: 7, old: 1479237194592, new: 1479237194600
24142016/11/15 19:12:14 [debug] 30743#30743: *162 http upstream exit: 0000000000000000
24152016/11/15 19:12:14 [debug] 30743#30743: *162 finalize http upstream request: 0
24162016/11/15 19:12:14 [debug] 30743#30743: *162 finalize http proxy request
24172016/11/15 19:12:14 [debug] 30743#30743: *162 free rr peer 1 0
24182016/11/15 19:12:14 [debug] 30743#30743: *162 close http upstream connection: 7
24192016/11/15 19:12:14 [debug] 30743#30743: *162 free: 00000F0D34DBBA30, unused: 48
24202016/11/15 19:12:14 [debug] 30743#30743: *162 event timer del: 7: 1479237194592
24212016/11/15 19:12:14 [debug] 30743#30743: *162 reusable connection: 0
24222016/11/15 19:12:14 [debug] 30743#30743: *162 http upstream temp fd: -1
24232016/11/15 19:12:14 [debug] 30743#30743: *162 http output filter "/appname/v2/heartbeat?"
24242016/11/15 19:12:14 [debug] 30743#30743: *162 http copy filter: "/appname/v2/heartbeat?"
24252016/11/15 19:12:14 [debug] 30743#30743: *162 lua capture body filter, uri "/appname/v2/heartbeat"
24262016/11/15 19:12:14 [debug] 30743#30743: *162 http postpone filter "/appname/v2/heartbeat?" 00007A9E70E05A50
24272016/11/15 19:12:14 [debug] 30743#30743: *162 write old buf t:1 f:0 00000F0D34D69D00, pos 00000F0D34D69DD8, size: 840 file: 0, size: 0
24282016/11/15 19:12:14 [debug] 30743#30743: *162 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
24292016/11/15 19:12:14 [debug] 30743#30743: *162 http write filter: l:1 f:0 s:840
24302016/11/15 19:12:14 [debug] 30743#30743: *162 http write filter limit 0
24312016/11/15 19:12:14 [debug] 30743#30743: *162 http2:441 create DATA frame 00000F0D34DBEDA8: len:840 flags:1
24322016/11/15 19:12:14 [debug] 30743#30743: *162 http2 frame out: 00000F0D34DBEDA8 sid:441 bl:0 len:840
24332016/11/15 19:12:14 [debug] 30743#30743: *162 SSL buf copy: 9
24342016/11/15 19:12:14 [debug] 30743#30743: *162 SSL buf copy: 840
24352016/11/15 19:12:14 [debug] 30743#30743: *162 SSL to write: 956
24362016/11/15 19:12:14 [debug] 30743#30743: *162 SSL_write: 956
24372016/11/15 19:12:14 [debug] 30743#30743: *162 http2:441 DATA frame 00000F0D34DBEDA8 was sent
24382016/11/15 19:12:14 [debug] 30743#30743: *162 http2 frame sent: 00000F0D34DBEDA8 sid:441 bl:0 len:840
24392016/11/15 19:12:14 [debug] 30743#30743: *162 http write filter 0000000000000000
24402016/11/15 19:12:14 [debug] 30743#30743: *162 http copy filter: 0 "/appname/v2/heartbeat?"
24412016/11/15 19:12:14 [debug] 30743#30743: *162 http finalize request: 0, "/appname/v2/heartbeat?" a:1, c:1
24422016/11/15 19:12:14 [debug] 30743#30743: *162 http request count:1 blk:0
24432016/11/15 19:12:14 [debug] 30743#30743: *162 http2 close stream 441, queued 0, processing 1
24442016/11/15 19:12:14 [debug] 30743#30743: *162 http close request
24452016/11/15 19:12:14 [debug] 30743#30743: *162 http log handler
24462016/11/15 19:12:14 [debug] 30743#30743: *162 free: 00000F0D34DBC950
24472016/11/15 19:12:14 [debug] 30743#30743: *162 free: 00000F0D34D69D00
24482016/11/15 19:12:14 [debug] 30743#30743: *162 free: 00000F0D34E03BC0, unused: 0
24492016/11/15 19:12:14 [debug] 30743#30743: *162 free: 00000F0D34E63480, unused: 2
24502016/11/15 19:12:14 [debug] 30743#30743: *162 free: 00000F0D34EB6570, unused: 0
24512016/11/15 19:12:14 [debug] 30743#30743: *162 free: 00000F0D34EB7580, unused: 8
24522016/11/15 19:12:14 [debug] 30743#30743: *162 free: 00000F0D34DBD960, unused: 0
24532016/11/15 19:12:14 [debug] 30743#30743: *162 free: 00000F0D34DBE970, unused: 1926
24542016/11/15 19:12:14 [debug] 30743#30743: *162 free: 00000F0D34DC4930, unused: 82
24552016/11/15 19:12:14 [debug] 30743#30743: *162 free: 00000F0D34E16F00, unused: 156
24562016/11/15 19:12:14 [debug] 30743#30743: *162 free: 00000F0D34E61530, unused: 111
24572016/11/15 19:12:14 [debug] 30743#30743: *162 post event 00006EFC4C5081F0
24582016/11/15 19:12:14 [debug] 30743#30743: *162 delete posted event 00006EFC4C5081F0
24592016/11/15 19:12:14 [debug] 30743#30743: *162 http2 handle connection handler
24602016/11/15 19:12:14 [debug] 30743#30743: *162 free: 00000F0D34E120F0, unused: 3488
24612016/11/15 19:12:14 [debug] 30743#30743: *162 free: 00000F0D34D83A10
24622016/11/15 19:12:14 [debug] 30743#30743: *162 reusable connection: 1
24632016/11/15 19:12:14 [debug] 30743#30743: *162 event timer add: 3: 180000:1479237314600
24642016/11/15 19:12:18 [debug] 30743#30743: *162 http2 idle handler
24652016/11/15 19:12:18 [debug] 30743#30743: *162 reusable connection: 0
24662016/11/15 19:12:18 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E03BC0:4096 @16
24672016/11/15 19:12:18 [debug] 30743#30743: *162 http2 read handler
24682016/11/15 19:12:18 [debug] 30743#30743: *162 SSL_read: 1386
24692016/11/15 19:12:18 [debug] 30743#30743: *162 SSL_read: 2861
24702016/11/15 19:12:18 [debug] 30743#30743: *162 SSL_read: 2861
24712016/11/15 19:12:18 [debug] 30743#30743: *162 SSL_read: 2861
24722016/11/15 19:12:18 [debug] 30743#30743: *162 SSL_read: 2861
24732016/11/15 19:12:18 [debug] 30743#30743: *162 SSL_read: -1
24742016/11/15 19:12:18 [debug] 30743#30743: *162 SSL_get_error: 2
24752016/11/15 19:12:18 [debug] 30743#30743: *162 process http2 frame type:1 f:24 l:1377 sid:443
24762016/11/15 19:12:18 [debug] 30743#30743: *162 http2 HEADERS frame sid:443 on 0 excl:0 weight:183
24772016/11/15 19:12:18 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E61530:1024 @16
24782016/11/15 19:12:18 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E120F0:4096 @16
24792016/11/15 19:12:18 [debug] 30743#30743: *162 posix_memalign: 00000F0D34E63480:4096 @16
24802016/11/15 19:12:18 [debug] 30743#30743: *162 http2 get indexed header name: 3
24812016/11/15 19:12:18 [debug] 30743#30743: *162 http2 get indexed header name: 75
24822016/11/15 19:12:18 [debug] 30743#30743: *162 http2 get indexed header name: 7
24832016/11/15 19:12:18 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
24842016/11/15 19:12:18 [debug] 30743#30743: *162 http2 hpack encoded string length: 13
24852016/11/15 19:12:18 [debug] 30743#30743: *162 http uri: "/appname/v2/heartbeat"
24862016/11/15 19:12:18 [debug] 30743#30743: *162 http args: ""
24872016/11/15 19:12:18 [debug] 30743#30743: *162 http exten: ""
24882016/11/15 19:12:18 [debug] 30743#30743: *162 http2 get indexed header: 28
24892016/11/15 19:12:18 [debug] 30743#30743: *162 http2 hpack encoded string length: 4
24902016/11/15 19:12:18 [debug] 30743#30743: *162 http2 add header to hpack table: "content-length: 27198"
24912016/11/15 19:12:18 [debug] 30743#30743: *162 http2 hpack table account: 51 free:20
24922016/11/15 19:12:18 [debug] 30743#30743: *162 http2 http header: "content-length: 27198"
24932016/11/15 19:12:18 [debug] 30743#30743: *162 http2 get indexed header name: 74
24942016/11/15 19:12:18 [debug] 30743#30743: *162 http2 http header: "accept: application/json, text/plain, */*"
24952016/11/15 19:12:18 [debug] 30743#30743: *162 http2 get indexed header name: 86
24962016/11/15 19:12:18 [debug] 30743#30743: *162 http2 http header: "origin: https://app3.mydomain.local"
24972016/11/15 19:12:18 [debug] 30743#30743: *162 http2 get indexed header name: 64
24982016/11/15 19:12:18 [debug] 30743#30743: *162 http2 http header: "appname-csrf-token: YYY
24992016/11/15 19:12:18 [debug] 30743#30743: *162 http2 get indexed header name: 70
25002016/11/15 19:12:18 [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"
25012016/11/15 19:12:18 [debug] 30743#30743: *162 http2 get indexed header name: 85
25022016/11/15 19:12:18 [debug] 30743#30743: *162 http2 http header: "content-type: application/json;charset=UTF-8"
25032016/11/15 19:12:18 [debug] 30743#30743: *162 http2 get indexed header name: 83
25042016/11/15 19:12:18 [debug] 30743#30743: *162 http2 http header: "accept-encoding: gzip, deflate, br"
25052016/11/15 19:12:18 [debug] 30743#30743: *162 http2 get indexed header: 17
25062016/11/15 19:12:18 [debug] 30743#30743: *162 http2 hpack encoded string length: 11
25072016/11/15 19:12:18 [debug] 30743#30743: *162 http2 add header to hpack table: "accept-language: en-US,en;q=0.8"
25082016/11/15 19:12:18 [debug] 30743#30743: *162 http2 hpack table account: 61 free:30
25092016/11/15 19:12:18 [debug] 30743#30743: *162 http2 http header: "accept-language: en-US,en;q=0.8"
25102016/11/15 19:12:18 [debug] 30743#30743: *162 http2 get indexed header: 32
25112016/11/15 19:12:18 [debug] 30743#30743: *162 http2 hpack encoded string length: 5
25122016/11/15 19:12:18 [debug] 30743#30743: *162 http2 add header to hpack table: "cookie: XXX"
25132016/11/15 19:12:18 [debug] 30743#30743: *162 http2 hpack table account: 44 free:13
25142016/11/15 19:12:18 [debug] 30743#30743: *162 http2 get indexed header: 32
25152016/11/15 19:12:18 [debug] 30743#30743: *162 http2 hpack encoded string length: 662
25162016/11/15 19:12:18 [debug] 30743#30743: *162 malloc: 00000F0D34DB5070:1060
25172016/11/15 19:12:18 [debug] 30743#30743: *162 http2 add header to hpack table: "cookie: XXX"
25182016/11/15 19:12:18 [debug] 30743#30743: *162 http2 hpack table account: 873 free:842
25192016/11/15 19:12:18 [debug] 30743#30743: *162 http2 get indexed header: 32
25202016/11/15 19:12:18 [debug] 30743#30743: *162 http2 hpack encoded string length: 622
25212016/11/15 19:12:18 [debug] 30743#30743: *162 malloc: 00000F0D34E16F00:996
25222016/11/15 19:12:18 [debug] 30743#30743: *162 http2 add header to hpack table: "cookie: XXX"
25232016/11/15 19:12:18 [debug] 30743#30743: *162 http2 hpack table account: 918 free:887
25242016/11/15 19:12:18 [debug] 30743#30743: *162 http2 get indexed header: 32
25252016/11/15 19:12:18 [debug] 30743#30743: *162 http2 hpack encoded string length: 22
25262016/11/15 19:12:18 [debug] 30743#30743: *162 http2 add header to hpack table: "cookie: XXX"
25272016/11/15 19:12:18 [debug] 30743#30743: *162 http2 hpack table account: 68 free:37
25282016/11/15 19:12:18 [debug] 30743#30743: *162 http2 get indexed header name: 68
25292016/11/15 19:12:18 [debug] 30743#30743: *162 http2 http request line: "POST /appname/v2/heartbeat HTTP/2.0"
25302016/11/15 19:12:18 [debug] 30743#30743: *162 generic phase: 0
25312016/11/15 19:12:18 [debug] 30743#30743: *162 rewrite phase: 1
25322016/11/15 19:12:18 [debug] 30743#30743: *162 test location: "^/widgets/$"
25332016/11/15 19:12:18 [debug] 30743#30743: *162 test location: "/"
25342016/11/15 19:12:18 [debug] 30743#30743: *162 test location: "docs/"
25352016/11/15 19:12:18 [debug] 30743#30743: *162 test location: "appname/socket"
25362016/11/15 19:12:18 [debug] 30743#30743: *162 test location: ~ "^/(index.html)?$"
25372016/11/15 19:12:18 [debug] 30743#30743: *162 test location: ~ "^/login/?$"
25382016/11/15 19:12:18 [debug] 30743#30743: *162 test location: ~ "^/(appname)"
25392016/11/15 19:12:18 [debug] 30743#30743: *162 test location: ~ "/components/(.*)"
25402016/11/15 19:12:18 [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)$"
25412016/11/15 19:12:18 [debug] 30743#30743: *162 using configuration "/"
25422016/11/15 19:12:18 [debug] 30743#30743: *162 http cl:27198 max:1073741824
25432016/11/15 19:12:18 [debug] 30743#30743: *162 rewrite phase: 3
25442016/11/15 19:12:18 [debug] 30743#30743: *162 post rewrite phase: 4
25452016/11/15 19:12:18 [debug] 30743#30743: *162 generic phase: 5
25462016/11/15 19:12:18 [debug] 30743#30743: *162 generic phase: 6
25472016/11/15 19:12:18 [debug] 30743#30743: *162 generic phase: 7
25482016/11/15 19:12:18 [debug] 30743#30743: *162 access phase: 8
25492016/11/15 19:12:18 [debug] 30743#30743: *162 access phase: 9
25502016/11/15 19:12:18 [debug] 30743#30743: *162 access phase: 10
25512016/11/15 19:12:18 [debug] 30743#30743: *162 auth request handler
25522016/11/15 19:12:18 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB6570:4096 @16
25532016/11/15 19:12:18 [debug] 30743#30743: *162 http subrequest "/authorize?"
25542016/11/15 19:12:18 [debug] 30743#30743: *162 http posted request: "/authorize?"
25552016/11/15 19:12:18 [debug] 30743#30743: *162 rewrite phase: 1
25562016/11/15 19:12:18 [debug] 30743#30743: *162 test location: "^/widgets/$"
25572016/11/15 19:12:18 [debug] 30743#30743: *162 test location: "/"
25582016/11/15 19:12:18 [debug] 30743#30743: *162 test location: "docs/"
25592016/11/15 19:12:18 [debug] 30743#30743: *162 test location: "dashboard"
25602016/11/15 19:12:18 [debug] 30743#30743: *162 test location: "authorize"
25612016/11/15 19:12:18 [debug] 30743#30743: *162 using configuration "=/authorize"
25622016/11/15 19:12:18 [debug] 30743#30743: *162 http cl:27198 max:1073741824
25632016/11/15 19:12:18 [debug] 30743#30743: *162 rewrite phase: 3
25642016/11/15 19:12:18 [debug] 30743#30743: *162 post rewrite phase: 4
25652016/11/15 19:12:18 [debug] 30743#30743: *162 generic phase: 5
25662016/11/15 19:12:18 [debug] 30743#30743: *162 generic phase: 6
25672016/11/15 19:12:18 [debug] 30743#30743: *162 generic phase: 7
25682016/11/15 19:12:18 [debug] 30743#30743: *162 http init upstream, client timer: 0
25692016/11/15 19:12:18 [debug] 30743#30743: *162 posix_memalign: 00000F0D34EB7580:4096 @16
25702016/11/15 19:12:18 [debug] 30743#30743: *162 http script copy: "X-Real-IP: "
25712016/11/15 19:12:18 [debug] 30743#30743: *162 http script var: "192.168.123.11"
25722016/11/15 19:12:18 [debug] 30743#30743: *162 http script copy: "
2573"
25742016/11/15 19:12:18 [debug] 30743#30743: *162 http script copy: "X-Forwarded-For: "
25752016/11/15 19:12:18 [debug] 30743#30743: *162 http script var: "192.168.123.11"
25762016/11/15 19:12:18 [debug] 30743#30743: *162 http script copy: "
2577"
25782016/11/15 19:12:18 [debug] 30743#30743: *162 http script copy: "Host: "
25792016/11/15 19:12:18 [debug] 30743#30743: *162 http script var: "app3.mydomain.local"
25802016/11/15 19:12:18 [debug] 30743#30743: *162 http script copy: "
2581"
25822016/11/15 19:12:18 [debug] 30743#30743: *162 http script copy: "X-NginX-Proxy: true
2583"
25842016/11/15 19:12:18 [debug] 30743#30743: *162 http script copy: "X-Client-S-DN: "
25852016/11/15 19:12:18 [debug] 30743#30743: *162 http script var: "XXX"
25862016/11/15 19:12:18 [debug] 30743#30743: *162 http script copy: "
2587"
25882016/11/15 19:12:18 [debug] 30743#30743: *162 http script copy: "X-Client-Serial: "
25892016/11/15 19:12:18 [debug] 30743#30743: *162 http script var: "XXXX"
25902016/11/15 19:12:18 [debug] 30743#30743: *162 http script copy: "
2591"
25922016/11/15 19:12:18 [debug] 30743#30743: *162 http script copy: "x-appname-uri: "
25932016/11/15 19:12:18 [debug] 30743#30743: *162 http script var: "/appname/v2/heartbeat"
25942016/11/15 19:12:18 [debug] 30743#30743: *162 http script copy: "
2595"
25962016/11/15 19:12:18 [debug] 30743#30743: *162 http script copy: "x-appname-method: "
25972016/11/15 19:12:18 [debug] 30743#30743: *162 http script var: "POST"
25982016/11/15 19:12:18 [debug] 30743#30743: *162 http script copy: "
2599"
26002016/11/15 19:12:18 [debug] 30743#30743: *162 http script copy: "Connection: close
2601"
26022016/11/15 19:12:18 [debug] 30743#30743: *162 http script copy: ""
26032016/11/15 19:12:18 [debug] 30743#30743: *162 http script copy: ""
26042016/11/15 19:12:18 [debug] 30743#30743: *162 http proxy header: "accept: application/json, text/plain, */*"
26052016/11/15 19:12:18 [debug] 30743#30743: *162 http proxy header: "origin: https://app3.mydomain.local"
26062016/11/15 19:12:18 [debug] 30743#30743: *162 http proxy header: "appname-csrf-token: YYY
26072016/11/15 19:12:18 [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"
26082016/11/15 19:12:18 [debug] 30743#30743: *162 http proxy header: "content-type: application/json;charset=UTF-8"
26092016/11/15 19:12:18 [debug] 30743#30743: *162 http proxy header: "accept-encoding: gzip, deflate, br"
26102016/11/15 19:12:18 [debug] 30743#30743: *162 http proxy header: "accept-language: en-US,en;q=0.8"
26112016/11/15 19:12:18 [debug] 30743#30743: *162 http proxy header: "cookie: XXX"
26122016/11/15 19:12:18 [debug] 30743#30743: *162 http proxy header:
2613"GET /authorize HTTP/1.1
2614X-Real-IP: 192.168.123.11
2615X-Forwarded-For: 192.168.123.11
2616Host: app3.mydomain.local
2617X-NginX-Proxy: true
2618
2619
2620x-appname-uri: /appname/v2/heartbeat
2621x-appname-method: POST
2622Connection: close
2623accept: application/json, text/plain, */*
2624origin: https://app3.mydomain.local
2625appname-csrf-token: YYY
2626user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/50.0.2661.75 Safari/537.36
2627content-type: application/json;charset=UTF-8
2628accept-encoding: gzip, deflate, br
2629accept-language: en-US,en;q=0.8