Ticket #157: SNIPPET.TXT

File SNIPPET.TXT, 34.6 KB (added by losepete, 7 years ago)

section of cups error_log

Line 
1D [17/Jun/2017:04:57:29 +0000] [Job 1] Set job-printer-state-message to "Color Manager: no profiles specified in PPD", current level=INFO
2D [17/Jun/2017:04:57:29 +0000] [Job 1] Color Manager: ICC Profile: None
3D [17/Jun/2017:04:57:29 +0000] [Job 1] Ghostscript using Any-Part-of-Pixel method to fill paths.
4D [17/Jun/2017:04:57:29 +0000] [Job 1] Ghostscript command line: gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -dNOMEDIAATTRS -sstdout=%stderr -sOutputFile=%stdout -sDEVICE=cups -sMediaClass=Cassette -sMediaType=Plain -r600x600 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -dcupsBitsPerColor=8 -dcupsColorOrder=0 -dcupsColorSpace=1 -dcupsRowFeed=2 -scupsPageSizeName=A4 -I/@unixroot/usr/share/cups/fonts -c \'<</.HWMargins[0.000000 0.000000 0.000000 0.000000] /Margins[0 0]>>setpagedevice\' -f -_
5D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[0]=\"CUPS_CACHEDIR=/@unixroot/var/cache/cups\"
6D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[1]=\"CUPS_DATADIR=/@unixroot/usr/share/cups\"
7D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[2]=\"CUPS_DOCROOT=/@unixroot/usr/share/doc/cups\"
8D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[3]=\"CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts\"
9D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[4]=\"CUPS_REQUESTROOT=/@unixroot/var/spool/cups\"
10D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[5]=\"CUPS_SERVERBIN=/@unixroot/usr/lib/cups\"
11D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[6]=\"CUPS_SERVERROOT=/@unixroot/etc/cups\"
12D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[7]=\"CUPS_STATEDIR=/@unixroot/var/run/cups\"
13D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[8]=\"HOME=I:\\TEMP\"
14D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[9]=\"NLSPATH=F:\\MPTN\\MSG\\NLS\\%N;F:\\TCPIP\\msg\\ENUS850\\%N;\"
15D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[10]=\"PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin\"
16D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[11]=\"SERVER_ADMIN=root@TM5320\"
17D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[12]=\"SOFTWARE=CUPS/2.1.3\"
18D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[13]=\"TMPDIR=I:\\TEMP\"
19D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[14]=\"TZ=GMT0BST,3,-1,0,3600,10,-1,0,7200,3600\"
20D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[15]=\"USER=root\"
21D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[16]=\"UNIXROOT=I:\"
22D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[17]=\"CUPS_MAX_MESSAGE=2047\"
23D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[18]=\"CUPS_SERVER=localhost\"
24d [17/Jun/2017:04:57:29 +0000] cupsdAddEvent(event=job-progress, dest=0x2005ebe0(CanonMG7500), job=0x200c5720(1), text="%s", ...)
25D [17/Jun/2017:04:57:29 +0000] Discarding unused job-progress event...
26d [17/Jun/2017:04:57:29 +0000] cupsdAddEvent(event=printer-state-changed, dest=0x2005ebe0(CanonMG7500), job=0(0), text="Printer \"%s\" state changed.", ...)
27D [17/Jun/2017:04:57:29 +0000] Discarding unused printer-state-changed event...
28d [17/Jun/2017:04:57:29 +0000] select_timeout: JobHistoryUpdate=0
29D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[19]=\"CUPS_ENCRYPTION=IfRequested\"
30D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[20]=\"IPP_PORT=631\"
31D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[21]=\"CHARSET=utf-8\"
32D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[22]=\"LANG=en_US.UTF-8\"
33D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[23]=\"PPD=/@unixroot/etc/cups/ppd/CanonMG7500.ppd\"
34D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[24]=\"RIP_MAX_CACHE=128m\"
35D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[25]=\"CONTENT_TYPE=application/vnd.cups-pdf-banner\"
36D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[26]=\"DEVICE_URI=usb://Canon/MG7500%20series?serial=117758&interface=1\"
37D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[27]=\"PRINTER_INFO=Canon MG7500 series\"
38D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[28]=\"PRINTER_LOCATION=\"
39D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[29]=\"PRINTER=CanonMG7500\"
40D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[30]=\"PRINTER_STATE_REASONS=none\"
41D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[31]=\"CUPS_FILETYPE=document\"
42D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[32]=\"FINAL_CONTENT_TYPE=application/vnd.cups-raster\"
43D [17/Jun/2017:04:57:29 +0000] [Job 1] envp[33]=\"AUTH_INFO_REQUIRED=none\"
44D [17/Jun/2017:04:57:29 +0000] [Job 1] bww pipe1 6, pipe2 7
45D [17/Jun/2017:04:57:29 +0000] [Job 1] bww after fork
46D [17/Jun/2017:04:57:29 +0000] [Job 1] bww before execvp file: gs
47D [17/Jun/2017:04:57:29 +0000] [Job 1] bww before fread
48D [17/Jun/2017:04:57:29 +0000] [Job 1] bww read bytes: 8192 write bytes 8192
49d [17/Jun/2017:04:57:29 +0000] select_timeout: JobHistoryUpdate=0
50d [17/Jun/2017:04:57:29 +0000] [CGI] cgiSetIPPVars(response=0x200477e0, filter_name=\"(null)\", filter_value=\"(null)\", prefix=\"(null)\", parent_el=0)
51D [17/Jun/2017:04:57:29 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
52D [17/Jun/2017:04:57:29 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
53d [17/Jun/2017:04:57:29 +0000] [CGI] cgiSetIPPObjectVars(obj=0x200478a0, prefix=\"(null)\", element=0)
54D [17/Jun/2017:04:57:29 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
55D [17/Jun/2017:04:57:29 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
56D [17/Jun/2017:04:57:29 +0000] [CGI] cgiSetArray: job_uri[0]=\"/jobs/1\"
57d [17/Jun/2017:04:57:29 +0000] [CGI] job_uri[0]=\"/jobs/1\"
58D [17/Jun/2017:04:57:29 +0000] [CGI] cgiSetArray: job_id[0]=\"1\"
59d [17/Jun/2017:04:57:29 +0000] [CGI] job_id[0]=\"1\"
60D [17/Jun/2017:04:57:29 +0000] [CGI] cgiSetArray: job_state[0]=\"3\"
61d [17/Jun/2017:04:57:29 +0000] [CGI] job_state[0]=\"3\"
62D [17/Jun/2017:04:57:29 +0000] [CGI] cgiSetArray: job_state_message[0]=\"\"
63d [17/Jun/2017:04:57:29 +0000] [CGI] job_state_message[0]=\"\"
64D [17/Jun/2017:04:57:29 +0000] [CGI] cgiSetArray: job_state_reasons[0]=\"none\"
65d [17/Jun/2017:04:57:29 +0000] [CGI] job_state_reasons[0]=\"none\"
66d [17/Jun/2017:04:57:29 +0000] [CGI] Returing 1 from cgiSetIPPVars()...
67D [17/Jun/2017:04:57:29 +0000] [CGI] cgiSetVariable: refresh_page=\"2;URL=/printers/CanonMG7500\"
68D [17/Jun/2017:04:57:29 +0000] [CGI] cgiSetVariable: TITLE=\"Print Test Page\"
69D [17/Jun/2017:04:57:29 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
70D [17/Jun/2017:04:57:29 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
71d [17/Jun/2017:04:57:29 +0000] [CGI] cgiCopyTemplateLang(tmpl=\"header.tmpl\")
72d [17/Jun/2017:04:57:29 +0000] [CGI] lang=\"en_US.UTF8\", locale=\"/en_US\"...
73d [17/Jun/2017:04:57:29 +0000] [CGI] Template file is \"/@unixroot/usr/share/cups/templates/header.tmpl\"...
74d [17/Jun/2017:04:57:29 +0000] [CGI] Starting at file position 0...
75d [17/Jun/2017:04:57:29 +0000] [CGI] Starting \"{refresh_page?\" at 294, result=1...
76d [17/Jun/2017:04:57:29 +0000] [CGI] Output first part...
77d [17/Jun/2017:04:57:29 +0000] [CGI] Starting at file position 294...
78d [17/Jun/2017:04:57:29 +0000] [CGI] \"{refresh_page}\" at 344...
79d [17/Jun/2017:04:57:29 +0000] [CGI] Returning at file position 347 on character \':\'...
80d [17/Jun/2017:04:57:29 +0000] [CGI] Skip second part...
81d [17/Jun/2017:04:57:29 +0000] [CGI] Starting at file position 347...
82d [17/Jun/2017:04:57:29 +0000] [CGI] Returning at file position 348 on character \'}\'...
83d [17/Jun/2017:04:57:29 +0000] [CGI] Finished \"{refresh_page?\", out=0x1b8c8ffc...
84d [17/Jun/2017:04:57:29 +0000] [CGI] \"{display:none;}\" at 493...
85d [17/Jun/2017:04:57:29 +0000] [CGI] Lone { at 633...
86d [17/Jun/2017:04:57:29 +0000] [CGI] Lone { at 708...
87d [17/Jun/2017:04:57:29 +0000] [CGI] Lone { at 843...
88d [17/Jun/2017:04:57:29 +0000] [CGI] Lone { at 877...
89d [17/Jun/2017:04:57:29 +0000] [CGI] \"{title}\" at 1081...
90d [17/Jun/2017:04:57:29 +0000] [CGI] Starting \"{SECTION=admin\" at 1315, result=0...
91d [17/Jun/2017:04:57:29 +0000] [CGI] Skip first part...
92d [17/Jun/2017:04:57:29 +0000] [CGI] Starting at file position 1315...
93d [17/Jun/2017:04:57:29 +0000] [CGI] Returning at file position 1331 on character \':\'...
94d [17/Jun/2017:04:57:29 +0000] [CGI] Output second part...
95d [17/Jun/2017:04:57:29 +0000] [CGI] Starting at file position 1331...
96d [17/Jun/2017:04:57:29 +0000] [CGI] Returning at file position 1332 on character \'}\'...
97d [17/Jun/2017:04:57:29 +0000] [Client 2] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0(), file=-1
98D [17/Jun/2017:04:57:29 +0000] [Client 2] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
99D [17/Jun/2017:04:57:29 +0000] [Client 2] Closing connection.
100D [17/Jun/2017:04:57:29 +0000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
101d [17/Jun/2017:04:57:29 +0000] cupsdRemoveSelect(fd=10)
102d [17/Jun/2017:04:57:29 +0000] cupsdRemoveSelect(fd=-1)
103d [17/Jun/2017:04:57:29 +0000] [Client 1] write_pipe: CGI output on fd 11.
104d [17/Jun/2017:04:57:29 +0000] cupsdRemoveSelect(fd=11)
105d [17/Jun/2017:04:57:29 +0000] cupsdAddSelect(fd=9, read_cb=0, write_cb=0x185a0, data=0x200c6360)
106D [17/Jun/2017:04:57:29 +0000] [Client 1] CGI data ready to be sent.
107D [17/Jun/2017:04:57:29 +0000] [Job 1] Loading USB quirks from \"/@unixroot/usr/share/cups/usb\".
108D [17/Jun/2017:04:57:29 +0000] [Job 1] Loaded 129 quirks.
109D [17/Jun/2017:04:57:29 +0000] [Job 1] Printing on printer with URI: usb://Canon/MG7500%20series?serial=117758&interface=1
110D [17/Jun/2017:04:57:29 +0000] [Job 1] libusb_get_device_list=2
111d [17/Jun/2017:04:57:29 +0000] select_timeout: JobHistoryUpdate=0
112d [17/Jun/2017:04:57:29 +0000] [CGI] Finished \"{SECTION=admin\", out=0x1b8c8ffc...
113d [17/Jun/2017:04:57:29 +0000] [CGI] Starting \"{SECTION=classes\" at 1396, result=0...
114d [17/Jun/2017:04:57:29 +0000] [CGI] Skip first part...
115d [17/Jun/2017:04:57:29 +0000] [CGI] Starting at file position 1396...
116d [17/Jun/2017:04:57:29 +0000] [CGI] Returning at file position 1412 on character \':\'...
117d [17/Jun/2017:04:57:29 +0000] [CGI] Output second part...
118d [17/Jun/2017:04:57:29 +0000] [CGI] Starting at file position 1412...
119d [17/Jun/2017:04:57:29 +0000] [CGI] Returning at file position 1413 on character \'}\'...
120d [17/Jun/2017:04:57:29 +0000] [CGI] Finished \"{SECTION=classes\", out=0x1b8c8ffc...
121d [17/Jun/2017:04:57:29 +0000] [CGI] Starting \"{SECTION=help\" at 1470, result=0...
122d [17/Jun/2017:04:57:29 +0000] [CGI] Skip first part...
123d [17/Jun/2017:04:57:29 +0000] [CGI] Starting at file position 1470...
124d [17/Jun/2017:04:57:29 +0000] [CGI] Returning at file position 1486 on character \':\'...
125d [17/Jun/2017:04:57:29 +0000] [CGI] Output second part...
126d [17/Jun/2017:04:57:29 +0000] [CGI] Starting at file position 1486...
127d [17/Jun/2017:04:57:29 +0000] [CGI] Returning at file position 1487 on character \'}\'...
128d [17/Jun/2017:04:57:29 +0000] [CGI] Finished \"{SECTION=help\", out=0x1b8c8ffc...
129d [17/Jun/2017:04:57:29 +0000] [CGI] Starting \"{SECTION=jobs\" at 1538, result=0...
130d [17/Jun/2017:04:57:29 +0000] [CGI] Skip first part...
131d [17/Jun/2017:04:57:29 +0000] [CGI] Starting at file position 1538...
132d [17/Jun/2017:04:57:29 +0000] [CGI] Returning at file position 1554 on character \':\'...
133d [17/Jun/2017:04:57:29 +0000] [CGI] Output second part...
134d [17/Jun/2017:04:57:29 +0000] [CGI] Starting at file position 1554...
135d [17/Jun/2017:04:57:29 +0000] [CGI] Returning at file position 1555 on character \'}\'...
136d [17/Jun/2017:04:57:29 +0000] [CGI] Finished \"{SECTION=jobs\", out=0x1b8c8ffc...
137d [17/Jun/2017:04:57:29 +0000] [CGI] Starting \"{SECTION=printers\" at 1610, result=1...
138d [17/Jun/2017:04:57:29 +0000] [CGI] Output first part...
139d [17/Jun/2017:04:57:29 +0000] [CGI] Starting at file position 1610...
140d [17/Jun/2017:04:57:29 +0000] [CGI] Returning at file position 1626 on character \':\'...
141d [17/Jun/2017:04:57:29 +0000] [CGI] Skip second part...
142d [17/Jun/2017:04:57:29 +0000] [CGI] Starting at file position 1626...
143d [17/Jun/2017:04:57:29 +0000] [CGI] Returning at file position 1627 on character \'}\'...
144d [17/Jun/2017:04:57:29 +0000] [CGI] Finished \"{SECTION=printers\", out=0x1b8c8ffc...
145d [17/Jun/2017:04:57:29 +0000] [CGI] \"{title}\" at 1750...
146d [17/Jun/2017:04:57:29 +0000] [CGI] Returning at file position 1757 on EOF...
147D [17/Jun/2017:04:57:29 +0000] [CGI] cgiSetVariable: PRINTER_NAME=\"CanonMG7500\"
148d [17/Jun/2017:04:57:29 +0000] [CGI] cgiCopyTemplateLang(tmpl=\"test-page.tmpl\")
149d [17/Jun/2017:04:57:29 +0000] [CGI] lang=\"en_US.UTF8\", locale=\"/en_US\"...
150d [17/Jun/2017:04:57:29 +0000] [CGI] Template file is \"/@unixroot/usr/share/cups/templates/test-page.tmpl\"...
151d [17/Jun/2017:04:57:29 +0000] [CGI] Starting at file position 0...
152d [17/Jun/2017:04:57:29 +0000] [CGI] \"{printer_name}\" at 51...
153d [17/Jun/2017:04:57:29 +0000] [CGI] \"{SECTION}\" at 106...
154d [17/Jun/2017:04:57:29 +0000] [CGI] \"{printer_name}\" at 121...
155d [17/Jun/2017:04:57:29 +0000] [CGI] \"{printer_name}\" at 138...
156D [17/Jun/2017:04:57:29 +0000] [Client 1] con->http=0x2006b420
157D [17/Jun/2017:04:57:29 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=165, file=11
158d [17/Jun/2017:04:57:29 +0000] cupsdAddSelect(fd=11, read_cb=0x14a20, write_cb=0, data=0x200c6360)
159D [17/Jun/2017:04:57:29 +0000] [Client 1] Waiting for CGI data.
160D [17/Jun/2017:04:57:29 +0000] [Client 1] Script header: Content-Type: text/html;charset=utf-8
161D [17/Jun/2017:04:57:29 +0000] [Client 1] Script header:
162D [17/Jun/2017:04:57:29 +0000] [Client 1] Sending status 200 for CGI.
163D [17/Jun/2017:04:57:29 +0000] [Client 1] cupsdSendHeader: code=200, type="(null)", auth_type=0
164d [17/Jun/2017:04:57:29 +0000] process_children()
165d [17/Jun/2017:04:57:29 +0000] cupsdFinishProcess(pid=165, name=0x17f700, namelen=1024, job_id=0x17f6fc(0)) = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi"
166D [17/Jun/2017:04:57:29 +0000] PID 165 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
167d [17/Jun/2017:04:57:29 +0000] select_timeout: JobHistoryUpdate=0
168d [17/Jun/2017:04:57:29 +0000] cupsdAcceptClient(lis=0x2003eb60(6)) Clients=1
169D [17/Jun/2017:04:57:29 +0000] [Client 3] Accepted from localhost:55059 (IPv4)
170d [17/Jun/2017:04:57:29 +0000] cupsdAddSelect(fd=10, read_cb=0x19630, write_cb=0, data=0x2005d680)
171D [17/Jun/2017:04:57:29 +0000] [Client 3] Waiting for request.
172d [17/Jun/2017:04:57:29 +0000] [CGI] \"{job_id}\" at 147...
173d [17/Jun/2017:04:57:29 +0000] [CGI] Returning at file position 157 on EOF...
174d [17/Jun/2017:04:57:29 +0000] [CGI] cgiCopyTemplateLang(tmpl=\"trailer.tmpl\")
175d [17/Jun/2017:04:57:29 +0000] [CGI] lang=\"en_US.UTF8\", locale=\"/en_US\"...
176d [17/Jun/2017:04:57:29 +0000] [CGI] Template file is \"/@unixroot/usr/share/cups/templates/trailer.tmpl\"...
177d [17/Jun/2017:04:57:29 +0000] [CGI] Starting at file position 0...
178d [17/Jun/2017:04:57:29 +0000] [CGI] Returning at file position 218 on EOF...
179D [17/Jun/2017:04:57:29 +0000] [Client 1] con->http=0x2006b420
180D [17/Jun/2017:04:57:29 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=165, file=11
181d [17/Jun/2017:04:57:29 +0000] cupsdAddSelect(fd=11, read_cb=0x14a20, write_cb=0, data=0x200c6360)
182D [17/Jun/2017:04:57:29 +0000] [Client 1] Waiting for CGI data.
183d [17/Jun/2017:04:57:29 +0000] cupsdRemoveSelect(fd=9)
184d [17/Jun/2017:04:57:29 +0000] [Client 1] write_pipe: CGI output on fd 11.
185d [17/Jun/2017:04:57:29 +0000] cupsdRemoveSelect(fd=11)
186d [17/Jun/2017:04:57:29 +0000] cupsdAddSelect(fd=9, read_cb=0, write_cb=0x185a0, data=0x200c6360)
187D [17/Jun/2017:04:57:29 +0000] [Client 1] CGI data ready to be sent.
188D [17/Jun/2017:04:57:29 +0000] [Job 1] STATE: +connecting-to-device
189d [17/Jun/2017:04:57:29 +0000] cupsdSetPrinterReasons(p=0x2005ebe0(CanonMG7500),s="+connecting-to-device"
190D [17/Jun/2017:04:57:29 +0000] cupsdMarkDirty(---J-)
191D [17/Jun/2017:04:57:29 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients, printing jobs, and dirty files"
192D [17/Jun/2017:04:57:29 +0000] [Job 1] STATE: -connecting-to-device
193d [17/Jun/2017:04:57:29 +0000] cupsdSetPrinterReasons(p=0x2005ebe0(CanonMG7500),s="-connecting-to-device"
194D [17/Jun/2017:04:57:29 +0000] cupsdMarkDirty(---J-)
195D [17/Jun/2017:04:57:29 +0000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
196D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: ============================================================
197D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: VVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVV
198D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: 5.2.11 Starting
199D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: command line: CanonMG7500 \'1\' \'anonymous\' \'Test Page\' \'1\' <args>
200D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: using PPD file /@unixroot/etc/cups/ppd/CanonMG7500.ppd
201d [17/Jun/2017:04:57:29 +0000] cupsdAddEvent(event=printer-state-changed, dest=0x2005ebe0(CanonMG7500), job=0(0), text="Printer \"%s\" state changed.", ...)
202D [17/Jun/2017:04:57:29 +0000] Discarding unused printer-state-changed event...
203d [17/Jun/2017:04:57:29 +0000] select_timeout: JobHistoryUpdate=0
204D [17/Jun/2017:04:57:29 +0000] [Client 1] con->http=0x2006b420
205D [17/Jun/2017:04:57:29 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=165, file=11
206d [17/Jun/2017:04:57:29 +0000] cupsdAddSelect(fd=11, read_cb=0x14a20, write_cb=0, data=0x200c6360)
207D [17/Jun/2017:04:57:29 +0000] [Client 1] Waiting for CGI data.
208D [17/Jun/2017:04:57:29 +0000] [Client 1] Sending 0-length chunk.
209D [17/Jun/2017:04:57:29 +0000] [Client 1] Flushing write buffer.
210D [17/Jun/2017:04:57:29 +0000] [Client 1] New state is HTTP_STATE_WAITING
211d [17/Jun/2017:04:57:29 +0000] cupsdAddSelect(fd=9, read_cb=0x19630, write_cb=0, data=0x200c6360)
212D [17/Jun/2017:04:57:29 +0000] [Client 1] Waiting for request.
213d [17/Jun/2017:04:57:29 +0000] cupsdRemoveSelect(fd=11)
214d [17/Jun/2017:04:57:29 +0000] cupsdEndProcess(pid=165, force=0)
215D [17/Jun/2017:04:57:29 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
216d [17/Jun/2017:04:57:29 +0000] [Job 1] Printer found with device ID: MFG:Canon;CMD:BJL,BJRaster3,BSCCe,NCCe,IVEC,IVECPLI;SOJ:BJNP2,BJNPe;MDL:MG7500 series;CLS:PRINTER;DES:Canon MG7500 series;VER:4.000;STA:10;FSI:03;HRI:D;MSI:AOFF,B240,DAT,E3,H2FSF,JOFF,K21000700;PDR:4;PSE:ADPJ03574;CID:CA_IVEC1TYPE4_IJP; Device URI: usb://Canon/MG7500%20series?serial=117758&interface=1
217D [17/Jun/2017:04:57:29 +0000] [Job 1] Device protocol: 2
218I [17/Jun/2017:04:57:29 +0000] [Job 1] Sending data to printer.
219D [17/Jun/2017:04:57:29 +0000] cupsdMarkDirty(---J-)
220D [17/Jun/2017:04:57:29 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
221D [17/Jun/2017:04:57:29 +0000] [Job 1] Set job-printer-state-message to "Sending data to printer.", current level=INFO
222D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: CUPS option count is 6 (196 bytes)
223D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: CUPS option 0 date-time-at-creation =
224D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: CUPS option 1 date-time-at-processing =
225D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: CUPS option 2 job-originating-host-name = localhost
226D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: CUPS option 3 job-uuid = urn:uuid:231cee24-d2c9-3333-56fb-535c6269c2d0
227D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: CUPS option 4 time-at-creation = 1497671848
228D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: CUPS option 5 time-at-processing = 1497671848
229D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Driver Canon MG7500 series
230D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Using fd 0
231D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set options:
232D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set string DitherAlgorithm to None
233D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set special string DitherAlgorithm to None
234D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting PageSize to (null)
235D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting MediaType to (null)
236D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting InputSlot to (null)
237D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set string CassetteTray to Default
238D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set special string CassetteTray to Default
239D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set string CDInnerRadius to None
240D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set special string CDInnerRadius to None
241D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set dimension CDOuterDiameter to 329 (329)
242D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set dimension CDInnerDiameter to 121 (121)
243D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set dimension CDXAdjustment to 0 (0)
244D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set dimension CDYAdjustment to 0 (0)
245D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting Resolution to (null)
246D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set string InkType to CMYK
247D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set special string InkType to CMYK
248D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting InkChannels to (null)
249D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting PrintingMode to (null)
250d [17/Jun/2017:04:57:29 +0000] cupsdAddEvent(event=job-progress, dest=0x2005ebe0(CanonMG7500), job=0x200c5720(1), text="%s", ...)
251D [17/Jun/2017:04:57:29 +0000] Discarding unused job-progress event...
252d [17/Jun/2017:04:57:29 +0000] cupsdAddEvent(event=printer-state-changed, dest=0x2005ebe0(CanonMG7500), job=0(0), text="Printer \"%s\" state changed.", ...)
253D [17/Jun/2017:04:57:29 +0000] Discarding unused printer-state-changed event...
254d [17/Jun/2017:04:57:29 +0000] select_timeout: JobHistoryUpdate=0
255D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set string InkSet to None
256D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set special string InkSet to None
257D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set bool FullBleed to False (0)
258D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting Duplex to (null)
259D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set string Quality to Standard
260D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set special string Quality to Standard
261D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set string ColorCorrection to None
262D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set special string ColorCorrection to None
263D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting ChannelBitDepth to (null)
264D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting InputImageType to (null)
265D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting STPIOutputType to (null)
266D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting STPIRawChannels to (null)
267D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting SimpleGamma to (null)
268D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set bool LinearContrast to False (0)
269D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting LUTDumpFile to (null)
270D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CyanCurve to (null)
271D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting MagentaCurve to (null)
272D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting YellowCurve to (null)
273D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting BlackCurve to (null)
274D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting RedCurve to (null)
275D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting GreenCurve to (null)
276D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting BlueCurve to (null)
277D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting WhiteCurve to (null)
278D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting HueMap to (null)
279D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting SatMap to (null)
280D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting LumMap to (null)
281D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting GCRCurve to (null)
282D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh0 to (null)
283D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh1 to (null)
284D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh2 to (null)
285D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh3 to (null)
286D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh4 to (null)
287D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh5 to (null)
288D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh6 to (null)
289D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh7 to (null)
290D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh8 to (null)
291d [17/Jun/2017:04:57:29 +0000] select_timeout: JobHistoryUpdate=0
292D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh9 to (null)
293D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh10 to (null)
294D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh11 to (null)
295D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh12 to (null)
296D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh13 to (null)
297D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh14 to (null)
298D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh15 to (null)
299D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh16 to (null)
300D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh17 to (null)
301D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh18 to (null)
302D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh19 to (null)
303D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh20 to (null)
304D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh21 to (null)
305D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh22 to (null)
306D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh23 to (null)
307D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh24 to (null)
308I [17/Jun/2017:04:57:29 +0000] [Job 1] Start rendering...
309D [17/Jun/2017:04:57:29 +0000] cupsdMarkDirty(---J-)
310D [17/Jun/2017:04:57:29 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
311D [17/Jun/2017:04:57:29 +0000] [Job 1] Set job-printer-state-message to "Start rendering...", current level=INFO
312I [17/Jun/2017:04:57:29 +0000] [Job 1] Processing page 1...
313D [17/Jun/2017:04:57:29 +0000] cupsdMarkDirty(---J-)
314D [17/Jun/2017:04:57:29 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
315D [17/Jun/2017:04:57:29 +0000] [Job 1] Set job-printer-state-message to "Processing page 1...", current level=INFO
316D [17/Jun/2017:04:57:29 +0000] [Job 1] Read 512 bytes of back-channel data...
317D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh25 to (null)
318D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh26 to (null)
319D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh27 to (null)
320D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh28 to (null)
321D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh29 to (null)
322D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh30 to (null)
323D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh31 to (null)
324D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh32 to (null)
325D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh33 to (null)
326D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh34 to (null)
327D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh35 to (null)
328D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh36 to (null)
329D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh37 to (null)
330D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh38 to (null)
331D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh39 to (null)
332D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh40 to (null)
333D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh41 to (null)
334D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh42 to (null)
335D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh43 to (null)
336d [17/Jun/2017:04:57:29 +0000] cupsdAddEvent(event=job-progress, dest=0x2005ebe0(CanonMG7500), job=0x200c5720(1), text="%s", ...)
337D [17/Jun/2017:04:57:29 +0000] Discarding unused job-progress event...
338d [17/Jun/2017:04:57:29 +0000] cupsdAddEvent(event=printer-state-changed, dest=0x2005ebe0(CanonMG7500), job=0(0), text="Printer \"%s\" state changed.", ...)
339D [17/Jun/2017:04:57:29 +0000] Discarding unused printer-state-changed event...
340d [17/Jun/2017:04:57:29 +0000] select_timeout: JobHistoryUpdate=0
341D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh44 to (null)
342D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh45 to (null)
343D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh46 to (null)
344D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh47 to (null)
345D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh48 to (null)
346D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh49 to (null)
347D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh50 to (null)
348D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh51 to (null)
349D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh52 to (null)
350D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh53 to (null)
351D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh54 to (null)
352D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh55 to (null)
353D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh56 to (null)
354D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh57 to (null)
355D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh58 to (null)
356D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh59 to (null)
357D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh60 to (null)
358D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh61 to (null)
359D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh62 to (null)
360D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting CurveCh63 to (null)
361D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set string ImageType to TextGraphics
362D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Set special string ImageType to TextGraphics
363D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting JobMode to (null)
364D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: Not setting PageNumber to (null)
365D [17/Jun/2017:04:57:29 +0000] [Job 1] Gutenprint: End options
366D [17/Jun/2017:04:57:29 +0000] [Job 1] Read 512 bytes of back-channel data...
367d [17/Jun/2017:04:57:29 +0000] select_timeout: JobHistoryUpdate=0
368D [17/Jun/2017:04:57:29 +0000] [Job 1] Read 378 bytes of back-channel data...
369d [17/Jun/2017:04:57:29 +0000] select_timeout: JobHistoryUpdate=0
370D [17/Jun/2017:04:57:29 +0000] [Job 1] Read 512 bytes of back-channel data...
371d [17/Jun/2017:04:57:29 +0000] select_timeout: JobHistoryUpdate=0
372D [17/Jun/2017:04:57:29 +0000] [Job 1] Read 512 bytes of back-channel data...
373d [17/Jun/2017:04:57:29 +0000] select_timeout: JobHistoryUpdate=0
374D [17/Jun/2017:04:57:29 +0000] [Job 1] Read 378 bytes of back-channel data...
375d [17/Jun/2017:04:57:29 +0000] select_timeout: JobHistoryUpdate=0
376D [17/Jun/2017:04:57:29 +0000] [Job 1] Read 512 bytes of back-channel data...
377d [17/Jun/2017:04:57:29 +0000] select_timeout: JobHistoryUpdate=0
378D [17/Jun/2017:04:57:29 +0000] [Job 1] Read 512 bytes of back-channel data...
379d [17/Jun/2017:04:57:29 +0000] select_timeout: JobHistoryUpdate=0
380D [17/Jun/2017:04:57:29 +0000] [Job 1] bww read bytes: 8192 write bytes 8192
381d [17/Jun/2017:04:57:29 +0000] select_timeout: JobHistoryUpdate=0
382D [17/Jun/2017:04:57:29 +0000] [Job 1] bww read bytes: 8192 write bytes 8192
383d [17/Jun/2017:04:57:29 +0000] select_timeout: JobHistoryUpdate=0
384D [17/Jun/2017:04:57:29 +0000] [Job 1] bww read bytes: 8192 write bytes 8192
385d [17/Jun/2017:04:57:29 +0000] select_timeout: JobHistoryUpdate=0
386D [17/Jun/2017:04:57:29 +0000] [Job 1] bww read bytes: 7215 write bytes 7215
387D [17/Jun/2017:04:57:29 +0000] [Job 1] bww before waitpid
388d [17/Jun/2017:04:57:29 +0000] select_timeout: JobHistoryUpdate=0
389D [17/Jun/2017:04:57:30 +0000] [Job 1] bww before wstatus
390D [17/Jun/2017:04:57:30 +0000] [Job 1] bww status: 2816
391d [17/Jun/2017:04:57:30 +0000] select_timeout: JobHistoryUpdate=0
392D [17/Jun/2017:04:57:30 +0000] [Job 1] Gutenprint: About to start printing loop.
393D [17/Jun/2017:04:57:30 +0000] [Job 1] Gutenprint: stats 0B, 1.360u, 0.000s, 1.349el
394D [17/Jun/2017:04:57:30 +0000] [Job 1] Gutenprint: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
395D [17/Jun/2017:04:57:30 +0000] [Job 1] Gutenprint: ============================================================
396d [17/Jun/2017:04:57:30 +0000] select_timeout: JobHistoryUpdate=0
397D [17/Jun/2017:04:57:30 +0000] [Job 1] Sent 0 bytes...
398D [17/Jun/2017:04:57:30 +0000] [Job 1] Waiting for read thread to exit...
399d [17/Jun/2017:04:57:30 +0000] process_children()
400d [17/Jun/2017:04:57:30 +0000] cupsdFinishProcess(pid=171, name=0x17f700, namelen=1024, job_id=0x17f6fc(1)) = "/@unixroot/usr/lib/cups/filter/gstoraster.exe"
401D [17/Jun/2017:04:57:30 +0000] [Job 1] PID 171 (/@unixroot/usr/lib/cups/filter/gstoraster.exe) stopped with status 1.
402d [17/Jun/2017:04:57:30 +0000] select_timeout: JobHistoryUpdate=0
403D [17/Jun/2017:04:57:30 +0000] [Job 1] Read 378 bytes of back-channel data...
404d [17/Jun/2017:04:57:30 +0000] process_children()
405d [17/Jun/2017:04:57:30 +0000] cupsdFinishProcess(pid=173, name=0x17f700, namelen=1024, job_id=0x17f6fc(1)) = "/@unixroot/usr/lib/cups/filter/rastertogutenprint.5.2.exe"
406D [17/Jun/2017:04:57:30 +0000] [Job 1] PID 173 (/@unixroot/usr/lib/cups/filter/rastertogutenprint.5.2.exe) exited with no errors.
407d [17/Jun/2017:04:57:30 +0000] select_timeout: JobHistoryUpdate=0
408d [17/Jun/2017:04:57:30 +0000] process_children()