Ticket #143: error_log-2017-12-08-TempDir

File error_log-2017-12-08-TempDir, 90.7 KB (added by Lewis Rosenthal, 6 years ago)

TempDir uncommented in cups-files.conf. ijs succeeds.

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