2021/04/13 13:30:38 [debug] 6938#0: accept on 0.0.0.0:9876, ready: 0 2021/04/13 13:30:38 [debug] 6938#0: posix_memalign: 0000556C850BB8D0:512 @16 2021/04/13 13:30:38 [debug] 6938#0: *64939 accept: 192.168.200.9:58032 fd:26 2021/04/13 13:30:38 [debug] 6938#0: *64939 event timer add: 26: 60000:4129392299 2021/04/13 13:30:38 [debug] 6938#0: *64939 reusable connection: 1 2021/04/13 13:30:38 [debug] 6938#0: *64939 epoll add event: fd:26 op:1 ev:80002001 2021/04/13 13:30:38 [debug] 6938#0: timer delta: 1782 2021/04/13 13:30:38 [debug] 6938#0: worker cycle 2021/04/13 13:30:38 [debug] 6938#0: epoll timer: 3647 2021/04/13 13:30:38 [debug] 6938#0: epoll: fd:26 ev:0001 d:00007F723E376871 2021/04/13 13:30:38 [debug] 6938#0: *64939 http wait request handler 2021/04/13 13:30:38 [debug] 6938#0: *64939 malloc: 0000556C85041AE0:1024 2021/04/13 13:30:38 [debug] 6938#0: *64939 recv: eof:0, avail:1 2021/04/13 13:30:38 [debug] 6938#0: *64939 recv: fd:26 1024 of 1024 2021/04/13 13:30:38 [debug] 6938#0: *64939 reusable connection: 0 2021/04/13 13:30:38 [debug] 6938#0: *64939 posix_memalign: 0000556C850B9B20:4096 @16 2021/04/13 13:30:38 [debug] 6938#0: *64939 http process request line 2021/04/13 13:30:38 [debug] 6938#0: *64939 http request line: "POST / HTTP/1.1" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http uri: "/" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http args: "" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http exten: "" 2021/04/13 13:30:38 [debug] 6938#0: *64939 posix_memalign: 0000556C850B29F0:4096 @16 2021/04/13 13:30:38 [debug] 6938#0: *64939 http process request header line 2021/04/13 13:30:38 [debug] 6938#0: *64939 http header: "Content-Type: application/json" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http header: "Authorization: Basic aHJ1zXVsE7SerT2we3512SCDEdrpsjP2qadrfbgTRESsjieAQ1LmnQWDxsFGY==" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http header: "User-Agent: Java/1.8.0_191" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http header: "Host: 192.168.100.123:9876" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http header: "Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http header: "Connection: keep-alive" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http header: "Content-Length: 4139" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http header done 2021/04/13 13:30:38 [debug] 6938#0: *64939 event timer del: 26: 4129392299 2021/04/13 13:30:38 [debug] 6938#0: *64939 generic phase: 0 2021/04/13 13:30:38 [debug] 6938#0: *64939 rewrite phase: 1 2021/04/13 13:30:38 [debug] 6938#0: *64939 test location: "/" 2021/04/13 13:30:38 [debug] 6938#0: *64939 using configuration "/" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http cl:4139 max:26214400 2021/04/13 13:30:38 [debug] 6938#0: *64939 rewrite phase: 3 2021/04/13 13:30:38 [debug] 6938#0: *64939 post rewrite phase: 4 2021/04/13 13:30:38 [debug] 6938#0: *64939 generic phase: 5 2021/04/13 13:30:38 [debug] 6938#0: *64939 generic phase: 6 2021/04/13 13:30:38 [debug] 6938#0: *64939 generic phase: 7 2021/04/13 13:30:38 [debug] 6938#0: *64939 generic phase: 8 2021/04/13 13:30:38 [debug] 6938#0: *64939 access phase: 9 2021/04/13 13:30:38 [debug] 6938#0: *64939 access phase: 10 2021/04/13 13:30:38 [debug] 6938#0: *64939 access phase: 11 2021/04/13 13:30:38 [debug] 6938#0: *64939 post access phase: 12 2021/04/13 13:30:38 [debug] 6938#0: *64939 generic phase: 13 2021/04/13 13:30:38 [debug] 6938#0: *64939 generic phase: 14 2021/04/13 13:30:38 [debug] 6938#0: *64939 http client request body preread 600 2021/04/13 13:30:38 [debug] 6938#0: *64939 http request body content length filter 2021/04/13 13:30:38 [debug] 6938#0: *64939 http body new buf t:1 f:0 0000556C85041C88, pos 0000556C85041C88, size: 600 file: 0, size: 0 2021/04/13 13:30:38 [debug] 6938#0: *64939 posix_memalign: 0000556C850B3A00:4096 @16 2021/04/13 13:30:38 [debug] 6938#0: *64939 http read client request body 2021/04/13 13:30:38 [debug] 6938#0: *64939 recv: eof:0, avail:1 2021/04/13 13:30:38 [debug] 6938#0: *64939 recv: fd:26 3539 of 3539 2021/04/13 13:30:38 [debug] 6938#0: *64939 http client request body recv 3539 2021/04/13 13:30:38 [debug] 6938#0: *64939 http body new buf t:1 f:0 0000556C850B3A20, pos 0000556C850B3A20, size: 3539 file: 0, size: 0 2021/04/13 13:30:38 [debug] 6938#0: *64939 http client request body rest 0 2021/04/13 13:30:38 [debug] 6938#0: *64939 http init upstream, client timer: 0 2021/04/13 13:30:38 [debug] 6938#0: *64939 epoll add event: fd:26 op:3 ev:80002005 2021/04/13 13:30:38 [debug] 6938#0: *64939 http script copy: "Host" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http script var: "mysite.api.prod.vijay.com" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http script copy: "Connection" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http script copy: "close" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http script copy: "Content-Length" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http script var: "4139" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http script copy: "" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http proxy header: "Content-Type: application/json" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http proxy header: "Authorization: Basic aHJ1zXVsE7SerT2we3512SCDEdrpsjP2qadrfbgTRESsjieAQ1LmnQWDxsFGY==" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http proxy header: "User-Agent: Java/1.8.0_191" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http proxy header: "Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http proxy header: 2021/04/13 13:30:38 [debug] 6938#0: *64939 http cleanup add: 0000556C850B3708 2021/04/13 13:30:38 [debug] 6938#0: *64939 get rr peer, try: 4 2021/04/13 13:30:38 [debug] 6938#0: *64939 get rr peer, current: 0000556C85071AA0 -2 2021/04/13 13:30:38 [debug] 6938#0: *64939 stream socket 27 2021/04/13 13:30:38 [debug] 6938#0: *64939 epoll add connection: fd:27 ev:80002005 2021/04/13 13:30:38 [debug] 6938#0: *64939 connect to 99.86.116.51:443, fd:27 #64940 2021/04/13 13:30:38 [debug] 6938#0: *64939 http upstream connect: -2 2021/04/13 13:30:38 [debug] 6938#0: *64939 posix_memalign: 0000556C85042D90:128 @16 2021/04/13 13:30:38 [debug] 6938#0: *64939 event timer add: 27: 60000:4129392300 2021/04/13 13:30:38 [debug] 6938#0: *64939 http finalize request: -4, "/?" a:1, c:2 2021/04/13 13:30:38 [debug] 6938#0: *64939 http request count:2 blk:0 2021/04/13 13:30:38 [debug] 6938#0: timer delta: 1 2021/04/13 13:30:38 [debug] 6938#0: worker cycle 2021/04/13 13:30:38 [debug] 6938#0: epoll timer: 3646 2021/04/13 13:30:38 [debug] 6938#0: epoll: fd:26 ev:0004 d:00007F723E376871 2021/04/13 13:30:38 [debug] 6938#0: *64939 http run request: "/?" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http upstream check client, write event:1, "/" 2021/04/13 13:30:38 [debug] 6938#0: timer delta: 0 2021/04/13 13:30:38 [debug] 6938#0: worker cycle 2021/04/13 13:30:38 [debug] 6938#0: epoll timer: 3646 2021/04/13 13:30:38 [debug] 6938#0: epoll: fd:27 ev:0004 d:00007F723E375880 2021/04/13 13:30:38 [debug] 6938#0: *64939 http upstream request: "/?" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http upstream send request handler 2021/04/13 13:30:38 [debug] 6938#0: *64939 malloc: 0000556C850B9120:88 2021/04/13 13:30:38 [debug] 6938#0: *64939 upstream SSL server name: "mysite.api.prod.vijay.com" 2021/04/13 13:30:38 [debug] 6938#0: *64939 set session: 0000556C85020940 2021/04/13 13:30:38 [debug] 6938#0: *64939 tcp_nodelay 2021/04/13 13:30:38 [debug] 6938#0: *64939 SSL_do_handshake: -1 2021/04/13 13:30:38 [debug] 6938#0: *64939 SSL_get_error: 2 2021/04/13 13:30:38 [debug] 6938#0: timer delta: 1 2021/04/13 13:30:38 [debug] 6938#0: worker cycle 2021/04/13 13:30:38 [debug] 6938#0: epoll timer: 3645 2021/04/13 13:30:38 [debug] 6938#0: epoll: fd:27 ev:0005 d:00007F723E375880 2021/04/13 13:30:38 [debug] 6938#0: *64939 SSL handshake handler: 0 2021/04/13 13:30:38 [debug] 6938#0: *64939 SSL_do_handshake: 1 2021/04/13 13:30:38 [debug] 6938#0: *64939 SSL: TLSv1.2, cipher: "ECDHE-RSA-AES128-GCM-SHA256 TLSv1.2 Kx=ECDH Au=RSA Enc=AESGCM(128) Mac=AEAD" 2021/04/13 13:30:38 [debug] 6938#0: *64939 SSL reused session 2021/04/13 13:30:38 [debug] 6938#0: *64939 http upstream ssl handshake: "/?" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http upstream send request 2021/04/13 13:30:38 [debug] 6938#0: *64939 http upstream send request body 2021/04/13 13:30:38 [debug] 6938#0: *64939 chain writer buf fl:0 s:454 2021/04/13 13:30:38 [debug] 6938#0: *64939 chain writer buf fl:0 s:600 2021/04/13 13:30:38 [debug] 6938#0: *64939 chain writer buf fl:1 s:3539 2021/04/13 13:30:38 [debug] 6938#0: *64939 chain writer in: 0000556C850B37A0 2021/04/13 13:30:38 [debug] 6938#0: *64939 malloc: 0000556C8503A6B0:80 2021/04/13 13:30:38 [debug] 6938#0: *64939 malloc: 0000556C850BBE50:16384 2021/04/13 13:30:38 [debug] 6938#0: *64939 SSL buf copy: 454 2021/04/13 13:30:38 [debug] 6938#0: *64939 SSL buf copy: 600 2021/04/13 13:30:38 [debug] 6938#0: *64939 SSL buf copy: 3539 2021/04/13 13:30:38 [debug] 6938#0: *64939 SSL to write: 4593 2021/04/13 13:30:38 [debug] 6938#0: *64939 SSL_write: 4593 2021/04/13 13:30:38 [debug] 6938#0: *64939 chain writer out: 0000000000000000 2021/04/13 13:30:38 [debug] 6938#0: *64939 event timer del: 27: 4129392300 2021/04/13 13:30:38 [debug] 6938#0: *64939 event timer add: 27: 60000:4129392303 2021/04/13 13:30:38 [debug] 6938#0: *64939 http upstream process header 2021/04/13 13:30:38 [debug] 6938#0: *64939 malloc: 0000556C850B62A0:4096 2021/04/13 13:30:38 [debug] 6938#0: *64939 SSL_read: -1 2021/04/13 13:30:38 [debug] 6938#0: *64939 SSL_get_error: 2 2021/04/13 13:30:38 [debug] 6938#0: *64939 http upstream request: "/?" 2021/04/13 13:30:38 [debug] 6938#0: *64939 http upstream dummy handler 2021/04/13 13:30:38 [debug] 6938#0: timer delta: 2 2021/04/13 13:30:38 [debug] 6938#0: worker cycle 2021/04/13 13:30:38 [debug] 6938#0: epoll timer: 3643 2021/04/13 13:30:38 [debug] 6938#0: epoll: fd:3 ev:2005 d:00007F723E376F01 2021/04/13 13:30:38 [debug] 6938#0: *64929 http run request: "/?" 2021/04/13 13:30:38 [debug] 6938#0: *64929 http upstream check client, write event:0, "/" 2021/04/13 13:30:38 [info] 6938#0: *64929 epoll_wait() reported that client prematurely closed connection, so upstream connection is closed too while reading response header from upstream, client: 192.168.200.9, server: 192.168.100.123, request: "POST / HTTP/1.1", upstream: "https://192.6.136.18:443/speed/send", host: "192.168.100.123:9876" 2021/04/13 13:30:38 [debug] 6938#0: *64929 finalize http upstream request: 499 2021/04/13 13:30:38 [debug] 6938#0: *64929 finalize http proxy request 2021/04/13 13:30:38 [debug] 6938#0: *64929 free rr peer 4 0 2021/04/13 13:30:38 [debug] 6938#0: *64929 SSL_shutdown: 1 2021/04/13 13:30:38 [debug] 6938#0: *64929 close http upstream connection: 9 2021/04/13 13:30:38 [debug] 6938#0: *64929 free: 0000556C8501C7F0 2021/04/13 13:30:38 [debug] 6938#0: *64929 free: 0000556C850355D0 2021/04/13 13:30:38 [debug] 6938#0: *64929 free: 0000556C85037FB0 2021/04/13 13:30:38 [debug] 6938#0: *64929 free: 0000556C8501A200, unused: 0 2021/04/13 13:30:38 [debug] 6938#0: *64929 event timer del: 9: 4129385076 2021/04/13 13:30:38 [debug] 6938#0: *64929 reusable connection: 0 2021/04/13 13:30:38 [debug] 6938#0: *64929 http finalize request: 499, "/?" a:1, c:1 2021/04/13 13:30:38 [debug] 6938#0: *64929 http terminate request count:1 2021/04/13 13:30:38 [debug] 6938#0: *64929 http terminate cleanup count:1 blk:0 2021/04/13 13:30:38 [debug] 6938#0: *64929 http posted request: "/?" 2021/04/13 13:30:38 [debug] 6938#0: *64929 http terminate handler count:1 2021/04/13 13:30:38 [debug] 6938#0: *64929 http request count:1 blk:0 2021/04/13 13:30:38 [debug] 6938#0: *64929 http close request 2021/04/13 13:30:38 [debug] 6938#0: *64929 http log handler 2021/04/13 13:30:38 [debug] 6938#0: *64929 free: 0000556C84FED250 2021/04/13 13:30:38 [debug] 6938#0: *64929 free: 0000556C85038680, unused: 0 2021/04/13 13:30:38 [debug] 6938#0: *64929 free: 0000556C85021830, unused: 22 2021/04/13 13:30:38 [debug] 6938#0: *64929 free: 0000556C84FE5230, unused: 255 2021/04/13 13:30:38 [debug] 6938#0: *64929 close http connection: 3 2021/04/13 13:30:38 [debug] 6938#0: *64929 reusable connection: 0 2021/04/13 13:30:38 [debug] 6938#0: *64929 free: 0000556C850340D0 2021/04/13 13:30:38 [debug] 6938#0: *64929 free: 0000556C84FF23F0, unused: 136 2021/04/13 13:30:38 [debug] 6938#0: timer delta: 776 2021/04/13 13:30:38 [debug] 6938#0: worker cycle 2021/04/13 13:30:38 [debug] 6938#0: epoll timer: 2867 2021/04/13 13:30:39 [debug] 6938#0: epoll: fd:17 ev:2005 d:00007F723E376C31 2021/04/13 13:30:39 [debug] 6938#0: *64931 http run request: "/?" 2021/04/13 13:30:39 [debug] 6938#0: *64931 http upstream check client, write event:0, "/" 2021/04/13 13:30:39 [info] 6938#0: *64931 epoll_wait() reported that client prematurely closed connection, so upstream connection is closed too while reading response header from upstream, client: 192.168.200.9, server: 192.168.100.123, request: "POST / HTTP/1.1", upstream: "https://192.6.136.18:443/speed/send", host: "192.168.100.123:9876" 2021/04/13 13:30:39 [debug] 6938#0: *64931 finalize http upstream request: 499 2021/04/13 13:30:39 [debug] 6938#0: *64931 finalize http proxy request 2021/04/13 13:30:39 [debug] 6938#0: *64931 free rr peer 4 0 2021/04/13 13:30:39 [debug] 6938#0: *64931 SSL_shutdown: 1 2021/04/13 13:30:39 [debug] 6938#0: *64931 close http upstream connection: 20 2021/04/13 13:30:39 [debug] 6938#0: *64931 free: 0000556C850AE9E0 2021/04/13 13:30:39 [debug] 6938#0: *64931 free: 0000556C85035420 2021/04/13 13:30:39 [debug] 6938#0: *64931 free: 0000556C8503C7A0 2021/04/13 13:30:39 [debug] 6938#0: *64931 free: 0000556C84FF18E0, unused: 0 2021/04/13 13:30:39 [debug] 6938#0: *64931 event timer del: 20: 4129385215 2021/04/13 13:30:39 [debug] 6938#0: *64931 reusable connection: 0 2021/04/13 13:30:39 [debug] 6938#0: *64931 http finalize request: 499, "/?" a:1, c:1 2021/04/13 13:30:39 [debug] 6938#0: *64931 http terminate request count:1 2021/04/13 13:30:39 [debug] 6938#0: *64931 http terminate cleanup count:1 blk:0 2021/04/13 13:30:39 [debug] 6938#0: *64931 http posted request: "/?" 2021/04/13 13:30:39 [debug] 6938#0: *64931 http terminate handler count:1 2021/04/13 13:30:39 [debug] 6938#0: *64931 http request count:1 blk:0 2021/04/13 13:30:39 [debug] 6938#0: *64931 http close request 2021/04/13 13:30:39 [debug] 6938#0: *64931 http log handler 2021/04/13 13:30:39 [debug] 6938#0: *64931 free: 0000556C850AA120 2021/04/13 13:30:39 [debug] 6938#0: *64931 free: 0000556C84FF7060, unused: 0 2021/04/13 13:30:39 [debug] 6938#0: *64931 free: 0000556C850436B0, unused: 22 2021/04/13 13:30:39 [debug] 6938#0: *64931 free: 0000556C84FF2CE0, unused: 493 2021/04/13 13:30:39 [debug] 6938#0: *64931 close http connection: 17 2021/04/13 13:30:39 [debug] 6938#0: *64931 reusable connection: 0 2021/04/13 13:30:39 [debug] 6938#0: *64931 free: 0000556C84FF6810 2021/04/13 13:30:39 [debug] 6938#0: *64931 free: 0000556C84FF6030, unused: 136 2021/04/13 13:30:39 [debug] 6938#0: timer delta: 139 2021/04/13 13:30:39 [debug] 6938#0: worker cycle 2021/04/13 13:30:39 [debug] 6938#0: epoll timer: 2728 2021/04/13 13:30:39 [debug] 6938#0: epoll: fd:8 ev:0001 d:00007F723E3751F0