Built nginx with nchan only, opened the HTML client, sent curl command and shutdown nginx. Repeated the same with nchan+pagespeed built.
### nchan only ``` 2018/09/04 02:02:11 [debug] 12631#12631: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:02:11 [debug] 12632#12632: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:02:11 [debug] 12633#12633: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:02:11 [debug] 12634#12634: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:02:11 [debug] 12635#12635: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:02:11 [debug] 12636#12636: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:02:11 [debug] 12637#12637: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:02:11 [debug] 12638#12638: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:02:11 [debug] 12639#12639: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:02:11 [debug] 12640#12640: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:02:11 [debug] 12642#12642: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:02:11 [debug] 12641#12641: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:02:13 [debug] 12631#12631: accept on 0.0.0.0:80, ready: 0 2018/09/04 02:02:13 [debug] 12631#12631: posix_memalign: 00005631FEE73D80:512 @16 2018/09/04 02:02:13 [debug] 12631#12631: *1 accept: 127.0.0.1:29965 fd:46 2018/09/04 02:02:13 [debug] 12631#12631: *1 event timer add: 46: 60000:352348155 2018/09/04 02:02:13 [debug] 12631#12631: *1 reusable connection: 1 2018/09/04 02:02:13 [debug] 12631#12631: *1 epoll add event: fd:46 op:1 ev:80002001 2018/09/04 02:02:13 [debug] 12631#12631: *1 http wait request handler 2018/09/04 02:02:13 [debug] 12631#12631: *1 malloc: 00005631FEE5CC90:1024 2018/09/04 02:02:13 [debug] 12631#12631: *1 recv: eof:0, avail:1 2018/09/04 02:02:13 [debug] 12631#12631: *1 recv: fd:46 925 of 1024 2018/09/04 02:02:13 [debug] 12631#12631: *1 reusable connection: 0 2018/09/04 02:02:13 [debug] 12631#12631: *1 posix_memalign: 00005631FEE84C00:4096 @16 2018/09/04 02:02:13 [debug] 12631#12631: *1 http process request line 2018/09/04 02:02:13 [debug] 12631#12631: *1 http request line: "GET /ws.html HTTP/1.1" 2018/09/04 02:02:13 [debug] 12631#12631: *1 http uri: "/ws.html" 2018/09/04 02:02:13 [debug] 12631#12631: *1 http args: "" 2018/09/04 02:02:13 [debug] 12631#12631: *1 http exten: "html" 2018/09/04 02:02:13 [debug] 12631#12631: *1 posix_memalign: 00005631FEE79B00:4096 @16 2018/09/04 02:02:13 [debug] 12631#12631: *1 http process request header line 2018/09/04 02:02:13 [debug] 12631#12631: *1 http header: "Host: localhost" 2018/09/04 02:02:13 [debug] 12631#12631: *1 http header: "Connection: keep-alive" 2018/09/04 02:02:13 [debug] 12631#12631: *1 http header: "Cache-Control: max-age=0" 2018/09/04 02:02:13 [debug] 12631#12631: *1 http header: "Upgrade-Insecure-Requests: 1" 2018/09/04 02:02:13 [debug] 12631#12631: *1 http header: "User-Agent: user-agent" 2018/09/04 02:02:13 [debug] 12631#12631: *1 http header: "Accept: text/html,application/xhtml+xml,application/xml;q=0.9,image/webp,image/apng,*/*;q=0.8" 2018/09/04 02:02:13 [debug] 12631#12631: *1 http header: "Accept-Encoding: gzip, deflate" 2018/09/04 02:02:13 [debug] 12631#12631: *1 http header: "Accept-Language: en-US,en" 2018/09/04 02:02:13 [debug] 12631#12631: *1 http header: "If-None-Match: "5b8de380-2b0"" 2018/09/04 02:02:13 [debug] 12631#12631: *1 http header: "If-Modified-Since: Tue, 04 Sep 2018 01:44:32 UTC" 2018/09/04 02:02:13 [debug] 12631#12631: *1 http header done 2018/09/04 02:02:13 [debug] 12631#12631: *1 event timer del: 46: 352348155 2018/09/04 02:02:13 [debug] 12631#12631: *1 generic phase: 0 2018/09/04 02:02:13 [debug] 12631#12631: *1 rewrite phase: 1 2018/09/04 02:02:13 [debug] 12631#12631: *1 test location: "/" 2018/09/04 02:02:13 [debug] 12631#12631: *1 test location: "sub" 2018/09/04 02:02:13 [debug] 12631#12631: *1 using configuration "/" 2018/09/04 02:02:13 [debug] 12631#12631: *1 http cl:-1 max:1048576 2018/09/04 02:02:13 [debug] 12631#12631: *1 rewrite phase: 3 2018/09/04 02:02:13 [debug] 12631#12631: *1 post rewrite phase: 4 2018/09/04 02:02:13 [debug] 12631#12631: *1 generic phase: 5 2018/09/04 02:02:13 [debug] 12631#12631: *1 generic phase: 6 2018/09/04 02:02:13 [debug] 12631#12631: *1 generic phase: 7 2018/09/04 02:02:13 [debug] 12631#12631: *1 access phase: 8 2018/09/04 02:02:13 [debug] 12631#12631: *1 access phase: 9 2018/09/04 02:02:13 [debug] 12631#12631: *1 access phase: 10 2018/09/04 02:02:13 [debug] 12631#12631: *1 post access phase: 11 2018/09/04 02:02:13 [debug] 12631#12631: *1 generic phase: 12 2018/09/04 02:02:13 [debug] 12631#12631: *1 generic phase: 13 2018/09/04 02:02:13 [debug] 12631#12631: *1 content phase: 14 2018/09/04 02:02:13 [debug] 12631#12631: *1 content phase: 15 2018/09/04 02:02:13 [debug] 12631#12631: *1 content phase: 16 2018/09/04 02:02:13 [debug] 12631#12631: *1 content phase: 17 2018/09/04 02:02:13 [debug] 12631#12631: *1 content phase: 18 2018/09/04 02:02:13 [debug] 12631#12631: *1 http filename: "/var/www/ws.html" 2018/09/04 02:02:13 [debug] 12631#12631: *1 add cleanup: 00005631FEE85A40 2018/09/04 02:02:13 [debug] 12631#12631: *1 http static fd: 47 2018/09/04 02:02:13 [debug] 12631#12631: *1 http set discard body 2018/09/04 02:02:13 [debug] 12631#12631: *1 http ims:1536025472 lm:1536025472 2018/09/04 02:02:13 [debug] 12631#12631: *1 http im:""5b8de380-2b0"" etag:"5b8de380-2b0" 2018/09/04 02:02:13 [debug] 12631#12631: *1 HTTP/1.1 304 Not Modified Server: nginx/1.15.4 Date: Tue, 04 Sep 2018 02:02:13 UTC Last-Modified: Tue, 04 Sep 2018 01:44:32 UTC Connection: keep-alive ETag: "5b8de380-2b0" 2018/09/04 02:02:13 [debug] 12631#12631: *1 write new buf t:1 f:0 00005631FEE79F30, pos 00005631FEE79F30, size: 180 file: 0, size: 0 2018/09/04 02:02:13 [debug] 12631#12631: *1 http write filter: l:1 f:0 s:180 2018/09/04 02:02:13 [debug] 12631#12631: *1 http write filter limit 0 2018/09/04 02:02:13 [debug] 12631#12631: *1 writev: 180 of 180 2018/09/04 02:02:13 [debug] 12631#12631: *1 http write filter 0000000000000000 2018/09/04 02:02:13 [debug] 12631#12631: *1 http finalize request: 0, "/ws.html?" a:1, c:1 2018/09/04 02:02:13 [debug] 12631#12631: *1 set http keepalive handler 2018/09/04 02:02:13 [debug] 12631#12631: *1 http close request 2018/09/04 02:02:13 [debug] 12631#12631: *1 http log handler 2018/09/04 02:02:13 [debug] 12631#12631: *1 run cleanup: 00005631FEE85A40 2018/09/04 02:02:13 [debug] 12631#12631: *1 file cleanup: fd:47 2018/09/04 02:02:13 [debug] 12631#12631: *1 free: 00005631FEE84C00, unused: 24 2018/09/04 02:02:13 [debug] 12631#12631: *1 free: 00005631FEE79B00, unused: 2608 2018/09/04 02:02:13 [debug] 12631#12631: *1 free: 00005631FEE5CC90 2018/09/04 02:02:13 [debug] 12631#12631: *1 hc free: 0000000000000000 2018/09/04 02:02:13 [debug] 12631#12631: *1 hc busy: 0000000000000000 0 2018/09/04 02:02:13 [debug] 12631#12631: *1 tcp_nodelay 2018/09/04 02:02:13 [debug] 12631#12631: *1 reusable connection: 1 2018/09/04 02:02:13 [debug] 12631#12631: *1 event timer add: 46: 75000:352363155 2018/09/04 02:02:13 [debug] 12631#12631: accept on 0.0.0.0:80, ready: 0 2018/09/04 02:02:13 [debug] 12631#12631: posix_memalign: 00005631FEE5C280:512 @16 2018/09/04 02:02:13 [debug] 12631#12631: *2 accept: 127.0.0.1:27591 fd:47 2018/09/04 02:02:13 [debug] 12631#12631: *2 event timer add: 47: 60000:352348299 2018/09/04 02:02:13 [debug] 12631#12631: *2 reusable connection: 1 2018/09/04 02:02:13 [debug] 12631#12631: *2 epoll add event: fd:47 op:1 ev:80002001 2018/09/04 02:02:13 [debug] 12631#12631: *2 http wait request handler 2018/09/04 02:02:13 [debug] 12631#12631: *2 malloc: 00005631FEE5CC90:1024 2018/09/04 02:02:13 [debug] 12631#12631: *2 recv: eof:0, avail:1 2018/09/04 02:02:13 [debug] 12631#12631: *2 recv: fd:47 928 of 1024 2018/09/04 02:02:13 [debug] 12631#12631: *2 reusable connection: 0 2018/09/04 02:02:13 [debug] 12631#12631: *2 posix_memalign: 00005631FEE84C00:4096 @16 2018/09/04 02:02:13 [debug] 12631#12631: *2 http process request line 2018/09/04 02:02:13 [debug] 12631#12631: *2 http request line: "GET /sub?1234 HTTP/1.1" 2018/09/04 02:02:13 [debug] 12631#12631: *2 http uri: "/sub" 2018/09/04 02:02:13 [debug] 12631#12631: *2 http args: "1234" 2018/09/04 02:02:13 [debug] 12631#12631: *2 http exten: "" 2018/09/04 02:02:13 [debug] 12631#12631: *2 posix_memalign: 00005631FEE79B00:4096 @16 2018/09/04 02:02:13 [debug] 12631#12631: *2 http process request header line 2018/09/04 02:02:13 [debug] 12631#12631: *2 http header: "Host: localhost" 2018/09/04 02:02:13 [debug] 12631#12631: *2 http header: "Connection: Upgrade" 2018/09/04 02:02:13 [debug] 12631#12631: *2 http header: "Pragma: no-cache" 2018/09/04 02:02:13 [debug] 12631#12631: *2 http header: "Cache-Control: no-cache" 2018/09/04 02:02:13 [debug] 12631#12631: *2 http header: "User-Agent: user-agent" 2018/09/04 02:02:13 [debug] 12631#12631: *2 http header: "Upgrade: websocket" 2018/09/04 02:02:13 [debug] 12631#12631: *2 http header: "Origin: http://localhost"; 2018/09/04 02:02:13 [debug] 12631#12631: *2 http header: "Sec-WebSocket-Version: 13" 2018/09/04 02:02:13 [debug] 12631#12631: *2 http header: "Accept-Encoding: gzip, deflate" 2018/09/04 02:02:13 [debug] 12631#12631: *2 http header: "Accept-Language: en-US,en" 2018/09/04 02:02:13 [debug] 12631#12631: *2 http header: "Sec-WebSocket-Key: 8Sokigq5GBL5YB9hlnTAhQ==" 2018/09/04 02:02:13 [debug] 12631#12631: *2 http header: "Sec-WebSocket-Extensions: permessage-deflate; client_max_window_bits" 2018/09/04 02:02:13 [debug] 12631#12631: *2 http header done 2018/09/04 02:02:13 [debug] 12631#12631: *2 event timer del: 47: 352348299 2018/09/04 02:02:13 [debug] 12631#12631: *2 generic phase: 0 2018/09/04 02:02:13 [debug] 12631#12631: *2 rewrite phase: 1 2018/09/04 02:02:13 [debug] 12631#12631: *2 test location: "/" 2018/09/04 02:02:13 [debug] 12631#12631: *2 test location: "sub" 2018/09/04 02:02:13 [debug] 12631#12631: *2 using configuration "=/sub" 2018/09/04 02:02:13 [debug] 12631#12631: *2 http cl:-1 max:1048576 2018/09/04 02:02:13 [debug] 12631#12631: *2 rewrite phase: 3 2018/09/04 02:02:13 [debug] 12631#12631: *2 post rewrite phase: 4 2018/09/04 02:02:13 [debug] 12631#12631: *2 generic phase: 5 2018/09/04 02:02:13 [debug] 12631#12631: *2 generic phase: 6 2018/09/04 02:02:13 [debug] 12631#12631: *2 generic phase: 7 2018/09/04 02:02:13 [debug] 12631#12631: *2 access phase: 8 2018/09/04 02:02:13 [debug] 12631#12631: *2 access phase: 9 2018/09/04 02:02:13 [debug] 12631#12631: *2 access phase: 10 2018/09/04 02:02:13 [debug] 12631#12631: *2 post access phase: 11 2018/09/04 02:02:13 [debug] 12631#12631: *2 generic phase: 12 2018/09/04 02:02:13 [debug] 12631#12631: *2 generic phase: 13 2018/09/04 02:02:13 [debug] 12631#12631: *2 http cleanup add: 00005631FEE85B50 2018/09/04 02:02:13 [debug] 12631#12631: *2 HTTP/1.1 101 Switching Protocols Server: nginx/1.15.4 Date: Tue, 04 Sep 2018 02:02:13 UTC Content-Length: 0 Connection: upgrade Sec-WebSocket-Extensions: permessage-deflate Access-Control-Allow-Credentials: true Access-Control-Allow-Origin: http://localhost Sec-WebSocket-Accept: 4Cc4OsQhP6Y2eDs9m/9n8+fw7uE= Upgrade: websocket 2018/09/04 02:02:13 [debug] 12631#12631: *2 write new buf t:1 f:0 00005631FEE79F58, pos 00005631FEE79F58, size: 346 file: 0, size: 0 2018/09/04 02:02:13 [debug] 12631#12631: *2 http write filter: l:1 f:0 s:346 2018/09/04 02:02:13 [debug] 12631#12631: *2 http write filter limit 0 2018/09/04 02:02:13 [debug] 12631#12631: *2 writev: 346 of 346 2018/09/04 02:02:13 [debug] 12631#12631: *2 http write filter 0000000000000000 2018/09/04 02:02:13 [debug] 12631#12631: *2 http finalize request: -4, "/sub?1234" a:1, c:2 2018/09/04 02:02:13 [debug] 12631#12631: *2 http request count:2 blk:0 2018/09/04 02:02:14 [debug] 12631#12631: accept on 0.0.0.0:80, ready: 0 2018/09/04 02:02:14 [debug] 12631#12631: posix_memalign: 00005631FEE72420:512 @16 2018/09/04 02:02:14 [debug] 12631#12631: *3 accept: 127.0.0.1:7528 fd:48 2018/09/04 02:02:14 [debug] 12631#12631: *3 event timer add: 48: 60000:352349935 2018/09/04 02:02:14 [debug] 12631#12631: *3 reusable connection: 1 2018/09/04 02:02:14 [debug] 12631#12631: *3 epoll add event: fd:48 op:1 ev:80002001 2018/09/04 02:02:14 [debug] 12631#12631: *3 http wait request handler 2018/09/04 02:02:14 [debug] 12631#12631: *3 malloc: 00005631FEE98410:1024 2018/09/04 02:02:14 [debug] 12631#12631: *3 recv: eof:0, avail:1 2018/09/04 02:02:14 [debug] 12631#12631: *3 recv: fd:48 170 of 1024 2018/09/04 02:02:14 [debug] 12631#12631: *3 reusable connection: 0 2018/09/04 02:02:14 [debug] 12631#12631: *3 posix_memalign: 00005631FEE7AB10:4096 @16 2018/09/04 02:02:14 [debug] 12631#12631: *3 http process request line 2018/09/04 02:02:14 [debug] 12631#12631: *3 http request line: "POST /pub?1234 HTTP/1.1" 2018/09/04 02:02:14 [debug] 12631#12631: *3 http uri: "/pub" 2018/09/04 02:02:14 [debug] 12631#12631: *3 http args: "1234" 2018/09/04 02:02:14 [debug] 12631#12631: *3 http exten: "" 2018/09/04 02:02:14 [debug] 12631#12631: *3 posix_memalign: 00005631FEE7BB20:4096 @16 2018/09/04 02:02:14 [debug] 12631#12631: *3 http process request header line 2018/09/04 02:02:14 [debug] 12631#12631: *3 http header: "Host: localhost" 2018/09/04 02:02:14 [debug] 12631#12631: *3 http header: "User-Agent: curl/7.58.0" 2018/09/04 02:02:14 [debug] 12631#12631: *3 http header: "Accept: */*" 2018/09/04 02:02:14 [debug] 12631#12631: *3 http header: "Content-Length: 13" 2018/09/04 02:02:14 [debug] 12631#12631: *3 http header: "Content-Type: application/x-www-form-urlencoded" 2018/09/04 02:02:14 [debug] 12631#12631: *3 http header done 2018/09/04 02:02:14 [debug] 12631#12631: *3 event timer del: 48: 352349935 2018/09/04 02:02:14 [debug] 12631#12631: *3 generic phase: 0 2018/09/04 02:02:14 [debug] 12631#12631: *3 rewrite phase: 1 2018/09/04 02:02:14 [debug] 12631#12631: *3 test location: "/" 2018/09/04 02:02:14 [debug] 12631#12631: *3 test location: "sub" 2018/09/04 02:02:14 [debug] 12631#12631: *3 test location: "pub" 2018/09/04 02:02:14 [debug] 12631#12631: *3 using configuration "=/pub" 2018/09/04 02:02:14 [debug] 12631#12631: *3 http cl:13 max:1048576 2018/09/04 02:02:14 [debug] 12631#12631: *3 rewrite phase: 3 2018/09/04 02:02:14 [debug] 12631#12631: *3 post rewrite phase: 4 2018/09/04 02:02:14 [debug] 12631#12631: *3 generic phase: 5 2018/09/04 02:02:14 [debug] 12631#12631: *3 generic phase: 6 2018/09/04 02:02:14 [debug] 12631#12631: *3 generic phase: 7 2018/09/04 02:02:14 [debug] 12631#12631: *3 access phase: 8 2018/09/04 02:02:14 [debug] 12631#12631: *3 access phase: 9 2018/09/04 02:02:14 [debug] 12631#12631: *3 access phase: 10 2018/09/04 02:02:14 [debug] 12631#12631: *3 post access phase: 11 2018/09/04 02:02:14 [debug] 12631#12631: *3 generic phase: 12 2018/09/04 02:02:14 [debug] 12631#12631: *3 generic phase: 13 2018/09/04 02:02:14 [debug] 12631#12631: *3 http client request body preread 13 2018/09/04 02:02:14 [debug] 12631#12631: *3 http request body content length filter 2018/09/04 02:02:14 [debug] 12631#12631: *3 http body new buf t:1 f:0 00005631FEE984AD, pos 00005631FEE984AD, size: 13 file: 0, size: 0 2018/09/04 02:02:14 [debug] 12631#12631: *3 http cleanup add: 00005631FEE7BAB0 2018/09/04 02:02:14 [debug] 12631#12631: *3 http finalize request: -4, "/pub?1234" a:1, c:2 2018/09/04 02:02:14 [debug] 12631#12631: *3 http request count:2 blk:0 2018/09/04 02:02:14 [debug] 12631#12631: *2 http output filter "/sub?1234" 2018/09/04 02:02:14 [debug] 12631#12631: *2 http copy filter: "/sub?1234" 2018/09/04 02:02:14 [debug] 12631#12631: *2 http postpone filter "/sub?1234" 00005631FEE7A0C8 2018/09/04 02:02:14 [debug] 12631#12631: *2 write new buf t:0 f:0 00005631FEE7A1A8, pos 00005631FEE7A1A8, size: 2 file: 0, size: 0 2018/09/04 02:02:14 [debug] 12631#12631: *2 write new buf t:1 f:0 00007FC82C8170E9, pos 00007FC82C8170E9, size: 13 file: 0, size: 0 2018/09/04 02:02:14 [debug] 12631#12631: *2 http write filter: l:1 f:0 s:15 2018/09/04 02:02:14 [debug] 12631#12631: *2 http write filter limit 0 2018/09/04 02:02:14 [debug] 12631#12631: *2 writev: 15 of 15 2018/09/04 02:02:14 [debug] 12631#12631: *2 http write filter 0000000000000000 2018/09/04 02:02:14 [debug] 12631#12631: *2 http copy filter: 0 "/sub?1234" 2018/09/04 02:02:14 [debug] 12631#12631: *3 HTTP/1.1 201 Created Server: nginx/1.15.4 Date: Tue, 04 Sep 2018 02:02:14 UTC Content-Type: text/plain Content-Length: 101 Connection: keep-alive 2018/09/04 02:02:14 [debug] 12631#12631: *3 write new buf t:1 f:0 00005631FEE7C058, pos 00005631FEE7C058, size: 154 file: 0, size: 0 2018/09/04 02:02:14 [debug] 12631#12631: *3 http write filter: l:0 f:0 s:154 2018/09/04 02:02:14 [debug] 12631#12631: *3 http output filter "/pub?1234" 2018/09/04 02:02:14 [debug] 12631#12631: *3 http copy filter: "/pub?1234" 2018/09/04 02:02:14 [debug] 12631#12631: *3 http postpone filter "/pub?1234" 00005631FEE7BAC8 2018/09/04 02:02:14 [debug] 12631#12631: *3 write old buf t:1 f:0 00005631FEE7C058, pos 00005631FEE7C058, size: 154 file: 0, size: 0 2018/09/04 02:02:14 [debug] 12631#12631: *3 write new buf t:0 f:0 00005631FCCC5160, pos 00005631FCCC5160, size: 101 file: 0, size: 0 2018/09/04 02:02:14 [debug] 12631#12631: *3 http write filter: l:1 f:1 s:255 2018/09/04 02:02:14 [debug] 12631#12631: *3 http write filter limit 0 2018/09/04 02:02:14 [debug] 12631#12631: *3 writev: 255 of 255 2018/09/04 02:02:14 [debug] 12631#12631: *3 http write filter 0000000000000000 2018/09/04 02:02:14 [debug] 12631#12631: *3 http copy filter: 0 "/pub?1234" 2018/09/04 02:02:14 [debug] 12631#12631: *3 http finalize request: 0, "/pub?1234" a:1, c:1 2018/09/04 02:02:14 [debug] 12631#12631: *3 set http keepalive handler 2018/09/04 02:02:14 [debug] 12631#12631: *3 http close request 2018/09/04 02:02:14 [debug] 12631#12631: *3 http log handler 2018/09/04 02:02:14 [debug] 12631#12631: *3 free: 00005631FEE7AB10, unused: 24 2018/09/04 02:02:14 [debug] 12631#12631: *3 free: 00005631FEE7BB20, unused: 2323 2018/09/04 02:02:14 [debug] 12631#12631: *3 free: 00005631FEE98410 2018/09/04 02:02:14 [debug] 12631#12631: *3 hc free: 0000000000000000 2018/09/04 02:02:14 [debug] 12631#12631: *3 hc busy: 0000000000000000 0 2018/09/04 02:02:14 [debug] 12631#12631: *3 tcp_nodelay 2018/09/04 02:02:14 [debug] 12631#12631: *3 reusable connection: 1 2018/09/04 02:02:14 [debug] 12631#12631: *3 event timer add: 48: 75000:352364935 2018/09/04 02:02:14 [debug] 12631#12631: *3 http keepalive handler 2018/09/04 02:02:14 [debug] 12631#12631: *3 malloc: 00005631FEE98410:1024 2018/09/04 02:02:14 [debug] 12631#12631: *3 recv: eof:1, avail:1 2018/09/04 02:02:14 [debug] 12631#12631: *3 recv: fd:48 0 of 1024 2018/09/04 02:02:14 [info] 12631#12631: *3 client 127.0.0.1 closed keepalive connection 2018/09/04 02:02:14 [debug] 12631#12631: *3 close http connection: 48 2018/09/04 02:02:14 [debug] 12631#12631: *3 event timer del: 48: 352364935 2018/09/04 02:02:14 [debug] 12631#12631: *3 reusable connection: 0 2018/09/04 02:02:14 [debug] 12631#12631: *3 free: 00005631FEE98410 2018/09/04 02:02:14 [debug] 12631#12631: *3 free: 00005631FEE72420, unused: 136 2018/09/04 02:02:17 [debug] 12631#12631: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:02:17 [debug] 12633#12633: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:02:17 [debug] 12631#12631: *1 http keepalive handler 2018/09/04 02:02:17 [debug] 12631#12631: *1 close http connection: 46 2018/09/04 02:02:17 [debug] 12631#12631: *1 event timer del: 46: 352363155 2018/09/04 02:02:17 [debug] 12632#12632: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:02:17 [debug] 12637#12637: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:02:17 [debug] 12631#12631: *1 reusable connection: 0 2018/09/04 02:02:17 [debug] 12640#12640: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:02:17 [debug] 12634#12634: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:02:17 [debug] 12639#12639: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:02:17 [debug] 12642#12642: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:02:17 [debug] 12635#12635: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:02:17 [debug] 12638#12638: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:02:17 [debug] 12636#12636: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:02:17 [debug] 12641#12641: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:02:17 [debug] 12631#12631: *1 free: 0000000000000000 2018/09/04 02:02:17 [debug] 12631#12631: *1 free: 00005631FEE73D80, unused: 136 2018/09/04 02:02:17 [debug] 12631#12631: *2 http output filter "/sub?1234" 2018/09/04 02:02:17 [debug] 12631#12631: *2 http copy filter: "/sub?1234" 2018/09/04 02:02:17 [debug] 12631#12631: *2 http postpone filter "/sub?1234" 00005631FEE7A2D8 2018/09/04 02:02:17 [debug] 12631#12631: *2 write new buf t:0 f:0 00005631FEE7A1A8, pos 00005631FEE7A1A8, size: 2 file: 0, size: 0 2018/09/04 02:02:17 [debug] 12631#12631: *2 write new buf t:0 f:0 00005631FCCC5A20, pos 00005631FCCC5A20, size: 2 file: 0, size: 0 2018/09/04 02:02:17 [debug] 12631#12631: *2 write new buf t:0 f:0 00005631FCA40BCF, pos 00005631FCA40BCF, size: 8 file: 0, size: 0 2018/09/04 02:02:17 [debug] 12631#12631: *2 http write filter: l:1 f:1 s:12 2018/09/04 02:02:17 [debug] 12631#12631: *2 http write filter limit 0 2018/09/04 02:02:17 [debug] 12631#12631: *2 writev: 12 of 12 2018/09/04 02:02:17 [debug] 12631#12631: *2 http write filter 0000000000000000 2018/09/04 02:02:17 [debug] 12631#12631: *2 http copy filter: 0 "/sub?1234" ``` ### nchan+pagespeed ``` 2018/09/04 02:01:01 [debug] 7724#7724: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:01:01 [debug] 7725#7725: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:01:01 [debug] 7726#7726: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:01:01 [debug] 7727#7727: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:01:01 [debug] 7728#7728: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:01:01 [debug] 7729#7729: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:01:01 [debug] 7730#7730: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:01:01 [debug] 7731#7731: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:01:01 [debug] 7732#7732: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:01:01 [debug] 7733#7733: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:01:01 [debug] 7734#7734: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:01:01 [debug] 7735#7735: epoll add event: fd:7 op:1 ev:10000001 2018/09/04 02:01:07 [debug] 7724#7724: accept on 0.0.0.0:80, ready: 0 2018/09/04 02:01:07 [debug] 7724#7724: posix_memalign: 000055E12D5E73E0:512 @16 2018/09/04 02:01:07 [debug] 7724#7724: *1 accept: 127.0.0.1:2289 fd:46 2018/09/04 02:01:07 [debug] 7724#7724: *1 event timer add: 46: 60000:352282791 2018/09/04 02:01:07 [debug] 7724#7724: *1 reusable connection: 1 2018/09/04 02:01:07 [debug] 7724#7724: *1 epoll add event: fd:46 op:1 ev:80002001 2018/09/04 02:01:07 [debug] 7724#7724: *1 http wait request handler 2018/09/04 02:01:07 [debug] 7724#7724: *1 malloc: 000055E12D60E9C0:1024 2018/09/04 02:01:07 [debug] 7724#7724: *1 recv: eof:0, avail:1 2018/09/04 02:01:07 [debug] 7724#7724: *1 recv: fd:46 861 of 1024 2018/09/04 02:01:07 [debug] 7724#7724: *1 reusable connection: 0 2018/09/04 02:01:07 [debug] 7724#7724: *1 posix_memalign: 000055E12D631A60:4096 @16 2018/09/04 02:01:07 [debug] 7724#7724: *1 http process request line 2018/09/04 02:01:07 [debug] 7724#7724: *1 http request line: "GET /ws.html HTTP/1.1" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http uri: "/ws.html" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http args: "" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http exten: "html" 2018/09/04 02:01:07 [debug] 7724#7724: *1 posix_memalign: 000055E12D656990:4096 @16 2018/09/04 02:01:07 [debug] 7724#7724: *1 http process request header line 2018/09/04 02:01:07 [debug] 7724#7724: *1 http header: "Host: localhost" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http header: "Connection: keep-alive" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http header: "Pragma: no-cache" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http header: "Cache-Control: no-cache" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http header: "Upgrade-Insecure-Requests: 1" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http header: "User-Agent: user-agent" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http header: "Accept: text/html,application/xhtml+xml,application/xml;q=0.9,image/webp,image/apng,*/*;q=0.8" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http header: "Accept-Encoding: gzip, deflate" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http header: "Accept-Language: en-US,en" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http header done 2018/09/04 02:01:07 [debug] 7724#7724: *1 event timer del: 46: 352282791 2018/09/04 02:01:07 [debug] 7724#7724: *1 generic phase: 0 2018/09/04 02:01:07 [debug] 7724#7724: *1 rewrite phase: 1 2018/09/04 02:01:07 [debug] 7724#7724: *1 test location: "/" 2018/09/04 02:01:07 [debug] 7724#7724: *1 test location: "sub" 2018/09/04 02:01:07 [debug] 7724#7724: *1 using configuration "/" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http cl:-1 max:1048576 2018/09/04 02:01:07 [debug] 7724#7724: *1 rewrite phase: 3 2018/09/04 02:01:07 [debug] 7724#7724: *1 post rewrite phase: 4 2018/09/04 02:01:07 [debug] 7724#7724: *1 generic phase: 5 2018/09/04 02:01:07 [debug] 7724#7724: *1 generic phase: 6 2018/09/04 02:01:07 [debug] 7724#7724: *1 generic phase: 7 2018/09/04 02:01:07 [debug] 7724#7724: *1 access phase: 8 2018/09/04 02:01:07 [debug] 7724#7724: *1 access phase: 9 2018/09/04 02:01:07 [debug] 7724#7724: *1 access phase: 10 2018/09/04 02:01:07 [debug] 7724#7724: *1 post access phase: 11 2018/09/04 02:01:07 [debug] 7724#7724: *1 generic phase: 12 2018/09/04 02:01:07 [debug] 7724#7724: *1 pagespeed phase: 12 2018/09/04 02:01:07 [debug] 7724#7724: *1 generic phase: 13 2018/09/04 02:01:07 [debug] 7724#7724: *1 generic phase: 14 2018/09/04 02:01:07 [debug] 7724#7724: *1 content phase: 15 2018/09/04 02:01:07 [debug] 7724#7724: *1 content phase: 16 2018/09/04 02:01:07 [debug] 7724#7724: *1 content phase: 17 2018/09/04 02:01:07 [debug] 7724#7724: *1 content phase: 18 2018/09/04 02:01:07 [debug] 7724#7724: *1 content phase: 19 2018/09/04 02:01:07 [debug] 7724#7724: *1 http filename: "/var/www/ws.html" 2018/09/04 02:01:07 [debug] 7724#7724: *1 add cleanup: 000055E12D6328A0 2018/09/04 02:01:07 [debug] 7724#7724: *1 http static fd: 47 2018/09/04 02:01:07 [debug] 7724#7724: *1 http set discard body 2018/09/04 02:01:07 [debug] 7724#7724: *1 HTTP/1.1 200 OK Server: nginx/1.15.4 Date: Tue, 04 Sep 2018 02:01:07 UTC Content-Type: text/html Content-Length: 688 Last-Modified: Tue, 04 Sep 2018 01:44:32 UTC Connection: keep-alive ETag: "5b8de380-2b0" Accept-Ranges: bytes 2018/09/04 02:01:07 [debug] 7724#7724: *1 write new buf t:1 f:0 000055E12D656DC0, pos 000055E12D656DC0, size: 238 file: 0, size: 0 2018/09/04 02:01:07 [debug] 7724#7724: *1 http write filter: l:0 f:0 s:238 2018/09/04 02:01:07 [debug] 7724#7724: *1 http output filter "/ws.html?" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http copy filter: "/ws.html?" 2018/09/04 02:01:07 [debug] 7724#7724: *1 read: 47, 000055E12D656F90, 688, 0 2018/09/04 02:01:07 [debug] 7724#7724: *1 http postpone filter "/ws.html?" 000055E12D657240 2018/09/04 02:01:07 [debug] 7724#7724: *1 write old buf t:1 f:0 000055E12D656DC0, pos 000055E12D656DC0, size: 238 file: 0, size: 0 2018/09/04 02:01:07 [debug] 7724#7724: *1 write new buf t:1 f:0 000055E12D656F90, pos 000055E12D656F90, size: 688 file: 0, size: 0 2018/09/04 02:01:07 [debug] 7724#7724: *1 http write filter: l:1 f:0 s:926 2018/09/04 02:01:07 [debug] 7724#7724: *1 http write filter limit 0 2018/09/04 02:01:07 [debug] 7724#7724: *1 writev: 926 of 926 2018/09/04 02:01:07 [debug] 7724#7724: *1 http write filter 0000000000000000 2018/09/04 02:01:07 [debug] 7724#7724: *1 http copy filter: 0 "/ws.html?" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http finalize request: 0, "/ws.html?" a:1, c:1 2018/09/04 02:01:07 [debug] 7724#7724: *1 set http keepalive handler 2018/09/04 02:01:07 [debug] 7724#7724: *1 http close request 2018/09/04 02:01:07 [debug] 7724#7724: *1 http log handler 2018/09/04 02:01:07 [debug] 7724#7724: *1 run cleanup: 000055E12D6328A0 2018/09/04 02:01:07 [debug] 7724#7724: *1 file cleanup: fd:47 2018/09/04 02:01:07 [debug] 7724#7724: *1 free: 000055E12D631A60, unused: 8 2018/09/04 02:01:07 [debug] 7724#7724: *1 free: 000055E12D656990, unused: 1604 2018/09/04 02:01:07 [debug] 7724#7724: *1 free: 000055E12D60E9C0 2018/09/04 02:01:07 [debug] 7724#7724: *1 hc free: 0000000000000000 2018/09/04 02:01:07 [debug] 7724#7724: *1 hc busy: 0000000000000000 0 2018/09/04 02:01:07 [debug] 7724#7724: *1 tcp_nodelay 2018/09/04 02:01:07 [debug] 7724#7724: *1 reusable connection: 1 2018/09/04 02:01:07 [debug] 7724#7724: *1 event timer add: 46: 75000:352297791 2018/09/04 02:01:07 [debug] 7724#7724: *1 http keepalive handler 2018/09/04 02:01:07 [debug] 7724#7724: *1 malloc: 000055E12D60E9C0:1024 2018/09/04 02:01:07 [debug] 7724#7724: *1 recv: eof:0, avail:1 2018/09/04 02:01:07 [debug] 7724#7724: *1 recv: fd:46 830 of 1024 2018/09/04 02:01:07 [debug] 7724#7724: *1 reusable connection: 0 2018/09/04 02:01:07 [debug] 7724#7724: *1 posix_memalign: 000055E12D631A60:4096 @16 2018/09/04 02:01:07 [debug] 7724#7724: *1 event timer del: 46: 352297791 2018/09/04 02:01:07 [debug] 7724#7724: *1 http process request line 2018/09/04 02:01:07 [debug] 7724#7724: *1 http request line: "GET /favicon.ico HTTP/1.1" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http uri: "/favicon.ico" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http args: "" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http exten: "ico" 2018/09/04 02:01:07 [debug] 7724#7724: *1 posix_memalign: 000055E12D656990:4096 @16 2018/09/04 02:01:07 [debug] 7724#7724: *1 http process request header line 2018/09/04 02:01:07 [debug] 7724#7724: *1 http header: "Host: localhost" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http header: "Connection: keep-alive" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http header: "Pragma: no-cache" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http header: "Cache-Control: no-cache" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http header: "User-Agent: user-agent" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http header: "Accept: image/webp,image/apng,image/*,*/*;q=0.8" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http header: "Referer: http://localhost/ws.html"; 2018/09/04 02:01:07 [debug] 7724#7724: *1 http header: "Accept-Encoding: gzip, deflate" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http header: "Accept-Language: en-US,en" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http header done 2018/09/04 02:01:07 [debug] 7724#7724: *1 generic phase: 0 2018/09/04 02:01:07 [debug] 7724#7724: *1 rewrite phase: 1 2018/09/04 02:01:07 [debug] 7724#7724: *1 test location: "/" 2018/09/04 02:01:07 [debug] 7724#7724: *1 test location: "sub" 2018/09/04 02:01:07 [debug] 7724#7724: *1 test location: "pub" 2018/09/04 02:01:07 [debug] 7724#7724: *1 using configuration "/" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http cl:-1 max:1048576 2018/09/04 02:01:07 [debug] 7724#7724: *1 rewrite phase: 3 2018/09/04 02:01:07 [debug] 7724#7724: *1 post rewrite phase: 4 2018/09/04 02:01:07 [debug] 7724#7724: *1 generic phase: 5 2018/09/04 02:01:07 [debug] 7724#7724: *1 generic phase: 6 2018/09/04 02:01:07 [debug] 7724#7724: *1 generic phase: 7 2018/09/04 02:01:07 [debug] 7724#7724: *1 access phase: 8 2018/09/04 02:01:07 [debug] 7724#7724: *1 access phase: 9 2018/09/04 02:01:07 [debug] 7724#7724: *1 access phase: 10 2018/09/04 02:01:07 [debug] 7724#7724: *1 post access phase: 11 2018/09/04 02:01:07 [debug] 7724#7724: *1 pagespeed phase: 12 2018/09/04 02:01:07 [debug] 7724#7724: *1 generic phase: 13 2018/09/04 02:01:07 [debug] 7724#7724: *1 generic phase: 14 2018/09/04 02:01:07 [debug] 7724#7724: *1 content phase: 15 2018/09/04 02:01:07 [debug] 7724#7724: *1 content phase: 16 2018/09/04 02:01:07 [debug] 7724#7724: *1 content phase: 17 2018/09/04 02:01:07 [debug] 7724#7724: *1 content phase: 18 2018/09/04 02:01:07 [debug] 7724#7724: *1 content phase: 19 2018/09/04 02:01:07 [debug] 7724#7724: *1 http filename: "/var/www/favicon.ico" 2018/09/04 02:01:07 [debug] 7724#7724: *1 add cleanup: 000055E12D632890 2018/09/04 02:01:07 [error] 7724#7724: *1 open() "/var/www/favicon.ico" failed (2: No such file or directory), client: 1227.0.0.1, server: , request: "GET /favicon.ico HTTP/1.1", host: "localhost", referrer: "http://localhost/ws.html"; 2018/09/04 02:01:07 [debug] 7724#7724: *1 http finalize request: 404, "/favicon.ico?" a:1, c:1 2018/09/04 02:01:07 [debug] 7724#7724: *1 http special response: 404, "/favicon.ico?" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http set discard body 2018/09/04 02:01:07 [debug] 7724#7724: *1 HTTP/1.1 404 Not Found Server: nginx/1.15.4 Date: Tue, 04 Sep 2018 02:01:07 UTC Content-Type: text/html Content-Length: 571 Connection: keep-alive 2018/09/04 02:01:07 [debug] 7724#7724: *1 write new buf t:1 f:0 000055E12D632910, pos 000055E12D632910, size: 155 file: 0, size: 0 2018/09/04 02:01:07 [debug] 7724#7724: *1 http write filter: l:0 f:0 s:155 2018/09/04 02:01:07 [debug] 7724#7724: *1 http output filter "/favicon.ico?" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http copy filter: "/favicon.ico?" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http postpone filter "/favicon.ico?" 000055E12D632A20 2018/09/04 02:01:07 [debug] 7724#7724: *1 write old buf t:1 f:0 000055E12D632910, pos 000055E12D632910, size: 155 file: 0, size: 0 2018/09/04 02:01:07 [debug] 7724#7724: *1 write new buf t:0 f:0 0000000000000000, pos 000055E12C126880, size: 116 file: 0, size: 0 2018/09/04 02:01:07 [debug] 7724#7724: *1 write new buf t:0 f:0 0000000000000000, pos 000055E12C1270A0, size: 53 file: 0, size: 0 2018/09/04 02:01:07 [debug] 7724#7724: *1 write new buf t:0 f:0 0000000000000000, pos 000055E12C126E80, size: 402 file: 0, size: 0 2018/09/04 02:01:07 [debug] 7724#7724: *1 http write filter: l:1 f:0 s:726 2018/09/04 02:01:07 [debug] 7724#7724: *1 http write filter limit 0 2018/09/04 02:01:07 [debug] 7724#7724: *1 writev: 726 of 726 2018/09/04 02:01:07 [debug] 7724#7724: *1 http write filter 0000000000000000 2018/09/04 02:01:07 [debug] 7724#7724: *1 http copy filter: 0 "/favicon.ico?" 2018/09/04 02:01:07 [debug] 7724#7724: *1 http finalize request: 0, "/favicon.ico?" a:1, c:1 2018/09/04 02:01:07 [debug] 7724#7724: *1 set http keepalive handler 2018/09/04 02:01:07 [debug] 7724#7724: *1 http close request 2018/09/04 02:01:07 [debug] 7724#7724: *1 http log handler 2018/09/04 02:01:07 [debug] 7724#7724: *1 free: 000055E12D631A60, unused: 0 2018/09/04 02:01:07 [debug] 7724#7724: *1 free: 000055E12D656990, unused: 2515 2018/09/04 02:01:07 [debug] 7724#7724: *1 free: 000055E12D60E9C0 2018/09/04 02:01:07 [debug] 7724#7724: *1 hc free: 0000000000000000 2018/09/04 02:01:07 [debug] 7724#7724: *1 hc busy: 0000000000000000 0 2018/09/04 02:01:07 [debug] 7724#7724: *1 reusable connection: 1 2018/09/04 02:01:07 [debug] 7724#7724: *1 event timer add: 46: 75000:352297907 2018/09/04 02:01:07 [debug] 7724#7724: accept on 0.0.0.0:80, ready: 0 2018/09/04 02:01:07 [debug] 7724#7724: posix_memalign: 000055E12D659F30:512 @16 2018/09/04 02:01:07 [debug] 7724#7724: *2 accept: 127.0.0.1:2232 fd:47 2018/09/04 02:01:07 [debug] 7724#7724: *2 event timer add: 47: 60000:352282923 2018/09/04 02:01:07 [debug] 7724#7724: *2 reusable connection: 1 2018/09/04 02:01:07 [debug] 7724#7724: *2 epoll add event: fd:47 op:1 ev:80002001 2018/09/04 02:01:07 [debug] 7724#7724: *2 http wait request handler 2018/09/04 02:01:07 [debug] 7724#7724: *2 malloc: 000055E12D60E9C0:1024 2018/09/04 02:01:07 [debug] 7724#7724: *2 recv: eof:0, avail:1 2018/09/04 02:01:07 [debug] 7724#7724: *2 recv: fd:47 928 of 1024 2018/09/04 02:01:07 [debug] 7724#7724: *2 reusable connection: 0 2018/09/04 02:01:07 [debug] 7724#7724: *2 posix_memalign: 000055E12D631A60:4096 @16 2018/09/04 02:01:07 [debug] 7724#7724: *2 http process request line 2018/09/04 02:01:07 [debug] 7724#7724: *2 http request line: "GET /sub?1234 HTTP/1.1" 2018/09/04 02:01:07 [debug] 7724#7724: *2 http uri: "/sub" 2018/09/04 02:01:07 [debug] 7724#7724: *2 http args: "1234" 2018/09/04 02:01:07 [debug] 7724#7724: *2 http exten: "" 2018/09/04 02:01:07 [debug] 7724#7724: *2 posix_memalign: 000055E12D656990:4096 @16 2018/09/04 02:01:07 [debug] 7724#7724: *2 http process request header line 2018/09/04 02:01:07 [debug] 7724#7724: *2 http header: "Host: localhost" 2018/09/04 02:01:07 [debug] 7724#7724: *2 http header: "Connection: Upgrade" 2018/09/04 02:01:07 [debug] 7724#7724: *2 http header: "Pragma: no-cache" 2018/09/04 02:01:07 [debug] 7724#7724: *2 http header: "Cache-Control: no-cache" 2018/09/04 02:01:07 [debug] 7724#7724: *2 http header: "User-Agent: user-agent" 2018/09/04 02:01:07 [debug] 7724#7724: *2 http header: "Upgrade: websocket" 2018/09/04 02:01:07 [debug] 7724#7724: *2 http header: "Origin: http://localhost"; 2018/09/04 02:01:07 [debug] 7724#7724: *2 http header: "Sec-WebSocket-Version: 13" 2018/09/04 02:01:07 [debug] 7724#7724: *2 http header: "Accept-Encoding: gzip, deflate" 2018/09/04 02:01:07 [debug] 7724#7724: *2 http header: "Accept-Language: en-US,en" 2018/09/04 02:01:07 [debug] 7724#7724: *2 http header: "Sec-WebSocket-Key: tDY9Svl5vPPm/X3upS+cLw==" 2018/09/04 02:01:07 [debug] 7724#7724: *2 http header: "Sec-WebSocket-Extensions: permessage-deflate; client_max_window_bits" 2018/09/04 02:01:07 [debug] 7724#7724: *2 http header done 2018/09/04 02:01:07 [debug] 7724#7724: *2 event timer del: 47: 352282923 2018/09/04 02:01:07 [debug] 7724#7724: *2 generic phase: 0 2018/09/04 02:01:07 [debug] 7724#7724: *2 rewrite phase: 1 2018/09/04 02:01:07 [debug] 7724#7724: *2 test location: "/" 2018/09/04 02:01:07 [debug] 7724#7724: *2 test location: "sub" 2018/09/04 02:01:07 [debug] 7724#7724: *2 using configuration "=/sub" 2018/09/04 02:01:07 [debug] 7724#7724: *2 http cl:-1 max:1048576 2018/09/04 02:01:07 [debug] 7724#7724: *2 rewrite phase: 3 2018/09/04 02:01:07 [debug] 7724#7724: *2 post rewrite phase: 4 2018/09/04 02:01:07 [debug] 7724#7724: *2 generic phase: 5 2018/09/04 02:01:07 [debug] 7724#7724: *2 generic phase: 6 2018/09/04 02:01:07 [debug] 7724#7724: *2 generic phase: 7 2018/09/04 02:01:07 [debug] 7724#7724: *2 access phase: 8 2018/09/04 02:01:07 [debug] 7724#7724: *2 access phase: 9 2018/09/04 02:01:07 [debug] 7724#7724: *2 access phase: 10 2018/09/04 02:01:07 [debug] 7724#7724: *2 post access phase: 11 2018/09/04 02:01:07 [debug] 7724#7724: *2 pagespeed phase: 12 2018/09/04 02:01:07 [debug] 7724#7724: *2 generic phase: 13 2018/09/04 02:01:07 [debug] 7724#7724: *2 generic phase: 14 2018/09/04 02:01:07 [debug] 7724#7724: *2 http cleanup add: 000055E12D6329C0 2018/09/04 02:01:07 [debug] 7724#7724: *2 HTTP/1.1 101 Switching Protocols Server: nginx/1.15.4 Date: Tue, 04 Sep 2018 02:01:07 UTC Content-Length: 0 Connection: upgrade Sec-WebSocket-Extensions: permessage-deflate Access-Control-Allow-Credentials: true Access-Control-Allow-Origin: http://localhost Sec-WebSocket-Accept: Mk9ifh6mLuOO7QDU3M7Ljdu65QE= Upgrade: websocket 2018/09/04 02:01:07 [debug] 7724#7724: *2 write new buf t:1 f:0 000055E12D656E38, pos 000055E12D656E38, size: 346 file: 0, size: 0 2018/09/04 02:01:07 [debug] 7724#7724: *2 http write filter: l:1 f:0 s:346 2018/09/04 02:01:07 [debug] 7724#7724: *2 http write filter limit 0 2018/09/04 02:01:07 [debug] 7724#7724: *2 writev: 346 of 346 2018/09/04 02:01:07 [debug] 7724#7724: *2 http write filter 0000000000000000 2018/09/04 02:01:07 [debug] 7724#7724: *2 http finalize request: -4, "/sub?1234" a:1, c:2 2018/09/04 02:01:07 [debug] 7724#7724: *2 http request count:2 blk:0 2018/09/04 02:01:10 [debug] 7724#7724: accept on 0.0.0.0:80, ready: 0 2018/09/04 02:01:10 [debug] 7724#7724: posix_memalign: 000055E12D659C40:512 @16 2018/09/04 02:01:10 [debug] 7724#7724: *3 accept: 127.0.0.1:7516 fd:48 2018/09/04 02:01:10 [debug] 7724#7724: *3 event timer add: 48: 60000:352285423 2018/09/04 02:01:10 [debug] 7724#7724: *3 reusable connection: 1 2018/09/04 02:01:10 [debug] 7724#7724: *3 epoll add event: fd:48 op:1 ev:80002001 2018/09/04 02:01:10 [debug] 7724#7724: *3 http wait request handler 2018/09/04 02:01:10 [debug] 7724#7724: *3 malloc: 000055E12D6519A0:1024 2018/09/04 02:01:10 [debug] 7724#7724: *3 recv: eof:0, avail:1 2018/09/04 02:01:10 [debug] 7724#7724: *3 recv: fd:48 170 of 1024 2018/09/04 02:01:10 [debug] 7724#7724: *3 reusable connection: 0 2018/09/04 02:01:10 [debug] 7724#7724: *3 posix_memalign: 000055E12D65E1D0:4096 @16 2018/09/04 02:01:10 [debug] 7724#7724: *3 http process request line 2018/09/04 02:01:10 [debug] 7724#7724: *3 http request line: "POST /pub?1234 HTTP/1.1" 2018/09/04 02:01:10 [debug] 7724#7724: *3 http uri: "/pub" 2018/09/04 02:01:10 [debug] 7724#7724: *3 http args: "1234" 2018/09/04 02:01:10 [debug] 7724#7724: *3 http exten: "" 2018/09/04 02:01:10 [debug] 7724#7724: *3 posix_memalign: 000055E12D65F1E0:4096 @16 2018/09/04 02:01:10 [debug] 7724#7724: *3 http process request header line 2018/09/04 02:01:10 [debug] 7724#7724: *3 http header: "Host: localhost" 2018/09/04 02:01:10 [debug] 7724#7724: *3 http header: "User-Agent: curl/7.58.0" 2018/09/04 02:01:10 [debug] 7724#7724: *3 http header: "Accept: */*" 2018/09/04 02:01:10 [debug] 7724#7724: *3 http header: "Content-Length: 13" 2018/09/04 02:01:10 [debug] 7724#7724: *3 http header: "Content-Type: application/x-www-form-urlencoded" 2018/09/04 02:01:10 [debug] 7724#7724: *3 http header done 2018/09/04 02:01:10 [debug] 7724#7724: *3 event timer del: 48: 352285423 2018/09/04 02:01:10 [debug] 7724#7724: *3 generic phase: 0 2018/09/04 02:01:10 [debug] 7724#7724: *3 rewrite phase: 1 2018/09/04 02:01:10 [debug] 7724#7724: *3 test location: "/" 2018/09/04 02:01:10 [debug] 7724#7724: *3 test location: "sub" 2018/09/04 02:01:10 [debug] 7724#7724: *3 test location: "pub" 2018/09/04 02:01:10 [debug] 7724#7724: *3 using configuration "=/pub" 2018/09/04 02:01:10 [debug] 7724#7724: *3 http cl:13 max:1048576 2018/09/04 02:01:10 [debug] 7724#7724: *3 rewrite phase: 3 2018/09/04 02:01:10 [debug] 7724#7724: *3 post rewrite phase: 4 2018/09/04 02:01:10 [debug] 7724#7724: *3 generic phase: 5 2018/09/04 02:01:10 [debug] 7724#7724: *3 generic phase: 6 2018/09/04 02:01:10 [debug] 7724#7724: *3 generic phase: 7 2018/09/04 02:01:10 [debug] 7724#7724: *3 access phase: 8 2018/09/04 02:01:10 [debug] 7724#7724: *3 access phase: 9 2018/09/04 02:01:10 [debug] 7724#7724: *3 access phase: 10 2018/09/04 02:01:10 [debug] 7724#7724: *3 post access phase: 11 2018/09/04 02:01:10 [debug] 7724#7724: *3 pagespeed phase: 12 2018/09/04 02:01:10 [debug] 7724#7724: *3 generic phase: 13 2018/09/04 02:01:10 [debug] 7724#7724: *3 generic phase: 14 2018/09/04 02:01:10 [debug] 7724#7724: *3 http client request body preread 13 2018/09/04 02:01:10 [debug] 7724#7724: *3 http request body content length filter 2018/09/04 02:01:10 [debug] 7724#7724: *3 http body new buf t:1 f:0 000055E12D651A3D, pos 000055E12D651A3D, size: 13 file: 0, size: 0 2018/09/04 02:01:10 [debug] 7724#7724: *3 http cleanup add: 000055E12D65F180 2018/09/04 02:01:10 [debug] 7724#7724: *3 http finalize request: -4, "/pub?1234" a:1, c:2 2018/09/04 02:01:10 [debug] 7724#7724: *3 http request count:2 blk:0 2018/09/04 02:01:10 [debug] 7724#7724: *2 http output filter "/sub?1234" 2018/09/04 02:01:10 [debug] 7724#7724: *2 http copy filter: "/sub?1234" 2018/09/04 02:01:10 [debug] 7724#7724: *2 http postpone filter "/sub?1234" 000055E12D632A18 2018/09/04 02:01:10 [debug] 7724#7724: *2 http copy filter: 0 "/sub?1234" 2018/09/04 02:01:10 [debug] 7724#7724: *3 HTTP/1.1 201 Created Server: nginx/1.15.4 Date: Tue, 04 Sep 2018 02:01:10 UTC Content-Type: text/plain Content-Length: 101 Connection: keep-alive 2018/09/04 02:01:10 [debug] 7724#7724: *3 write new buf t:1 f:0 000055E12D65F718, pos 000055E12D65F718, size: 154 file: 0, size: 0 2018/09/04 02:01:10 [debug] 7724#7724: *3 http write filter: l:0 f:0 s:154 2018/09/04 02:01:10 [debug] 7724#7724: *3 http output filter "/pub?1234" 2018/09/04 02:01:10 [debug] 7724#7724: *3 http copy filter: "/pub?1234" 2018/09/04 02:01:10 [debug] 7724#7724: *3 http postpone filter "/pub?1234" 000055E12D65F198 2018/09/04 02:01:10 [debug] 7724#7724: *3 write old buf t:1 f:0 000055E12D65F718, pos 000055E12D65F718, size: 154 file: 0, size: 0 2018/09/04 02:01:10 [debug] 7724#7724: *3 write new buf t:0 f:0 000055E12C15CD80, pos 000055E12C15CD80, size: 101 file: 0, size: 0 2018/09/04 02:01:10 [debug] 7724#7724: *3 http write filter: l:1 f:1 s:255 2018/09/04 02:01:10 [debug] 7724#7724: *3 http write filter limit 0 2018/09/04 02:01:10 [debug] 7724#7724: *3 writev: 255 of 255 2018/09/04 02:01:10 [debug] 7724#7724: *3 http write filter 0000000000000000 2018/09/04 02:01:10 [debug] 7724#7724: *3 http copy filter: 0 "/pub?1234" 2018/09/04 02:01:10 [debug] 7724#7724: *3 http finalize request: 0, "/pub?1234" a:1, c:1 2018/09/04 02:01:10 [debug] 7724#7724: *3 set http keepalive handler 2018/09/04 02:01:10 [debug] 7724#7724: *3 http close request 2018/09/04 02:01:10 [debug] 7724#7724: *3 http log handler 2018/09/04 02:01:10 [debug] 7724#7724: *3 free: 000055E12D65E1D0, unused: 8 2018/09/04 02:01:10 [debug] 7724#7724: *3 free: 000055E12D65F1E0, unused: 2323 2018/09/04 02:01:10 [debug] 7724#7724: *3 free: 000055E12D6519A0 2018/09/04 02:01:10 [debug] 7724#7724: *3 hc free: 0000000000000000 2018/09/04 02:01:10 [debug] 7724#7724: *3 hc busy: 0000000000000000 0 2018/09/04 02:01:10 [debug] 7724#7724: *3 tcp_nodelay 2018/09/04 02:01:10 [debug] 7724#7724: *3 reusable connection: 1 2018/09/04 02:01:10 [debug] 7724#7724: *3 event timer add: 48: 75000:352300423 2018/09/04 02:01:10 [debug] 7724#7724: *3 http keepalive handler 2018/09/04 02:01:10 [debug] 7724#7724: *3 malloc: 000055E12D6519A0:1024 2018/09/04 02:01:10 [debug] 7724#7724: *3 recv: eof:1, avail:1 2018/09/04 02:01:10 [debug] 7724#7724: *3 recv: fd:48 0 of 1024 2018/09/04 02:01:10 [info] 7724#7724: *3 client 127.0.0.1 closed keepalive connection 2018/09/04 02:01:10 [debug] 7724#7724: *3 close http connection: 48 2018/09/04 02:01:10 [debug] 7724#7724: *3 event timer del: 48: 352300423 2018/09/04 02:01:10 [debug] 7724#7724: *3 reusable connection: 0 2018/09/04 02:01:10 [debug] 7724#7724: *3 free: 000055E12D6519A0 2018/09/04 02:01:10 [debug] 7724#7724: *3 free: 000055E12D659C40, unused: 136 2018/09/04 02:01:13 [debug] 7726#7726: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:01:13 [debug] 7727#7727: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:01:13 [debug] 7724#7724: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:01:13 [debug] 7731#7731: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:01:13 [debug] 7724#7724: *1 http keepalive handler 2018/09/04 02:01:13 [debug] 7724#7724: *1 close http connection: 46 2018/09/04 02:01:13 [debug] 7728#7728: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:01:13 [debug] 7724#7724: *1 event timer del: 46: 352297907 2018/09/04 02:01:13 [debug] 7733#7733: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:01:13 [debug] 7734#7734: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:01:13 [debug] 7725#7725: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:01:13 [debug] 7724#7724: *1 reusable connection: 0 2018/09/04 02:01:13 [debug] 7732#7732: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:01:13 [debug] 7735#7735: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:01:13 [debug] 7730#7730: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:01:13 [debug] 7729#7729: epoll del event: fd:7 op:2 ev:00000000 2018/09/04 02:01:13 [debug] 7724#7724: *1 free: 0000000000000000 2018/09/04 02:01:13 [debug] 7724#7724: *1 free: 000055E12D5E73E0, unused: 136 2018/09/04 02:01:13 [debug] 7724#7724: *2 http output filter "/sub?1234" 2018/09/04 02:01:13 [debug] 7724#7724: *2 http copy filter: "/sub?1234" 2018/09/04 02:01:13 [debug] 7724#7724: *2 http postpone filter "/sub?1234" 000055E12D632A38 2018/09/04 02:01:13 [debug] 7724#7724: *2 http copy filter: 0 "/sub?1234" ``` ### nginx -V ``` nginx version: nginx/1.15.4 built by gcc 7.3.0 (Ubuntu 7.3.0-16ubuntu3) built with OpenSSL 1.1.0h 27 Mar 2018 TLS SNI support enabled configure arguments: --with-debug --sbin-path=/usr/sbin --prefix=/etc/nginx --conf-path=/etc/nginx/nginx.conf --error-log-path=/var/log/nginx/error.log --http-client-body-temp-path=/var/lib/nginx/body --http-fastcgi-temp-path=/var/lib/nginx/fastcgi --http-log-path=/var/log/nginx/access.log --http-proxy-temp-path=/var/lib/nginx/proxy --http-scgi-temp-path=/var/lib/nginx/scgi --http-uwsgi-temp-path=/var/lib/nginx/uwsgi --lock-path=/var/lock/nginx.lock --pid-path=/var/run/nginx.pid --with-pcre --with-pcre-jit --with-stream --with-stream_ssl_module --with-http_addition_module --with-http_auth_request_module --with-http_dav_module --with-http_flv_module --with-http_geoip_module --with-http_gunzip_module --with-http_gzip_static_module --with-http_mp4_module --with-http_realip_module --with-http_secure_link_module --with-http_stub_status_module --with-http_ssl_module --with-http_sub_module --with-http_v2_module --with-http_slice_module --with-file-aio --with-threads --with-http_v2_module -- add-module=/usr/src/nginx_modules/nchan --add-module=/usr/src/nginx_modules/incubator-pagespeed-ngx ``` [ Full content available at: https://github.com/apache/incubator-pagespeed-ngx/issues/1592 ] This message was relayed via gitbox.apache.org for [email protected]
