Ticket #143: error_log-2017-12-07

File error_log-2017-12-07, 105.5 KB (added by Lewis Rosenthal, 7 years ago)

updated error log (GPL GS 9.18, CUPS 2.1.3)

Line 
1D [07/Dec/2017:11:14:01 +0500] [Client 27] GET /printers/ HTTP/1.1
2D [07/Dec/2017:11:14:01 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
3D [07/Dec/2017:11:14:01 +0500] [Client 27] Read: status=200
4D [07/Dec/2017:11:14:01 +0500] [Client 27] No authentication data provided.
5D [07/Dec/2017:11:14:01 +0500] [Client 27] Processing GET /printers/
6D [07/Dec/2017:11:14:01 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi"
7D [07/Dec/2017:11:14:01 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups"
8D [07/Dec/2017:11:14:01 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups"
9D [07/Dec/2017:11:14:01 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
10D [07/Dec/2017:11:14:01 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
11D [07/Dec/2017:11:14:01 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
12D [07/Dec/2017:11:14:01 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
13D [07/Dec/2017:11:14:01 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups"
14D [07/Dec/2017:11:14:01 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups"
15D [07/Dec/2017:11:14:01 +0500] [CGI] envp[8] = "HOME=C:\\var\\temp"
16D [07/Dec/2017:11:14:01 +0500] [CGI] envp[9] = "NLSPATH=C:\\MPTN\\MSG\\NLS\\%N;C:\\TCPIP\\msg\\ENUS850\\%N;"
17D [07/Dec/2017:11:14:01 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
18D [07/Dec/2017:11:14:01 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@bluelion"
19D [07/Dec/2017:11:14:01 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3"
20D [07/Dec/2017:11:14:01 +0500] [CGI] envp[13] = "TMPDIR=C:\\var\\temp"
21D [07/Dec/2017:11:14:01 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600"
22D [07/Dec/2017:11:14:01 +0500] [CGI] envp[15] = "USER=root"
23D [07/Dec/2017:11:14:01 +0500] [CGI] envp[16] = "UNIXROOT=C:"
24D [07/Dec/2017:11:14:01 +0500] [CGI] envp[17] = "ETC=C:\\MPTN\\ETC"
25D [07/Dec/2017:11:14:01 +0500] [CGI] envp[18] = "CUPS_MAX_MESSAGE=2047"
26D [07/Dec/2017:11:14:01 +0500] [CGI] envp[19] = "CUPS_SERVER=\\socket\\cups.sock"
27D [07/Dec/2017:11:14:01 +0500] [CGI] envp[20] = "CUPS_ENCRYPTION=IfRequested"
28D [07/Dec/2017:11:14:01 +0500] [CGI] envp[21] = "IPP_PORT=631"
29D [07/Dec/2017:11:14:01 +0500] [CGI] envp[22] = "LANG=en_US.UTF8"
30D [07/Dec/2017:11:14:01 +0500] [CGI] envp[23] = "REDIRECT_STATUS=1"
31D [07/Dec/2017:11:14:01 +0500] [CGI] envp[24] = "GATEWAY_INTERFACE=CGI/1.1"
32D [07/Dec/2017:11:14:01 +0500] [CGI] envp[25] = "SERVER_NAME=localhost"
33D [07/Dec/2017:11:14:01 +0500] [CGI] envp[26] = "SERVER_PORT=631"
34D [07/Dec/2017:11:14:01 +0500] [CGI] envp[27] = "REMOTE_ADDR=127.0.0.1"
35D [07/Dec/2017:11:14:01 +0500] [CGI] envp[28] = "REMOTE_HOST=localhost"
36D [07/Dec/2017:11:14:01 +0500] [CGI] envp[29] = "SCRIPT_NAME=/printers/"
37D [07/Dec/2017:11:14:01 +0500] [CGI] envp[30] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/printers/"
38D [07/Dec/2017:11:14:01 +0500] [CGI] envp[31] = "SERVER_PROTOCOL=HTTP/1.1"
39D [07/Dec/2017:11:14:01 +0500] [CGI] envp[32] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:38.0) Gecko/20100101 Firefox/38.0"
40D [07/Dec/2017:11:14:01 +0500] [CGI] envp[33] = "HTTP_REFERER=http://localhost:631/"
41D [07/Dec/2017:11:14:01 +0500] [CGI] envp[34] = "REQUEST_METHOD=GET"
42D [07/Dec/2017:11:14:01 +0500] [CGI] envp[35] = "QUERY_STRING="
43D [07/Dec/2017:11:14:01 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/printers.cgi (PID 196)
44I [07/Dec/2017:11:14:01 +0500] [Client 27] Started "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" (pid=196, file=11)
45D [07/Dec/2017:11:14:01 +0500] [Client 27] Waiting for CGI data.
46D [07/Dec/2017:11:14:01 +0500] [Client 28] Accepted from localhost (Domain)
47D [07/Dec/2017:11:14:01 +0500] [Client 28] Waiting for request.
48D [07/Dec/2017:11:14:01 +0500] [CGI] org.cups.sid cookie not found, initializing!
49D [07/Dec/2017:11:14:01 +0500] [CGI] org.cups.sid cookie is \"9334d591df1517a2e8e935ad2c53aea6\"
50D [07/Dec/2017:11:14:01 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
51D [07/Dec/2017:11:14:01 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
52D [07/Dec/2017:11:14:01 +0500] [Client 28] POST / HTTP/1.1
53D [07/Dec/2017:11:14:01 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
54D [07/Dec/2017:11:14:01 +0500] [Client 28] Read: status=200
55D [07/Dec/2017:11:14:01 +0500] [Client 28] No authentication data provided.
56D [07/Dec/2017:11:14:01 +0500] [Client 28] 2.0 CUPS-Get-Default 1
57D [07/Dec/2017:11:14:01 +0500] CUPS-Get-Default
58D [07/Dec/2017:11:14:01 +0500] CUPS-Get-Default client-error-not-found: No default printer.
59D [07/Dec/2017:11:14:01 +0500] [Client 28] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
60D [07/Dec/2017:11:14:01 +0500] [Client 28] Content-Length: 113
61D [07/Dec/2017:11:14:01 +0500] [Client 28] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
62D [07/Dec/2017:11:14:01 +0500] [Client 28] con->http=0x200e10a0
63D [07/Dec/2017:11:14:01 +0500] [Client 28] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x200d1e20(IPP_STATE_DATA), pipe_pid=0, file=-1
64D [07/Dec/2017:11:14:01 +0500] [Client 28] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
65D [07/Dec/2017:11:14:01 +0500] [Client 28] bytes=0, http_state=0, data_remaining=113
66D [07/Dec/2017:11:14:01 +0500] [Client 28] Flushing write buffer.
67D [07/Dec/2017:11:14:01 +0500] [Client 28] New state is HTTP_STATE_WAITING
68D [07/Dec/2017:11:14:01 +0500] [Client 28] Waiting for request.
69D [07/Dec/2017:11:14:01 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
70D [07/Dec/2017:11:14:01 +0500] [CGI] show_all_printers(http=0x2003c360, user=\"(null)\")
71D [07/Dec/2017:11:14:01 +0500] [CGI] cgiSetVariable: TITLE=\"Printers\"
72D [07/Dec/2017:11:14:01 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
73D [07/Dec/2017:11:14:01 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
74D [07/Dec/2017:11:14:02 +0500] [Client 27] CGI data ready to be sent.
75D [07/Dec/2017:11:14:02 +0500] [Client 27] con->http=0x200da140
76D [07/Dec/2017:11:14:02 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=196, file=11
77D [07/Dec/2017:11:14:02 +0500] [Client 27] Waiting for CGI data.
78D [07/Dec/2017:11:14:02 +0500] [Client 27] Script header: Set-Cookie: org.cups.sid=9334d591df1517a2e8e935ad2c53aea6; path=/; httponly;
79D [07/Dec/2017:11:14:02 +0500] [Client 27] Script header: Content-Type: text/html;charset=utf-8
80D [07/Dec/2017:11:14:02 +0500] [Client 27] Script header:
81D [07/Dec/2017:11:14:02 +0500] [Client 27] Sending status 200 for CGI.
82D [07/Dec/2017:11:14:02 +0500] [Client 27] cupsdSendHeader: code=200, type="(null)", auth_type=0
83D [07/Dec/2017:11:14:02 +0500] [Client 27] con->http=0x200da140
84D [07/Dec/2017:11:14:02 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=196, file=11
85D [07/Dec/2017:11:14:02 +0500] [Client 27] Waiting for CGI data.
86D [07/Dec/2017:11:14:02 +0500] [Client 28] POST / HTTP/1.1
87D [07/Dec/2017:11:14:02 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
88D [07/Dec/2017:11:14:02 +0500] [Client 28] Read: status=200
89D [07/Dec/2017:11:14:02 +0500] [Client 28] No authentication data provided.
90D [07/Dec/2017:11:14:02 +0500] [Client 28] 2.0 CUPS-Get-Printers 2
91D [07/Dec/2017:11:14:02 +0500] CUPS-Get-Printers
92D [07/Dec/2017:11:14:02 +0500] [Client 28] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
93D [07/Dec/2017:11:14:02 +0500] [Client 28] Content-Length: 1006
94D [07/Dec/2017:11:14:02 +0500] [Client 28] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
95D [07/Dec/2017:11:14:02 +0500] [Client 28] con->http=0x200e10a0
96D [07/Dec/2017:11:14:02 +0500] [Client 28] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1006, response=0x200dd1e0(IPP_STATE_DATA), pipe_pid=0, file=-1
97D [07/Dec/2017:11:14:02 +0500] [Client 28] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
98D [07/Dec/2017:11:14:02 +0500] [Client 28] bytes=0, http_state=0, data_remaining=1006
99D [07/Dec/2017:11:14:02 +0500] [Client 28] Flushing write buffer.
100D [07/Dec/2017:11:14:02 +0500] [Client 28] New state is HTTP_STATE_WAITING
101D [07/Dec/2017:11:14:02 +0500] [Client 28] Waiting for request.
102D [07/Dec/2017:11:14:02 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
103D [07/Dec/2017:11:14:02 +0500] [Client 29] Accepted from localhost:51719 (IPv4)
104D [07/Dec/2017:11:14:02 +0500] [Client 29] Waiting for request.
105D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetVariable: TOTAL=\"3\"
106D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
107D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
108D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_state[0]=\"3\"
109D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_state_message[0]=\"\"
110D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\"
111D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/HP_Color_LaserJet_3800\"
112D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_name[0]=\"HP_Color_LaserJet_3800\"
113D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_location[0]=\"office\"
114D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_info[0]=\"HP Color LaserJet 3800 pcl3\"
115D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_make_and_model[0]=\"HP Color LaserJet 3800 pcl3, hpcups 3.16.11\"
116D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
117D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
118D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_state[1]=\"3\"
119D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_state_message[1]=\"\"
120D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: admin_uri[1]=\"/admin/\"
121D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_uri_supported[1]=\"/printers/HP_Color_Laserjet_3800_hpijs\"
122D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_name[1]=\"HP_Color_Laserjet_3800_hpijs\"
123D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_location[1]=\"office\"
124D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_info[1]=\"HP Color LaserJet 3800 hpijs pcl3\"
125D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_make_and_model[1]=\"HP Color LaserJet 3800 hpijs pcl3, 3.16.11\"
126D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
127D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
128D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_state[2]=\"3\"
129D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_state_message[2]=\"\"
130D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: admin_uri[2]=\"/admin/\"
131D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_uri_supported[2]=\"/printers/HP_Color_LaserJet_3800_PS\"
132D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_name[2]=\"HP_Color_LaserJet_3800_PS\"
133D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_location[2]=\"office\"
134D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_info[2]=\"HP Color LaserJet 3800 PS\"
135D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetArray: printer_make_and_model[2]=\"HP Color LaserJet 3800 Postscript (recommended)\"
136D [07/Dec/2017:11:14:02 +0500] [CGI] cgiSetVariable: THISURL=\"/printers/\"
137D [07/Dec/2017:11:14:02 +0500] [Client 27] CGI data ready to be sent.
138D [07/Dec/2017:11:14:02 +0500] [Client 27] con->http=0x200da140
139D [07/Dec/2017:11:14:02 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=196, file=11
140D [07/Dec/2017:11:14:02 +0500] [Client 27] Waiting for CGI data.
141D [07/Dec/2017:11:14:02 +0500] [Client 27] con->http=0x200da140
142D [07/Dec/2017:11:14:02 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=196, file=11
143D [07/Dec/2017:11:14:02 +0500] [Client 27] Waiting for CGI data.
144D [07/Dec/2017:11:14:02 +0500] [Client 27] CGI data ready to be sent.
145D [07/Dec/2017:11:14:02 +0500] [Client 27] con->http=0x200da140
146D [07/Dec/2017:11:14:02 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=196, file=11
147D [07/Dec/2017:11:14:02 +0500] [Client 27] Waiting for CGI data.
148D [07/Dec/2017:11:14:02 +0500] [Client 27] con->http=0x200da140
149D [07/Dec/2017:11:14:02 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=196, file=11
150D [07/Dec/2017:11:14:02 +0500] [Client 27] Waiting for CGI data.
151D [07/Dec/2017:11:14:02 +0500] [Client 27] CGI data ready to be sent.
152D [07/Dec/2017:11:14:02 +0500] [Client 28] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
153D [07/Dec/2017:11:14:02 +0500] [Client 28] Closing connection.
154D [07/Dec/2017:11:14:02 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
155D [07/Dec/2017:11:14:02 +0500] [Client 27] con->http=0x200da140
156D [07/Dec/2017:11:14:02 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=196, file=11
157D [07/Dec/2017:11:14:02 +0500] [Client 27] Waiting for CGI data.
158D [07/Dec/2017:11:14:02 +0500] [Client 27] con->http=0x200da140
159D [07/Dec/2017:11:14:02 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=196, file=11
160D [07/Dec/2017:11:14:02 +0500] [Client 27] Waiting for CGI data.
161D [07/Dec/2017:11:14:02 +0500] [Client 27] CGI data ready to be sent.
162D [07/Dec/2017:11:14:02 +0500] [Client 27] con->http=0x200da140
163D [07/Dec/2017:11:14:02 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=196, file=11
164D [07/Dec/2017:11:14:02 +0500] [Client 27] Waiting for CGI data.
165D [07/Dec/2017:11:14:02 +0500] [Client 27] Sending 0-length chunk.
166D [07/Dec/2017:11:14:02 +0500] [Client 27] Flushing write buffer.
167D [07/Dec/2017:11:14:02 +0500] [Client 27] New state is HTTP_STATE_WAITING
168D [07/Dec/2017:11:14:02 +0500] [Client 27] Waiting for request.
169D [07/Dec/2017:11:14:02 +0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
170D [07/Dec/2017:11:14:02 +0500] PID 196 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
171D [07/Dec/2017:11:14:04 +0500] [Client 27] GET /printers/HP_Color_Laserjet_3800_hpijs HTTP/1.1
172D [07/Dec/2017:11:14:04 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
173D [07/Dec/2017:11:14:04 +0500] [Client 27] Read: status=200
174D [07/Dec/2017:11:14:04 +0500] [Client 27] No authentication data provided.
175D [07/Dec/2017:11:14:04 +0500] [Client 27] Processing GET /printers/HP_Color_Laserjet_3800_hpijs
176D [07/Dec/2017:11:14:04 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi"
177D [07/Dec/2017:11:14:04 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups"
178D [07/Dec/2017:11:14:04 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups"
179D [07/Dec/2017:11:14:04 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
180D [07/Dec/2017:11:14:04 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
181D [07/Dec/2017:11:14:04 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
182D [07/Dec/2017:11:14:04 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
183D [07/Dec/2017:11:14:04 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups"
184D [07/Dec/2017:11:14:04 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups"
185D [07/Dec/2017:11:14:04 +0500] [CGI] envp[8] = "HOME=C:\\var\\temp"
186D [07/Dec/2017:11:14:04 +0500] [CGI] envp[9] = "NLSPATH=C:\\MPTN\\MSG\\NLS\\%N;C:\\TCPIP\\msg\\ENUS850\\%N;"
187D [07/Dec/2017:11:14:04 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
188D [07/Dec/2017:11:14:04 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@bluelion"
189D [07/Dec/2017:11:14:04 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3"
190D [07/Dec/2017:11:14:04 +0500] [CGI] envp[13] = "TMPDIR=C:\\var\\temp"
191D [07/Dec/2017:11:14:04 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600"
192D [07/Dec/2017:11:14:04 +0500] [CGI] envp[15] = "USER=root"
193D [07/Dec/2017:11:14:04 +0500] [CGI] envp[16] = "UNIXROOT=C:"
194D [07/Dec/2017:11:14:04 +0500] [CGI] envp[17] = "ETC=C:\\MPTN\\ETC"
195D [07/Dec/2017:11:14:04 +0500] [CGI] envp[18] = "CUPS_MAX_MESSAGE=2047"
196D [07/Dec/2017:11:14:04 +0500] [CGI] envp[19] = "CUPS_SERVER=\\socket\\cups.sock"
197D [07/Dec/2017:11:14:04 +0500] [CGI] envp[20] = "CUPS_ENCRYPTION=IfRequested"
198D [07/Dec/2017:11:14:04 +0500] [CGI] envp[21] = "IPP_PORT=631"
199D [07/Dec/2017:11:14:04 +0500] [CGI] envp[22] = "LANG=en_US.UTF8"
200D [07/Dec/2017:11:14:04 +0500] [CGI] envp[23] = "REDIRECT_STATUS=1"
201D [07/Dec/2017:11:14:04 +0500] [CGI] envp[24] = "GATEWAY_INTERFACE=CGI/1.1"
202D [07/Dec/2017:11:14:04 +0500] [CGI] envp[25] = "SERVER_NAME=localhost"
203D [07/Dec/2017:11:14:04 +0500] [CGI] envp[26] = "SERVER_PORT=631"
204D [07/Dec/2017:11:14:04 +0500] [CGI] envp[27] = "REMOTE_ADDR=127.0.0.1"
205D [07/Dec/2017:11:14:04 +0500] [CGI] envp[28] = "REMOTE_HOST=localhost"
206D [07/Dec/2017:11:14:04 +0500] [CGI] envp[29] = "SCRIPT_NAME=/printers/HP_Color_Laserjet_3800_hpijs"
207D [07/Dec/2017:11:14:04 +0500] [CGI] envp[30] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/printers/HP_Color_Laserjet_3800_hpijs"
208D [07/Dec/2017:11:14:04 +0500] [CGI] envp[31] = "PATH_INFO=/HP_Color_Laserjet_3800_hpijs"
209D [07/Dec/2017:11:14:04 +0500] [CGI] envp[32] = "SERVER_PROTOCOL=HTTP/1.1"
210D [07/Dec/2017:11:14:04 +0500] [CGI] envp[33] = "HTTP_COOKIE=org.cups.sid=9334d591df1517a2e8e935ad2c53aea6"
211D [07/Dec/2017:11:14:04 +0500] [CGI] envp[34] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:38.0) Gecko/20100101 Firefox/38.0"
212D [07/Dec/2017:11:14:04 +0500] [CGI] envp[35] = "HTTP_REFERER=http://localhost:631/printers/"
213D [07/Dec/2017:11:14:04 +0500] [CGI] envp[36] = "REQUEST_METHOD=GET"
214D [07/Dec/2017:11:14:04 +0500] [CGI] envp[37] = "QUERY_STRING="
215D [07/Dec/2017:11:14:04 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/printers.cgi (PID 198)
216I [07/Dec/2017:11:14:04 +0500] [Client 27] Started "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" (pid=198, file=11)
217D [07/Dec/2017:11:14:04 +0500] [Client 27] Waiting for CGI data.
218D [07/Dec/2017:11:14:04 +0500] [CGI] org.cups.sid cookie is \"9334d591df1517a2e8e935ad2c53aea6\"
219D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
220D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
221D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_Laserjet_3800_hpijs\"
222D [07/Dec/2017:11:14:04 +0500] [Client 30] Accepted from localhost (Domain)
223D [07/Dec/2017:11:14:04 +0500] [Client 30] Waiting for request.
224D [07/Dec/2017:11:14:04 +0500] [Client 30] POST / HTTP/1.1
225D [07/Dec/2017:11:14:04 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
226D [07/Dec/2017:11:14:04 +0500] [Client 30] Read: status=200
227D [07/Dec/2017:11:14:04 +0500] [Client 30] No authentication data provided.
228D [07/Dec/2017:11:14:04 +0500] [Client 30] 2.0 CUPS-Get-Default 1
229D [07/Dec/2017:11:14:04 +0500] CUPS-Get-Default
230D [07/Dec/2017:11:14:04 +0500] CUPS-Get-Default client-error-not-found: No default printer.
231D [07/Dec/2017:11:14:04 +0500] [Client 30] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
232D [07/Dec/2017:11:14:04 +0500] [Client 30] Content-Length: 113
233D [07/Dec/2017:11:14:04 +0500] [Client 30] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
234D [07/Dec/2017:11:14:04 +0500] [Client 30] con->http=0x200e10a0
235D [07/Dec/2017:11:14:04 +0500] [Client 30] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x200d4400(IPP_STATE_DATA), pipe_pid=0, file=-1
236D [07/Dec/2017:11:14:04 +0500] [Client 30] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
237D [07/Dec/2017:11:14:04 +0500] [Client 30] bytes=0, http_state=0, data_remaining=113
238D [07/Dec/2017:11:14:04 +0500] [Client 30] Flushing write buffer.
239D [07/Dec/2017:11:14:04 +0500] [Client 30] New state is HTTP_STATE_WAITING
240D [07/Dec/2017:11:14:04 +0500] [Client 30] Waiting for request.
241D [07/Dec/2017:11:14:04 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
242D [07/Dec/2017:11:14:04 +0500] [CGI] show_printer(http=0x2003c3c0, printer=\"HP_Color_Laserjet_3800_hpijs\")
243D [07/Dec/2017:11:14:04 +0500] [Client 30] POST / HTTP/1.1
244D [07/Dec/2017:11:14:04 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
245D [07/Dec/2017:11:14:04 +0500] [Client 30] Read: status=200
246D [07/Dec/2017:11:14:04 +0500] [Client 30] No authentication data provided.
247D [07/Dec/2017:11:14:04 +0500] [Client 30] 2.0 Get-Printer-Attributes 2
248D [07/Dec/2017:11:14:04 +0500] Get-Printer-Attributes ipp://localhost/printers/HP_Color_Laserjet_3800_hpijs
249D [07/Dec/2017:11:14:04 +0500] [Client 30] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_Color_Laserjet_3800_hpijs) from localhost
250D [07/Dec/2017:11:14:04 +0500] [Client 30] Content-Length: 756
251D [07/Dec/2017:11:14:04 +0500] [Client 30] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
252D [07/Dec/2017:11:14:04 +0500] [Client 30] con->http=0x200e10a0
253D [07/Dec/2017:11:14:04 +0500] [Client 30] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=756, response=0x200d44c0(IPP_STATE_DATA), pipe_pid=0, file=-1
254D [07/Dec/2017:11:14:04 +0500] [Client 30] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
255D [07/Dec/2017:11:14:04 +0500] [Client 30] bytes=0, http_state=0, data_remaining=756
256D [07/Dec/2017:11:14:04 +0500] [Client 30] Flushing write buffer.
257D [07/Dec/2017:11:14:04 +0500] [Client 30] New state is HTTP_STATE_WAITING
258D [07/Dec/2017:11:14:04 +0500] [Client 30] Waiting for request.
259D [07/Dec/2017:11:14:04 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
260D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
261D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
262D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
263D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
264D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\"
265D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetArray: printer_is_shared[0]=\"1\"
266D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetArray: printer_state[0]=\"3\"
267D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\"
268D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/HP_Color_Laserjet_3800_hpijs\"
269D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetArray: printer_name[0]=\"HP_Color_Laserjet_3800_hpijs\"
270D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetArray: printer_location[0]=\"office\"
271D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetArray: printer_info[0]=\"HP Color LaserJet 3800 hpijs pcl3\"
272D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\"
273D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetArray: device_uri[0]=\"socket://192.168.100.16\"
274D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetArray: color_supported[0]=\"1\"
275D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetArray: printer_make_and_model[0]=\"HP Color LaserJet 3800 hpijs pcl3, 3.16.11\"
276D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetArray: media_default[0]=\"na_letter_8.5x11in\"
277D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetArray: sides_supported[0]=\"one-sided, two-sided-long-edge, two-sided-short-edge\"
278D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetArray: sides_default[0]=\"one-sided\"
279D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetArray: printer_commands[0]=\"AutoConfigure, Clean, PrintSelfTestPage\"
280D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"0\"
281D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetVariable: TITLE=\"HP_Color_Laserjet_3800_hpijs\"
282D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
283D [07/Dec/2017:11:14:04 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
284D [07/Dec/2017:11:14:04 +0500] [Client 27] CGI data ready to be sent.
285D [07/Dec/2017:11:14:04 +0500] [Client 27] con->http=0x200da140
286D [07/Dec/2017:11:14:04 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=198, file=11
287D [07/Dec/2017:11:14:04 +0500] [Client 27] Waiting for CGI data.
288D [07/Dec/2017:11:14:04 +0500] [Client 27] Script header: Content-Type: text/html;charset=utf-8
289D [07/Dec/2017:11:14:04 +0500] [Client 27] Script header:
290D [07/Dec/2017:11:14:04 +0500] [Client 27] Sending status 200 for CGI.
291D [07/Dec/2017:11:14:04 +0500] [Client 27] cupsdSendHeader: code=200, type="(null)", auth_type=0
292D [07/Dec/2017:11:14:04 +0500] [Client 27] con->http=0x200da140
293D [07/Dec/2017:11:14:04 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=198, file=11
294D [07/Dec/2017:11:14:04 +0500] [Client 27] Waiting for CGI data.
295D [07/Dec/2017:11:14:04 +0500] [CGI] Regular expression \".*Clean.*\"
296D [07/Dec/2017:11:14:05 +0500] [CGI] matches[0].rm_so=0
297D [07/Dec/2017:11:14:05 +0500] [CGI] matches[1].rm_so=-1
298D [07/Dec/2017:11:14:05 +0500] [CGI] Regular expression \".*PrintSelfTestPage.*\"
299D [07/Dec/2017:11:14:05 +0500] [CGI] matches[0].rm_so=0
300D [07/Dec/2017:11:14:05 +0500] [CGI] matches[1].rm_so=-1
301D [07/Dec/2017:11:14:05 +0500] [Client 27] CGI data ready to be sent.
302D [07/Dec/2017:11:14:05 +0500] [Client 27] con->http=0x200da140
303D [07/Dec/2017:11:14:05 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=198, file=11
304D [07/Dec/2017:11:14:05 +0500] [Client 27] Waiting for CGI data.
305D [07/Dec/2017:11:14:05 +0500] [Client 27] con->http=0x200da140
306D [07/Dec/2017:11:14:05 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=198, file=11
307D [07/Dec/2017:11:14:05 +0500] [Client 27] Waiting for CGI data.
308D [07/Dec/2017:11:14:05 +0500] [Client 27] CGI data ready to be sent.
309D [07/Dec/2017:11:14:05 +0500] [Client 27] con->http=0x200da140
310D [07/Dec/2017:11:14:05 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=198, file=11
311D [07/Dec/2017:11:14:05 +0500] [Client 27] Waiting for CGI data.
312D [07/Dec/2017:11:14:05 +0500] [Client 27] con->http=0x200da140
313D [07/Dec/2017:11:14:05 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=198, file=11
314D [07/Dec/2017:11:14:05 +0500] [Client 27] Waiting for CGI data.
315D [07/Dec/2017:11:14:05 +0500] [Client 30] POST / HTTP/1.1
316D [07/Dec/2017:11:14:05 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
317D [07/Dec/2017:11:14:05 +0500] [Client 30] Read: status=200
318D [07/Dec/2017:11:14:05 +0500] [Client 30] No authentication data provided.
319D [07/Dec/2017:11:14:05 +0500] [Client 30] 2.0 Get-Jobs 3
320D [07/Dec/2017:11:14:05 +0500] Get-Jobs ipp://localhost:631/printers/HP_Color_Laserjet_3800_hpijs
321D [07/Dec/2017:11:14:05 +0500] [Client 30] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HP_Color_Laserjet_3800_hpijs) from localhost
322D [07/Dec/2017:11:14:05 +0500] [Client 30] Content-Length: 75
323D [07/Dec/2017:11:14:05 +0500] [Client 30] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
324D [07/Dec/2017:11:14:05 +0500] [Client 30] con->http=0x200e10a0
325D [07/Dec/2017:11:14:05 +0500] [Client 30] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x20130ae0(IPP_STATE_DATA), pipe_pid=0, file=-1
326D [07/Dec/2017:11:14:05 +0500] [Client 30] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
327D [07/Dec/2017:11:14:05 +0500] [Client 30] bytes=0, http_state=0, data_remaining=75
328D [07/Dec/2017:11:14:05 +0500] [Client 30] Flushing write buffer.
329D [07/Dec/2017:11:14:05 +0500] [Client 30] New state is HTTP_STATE_WAITING
330D [07/Dec/2017:11:14:05 +0500] [Client 30] Waiting for request.
331D [07/Dec/2017:11:14:05 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
332D [07/Dec/2017:11:14:05 +0500] [CGI] cgiClearVariables called.
333D [07/Dec/2017:11:14:05 +0500] [CGI] cgiSetVariable: ORDER=\"asc\"
334D [07/Dec/2017:11:14:05 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
335D [07/Dec/2017:11:14:05 +0500] [CGI] cgiSetVariable: TOTAL=\"0\"
336D [07/Dec/2017:11:14:05 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_Laserjet_3800_hpijs\"
337D [07/Dec/2017:11:14:05 +0500] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/HP_Color_Laserjet_3800_hpijs\"
338D [07/Dec/2017:11:14:05 +0500] [CGI] cgiSetVariable: THISURL=\"/printers/HP_Color_Laserjet_3800_hpijs\"
339D [07/Dec/2017:11:14:05 +0500] [CGI] cgiSetVariable: SEARCH_DEST=\"HP_Color_Laserjet_3800_hpijs\"
340D [07/Dec/2017:11:14:05 +0500] [Client 27] CGI data ready to be sent.
341D [07/Dec/2017:11:14:05 +0500] [Client 27] con->http=0x200da140
342D [07/Dec/2017:11:14:05 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=198, file=11
343D [07/Dec/2017:11:14:05 +0500] [Client 27] Waiting for CGI data.
344D [07/Dec/2017:11:14:05 +0500] [Client 27] con->http=0x200da140
345D [07/Dec/2017:11:14:05 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=198, file=11
346D [07/Dec/2017:11:14:05 +0500] [Client 27] Waiting for CGI data.
347D [07/Dec/2017:11:14:05 +0500] [Client 27] CGI data ready to be sent.
348D [07/Dec/2017:11:14:05 +0500] [Client 27] con->http=0x200da140
349D [07/Dec/2017:11:14:05 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=198, file=11
350D [07/Dec/2017:11:14:05 +0500] [Client 27] Waiting for CGI data.
351D [07/Dec/2017:11:14:05 +0500] [Client 27] con->http=0x200da140
352D [07/Dec/2017:11:14:05 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=198, file=11
353D [07/Dec/2017:11:14:05 +0500] [Client 27] Waiting for CGI data.
354D [07/Dec/2017:11:14:05 +0500] [Client 27] CGI data ready to be sent.
355D [07/Dec/2017:11:14:05 +0500] [Client 30] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
356D [07/Dec/2017:11:14:05 +0500] [Client 30] Closing connection.
357D [07/Dec/2017:11:14:05 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
358D [07/Dec/2017:11:14:05 +0500] [Client 27] con->http=0x200da140
359D [07/Dec/2017:11:14:05 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=198, file=11
360D [07/Dec/2017:11:14:05 +0500] [Client 27] Waiting for CGI data.
361D [07/Dec/2017:11:14:05 +0500] PID 198 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
362D [07/Dec/2017:11:14:05 +0500] [Client 27] con->http=0x200da140
363D [07/Dec/2017:11:14:05 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=198, file=11
364D [07/Dec/2017:11:14:05 +0500] [Client 27] Waiting for CGI data.
365D [07/Dec/2017:11:14:05 +0500] [Client 27] CGI data ready to be sent.
366D [07/Dec/2017:11:14:05 +0500] [Client 27] con->http=0x200da140
367D [07/Dec/2017:11:14:05 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=198, file=11
368D [07/Dec/2017:11:14:05 +0500] [Client 27] Waiting for CGI data.
369D [07/Dec/2017:11:14:05 +0500] [Client 27] Sending 0-length chunk.
370D [07/Dec/2017:11:14:05 +0500] [Client 27] Flushing write buffer.
371D [07/Dec/2017:11:14:05 +0500] [Client 27] New state is HTTP_STATE_WAITING
372D [07/Dec/2017:11:14:05 +0500] [Client 27] Waiting for request.
373D [07/Dec/2017:11:14:05 +0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
374D [07/Dec/2017:11:14:07 +0500] [Client 29] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
375D [07/Dec/2017:11:14:07 +0500] [Client 29] Closing connection.
376D [07/Dec/2017:11:14:07 +0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
377D [07/Dec/2017:11:14:09 +0500] [Client 31] Accepted from localhost:51720 (IPv4)
378D [07/Dec/2017:11:14:09 +0500] [Client 31] Waiting for request.
379D [07/Dec/2017:11:14:09 +0500] [Client 27] POST /printers/HP_Color_Laserjet_3800_hpijs HTTP/1.1
380D [07/Dec/2017:11:14:09 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
381D [07/Dec/2017:11:14:09 +0500] [Client 27] Read: status=200
382D [07/Dec/2017:11:14:09 +0500] [Client 27] No authentication data provided.
383D [07/Dec/2017:11:14:09 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi"
384D [07/Dec/2017:11:14:09 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups"
385D [07/Dec/2017:11:14:09 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups"
386D [07/Dec/2017:11:14:09 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
387D [07/Dec/2017:11:14:09 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
388D [07/Dec/2017:11:14:09 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
389D [07/Dec/2017:11:14:09 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
390D [07/Dec/2017:11:14:09 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups"
391D [07/Dec/2017:11:14:09 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups"
392D [07/Dec/2017:11:14:09 +0500] [CGI] envp[8] = "HOME=C:\\var\\temp"
393D [07/Dec/2017:11:14:09 +0500] [CGI] envp[9] = "NLSPATH=C:\\MPTN\\MSG\\NLS\\%N;C:\\TCPIP\\msg\\ENUS850\\%N;"
394D [07/Dec/2017:11:14:09 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
395D [07/Dec/2017:11:14:09 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@bluelion"
396D [07/Dec/2017:11:14:09 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3"
397D [07/Dec/2017:11:14:09 +0500] [CGI] envp[13] = "TMPDIR=C:\\var\\temp"
398D [07/Dec/2017:11:14:09 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600"
399D [07/Dec/2017:11:14:09 +0500] [CGI] envp[15] = "USER=root"
400D [07/Dec/2017:11:14:09 +0500] [CGI] envp[16] = "UNIXROOT=C:"
401D [07/Dec/2017:11:14:09 +0500] [CGI] envp[17] = "ETC=C:\\MPTN\\ETC"
402D [07/Dec/2017:11:14:09 +0500] [CGI] envp[18] = "CUPS_MAX_MESSAGE=2047"
403D [07/Dec/2017:11:14:09 +0500] [CGI] envp[19] = "CUPS_SERVER=\\socket\\cups.sock"
404D [07/Dec/2017:11:14:09 +0500] [CGI] envp[20] = "CUPS_ENCRYPTION=IfRequested"
405D [07/Dec/2017:11:14:09 +0500] [CGI] envp[21] = "IPP_PORT=631"
406D [07/Dec/2017:11:14:09 +0500] [CGI] envp[22] = "LANG=en_US.UTF8"
407D [07/Dec/2017:11:14:09 +0500] [CGI] envp[23] = "REDIRECT_STATUS=1"
408D [07/Dec/2017:11:14:09 +0500] [CGI] envp[24] = "GATEWAY_INTERFACE=CGI/1.1"
409D [07/Dec/2017:11:14:09 +0500] [CGI] envp[25] = "SERVER_NAME=localhost"
410D [07/Dec/2017:11:14:09 +0500] [CGI] envp[26] = "SERVER_PORT=631"
411D [07/Dec/2017:11:14:09 +0500] [CGI] envp[27] = "REMOTE_ADDR=127.0.0.1"
412D [07/Dec/2017:11:14:09 +0500] [CGI] envp[28] = "REMOTE_HOST=localhost"
413D [07/Dec/2017:11:14:09 +0500] [CGI] envp[29] = "SCRIPT_NAME=/printers/HP_Color_Laserjet_3800_hpijs"
414D [07/Dec/2017:11:14:09 +0500] [CGI] envp[30] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/printers/HP_Color_Laserjet_3800_hpijs"
415D [07/Dec/2017:11:14:09 +0500] [CGI] envp[31] = "PATH_INFO=/HP_Color_Laserjet_3800_hpijs"
416D [07/Dec/2017:11:14:09 +0500] [CGI] envp[32] = "SERVER_PROTOCOL=HTTP/1.1"
417D [07/Dec/2017:11:14:09 +0500] [CGI] envp[33] = "HTTP_COOKIE=org.cups.sid=9334d591df1517a2e8e935ad2c53aea6"
418D [07/Dec/2017:11:14:09 +0500] [CGI] envp[34] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:38.0) Gecko/20100101 Firefox/38.0"
419D [07/Dec/2017:11:14:09 +0500] [CGI] envp[35] = "HTTP_REFERER=http://localhost:631/printers/HP_Color_Laserjet_3800_hpijs"
420D [07/Dec/2017:11:14:09 +0500] [CGI] envp[36] = "REQUEST_METHOD=POST"
421D [07/Dec/2017:11:14:09 +0500] [CGI] envp[37] = "CONTENT_LENGTH=64"
422D [07/Dec/2017:11:14:09 +0500] [CGI] envp[38] = "CONTENT_TYPE=application/x-www-form-urlencoded"
423D [07/Dec/2017:11:14:09 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/printers.cgi (PID 200)
424I [07/Dec/2017:11:14:09 +0500] [Client 27] Started "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" (pid=200, file=13)
425D [07/Dec/2017:11:14:09 +0500] [Client 27] Waiting for CGI data.
426D [07/Dec/2017:11:14:09 +0500] [Client 32] Accepted from localhost (Domain)
427D [07/Dec/2017:11:14:09 +0500] [Client 32] Waiting for request.
428D [07/Dec/2017:11:14:09 +0500] [CGI] org.cups.sid cookie is \"9334d591df1517a2e8e935ad2c53aea6\"
429D [07/Dec/2017:11:14:09 +0500] [CGI] cgiSetVariable: org.cups.sid=\"9334d591df1517a2e8e935ad2c53aea6\"
430D [07/Dec/2017:11:14:09 +0500] [CGI] cgiSetVariable: OP=\"print-test-page\"
431D [07/Dec/2017:11:14:09 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
432D [07/Dec/2017:11:14:09 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
433D [07/Dec/2017:11:14:09 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_Laserjet_3800_hpijs\"
434D [07/Dec/2017:11:14:09 +0500] [Client 32] POST /printers/HP_Color_Laserjet_3800_hpijs HTTP/1.1
435D [07/Dec/2017:11:14:09 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
436D [07/Dec/2017:11:14:09 +0500] [Client 32] Read: status=200
437D [07/Dec/2017:11:14:09 +0500] [Client 32] No authentication data provided.
438D [07/Dec/2017:11:14:09 +0500] [Client 32] 2.0 Print-Job 1
439D [07/Dec/2017:11:14:09 +0500] Print-Job ipp://localhost:631/printers/HP_Color_Laserjet_3800_hpijs
440D [07/Dec/2017:11:14:10 +0500] [Job ???] Auto-typing file...
441I [07/Dec/2017:11:14:10 +0500] [Job ???] Request file type is application/vnd.cups-pdf-banner.
442D [07/Dec/2017:11:14:10 +0500] cupsdMarkDirty(---J-)
443D [07/Dec/2017:11:14:10 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
444D [07/Dec/2017:11:14:10 +0500] Adding default job-sheets values "none,none"...
445I [07/Dec/2017:11:14:10 +0500] [Job 7] Adding start banner page "none".
446D [07/Dec/2017:11:14:10 +0500] Discarding unused job-created event...
447D [07/Dec/2017:11:14:10 +0500] cupsdMarkDirty(---J-)
448D [07/Dec/2017:11:14:10 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
449I [07/Dec/2017:11:14:10 +0500] [Job 7] Adding end banner page "none".
450I [07/Dec/2017:11:14:10 +0500] [Job 7] File of type application/vnd.cups-pdf-banner queued by "anonymous".
451D [07/Dec/2017:11:14:10 +0500] [Job 7] hold_until=0
452I [07/Dec/2017:11:14:10 +0500] [Job 7] Queued on "HP_Color_Laserjet_3800_hpijs" by "anonymous".
453D [07/Dec/2017:11:14:10 +0500] [Job 7] time-at-processing=1512663250
454D [07/Dec/2017:11:14:10 +0500] cupsdMarkDirty(---J-)
455D [07/Dec/2017:11:14:10 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
456D [07/Dec/2017:11:14:10 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
457D [07/Dec/2017:11:14:10 +0500] Discarding unused printer-state-changed event...
458D [07/Dec/2017:11:14:10 +0500] [Job 7] 3 filters for job:
459D [07/Dec/2017:11:14:10 +0500] [Job 7] bannertopdf.exe (application/vnd.cups-pdf-banner to application/pdf, cost 32)
460D [07/Dec/2017:11:14:10 +0500] [Job 7] pdftopdf.exe (application/pdf to application/vnd.cups-pdf, cost 66)
461D [07/Dec/2017:11:14:10 +0500] [Job 7] foomatic-rip (application/vnd.cups-pdf to printer/HP_Color_Laserjet_3800_hpijs, cost 0)
462D [07/Dec/2017:11:14:10 +0500] [Job 7] job-sheets=none,none
463D [07/Dec/2017:11:14:10 +0500] [Job 7] argv[0]="HP_Color_Laserjet_3800_hpijs"
464D [07/Dec/2017:11:14:10 +0500] [Job 7] argv[1]="7"
465D [07/Dec/2017:11:14:10 +0500] [Job 7] argv[2]="anonymous"
466D [07/Dec/2017:11:14:10 +0500] [Job 7] argv[3]="Test Page"
467D [07/Dec/2017:11:14:10 +0500] [Job 7] argv[4]="1"
468D [07/Dec/2017:11:14:10 +0500] [Job 7] argv[5]="job-uuid=urn:uuid:13dbe33b-da97-3f34-5b49-5d04c0aad1a2 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1512663250 time-at-processing=1512663250"
469D [07/Dec/2017:11:14:10 +0500] [Job 7] argv[6]="/@unixroot/var/spool/cups/d00007-001"
470D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[0]="CUPS_CACHEDIR=/@unixroot/var/cache/cups"
471D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[1]="CUPS_DATADIR=/@unixroot/usr/share/cups"
472D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[2]="CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
473D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[3]="CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
474D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[4]="CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
475D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[5]="CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
476D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[6]="CUPS_SERVERROOT=/@unixroot/etc/cups"
477D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[7]="CUPS_STATEDIR=/@unixroot/var/run/cups"
478D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[8]="HOME=C:\\var\\temp"
479D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[9]="NLSPATH=C:\\MPTN\\MSG\\NLS\\%N;C:\\TCPIP\\msg\\ENUS850\\%N;"
480D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[10]="PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
481D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[11]="SERVER_ADMIN=root@bluelion"
482D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[12]="SOFTWARE=CUPS/2.1.3"
483D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[13]="TMPDIR=C:\\var\\temp"
484D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[14]="TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600"
485D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[15]="USER=root"
486D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[16]="UNIXROOT=C:"
487D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[17]="ETC=C:\\MPTN\\ETC"
488D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[18]="CUPS_MAX_MESSAGE=2047"
489D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[19]="CUPS_SERVER=\\socket\\cups.sock"
490D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[20]="CUPS_ENCRYPTION=IfRequested"
491D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[21]="IPP_PORT=631"
492D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[22]="CHARSET=utf-8"
493D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[23]="LANG=en_US.UTF-8"
494D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[24]="PPD=/@unixroot/etc/cups/ppd/HP_Color_Laserjet_3800_hpijs.ppd"
495D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[25]="RIP_MAX_CACHE=128m"
496D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[26]="CONTENT_TYPE=application/vnd.cups-pdf-banner"
497D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[27]="DEVICE_URI=socket://192.168.100.16"
498D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[28]="PRINTER_INFO=HP Color LaserJet 3800 hpijs pcl3"
499D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[29]="PRINTER_LOCATION=office"
500D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[30]="PRINTER=HP_Color_Laserjet_3800_hpijs"
501D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[31]="PRINTER_STATE_REASONS=none"
502D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[32]="CUPS_FILETYPE=document"
503D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[33]="FINAL_CONTENT_TYPE=application/vnd.cups-pdf"
504D [07/Dec/2017:11:14:10 +0500] [Job 7] envp[34]="AUTH_I****"
505I [07/Dec/2017:11:14:10 +0500] [Job 7] Started filter /@unixroot/usr/lib/cups/filter/bannertopdf.exe (PID 202)
506I [07/Dec/2017:11:14:10 +0500] [Job 7] Started filter /@unixroot/usr/lib/cups/filter/pdftopdf.exe (PID 203)
507I [07/Dec/2017:11:14:10 +0500] [Job 7] Started filter /@unixroot/usr/lib/cups/filter/foomatic-rip.exe (PID 205)
508I [07/Dec/2017:11:14:10 +0500] [Job 7] Started backend /@unixroot/usr/lib/cups/backend/socket.exe (PID 208)
509D [07/Dec/2017:11:14:10 +0500] Discarding unused job-state-changed event...
510D [07/Dec/2017:11:14:10 +0500] [Client 32] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/HP_Color_Laserjet_3800_hpijs) from localhost
511D [07/Dec/2017:11:14:10 +0500] [Client 32] Content-Length: 191
512D [07/Dec/2017:11:14:10 +0500] [Client 32] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
513D [07/Dec/2017:11:14:10 +0500] [Client 32] con->http=0x200e4100
514D [07/Dec/2017:11:14:10 +0500] [Client 32] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=191, response=0x20130980(IPP_STATE_IDLE), pipe_pid=0, file=-1
515D [07/Dec/2017:11:14:10 +0500] [Client 32] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
516D [07/Dec/2017:11:14:10 +0500] [Client 32] bytes=0, http_state=0, data_remaining=191
517D [07/Dec/2017:11:14:10 +0500] [Client 32] Flushing write buffer.
518D [07/Dec/2017:11:14:10 +0500] [Client 32] New state is HTTP_STATE_WAITING
519D [07/Dec/2017:11:14:10 +0500] [Client 32] Waiting for request.
520D [07/Dec/2017:11:14:10 +0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
521D [07/Dec/2017:11:14:10 +0500] [Job 7] WARN: not compiled with DBus support
522D [07/Dec/2017:11:14:10 +0500] [Job 7] \'CM Color Calibration\' Mode in SPOOLER-LESS: Off
523D [07/Dec/2017:11:14:10 +0500] [Job 7] Getting input from file
524D [07/Dec/2017:11:14:10 +0500] [Job 7] foomatic-rip version 1.17.2 running...
525D [07/Dec/2017:11:14:10 +0500] [Client 33] Accepted from localhost (Domain)
526D [07/Dec/2017:11:14:10 +0500] [Client 33] Waiting for request.
527D [07/Dec/2017:11:14:10 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
528D [07/Dec/2017:11:14:10 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
529D [07/Dec/2017:11:14:10 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
530D [07/Dec/2017:11:14:10 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
531D [07/Dec/2017:11:14:10 +0500] [Client 33] POST /printers/HP_Color_Laserjet_3800_hpijs HTTP/1.1
532D [07/Dec/2017:11:14:10 +0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
533D [07/Dec/2017:11:14:10 +0500] [Client 33] Read: status=200
534D [07/Dec/2017:11:14:10 +0500] [Client 33] No authentication data provided.
535D [07/Dec/2017:11:14:10 +0500] [Client 33] 2.0 Get-Printer-Attributes 1
536D [07/Dec/2017:11:14:10 +0500] Get-Printer-Attributes ipp://localhost/printers/HP_Color_Laserjet_3800_hpijs
537D [07/Dec/2017:11:14:10 +0500] [Client 33] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_Color_Laserjet_3800_hpijs) from localhost
538D [07/Dec/2017:11:14:10 +0500] [Client 33] Content-Length: 214
539D [07/Dec/2017:11:14:10 +0500] [Client 33] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
540D [07/Dec/2017:11:14:10 +0500] [Client 33] con->http=0x20130fa0
541D [07/Dec/2017:11:14:10 +0500] [Client 33] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=214, response=0x200d6920(IPP_STATE_DATA), pipe_pid=0, file=-1
542D [07/Dec/2017:11:14:10 +0500] [Client 33] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
543D [07/Dec/2017:11:14:10 +0500] [Client 33] bytes=0, http_state=0, data_remaining=214
544D [07/Dec/2017:11:14:10 +0500] [Client 33] Flushing write buffer.
545D [07/Dec/2017:11:14:10 +0500] [Client 33] New state is HTTP_STATE_WAITING
546D [07/Dec/2017:11:14:10 +0500] [Client 33] Waiting for request.
547D [07/Dec/2017:11:14:10 +0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
548D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: +connecting-to-device
549D [07/Dec/2017:11:14:10 +0500] cupsdMarkDirty(---J-)
550D [07/Dec/2017:11:14:10 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients, printing jobs, and dirty files"
551D [07/Dec/2017:11:14:10 +0500] [Job 7] Looking up \"192.168.100.16\"...
552D [07/Dec/2017:11:14:10 +0500] Discarding unused printer-state-changed event...
553D [07/Dec/2017:11:14:10 +0500] [Client 34] Accepted from localhost:51721 (IPv4)
554D [07/Dec/2017:11:14:10 +0500] [Client 34] Waiting for request.
555D [07/Dec/2017:11:14:10 +0500] [CGI] cgiSetArray: job_uri[0]=\"/jobs/7\"
556D [07/Dec/2017:11:14:10 +0500] [CGI] cgiSetArray: job_id[0]=\"7\"
557D [07/Dec/2017:11:14:10 +0500] [CGI] cgiSetArray: job_state[0]=\"3\"
558D [07/Dec/2017:11:14:10 +0500] [CGI] cgiSetArray: job_state_message[0]=\"\"
559D [07/Dec/2017:11:14:10 +0500] [CGI] cgiSetArray: job_state_reasons[0]=\"none\"
560D [07/Dec/2017:11:14:10 +0500] [CGI] cgiSetVariable: refresh_page=\"2;URL=/printers/HP_Color_Laserjet_3800_hpijs\"
561D [07/Dec/2017:11:14:10 +0500] [CGI] cgiSetVariable: TITLE=\"Print Test Page\"
562D [07/Dec/2017:11:14:10 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
563D [07/Dec/2017:11:14:10 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
564D [07/Dec/2017:11:14:10 +0500] [Client 27] CGI data ready to be sent.
565D [07/Dec/2017:11:14:10 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_Laserjet_3800_hpijs\"
566D [07/Dec/2017:11:14:10 +0500] [Client 27] con->http=0x200da140
567D [07/Dec/2017:11:14:10 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=200, file=13
568D [07/Dec/2017:11:14:10 +0500] [Client 27] Waiting for CGI data.
569D [07/Dec/2017:11:14:10 +0500] [Client 27] Script header: Content-Type: text/html;charset=utf-8
570D [07/Dec/2017:11:14:10 +0500] [Client 27] Script header:
571D [07/Dec/2017:11:14:10 +0500] [Client 27] Sending status 200 for CGI.
572D [07/Dec/2017:11:14:10 +0500] [Client 27] cupsdSendHeader: code=200, type="(null)", auth_type=0
573D [07/Dec/2017:11:14:10 +0500] [Client 27] con->http=0x200da140
574D [07/Dec/2017:11:14:10 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=200, file=13
575D [07/Dec/2017:11:14:10 +0500] [Client 27] Waiting for CGI data.
576D [07/Dec/2017:11:14:10 +0500] [Client 34] GET /printers/HP_Color_Laserjet_3800_hpijs.ppd HTTP/1.1
577D [07/Dec/2017:11:14:10 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
578D [07/Dec/2017:11:14:10 +0500] [Client 34] Read: status=200
579D [07/Dec/2017:11:14:10 +0500] [Client 34] No authentication data provided.
580D [07/Dec/2017:11:14:10 +0500] [Client 34] Processing GET /printers/HP_Color_Laserjet_3800_hpijs.ppd
581D [07/Dec/2017:11:14:10 +0500] [Client 34] filename="/@unixroot/etc/cups/ppd/HP_Color_Laserjet_3800_hpijs.ppd", type=application/vnd.cups-ppd
582D [07/Dec/2017:11:14:10 +0500] [Client 34] cupsdSendHeader: code=200, type="application/vnd.cups-ppd", auth_type=0
583D [07/Dec/2017:11:14:10 +0500] [Client 34] Sending file.
584D [07/Dec/2017:11:14:10 +0500] [Client 34] con->http=0x20135180
585D [07/Dec/2017:11:14:10 +0500] [Client 34] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=17
586D [07/Dec/2017:11:14:10 +0500] [Client 34] con->http=0x20135180
587D [07/Dec/2017:11:14:10 +0500] [Client 34] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=17
588D [07/Dec/2017:11:14:10 +0500] [Client 34] con->http=0x20135180
589D [07/Dec/2017:11:14:10 +0500] [Client 34] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=17
590D [07/Dec/2017:11:14:10 +0500] [Client 34] con->http=0x20135180
591D [07/Dec/2017:11:14:10 +0500] [Client 34] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=17
592D [07/Dec/2017:11:14:10 +0500] [Client 34] con->http=0x20135180
593D [07/Dec/2017:11:14:10 +0500] [Client 34] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=17
594D [07/Dec/2017:11:14:10 +0500] [Client 34] con->http=0x20135180
595D [07/Dec/2017:11:14:10 +0500] [Client 34] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=17
596D [07/Dec/2017:11:14:10 +0500] [Client 34] con->http=0x20135180
597D [07/Dec/2017:11:14:10 +0500] [Client 34] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=17
598D [07/Dec/2017:11:14:10 +0500] [Client 34] con->http=0x20135180
599D [07/Dec/2017:11:14:10 +0500] [Client 34] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=17
600D [07/Dec/2017:11:14:10 +0500] [Client 34] con->http=0x20135180
601D [07/Dec/2017:11:14:10 +0500] [Client 34] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=17
602D [07/Dec/2017:11:14:10 +0500] [Client 34] con->http=0x20135180
603D [07/Dec/2017:11:14:10 +0500] [Client 34] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=17
604D [07/Dec/2017:11:14:10 +0500] [Client 34] con->http=0x20135180
605D [07/Dec/2017:11:14:10 +0500] [Client 34] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=17
606D [07/Dec/2017:11:14:10 +0500] [Client 34] Flushing write buffer.
607D [07/Dec/2017:11:14:10 +0500] [Client 34] New state is HTTP_STATE_WAITING
608D [07/Dec/2017:11:14:10 +0500] [Client 34] Waiting for request.
609D [07/Dec/2017:11:14:10 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
610D [07/Dec/2017:11:14:10 +0500] [Job 7] Parsing PPD file ...
611D [07/Dec/2017:11:14:10 +0500] [Job 7] Added option ColorSpace
612D [07/Dec/2017:11:14:10 +0500] [Job 7] Added option Resolution
613D [07/Dec/2017:11:14:10 +0500] [Job 7] Added option PageSize
614D [07/Dec/2017:11:14:10 +0500] [Job 7] Added option Model
615D [07/Dec/2017:11:14:10 +0500] [Job 7] Added option PrintoutMode
616D [07/Dec/2017:11:14:10 +0500] [Job 7] Added option InputSlot
617D [07/Dec/2017:11:14:10 +0500] [Job 7] Added option Duplex
618D [07/Dec/2017:11:14:10 +0500] [Job 7] Added option Quality
619D [07/Dec/2017:11:14:10 +0500] [Job 7] Added option ImageableArea
620D [07/Dec/2017:11:14:10 +0500] [Job 7] Added option PaperDimension
621D [07/Dec/2017:11:14:10 +0500] [Job 7] Added option Font
622D [07/Dec/2017:11:14:10 +0500] [Client 34] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
623D [07/Dec/2017:11:14:10 +0500] [Client 34] Closing connection.
624D [07/Dec/2017:11:14:10 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
625D [07/Dec/2017:11:14:10 +0500] [Client 32] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
626D [07/Dec/2017:11:14:10 +0500] [Client 32] Closing connection.
627D [07/Dec/2017:11:14:10 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
628D [07/Dec/2017:11:14:10 +0500] [Client 27] CGI data ready to be sent.
629D [07/Dec/2017:11:14:10 +0500] [Client 27] con->http=0x200da140
630D [07/Dec/2017:11:14:10 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=200, file=13
631D [07/Dec/2017:11:14:10 +0500] [Client 27] Waiting for CGI data.
632D [07/Dec/2017:11:14:10 +0500] [Client 27] con->http=0x200da140
633D [07/Dec/2017:11:14:10 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=200, file=13
634D [07/Dec/2017:11:14:10 +0500] [Client 27] Waiting for CGI data.
635D [07/Dec/2017:11:14:10 +0500] [Client 27] CGI data ready to be sent.
636D [07/Dec/2017:11:14:10 +0500] [Client 27] con->http=0x200da140
637D [07/Dec/2017:11:14:10 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=200, file=13
638D [07/Dec/2017:11:14:10 +0500] [Client 27] Waiting for CGI data.
639D [07/Dec/2017:11:14:10 +0500] [Client 27] Sending 0-length chunk.
640D [07/Dec/2017:11:14:10 +0500] [Client 27] Flushing write buffer.
641D [07/Dec/2017:11:14:10 +0500] [Client 27] New state is HTTP_STATE_WAITING
642D [07/Dec/2017:11:14:10 +0500] [Client 27] Waiting for request.
643D [07/Dec/2017:11:14:10 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
644D [07/Dec/2017:11:14:10 +0500] PID 200 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
645D [07/Dec/2017:11:14:10 +0500] [Job 7] ATTR: marker-colors=#000000,#00FFFF,#FF00FF,#FFFF00
646D [07/Dec/2017:11:14:10 +0500] cupsdMarkDirty(P----)
647D [07/Dec/2017:11:14:10 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
648D [07/Dec/2017:11:14:10 +0500] [Job 7] ATTR: marker-names=\'\"Black Cartridge HP Q6470A\"\',\'\"Cyan Cartridge HP Q7581A\"\',\'\"Magenta Cartridge HP Q7583A\"\',\'\"Yellow Cartridge HP Q7582A\"\'
649D [07/Dec/2017:11:14:10 +0500] cupsdMarkDirty(P----)
650D [07/Dec/2017:11:14:10 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
651D [07/Dec/2017:11:14:10 +0500] [Job 7] ATTR: marker-types=toner-cartridge,toner-cartridge,toner-cartridge,toner-cartridge
652D [07/Dec/2017:11:14:10 +0500] cupsdMarkDirty(P----)
653D [07/Dec/2017:11:14:10 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
654D [07/Dec/2017:11:14:10 +0500] [Job 7] ATTR: marker-levels=11,66,66,13
655D [07/Dec/2017:11:14:10 +0500] cupsdMarkDirty(P----)
656D [07/Dec/2017:11:14:10 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
657D [07/Dec/2017:11:14:10 +0500] [Job 7] new_supply_state=0, change_state=ffff
658D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: -developer-low-report
659D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: -developer-empty-warning
660D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: -marker-supply-low-report
661D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: -marker-supply-empty-warning
662D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: -opc-near-eol-report
663D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: -opc-life-over-warning
664D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: -toner-low-report
665D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: -toner-empty-warning
666D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: -waste-receptacle-almost-full-report
667D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: -waste-receptacle-full-warning
668D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: -cleaner-life-almost-over-report
669D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: -cleaner-life-over-warning
670D [07/Dec/2017:11:14:10 +0500] Discarding unused printer-state-changed event...
671D [07/Dec/2017:11:14:10 +0500] [Job 7] new_state=2000, change_state=ffff
672D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: -media-empty-warning
673D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: -door-open-report
674D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: -media-jam-warning
675D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: -input-tray-missing-warning
676D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: -output-tray-missing-warning
677D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: -marker-supply-missing-warning
678D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: -output-area-almost-full-report
679D [07/Dec/2017:11:14:10 +0500] [Job 7] STATE: -output-area-full-warning
680D [07/Dec/2017:11:14:10 +0500] [Job 7] backendWaitLoop(snmp_fd=5, addr=0x20037e04, side_cb=0x110f0)
681D [07/Dec/2017:11:14:10 +0500] [Job 7] Parameter Summary
682D [07/Dec/2017:11:14:10 +0500] [Job 7] -----------------
683D [07/Dec/2017:11:14:10 +0500] [Job 7] Spooler: cups
684D [07/Dec/2017:11:14:10 +0500] [Job 7] Printer: HP_Color_Laserjet_3800_hpijs
685D [07/Dec/2017:11:14:10 +0500] [Job 7] Shell: /@unixroot/usr/bin/sh
686D [07/Dec/2017:11:14:10 +0500] [Job 7] PPD file: /@unixroot/etc/cups/ppd/HP_Color_Laserjet_3800_hpijs.ppd
687D [07/Dec/2017:11:14:10 +0500] [Job 7] ATTR file:
688D [07/Dec/2017:11:14:10 +0500] [Job 7] Printer model: HP Color LaserJet 3800 hpijs pcl3, 3.16.11
689D [07/Dec/2017:11:14:10 +0500] [Job 7] Job title: Test Page
690D [07/Dec/2017:11:14:10 +0500] [Job 7] File(s) to be printed:
691D [07/Dec/2017:11:14:10 +0500] [Job 7] <STDIN>
692D [07/Dec/2017:11:14:10 +0500] [Job 7] Ghostscript extra search path (\'GS_LIB\'): /@unixroot/usr/share/cups/fonts
693D [07/Dec/2017:11:14:10 +0500] [Job 7] Printing system options:
694D [07/Dec/2017:11:14:10 +0500] [Job 7] Pondering option \'job-uuid=urn:uuid:13dbe33b-da97-3f34-5b49-5d04c0aad1a2\'
695D [07/Dec/2017:11:14:10 +0500] [Job 7] Unknown option job-uuid=urn:uuid:13dbe33b-da97-3f34-5b49-5d04c0aad1a2.
696D [07/Dec/2017:11:14:10 +0500] [Job 7] Pondering option \'job-originating-host-name=localhost\'
697D [07/Dec/2017:11:14:10 +0500] [Job 7] Unknown option job-originating-host-name=localhost.
698D [07/Dec/2017:11:14:10 +0500] [Job 7] Pondering option \'date-time-at-creation=\'
699D [07/Dec/2017:11:14:10 +0500] [Job 7] Unknown option date-time-at-creation=.
700D [07/Dec/2017:11:14:10 +0500] [Job 7] Pondering option \'date-time-at-processing=\'
701D [07/Dec/2017:11:14:10 +0500] [Job 7] Unknown option date-time-at-processing=.
702D [07/Dec/2017:11:14:10 +0500] [Job 7] Pondering option \'time-at-creation=1512663250\'
703D [07/Dec/2017:11:14:10 +0500] [Job 7] Unknown option time-at-creation=1512663250.
704D [07/Dec/2017:11:14:10 +0500] [Job 7] Pondering option \'time-at-processing=1512663250\'
705D [07/Dec/2017:11:14:10 +0500] [Job 7] Unknown option time-at-processing=1512663250.
706D [07/Dec/2017:11:14:10 +0500] [Job 7] CM Color Calibration Mode in CUPS: Off
707D [07/Dec/2017:11:14:10 +0500] [Job 7] Options from the PPD file:
708D [07/Dec/2017:11:14:10 +0500] [Job 7] ================================================
709D [07/Dec/2017:11:14:10 +0500] [Job 7] File: <STDIN>
710D [07/Dec/2017:11:14:10 +0500] [Job 7] ================================================
711D [07/Dec/2017:11:14:10 +0500] [Job 7] pdftopdf: Last filter determined by the PPD: foomatic-rip; FINAL_CONTENT_TYPE: application/vnd.cups-pdf => pdftopdf will log pages in page_log.
712D [07/Dec/2017:11:14:11 +0500] [Job 7] PDF template file doesn\'t have form. It\'s okay.
713W [07/Dec/2017:11:14:11 +0500] cupsdDoSelect: select() returned -1... pause and retry select()
714D [07/Dec/2017:11:14:11 +0500] [Job 7] PAGE: 1 1
715D [07/Dec/2017:11:14:11 +0500] cupsdMarkDirty(---J-)
716D [07/Dec/2017:11:14:11 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
717D [07/Dec/2017:11:14:11 +0500] cupsdMarkDirty(---J-)
718D [07/Dec/2017:11:14:11 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
719D [07/Dec/2017:11:14:11 +0500] Discarding unused job-progress event...
720D [07/Dec/2017:11:14:11 +0500] [Job 7] Filetype: PDF
721D [07/Dec/2017:11:14:11 +0500] [Job 7] Storing temporary files in C:\\var\\temp
722D [07/Dec/2017:11:14:11 +0500] [Job 7] PID 202 (/@unixroot/usr/lib/cups/filter/bannertopdf.exe) exited with no errors.
723D [07/Dec/2017:11:14:11 +0500] [Job 7] PID 203 (/@unixroot/usr/lib/cups/filter/pdftopdf.exe) exited with no errors.
724D [07/Dec/2017:11:14:12 +0500] [Client 27] GET /printers/HP_Color_Laserjet_3800_hpijs HTTP/1.1
725D [07/Dec/2017:11:14:12 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
726D [07/Dec/2017:11:14:12 +0500] [Client 27] Read: status=200
727D [07/Dec/2017:11:14:12 +0500] [Client 27] No authentication data provided.
728D [07/Dec/2017:11:14:12 +0500] [Client 27] Processing GET /printers/HP_Color_Laserjet_3800_hpijs
729D [07/Dec/2017:11:14:12 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi"
730D [07/Dec/2017:11:14:12 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups"
731D [07/Dec/2017:11:14:12 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups"
732D [07/Dec/2017:11:14:12 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
733D [07/Dec/2017:11:14:12 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
734D [07/Dec/2017:11:14:12 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
735D [07/Dec/2017:11:14:12 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
736D [07/Dec/2017:11:14:12 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups"
737D [07/Dec/2017:11:14:12 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups"
738D [07/Dec/2017:11:14:12 +0500] [CGI] envp[8] = "HOME=C:\\var\\temp"
739D [07/Dec/2017:11:14:12 +0500] [CGI] envp[9] = "NLSPATH=C:\\MPTN\\MSG\\NLS\\%N;C:\\TCPIP\\msg\\ENUS850\\%N;"
740D [07/Dec/2017:11:14:12 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
741D [07/Dec/2017:11:14:12 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@bluelion"
742D [07/Dec/2017:11:14:12 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3"
743D [07/Dec/2017:11:14:12 +0500] [CGI] envp[13] = "TMPDIR=C:\\var\\temp"
744D [07/Dec/2017:11:14:12 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600"
745D [07/Dec/2017:11:14:12 +0500] [CGI] envp[15] = "USER=root"
746D [07/Dec/2017:11:14:12 +0500] [CGI] envp[16] = "UNIXROOT=C:"
747D [07/Dec/2017:11:14:12 +0500] [CGI] envp[17] = "ETC=C:\\MPTN\\ETC"
748D [07/Dec/2017:11:14:12 +0500] [CGI] envp[18] = "CUPS_MAX_MESSAGE=2047"
749D [07/Dec/2017:11:14:12 +0500] [CGI] envp[19] = "CUPS_SERVER=\\socket\\cups.sock"
750D [07/Dec/2017:11:14:12 +0500] [CGI] envp[20] = "CUPS_ENCRYPTION=IfRequested"
751D [07/Dec/2017:11:14:12 +0500] [CGI] envp[21] = "IPP_PORT=631"
752D [07/Dec/2017:11:14:12 +0500] [CGI] envp[22] = "LANG=en_US.UTF8"
753D [07/Dec/2017:11:14:12 +0500] [CGI] envp[23] = "REDIRECT_STATUS=1"
754D [07/Dec/2017:11:14:12 +0500] [CGI] envp[24] = "GATEWAY_INTERFACE=CGI/1.1"
755D [07/Dec/2017:11:14:12 +0500] [CGI] envp[25] = "SERVER_NAME=localhost"
756D [07/Dec/2017:11:14:12 +0500] [CGI] envp[26] = "SERVER_PORT=631"
757D [07/Dec/2017:11:14:12 +0500] [CGI] envp[27] = "REMOTE_ADDR=127.0.0.1"
758D [07/Dec/2017:11:14:12 +0500] [CGI] envp[28] = "REMOTE_HOST=localhost"
759D [07/Dec/2017:11:14:12 +0500] [CGI] envp[29] = "SCRIPT_NAME=/printers/HP_Color_Laserjet_3800_hpijs"
760D [07/Dec/2017:11:14:12 +0500] [CGI] envp[30] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/printers/HP_Color_Laserjet_3800_hpijs"
761D [07/Dec/2017:11:14:12 +0500] [CGI] envp[31] = "PATH_INFO=/HP_Color_Laserjet_3800_hpijs"
762D [07/Dec/2017:11:14:12 +0500] [CGI] envp[32] = "SERVER_PROTOCOL=HTTP/1.1"
763D [07/Dec/2017:11:14:12 +0500] [CGI] envp[33] = "HTTP_COOKIE=org.cups.sid=9334d591df1517a2e8e935ad2c53aea6"
764D [07/Dec/2017:11:14:12 +0500] [CGI] envp[34] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:38.0) Gecko/20100101 Firefox/38.0"
765D [07/Dec/2017:11:14:12 +0500] [CGI] envp[35] = "REQUEST_METHOD=GET"
766D [07/Dec/2017:11:14:12 +0500] [CGI] envp[36] = "QUERY_STRING="
767D [07/Dec/2017:11:14:12 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/printers.cgi (PID 212)
768I [07/Dec/2017:11:14:12 +0500] [Client 27] Started "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" (pid=212, file=12)
769D [07/Dec/2017:11:14:12 +0500] [Client 27] Waiting for CGI data.
770D [07/Dec/2017:11:14:12 +0500] [Client 35] Accepted from localhost (Domain)
771D [07/Dec/2017:11:14:12 +0500] [Client 35] Waiting for request.
772D [07/Dec/2017:11:14:12 +0500] [CGI] org.cups.sid cookie is \"9334d591df1517a2e8e935ad2c53aea6\"
773D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
774D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
775D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_Laserjet_3800_hpijs\"
776D [07/Dec/2017:11:14:12 +0500] [Client 35] POST / HTTP/1.1
777D [07/Dec/2017:11:14:12 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
778D [07/Dec/2017:11:14:12 +0500] [Client 35] Read: status=200
779D [07/Dec/2017:11:14:12 +0500] [Client 35] No authentication data provided.
780D [07/Dec/2017:11:14:12 +0500] [Client 35] 2.0 CUPS-Get-Default 1
781D [07/Dec/2017:11:14:12 +0500] CUPS-Get-Default
782D [07/Dec/2017:11:14:12 +0500] CUPS-Get-Default client-error-not-found: No default printer.
783D [07/Dec/2017:11:14:12 +0500] [Client 35] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
784D [07/Dec/2017:11:14:12 +0500] [Client 35] Content-Length: 113
785D [07/Dec/2017:11:14:12 +0500] [Client 35] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
786D [07/Dec/2017:11:14:12 +0500] [Client 35] con->http=0x200e4100
787D [07/Dec/2017:11:14:12 +0500] [Client 35] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x200e05c0(IPP_STATE_DATA), pipe_pid=0, file=-1
788D [07/Dec/2017:11:14:12 +0500] [Client 35] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
789D [07/Dec/2017:11:14:12 +0500] [Client 35] bytes=0, http_state=0, data_remaining=113
790D [07/Dec/2017:11:14:12 +0500] [Client 35] Flushing write buffer.
791D [07/Dec/2017:11:14:12 +0500] [Client 35] New state is HTTP_STATE_WAITING
792D [07/Dec/2017:11:14:12 +0500] [Client 35] Waiting for request.
793D [07/Dec/2017:11:14:12 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
794D [07/Dec/2017:11:14:12 +0500] [CGI] show_printer(http=0x2003c3c0, printer=\"HP_Color_Laserjet_3800_hpijs\")
795D [07/Dec/2017:11:14:12 +0500] [Client 35] POST / HTTP/1.1
796D [07/Dec/2017:11:14:12 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
797D [07/Dec/2017:11:14:12 +0500] [Client 35] Read: status=200
798D [07/Dec/2017:11:14:12 +0500] [Client 35] No authentication data provided.
799D [07/Dec/2017:11:14:12 +0500] [Client 35] 2.0 Get-Printer-Attributes 2
800D [07/Dec/2017:11:14:12 +0500] Get-Printer-Attributes ipp://localhost/printers/HP_Color_Laserjet_3800_hpijs
801D [07/Dec/2017:11:14:12 +0500] [Client 35] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_Color_Laserjet_3800_hpijs) from localhost
802D [07/Dec/2017:11:14:12 +0500] [Client 35] Content-Length: 756
803D [07/Dec/2017:11:14:12 +0500] [Client 35] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
804D [07/Dec/2017:11:14:12 +0500] [Client 35] con->http=0x200e4100
805D [07/Dec/2017:11:14:12 +0500] [Client 35] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=756, response=0x200e0620(IPP_STATE_DATA), pipe_pid=0, file=-1
806D [07/Dec/2017:11:14:12 +0500] [Client 35] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
807D [07/Dec/2017:11:14:12 +0500] [Client 35] bytes=0, http_state=0, data_remaining=756
808D [07/Dec/2017:11:14:12 +0500] [Client 35] Flushing write buffer.
809D [07/Dec/2017:11:14:12 +0500] [Client 35] New state is HTTP_STATE_WAITING
810D [07/Dec/2017:11:14:12 +0500] [Client 35] Waiting for request.
811D [07/Dec/2017:11:14:12 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
812D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
813D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
814D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
815D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
816D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\"
817D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: printer_is_shared[0]=\"1\"
818D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: printer_state[0]=\"4\"
819D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\"
820D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/HP_Color_Laserjet_3800_hpijs\"
821D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: printer_name[0]=\"HP_Color_Laserjet_3800_hpijs\"
822D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: printer_location[0]=\"office\"
823D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: printer_info[0]=\"HP Color LaserJet 3800 hpijs pcl3\"
824D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\"
825D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: device_uri[0]=\"socket://192.168.100.16\"
826D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: color_supported[0]=\"1\"
827D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: printer_make_and_model[0]=\"HP Color LaserJet 3800 hpijs pcl3, 3.16.11\"
828D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: media_default[0]=\"na_letter_8.5x11in\"
829D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: sides_supported[0]=\"one-sided, two-sided-long-edge, two-sided-short-edge\"
830D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: sides_default[0]=\"one-sided\"
831D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: printer_commands[0]=\"AutoConfigure, Clean, PrintSelfTestPage\"
832D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"0\"
833D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetVariable: refresh_page=\"10;URL=/printers/HP_Color_Laserjet_3800_hpijs\"
834D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetVariable: TITLE=\"HP_Color_Laserjet_3800_hpijs\"
835D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
836D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
837D [07/Dec/2017:11:14:12 +0500] [Client 27] CGI data ready to be sent.
838D [07/Dec/2017:11:14:12 +0500] [Client 27] con->http=0x200da140
839D [07/Dec/2017:11:14:12 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=212, file=12
840D [07/Dec/2017:11:14:12 +0500] [Client 27] Waiting for CGI data.
841D [07/Dec/2017:11:14:12 +0500] [Client 27] Script header: Content-Type: text/html;charset=utf-8
842D [07/Dec/2017:11:14:12 +0500] [Client 27] Script header:
843D [07/Dec/2017:11:14:12 +0500] [Client 27] Sending status 200 for CGI.
844D [07/Dec/2017:11:14:12 +0500] [Client 27] cupsdSendHeader: code=200, type="(null)", auth_type=0
845D [07/Dec/2017:11:14:12 +0500] [Client 36] Accepted from localhost:51722 (IPv4)
846D [07/Dec/2017:11:14:12 +0500] [Client 36] Waiting for request.
847D [07/Dec/2017:11:14:12 +0500] [Client 27] con->http=0x200da140
848D [07/Dec/2017:11:14:12 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=212, file=12
849D [07/Dec/2017:11:14:12 +0500] [Client 27] Waiting for CGI data.
850D [07/Dec/2017:11:14:12 +0500] [Client 31] GET /cups.css HTTP/1.1
851D [07/Dec/2017:11:14:12 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
852D [07/Dec/2017:11:14:12 +0500] [Client 31] Read: status=200
853D [07/Dec/2017:11:14:12 +0500] [Client 31] No authentication data provided.
854D [07/Dec/2017:11:14:12 +0500] [Client 31] Processing GET /cups.css
855D [07/Dec/2017:11:14:12 +0500] [Client 31] filename="/@unixroot/usr/share/doc/cups/cups.css", type=text/css
856D [07/Dec/2017:11:14:12 +0500] [Client 31] cupsdSendHeader: code=304, type="(null)", auth_type=0
857D [07/Dec/2017:11:14:12 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
858D [07/Dec/2017:11:14:12 +0500] [Client 27] CGI data ready to be sent.
859D [07/Dec/2017:11:14:12 +0500] [Client 35] POST / HTTP/1.1
860D [07/Dec/2017:11:14:12 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
861D [07/Dec/2017:11:14:12 +0500] [Client 35] Read: status=200
862D [07/Dec/2017:11:14:12 +0500] [Client 35] No authentication data provided.
863D [07/Dec/2017:11:14:12 +0500] [Client 35] 2.0 Get-Jobs 3
864D [07/Dec/2017:11:14:12 +0500] Get-Jobs ipp://localhost:631/printers/HP_Color_Laserjet_3800_hpijs
865D [07/Dec/2017:11:14:12 +0500] [Client 35] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HP_Color_Laserjet_3800_hpijs) from localhost
866D [07/Dec/2017:11:14:12 +0500] [Client 35] Content-Length: 403
867D [07/Dec/2017:11:14:12 +0500] [Client 35] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
868D [07/Dec/2017:11:14:12 +0500] [CGI] Regular expression \".*Clean.*\"
869D [07/Dec/2017:11:14:12 +0500] [CGI] matches[0].rm_so=0
870D [07/Dec/2017:11:14:12 +0500] [CGI] matches[1].rm_so=-1
871D [07/Dec/2017:11:14:12 +0500] [CGI] Regular expression \".*PrintSelfTestPage.*\"
872D [07/Dec/2017:11:14:12 +0500] [CGI] matches[0].rm_so=0
873D [07/Dec/2017:11:14:12 +0500] [CGI] matches[1].rm_so=-1
874D [07/Dec/2017:11:14:12 +0500] [Client 27] con->http=0x200da140
875D [07/Dec/2017:11:14:12 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=212, file=12
876D [07/Dec/2017:11:14:12 +0500] [Client 27] Waiting for CGI data.
877D [07/Dec/2017:11:14:12 +0500] [Client 35] con->http=0x200e4100
878D [07/Dec/2017:11:14:12 +0500] [Client 35] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=403, response=0x20138560(IPP_STATE_DATA), pipe_pid=0, file=-1
879D [07/Dec/2017:11:14:12 +0500] [Client 35] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
880D [07/Dec/2017:11:14:12 +0500] [Client 35] bytes=0, http_state=0, data_remaining=403
881D [07/Dec/2017:11:14:12 +0500] [Client 35] Flushing write buffer.
882D [07/Dec/2017:11:14:12 +0500] [Client 35] New state is HTTP_STATE_WAITING
883D [07/Dec/2017:11:14:12 +0500] [Client 35] Waiting for request.
884D [07/Dec/2017:11:14:12 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
885D [07/Dec/2017:11:14:12 +0500] [Client 27] con->http=0x200da140
886D [07/Dec/2017:11:14:12 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=212, file=12
887D [07/Dec/2017:11:14:12 +0500] [Client 27] Waiting for CGI data.
888D [07/Dec/2017:11:14:12 +0500] [CGI] cgiClearVariables called.
889D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetVariable: ORDER=\"asc\"
890D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
891D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetVariable: TOTAL=\"1\"
892D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
893D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
894D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: job_printer_name[0]=\"HP_Color_Laserjet_3800_hpijs\"
895D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: job_printer_uri[0]=\"/printers/HP_Color_Laserjet_3800_hpijs\"
896D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: job_name[0]=\"Test Page\"
897D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: job_originating_user_name[0]=\"anonymous\"
898D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: time_at_completed[0]=\"novalue\"
899D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: time_at_creation[0]=\"Thu Dec 7 11:14:10 EST 2017\"
900D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: time_at_processing[0]=\"Thu Dec 7 11:14:10 EST 2017\"
901D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: job_id[0]=\"7\"
902D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: job_state[0]=\"5\"
903D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: job_media_sheets_completed[0]=\"1\"
904D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: job_k_octets[0]=\"1\"
905D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetArray: job_printer_state_message[0]=\"\"
906D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_Laserjet_3800_hpijs\"
907D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/HP_Color_Laserjet_3800_hpijs\"
908D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetVariable: THISURL=\"/printers/HP_Color_Laserjet_3800_hpijs\"
909D [07/Dec/2017:11:14:12 +0500] [CGI] cgiSetVariable: SEARCH_DEST=\"HP_Color_Laserjet_3800_hpijs\"
910D [07/Dec/2017:11:14:12 +0500] [Client 27] CGI data ready to be sent.
911D [07/Dec/2017:11:14:12 +0500] [Client 27] con->http=0x200da140
912D [07/Dec/2017:11:14:12 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=212, file=12
913D [07/Dec/2017:11:14:12 +0500] [Client 27] Waiting for CGI data.
914D [07/Dec/2017:11:14:12 +0500] [Client 27] con->http=0x200da140
915D [07/Dec/2017:11:14:12 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=212, file=12
916D [07/Dec/2017:11:14:12 +0500] [Client 27] Waiting for CGI data.
917D [07/Dec/2017:11:14:12 +0500] [Client 27] CGI data ready to be sent.
918D [07/Dec/2017:11:14:12 +0500] [Client 27] con->http=0x200da140
919D [07/Dec/2017:11:14:12 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=212, file=12
920D [07/Dec/2017:11:14:12 +0500] [Client 27] Waiting for CGI data.
921D [07/Dec/2017:11:14:12 +0500] [Client 27] con->http=0x200da140
922D [07/Dec/2017:11:14:12 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=212, file=12
923D [07/Dec/2017:11:14:12 +0500] [Client 27] Waiting for CGI data.
924D [07/Dec/2017:11:14:12 +0500] [Client 27] CGI data ready to be sent.
925D [07/Dec/2017:11:14:12 +0500] [Client 35] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
926D [07/Dec/2017:11:14:12 +0500] [Client 35] Closing connection.
927D [07/Dec/2017:11:14:12 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
928D [07/Dec/2017:11:14:12 +0500] [Client 27] con->http=0x200da140
929D [07/Dec/2017:11:14:12 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=212, file=12
930D [07/Dec/2017:11:14:12 +0500] [Client 27] Waiting for CGI data.
931D [07/Dec/2017:11:14:12 +0500] PID 212 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
932D [07/Dec/2017:11:14:12 +0500] [Client 27] con->http=0x200da140
933D [07/Dec/2017:11:14:12 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=212, file=12
934D [07/Dec/2017:11:14:12 +0500] [Client 27] Waiting for CGI data.
935D [07/Dec/2017:11:14:12 +0500] [Client 27] CGI data ready to be sent.
936D [07/Dec/2017:11:14:12 +0500] [Client 27] con->http=0x200da140
937D [07/Dec/2017:11:14:12 +0500] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=212, file=12
938D [07/Dec/2017:11:14:12 +0500] [Client 27] Waiting for CGI data.
939D [07/Dec/2017:11:14:12 +0500] [Client 27] Sending 0-length chunk.
940D [07/Dec/2017:11:14:12 +0500] [Client 27] Flushing write buffer.
941D [07/Dec/2017:11:14:12 +0500] [Client 27] New state is HTTP_STATE_WAITING
942D [07/Dec/2017:11:14:12 +0500] [Client 27] Waiting for request.
943D [07/Dec/2017:11:14:12 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
944D [07/Dec/2017:11:14:13 +0500] [Job 7] GPL Ghostscript 9.18: Unrecoverable error, exit code 1
945D [07/Dec/2017:11:14:13 +0500] [Job 7] Process is dying with \"Unable to determine number of pages, page count: -1
946D [07/Dec/2017:11:14:13 +0500] [Job 7] \", exit stat 3
947D [07/Dec/2017:11:14:13 +0500] [Job 7] Cleaning up...
948D [07/Dec/2017:11:14:13 +0500] [Client 33] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
949D [07/Dec/2017:11:14:13 +0500] [Client 33] Closing connection.
950D [07/Dec/2017:11:14:13 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
951D [07/Dec/2017:11:14:13 +0500] [Job 7] PID 205 (/@unixroot/usr/lib/cups/filter/foomatic-rip.exe) stopped with status 3.
952D [07/Dec/2017:11:14:13 +0500] [Job 7] PID 208 (/@unixroot/usr/lib/cups/backend/socket.exe) exited with no errors.
953D [07/Dec/2017:11:14:13 +0500] Discarding unused job-state-changed event...
954E [07/Dec/2017:11:14:13 +0500] [Job 7] Job stopped due to filter errors; please consult the error_log file for details.
955D [07/Dec/2017:11:14:13 +0500] cupsdMarkDirty(---J-)
956D [07/Dec/2017:11:14:13 +0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
957D [07/Dec/2017:11:14:13 +0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
958D [07/Dec/2017:11:14:13 +0500] Discarding unused printer-state-changed event...
959D [07/Dec/2017:11:14:13 +0500] cupsdMarkDirty(---J-)
960D [07/Dec/2017:11:14:13 +0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
961D [07/Dec/2017:11:14:13 +0500] [Job 7] The following messages were recorded from 11:14:10 am to 11:14:10 am
962D [07/Dec/2017:11:14:13 +0500] [Job 7] hrDeviceDesc=\"HP Color LaserJet 3800\"
963D [07/Dec/2017:11:14:13 +0500] [Job 7] prtMarkerColorantValue.1.1 = \"black\"
964D [07/Dec/2017:11:14:13 +0500] [Job 7] prtMarkerColorantValue.1.2 = \"cyan\"
965D [07/Dec/2017:11:14:13 +0500] [Job 7] prtMarkerColorantValue.1.3 = \"magenta\"
966D [07/Dec/2017:11:14:13 +0500] [Job 7] prtMarkerColorantValue.1.4 = \"yellow\"
967D [07/Dec/2017:11:14:13 +0500] [Job 7] End of messages
968D [07/Dec/2017:11:14:13 +0500] [Job 7] printer-state=3(idle)
969D [07/Dec/2017:11:14:13 +0500] [Job 7] printer-state-message="Filter failed"
970D [07/Dec/2017:11:14:13 +0500] [Job 7] printer-state-reasons=none
971D [07/Dec/2017:11:14:14 +0500] [Job 7] Unloading...
972D [07/Dec/2017:11:14:18 +0500] [Client 36] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
973D [07/Dec/2017:11:14:18 +0500] [Client 36] Closing connection.
974D [07/Dec/2017:11:14:18 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
975D [07/Dec/2017:11:14:23 +0500] [Client 37] Accepted from localhost:51723 (IPv4)
976D [07/Dec/2017:11:14:23 +0500] [Client 37] Waiting for request.
977D [07/Dec/2017:11:14:23 +0500] [Client 27] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
978D [07/Dec/2017:11:14:23 +0500] [Client 27] Closing connection.
979D [07/Dec/2017:11:14:23 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
980D [07/Dec/2017:11:14:23 +0500] [Client 31] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
981D [07/Dec/2017:11:14:23 +0500] [Client 31] Closing connection.
982D [07/Dec/2017:11:14:23 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
983D [07/Dec/2017:11:14:23 +0500] [Client 38] Accepted from localhost:51724 (IPv4)
984D [07/Dec/2017:11:14:23 +0500] [Client 38] Waiting for request.
985D [07/Dec/2017:11:14:23 +0500] [Client 37] GET /printers/HP_Color_Laserjet_3800_hpijs HTTP/1.1
986D [07/Dec/2017:11:14:23 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
987D [07/Dec/2017:11:14:23 +0500] [Client 37] Read: status=200
988D [07/Dec/2017:11:14:23 +0500] [Client 37] No authentication data provided.
989D [07/Dec/2017:11:14:23 +0500] [Client 37] Processing GET /printers/HP_Color_Laserjet_3800_hpijs
990D [07/Dec/2017:11:14:23 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi"
991D [07/Dec/2017:11:14:23 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups"
992D [07/Dec/2017:11:14:23 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups"
993D [07/Dec/2017:11:14:23 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
994D [07/Dec/2017:11:14:23 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
995D [07/Dec/2017:11:14:23 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
996D [07/Dec/2017:11:14:23 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
997D [07/Dec/2017:11:14:23 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups"
998D [07/Dec/2017:11:14:23 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups"
999D [07/Dec/2017:11:14:23 +0500] [CGI] envp[8] = "HOME=C:\\var\\temp"
1000D [07/Dec/2017:11:14:23 +0500] [CGI] envp[9] = "NLSPATH=C:\\MPTN\\MSG\\NLS\\%N;C:\\TCPIP\\msg\\ENUS850\\%N;"
1001D [07/Dec/2017:11:14:23 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
1002D [07/Dec/2017:11:14:23 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@bluelion"
1003D [07/Dec/2017:11:14:23 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3"
1004D [07/Dec/2017:11:14:23 +0500] [CGI] envp[13] = "TMPDIR=C:\\var\\temp"
1005D [07/Dec/2017:11:14:23 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600"
1006D [07/Dec/2017:11:14:23 +0500] [CGI] envp[15] = "USER=root"
1007D [07/Dec/2017:11:14:23 +0500] [CGI] envp[16] = "UNIXROOT=C:"
1008D [07/Dec/2017:11:14:23 +0500] [CGI] envp[17] = "ETC=C:\\MPTN\\ETC"
1009D [07/Dec/2017:11:14:23 +0500] [CGI] envp[18] = "CUPS_MAX_MESSAGE=2047"
1010D [07/Dec/2017:11:14:23 +0500] [CGI] envp[19] = "CUPS_SERVER=\\socket\\cups.sock"
1011D [07/Dec/2017:11:14:23 +0500] [CGI] envp[20] = "CUPS_ENCRYPTION=IfRequested"
1012D [07/Dec/2017:11:14:23 +0500] [CGI] envp[21] = "IPP_PORT=631"
1013D [07/Dec/2017:11:14:23 +0500] [CGI] envp[22] = "LANG=en_US.UTF8"
1014D [07/Dec/2017:11:14:23 +0500] [CGI] envp[23] = "REDIRECT_STATUS=1"
1015D [07/Dec/2017:11:14:23 +0500] [CGI] envp[24] = "GATEWAY_INTERFACE=CGI/1.1"
1016D [07/Dec/2017:11:14:23 +0500] [CGI] envp[25] = "SERVER_NAME=localhost"
1017D [07/Dec/2017:11:14:23 +0500] [CGI] envp[26] = "SERVER_PORT=631"
1018D [07/Dec/2017:11:14:23 +0500] [CGI] envp[27] = "REMOTE_ADDR=127.0.0.1"
1019D [07/Dec/2017:11:14:23 +0500] [CGI] envp[28] = "REMOTE_HOST=localhost"
1020D [07/Dec/2017:11:14:23 +0500] [CGI] envp[29] = "SCRIPT_NAME=/printers/HP_Color_Laserjet_3800_hpijs"
1021D [07/Dec/2017:11:14:23 +0500] [CGI] envp[30] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/printers/HP_Color_Laserjet_3800_hpijs"
1022D [07/Dec/2017:11:14:23 +0500] [CGI] envp[31] = "PATH_INFO=/HP_Color_Laserjet_3800_hpijs"
1023D [07/Dec/2017:11:14:23 +0500] [CGI] envp[32] = "SERVER_PROTOCOL=HTTP/1.1"
1024D [07/Dec/2017:11:14:23 +0500] [CGI] envp[33] = "HTTP_COOKIE=org.cups.sid=9334d591df1517a2e8e935ad2c53aea6"
1025D [07/Dec/2017:11:14:23 +0500] [CGI] envp[34] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:38.0) Gecko/20100101 Firefox/38.0"
1026D [07/Dec/2017:11:14:23 +0500] [CGI] envp[35] = "REQUEST_METHOD=GET"
1027D [07/Dec/2017:11:14:23 +0500] [CGI] envp[36] = "QUERY_STRING="
1028D [07/Dec/2017:11:14:23 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/printers.cgi (PID 214)
1029I [07/Dec/2017:11:14:23 +0500] [Client 37] Started "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" (pid=214, file=11)
1030D [07/Dec/2017:11:14:23 +0500] [Client 37] Waiting for CGI data.
1031D [07/Dec/2017:11:14:23 +0500] [Client 39] Accepted from localhost (Domain)
1032D [07/Dec/2017:11:14:23 +0500] [Client 39] Waiting for request.
1033D [07/Dec/2017:11:14:23 +0500] [CGI] org.cups.sid cookie is \"9334d591df1517a2e8e935ad2c53aea6\"
1034D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
1035D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
1036D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_Laserjet_3800_hpijs\"
1037D [07/Dec/2017:11:14:23 +0500] [Client 39] POST / HTTP/1.1
1038D [07/Dec/2017:11:14:23 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1039D [07/Dec/2017:11:14:23 +0500] [Client 39] Read: status=200
1040D [07/Dec/2017:11:14:23 +0500] [Client 39] No authentication data provided.
1041D [07/Dec/2017:11:14:23 +0500] [Client 39] 2.0 CUPS-Get-Default 1
1042D [07/Dec/2017:11:14:23 +0500] CUPS-Get-Default
1043D [07/Dec/2017:11:14:23 +0500] CUPS-Get-Default client-error-not-found: No default printer.
1044D [07/Dec/2017:11:14:23 +0500] [Client 39] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
1045D [07/Dec/2017:11:14:23 +0500] [Client 39] Content-Length: 113
1046D [07/Dec/2017:11:14:23 +0500] [Client 39] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
1047D [07/Dec/2017:11:14:23 +0500] [Client 39] con->http=0x200da140
1048D [07/Dec/2017:11:14:23 +0500] [Client 39] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x2005f420(IPP_STATE_DATA), pipe_pid=0, file=-1
1049D [07/Dec/2017:11:14:23 +0500] [Client 39] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
1050D [07/Dec/2017:11:14:23 +0500] [Client 39] bytes=0, http_state=0, data_remaining=113
1051D [07/Dec/2017:11:14:23 +0500] [Client 39] Flushing write buffer.
1052D [07/Dec/2017:11:14:23 +0500] [Client 39] New state is HTTP_STATE_WAITING
1053D [07/Dec/2017:11:14:23 +0500] [Client 39] Waiting for request.
1054D [07/Dec/2017:11:14:23 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1055D [07/Dec/2017:11:14:23 +0500] [CGI] show_printer(http=0x2003c3c0, printer=\"HP_Color_Laserjet_3800_hpijs\")
1056D [07/Dec/2017:11:14:23 +0500] [Client 39] POST / HTTP/1.1
1057D [07/Dec/2017:11:14:23 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1058D [07/Dec/2017:11:14:23 +0500] [Client 39] Read: status=200
1059D [07/Dec/2017:11:14:23 +0500] [Client 39] No authentication data provided.
1060D [07/Dec/2017:11:14:23 +0500] [Client 39] 2.0 Get-Printer-Attributes 2
1061D [07/Dec/2017:11:14:23 +0500] Get-Printer-Attributes ipp://localhost/printers/HP_Color_Laserjet_3800_hpijs
1062D [07/Dec/2017:11:14:23 +0500] [Client 39] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_Color_Laserjet_3800_hpijs) from localhost
1063D [07/Dec/2017:11:14:23 +0500] [Client 39] Content-Length: 756
1064D [07/Dec/2017:11:14:23 +0500] [Client 39] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
1065D [07/Dec/2017:11:14:23 +0500] [Client 39] con->http=0x200da140
1066D [07/Dec/2017:11:14:23 +0500] [Client 39] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=756, response=0x200d7060(IPP_STATE_DATA), pipe_pid=0, file=-1
1067D [07/Dec/2017:11:14:23 +0500] [Client 39] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
1068D [07/Dec/2017:11:14:23 +0500] [Client 39] bytes=0, http_state=0, data_remaining=756
1069D [07/Dec/2017:11:14:23 +0500] [Client 39] Flushing write buffer.
1070D [07/Dec/2017:11:14:23 +0500] [Client 39] New state is HTTP_STATE_WAITING
1071D [07/Dec/2017:11:14:23 +0500] [Client 39] Waiting for request.
1072D [07/Dec/2017:11:14:23 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1073D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
1074D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
1075D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
1076D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
1077D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\"
1078D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: printer_is_shared[0]=\"1\"
1079D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: printer_state[0]=\"3\"
1080D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\"
1081D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/HP_Color_Laserjet_3800_hpijs\"
1082D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: printer_name[0]=\"HP_Color_Laserjet_3800_hpijs\"
1083D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: printer_location[0]=\"office\"
1084D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: printer_info[0]=\"HP Color LaserJet 3800 hpijs pcl3\"
1085D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\"
1086D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: device_uri[0]=\"socket://192.168.100.16\"
1087D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: color_supported[0]=\"1\"
1088D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: printer_make_and_model[0]=\"HP Color LaserJet 3800 hpijs pcl3, 3.16.11\"
1089D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: media_default[0]=\"na_letter_8.5x11in\"
1090D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: sides_supported[0]=\"one-sided, two-sided-long-edge, two-sided-short-edge\"
1091D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: sides_default[0]=\"one-sided\"
1092D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: printer_commands[0]=\"AutoConfigure, Clean, PrintSelfTestPage\"
1093D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"0\"
1094D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetVariable: TITLE=\"HP_Color_Laserjet_3800_hpijs\"
1095D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
1096D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
1097D [07/Dec/2017:11:14:23 +0500] [Client 37] CGI data ready to be sent.
1098D [07/Dec/2017:11:14:23 +0500] [Client 37] con->http=0x200e4100
1099D [07/Dec/2017:11:14:23 +0500] [Client 37] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=214, file=11
1100D [07/Dec/2017:11:14:23 +0500] [Client 37] Waiting for CGI data.
1101D [07/Dec/2017:11:14:23 +0500] [Client 37] Script header: Content-Type: text/html;charset=utf-8
1102D [07/Dec/2017:11:14:23 +0500] [Client 37] Script header:
1103D [07/Dec/2017:11:14:23 +0500] [Client 37] Sending status 200 for CGI.
1104D [07/Dec/2017:11:14:23 +0500] [Client 37] cupsdSendHeader: code=200, type="(null)", auth_type=0
1105D [07/Dec/2017:11:14:23 +0500] [Client 37] CGI data ready to be sent.
1106D [07/Dec/2017:11:14:23 +0500] [Client 37] con->http=0x200e4100
1107D [07/Dec/2017:11:14:23 +0500] [Client 37] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=214, file=11
1108D [07/Dec/2017:11:14:23 +0500] [Client 37] Waiting for CGI data.
1109D [07/Dec/2017:11:14:23 +0500] [Client 39] POST / HTTP/1.1
1110D [07/Dec/2017:11:14:23 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1111D [07/Dec/2017:11:14:23 +0500] [Client 39] Read: status=200
1112D [07/Dec/2017:11:14:23 +0500] [Client 39] No authentication data provided.
1113D [07/Dec/2017:11:14:23 +0500] [Client 39] 2.0 Get-Jobs 3
1114D [07/Dec/2017:11:14:23 +0500] Get-Jobs ipp://localhost:631/printers/HP_Color_Laserjet_3800_hpijs
1115D [07/Dec/2017:11:14:23 +0500] [Job 7] Loading attributes...
1116D [07/Dec/2017:11:14:23 +0500] [Client 39] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HP_Color_Laserjet_3800_hpijs) from localhost
1117D [07/Dec/2017:11:14:23 +0500] [Client 39] Content-Length: 435
1118D [07/Dec/2017:11:14:23 +0500] [Client 39] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
1119D [07/Dec/2017:11:14:23 +0500] [CGI] Regular expression \".*Clean.*\"
1120D [07/Dec/2017:11:14:23 +0500] [CGI] matches[0].rm_so=0
1121D [07/Dec/2017:11:14:23 +0500] [CGI] matches[1].rm_so=-1
1122D [07/Dec/2017:11:14:23 +0500] [CGI] Regular expression \".*PrintSelfTestPage.*\"
1123D [07/Dec/2017:11:14:23 +0500] [CGI] matches[0].rm_so=0
1124D [07/Dec/2017:11:14:23 +0500] [CGI] matches[1].rm_so=-1
1125D [07/Dec/2017:11:14:23 +0500] [Client 37] CGI data ready to be sent.
1126D [07/Dec/2017:11:14:23 +0500] [Client 37] con->http=0x200e4100
1127D [07/Dec/2017:11:14:23 +0500] [Client 37] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=214, file=11
1128D [07/Dec/2017:11:14:23 +0500] [Client 37] Waiting for CGI data.
1129D [07/Dec/2017:11:14:23 +0500] [Client 39] con->http=0x200da140
1130D [07/Dec/2017:11:14:23 +0500] [Client 39] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=435, response=0x20130a00(IPP_STATE_DATA), pipe_pid=0, file=-1
1131D [07/Dec/2017:11:14:23 +0500] [Client 39] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
1132D [07/Dec/2017:11:14:23 +0500] [Client 39] bytes=0, http_state=0, data_remaining=435
1133D [07/Dec/2017:11:14:23 +0500] [Client 39] Flushing write buffer.
1134D [07/Dec/2017:11:14:23 +0500] [Client 39] New state is HTTP_STATE_WAITING
1135D [07/Dec/2017:11:14:23 +0500] [Client 39] Waiting for request.
1136D [07/Dec/2017:11:14:23 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1137D [07/Dec/2017:11:14:23 +0500] [Client 37] con->http=0x200e4100
1138D [07/Dec/2017:11:14:23 +0500] [Client 37] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=214, file=11
1139D [07/Dec/2017:11:14:23 +0500] [Client 37] Waiting for CGI data.
1140D [07/Dec/2017:11:14:23 +0500] [CGI] cgiClearVariables called.
1141D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetVariable: ORDER=\"asc\"
1142D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
1143D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetVariable: TOTAL=\"1\"
1144D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
1145D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
1146D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: job_preserved[0]=\"1\"
1147D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: job_printer_name[0]=\"HP_Color_Laserjet_3800_hpijs\"
1148D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: job_printer_uri[0]=\"/printers/HP_Color_Laserjet_3800_hpijs\"
1149D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: job_name[0]=\"Test Page\"
1150D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: job_originating_user_name[0]=\"anonymous\"
1151D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: time_at_completed[0]=\"novalue\"
1152D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: time_at_creation[0]=\"Thu Dec 7 11:14:10 EST 2017\"
1153D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: time_at_processing[0]=\"Thu Dec 7 11:14:10 EST 2017\"
1154D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: job_id[0]=\"7\"
1155D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: job_state[0]=\"6\"
1156D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: job_media_sheets_completed[0]=\"1\"
1157D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: job_k_octets[0]=\"1\"
1158D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetArray: job_printer_state_message[0]=\"Filter failed\"
1159D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_Laserjet_3800_hpijs\"
1160D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/HP_Color_Laserjet_3800_hpijs\"
1161D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetVariable: THISURL=\"/printers/HP_Color_Laserjet_3800_hpijs\"
1162D [07/Dec/2017:11:14:23 +0500] [CGI] cgiSetVariable: SEARCH_DEST=\"HP_Color_Laserjet_3800_hpijs\"
1163D [07/Dec/2017:11:14:23 +0500] [Client 37] CGI data ready to be sent.
1164D [07/Dec/2017:11:14:23 +0500] [Client 37] con->http=0x200e4100
1165D [07/Dec/2017:11:14:23 +0500] [Client 37] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=214, file=11
1166D [07/Dec/2017:11:14:23 +0500] [Client 37] Waiting for CGI data.
1167D [07/Dec/2017:11:14:23 +0500] [Client 37] con->http=0x200e4100
1168D [07/Dec/2017:11:14:23 +0500] [Client 37] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=214, file=11
1169D [07/Dec/2017:11:14:23 +0500] [Client 37] Waiting for CGI data.
1170D [07/Dec/2017:11:14:23 +0500] [Client 38] GET /cups.css HTTP/1.1
1171D [07/Dec/2017:11:14:23 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1172D [07/Dec/2017:11:14:23 +0500] [Client 38] Read: status=200
1173D [07/Dec/2017:11:14:23 +0500] [Client 38] No authentication data provided.
1174D [07/Dec/2017:11:14:23 +0500] [Client 38] Processing GET /cups.css
1175D [07/Dec/2017:11:14:23 +0500] [Client 38] filename="/@unixroot/usr/share/doc/cups/cups.css", type=text/css
1176D [07/Dec/2017:11:14:23 +0500] [Client 38] cupsdSendHeader: code=304, type="(null)", auth_type=0
1177D [07/Dec/2017:11:14:23 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1178D [07/Dec/2017:11:14:23 +0500] [Client 37] CGI data ready to be sent.
1179D [07/Dec/2017:11:14:23 +0500] [Client 39] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
1180D [07/Dec/2017:11:14:23 +0500] [Client 39] Closing connection.
1181D [07/Dec/2017:11:14:23 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1182D [07/Dec/2017:11:14:23 +0500] [Client 40] Accepted from localhost:51725 (IPv4)
1183D [07/Dec/2017:11:14:23 +0500] [Client 40] Waiting for request.
1184D [07/Dec/2017:11:14:23 +0500] [Client 37] con->http=0x200e4100
1185D [07/Dec/2017:11:14:23 +0500] [Client 37] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=214, file=11
1186D [07/Dec/2017:11:14:23 +0500] [Client 37] Waiting for CGI data.
1187D [07/Dec/2017:11:14:23 +0500] [Client 37] CGI data ready to be sent.
1188D [07/Dec/2017:11:14:23 +0500] [Client 37] con->http=0x200e4100
1189D [07/Dec/2017:11:14:23 +0500] [Client 37] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=214, file=11
1190D [07/Dec/2017:11:14:23 +0500] [Client 37] Waiting for CGI data.
1191D [07/Dec/2017:11:14:23 +0500] [Client 37] Sending 0-length chunk.
1192D [07/Dec/2017:11:14:23 +0500] [Client 37] Flushing write buffer.
1193D [07/Dec/2017:11:14:23 +0500] [Client 37] New state is HTTP_STATE_WAITING
1194D [07/Dec/2017:11:14:23 +0500] [Client 37] Waiting for request.
1195D [07/Dec/2017:11:14:23 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
1196W [07/Dec/2017:11:14:23 +0500] cupsdDoSelect: select() returned -1... pause and retry select()
1197D [07/Dec/2017:11:14:24 +0500] PID 214 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
1198D [07/Dec/2017:11:14:28 +0500] [Client 40] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
1199D [07/Dec/2017:11:14:28 +0500] [Client 40] Closing connection.
1200D [07/Dec/2017:11:14:28 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
1201D [07/Dec/2017:11:14:33 +0500] [Client 38] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
1202D [07/Dec/2017:11:14:33 +0500] [Client 38] Closing connection.
1203D [07/Dec/2017:11:14:33 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
1204D [07/Dec/2017:11:14:33 +0500] [Client 37] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
1205D [07/Dec/2017:11:14:33 +0500] [Client 37] Closing connection.
1206D [07/Dec/2017:11:14:33 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
1207D [07/Dec/2017:11:14:33 +0500] Report: clients=0
1208D [07/Dec/2017:11:14:33 +0500] Report: jobs=1
1209D [07/Dec/2017:11:14:33 +0500] Report: jobs-active=1
1210D [07/Dec/2017:11:14:33 +0500] Report: printers=3
1211D [07/Dec/2017:11:14:33 +0500] Report: stringpool-string-count=26187
1212D [07/Dec/2017:11:14:33 +0500] Report: stringpool-alloc-bytes=12328
1213D [07/Dec/2017:11:14:33 +0500] Report: stringpool-total-bytes=430272
1214I [07/Dec/2017:11:14:41 +0500] Saving printers.conf...
1215I [07/Dec/2017:11:14:41 +0500] Saving job.cache...
1216D [07/Dec/2017:11:14:41 +0500] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"