Ticket #143: error_log-2017-12-08

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

TMP and TEMP changed in session (not master env). Printing fails.

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