Ticket #143: error_log-2017-12-13-PS

File error_log-2017-12-13-PS, 86.2 KB (added by Lewis Rosenthal, 7 years ago)

PS error after test libcx0 (may be unrelated to fixes here)

Line 
1*** HEAP RESULT 1
2D [13/Dec/2017:12:15:52 +0500] [Client 14] Returning HTTP Unauthorized for Cancel-Job (ipp://localhost/jobs/20) from localhost
3D [13/Dec/2017:12:15:52 +0500] [Client 14] cupsdSendHeader: code=401, type="text/html", auth_type=0
4D [13/Dec/2017:12:15:52 +0500] [Client 14] WWW-Authenticate: Basic realm=\"CUPS\", trc=\"y\"
5D [13/Dec/2017:12:15:52 +0500] [Client 15] Accepted from localhost (Domain)
6D [13/Dec/2017:12:15:52 +0500] [Client 15] Waiting for request.
7D [13/Dec/2017:12:15:52 +0500] [Client 14] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
8D [13/Dec/2017:12:15:52 +0500] [Client 14] Closing connection.
9D [13/Dec/2017:12:15:52 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
10D [13/Dec/2017:12:15:52 +0500] [Client 16] Accepted from localhost (Domain)
11D [13/Dec/2017:12:15:52 +0500] [Client 16] Waiting for request.
12D [13/Dec/2017:12:15:52 +0500] [Client 15] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
13D [13/Dec/2017:12:15:52 +0500] [Client 15] Closing connection.
14D [13/Dec/2017:12:15:52 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
15D [13/Dec/2017:12:15:52 +0500] [Client 17] Accepted from localhost (Domain)
16D [13/Dec/2017:12:15:52 +0500] [Client 17] Waiting for request.
17D [13/Dec/2017:12:15:52 +0500] [Client 16] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
18D [13/Dec/2017:12:15:52 +0500] [Client 16] Closing connection.
19D [13/Dec/2017:12:15:52 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
20D [13/Dec/2017:12:15:52 +0500] [Client 17] POST /jobs HTTP/1.1
21D [13/Dec/2017:12:15:52 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
22D [13/Dec/2017:12:15:52 +0500] [Client 17] Read: status=200
23D [13/Dec/2017:12:15:52 +0500] [Client 17] Authorized as root using Local.
24D [13/Dec/2017:12:15:52 +0500] [Client 17] 2.0 Cancel-Job 1
25D [13/Dec/2017:12:15:52 +0500] Cancel-Job ipp://localhost/jobs/20
26D [13/Dec/2017:12:15:52 +0500] cupsdIsAuthorized: username="root"
27D [13/Dec/2017:12:15:52 +0500] [Job 20] time-at-completed=1513185352
28D [13/Dec/2017:12:15:52 +0500] Discarding unused job-completed event...
29I [13/Dec/2017:12:15:52 +0500] [Job 20] Job canceled by \"root\"
30I [13/Dec/2017:12:15:52 +0500] Expiring subscriptions...
31D [13/Dec/2017:12:15:52 +0500] cupsdMarkDirty(---J-)
32D [13/Dec/2017:12:15:52 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
33D [13/Dec/2017:12:15:52 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
34I [13/Dec/2017:12:15:52 +0500] [Job 20] Canceled by "root".
35D [13/Dec/2017:12:15:52 +0500] [Client 17] Returning IPP successful-ok for Cancel-Job (ipp://localhost/jobs/20) from localhost
36D [13/Dec/2017:12:15:52 +0500] [Client 17] Content-Length: 75
37D [13/Dec/2017:12:15:52 +0500] [Client 17] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
38D [13/Dec/2017:12:15:52 +0500] [Job 20] Removing document files.
39D [13/Dec/2017:12:15:53 +0500] cupsdMarkDirty(---J-)
40D [13/Dec/2017:12:15:53 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
41D [13/Dec/2017:12:15:53 +0500] [Client 17] con->http=0x2020dee0
42D [13/Dec/2017:12:15:53 +0500] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x20209160(IPP_STATE_DATA), pipe_pid=0, file=-1
43D [13/Dec/2017:12:15:53 +0500] [Client 17] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
44D [13/Dec/2017:12:15:53 +0500] [Client 17] bytes=0, http_state=0, data_remaining=75
45D [13/Dec/2017:12:15:53 +0500] [Client 17] Flushing write buffer.
46D [13/Dec/2017:12:15:53 +0500] [Client 17] New state is HTTP_STATE_WAITING
47D [13/Dec/2017:12:15:53 +0500] [Client 17] Waiting for request.
48D [13/Dec/2017:12:15:53 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
49D [13/Dec/2017:12:15:53 +0500] [CGI] cgiSetVariable: refresh_page=\"5;URL=http://localhost:631/jobs/\"
50D [13/Dec/2017:12:15:53 +0500] [CGI] cgiSetVariable: TITLE=\"Jobs\"
51D [13/Dec/2017:12:15:53 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
52D [13/Dec/2017:12:15:53 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
53D [13/Dec/2017:12:15:53 +0500] [Client 13] CGI data ready to be sent.
54D [13/Dec/2017:12:15:53 +0500] [Client 13] con->http=0x20203540
55D [13/Dec/2017:12:15:53 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=95, file=13
56D [13/Dec/2017:12:15:53 +0500] [Client 13] Waiting for CGI data.
57D [13/Dec/2017:12:15:53 +0500] [Client 13] Script header: *** HEAP RESULT 1
58D [13/Dec/2017:12:15:53 +0500] [Client 13] Script header: Content-Type: text/html;charset=utf-8
59D [13/Dec/2017:12:15:53 +0500] [Client 13] Script header:
60D [13/Dec/2017:12:15:53 +0500] [Client 13] Sending status 200 for CGI.
61D [13/Dec/2017:12:15:53 +0500] [Client 13] cupsdSendHeader: code=200, type="(null)", auth_type=0
62D [13/Dec/2017:12:15:53 +0500] [Client 13] con->http=0x20203540
63D [13/Dec/2017:12:15:53 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=95, file=13
64D [13/Dec/2017:12:15:53 +0500] [Client 13] Waiting for CGI data.
65D [13/Dec/2017:12:15:53 +0500] [Client 13] CGI data ready to be sent.
66D [13/Dec/2017:12:15:53 +0500] [Client 17] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
67D [13/Dec/2017:12:15:53 +0500] [Client 17] Closing connection.
68D [13/Dec/2017:12:15:53 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
69D [13/Dec/2017:12:15:53 +0500] [Client 13] con->http=0x20203540
70D [13/Dec/2017:12:15:53 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=95, file=13
71D [13/Dec/2017:12:15:53 +0500] [Client 13] Waiting for CGI data.
72D [13/Dec/2017:12:15:53 +0500] [Client 13] con->http=0x20203540
73D [13/Dec/2017:12:15:53 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=95, file=13
74D [13/Dec/2017:12:15:53 +0500] [Client 13] Waiting for CGI data.
75D [13/Dec/2017:12:15:53 +0500] [Client 13] CGI data ready to be sent.
76D [13/Dec/2017:12:15:53 +0500] [Client 13] con->http=0x20203540
77D [13/Dec/2017:12:15:53 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=95, file=13
78D [13/Dec/2017:12:15:53 +0500] [Client 13] Waiting for CGI data.
79D [13/Dec/2017:12:15:53 +0500] [Client 13] Sending 0-length chunk.
80D [13/Dec/2017:12:15:53 +0500] [Client 13] Flushing write buffer.
81D [13/Dec/2017:12:15:53 +0500] [Client 13] New state is HTTP_STATE_WAITING
82D [13/Dec/2017:12:15:53 +0500] [Client 13] Waiting for request.
83D [13/Dec/2017:12:15:53 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
84D [13/Dec/2017:12:15:53 +0500] PID 95 (/@unixroot/usr/lib/cups/cgi-bin/jobs.cgi) exited with no errors.
85D [13/Dec/2017:12:15:55 +0500] [Client 13] GET /printers/ HTTP/1.1
86D [13/Dec/2017:12:15:55 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
87D [13/Dec/2017:12:15:55 +0500] [Client 13] Read: status=200
88D [13/Dec/2017:12:15:55 +0500] [Client 13] No authentication data provided.
89D [13/Dec/2017:12:15:55 +0500] [Client 13] Processing GET /printers/
90D [13/Dec/2017:12:15:55 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi"
91D [13/Dec/2017:12:15:55 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups"
92D [13/Dec/2017:12:15:55 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups"
93D [13/Dec/2017:12:15:55 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
94D [13/Dec/2017:12:15:55 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
95D [13/Dec/2017:12:15:55 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
96D [13/Dec/2017:12:15:55 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
97D [13/Dec/2017:12:15:55 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups"
98D [13/Dec/2017:12:15:55 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups"
99D [13/Dec/2017:12:15:55 +0500] [CGI] envp[8] = "HOME=C:\\var\\temp"
100D [13/Dec/2017:12:15:55 +0500] [CGI] envp[9] = "NLSPATH=C:\\MPTN\\MSG\\NLS\\%N;C:\\TCPIP\\msg\\ENUS850\\%N;"
101D [13/Dec/2017:12:15:55 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
102D [13/Dec/2017:12:15:55 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@bluelion"
103D [13/Dec/2017:12:15:55 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3"
104D [13/Dec/2017:12:15:55 +0500] [CGI] envp[13] = "TMPDIR=C:\\var\\temp"
105D [13/Dec/2017:12:15:55 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600"
106D [13/Dec/2017:12:15:55 +0500] [CGI] envp[15] = "USER=root"
107D [13/Dec/2017:12:15:55 +0500] [CGI] envp[16] = "UNIXROOT=C:"
108D [13/Dec/2017:12:15:55 +0500] [CGI] envp[17] = "ETC=C:\\MPTN\\ETC"
109D [13/Dec/2017:12:15:55 +0500] [CGI] envp[18] = "CUPS_MAX_MESSAGE=2047"
110D [13/Dec/2017:12:15:55 +0500] [CGI] envp[19] = "CUPS_SERVER=\\socket\\cups.sock"
111D [13/Dec/2017:12:15:55 +0500] [CGI] envp[20] = "CUPS_ENCRYPTION=IfRequested"
112D [13/Dec/2017:12:15:55 +0500] [CGI] envp[21] = "IPP_PORT=631"
113D [13/Dec/2017:12:15:55 +0500] [CGI] envp[22] = "LANG=en_US.UTF8"
114D [13/Dec/2017:12:15:55 +0500] [CGI] envp[23] = "REDIRECT_STATUS=1"
115D [13/Dec/2017:12:15:55 +0500] [CGI] envp[24] = "GATEWAY_INTERFACE=CGI/1.1"
116D [13/Dec/2017:12:15:55 +0500] [CGI] envp[25] = "SERVER_NAME=localhost"
117D [13/Dec/2017:12:15:55 +0500] [CGI] envp[26] = "SERVER_PORT=631"
118D [13/Dec/2017:12:15:55 +0500] [CGI] envp[27] = "REMOTE_ADDR=127.0.0.1"
119D [13/Dec/2017:12:15:55 +0500] [CGI] envp[28] = "REMOTE_HOST=localhost"
120D [13/Dec/2017:12:15:55 +0500] [CGI] envp[29] = "SCRIPT_NAME=/printers/"
121D [13/Dec/2017:12:15:55 +0500] [CGI] envp[30] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/printers/"
122D [13/Dec/2017:12:15:55 +0500] [CGI] envp[31] = "SERVER_PROTOCOL=HTTP/1.1"
123D [13/Dec/2017:12:15:55 +0500] [CGI] envp[32] = "HTTP_COOKIE=org.cups.sid=53f2efb08fa70698b63f646e39907fa4"
124D [13/Dec/2017:12:15:55 +0500] [CGI] envp[33] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:45.0) Gecko/20100101 Firefox/45.0"
125D [13/Dec/2017:12:15:55 +0500] [CGI] envp[34] = "HTTP_REFERER=http://localhost:631/jobs/"
126D [13/Dec/2017:12:15:55 +0500] [CGI] envp[35] = "REQUEST_METHOD=GET"
127D [13/Dec/2017:12:15:55 +0500] [CGI] envp[36] = "QUERY_STRING="
128D [13/Dec/2017:12:15:55 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/printers.cgi (PID 97)
129I [13/Dec/2017:12:15:55 +0500] [Client 13] Started "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" (pid=97, file=12)
130D [13/Dec/2017:12:15:55 +0500] [Client 13] Waiting for CGI data.
131D [13/Dec/2017:12:15:55 +0500] [Client 18] Accepted from localhost (Domain)
132D [13/Dec/2017:12:15:55 +0500] [Client 18] Waiting for request.
133D [13/Dec/2017:12:15:55 +0500] [CGI] org.cups.sid cookie is \"53f2efb08fa70698b63f646e39907fa4\"
134D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
135D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
136D [13/Dec/2017:12:15:55 +0500] [Client 18] POST / HTTP/1.1
137D [13/Dec/2017:12:15:55 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
138D [13/Dec/2017:12:15:55 +0500] [Client 18] Read: status=200
139D [13/Dec/2017:12:15:55 +0500] [Client 18] No authentication data provided.
140D [13/Dec/2017:12:15:55 +0500] [Client 18] 2.0 CUPS-Get-Default 1
141D [13/Dec/2017:12:15:55 +0500] CUPS-Get-Default
142D [13/Dec/2017:12:15:55 +0500] CUPS-Get-Default client-error-not-found: No default printer.
143D [13/Dec/2017:12:15:55 +0500] [Client 18] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
144D [13/Dec/2017:12:15:55 +0500] [Client 18] Content-Length: 113
145D [13/Dec/2017:12:15:55 +0500] [Client 18] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
146D [13/Dec/2017:12:15:55 +0500] [Client 18] con->http=0x20209d00
147D [13/Dec/2017:12:15:55 +0500] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x202091e0(IPP_STATE_DATA), pipe_pid=0, file=-1
148D [13/Dec/2017:12:15:55 +0500] [Client 18] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
149D [13/Dec/2017:12:15:55 +0500] [Client 18] bytes=0, http_state=0, data_remaining=113
150D [13/Dec/2017:12:15:55 +0500] [Client 18] Flushing write buffer.
151D [13/Dec/2017:12:15:55 +0500] [Client 18] New state is HTTP_STATE_WAITING
152D [13/Dec/2017:12:15:55 +0500] [Client 18] Waiting for request.
153D [13/Dec/2017:12:15:55 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
154D [13/Dec/2017:12:15:55 +0500] [CGI] show_all_printers(http=0x2003c380, user=\"(null)\")
155D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetVariable: TITLE=\"Printers\"
156D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
157D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
158D [13/Dec/2017:12:15:55 +0500] [Client 13] CGI data ready to be sent.
159D [13/Dec/2017:12:15:55 +0500] [Client 13] con->http=0x20203540
160D [13/Dec/2017:12:15:55 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=97, file=12
161D [13/Dec/2017:12:15:55 +0500] [Client 13] Waiting for CGI data.
162D [13/Dec/2017:12:15:55 +0500] [Client 13] Script header: *** HEAP RESULT 1
163D [13/Dec/2017:12:15:55 +0500] [Client 13] Script header: Content-Type: text/html;charset=utf-8
164D [13/Dec/2017:12:15:55 +0500] [Client 13] Script header:
165D [13/Dec/2017:12:15:55 +0500] [Client 13] Sending status 200 for CGI.
166D [13/Dec/2017:12:15:55 +0500] [Client 13] cupsdSendHeader: code=200, type="(null)", auth_type=0
167D [13/Dec/2017:12:15:55 +0500] [Client 13] con->http=0x20203540
168D [13/Dec/2017:12:15:55 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=97, file=12
169D [13/Dec/2017:12:15:55 +0500] [Client 13] Waiting for CGI data.
170D [13/Dec/2017:12:15:55 +0500] [Client 18] POST / HTTP/1.1
171D [13/Dec/2017:12:15:55 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
172D [13/Dec/2017:12:15:55 +0500] [Client 18] Read: status=200
173D [13/Dec/2017:12:15:55 +0500] [Client 18] No authentication data provided.
174D [13/Dec/2017:12:15:55 +0500] [Client 18] 2.0 CUPS-Get-Printers 2
175D [13/Dec/2017:12:15:55 +0500] CUPS-Get-Printers
176D [13/Dec/2017:12:15:55 +0500] [Client 18] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
177D [13/Dec/2017:12:15:55 +0500] [Client 18] Content-Length: 1019
178D [13/Dec/2017:12:15:55 +0500] [Client 18] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
179D [13/Dec/2017:12:15:55 +0500] [Client 18] con->http=0x20209d00
180D [13/Dec/2017:12:15:55 +0500] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1019, response=0x20126be0(IPP_STATE_DATA), pipe_pid=0, file=-1
181D [13/Dec/2017:12:15:55 +0500] [Client 18] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
182D [13/Dec/2017:12:15:55 +0500] [Client 18] bytes=0, http_state=0, data_remaining=1019
183D [13/Dec/2017:12:15:55 +0500] [Client 18] Flushing write buffer.
184D [13/Dec/2017:12:15:55 +0500] [Client 18] New state is HTTP_STATE_WAITING
185D [13/Dec/2017:12:15:55 +0500] [Client 18] Waiting for request.
186D [13/Dec/2017:12:15:55 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
187D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetVariable: TOTAL=\"3\"
188D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
189D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
190D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_state[0]=\"3\"
191D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_state_message[0]=\"\"
192D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\"
193D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/HP_Color_LaserJet_3800\"
194D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_name[0]=\"HP_Color_LaserJet_3800\"
195D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_location[0]=\"office\"
196D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_info[0]=\"HP Color LaserJet 3800 pcl3\"
197D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_make_and_model[0]=\"HP Color LaserJet 3800 pcl3, hpcups 3.16.11\"
198D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
199D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
200D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_state[1]=\"3\"
201D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_state_message[1]=\"\"
202D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: admin_uri[1]=\"/admin/\"
203D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_uri_supported[1]=\"/printers/HP_Color_Laserjet_3800_hpijs\"
204D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_name[1]=\"HP_Color_Laserjet_3800_hpijs\"
205D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_location[1]=\"office\"
206D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_info[1]=\"HP Color LaserJet 3800 hpijs pcl3\"
207D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_make_and_model[1]=\"HP Color LaserJet 3800 hpijs pcl3, 3.16.11\"
208D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
209D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
210D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_state[2]=\"3\"
211D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_state_message[2]=\"Filter failed\"
212D [13/Dec/2017:12:15:55 +0500] [Client 13] CGI data ready to be sent.
213D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: admin_uri[2]=\"/admin/\"
214D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_uri_supported[2]=\"/printers/HP_Color_LaserJet_3800_PS\"
215D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_name[2]=\"HP_Color_LaserJet_3800_PS\"
216D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_location[2]=\"office\"
217D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_info[2]=\"HP Color LaserJet 3800 PS\"
218D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetArray: printer_make_and_model[2]=\"HP Color LaserJet 3800 Postscript (recommended)\"
219D [13/Dec/2017:12:15:55 +0500] [CGI] cgiSetVariable: THISURL=\"/printers/\"
220D [13/Dec/2017:12:15:55 +0500] [Client 13] con->http=0x20203540
221D [13/Dec/2017:12:15:55 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=97, file=12
222D [13/Dec/2017:12:15:55 +0500] [Client 13] Waiting for CGI data.
223D [13/Dec/2017:12:15:55 +0500] [Client 13] con->http=0x20203540
224D [13/Dec/2017:12:15:55 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=97, file=12
225D [13/Dec/2017:12:15:55 +0500] [Client 13] Waiting for CGI data.
226D [13/Dec/2017:12:15:55 +0500] [Client 13] CGI data ready to be sent.
227D [13/Dec/2017:12:15:55 +0500] [Client 18] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
228D [13/Dec/2017:12:15:55 +0500] [Client 18] Closing connection.
229D [13/Dec/2017:12:15:55 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
230D [13/Dec/2017:12:15:55 +0500] [Client 13] con->http=0x20203540
231D [13/Dec/2017:12:15:55 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=97, file=12
232D [13/Dec/2017:12:15:55 +0500] [Client 13] Waiting for CGI data.
233D [13/Dec/2017:12:15:55 +0500] [Client 13] con->http=0x20203540
234D [13/Dec/2017:12:15:55 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=97, file=12
235D [13/Dec/2017:12:15:55 +0500] [Client 13] Waiting for CGI data.
236D [13/Dec/2017:12:15:55 +0500] [Client 13] CGI data ready to be sent.
237D [13/Dec/2017:12:15:55 +0500] [Client 13] con->http=0x20203540
238D [13/Dec/2017:12:15:55 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=97, file=12
239D [13/Dec/2017:12:15:55 +0500] [Client 13] Waiting for CGI data.
240D [13/Dec/2017:12:15:55 +0500] [Client 13] Sending 0-length chunk.
241D [13/Dec/2017:12:15:55 +0500] [Client 13] Flushing write buffer.
242D [13/Dec/2017:12:15:55 +0500] [Client 13] New state is HTTP_STATE_WAITING
243D [13/Dec/2017:12:15:55 +0500] [Client 13] Waiting for request.
244D [13/Dec/2017:12:15:55 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
245W [13/Dec/2017:12:15:55 +0500] cupsdDoSelect: select() returned -1... pause and retry select()
246I [13/Dec/2017:12:15:57 +0500] Saving printers.conf...
247I [13/Dec/2017:12:15:57 +0500] Saving job.cache...
248D [13/Dec/2017:12:15:57 +0500] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
249D [13/Dec/2017:12:15:57 +0500] PID 97 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
250D [13/Dec/2017:12:15:57 +0500] [Client 12] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
251D [13/Dec/2017:12:15:57 +0500] [Client 12] Closing connection.
252D [13/Dec/2017:12:15:57 +0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
253D [13/Dec/2017:12:16:01 +0500] [Client 13] GET /printers/HP_Color_LaserJet_3800_PS HTTP/1.1
254D [13/Dec/2017:12:16:01 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
255D [13/Dec/2017:12:16:01 +0500] [Client 13] Read: status=200
256D [13/Dec/2017:12:16:01 +0500] [Client 13] No authentication data provided.
257D [13/Dec/2017:12:16:01 +0500] [Client 13] Processing GET /printers/HP_Color_LaserJet_3800_PS
258D [13/Dec/2017:12:16:01 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi"
259D [13/Dec/2017:12:16:01 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups"
260D [13/Dec/2017:12:16:01 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups"
261D [13/Dec/2017:12:16:01 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
262D [13/Dec/2017:12:16:01 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
263D [13/Dec/2017:12:16:01 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
264D [13/Dec/2017:12:16:01 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
265D [13/Dec/2017:12:16:01 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups"
266D [13/Dec/2017:12:16:01 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups"
267D [13/Dec/2017:12:16:01 +0500] [CGI] envp[8] = "HOME=C:\\var\\temp"
268D [13/Dec/2017:12:16:01 +0500] [CGI] envp[9] = "NLSPATH=C:\\MPTN\\MSG\\NLS\\%N;C:\\TCPIP\\msg\\ENUS850\\%N;"
269D [13/Dec/2017:12:16:01 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
270D [13/Dec/2017:12:16:01 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@bluelion"
271D [13/Dec/2017:12:16:01 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3"
272D [13/Dec/2017:12:16:01 +0500] [CGI] envp[13] = "TMPDIR=C:\\var\\temp"
273D [13/Dec/2017:12:16:01 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600"
274D [13/Dec/2017:12:16:01 +0500] [CGI] envp[15] = "USER=root"
275D [13/Dec/2017:12:16:01 +0500] [CGI] envp[16] = "UNIXROOT=C:"
276D [13/Dec/2017:12:16:01 +0500] [CGI] envp[17] = "ETC=C:\\MPTN\\ETC"
277D [13/Dec/2017:12:16:01 +0500] [CGI] envp[18] = "CUPS_MAX_MESSAGE=2047"
278D [13/Dec/2017:12:16:01 +0500] [CGI] envp[19] = "CUPS_SERVER=\\socket\\cups.sock"
279D [13/Dec/2017:12:16:01 +0500] [CGI] envp[20] = "CUPS_ENCRYPTION=IfRequested"
280D [13/Dec/2017:12:16:01 +0500] [CGI] envp[21] = "IPP_PORT=631"
281D [13/Dec/2017:12:16:01 +0500] [CGI] envp[22] = "LANG=en_US.UTF8"
282D [13/Dec/2017:12:16:01 +0500] [CGI] envp[23] = "REDIRECT_STATUS=1"
283D [13/Dec/2017:12:16:01 +0500] [CGI] envp[24] = "GATEWAY_INTERFACE=CGI/1.1"
284D [13/Dec/2017:12:16:01 +0500] [CGI] envp[25] = "SERVER_NAME=localhost"
285D [13/Dec/2017:12:16:01 +0500] [CGI] envp[26] = "SERVER_PORT=631"
286D [13/Dec/2017:12:16:01 +0500] [CGI] envp[27] = "REMOTE_ADDR=127.0.0.1"
287D [13/Dec/2017:12:16:01 +0500] [CGI] envp[28] = "REMOTE_HOST=localhost"
288D [13/Dec/2017:12:16:01 +0500] [CGI] envp[29] = "SCRIPT_NAME=/printers/HP_Color_LaserJet_3800_PS"
289D [13/Dec/2017:12:16:01 +0500] [CGI] envp[30] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/printers/HP_Color_LaserJet_3800_PS"
290D [13/Dec/2017:12:16:01 +0500] [CGI] envp[31] = "PATH_INFO=/HP_Color_LaserJet_3800_PS"
291D [13/Dec/2017:12:16:01 +0500] [CGI] envp[32] = "SERVER_PROTOCOL=HTTP/1.1"
292D [13/Dec/2017:12:16:01 +0500] [CGI] envp[33] = "HTTP_COOKIE=org.cups.sid=53f2efb08fa70698b63f646e39907fa4"
293D [13/Dec/2017:12:16:01 +0500] [CGI] envp[34] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:45.0) Gecko/20100101 Firefox/45.0"
294D [13/Dec/2017:12:16:01 +0500] [CGI] envp[35] = "HTTP_REFERER=http://localhost:631/printers/"
295D [13/Dec/2017:12:16:01 +0500] [CGI] envp[36] = "REQUEST_METHOD=GET"
296D [13/Dec/2017:12:16:01 +0500] [CGI] envp[37] = "QUERY_STRING="
297D [13/Dec/2017:12:16:01 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/printers.cgi (PID 99)
298I [13/Dec/2017:12:16:01 +0500] [Client 13] Started "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" (pid=99, file=10)
299D [13/Dec/2017:12:16:01 +0500] [Client 13] Waiting for CGI data.
300D [13/Dec/2017:12:16:01 +0500] [Client 19] Accepted from localhost (Domain)
301D [13/Dec/2017:12:16:01 +0500] [Client 19] Waiting for request.
302D [13/Dec/2017:12:16:01 +0500] [CGI] org.cups.sid cookie is \"53f2efb08fa70698b63f646e39907fa4\"
303D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
304D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
305D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_LaserJet_3800_PS\"
306D [13/Dec/2017:12:16:01 +0500] [Client 19] POST / HTTP/1.1
307D [13/Dec/2017:12:16:01 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
308D [13/Dec/2017:12:16:01 +0500] [Client 19] Read: status=200
309D [13/Dec/2017:12:16:01 +0500] [Client 19] No authentication data provided.
310D [13/Dec/2017:12:16:01 +0500] [Client 19] 2.0 CUPS-Get-Default 1
311D [13/Dec/2017:12:16:01 +0500] CUPS-Get-Default
312D [13/Dec/2017:12:16:01 +0500] CUPS-Get-Default client-error-not-found: No default printer.
313D [13/Dec/2017:12:16:01 +0500] [Client 19] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
314D [13/Dec/2017:12:16:01 +0500] [Client 19] Content-Length: 113
315D [13/Dec/2017:12:16:01 +0500] [Client 19] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
316D [13/Dec/2017:12:16:01 +0500] [Client 19] con->http=0x202004e0
317D [13/Dec/2017:12:16:01 +0500] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x202091e0(IPP_STATE_DATA), pipe_pid=0, file=-1
318D [13/Dec/2017:12:16:01 +0500] [Client 19] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
319D [13/Dec/2017:12:16:01 +0500] [Client 19] bytes=0, http_state=0, data_remaining=113
320D [13/Dec/2017:12:16:01 +0500] [Client 19] Flushing write buffer.
321D [13/Dec/2017:12:16:01 +0500] [Client 19] New state is HTTP_STATE_WAITING
322D [13/Dec/2017:12:16:01 +0500] [Client 19] Waiting for request.
323D [13/Dec/2017:12:16:01 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
324D [13/Dec/2017:12:16:01 +0500] [CGI] show_printer(http=0x2003c3c0, printer=\"HP_Color_LaserJet_3800_PS\")
325D [13/Dec/2017:12:16:01 +0500] [Client 19] POST / HTTP/1.1
326D [13/Dec/2017:12:16:01 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
327D [13/Dec/2017:12:16:01 +0500] [Client 19] Read: status=200
328D [13/Dec/2017:12:16:01 +0500] [Client 19] No authentication data provided.
329D [13/Dec/2017:12:16:01 +0500] [Client 19] 2.0 Get-Printer-Attributes 2
330D [13/Dec/2017:12:16:01 +0500] Get-Printer-Attributes ipp://localhost/printers/HP_Color_LaserJet_3800_PS
331D [13/Dec/2017:12:16:01 +0500] [Client 19] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_Color_LaserJet_3800_PS) from localhost
332D [13/Dec/2017:12:16:01 +0500] [Client 19] Content-Length: 698
333D [13/Dec/2017:12:16:01 +0500] [Client 19] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
334D [13/Dec/2017:12:16:01 +0500] [Client 19] con->http=0x202004e0
335D [13/Dec/2017:12:16:01 +0500] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=698, response=0x20207d40(IPP_STATE_DATA), pipe_pid=0, file=-1
336D [13/Dec/2017:12:16:01 +0500] [Client 19] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
337D [13/Dec/2017:12:16:01 +0500] [Client 19] bytes=0, http_state=0, data_remaining=698
338D [13/Dec/2017:12:16:01 +0500] [Client 19] Flushing write buffer.
339D [13/Dec/2017:12:16:01 +0500] [Client 19] New state is HTTP_STATE_WAITING
340D [13/Dec/2017:12:16:01 +0500] [Client 19] Waiting for request.
341D [13/Dec/2017:12:16:01 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
342D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
343D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
344D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
345D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
346D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\"
347D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetArray: printer_is_shared[0]=\"1\"
348D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetArray: printer_state[0]=\"3\"
349D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\"
350D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/HP_Color_LaserJet_3800_PS\"
351D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetArray: printer_name[0]=\"HP_Color_LaserJet_3800_PS\"
352D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetArray: printer_location[0]=\"office\"
353D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetArray: printer_info[0]=\"HP Color LaserJet 3800 PS\"
354D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\"
355D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetArray: device_uri[0]=\"socket://192.168.100.16\"
356D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetArray: color_supported[0]=\"1\"
357D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetArray: printer_make_and_model[0]=\"HP Color LaserJet 3800 Postscript (recommended)\"
358D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetArray: media_default[0]=\"na_letter_8.5x11in\"
359D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetArray: sides_supported[0]=\"one-sided\"
360D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetArray: sides_default[0]=\"one-sided\"
361D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetArray: printer_commands[0]=\"AutoConfigure, Clean, PrintSelfTestPage\"
362D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"0\"
363D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetVariable: TITLE=\"HP_Color_LaserJet_3800_PS\"
364D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
365D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
366D [13/Dec/2017:12:16:01 +0500] [Client 13] CGI data ready to be sent.
367D [13/Dec/2017:12:16:01 +0500] [Client 13] con->http=0x20203540
368D [13/Dec/2017:12:16:01 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=99, file=10
369D [13/Dec/2017:12:16:01 +0500] [Client 13] Waiting for CGI data.
370D [13/Dec/2017:12:16:01 +0500] [Client 13] Script header: *** HEAP RESULT 1
371D [13/Dec/2017:12:16:01 +0500] [Client 13] Script header: Content-Type: text/html;charset=utf-8
372D [13/Dec/2017:12:16:01 +0500] [Client 13] Script header:
373D [13/Dec/2017:12:16:01 +0500] [Client 13] Sending status 200 for CGI.
374D [13/Dec/2017:12:16:01 +0500] [Client 13] cupsdSendHeader: code=200, type="(null)", auth_type=0
375D [13/Dec/2017:12:16:01 +0500] [Client 13] CGI data ready to be sent.
376D [13/Dec/2017:12:16:01 +0500] [Client 13] con->http=0x20203540
377D [13/Dec/2017:12:16:01 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=99, file=10
378D [13/Dec/2017:12:16:01 +0500] [Client 13] Waiting for CGI data.
379D [13/Dec/2017:12:16:01 +0500] [Client 19] POST / HTTP/1.1
380D [13/Dec/2017:12:16:01 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
381D [13/Dec/2017:12:16:01 +0500] [Client 19] Read: status=200
382D [13/Dec/2017:12:16:01 +0500] [Client 19] No authentication data provided.
383D [13/Dec/2017:12:16:01 +0500] [Client 19] 2.0 Get-Jobs 3
384D [13/Dec/2017:12:16:01 +0500] Get-Jobs ipp://localhost:631/printers/HP_Color_LaserJet_3800_PS
385D [13/Dec/2017:12:16:01 +0500] [Client 19] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HP_Color_LaserJet_3800_PS) from localhost
386D [13/Dec/2017:12:16:01 +0500] [Client 19] Content-Length: 75
387D [13/Dec/2017:12:16:01 +0500] [Client 19] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
388D [13/Dec/2017:12:16:01 +0500] [CGI] Regular expression \".*Clean.*\"
389D [13/Dec/2017:12:16:01 +0500] [CGI] matches[0].rm_so=0
390D [13/Dec/2017:12:16:01 +0500] [CGI] matches[1].rm_so=-1
391D [13/Dec/2017:12:16:01 +0500] [CGI] Regular expression \".*PrintSelfTestPage.*\"
392D [13/Dec/2017:12:16:01 +0500] [CGI] matches[0].rm_so=0
393D [13/Dec/2017:12:16:01 +0500] [CGI] matches[1].rm_so=-1
394D [13/Dec/2017:12:16:01 +0500] [Client 13] con->http=0x20203540
395D [13/Dec/2017:12:16:01 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=99, file=10
396D [13/Dec/2017:12:16:01 +0500] [Client 13] Waiting for CGI data.
397D [13/Dec/2017:12:16:01 +0500] [Client 19] con->http=0x202004e0
398D [13/Dec/2017:12:16:01 +0500] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x202091e0(IPP_STATE_DATA), pipe_pid=0, file=-1
399D [13/Dec/2017:12:16:01 +0500] [Client 19] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
400D [13/Dec/2017:12:16:01 +0500] [Client 19] bytes=0, http_state=0, data_remaining=75
401D [13/Dec/2017:12:16:01 +0500] [Client 19] Flushing write buffer.
402D [13/Dec/2017:12:16:01 +0500] [Client 19] New state is HTTP_STATE_WAITING
403D [13/Dec/2017:12:16:01 +0500] [Client 19] Waiting for request.
404D [13/Dec/2017:12:16:01 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
405D [13/Dec/2017:12:16:01 +0500] [CGI] cgiClearVariables called.
406D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetVariable: ORDER=\"asc\"
407D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
408D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetVariable: TOTAL=\"0\"
409D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_LaserJet_3800_PS\"
410D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/HP_Color_LaserJet_3800_PS\"
411D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetVariable: THISURL=\"/printers/HP_Color_LaserJet_3800_PS\"
412D [13/Dec/2017:12:16:01 +0500] [CGI] cgiSetVariable: SEARCH_DEST=\"HP_Color_LaserJet_3800_PS\"
413D [13/Dec/2017:12:16:01 +0500] [Client 13] CGI data ready to be sent.
414D [13/Dec/2017:12:16:01 +0500] [Client 13] con->http=0x20203540
415D [13/Dec/2017:12:16:01 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=99, file=10
416D [13/Dec/2017:12:16:01 +0500] [Client 13] Waiting for CGI data.
417D [13/Dec/2017:12:16:01 +0500] [Client 13] con->http=0x20203540
418D [13/Dec/2017:12:16:01 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=99, file=10
419D [13/Dec/2017:12:16:01 +0500] [Client 13] Waiting for CGI data.
420D [13/Dec/2017:12:16:01 +0500] [Client 13] CGI data ready to be sent.
421D [13/Dec/2017:12:16:01 +0500] [Client 19] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
422D [13/Dec/2017:12:16:01 +0500] [Client 19] Closing connection.
423D [13/Dec/2017:12:16:01 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
424D [13/Dec/2017:12:16:01 +0500] [Client 13] con->http=0x20203540
425D [13/Dec/2017:12:16:01 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=99, file=10
426D [13/Dec/2017:12:16:01 +0500] [Client 13] Waiting for CGI data.
427D [13/Dec/2017:12:16:01 +0500] [Client 13] CGI data ready to be sent.
428D [13/Dec/2017:12:16:01 +0500] [Client 13] con->http=0x20203540
429D [13/Dec/2017:12:16:01 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=99, file=10
430D [13/Dec/2017:12:16:01 +0500] [Client 13] Waiting for CGI data.
431D [13/Dec/2017:12:16:01 +0500] [Client 13] Sending 0-length chunk.
432D [13/Dec/2017:12:16:01 +0500] [Client 13] Flushing write buffer.
433D [13/Dec/2017:12:16:01 +0500] [Client 13] New state is HTTP_STATE_WAITING
434D [13/Dec/2017:12:16:01 +0500] [Client 13] Waiting for request.
435D [13/Dec/2017:12:16:01 +0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
436D [13/Dec/2017:12:16:01 +0500] PID 99 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
437D [13/Dec/2017:12:16:05 +0500] [Client 13] POST /printers/HP_Color_LaserJet_3800_PS HTTP/1.1
438D [13/Dec/2017:12:16:05 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
439D [13/Dec/2017:12:16:05 +0500] [Client 13] Read: status=200
440D [13/Dec/2017:12:16:05 +0500] [Client 13] No authentication data provided.
441D [13/Dec/2017:12:16:05 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi"
442D [13/Dec/2017:12:16:05 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups"
443D [13/Dec/2017:12:16:05 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups"
444D [13/Dec/2017:12:16:05 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
445D [13/Dec/2017:12:16:05 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
446D [13/Dec/2017:12:16:05 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
447D [13/Dec/2017:12:16:05 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
448D [13/Dec/2017:12:16:05 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups"
449D [13/Dec/2017:12:16:05 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups"
450D [13/Dec/2017:12:16:05 +0500] [CGI] envp[8] = "HOME=C:\\var\\temp"
451D [13/Dec/2017:12:16:05 +0500] [CGI] envp[9] = "NLSPATH=C:\\MPTN\\MSG\\NLS\\%N;C:\\TCPIP\\msg\\ENUS850\\%N;"
452D [13/Dec/2017:12:16:05 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
453D [13/Dec/2017:12:16:05 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@bluelion"
454D [13/Dec/2017:12:16:05 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3"
455D [13/Dec/2017:12:16:05 +0500] [CGI] envp[13] = "TMPDIR=C:\\var\\temp"
456D [13/Dec/2017:12:16:05 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600"
457D [13/Dec/2017:12:16:05 +0500] [CGI] envp[15] = "USER=root"
458D [13/Dec/2017:12:16:05 +0500] [CGI] envp[16] = "UNIXROOT=C:"
459D [13/Dec/2017:12:16:05 +0500] [CGI] envp[17] = "ETC=C:\\MPTN\\ETC"
460D [13/Dec/2017:12:16:05 +0500] [CGI] envp[18] = "CUPS_MAX_MESSAGE=2047"
461D [13/Dec/2017:12:16:05 +0500] [CGI] envp[19] = "CUPS_SERVER=\\socket\\cups.sock"
462D [13/Dec/2017:12:16:05 +0500] [CGI] envp[20] = "CUPS_ENCRYPTION=IfRequested"
463D [13/Dec/2017:12:16:05 +0500] [CGI] envp[21] = "IPP_PORT=631"
464D [13/Dec/2017:12:16:05 +0500] [CGI] envp[22] = "LANG=en_US.UTF8"
465D [13/Dec/2017:12:16:05 +0500] [CGI] envp[23] = "REDIRECT_STATUS=1"
466D [13/Dec/2017:12:16:05 +0500] [CGI] envp[24] = "GATEWAY_INTERFACE=CGI/1.1"
467D [13/Dec/2017:12:16:05 +0500] [CGI] envp[25] = "SERVER_NAME=localhost"
468D [13/Dec/2017:12:16:05 +0500] [CGI] envp[26] = "SERVER_PORT=631"
469D [13/Dec/2017:12:16:05 +0500] [CGI] envp[27] = "REMOTE_ADDR=127.0.0.1"
470D [13/Dec/2017:12:16:05 +0500] [CGI] envp[28] = "REMOTE_HOST=localhost"
471D [13/Dec/2017:12:16:05 +0500] [CGI] envp[29] = "SCRIPT_NAME=/printers/HP_Color_LaserJet_3800_PS"
472D [13/Dec/2017:12:16:05 +0500] [CGI] envp[30] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/printers/HP_Color_LaserJet_3800_PS"
473D [13/Dec/2017:12:16:05 +0500] [CGI] envp[31] = "PATH_INFO=/HP_Color_LaserJet_3800_PS"
474D [13/Dec/2017:12:16:05 +0500] [CGI] envp[32] = "SERVER_PROTOCOL=HTTP/1.1"
475D [13/Dec/2017:12:16:05 +0500] [CGI] envp[33] = "HTTP_COOKIE=org.cups.sid=53f2efb08fa70698b63f646e39907fa4"
476D [13/Dec/2017:12:16:05 +0500] [CGI] envp[34] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:45.0) Gecko/20100101 Firefox/45.0"
477D [13/Dec/2017:12:16:05 +0500] [CGI] envp[35] = "HTTP_REFERER=http://localhost:631/printers/HP_Color_LaserJet_3800_PS"
478D [13/Dec/2017:12:16:05 +0500] [CGI] envp[36] = "REQUEST_METHOD=POST"
479D [13/Dec/2017:12:16:05 +0500] [CGI] envp[37] = "CONTENT_LENGTH=64"
480D [13/Dec/2017:12:16:05 +0500] [CGI] envp[38] = "CONTENT_TYPE=application/x-www-form-urlencoded"
481D [13/Dec/2017:12:16:05 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/printers.cgi (PID 101)
482I [13/Dec/2017:12:16:05 +0500] [Client 13] Started "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" (pid=101, file=12)
483D [13/Dec/2017:12:16:05 +0500] [Client 13] Waiting for CGI data.
484D [13/Dec/2017:12:16:05 +0500] [Client 20] Accepted from localhost (Domain)
485D [13/Dec/2017:12:16:05 +0500] [Client 20] Waiting for request.
486D [13/Dec/2017:12:16:05 +0500] [CGI] org.cups.sid cookie is \"53f2efb08fa70698b63f646e39907fa4\"
487D [13/Dec/2017:12:16:05 +0500] [CGI] cgiSetVariable: org.cups.sid=\"53f2efb08fa70698b63f646e39907fa4\"
488D [13/Dec/2017:12:16:05 +0500] [CGI] cgiSetVariable: OP=\"print-test-page\"
489D [13/Dec/2017:12:16:05 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
490D [13/Dec/2017:12:16:05 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
491D [13/Dec/2017:12:16:05 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_LaserJet_3800_PS\"
492D [13/Dec/2017:12:16:05 +0500] [Client 20] POST /printers/HP_Color_LaserJet_3800_PS HTTP/1.1
493D [13/Dec/2017:12:16:05 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
494D [13/Dec/2017:12:16:05 +0500] [Client 20] Read: status=200
495D [13/Dec/2017:12:16:05 +0500] [Client 20] No authentication data provided.
496D [13/Dec/2017:12:16:05 +0500] [Client 20] 2.0 Print-Job 1
497D [13/Dec/2017:12:16:05 +0500] [Client 20] Read: status=100
498D [13/Dec/2017:12:16:05 +0500] Print-Job ipp://localhost:631/printers/HP_Color_LaserJet_3800_PS
499D [13/Dec/2017:12:16:05 +0500] [Job ???] Auto-typing file...
500I [13/Dec/2017:12:16:05 +0500] [Job ???] Request file type is application/vnd.cups-pdf-banner.
501D [13/Dec/2017:12:16:05 +0500] cupsdMarkDirty(---J-)
502D [13/Dec/2017:12:16:05 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
503D [13/Dec/2017:12:16:05 +0500] Adding default job-sheets values "none,none"...
504I [13/Dec/2017:12:16:05 +0500] [Job 22] Adding start banner page "none".
505D [13/Dec/2017:12:16:05 +0500] Discarding unused job-created event...
506D [13/Dec/2017:12:16:05 +0500] cupsdMarkDirty(---J-)
507D [13/Dec/2017:12:16:05 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
508I [13/Dec/2017:12:16:05 +0500] [Job 22] Adding end banner page "none".
509I [13/Dec/2017:12:16:05 +0500] [Job 22] File of type application/vnd.cups-pdf-banner queued by "anonymous".
510D [13/Dec/2017:12:16:05 +0500] [Job 22] hold_until=0
511I [13/Dec/2017:12:16:05 +0500] [Job 22] Queued on "HP_Color_LaserJet_3800_PS" by "anonymous".
512D [13/Dec/2017:12:16:05 +0500] [Job 22] time-at-processing=1513185365
513D [13/Dec/2017:12:16:05 +0500] cupsdMarkDirty(---J-)
514D [13/Dec/2017:12:16:05 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
515D [13/Dec/2017:12:16:05 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
516D [13/Dec/2017:12:16:05 +0500] Discarding unused printer-state-changed event...
517D [13/Dec/2017:12:16:05 +0500] [Job 22] 4 filters for job:
518D [13/Dec/2017:12:16:05 +0500] [Job 22] bannertopdf.exe (application/vnd.cups-pdf-banner to application/pdf, cost 32)
519D [13/Dec/2017:12:16:05 +0500] [Job 22] pdftopdf.exe (application/pdf to application/vnd.cups-pdf, cost 66)
520D [13/Dec/2017:12:16:05 +0500] [Job 22] pdftops.exe (application/vnd.cups-pdf to application/vnd.cups-postscript, cost 100)
521D [13/Dec/2017:12:16:05 +0500] [Job 22] hpps (application/vnd.cups-postscript to printer/HP_Color_LaserJet_3800_PS, cost 0)
522D [13/Dec/2017:12:16:05 +0500] [Job 22] job-sheets=none,none
523D [13/Dec/2017:12:16:05 +0500] [Job 22] argv[0]="HP_Color_LaserJet_3800_PS"
524D [13/Dec/2017:12:16:05 +0500] [Job 22] argv[1]="22"
525D [13/Dec/2017:12:16:05 +0500] [Job 22] argv[2]="anonymous"
526D [13/Dec/2017:12:16:05 +0500] [Job 22] argv[3]="Test Page"
527D [13/Dec/2017:12:16:05 +0500] [Job 22] argv[4]="1"
528D [13/Dec/2017:12:16:05 +0500] [Job 22] argv[5]="job-uuid=urn:uuid:4f842431-d4bf-3d9c-4bf7-48c504741ecc job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1513185365 time-at-processing=1513185365"
529D [13/Dec/2017:12:16:05 +0500] [Job 22] argv[6]="/@unixroot/var/spool/cups/d00022-001"
530D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[0]="CUPS_CACHEDIR=/@unixroot/var/cache/cups"
531D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[1]="CUPS_DATADIR=/@unixroot/usr/share/cups"
532D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[2]="CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
533D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[3]="CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
534D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[4]="CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
535D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[5]="CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
536D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[6]="CUPS_SERVERROOT=/@unixroot/etc/cups"
537D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[7]="CUPS_STATEDIR=/@unixroot/var/run/cups"
538D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[8]="HOME=C:\\var\\temp"
539D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[9]="NLSPATH=C:\\MPTN\\MSG\\NLS\\%N;C:\\TCPIP\\msg\\ENUS850\\%N;"
540D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[10]="PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
541D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[11]="SERVER_ADMIN=root@bluelion"
542D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[12]="SOFTWARE=CUPS/2.1.3"
543D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[13]="TMPDIR=C:\\var\\temp"
544D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[14]="TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600"
545D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[15]="USER=root"
546D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[16]="UNIXROOT=C:"
547D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[17]="ETC=C:\\MPTN\\ETC"
548D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[18]="CUPS_MAX_MESSAGE=2047"
549D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[19]="CUPS_SERVER=\\socket\\cups.sock"
550D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[20]="CUPS_ENCRYPTION=IfRequested"
551D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[21]="IPP_PORT=631"
552D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[22]="CHARSET=utf-8"
553D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[23]="LANG=en_US.UTF-8"
554D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[24]="PPD=/@unixroot/etc/cups/ppd/HP_Color_LaserJet_3800_PS.ppd"
555D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[25]="RIP_MAX_CACHE=128m"
556D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[26]="CONTENT_TYPE=application/vnd.cups-pdf-banner"
557D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[27]="DEVICE_URI=socket://192.168.100.16"
558D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[28]="PRINTER_INFO=HP Color LaserJet 3800 PS"
559D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[29]="PRINTER_LOCATION=office"
560D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[30]="PRINTER=HP_Color_LaserJet_3800_PS"
561D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[31]="PRINTER_STATE_REASONS=none"
562D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[32]="CUPS_FILETYPE=document"
563D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[33]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
564D [13/Dec/2017:12:16:05 +0500] [Job 22] envp[34]="AUTH_I****"
565I [13/Dec/2017:12:16:05 +0500] [Job 22] Started filter /@unixroot/usr/lib/cups/filter/bannertopdf.exe (PID 103)
566I [13/Dec/2017:12:16:05 +0500] [Job 22] Started filter /@unixroot/usr/lib/cups/filter/pdftopdf.exe (PID 105)
567I [13/Dec/2017:12:16:05 +0500] [Job 22] Started filter /@unixroot/usr/lib/cups/filter/pdftops.exe (PID 107)
568I [13/Dec/2017:12:16:05 +0500] [Job 22] Started filter /@unixroot/usr/lib/cups/filter/hpps (PID 109)
569I [13/Dec/2017:12:16:06 +0500] [Job 22] Started backend /@unixroot/usr/lib/cups/backend/socket.exe (PID 112)
570D [13/Dec/2017:12:16:06 +0500] Discarding unused job-state-changed event...
571D [13/Dec/2017:12:16:06 +0500] [Client 20] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/HP_Color_LaserJet_3800_PS) from localhost
572D [13/Dec/2017:12:16:06 +0500] [Client 20] Content-Length: 192
573D [13/Dec/2017:12:16:06 +0500] [Client 20] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
574D [13/Dec/2017:12:16:06 +0500] [Job 22] PID 103 (/@unixroot/usr/lib/cups/filter/bannertopdf.exe) exited with no errors.
575D [13/Dec/2017:12:16:06 +0500] [Client 20] con->http=0x202004e0
576D [13/Dec/2017:12:16:06 +0500] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=192, response=0x20126be0(IPP_STATE_IDLE), pipe_pid=0, file=-1
577D [13/Dec/2017:12:16:06 +0500] [Client 20] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
578D [13/Dec/2017:12:16:06 +0500] [Client 20] bytes=0, http_state=0, data_remaining=192
579D [13/Dec/2017:12:16:06 +0500] [Client 20] Flushing write buffer.
580D [13/Dec/2017:12:16:06 +0500] [Client 20] New state is HTTP_STATE_WAITING
581D [13/Dec/2017:12:16:06 +0500] [Client 20] Waiting for request.
582D [13/Dec/2017:12:16:06 +0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
583D [13/Dec/2017:12:16:06 +0500] [Job 22] PDF template file doesn\'t have form. It\'s okay.
584D [13/Dec/2017:12:16:06 +0500] [Job 22] pdftopdf: Last filter determined by the PPD: hpps; FINAL_CONTENT_TYPE: application/vnd.cups-postscript => pdftopdf will log pages in page_log.
585D [13/Dec/2017:12:16:06 +0500] [Job 22] pdftops - copying to temp print file \"C:\\var\\temp/0006c5a38de4d\"
586D [13/Dec/2017:12:16:06 +0500] [Job 22] PAGE: 1 1
587D [13/Dec/2017:12:16:06 +0500] cupsdMarkDirty(---J-)
588D [13/Dec/2017:12:16:06 +0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
589D [13/Dec/2017:12:16:06 +0500] cupsdMarkDirty(---J-)
590D [13/Dec/2017:12:16:06 +0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
591D [13/Dec/2017:12:16:06 +0500] Discarding unused job-progress event...
592D [13/Dec/2017:12:16:06 +0500] [Job 22] \033[35;01mwarning: python-dbus not installed.\033[0m
593D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: +connecting-to-device
594D [13/Dec/2017:12:16:06 +0500] cupsdMarkDirty(---J-)
595D [13/Dec/2017:12:16:06 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients, printing jobs, and dirty files"
596D [13/Dec/2017:12:16:06 +0500] [Job 22] Looking up \"192.168.100.16\"...
597D [13/Dec/2017:12:16:06 +0500] Discarding unused printer-state-changed event...
598D [13/Dec/2017:12:16:06 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
599D [13/Dec/2017:12:16:06 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
600D [13/Dec/2017:12:16:06 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
601D [13/Dec/2017:12:16:06 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
602D [13/Dec/2017:12:16:06 +0500] [Job 22] PID 105 (/@unixroot/usr/lib/cups/filter/pdftopdf.exe) exited with no errors.
603D [13/Dec/2017:12:16:06 +0500] [Job 22] Printer make and model: HP Hewlett-Packard HP Color LaserJet 3800
604D [13/Dec/2017:12:16:06 +0500] [Job 22] Running command line for pstops: pstops 22 anonymous \'Test Page\' 1 \'job-uuid=urn:uuid:4f842431-d4bf-3d9c-4bf7-48c504741ecc job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1513185365 time-at-processing=1513185365\'
605D [13/Dec/2017:12:16:06 +0500] [Job 22] Using image rendering resolution 600 dpi
606D [13/Dec/2017:12:16:06 +0500] [Job 22] Running command line for pdftops: pdftops -level2 -origpagesizes -nocenter -r 600 C:\\var\\temp/0006c5a38de4d -
607D [13/Dec/2017:12:16:06 +0500] [CGI] cgiSetArray: job_uri[0]=\"/jobs/22\"
608D [13/Dec/2017:12:16:06 +0500] [CGI] cgiSetArray: job_id[0]=\"22\"
609D [13/Dec/2017:12:16:06 +0500] [CGI] cgiSetArray: job_state[0]=\"3\"
610D [13/Dec/2017:12:16:06 +0500] [CGI] cgiSetArray: job_state_message[0]=\"\"
611D [13/Dec/2017:12:16:06 +0500] [CGI] cgiSetArray: job_state_reasons[0]=\"none\"
612D [13/Dec/2017:12:16:06 +0500] [CGI] cgiSetVariable: refresh_page=\"2;URL=/printers/HP_Color_LaserJet_3800_PS\"
613D [13/Dec/2017:12:16:06 +0500] [CGI] cgiSetVariable: TITLE=\"Print Test Page\"
614D [13/Dec/2017:12:16:06 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
615D [13/Dec/2017:12:16:06 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
616D [13/Dec/2017:12:16:06 +0500] [Client 13] CGI data ready to be sent.
617D [13/Dec/2017:12:16:06 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_LaserJet_3800_PS\"
618D [13/Dec/2017:12:16:06 +0500] [Client 13] con->http=0x20203540
619D [13/Dec/2017:12:16:06 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=101, file=12
620D [13/Dec/2017:12:16:06 +0500] [Client 13] Waiting for CGI data.
621D [13/Dec/2017:12:16:06 +0500] [Client 13] Script header: *** HEAP RESULT 1
622D [13/Dec/2017:12:16:06 +0500] [Client 13] Script header: Content-Type: text/html;charset=utf-8
623D [13/Dec/2017:12:16:06 +0500] [Client 13] Script header:
624D [13/Dec/2017:12:16:06 +0500] [Client 13] Sending status 200 for CGI.
625D [13/Dec/2017:12:16:06 +0500] [Client 13] cupsdSendHeader: code=200, type="(null)", auth_type=0
626D [13/Dec/2017:12:16:06 +0500] [Client 20] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
627D [13/Dec/2017:12:16:06 +0500] [Client 20] Closing connection.
628D [13/Dec/2017:12:16:06 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
629D [13/Dec/2017:12:16:06 +0500] [Client 13] con->http=0x20203540
630D [13/Dec/2017:12:16:06 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=101, file=12
631D [13/Dec/2017:12:16:06 +0500] [Client 13] Waiting for CGI data.
632D [13/Dec/2017:12:16:06 +0500] [Client 13] CGI data ready to be sent.
633D [13/Dec/2017:12:16:06 +0500] [Job 22] Traceback (most recent call last):
634D [13/Dec/2017:12:16:06 +0500] [Job 22] File \"C:/usr/lib/cups/filter/hpps\", line 215, in <module>
635D [13/Dec/2017:12:16:06 +0500] [Job 22] p = subprocess.Popen([\'hostname\', \'-s\'], stdout=subprocess.PIPE, stderr=subprocess.PIPE)
636D [13/Dec/2017:12:16:06 +0500] [Job 22] File \"/@unixroot/usr/lib/python2.7/subprocess.py\", line 712, in __init__
637D [13/Dec/2017:12:16:06 +0500] [Job 22] errread, errwrite)
638D [13/Dec/2017:12:16:06 +0500] [Job 22] File \"/@unixroot/usr/lib/python2.7/subprocess.py\", line 1329, in _execute_child
639D [13/Dec/2017:12:16:06 +0500] [Job 22] raise child_exception
640D [13/Dec/2017:12:16:06 +0500] [Job 22] OSError: [Errno 2] No such file or directory
641D [13/Dec/2017:12:16:06 +0500] [Client 13] con->http=0x20203540
642D [13/Dec/2017:12:16:06 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=101, file=12
643D [13/Dec/2017:12:16:06 +0500] [Client 13] Waiting for CGI data.
644D [13/Dec/2017:12:16:06 +0500] [Client 13] con->http=0x20203540
645D [13/Dec/2017:12:16:06 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=101, file=12
646D [13/Dec/2017:12:16:06 +0500] [Client 13] Waiting for CGI data.
647D [13/Dec/2017:12:16:06 +0500] [Client 13] CGI data ready to be sent.
648D [13/Dec/2017:12:16:06 +0500] [Client 13] con->http=0x20203540
649D [13/Dec/2017:12:16:06 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=101, file=12
650D [13/Dec/2017:12:16:06 +0500] [Client 13] Waiting for CGI data.
651D [13/Dec/2017:12:16:06 +0500] [Client 13] Sending 0-length chunk.
652D [13/Dec/2017:12:16:06 +0500] [Client 13] Flushing write buffer.
653D [13/Dec/2017:12:16:06 +0500] [Client 13] New state is HTTP_STATE_WAITING
654D [13/Dec/2017:12:16:06 +0500] [Client 13] Waiting for request.
655D [13/Dec/2017:12:16:06 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
656D [13/Dec/2017:12:16:06 +0500] [Job 22] Started filter pdftops (PID 116)
657D [13/Dec/2017:12:16:06 +0500] [Job 22] ATTR: marker-colors=#000000,#00FFFF,#FF00FF,#FFFF00
658D [13/Dec/2017:12:16:06 +0500] cupsdMarkDirty(P----)
659D [13/Dec/2017:12:16:06 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
660D [13/Dec/2017:12:16:06 +0500] [Job 22] ATTR: marker-names=\'\"Black Cartridge HP Q6470A\"\',\'\"Cyan Cartridge HP Q7581A\"\',\'\"Magenta Cartridge HP Q7583A\"\',\'\"Yellow Cartridge HP Q7582A\"\'
661D [13/Dec/2017:12:16:06 +0500] cupsdMarkDirty(P----)
662D [13/Dec/2017:12:16:06 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
663D [13/Dec/2017:12:16:06 +0500] [Job 22] ATTR: marker-types=toner-cartridge,toner-cartridge,toner-cartridge,toner-cartridge
664D [13/Dec/2017:12:16:06 +0500] cupsdMarkDirty(P----)
665D [13/Dec/2017:12:16:06 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
666D [13/Dec/2017:12:16:06 +0500] [Job 22] ATTR: marker-levels=11,66,66,13
667D [13/Dec/2017:12:16:06 +0500] cupsdMarkDirty(P----)
668D [13/Dec/2017:12:16:06 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
669D [13/Dec/2017:12:16:06 +0500] [Job 22] new_supply_state=0, change_state=ffff
670D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -developer-low-report
671D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -developer-empty-warning
672D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -marker-supply-low-report
673D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -marker-supply-empty-warning
674D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -opc-near-eol-report
675D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -opc-life-over-warning
676D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -toner-low-report
677D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -toner-empty-warning
678D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -waste-receptacle-almost-full-report
679D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -waste-receptacle-full-warning
680D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -cleaner-life-almost-over-report
681D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -cleaner-life-over-warning
682D [13/Dec/2017:12:16:06 +0500] Discarding unused printer-state-changed event...
683W [13/Dec/2017:12:16:06 +0500] cupsdDoSelect: select() returned -1... pause and retry select()
684D [13/Dec/2017:12:16:06 +0500] [Job 22] Started filter pstops (PID 118)
685D [13/Dec/2017:12:16:06 +0500] [Job 22] new_state=2000, change_state=ffff
686D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -media-empty-warning
687D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -door-open-report
688D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -media-jam-warning
689D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -input-tray-missing-warning
690D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -output-tray-missing-warning
691D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -marker-supply-missing-warning
692D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -output-area-almost-full-report
693D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -output-area-full-warning
694D [13/Dec/2017:12:16:06 +0500] [Job 22] backendWaitLoop(snmp_fd=5, addr=0x20037e04, side_cb=0x110f0)
695D [13/Dec/2017:12:16:06 +0500] [Job 22] Connecting to 192.168.100.16:9100
696I [13/Dec/2017:12:16:06 +0500] [Job 22] Connecting to printer.
697D [13/Dec/2017:12:16:06 +0500] cupsdMarkDirty(---J-)
698D [13/Dec/2017:12:16:06 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
699D [13/Dec/2017:12:16:06 +0500] [Job 22] Set job-printer-state-message to "Connecting to printer.", current level=INFO
700D [13/Dec/2017:12:16:06 +0500] [Job 22] Page = 612x792; 12,12 to 600,780
701D [13/Dec/2017:12:16:06 +0500] [Job 22] slow_collate=0, slow_duplex=0, slow_order=0
702D [13/Dec/2017:12:16:06 +0500] [Job 22] This document does not conform to the Adobe Document Structuring Conventions and may not print correctly.
703D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: -connecting-to-device
704D [13/Dec/2017:12:16:06 +0500] cupsdMarkDirty(---J-)
705D [13/Dec/2017:12:16:06 +0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
706I [13/Dec/2017:12:16:06 +0500] [Job 22] Connected to printer.
707D [13/Dec/2017:12:16:06 +0500] cupsdMarkDirty(---J-)
708D [13/Dec/2017:12:16:06 +0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
709D [13/Dec/2017:12:16:06 +0500] [Job 22] Set job-printer-state-message to "Connected to printer.", current level=INFO
710D [13/Dec/2017:12:16:06 +0500] [Job 22] Connected to 192.168.100.16:9100...
711D [13/Dec/2017:12:16:06 +0500] [Job 22] backendRunLoop(print_fd=0, device_fd=6, snmp_fd=5, addr=0x20037e04, use_bc=1, side_cb=0x110f0)
712D [13/Dec/2017:12:16:06 +0500] [Job 22] STATE: +cups-waiting-for-job-completed
713D [13/Dec/2017:12:16:06 +0500] cupsdMarkDirty(P----)
714D [13/Dec/2017:12:16:06 +0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
715D [13/Dec/2017:12:16:06 +0500] cupsdMarkDirty(---J-)
716D [13/Dec/2017:12:16:06 +0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
717I [13/Dec/2017:12:16:06 +0500] [Job 22] Waiting for printer to finish.
718D [13/Dec/2017:12:16:06 +0500] cupsdMarkDirty(---J-)
719D [13/Dec/2017:12:16:06 +0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
720D [13/Dec/2017:12:16:06 +0500] [Job 22] Set job-printer-state-message to "Waiting for printer to finish.", current level=INFO
721D [13/Dec/2017:12:16:06 +0500] Discarding unused job-progress event...
722D [13/Dec/2017:12:16:06 +0500] Discarding unused printer-state-changed event...
723D [13/Dec/2017:12:16:06 +0500] PID 101 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
724D [13/Dec/2017:12:16:06 +0500] [Job 22] PID 109 (/@unixroot/usr/lib/cups/filter/hpps) stopped with status 1.
725D [13/Dec/2017:12:16:06 +0500] [Job 22] ATTR: marker-levels=11,65,65,13
726D [13/Dec/2017:12:16:06 +0500] cupsdMarkDirty(P----)
727D [13/Dec/2017:12:16:06 +0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
728D [13/Dec/2017:12:16:06 +0500] [Job 22] new_supply_state=0, change_state=0
729D [13/Dec/2017:12:16:06 +0500] Discarding unused printer-state-changed event...
730D [13/Dec/2017:12:16:06 +0500] [Job 22] new_state=2000, change_state=0
731W [13/Dec/2017:12:16:06 +0500] cupsdDoSelect: select() returned -1... pause and retry select()
732D [13/Dec/2017:12:16:08 +0500] [Job 22] PID 112 (/@unixroot/usr/lib/cups/backend/socket.exe) exited with no errors.
733D [13/Dec/2017:12:16:08 +0500] [Job 22] PID 116 (pdftops) exited with no errors.
734D [13/Dec/2017:12:16:08 +0500] [Job 22] PID 118 (pstops) exited with no errors.
735D [13/Dec/2017:12:16:08 +0500] [Job 22] PID 107 (/@unixroot/usr/lib/cups/filter/pdftops.exe) exited with no errors.
736D [13/Dec/2017:12:16:08 +0500] cupsdMarkDirty(P----)
737D [13/Dec/2017:12:16:08 +0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
738D [13/Dec/2017:12:16:08 +0500] Discarding unused job-state-changed event...
739E [13/Dec/2017:12:16:08 +0500] [Job 22] Job stopped due to filter errors; please consult the error_log file for details.
740D [13/Dec/2017:12:16:08 +0500] cupsdMarkDirty(---J-)
741D [13/Dec/2017:12:16:08 +0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
742D [13/Dec/2017:12:16:08 +0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
743D [13/Dec/2017:12:16:08 +0500] Discarding unused printer-state-changed event...
744D [13/Dec/2017:12:16:08 +0500] cupsdMarkDirty(---J-)
745D [13/Dec/2017:12:16:08 +0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
746D [13/Dec/2017:12:16:08 +0500] [Job 22] The following messages were recorded from 12:16:06 pm to 12:16:06 pm
747D [13/Dec/2017:12:16:08 +0500] [Job 22] hrDeviceDesc=\"HP Color LaserJet 3800\"
748D [13/Dec/2017:12:16:08 +0500] [Job 22] prtMarkerColorantValue.1.1 = \"black\"
749D [13/Dec/2017:12:16:08 +0500] [Job 22] prtMarkerColorantValue.1.2 = \"cyan\"
750D [13/Dec/2017:12:16:08 +0500] [Job 22] prtMarkerColorantValue.1.3 = \"magenta\"
751D [13/Dec/2017:12:16:08 +0500] [Job 22] prtMarkerColorantValue.1.4 = \"yellow\"
752D [13/Dec/2017:12:16:08 +0500] [Job 22] prtMarkerSuppliesLevel.1.1 = 660
753D [13/Dec/2017:12:16:08 +0500] [Job 22] prtMarkerSuppliesLevel.1.2 = 3900
754D [13/Dec/2017:12:16:08 +0500] [Job 22] prtMarkerSuppliesLevel.1.3 = 3900
755D [13/Dec/2017:12:16:08 +0500] [Job 22] prtMarkerSuppliesLevel.1.4 = 780
756D [13/Dec/2017:12:16:08 +0500] [Job 22] End of messages
757D [13/Dec/2017:12:16:08 +0500] [Job 22] printer-state=3(idle)
758D [13/Dec/2017:12:16:08 +0500] [Job 22] printer-state-message="Filter failed"
759D [13/Dec/2017:12:16:08 +0500] [Job 22] printer-state-reasons=none
760D [13/Dec/2017:12:16:08 +0500] [Client 13] GET /printers/HP_Color_LaserJet_3800_PS HTTP/1.1
761D [13/Dec/2017:12:16:08 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"
762D [13/Dec/2017:12:16:08 +0500] [Client 13] Read: status=200
763D [13/Dec/2017:12:16:08 +0500] [Client 13] No authentication data provided.
764D [13/Dec/2017:12:16:08 +0500] [Client 13] Processing GET /printers/HP_Color_LaserJet_3800_PS
765D [13/Dec/2017:12:16:08 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi"
766D [13/Dec/2017:12:16:08 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups"
767D [13/Dec/2017:12:16:08 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups"
768D [13/Dec/2017:12:16:08 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
769D [13/Dec/2017:12:16:08 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
770D [13/Dec/2017:12:16:08 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
771D [13/Dec/2017:12:16:08 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
772D [13/Dec/2017:12:16:08 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups"
773D [13/Dec/2017:12:16:08 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups"
774D [13/Dec/2017:12:16:08 +0500] [CGI] envp[8] = "HOME=C:\\var\\temp"
775D [13/Dec/2017:12:16:08 +0500] [CGI] envp[9] = "NLSPATH=C:\\MPTN\\MSG\\NLS\\%N;C:\\TCPIP\\msg\\ENUS850\\%N;"
776D [13/Dec/2017:12:16:08 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
777D [13/Dec/2017:12:16:08 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@bluelion"
778D [13/Dec/2017:12:16:08 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3"
779D [13/Dec/2017:12:16:08 +0500] [CGI] envp[13] = "TMPDIR=C:\\var\\temp"
780D [13/Dec/2017:12:16:08 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600"
781D [13/Dec/2017:12:16:08 +0500] [CGI] envp[15] = "USER=root"
782D [13/Dec/2017:12:16:08 +0500] [CGI] envp[16] = "UNIXROOT=C:"
783D [13/Dec/2017:12:16:08 +0500] [CGI] envp[17] = "ETC=C:\\MPTN\\ETC"
784D [13/Dec/2017:12:16:08 +0500] [CGI] envp[18] = "CUPS_MAX_MESSAGE=2047"
785D [13/Dec/2017:12:16:08 +0500] [CGI] envp[19] = "CUPS_SERVER=\\socket\\cups.sock"
786D [13/Dec/2017:12:16:08 +0500] [CGI] envp[20] = "CUPS_ENCRYPTION=IfRequested"
787D [13/Dec/2017:12:16:08 +0500] [CGI] envp[21] = "IPP_PORT=631"
788D [13/Dec/2017:12:16:08 +0500] [CGI] envp[22] = "LANG=en_US.UTF8"
789D [13/Dec/2017:12:16:08 +0500] [CGI] envp[23] = "REDIRECT_STATUS=1"
790D [13/Dec/2017:12:16:08 +0500] [CGI] envp[24] = "GATEWAY_INTERFACE=CGI/1.1"
791D [13/Dec/2017:12:16:08 +0500] [CGI] envp[25] = "SERVER_NAME=localhost"
792D [13/Dec/2017:12:16:08 +0500] [CGI] envp[26] = "SERVER_PORT=631"
793D [13/Dec/2017:12:16:08 +0500] [CGI] envp[27] = "REMOTE_ADDR=127.0.0.1"
794D [13/Dec/2017:12:16:08 +0500] [CGI] envp[28] = "REMOTE_HOST=localhost"
795D [13/Dec/2017:12:16:08 +0500] [CGI] envp[29] = "SCRIPT_NAME=/printers/HP_Color_LaserJet_3800_PS"
796D [13/Dec/2017:12:16:08 +0500] [CGI] envp[30] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/printers/HP_Color_LaserJet_3800_PS"
797D [13/Dec/2017:12:16:08 +0500] [CGI] envp[31] = "PATH_INFO=/HP_Color_LaserJet_3800_PS"
798D [13/Dec/2017:12:16:08 +0500] [CGI] envp[32] = "SERVER_PROTOCOL=HTTP/1.1"
799D [13/Dec/2017:12:16:08 +0500] [CGI] envp[33] = "HTTP_COOKIE=org.cups.sid=53f2efb08fa70698b63f646e39907fa4"
800D [13/Dec/2017:12:16:08 +0500] [CGI] envp[34] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:45.0) Gecko/20100101 Firefox/45.0"
801D [13/Dec/2017:12:16:08 +0500] [CGI] envp[35] = "REQUEST_METHOD=GET"
802D [13/Dec/2017:12:16:08 +0500] [CGI] envp[36] = "QUERY_STRING="
803D [13/Dec/2017:12:16:08 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/printers.cgi (PID 120)
804I [13/Dec/2017:12:16:08 +0500] [Client 13] Started "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" (pid=120, file=10)
805D [13/Dec/2017:12:16:08 +0500] [Client 13] Waiting for CGI data.
806D [13/Dec/2017:12:16:08 +0500] [Client 21] Accepted from localhost (Domain)
807D [13/Dec/2017:12:16:08 +0500] [Client 21] Waiting for request.
808D [13/Dec/2017:12:16:08 +0500] [CGI] org.cups.sid cookie is \"53f2efb08fa70698b63f646e39907fa4\"
809D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
810D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
811D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_LaserJet_3800_PS\"
812D [13/Dec/2017:12:16:08 +0500] [Client 21] POST / HTTP/1.1
813D [13/Dec/2017:12:16:08 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
814D [13/Dec/2017:12:16:08 +0500] [Client 21] Read: status=200
815D [13/Dec/2017:12:16:08 +0500] [Client 21] No authentication data provided.
816D [13/Dec/2017:12:16:08 +0500] [Client 21] 2.0 CUPS-Get-Default 1
817D [13/Dec/2017:12:16:08 +0500] CUPS-Get-Default
818D [13/Dec/2017:12:16:08 +0500] CUPS-Get-Default client-error-not-found: No default printer.
819D [13/Dec/2017:12:16:08 +0500] [Client 21] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
820D [13/Dec/2017:12:16:08 +0500] [Client 21] Content-Length: 113
821D [13/Dec/2017:12:16:08 +0500] [Client 21] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
822D [13/Dec/2017:12:16:08 +0500] [Client 21] con->http=0x202004e0
823D [13/Dec/2017:12:16:08 +0500] [Client 21] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x20206a40(IPP_STATE_DATA), pipe_pid=0, file=-1
824D [13/Dec/2017:12:16:08 +0500] [Client 21] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
825D [13/Dec/2017:12:16:08 +0500] [Client 21] bytes=0, http_state=0, data_remaining=113
826D [13/Dec/2017:12:16:08 +0500] [Client 21] Flushing write buffer.
827D [13/Dec/2017:12:16:08 +0500] [Client 21] New state is HTTP_STATE_WAITING
828D [13/Dec/2017:12:16:08 +0500] [Client 21] Waiting for request.
829D [13/Dec/2017:12:16:08 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
830D [13/Dec/2017:12:16:08 +0500] [CGI] show_printer(http=0x2003c3c0, printer=\"HP_Color_LaserJet_3800_PS\")
831D [13/Dec/2017:12:16:08 +0500] [Client 21] POST / HTTP/1.1
832D [13/Dec/2017:12:16:08 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
833D [13/Dec/2017:12:16:08 +0500] [Client 21] Read: status=200
834D [13/Dec/2017:12:16:08 +0500] [Client 21] No authentication data provided.
835D [13/Dec/2017:12:16:08 +0500] [Client 21] 2.0 Get-Printer-Attributes 2
836D [13/Dec/2017:12:16:08 +0500] Get-Printer-Attributes ipp://localhost/printers/HP_Color_LaserJet_3800_PS
837D [13/Dec/2017:12:16:08 +0500] [Client 21] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_Color_LaserJet_3800_PS) from localhost
838D [13/Dec/2017:12:16:08 +0500] [Client 21] Content-Length: 698
839D [13/Dec/2017:12:16:08 +0500] [Client 21] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
840D [13/Dec/2017:12:16:08 +0500] [Client 21] con->http=0x202004e0
841D [13/Dec/2017:12:16:08 +0500] [Client 21] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=698, response=0x20206a80(IPP_STATE_DATA), pipe_pid=0, file=-1
842D [13/Dec/2017:12:16:08 +0500] [Client 21] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
843D [13/Dec/2017:12:16:08 +0500] [Client 21] bytes=0, http_state=0, data_remaining=698
844D [13/Dec/2017:12:16:08 +0500] [Client 21] Flushing write buffer.
845D [13/Dec/2017:12:16:08 +0500] [Client 21] New state is HTTP_STATE_WAITING
846D [13/Dec/2017:12:16:08 +0500] [Client 21] Waiting for request.
847D [13/Dec/2017:12:16:08 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
848D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
849D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
850D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
851D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
852D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\"
853D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: printer_is_shared[0]=\"1\"
854D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: printer_state[0]=\"3\"
855D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\"
856D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/HP_Color_LaserJet_3800_PS\"
857D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: printer_name[0]=\"HP_Color_LaserJet_3800_PS\"
858D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: printer_location[0]=\"office\"
859D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: printer_info[0]=\"HP Color LaserJet 3800 PS\"
860D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\"
861D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: device_uri[0]=\"socket://192.168.100.16\"
862D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: color_supported[0]=\"1\"
863D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: printer_make_and_model[0]=\"HP Color LaserJet 3800 Postscript (recommended)\"
864D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: media_default[0]=\"na_letter_8.5x11in\"
865D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: sides_supported[0]=\"one-sided\"
866D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: sides_default[0]=\"one-sided\"
867D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: printer_commands[0]=\"AutoConfigure, Clean, PrintSelfTestPage\"
868D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"0\"
869D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetVariable: TITLE=\"HP_Color_LaserJet_3800_PS\"
870D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
871D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
872D [13/Dec/2017:12:16:08 +0500] [Client 13] CGI data ready to be sent.
873D [13/Dec/2017:12:16:08 +0500] [Client 13] con->http=0x20203540
874D [13/Dec/2017:12:16:08 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=120, file=10
875D [13/Dec/2017:12:16:08 +0500] [Client 13] Waiting for CGI data.
876D [13/Dec/2017:12:16:08 +0500] [Client 13] Script header: *** HEAP RESULT 1
877D [13/Dec/2017:12:16:08 +0500] [Client 13] Script header: Content-Type: text/html;charset=utf-8
878D [13/Dec/2017:12:16:08 +0500] [Client 13] Script header:
879D [13/Dec/2017:12:16:08 +0500] [Client 13] Sending status 200 for CGI.
880D [13/Dec/2017:12:16:08 +0500] [Client 13] cupsdSendHeader: code=200, type="(null)", auth_type=0
881D [13/Dec/2017:12:16:08 +0500] [Client 13] CGI data ready to be sent.
882D [13/Dec/2017:12:16:08 +0500] [Client 13] con->http=0x20203540
883D [13/Dec/2017:12:16:08 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=120, file=10
884D [13/Dec/2017:12:16:08 +0500] [Client 13] Waiting for CGI data.
885D [13/Dec/2017:12:16:08 +0500] [CGI] Regular expression \".*Clean.*\"
886D [13/Dec/2017:12:16:08 +0500] [CGI] matches[0].rm_so=0
887D [13/Dec/2017:12:16:08 +0500] [CGI] matches[1].rm_so=-1
888D [13/Dec/2017:12:16:08 +0500] [CGI] Regular expression \".*PrintSelfTestPage.*\"
889D [13/Dec/2017:12:16:08 +0500] [CGI] matches[0].rm_so=0
890D [13/Dec/2017:12:16:08 +0500] [CGI] matches[1].rm_so=-1
891D [13/Dec/2017:12:16:08 +0500] [Client 13] con->http=0x20203540
892D [13/Dec/2017:12:16:08 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=120, file=10
893D [13/Dec/2017:12:16:08 +0500] [Client 13] Waiting for CGI data.
894D [13/Dec/2017:12:16:08 +0500] [Client 13] CGI data ready to be sent.
895D [13/Dec/2017:12:16:08 +0500] [Client 21] POST / HTTP/1.1
896D [13/Dec/2017:12:16:08 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
897D [13/Dec/2017:12:16:08 +0500] [Client 21] Read: status=200
898D [13/Dec/2017:12:16:08 +0500] [Client 21] No authentication data provided.
899D [13/Dec/2017:12:16:08 +0500] [Client 21] 2.0 Get-Jobs 3
900D [13/Dec/2017:12:16:08 +0500] Get-Jobs ipp://localhost:631/printers/HP_Color_LaserJet_3800_PS
901D [13/Dec/2017:12:16:08 +0500] [Client 21] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HP_Color_LaserJet_3800_PS) from localhost
902D [13/Dec/2017:12:16:08 +0500] [Client 21] Content-Length: 432
903D [13/Dec/2017:12:16:08 +0500] [Client 21] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
904D [13/Dec/2017:12:16:08 +0500] [Client 13] con->http=0x20203540
905D [13/Dec/2017:12:16:08 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=120, file=10
906D [13/Dec/2017:12:16:08 +0500] [Client 13] Waiting for CGI data.
907D [13/Dec/2017:12:16:08 +0500] [Client 21] con->http=0x202004e0
908D [13/Dec/2017:12:16:08 +0500] [Client 21] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=432, response=0x20206ac0(IPP_STATE_DATA), pipe_pid=0, file=-1
909D [13/Dec/2017:12:16:08 +0500] [Client 21] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
910D [13/Dec/2017:12:16:08 +0500] [Client 21] bytes=0, http_state=0, data_remaining=432
911D [13/Dec/2017:12:16:08 +0500] [Client 21] Flushing write buffer.
912D [13/Dec/2017:12:16:08 +0500] [Client 21] New state is HTTP_STATE_WAITING
913D [13/Dec/2017:12:16:08 +0500] [Client 21] Waiting for request.
914D [13/Dec/2017:12:16:08 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
915D [13/Dec/2017:12:16:08 +0500] [Client 13] con->http=0x20203540
916D [13/Dec/2017:12:16:08 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=120, file=10
917D [13/Dec/2017:12:16:08 +0500] [Client 13] Waiting for CGI data.
918D [13/Dec/2017:12:16:08 +0500] [CGI] cgiClearVariables called.
919D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetVariable: ORDER=\"asc\"
920D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
921D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetVariable: TOTAL=\"1\"
922D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
923D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
924D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: job_preserved[0]=\"1\"
925D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: job_printer_name[0]=\"HP_Color_LaserJet_3800_PS\"
926D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: job_printer_uri[0]=\"/printers/HP_Color_LaserJet_3800_PS\"
927D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: job_name[0]=\"Test Page\"
928D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: job_originating_user_name[0]=\"anonymous\"
929D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: time_at_completed[0]=\"novalue\"
930D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: time_at_creation[0]=\"Wed Dec 13 12:16:05 EST 2017\"
931D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: time_at_processing[0]=\"Wed Dec 13 12:16:05 EST 2017\"
932D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: job_id[0]=\"22\"
933D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: job_state[0]=\"6\"
934D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: job_media_sheets_completed[0]=\"1\"
935D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: job_k_octets[0]=\"1\"
936D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetArray: job_printer_state_message[0]=\"Filter failed\"
937D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_LaserJet_3800_PS\"
938D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/HP_Color_LaserJet_3800_PS\"
939D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetVariable: THISURL=\"/printers/HP_Color_LaserJet_3800_PS\"
940D [13/Dec/2017:12:16:08 +0500] [CGI] cgiSetVariable: SEARCH_DEST=\"HP_Color_LaserJet_3800_PS\"
941D [13/Dec/2017:12:16:08 +0500] [Client 13] CGI data ready to be sent.
942D [13/Dec/2017:12:16:08 +0500] [Client 13] con->http=0x20203540
943D [13/Dec/2017:12:16:08 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=120, file=10
944D [13/Dec/2017:12:16:08 +0500] [Client 13] Waiting for CGI data.
945D [13/Dec/2017:12:16:08 +0500] [Client 13] CGI data ready to be sent.
946D [13/Dec/2017:12:16:08 +0500] [Client 13] con->http=0x20203540
947D [13/Dec/2017:12:16:08 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=120, file=10
948D [13/Dec/2017:12:16:08 +0500] [Client 13] Waiting for CGI data.
949D [13/Dec/2017:12:16:08 +0500] [Client 13] CGI data ready to be sent.
950D [13/Dec/2017:12:16:08 +0500] [Client 13] con->http=0x20203540
951D [13/Dec/2017:12:16:08 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=120, file=10
952D [13/Dec/2017:12:16:08 +0500] [Client 13] Waiting for CGI data.
953D [13/Dec/2017:12:16:08 +0500] [Client 13] CGI data ready to be sent.
954D [13/Dec/2017:12:16:08 +0500] [Client 13] con->http=0x20203540
955D [13/Dec/2017:12:16:08 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=120, file=10
956D [13/Dec/2017:12:16:08 +0500] [Client 13] Waiting for CGI data.
957D [13/Dec/2017:12:16:08 +0500] [Client 13] CGI data ready to be sent.
958D [13/Dec/2017:12:16:08 +0500] [Client 13] con->http=0x20203540
959D [13/Dec/2017:12:16:08 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=120, file=10
960D [13/Dec/2017:12:16:08 +0500] [Client 13] Waiting for CGI data.
961D [13/Dec/2017:12:16:08 +0500] [Client 21] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
962D [13/Dec/2017:12:16:08 +0500] [Client 21] Closing connection.
963D [13/Dec/2017:12:16:08 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
964D [13/Dec/2017:12:16:08 +0500] PID 120 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
965D [13/Dec/2017:12:16:08 +0500] [Client 13] CGI data ready to be sent.
966D [13/Dec/2017:12:16:08 +0500] [Client 13] con->http=0x20203540
967D [13/Dec/2017:12:16:08 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=120, file=10
968D [13/Dec/2017:12:16:08 +0500] [Client 13] Waiting for CGI data.
969D [13/Dec/2017:12:16:08 +0500] [Client 13] Sending 0-length chunk.
970D [13/Dec/2017:12:16:08 +0500] [Client 13] Flushing write buffer.
971D [13/Dec/2017:12:16:08 +0500] [Client 13] New state is HTTP_STATE_WAITING
972D [13/Dec/2017:12:16:08 +0500] [Client 13] Waiting for request.
973D [13/Dec/2017:12:16:08 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
974D [13/Dec/2017:12:16:08 +0500] [Client 22] Accepted from localhost:49673 (IPv4)
975D [13/Dec/2017:12:16:08 +0500] [Client 22] Waiting for request.
976D [13/Dec/2017:12:16:08 +0500] [Client 13] GET /cups.css HTTP/1.1
977D [13/Dec/2017:12:16:08 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
978D [13/Dec/2017:12:16:08 +0500] [Client 13] Read: status=200
979D [13/Dec/2017:12:16:08 +0500] [Client 13] No authentication data provided.
980D [13/Dec/2017:12:16:08 +0500] [Client 13] Processing GET /cups.css
981D [13/Dec/2017:12:16:08 +0500] [Client 13] filename="/@unixroot/usr/share/doc/cups/cups.css", type=text/css
982D [13/Dec/2017:12:16:08 +0500] [Client 13] cupsdSendHeader: code=304, type="(null)", auth_type=0
983D [13/Dec/2017:12:16:08 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
984D [13/Dec/2017:12:16:09 +0500] [Job 22] Unloading...
985D [13/Dec/2017:12:16:13 +0500] [Client 22] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
986D [13/Dec/2017:12:16:13 +0500] [Client 22] Closing connection.
987D [13/Dec/2017:12:16:13 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
988D [13/Dec/2017:12:16:18 +0500] [Client 13] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
989D [13/Dec/2017:12:16:18 +0500] [Client 13] Closing connection.
990D [13/Dec/2017:12:16:18 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
991D [13/Dec/2017:12:16:18 +0500] [Job 20] Unloading...
992I [13/Dec/2017:12:16:36 +0500] Saving printers.conf...
993I [13/Dec/2017:12:16:36 +0500] Saving job.cache...
994D [13/Dec/2017:12:16:36 +0500] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
995D [13/Dec/2017:12:16:36 +0500] Report: clients=0
996D [13/Dec/2017:12:16:36 +0500] Report: jobs=16
997D [13/Dec/2017:12:16:36 +0500] Report: jobs-active=1
998D [13/Dec/2017:12:16:36 +0500] Report: printers=3
999D [13/Dec/2017:12:16:36 +0500] Report: stringpool-string-count=20681
1000D [13/Dec/2017:12:16:36 +0500] Report: stringpool-alloc-bytes=11152
1001D [13/Dec/2017:12:16:36 +0500] Report: stringpool-total-bytes=386080