How to enable debugging log in nginx

Enable debugging log in Nginx to inspect internal behavior and upstream interactions.

The easiest way to enable debug log is to define error_log directive using an appropriate log level.

server {
  listen 8080 ssl;
  server_name default;
  ssl_certificate_key /etc/nginx/ssl/nginx.key;
  ssl_certificate     /etc/nginx/ssl/nginx.crt;
  access_log /var/log/nginx/service-access.log;
  error_log /var/log/nginx/service-error.log debug;
  location / {
     proxy_pass http://127.0.0.1:19999/;
  }
}

The downside is that it will log every request from every client, so it is not suitable in most cases. The solution to this problem is to enable the debugging log for selected client addresses only.

Edit main nginx.conf configuration file to define multiple debug_connection directives.

events {
  worker_connections 768;
  # multi_accept on;
  debug_connection 192.168.50.1;
  debug_connection 192.168.60.0/24;
}

This directive will not alter existing error_log directives. Debugging log will be stored to the one defined in the server context or main error log file otherwise.

2020/01/21 22:57:57 [debug] 3956#3956: *370 accept: 192.168.50.1:49040 fd:11
2020/01/21 22:57:57 [debug] 3956#3956: *370 event timer add: 11: 60000:31040672
2020/01/21 22:57:57 [debug] 3956#3956: *370 reusable connection: 1
2020/01/21 22:57:57 [debug] 3956#3956: *370 epoll add event: fd:11 op:1 ev:80002001
2020/01/21 22:57:57 [debug] 3956#3956: *370 http check ssl handshake
2020/01/21 22:57:57 [debug] 3956#3956: *370 http recv(): 1
2020/01/21 22:57:57 [debug] 3956#3956: *370 https ssl handshake: 0x16
2020/01/21 22:57:57 [debug] 3956#3956: *370 tcp_nodelay
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL ALPN supported by client: h2
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL ALPN supported by client: http/1.1
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL ALPN selected: http/1.1
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL_do_handshake: -1
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL_get_error: 2
2020/01/21 22:57:57 [debug] 3956#3956: *370 reusable connection: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL handshake handler: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL_do_handshake: 1
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL: TLSv1.2, cipher: "ECDHE-RSA-AES256-GCM-SHA384 TLSv1.2 Kx=ECDH Au=RSA Enc=AESGCM(256) Mac=AEAD"
2020/01/21 22:57:57 [debug] 3956#3956: *370 reusable connection: 1
2020/01/21 22:57:57 [debug] 3956#3956: *370 http wait request handler
2020/01/21 22:57:57 [debug] 3956#3956: *370 malloc: 000055FCE1CDC8E0:1024
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL_read: -1
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL_get_error: 2
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 000055FCE1CDC8E0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http wait request handler
2020/01/21 22:57:57 [debug] 3956#3956: *370 malloc: 000055FCE1CDC8E0:1024
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL_read: 83
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL_read: -1
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL_get_error: 2
2020/01/21 22:57:57 [debug] 3956#3956: *370 reusable connection: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 posix_memalign: 000055FCE1D1A770:4096 @16
2020/01/21 22:57:57 [debug] 3956#3956: *370 http process request line
2020/01/21 22:57:57 [debug] 3956#3956: *370 http request line: "GET / HTTP/1.1"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http uri: "/"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http args: ""
2020/01/21 22:57:57 [debug] 3956#3956: *370 http exten: ""
2020/01/21 22:57:57 [debug] 3956#3956: *370 posix_memalign: 000055FCE1D12C60:4096 @16
2020/01/21 22:57:57 [debug] 3956#3956: *370 http process request header line
2020/01/21 22:57:57 [debug] 3956#3956: *370 http header: "Host: 192.168.50.201:8080"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http header: "User-Agent: curl/7.58.0"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http header: "Accept: */*"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http header done
2020/01/21 22:57:57 [debug] 3956#3956: *370 event timer del: 11: 31040672
2020/01/21 22:57:57 [debug] 3956#3956: *370 generic phase: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 rewrite phase: 1
2020/01/21 22:57:57 [debug] 3956#3956: *370 test location: "/"
2020/01/21 22:57:57 [debug] 3956#3956: *370 using configuration "/"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http cl:-1 max:1048576
2020/01/21 22:57:57 [debug] 3956#3956: *370 rewrite phase: 3
2020/01/21 22:57:57 [debug] 3956#3956: *370 post rewrite phase: 4
2020/01/21 22:57:57 [debug] 3956#3956: *370 generic phase: 5
2020/01/21 22:57:57 [debug] 3956#3956: *370 generic phase: 6
2020/01/21 22:57:57 [debug] 3956#3956: *370 generic phase: 7
2020/01/21 22:57:57 [debug] 3956#3956: *370 access phase: 8
2020/01/21 22:57:57 [debug] 3956#3956: *370 access phase: 9
2020/01/21 22:57:57 [debug] 3956#3956: *370 access phase: 10
2020/01/21 22:57:57 [debug] 3956#3956: *370 access phase: 11
2020/01/21 22:57:57 [debug] 3956#3956: *370 post access phase: 12
2020/01/21 22:57:57 [debug] 3956#3956: *370 generic phase: 13
2020/01/21 22:57:57 [debug] 3956#3956: *370 generic phase: 14
2020/01/21 22:57:57 [debug] 3956#3956: *370 generic phase: 15
2020/01/21 22:57:57 [debug] 3956#3956: *370 http init upstream, client timer: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 epoll add event: fd:11 op:3 ev:80002005
2020/01/21 22:57:57 [debug] 3956#3956: *370 http script copy: "Host"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http script var: "127.0.0.1:19999"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http script copy: "Connection"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http script copy: "close"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http script copy: ""
2020/01/21 22:57:57 [debug] 3956#3956: *370 http script copy: ""
2020/01/21 22:57:57 [debug] 3956#3956: *370 http proxy header: "User-Agent: curl/7.58.0"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http proxy header: "Accept: */*"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http proxy header:
"GET / HTTP/1.0
Host: 127.0.0.1:19999
Connection: close
User-Agent: curl/7.58.0
Accept: */*
"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http cleanup add: 000055FCE1D13568
2020/01/21 22:57:57 [debug] 3956#3956: *370 get rr peer, try: 1
2020/01/21 22:57:57 [debug] 3956#3956: *370 stream socket 12
2020/01/21 22:57:57 [debug] 3956#3956: *370 epoll add connection: fd:12 ev:80002005
2020/01/21 22:57:57 [debug] 3956#3956: *370 connect to 127.0.0.1:19999, fd:12 #371
2020/01/21 22:57:57 [debug] 3956#3956: *370 http upstream connect: -2
2020/01/21 22:57:57 [debug] 3956#3956: *370 posix_memalign: 000055FCE1D0E100:128 @16
2020/01/21 22:57:57 [debug] 3956#3956: *370 event timer add: 12: 60000:31040684
2020/01/21 22:57:57 [debug] 3956#3956: *370 http finalize request: -4, "/?" a:1, c:2
2020/01/21 22:57:57 [debug] 3956#3956: *370 http request count:2 blk:0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http run request: "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http upstream check client, write event:1, "/"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http upstream request: "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http upstream send request handler
2020/01/21 22:57:57 [debug] 3956#3956: *370 http upstream send request
2020/01/21 22:57:57 [debug] 3956#3956: *370 http upstream send request body
2020/01/21 22:57:57 [debug] 3956#3956: *370 chain writer buf fl:1 s:98
2020/01/21 22:57:57 [debug] 3956#3956: *370 chain writer in: 000055FCE1D1B760
2020/01/21 22:57:57 [debug] 3956#3956: *370 writev: 98 of 98
2020/01/21 22:57:57 [debug] 3956#3956: *370 chain writer out: 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 event timer del: 12: 31040684
2020/01/21 22:57:57 [debug] 3956#3956: *370 event timer add: 12: 60000:31040684
2020/01/21 22:57:57 [debug] 3956#3956: *370 http upstream request: "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http upstream process header
2020/01/21 22:57:57 [debug] 3956#3956: *370 malloc: 000055FCE1D1F250:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 recv: eof:1, avail:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 recv: fd:12 4096 of 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 http proxy status 200 "200 OK"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http proxy header: "Connection: close"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http proxy header: "Server: NetData Embedded HTTP Server v1.19.0-336-nightly"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http proxy header: "Access-Control-Allow-Origin: *"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http proxy header: "Access-Control-Allow-Credentials: true"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http proxy header: "Content-Type: text/html; charset=utf-8"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http proxy header: "Date: Mon, 20 Jan 2020 20:38:49 GMT"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http proxy header: "Cache-Control: public"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http proxy header: "Expires: Wed, 22 Jan 2020 22:57:57 GMT"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http proxy header: "Content-Length: 129572"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http proxy header done
2020/01/21 22:57:57 [debug] 3956#3956: *370 xslt filter header
2020/01/21 22:57:57 [debug] 3956#3956: *370 posix_memalign: 000055FCE1D106D0:4096 @16
2020/01/21 22:57:57 [debug] 3956#3956: *370 HTTP/1.1 200 OK
Server: nginx/1.14.2
Date: Tue, 21 Jan 2020 22:57:57 GMT
Content-Type: text/html; charset=utf-8
Content-Length: 129572
Connection: keep-alive
Access-Control-Allow-Origin: *
Access-Control-Allow-Credentials: true
Cache-Control: public
Expires: Wed, 22 Jan 2020 22:57:57 GMT
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D106F0, pos 000055FCE1D106F0, size: 301 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter: l:0 f:0 s:301
2020/01/21 22:57:57 [debug] 3956#3956: *370 http cacheable: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http proxy filter init s:200 h:0 c:0 l:129572
2020/01/21 22:57:57 [debug] 3956#3956: *370 http upstream process upstream
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe read upstream: 1
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe preread: 3764
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #0
2020/01/21 22:57:57 [debug] 3956#3956: *370 malloc: 000055FCE1D1D1D0:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: eof:1, avail:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: 1, last:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe recv chain: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #1
2020/01/21 22:57:57 [debug] 3956#3956: *370 malloc: 000055FCE1D08F90:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: eof:1, avail:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: 1, last:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe recv chain: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #2
2020/01/21 22:57:57 [debug] 3956#3956: *370 malloc: 000055FCE1D09FA0:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: eof:1, avail:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: 1, last:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe recv chain: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #3
2020/01/21 22:57:57 [debug] 3956#3956: *370 malloc: 000055FCE1D15290:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: eof:1, avail:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: 1, last:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe recv chain: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #4
2020/01/21 22:57:57 [debug] 3956#3956: *370 malloc: 000055FCE1D162A0:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: eof:1, avail:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: 1, last:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe recv chain: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #5
2020/01/21 22:57:57 [debug] 3956#3956: *370 malloc: 000055FCE1D172B0:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: eof:1, avail:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: 1, last:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe recv chain: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #6
2020/01/21 22:57:57 [debug] 3956#3956: *370 malloc: 000055FCE1C8B2B0:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: eof:1, avail:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: 1, last:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe recv chain: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #7
2020/01/21 22:57:57 [debug] 3956#3956: *370 malloc: 000055FCE1C8C2C0:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: eof:1, avail:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: 1, last:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe recv chain: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #8
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe downstream ready
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D1F250, pos 000055FCE1D1F39C, size: 3764 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D1D1D0, pos 000055FCE1D1D1D0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D08F90, pos 000055FCE1D08F90, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D09FA0, pos 000055FCE1D09FA0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D15290, pos 000055FCE1D15290, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D162A0, pos 000055FCE1D162A0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D172B0, pos 000055FCE1D172B0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1C8B2B0, pos 000055FCE1C8B2B0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1C8C2C0, pos 000055FCE1C8C2C0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe length: 93040
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write downstream: 1
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write busy: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D1F39C 3764
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D1D1D0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D08F90 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write: out:000055FCE1D13B78, f:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 http output filter "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 image filter
2020/01/21 22:57:57 [debug] 3956#3956: *370 xslt filter body
2020/01/21 22:57:57 [debug] 3956#3956: *370 http postpone filter "/?" 000055FCE1D13B68
2020/01/21 22:57:57 [debug] 3956#3956: *370 write old buf t:1 f:0 000055FCE1D106F0, pos 000055FCE1D106F0, size: 301 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D1F250, pos 000055FCE1D1F39C, size: 3764 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D1D1D0, pos 000055FCE1D1D1D0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter: l:0 f:1 s:8161
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter limit 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 posix_memalign: 000055FCE1D0CFE0:512 @16
2020/01/21 22:57:57 [debug] 3956#3956: *370 malloc: 000055FCE1D21960:16384
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 301
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 3764
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: -2 "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write busy: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D08F90 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D09FA0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D15290 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write: out:000055FCE1D109E0, f:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 http output filter "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 image filter
2020/01/21 22:57:57 [debug] 3956#3956: *370 xslt filter body
2020/01/21 22:57:57 [debug] 3956#3956: *370 http postpone filter "/?" 000055FCE1D10F58
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D08F90, pos 000055FCE1D08F90, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D09FA0, pos 000055FCE1D09FA0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter: l:0 f:1 s:8192
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter limit 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: -2 "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write busy: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D15290 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D162A0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D172B0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write: out:000055FCE1D10B60, f:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 http output filter "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 image filter
2020/01/21 22:57:57 [debug] 3956#3956: *370 xslt filter body
2020/01/21 22:57:57 [debug] 3956#3956: *370 http postpone filter "/?" 000055FCE1D10F68
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D15290, pos 000055FCE1D15290, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D162A0, pos 000055FCE1D162A0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter: l:0 f:1 s:8192
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter limit 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 31
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL to write: 16384
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL_write: 16384
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4065
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: -2 "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write busy: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D172B0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1C8B2B0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1C8C2C0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write: out:000055FCE1D10CE0, f:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 http output filter "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 image filter
2020/01/21 22:57:57 [debug] 3956#3956: *370 xslt filter body
2020/01/21 22:57:57 [debug] 3956#3956: *370 http postpone filter "/?" 000055FCE1D10F88
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D172B0, pos 000055FCE1D172B0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1C8B2B0, pos 000055FCE1C8B2B0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter: l:0 f:1 s:8192
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter limit 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: -2 "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write busy: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1C8C2C0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write: out:000055FCE1D10E60, f:0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http output filter "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 image filter
2020/01/21 22:57:57 [debug] 3956#3956: *370 xslt filter body
2020/01/21 22:57:57 [debug] 3956#3956: *370 http postpone filter "/?" 000055FCE1D10E60
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1C8C2C0, pos 000055FCE1C8C2C0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter: l:0 f:1 s:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter limit 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 31
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL to write: 16384
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL_write: 16384
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4065
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: -2 "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write busy: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write: out:0000000000000000, f:0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe read upstream: 1
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: eof:1, avail:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: 9, last:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe recv chain: 36864
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #9
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #10
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #11
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #12
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #13
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #14
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #15
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #16
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #17
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe downstream ready
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1C8C2C0, pos 000055FCE1C8C2C0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D172B0, pos 000055FCE1D172B0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1C8B2B0, pos 000055FCE1C8B2B0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D15290, pos 000055FCE1D15290, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D162A0, pos 000055FCE1D162A0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D08F90, pos 000055FCE1D08F90, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D09FA0, pos 000055FCE1D09FA0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D1F250, pos 000055FCE1D1F250, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D1D1D0, pos 000055FCE1D1D1D0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe length: 56176
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write downstream: 1
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write busy: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1C8C2C0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D172B0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1C8B2B0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write: out:000055FCE1D10E60, f:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 http output filter "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 image filter
2020/01/21 22:57:57 [debug] 3956#3956: *370 xslt filter body
2020/01/21 22:57:57 [debug] 3956#3956: *370 http postpone filter "/?" 000055FCE1D10F38
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1C8C2C0, pos 000055FCE1C8C2C0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D172B0, pos 000055FCE1D172B0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter: l:0 f:1 s:8192
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter limit 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: -2 "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write busy: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1C8B2B0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D15290 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D162A0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write: out:000055FCE1D10CE0, f:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 http output filter "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 image filter
2020/01/21 22:57:57 [debug] 3956#3956: *370 xslt filter body
2020/01/21 22:57:57 [debug] 3956#3956: *370 http postpone filter "/?" 000055FCE1D10F58
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1C8B2B0, pos 000055FCE1C8B2B0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D15290, pos 000055FCE1D15290, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter: l:0 f:1 s:8192
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter limit 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 31
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL to write: 16384
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL_write: 16384
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4065
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: -2 "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write busy: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D162A0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D08F90 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D09FA0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write: out:000055FCE1D10B60, f:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 http output filter "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 image filter
2020/01/21 22:57:57 [debug] 3956#3956: *370 xslt filter body
2020/01/21 22:57:57 [debug] 3956#3956: *370 http postpone filter "/?" 000055FCE1D10F68
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D162A0, pos 000055FCE1D162A0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D08F90, pos 000055FCE1D08F90, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter: l:0 f:1 s:8192
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter limit 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: -2 "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write busy: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D09FA0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D1F250 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D1D1D0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write: out:000055FCE1D109E0, f:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 http output filter "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 image filter
2020/01/21 22:57:57 [debug] 3956#3956: *370 xslt filter body
2020/01/21 22:57:57 [debug] 3956#3956: *370 http postpone filter "/?" 000055FCE1D10F88
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D09FA0, pos 000055FCE1D09FA0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D1F250, pos 000055FCE1D1F250, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter: l:0 f:1 s:8192
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter limit 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 31
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL to write: 16384
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL_write: 16384
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4065
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: -2 "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write busy: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D1D1D0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write: out:000055FCE1D13B78, f:0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http output filter "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 image filter
2020/01/21 22:57:57 [debug] 3956#3956: *370 xslt filter body
2020/01/21 22:57:57 [debug] 3956#3956: *370 http postpone filter "/?" 000055FCE1D13B78
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D1D1D0, pos 000055FCE1D1D1D0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter: l:0 f:1 s:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter limit 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: -2 "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write busy: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write: out:0000000000000000, f:0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe read upstream: 1
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: eof:1, avail:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: 9, last:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe recv chain: 36864
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #18
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #19
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #20
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #21
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #22
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #23
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #24
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #25
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #26
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe downstream ready
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D1D1D0, pos 000055FCE1D1D1D0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D09FA0, pos 000055FCE1D09FA0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D1F250, pos 000055FCE1D1F250, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D162A0, pos 000055FCE1D162A0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D08F90, pos 000055FCE1D08F90, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1C8B2B0, pos 000055FCE1C8B2B0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D15290, pos 000055FCE1D15290, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1C8C2C0, pos 000055FCE1C8C2C0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D172B0, pos 000055FCE1D172B0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe length: 19312
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write downstream: 1
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write busy: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D1D1D0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D09FA0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D1F250 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write: out:000055FCE1D13B78, f:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 http output filter "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 image filter
2020/01/21 22:57:57 [debug] 3956#3956: *370 xslt filter body
2020/01/21 22:57:57 [debug] 3956#3956: *370 http postpone filter "/?" 000055FCE1D13B68
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D1D1D0, pos 000055FCE1D1D1D0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D09FA0, pos 000055FCE1D09FA0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter: l:0 f:1 s:8192
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter limit 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: -2 "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write busy: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D1F250 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D162A0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D08F90 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write: out:000055FCE1D109E0, f:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 http output filter "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 image filter
2020/01/21 22:57:57 [debug] 3956#3956: *370 xslt filter body
2020/01/21 22:57:57 [debug] 3956#3956: *370 http postpone filter "/?" 000055FCE1D10F58
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D1F250, pos 000055FCE1D1F250, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D162A0, pos 000055FCE1D162A0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter: l:0 f:1 s:8192
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter limit 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 31
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL to write: 16384
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL_write: 16384
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4065
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: -2 "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write busy: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D08F90 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1C8B2B0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D15290 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write: out:000055FCE1D10B60, f:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 http output filter "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 image filter
2020/01/21 22:57:57 [debug] 3956#3956: *370 xslt filter body
2020/01/21 22:57:57 [debug] 3956#3956: *370 http postpone filter "/?" 000055FCE1D10F68
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D08F90, pos 000055FCE1D08F90, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1C8B2B0, pos 000055FCE1C8B2B0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter: l:0 f:1 s:8192
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter limit 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: -2 "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write busy: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D15290 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1C8C2C0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D172B0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write: out:000055FCE1D10CE0, f:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 http output filter "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 image filter
2020/01/21 22:57:57 [debug] 3956#3956: *370 xslt filter body
2020/01/21 22:57:57 [debug] 3956#3956: *370 http postpone filter "/?" 000055FCE1D10F88
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D15290, pos 000055FCE1D15290, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1C8C2C0, pos 000055FCE1C8C2C0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter: l:0 f:1 s:8192
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter limit 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 31
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL to write: 16384
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL_write: 16384
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4065
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: -2 "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write busy: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write buf ls:1 000055FCE1D172B0 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write: out:000055FCE1D10E60, f:0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http output filter "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 image filter
2020/01/21 22:57:57 [debug] 3956#3956: *370 xslt filter body
2020/01/21 22:57:57 [debug] 3956#3956: *370 http postpone filter "/?" 000055FCE1D10E60
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D172B0, pos 000055FCE1D172B0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter: l:0 f:1 s:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter limit 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: -2 "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write busy: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write: out:0000000000000000, f:0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe read upstream: 1
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: eof:1, avail:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: 9, last:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe recv chain: 19312
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #27
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #28
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #29
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #30
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: eof:1, avail:0
2020/01/21 22:57:57 [debug] 3956#3956: *370 readv: 5, last:4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe recv chain: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D172B0, pos 000055FCE1D172B0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D15290, pos 000055FCE1D15290, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1C8C2C0, pos 000055FCE1C8C2C0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf in   s:1 t:1 f:0 000055FCE1D08F90, pos 000055FCE1D08F90, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf free s:0 t:1 f:0 000055FCE1C8B2B0, pos 000055FCE1C8B2B0, size: 2928 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf free s:0 t:1 f:0 000055FCE1D1F250, pos 000055FCE1D1F250, size: 0 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf free s:0 t:1 f:0 000055FCE1D162A0, pos 000055FCE1D162A0, size: 0 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf free s:0 t:1 f:0 000055FCE1D1D1D0, pos 000055FCE1D1D1D0, size: 0 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe buf free s:0 t:1 f:0 000055FCE1D09FA0, pos 000055FCE1D09FA0, size: 0 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe length: 2928
2020/01/21 22:57:57 [debug] 3956#3956: *370 input buf #31
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 000055FCE1D162A0
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 000055FCE1D1D1D0
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 000055FCE1D09FA0
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write downstream: 1
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write downstream flush in
2020/01/21 22:57:57 [debug] 3956#3956: *370 http output filter "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 image filter
2020/01/21 22:57:57 [debug] 3956#3956: *370 xslt filter body
2020/01/21 22:57:57 [debug] 3956#3956: *370 http postpone filter "/?" 000055FCE1D1B760
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D172B0, pos 000055FCE1D172B0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D15290, pos 000055FCE1D15290, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1C8C2C0, pos 000055FCE1C8C2C0, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1D08F90, pos 000055FCE1D08F90, size: 4096 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:1 f:0 000055FCE1C8B2B0, pos 000055FCE1C8B2B0, size: 2928 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter: l:0 f:0 s:19312
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter limit 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 31
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL to write: 16384
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL_write: 16384
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4065
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 4096
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL buf copy: 2928
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: -2 "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 pipe write downstream done
2020/01/21 22:57:57 [debug] 3956#3956: *370 event timer: 12, old: 31040684, new: 31040684
2020/01/21 22:57:57 [debug] 3956#3956: *370 http upstream exit: 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 finalize http upstream request: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 finalize http proxy request
2020/01/21 22:57:57 [debug] 3956#3956: *370 free rr peer 1 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 close http upstream connection: 12
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 000055FCE1D0E100, unused: 48
2020/01/21 22:57:57 [debug] 3956#3956: *370 event timer del: 12: 31040684
2020/01/21 22:57:57 [debug] 3956#3956: *370 reusable connection: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http upstream temp fd: -1
2020/01/21 22:57:57 [debug] 3956#3956: *370 http output filter "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 image filter
2020/01/21 22:57:57 [debug] 3956#3956: *370 xslt filter body
2020/01/21 22:57:57 [debug] 3956#3956: *370 http postpone filter "/?" 00007FFE305DFF40
2020/01/21 22:57:57 [debug] 3956#3956: *370 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter: l:1 f:0 s:0
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter limit 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL to write: 15185
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL_write: 15185
2020/01/21 22:57:57 [debug] 3956#3956: *370 http write filter 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 http copy filter: 0 "/?"
2020/01/21 22:57:57 [debug] 3956#3956: *370 http finalize request: 0, "/?" a:1, c:1
2020/01/21 22:57:57 [debug] 3956#3956: *370 set http keepalive handler
2020/01/21 22:57:57 [debug] 3956#3956: *370 http close request
2020/01/21 22:57:57 [debug] 3956#3956: *370 http log handler
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 000055FCE1C8C2C0
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 000055FCE1C8B2B0
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 000055FCE1D172B0
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 000055FCE1D15290
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 000055FCE1D08F90
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 000055FCE1D1F250
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 000055FCE1D1A770, unused: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 000055FCE1D12C60, unused: 8
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 000055FCE1D106D0, unused: 1549
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 000055FCE1CDC8E0
2020/01/21 22:57:57 [debug] 3956#3956: *370 hc free: 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 hc busy: 0000000000000000 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 000055FCE1D21960
2020/01/21 22:57:57 [debug] 3956#3956: *370 reusable connection: 1
2020/01/21 22:57:57 [debug] 3956#3956: *370 event timer add: 11: 65000:31045684
2020/01/21 22:57:57 [debug] 3956#3956: *370 http keepalive handler
2020/01/21 22:57:57 [debug] 3956#3956: *370 malloc: 000055FCE1CDC8E0:1024
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL_read: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL_get_error: 6
2020/01/21 22:57:57 [debug] 3956#3956: *370 peer shutdown SSL cleanly
2020/01/21 22:57:57 [info] 3956#3956: *370 client 192.168.50.1 closed keepalive connection
2020/01/21 22:57:57 [debug] 3956#3956: *370 close http connection: 11
2020/01/21 22:57:57 [debug] 3956#3956: *370 SSL_shutdown: 1
2020/01/21 22:57:57 [debug] 3956#3956: *370 event timer del: 11: 31045684
2020/01/21 22:57:57 [debug] 3956#3956: *370 reusable connection: 0
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 000055FCE1CDC8E0
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 0000000000000000
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 000055FCE1C6A7C0, unused: 48
2020/01/21 22:57:57 [debug] 3956#3956: *370 free: 000055FCE1D0CFE0, unused: 400

Additional information

Core functionality