I [08/Dec/2017:11:20:41 +0500] Listening to 127.0.0.1:631 (IPv4) I [08/Dec/2017:11:20:41 +0500] Listening to localhost (Domain) I [08/Dec/2017:11:20:41 +0500] Remote access is disabled. D [08/Dec/2017:11:20:41 +0500] Added auto ServerAlias bluelion I [08/Dec/2017:11:20:41 +0500] Loaded configuration file "/@unixroot/etc/cups/cupsd.conf" I [08/Dec/2017:11:20:41 +0500] Configured for up to 100 clients. I [08/Dec/2017:11:20:41 +0500] Allowing up to 100 client connections per host. I [08/Dec/2017:11:20:41 +0500] Using policy "default" as the default. I [08/Dec/2017:11:20:41 +0500] Full reload is required. I [08/Dec/2017:11:20:41 +0500] Loaded MIME database from "/@unixroot/usr/share/cups/mime" and "/@unixroot/etc/cups": 45 types, 53 filters... D [08/Dec/2017:11:20:41 +0500] Loading printer HP_Color_LaserJet_3800... D [08/Dec/2017:11:20:41 +0500] load_ppd: Loading /@unixroot/var/cache/cups/HP_Color_LaserJet_3800.data... D [08/Dec/2017:11:20:41 +0500] cupsdRegisterPrinter(p=0x2005f4a0(HP_Color_LaserJet_3800)) D [08/Dec/2017:11:20:41 +0500] Loading printer HP_Color_Laserjet_3800_hpijs... D [08/Dec/2017:11:20:41 +0500] load_ppd: Loading /@unixroot/var/cache/cups/HP_Color_Laserjet_3800_hpijs.data... D [08/Dec/2017:11:20:41 +0500] cupsdRegisterPrinter(p=0x20062940(HP_Color_Laserjet_3800_hpijs)) D [08/Dec/2017:11:20:41 +0500] load_ppd: Loading /@unixroot/var/cache/cups/HP_Color_Laserjet_3800_hpijs.data... D [08/Dec/2017:11:20:41 +0500] cupsdRegisterPrinter(p=0x20062940(HP_Color_Laserjet_3800_hpijs)) D [08/Dec/2017:11:20:41 +0500] Loading printer HP_Color_LaserJet_3800_PS... D [08/Dec/2017:11:20:41 +0500] load_ppd: Loading /@unixroot/var/cache/cups/HP_Color_LaserJet_3800_PS.data... D [08/Dec/2017:11:20:41 +0500] cupsdRegisterPrinter(p=0x200e0380(HP_Color_LaserJet_3800_PS)) D [08/Dec/2017:11:20:41 +0500] load_ppd: Loading /@unixroot/var/cache/cups/HP_Color_LaserJet_3800_PS.data... D [08/Dec/2017:11:20:41 +0500] cupsdRegisterPrinter(p=0x200e0380(HP_Color_LaserJet_3800_PS)) I [08/Dec/2017:11:20:41 +0500] Loading job cache file "/@unixroot/var/cache/cups/job.cache"... D [08/Dec/2017:11:20:41 +0500] [Job 7] Loading from cache... D [08/Dec/2017:11:20:41 +0500] [Job 8] Loading from cache... D [08/Dec/2017:11:20:41 +0500] [Job 8] Loading attributes... I [08/Dec/2017:11:20:41 +0500] Full reload complete. D [08/Dec/2017:11:20:41 +0500] cupsdCleanFiles(path="/@unixroot/var/cache/cups", pattern="*.ipp") I [08/Dec/2017:11:20:41 +0500] Cleaning out old files in "/@unixroot/var/cache/cups". I [08/Dec/2017:11:20:41 +0500] Listening to 127.0.0.1:631 on fd 7... I [08/Dec/2017:11:20:41 +0500] Listening to localhost on fd 11... I [08/Dec/2017:11:20:41 +0500] Resuming new connection processing... D [08/Dec/2017:11:20:41 +0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [08/Dec/2017:11:20:41 +0500] cupsdAddCert: Adding certificate for PID 0 D [08/Dec/2017:11:20:41 +0500] Discarding unused server-started event... D [08/Dec/2017:11:20:42 +0500] Report: clients=0 D [08/Dec/2017:11:20:42 +0500] Report: jobs=2 D [08/Dec/2017:11:20:42 +0500] Report: jobs-active=1 D [08/Dec/2017:11:20:42 +0500] Report: printers=3 D [08/Dec/2017:11:20:42 +0500] Report: stringpool-string-count=20690 D [08/Dec/2017:11:20:42 +0500] Report: stringpool-alloc-bytes=12216 D [08/Dec/2017:11:20:42 +0500] Report: stringpool-total-bytes=386336 D [08/Dec/2017:11:22:26 +0500] [Client 1] Accepted from localhost:56468 (IPv4) D [08/Dec/2017:11:22:26 +0500] [Client 1] Waiting for request. D [08/Dec/2017:11:22:26 +0500] [Job 8] Unloading... D [08/Dec/2017:11:22:26 +0500] Report: clients=1 D [08/Dec/2017:11:22:26 +0500] Report: jobs=2 D [08/Dec/2017:11:22:26 +0500] Report: jobs-active=1 D [08/Dec/2017:11:22:26 +0500] Report: printers=3 D [08/Dec/2017:11:22:26 +0500] Report: stringpool-string-count=20647 D [08/Dec/2017:11:22:26 +0500] Report: stringpool-alloc-bytes=11048 D [08/Dec/2017:11:22:26 +0500] Report: stringpool-total-bytes=385352 D [08/Dec/2017:11:22:31 +0500] [Client 1] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [08/Dec/2017:11:22:31 +0500] [Client 1] Closing connection. D [08/Dec/2017:11:22:31 +0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [08/Dec/2017:11:23:22 +0500] [Client 2] Accepted from localhost:56471 (IPv4) D [08/Dec/2017:11:23:22 +0500] [Client 2] Waiting for request. D [08/Dec/2017:11:23:27 +0500] [Client 2] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [08/Dec/2017:11:23:27 +0500] [Client 2] Closing connection. D [08/Dec/2017:11:23:27 +0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [08/Dec/2017:11:23:27 +0500] Report: clients=0 D [08/Dec/2017:11:23:27 +0500] Report: jobs=2 D [08/Dec/2017:11:23:27 +0500] Report: jobs-active=1 D [08/Dec/2017:11:23:27 +0500] Report: printers=3 D [08/Dec/2017:11:23:27 +0500] Report: stringpool-string-count=20647 D [08/Dec/2017:11:23:27 +0500] Report: stringpool-alloc-bytes=11048 D [08/Dec/2017:11:23:27 +0500] Report: stringpool-total-bytes=385352 D [08/Dec/2017:11:23:29 +0500] [Client 3] Accepted from localhost:56472 (IPv4) D [08/Dec/2017:11:23:29 +0500] [Client 3] Waiting for request. D [08/Dec/2017:11:23:31 +0500] [Client 3] GET /printers/ HTTP/1.1 D [08/Dec/2017:11:23:31 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [08/Dec/2017:11:23:31 +0500] [Client 3] Read: status=200 D [08/Dec/2017:11:23:31 +0500] [Client 3] No authentication data provided. D [08/Dec/2017:11:23:31 +0500] [Client 3] Processing GET /printers/ D [08/Dec/2017:11:23:31 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[8] = "HOME=C:\\var\\temp" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[9] = "NLSPATH=C:\\MPTN\\MSG\\NLS\\%N;C:\\TCPIP\\msg\\ENUS850\\%N;" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@bluelion" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[13] = "TMPDIR=C:\\var\\temp" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[15] = "USER=root" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[16] = "UNIXROOT=C:" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[17] = "ETC=C:\\MPTN\\ETC" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[18] = "CUPS_MAX_MESSAGE=2047" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[19] = "CUPS_SERVER=\\socket\\cups.sock" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[20] = "CUPS_ENCRYPTION=IfRequested" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[21] = "IPP_PORT=631" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[22] = "LANG=en_US.UTF8" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[23] = "REDIRECT_STATUS=1" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[24] = "GATEWAY_INTERFACE=CGI/1.1" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[25] = "SERVER_NAME=localhost" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[26] = "SERVER_PORT=631" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[27] = "REMOTE_ADDR=127.0.0.1" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[28] = "REMOTE_HOST=localhost" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[29] = "SCRIPT_NAME=/printers/" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[30] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/printers/" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[31] = "SERVER_PROTOCOL=HTTP/1.1" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[32] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:45.0) Gecko/20100101 Firefox/45.0" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[33] = "HTTP_REFERER=http://localhost:631/" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[34] = "REQUEST_METHOD=GET" D [08/Dec/2017:11:23:31 +0500] [CGI] envp[35] = "QUERY_STRING=" D [08/Dec/2017:11:23:32 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/printers.cgi (PID 75) I [08/Dec/2017:11:23:32 +0500] [Client 3] Started "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" (pid=75, file=15) D [08/Dec/2017:11:23:32 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:32 +0500] [CGI] org.cups.sid cookie not found, initializing! D [08/Dec/2017:11:23:32 +0500] [CGI] org.cups.sid cookie is \"41f45aede00677dfbe5c986711944887\" D [08/Dec/2017:11:23:32 +0500] [CGI] cgiSetVariable: SECTION=\"printers\" D [08/Dec/2017:11:23:32 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [08/Dec/2017:11:23:32 +0500] [Client 4] Accepted from localhost (Domain) D [08/Dec/2017:11:23:32 +0500] [Client 4] Waiting for request. D [08/Dec/2017:11:23:32 +0500] [Client 4] POST / HTTP/1.1 D [08/Dec/2017:11:23:32 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [08/Dec/2017:11:23:32 +0500] [Client 4] Read: status=200 D [08/Dec/2017:11:23:32 +0500] [Client 4] No authentication data provided. D [08/Dec/2017:11:23:32 +0500] [Client 4] Read: status=100 D [08/Dec/2017:11:23:32 +0500] [Client 4] Read: status=100 D [08/Dec/2017:11:23:32 +0500] [Client 4] Read: status=100 D [08/Dec/2017:11:23:32 +0500] [Client 4] Read: status=100 D [08/Dec/2017:11:23:32 +0500] [Client 4] Read: status=100 D [08/Dec/2017:11:23:32 +0500] [Client 4] Read: status=100 D [08/Dec/2017:11:23:32 +0500] [Client 4] 2.0 CUPS-Get-Default 1 D [08/Dec/2017:11:23:33 +0500] CUPS-Get-Default D [08/Dec/2017:11:23:33 +0500] CUPS-Get-Default client-error-not-found: No default printer. D [08/Dec/2017:11:23:33 +0500] [Client 4] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost D [08/Dec/2017:11:23:33 +0500] [Client 4] Content-Length: 113 D [08/Dec/2017:11:23:33 +0500] [Client 4] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [08/Dec/2017:11:23:33 +0500] [Client 4] con->http=0x201fb220 D [08/Dec/2017:11:23:33 +0500] [Client 4] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x2011e900(IPP_STATE_DATA), pipe_pid=0, file=-1 D [08/Dec/2017:11:23:33 +0500] [Client 4] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [08/Dec/2017:11:23:33 +0500] [Client 4] bytes=0, http_state=0, data_remaining=113 D [08/Dec/2017:11:23:33 +0500] [Client 4] Flushing write buffer. D [08/Dec/2017:11:23:33 +0500] [Client 4] New state is HTTP_STATE_WAITING D [08/Dec/2017:11:23:33 +0500] [Client 4] Waiting for request. D [08/Dec/2017:11:23:33 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [08/Dec/2017:11:23:33 +0500] [CGI] show_all_printers(http=0x2003c360, user=\"(null)\") D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetVariable: TITLE=\"Printers\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\" D [08/Dec/2017:11:23:33 +0500] [Client 3] CGI data ready to be sent. D [08/Dec/2017:11:23:33 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:33 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=75, file=15 D [08/Dec/2017:11:23:33 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:33 +0500] [Client 3] Script header: Set-Cookie: org.cups.sid=41f45aede00677dfbe5c986711944887; path=/; httponly; D [08/Dec/2017:11:23:33 +0500] [Client 3] Script header: Content-Type: text/html;charset=utf-8 D [08/Dec/2017:11:23:33 +0500] [Client 3] Script header: D [08/Dec/2017:11:23:33 +0500] [Client 3] Sending status 200 for CGI. D [08/Dec/2017:11:23:33 +0500] [Client 3] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [08/Dec/2017:11:23:33 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:33 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=75, file=15 D [08/Dec/2017:11:23:33 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:33 +0500] [Client 3] CGI data ready to be sent. D [08/Dec/2017:11:23:33 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:33 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=75, file=15 D [08/Dec/2017:11:23:33 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:33 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:33 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=75, file=15 D [08/Dec/2017:11:23:33 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:33 +0500] [Client 3] CGI data ready to be sent. D [08/Dec/2017:11:23:33 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:33 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=75, file=15 D [08/Dec/2017:11:23:33 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:33 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:33 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=75, file=15 D [08/Dec/2017:11:23:33 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:33 +0500] [Client 4] POST / HTTP/1.1 D [08/Dec/2017:11:23:33 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [08/Dec/2017:11:23:33 +0500] [Client 4] Read: status=200 D [08/Dec/2017:11:23:33 +0500] [Client 4] No authentication data provided. D [08/Dec/2017:11:23:33 +0500] [Client 4] 2.0 CUPS-Get-Printers 2 D [08/Dec/2017:11:23:33 +0500] CUPS-Get-Printers D [08/Dec/2017:11:23:33 +0500] [Client 4] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [08/Dec/2017:11:23:33 +0500] [Client 4] Content-Length: 1006 D [08/Dec/2017:11:23:33 +0500] [Client 4] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [08/Dec/2017:11:23:33 +0500] [Client 4] con->http=0x201fb220 D [08/Dec/2017:11:23:33 +0500] [Client 4] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1006, response=0x2011ebe0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [08/Dec/2017:11:23:33 +0500] [Client 4] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [08/Dec/2017:11:23:33 +0500] [Client 4] bytes=0, http_state=0, data_remaining=1006 D [08/Dec/2017:11:23:33 +0500] [Client 4] Flushing write buffer. D [08/Dec/2017:11:23:33 +0500] [Client 4] New state is HTTP_STATE_WAITING D [08/Dec/2017:11:23:33 +0500] [Client 4] Waiting for request. D [08/Dec/2017:11:23:33 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetVariable: TOTAL=\"3\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_state[0]=\"3\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_state_message[0]=\"\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/HP_Color_LaserJet_3800\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_name[0]=\"HP_Color_LaserJet_3800\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_location[0]=\"office\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_info[0]=\"HP Color LaserJet 3800 pcl3\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_make_and_model[0]=\"HP Color LaserJet 3800 pcl3, hpcups 3.16.11\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_state[1]=\"3\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_state_message[1]=\"\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: admin_uri[1]=\"/admin/\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_uri_supported[1]=\"/printers/HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_name[1]=\"HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_location[1]=\"office\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_info[1]=\"HP Color LaserJet 3800 hpijs pcl3\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_make_and_model[1]=\"HP Color LaserJet 3800 hpijs pcl3, 3.16.11\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_state[2]=\"3\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_state_message[2]=\"\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: admin_uri[2]=\"/admin/\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_uri_supported[2]=\"/printers/HP_Color_LaserJet_3800_PS\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_name[2]=\"HP_Color_LaserJet_3800_PS\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_location[2]=\"office\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_info[2]=\"HP Color LaserJet 3800 PS\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetArray: printer_make_and_model[2]=\"HP Color LaserJet 3800 Postscript (recommended)\" D [08/Dec/2017:11:23:33 +0500] [CGI] cgiSetVariable: THISURL=\"/printers/\" D [08/Dec/2017:11:23:33 +0500] [Client 3] CGI data ready to be sent. D [08/Dec/2017:11:23:33 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:33 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=75, file=15 D [08/Dec/2017:11:23:33 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:33 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:33 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=75, file=15 D [08/Dec/2017:11:23:33 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:33 +0500] [Client 3] CGI data ready to be sent. D [08/Dec/2017:11:23:33 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:33 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=75, file=15 D [08/Dec/2017:11:23:33 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:33 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:33 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=75, file=15 D [08/Dec/2017:11:23:33 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:33 +0500] [Client 3] CGI data ready to be sent. D [08/Dec/2017:11:23:33 +0500] [Client 4] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [08/Dec/2017:11:23:33 +0500] [Client 4] Closing connection. D [08/Dec/2017:11:23:33 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [08/Dec/2017:11:23:33 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:33 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=75, file=15 D [08/Dec/2017:11:23:33 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:33 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:33 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=75, file=15 D [08/Dec/2017:11:23:33 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:33 +0500] [Client 3] CGI data ready to be sent. D [08/Dec/2017:11:23:33 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:33 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=75, file=15 D [08/Dec/2017:11:23:33 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:33 +0500] [Client 3] Sending 0-length chunk. D [08/Dec/2017:11:23:33 +0500] [Client 3] Flushing write buffer. D [08/Dec/2017:11:23:33 +0500] [Client 3] New state is HTTP_STATE_WAITING D [08/Dec/2017:11:23:33 +0500] [Client 3] Waiting for request. D [08/Dec/2017:11:23:33 +0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [08/Dec/2017:11:23:33 +0500] PID 75 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [08/Dec/2017:11:23:43 +0500] [Client 3] GET /printers/HP_Color_Laserjet_3800_hpijs HTTP/1.1 D [08/Dec/2017:11:23:43 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [08/Dec/2017:11:23:43 +0500] [Client 3] Read: status=200 D [08/Dec/2017:11:23:43 +0500] [Client 3] No authentication data provided. D [08/Dec/2017:11:23:43 +0500] [Client 3] Processing GET /printers/HP_Color_Laserjet_3800_hpijs D [08/Dec/2017:11:23:43 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[8] = "HOME=C:\\var\\temp" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[9] = "NLSPATH=C:\\MPTN\\MSG\\NLS\\%N;C:\\TCPIP\\msg\\ENUS850\\%N;" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@bluelion" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[13] = "TMPDIR=C:\\var\\temp" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[15] = "USER=root" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[16] = "UNIXROOT=C:" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[17] = "ETC=C:\\MPTN\\ETC" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[18] = "CUPS_MAX_MESSAGE=2047" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[19] = "CUPS_SERVER=\\socket\\cups.sock" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[20] = "CUPS_ENCRYPTION=IfRequested" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[21] = "IPP_PORT=631" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[22] = "LANG=en_US.UTF8" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[23] = "REDIRECT_STATUS=1" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[24] = "GATEWAY_INTERFACE=CGI/1.1" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[25] = "SERVER_NAME=localhost" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[26] = "SERVER_PORT=631" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[27] = "REMOTE_ADDR=127.0.0.1" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[28] = "REMOTE_HOST=localhost" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[29] = "SCRIPT_NAME=/printers/HP_Color_Laserjet_3800_hpijs" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[30] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/printers/HP_Color_Laserjet_3800_hpijs" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[31] = "PATH_INFO=/HP_Color_Laserjet_3800_hpijs" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[32] = "SERVER_PROTOCOL=HTTP/1.1" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[33] = "HTTP_COOKIE=org.cups.sid=41f45aede00677dfbe5c986711944887" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[34] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:45.0) Gecko/20100101 Firefox/45.0" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[35] = "HTTP_REFERER=http://localhost:631/printers/" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[36] = "REQUEST_METHOD=GET" D [08/Dec/2017:11:23:43 +0500] [CGI] envp[37] = "QUERY_STRING=" D [08/Dec/2017:11:23:43 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/printers.cgi (PID 77) I [08/Dec/2017:11:23:43 +0500] [Client 3] Started "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" (pid=77, file=15) D [08/Dec/2017:11:23:43 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:43 +0500] [Client 5] Accepted from localhost (Domain) D [08/Dec/2017:11:23:43 +0500] [Client 5] Waiting for request. D [08/Dec/2017:11:23:43 +0500] [CGI] org.cups.sid cookie is \"41f45aede00677dfbe5c986711944887\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetVariable: SECTION=\"printers\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:23:43 +0500] [Client 5] POST / HTTP/1.1 D [08/Dec/2017:11:23:43 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [08/Dec/2017:11:23:43 +0500] [Client 5] Read: status=200 D [08/Dec/2017:11:23:43 +0500] [Client 5] No authentication data provided. D [08/Dec/2017:11:23:43 +0500] [Client 5] 2.0 CUPS-Get-Default 1 D [08/Dec/2017:11:23:43 +0500] CUPS-Get-Default D [08/Dec/2017:11:23:43 +0500] CUPS-Get-Default client-error-not-found: No default printer. D [08/Dec/2017:11:23:43 +0500] [Client 5] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost D [08/Dec/2017:11:23:43 +0500] [Client 5] Content-Length: 113 D [08/Dec/2017:11:23:43 +0500] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [08/Dec/2017:11:23:43 +0500] [Client 5] con->http=0x201fb220 D [08/Dec/2017:11:23:43 +0500] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x2011e980(IPP_STATE_DATA), pipe_pid=0, file=-1 D [08/Dec/2017:11:23:43 +0500] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [08/Dec/2017:11:23:43 +0500] [Client 5] bytes=0, http_state=0, data_remaining=113 D [08/Dec/2017:11:23:43 +0500] [Client 5] Flushing write buffer. D [08/Dec/2017:11:23:43 +0500] [Client 5] New state is HTTP_STATE_WAITING D [08/Dec/2017:11:23:43 +0500] [Client 5] Waiting for request. D [08/Dec/2017:11:23:43 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [08/Dec/2017:11:23:43 +0500] [CGI] show_printer(http=0x2003c3c0, printer=\"HP_Color_Laserjet_3800_hpijs\") D [08/Dec/2017:11:23:43 +0500] [Client 5] POST / HTTP/1.1 D [08/Dec/2017:11:23:43 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [08/Dec/2017:11:23:43 +0500] [Client 5] Read: status=200 D [08/Dec/2017:11:23:43 +0500] [Client 5] No authentication data provided. D [08/Dec/2017:11:23:43 +0500] [Client 5] 2.0 Get-Printer-Attributes 2 D [08/Dec/2017:11:23:43 +0500] Get-Printer-Attributes ipp://localhost/printers/HP_Color_Laserjet_3800_hpijs D [08/Dec/2017:11:23:43 +0500] [Client 5] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_Color_Laserjet_3800_hpijs) from localhost D [08/Dec/2017:11:23:43 +0500] [Client 5] Content-Length: 756 D [08/Dec/2017:11:23:43 +0500] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [08/Dec/2017:11:23:43 +0500] [Client 5] con->http=0x201fb220 D [08/Dec/2017:11:23:43 +0500] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=756, response=0x2011e9e0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [08/Dec/2017:11:23:43 +0500] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [08/Dec/2017:11:23:43 +0500] [Client 5] bytes=0, http_state=0, data_remaining=756 D [08/Dec/2017:11:23:43 +0500] [Client 5] Flushing write buffer. D [08/Dec/2017:11:23:43 +0500] [Client 5] New state is HTTP_STATE_WAITING D [08/Dec/2017:11:23:43 +0500] [Client 5] Waiting for request. D [08/Dec/2017:11:23:43 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetArray: printer_is_shared[0]=\"1\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetArray: printer_state[0]=\"3\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetArray: printer_name[0]=\"HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetArray: printer_location[0]=\"office\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetArray: printer_info[0]=\"HP Color LaserJet 3800 hpijs pcl3\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetArray: device_uri[0]=\"socket://192.168.100.16\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetArray: color_supported[0]=\"1\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetArray: printer_make_and_model[0]=\"HP Color LaserJet 3800 hpijs pcl3, 3.16.11\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetArray: media_default[0]=\"na_letter_8.5x11in\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetArray: sides_supported[0]=\"one-sided, two-sided-long-edge, two-sided-short-edge\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetArray: sides_default[0]=\"one-sided\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetArray: printer_commands[0]=\"AutoConfigure, Clean, PrintSelfTestPage\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"0\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetVariable: TITLE=\"HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\" D [08/Dec/2017:11:23:43 +0500] [Client 3] CGI data ready to be sent. D [08/Dec/2017:11:23:43 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:43 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=77, file=15 D [08/Dec/2017:11:23:43 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:43 +0500] [Client 3] Script header: Content-Type: text/html;charset=utf-8 D [08/Dec/2017:11:23:43 +0500] [Client 3] Script header: D [08/Dec/2017:11:23:43 +0500] [Client 3] Sending status 200 for CGI. D [08/Dec/2017:11:23:43 +0500] [Client 3] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [08/Dec/2017:11:23:43 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:43 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=77, file=15 D [08/Dec/2017:11:23:43 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:43 +0500] [CGI] Regular expression \".*Clean.*\" D [08/Dec/2017:11:23:43 +0500] [Client 6] Accepted from localhost:56473 (IPv4) D [08/Dec/2017:11:23:43 +0500] [Client 6] Waiting for request. D [08/Dec/2017:11:23:43 +0500] [CGI] matches[0].rm_so=0 D [08/Dec/2017:11:23:43 +0500] [CGI] matches[1].rm_so=-1 D [08/Dec/2017:11:23:43 +0500] [CGI] Regular expression \".*PrintSelfTestPage.*\" D [08/Dec/2017:11:23:43 +0500] [CGI] matches[0].rm_so=0 D [08/Dec/2017:11:23:43 +0500] [CGI] matches[1].rm_so=-1 D [08/Dec/2017:11:23:43 +0500] [Client 3] CGI data ready to be sent. D [08/Dec/2017:11:23:43 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:43 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=77, file=15 D [08/Dec/2017:11:23:43 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:43 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:43 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=77, file=15 D [08/Dec/2017:11:23:43 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:43 +0500] [Client 3] CGI data ready to be sent. D [08/Dec/2017:11:23:43 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:43 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=77, file=15 D [08/Dec/2017:11:23:43 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:43 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:43 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=77, file=15 D [08/Dec/2017:11:23:43 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:43 +0500] [Client 5] POST / HTTP/1.1 D [08/Dec/2017:11:23:43 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [08/Dec/2017:11:23:43 +0500] [Client 5] Read: status=200 D [08/Dec/2017:11:23:43 +0500] [Client 5] No authentication data provided. D [08/Dec/2017:11:23:43 +0500] [Client 5] 2.0 Get-Jobs 3 D [08/Dec/2017:11:23:43 +0500] Get-Jobs ipp://localhost:631/printers/HP_Color_Laserjet_3800_hpijs D [08/Dec/2017:11:23:43 +0500] [Client 5] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HP_Color_Laserjet_3800_hpijs) from localhost D [08/Dec/2017:11:23:43 +0500] [Client 5] Content-Length: 75 D [08/Dec/2017:11:23:43 +0500] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [08/Dec/2017:11:23:43 +0500] [Client 5] con->http=0x201fb220 D [08/Dec/2017:11:23:43 +0500] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x2011eda0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [08/Dec/2017:11:23:43 +0500] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [08/Dec/2017:11:23:43 +0500] [Client 5] bytes=0, http_state=0, data_remaining=75 D [08/Dec/2017:11:23:43 +0500] [Client 5] Flushing write buffer. D [08/Dec/2017:11:23:43 +0500] [Client 5] New state is HTTP_STATE_WAITING D [08/Dec/2017:11:23:43 +0500] [Client 5] Waiting for request. D [08/Dec/2017:11:23:43 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiClearVariables called. D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetVariable: ORDER=\"asc\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetVariable: SECTION=\"printers\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetVariable: TOTAL=\"0\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetVariable: THISURL=\"/printers/HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:23:43 +0500] [CGI] cgiSetVariable: SEARCH_DEST=\"HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:23:43 +0500] [Client 3] CGI data ready to be sent. D [08/Dec/2017:11:23:43 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:43 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=77, file=15 D [08/Dec/2017:11:23:43 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:43 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:43 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=77, file=15 D [08/Dec/2017:11:23:43 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:43 +0500] [Client 3] CGI data ready to be sent. D [08/Dec/2017:11:23:43 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:43 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=77, file=15 D [08/Dec/2017:11:23:43 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:43 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:43 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=77, file=15 D [08/Dec/2017:11:23:43 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:43 +0500] [Client 3] CGI data ready to be sent. D [08/Dec/2017:11:23:43 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:43 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=77, file=15 D [08/Dec/2017:11:23:43 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:43 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:43 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=77, file=15 D [08/Dec/2017:11:23:43 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:43 +0500] [Client 3] CGI data ready to be sent. D [08/Dec/2017:11:23:43 +0500] [Client 5] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [08/Dec/2017:11:23:43 +0500] [Client 5] Closing connection. D [08/Dec/2017:11:23:43 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [08/Dec/2017:11:23:43 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:43 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=77, file=15 D [08/Dec/2017:11:23:43 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:43 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:43 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=77, file=15 D [08/Dec/2017:11:23:43 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:43 +0500] [Client 3] CGI data ready to be sent. D [08/Dec/2017:11:23:43 +0500] [Client 3] con->http=0x201f7040 D [08/Dec/2017:11:23:43 +0500] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=77, file=15 D [08/Dec/2017:11:23:43 +0500] [Client 3] Waiting for CGI data. D [08/Dec/2017:11:23:43 +0500] [Client 3] Sending 0-length chunk. D [08/Dec/2017:11:23:43 +0500] [Client 3] Flushing write buffer. D [08/Dec/2017:11:23:43 +0500] [Client 3] New state is HTTP_STATE_WAITING D [08/Dec/2017:11:23:43 +0500] [Client 3] Waiting for request. D [08/Dec/2017:11:23:43 +0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" W [08/Dec/2017:11:23:43 +0500] cupsdDoSelect: select() returned -1... pause and retry select() D [08/Dec/2017:11:23:45 +0500] PID 77 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [08/Dec/2017:11:23:48 +0500] [Client 6] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [08/Dec/2017:11:23:48 +0500] [Client 6] Closing connection. D [08/Dec/2017:11:23:48 +0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [08/Dec/2017:11:23:54 +0500] [Client 3] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [08/Dec/2017:11:23:54 +0500] [Client 3] Closing connection. D [08/Dec/2017:11:23:54 +0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [08/Dec/2017:11:23:58 +0500] [Client 7] Accepted from localhost:56474 (IPv4) D [08/Dec/2017:11:23:58 +0500] [Client 7] Waiting for request. D [08/Dec/2017:11:23:58 +0500] [Client 7] POST /printers/HP_Color_Laserjet_3800_hpijs HTTP/1.1 D [08/Dec/2017:11:23:58 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [08/Dec/2017:11:23:58 +0500] [Client 7] Read: status=200 D [08/Dec/2017:11:23:58 +0500] [Client 7] No authentication data provided. D [08/Dec/2017:11:23:59 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[8] = "HOME=C:\\var\\temp" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[9] = "NLSPATH=C:\\MPTN\\MSG\\NLS\\%N;C:\\TCPIP\\msg\\ENUS850\\%N;" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@bluelion" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[13] = "TMPDIR=C:\\var\\temp" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[15] = "USER=root" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[16] = "UNIXROOT=C:" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[17] = "ETC=C:\\MPTN\\ETC" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[18] = "CUPS_MAX_MESSAGE=2047" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[19] = "CUPS_SERVER=\\socket\\cups.sock" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[20] = "CUPS_ENCRYPTION=IfRequested" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[21] = "IPP_PORT=631" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[22] = "LANG=en_US.UTF8" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[23] = "REDIRECT_STATUS=1" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[24] = "GATEWAY_INTERFACE=CGI/1.1" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[25] = "SERVER_NAME=localhost" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[26] = "SERVER_PORT=631" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[27] = "REMOTE_ADDR=127.0.0.1" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[28] = "REMOTE_HOST=localhost" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[29] = "SCRIPT_NAME=/printers/HP_Color_Laserjet_3800_hpijs" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[30] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/printers/HP_Color_Laserjet_3800_hpijs" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[31] = "PATH_INFO=/HP_Color_Laserjet_3800_hpijs" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[32] = "SERVER_PROTOCOL=HTTP/1.1" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[33] = "HTTP_COOKIE=org.cups.sid=41f45aede00677dfbe5c986711944887" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[34] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:45.0) Gecko/20100101 Firefox/45.0" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[35] = "HTTP_REFERER=http://localhost:631/printers/HP_Color_Laserjet_3800_hpijs" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[36] = "REQUEST_METHOD=POST" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[37] = "CONTENT_LENGTH=64" D [08/Dec/2017:11:23:59 +0500] [CGI] envp[38] = "CONTENT_TYPE=application/x-www-form-urlencoded" D [08/Dec/2017:11:23:59 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/printers.cgi (PID 79) I [08/Dec/2017:11:23:59 +0500] [Client 7] Started "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" (pid=79, file=16) D [08/Dec/2017:11:23:59 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:23:59 +0500] [Client 8] Accepted from localhost (Domain) D [08/Dec/2017:11:23:59 +0500] [Client 8] Waiting for request. D [08/Dec/2017:11:23:59 +0500] [CGI] org.cups.sid cookie is \"41f45aede00677dfbe5c986711944887\" D [08/Dec/2017:11:23:59 +0500] [CGI] cgiSetVariable: org.cups.sid=\"41f45aede00677dfbe5c986711944887\" D [08/Dec/2017:11:23:59 +0500] [CGI] cgiSetVariable: OP=\"print-test-page\" D [08/Dec/2017:11:23:59 +0500] [CGI] cgiSetVariable: SECTION=\"printers\" D [08/Dec/2017:11:23:59 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [08/Dec/2017:11:23:59 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:23:59 +0500] [Client 8] POST /printers/HP_Color_Laserjet_3800_hpijs HTTP/1.1 D [08/Dec/2017:11:23:59 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [08/Dec/2017:11:23:59 +0500] [Client 8] Read: status=200 D [08/Dec/2017:11:23:59 +0500] [Client 8] No authentication data provided. D [08/Dec/2017:11:23:59 +0500] [Client 8] 2.0 Print-Job 1 D [08/Dec/2017:11:23:59 +0500] [Client 8] Read: status=100 D [08/Dec/2017:11:23:59 +0500] Print-Job ipp://localhost:631/printers/HP_Color_Laserjet_3800_hpijs D [08/Dec/2017:11:23:59 +0500] [Job ???] Auto-typing file... I [08/Dec/2017:11:23:59 +0500] [Job ???] Request file type is application/vnd.cups-pdf-banner. D [08/Dec/2017:11:23:59 +0500] cupsdMarkDirty(---J-) D [08/Dec/2017:11:23:59 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients" D [08/Dec/2017:11:23:59 +0500] Adding default job-sheets values "none,none"... I [08/Dec/2017:11:23:59 +0500] [Job 9] Adding start banner page "none". D [08/Dec/2017:11:23:59 +0500] Discarding unused job-created event... D [08/Dec/2017:11:23:59 +0500] cupsdMarkDirty(---J-) D [08/Dec/2017:11:23:59 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [08/Dec/2017:11:23:59 +0500] [Job 9] Adding end banner page "none". I [08/Dec/2017:11:23:59 +0500] [Job 9] File of type application/vnd.cups-pdf-banner queued by "anonymous". D [08/Dec/2017:11:23:59 +0500] [Job 9] hold_until=0 I [08/Dec/2017:11:23:59 +0500] [Job 9] Queued on "HP_Color_Laserjet_3800_hpijs" by "anonymous". D [08/Dec/2017:11:23:59 +0500] [Job 9] time-at-processing=1512750239 D [08/Dec/2017:11:23:59 +0500] cupsdMarkDirty(---J-) D [08/Dec/2017:11:23:59 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:23:59 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:23:59 +0500] Discarding unused printer-state-changed event... D [08/Dec/2017:11:23:59 +0500] [Job 9] 3 filters for job: D [08/Dec/2017:11:23:59 +0500] [Job 9] bannertopdf.exe (application/vnd.cups-pdf-banner to application/pdf, cost 32) D [08/Dec/2017:11:23:59 +0500] [Job 9] pdftopdf.exe (application/pdf to application/vnd.cups-pdf, cost 66) D [08/Dec/2017:11:23:59 +0500] [Job 9] foomatic-rip (application/vnd.cups-pdf to printer/HP_Color_Laserjet_3800_hpijs, cost 0) D [08/Dec/2017:11:23:59 +0500] [Job 9] job-sheets=none,none D [08/Dec/2017:11:23:59 +0500] [Job 9] argv[0]="HP_Color_Laserjet_3800_hpijs" D [08/Dec/2017:11:23:59 +0500] [Job 9] argv[1]="9" D [08/Dec/2017:11:23:59 +0500] [Job 9] argv[2]="anonymous" D [08/Dec/2017:11:23:59 +0500] [Job 9] argv[3]="Test Page" D [08/Dec/2017:11:23:59 +0500] [Job 9] argv[4]="1" D [08/Dec/2017:11:23:59 +0500] [Job 9] argv[5]="job-uuid=urn:uuid:ae9cdb5a-7f9b-3015-642a-28af8a16ab62 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1512750239 time-at-processing=1512750239" D [08/Dec/2017:11:23:59 +0500] [Job 9] argv[6]="/@unixroot/var/spool/cups/d00009-001" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[0]="CUPS_CACHEDIR=/@unixroot/var/cache/cups" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[1]="CUPS_DATADIR=/@unixroot/usr/share/cups" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[2]="CUPS_DOCROOT=/@unixroot/usr/share/doc/cups" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[3]="CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[4]="CUPS_REQUESTROOT=/@unixroot/var/spool/cups" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[5]="CUPS_SERVERBIN=/@unixroot/usr/lib/cups" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[6]="CUPS_SERVERROOT=/@unixroot/etc/cups" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[7]="CUPS_STATEDIR=/@unixroot/var/run/cups" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[8]="HOME=C:\\var\\temp" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[9]="NLSPATH=C:\\MPTN\\MSG\\NLS\\%N;C:\\TCPIP\\msg\\ENUS850\\%N;" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[10]="PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[11]="SERVER_ADMIN=root@bluelion" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[12]="SOFTWARE=CUPS/2.1.3" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[13]="TMPDIR=C:\\var\\temp" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[14]="TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[15]="USER=root" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[16]="UNIXROOT=C:" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[17]="ETC=C:\\MPTN\\ETC" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[18]="CUPS_MAX_MESSAGE=2047" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[19]="CUPS_SERVER=\\socket\\cups.sock" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[20]="CUPS_ENCRYPTION=IfRequested" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[21]="IPP_PORT=631" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[22]="CHARSET=utf-8" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[23]="LANG=en_US.UTF-8" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[24]="PPD=/@unixroot/etc/cups/ppd/HP_Color_Laserjet_3800_hpijs.ppd" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[25]="RIP_MAX_CACHE=128m" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[26]="CONTENT_TYPE=application/vnd.cups-pdf-banner" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[27]="DEVICE_URI=socket://192.168.100.16" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[28]="PRINTER_INFO=HP Color LaserJet 3800 hpijs pcl3" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[29]="PRINTER_LOCATION=office" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[30]="PRINTER=HP_Color_Laserjet_3800_hpijs" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[31]="PRINTER_STATE_REASONS=none" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[32]="CUPS_FILETYPE=document" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[33]="FINAL_CONTENT_TYPE=application/vnd.cups-pdf" D [08/Dec/2017:11:23:59 +0500] [Job 9] envp[34]="AUTH_I****" I [08/Dec/2017:11:23:59 +0500] [Job 9] Started filter /@unixroot/usr/lib/cups/filter/bannertopdf.exe (PID 81) I [08/Dec/2017:11:23:59 +0500] [Job 9] Started filter /@unixroot/usr/lib/cups/filter/pdftopdf.exe (PID 82) I [08/Dec/2017:11:24:00 +0500] [Job 9] Started filter /@unixroot/usr/lib/cups/filter/foomatic-rip.exe (PID 84) I [08/Dec/2017:11:24:00 +0500] [Job 9] Started backend /@unixroot/usr/lib/cups/backend/socket.exe (PID 87) D [08/Dec/2017:11:24:00 +0500] Discarding unused job-state-changed event... D [08/Dec/2017:11:24:00 +0500] [Client 8] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/HP_Color_Laserjet_3800_hpijs) from localhost D [08/Dec/2017:11:24:00 +0500] [Client 8] Content-Length: 191 D [08/Dec/2017:11:24:00 +0500] [Client 8] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [08/Dec/2017:11:24:00 +0500] [Client 9] Accepted from localhost (Domain) D [08/Dec/2017:11:24:00 +0500] [Client 9] Waiting for request. D [08/Dec/2017:11:24:00 +0500] [Client 8] con->http=0x201fb220 D [08/Dec/2017:11:24:00 +0500] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=191, response=0x2011ea20(IPP_STATE_IDLE), pipe_pid=0, file=-1 D [08/Dec/2017:11:24:00 +0500] [Client 8] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [08/Dec/2017:11:24:00 +0500] [Client 8] bytes=0, http_state=0, data_remaining=191 D [08/Dec/2017:11:24:00 +0500] [Client 8] Flushing write buffer. D [08/Dec/2017:11:24:00 +0500] [Client 8] New state is HTTP_STATE_WAITING D [08/Dec/2017:11:24:00 +0500] [Client 8] Waiting for request. D [08/Dec/2017:11:24:00 +0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:24:00 +0500] [Job 9] WARN: not compiled with DBus support D [08/Dec/2017:11:24:00 +0500] [Job 9] \'CM Color Calibration\' Mode in SPOOLER-LESS: Off D [08/Dec/2017:11:24:00 +0500] [Job 9] Getting input from file D [08/Dec/2017:11:24:00 +0500] [Job 9] foomatic-rip version 1.17.2 running... D [08/Dec/2017:11:24:00 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [08/Dec/2017:11:24:00 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\" D [08/Dec/2017:11:24:00 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [08/Dec/2017:11:24:00 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\" D [08/Dec/2017:11:24:00 +0500] [CGI] cgiSetArray: job_uri[0]=\"/jobs/9\" D [08/Dec/2017:11:24:00 +0500] [CGI] cgiSetArray: job_id[0]=\"9\" D [08/Dec/2017:11:24:00 +0500] [CGI] cgiSetArray: job_state[0]=\"3\" D [08/Dec/2017:11:24:00 +0500] [CGI] cgiSetArray: job_state_message[0]=\"\" D [08/Dec/2017:11:24:00 +0500] [CGI] cgiSetArray: job_state_reasons[0]=\"none\" D [08/Dec/2017:11:24:00 +0500] [CGI] cgiSetVariable: refresh_page=\"2;URL=/printers/HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:00 +0500] [CGI] cgiSetVariable: TITLE=\"Print Test Page\" D [08/Dec/2017:11:24:00 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [08/Dec/2017:11:24:00 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\" D [08/Dec/2017:11:24:00 +0500] [Client 8] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [08/Dec/2017:11:24:00 +0500] [Client 8] Closing connection. D [08/Dec/2017:11:24:00 +0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [08/Dec/2017:11:24:00 +0500] [Client 7] CGI data ready to be sent. D [08/Dec/2017:11:24:00 +0500] [Client 9] POST /printers/HP_Color_Laserjet_3800_hpijs HTTP/1.1 D [08/Dec/2017:11:24:00 +0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [08/Dec/2017:11:24:00 +0500] [Client 9] Read: status=200 D [08/Dec/2017:11:24:00 +0500] [Client 9] No authentication data provided. D [08/Dec/2017:11:24:00 +0500] [Client 9] 2.0 Get-Printer-Attributes 1 D [08/Dec/2017:11:24:00 +0500] Get-Printer-Attributes ipp://localhost/printers/HP_Color_Laserjet_3800_hpijs D [08/Dec/2017:11:24:00 +0500] [Client 9] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_Color_Laserjet_3800_hpijs) from localhost D [08/Dec/2017:11:24:00 +0500] [Client 9] Content-Length: 214 D [08/Dec/2017:11:24:00 +0500] [Client 9] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [08/Dec/2017:11:24:00 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:00 +0500] [Client 7] con->http=0x201f7040 D [08/Dec/2017:11:24:00 +0500] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=79, file=16 D [08/Dec/2017:11:24:00 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:00 +0500] [Client 7] Script header: Content-Type: text/html;charset=utf-8 D [08/Dec/2017:11:24:00 +0500] [Client 7] Script header: D [08/Dec/2017:11:24:00 +0500] [Client 7] Sending status 200 for CGI. D [08/Dec/2017:11:24:00 +0500] [Client 7] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [08/Dec/2017:11:24:00 +0500] [Client 9] con->http=0x20200880 D [08/Dec/2017:11:24:00 +0500] [Client 9] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=214, response=0x201ff0c0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [08/Dec/2017:11:24:00 +0500] [Client 9] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [08/Dec/2017:11:24:00 +0500] [Client 9] bytes=0, http_state=0, data_remaining=214 D [08/Dec/2017:11:24:00 +0500] [Client 9] Flushing write buffer. D [08/Dec/2017:11:24:00 +0500] [Client 9] New state is HTTP_STATE_WAITING D [08/Dec/2017:11:24:00 +0500] [Client 9] Waiting for request. D [08/Dec/2017:11:24:00 +0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [08/Dec/2017:11:24:00 +0500] [Client 7] con->http=0x201f7040 D [08/Dec/2017:11:24:00 +0500] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=79, file=16 D [08/Dec/2017:11:24:00 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:00 +0500] [Client 7] CGI data ready to be sent. D [08/Dec/2017:11:24:00 +0500] [Client 7] con->http=0x201f7040 D [08/Dec/2017:11:24:00 +0500] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=79, file=16 D [08/Dec/2017:11:24:00 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:00 +0500] [Client 7] Sending 0-length chunk. D [08/Dec/2017:11:24:00 +0500] [Client 7] Flushing write buffer. D [08/Dec/2017:11:24:00 +0500] [Client 7] New state is HTTP_STATE_WAITING D [08/Dec/2017:11:24:00 +0500] [Client 7] Waiting for request. D [08/Dec/2017:11:24:00 +0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [08/Dec/2017:11:24:00 +0500] PID 79 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [08/Dec/2017:11:24:00 +0500] [Client 10] Accepted from localhost:56475 (IPv4) D [08/Dec/2017:11:24:00 +0500] [Client 10] Waiting for request. D [08/Dec/2017:11:24:00 +0500] [Client 10] GET /printers/HP_Color_Laserjet_3800_hpijs.ppd HTTP/1.1 D [08/Dec/2017:11:24:00 +0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [08/Dec/2017:11:24:00 +0500] [Client 10] Read: status=200 D [08/Dec/2017:11:24:00 +0500] [Client 10] No authentication data provided. D [08/Dec/2017:11:24:00 +0500] [Client 10] Processing GET /printers/HP_Color_Laserjet_3800_hpijs.ppd D [08/Dec/2017:11:24:00 +0500] [Client 10] filename="/@unixroot/etc/cups/ppd/HP_Color_Laserjet_3800_hpijs.ppd", type=application/vnd.cups-ppd D [08/Dec/2017:11:24:00 +0500] [Client 10] cupsdSendHeader: code=200, type="application/vnd.cups-ppd", auth_type=0 D [08/Dec/2017:11:24:00 +0500] [Client 10] Sending file. D [08/Dec/2017:11:24:00 +0500] [Client 10] con->http=0x201fb220 D [08/Dec/2017:11:24:00 +0500] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=16 D [08/Dec/2017:11:24:00 +0500] [Client 10] con->http=0x201fb220 D [08/Dec/2017:11:24:00 +0500] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=16 D [08/Dec/2017:11:24:00 +0500] [Client 10] con->http=0x201fb220 D [08/Dec/2017:11:24:00 +0500] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=16 D [08/Dec/2017:11:24:00 +0500] [Client 10] con->http=0x201fb220 D [08/Dec/2017:11:24:00 +0500] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=16 D [08/Dec/2017:11:24:00 +0500] [Client 10] con->http=0x201fb220 D [08/Dec/2017:11:24:00 +0500] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=16 D [08/Dec/2017:11:24:00 +0500] [Client 10] con->http=0x201fb220 D [08/Dec/2017:11:24:00 +0500] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=16 D [08/Dec/2017:11:24:01 +0500] [Client 10] con->http=0x201fb220 D [08/Dec/2017:11:24:01 +0500] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=16 D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: +connecting-to-device D [08/Dec/2017:11:24:01 +0500] cupsdMarkDirty(---J-) D [08/Dec/2017:11:24:01 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients, printing jobs, and dirty files" D [08/Dec/2017:11:24:01 +0500] [Job 9] Looking up \"192.168.100.16\"... D [08/Dec/2017:11:24:01 +0500] Discarding unused printer-state-changed event... D [08/Dec/2017:11:24:01 +0500] [Client 10] con->http=0x201fb220 D [08/Dec/2017:11:24:01 +0500] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=16 D [08/Dec/2017:11:24:01 +0500] [Client 10] con->http=0x201fb220 D [08/Dec/2017:11:24:01 +0500] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=16 D [08/Dec/2017:11:24:01 +0500] [Client 10] con->http=0x201fb220 D [08/Dec/2017:11:24:01 +0500] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=16 D [08/Dec/2017:11:24:01 +0500] [Client 10] con->http=0x201fb220 D [08/Dec/2017:11:24:01 +0500] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20785, response=0(), pipe_pid=0, file=16 D [08/Dec/2017:11:24:01 +0500] [Client 10] Flushing write buffer. D [08/Dec/2017:11:24:01 +0500] [Client 10] New state is HTTP_STATE_WAITING D [08/Dec/2017:11:24:01 +0500] [Client 10] Waiting for request. D [08/Dec/2017:11:24:01 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:24:01 +0500] [Client 10] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [08/Dec/2017:11:24:01 +0500] [Client 10] Closing connection. D [08/Dec/2017:11:24:01 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [08/Dec/2017:11:24:01 +0500] [Job 9] Parsing PPD file ... D [08/Dec/2017:11:24:01 +0500] [Job 9] Added option ColorSpace D [08/Dec/2017:11:24:01 +0500] [Job 9] Added option Resolution D [08/Dec/2017:11:24:01 +0500] [Job 9] Added option PageSize D [08/Dec/2017:11:24:01 +0500] [Job 9] Added option Model D [08/Dec/2017:11:24:01 +0500] [Job 9] Added option PrintoutMode D [08/Dec/2017:11:24:01 +0500] [Job 9] Added option InputSlot D [08/Dec/2017:11:24:01 +0500] [Job 9] Added option Duplex D [08/Dec/2017:11:24:01 +0500] [Job 9] Added option Quality D [08/Dec/2017:11:24:01 +0500] [Job 9] Added option ImageableArea D [08/Dec/2017:11:24:01 +0500] [Job 9] Added option PaperDimension D [08/Dec/2017:11:24:01 +0500] [Job 9] Added option Font D [08/Dec/2017:11:24:01 +0500] [Job 9] pdftopdf: Last filter determined by the PPD: foomatic-rip; FINAL_CONTENT_TYPE: application/vnd.cups-pdf => pdftopdf will log pages in page_log. D [08/Dec/2017:11:24:01 +0500] [Job 9] ATTR: marker-colors=#000000,#00FFFF,#FF00FF,#FFFF00 D [08/Dec/2017:11:24:01 +0500] cupsdMarkDirty(P----) D [08/Dec/2017:11:24:01 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [08/Dec/2017:11:24:01 +0500] [Job 9] ATTR: marker-names=\'\"Black Cartridge HP Q6470A\"\',\'\"Cyan Cartridge HP Q7581A\"\',\'\"Magenta Cartridge HP Q7583A\"\',\'\"Yellow Cartridge HP Q7582A\"\' D [08/Dec/2017:11:24:01 +0500] cupsdMarkDirty(P----) D [08/Dec/2017:11:24:01 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [08/Dec/2017:11:24:01 +0500] [Job 9] ATTR: marker-types=toner-cartridge,toner-cartridge,toner-cartridge,toner-cartridge D [08/Dec/2017:11:24:01 +0500] cupsdMarkDirty(P----) D [08/Dec/2017:11:24:01 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [08/Dec/2017:11:24:01 +0500] [Job 9] ATTR: marker-levels=11,66,66,13 D [08/Dec/2017:11:24:01 +0500] cupsdMarkDirty(P----) D [08/Dec/2017:11:24:01 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [08/Dec/2017:11:24:01 +0500] [Job 9] new_supply_state=0, change_state=ffff D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: -developer-low-report D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: -developer-empty-warning D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: -marker-supply-low-report D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: -marker-supply-empty-warning D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: -opc-near-eol-report D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: -opc-life-over-warning D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: -toner-low-report D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: -toner-empty-warning D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: -waste-receptacle-almost-full-report D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: -waste-receptacle-full-warning D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: -cleaner-life-almost-over-report D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: -cleaner-life-over-warning D [08/Dec/2017:11:24:01 +0500] Discarding unused printer-state-changed event... D [08/Dec/2017:11:24:01 +0500] [Job 9] new_state=2000, change_state=ffff D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: -media-empty-warning D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: -door-open-report D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: -media-jam-warning D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: -input-tray-missing-warning D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: -output-tray-missing-warning D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: -marker-supply-missing-warning D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: -output-area-almost-full-report D [08/Dec/2017:11:24:01 +0500] [Job 9] STATE: -output-area-full-warning D [08/Dec/2017:11:24:01 +0500] [Job 9] backendWaitLoop(snmp_fd=5, addr=0x20037e04, side_cb=0x110f0) D [08/Dec/2017:11:24:01 +0500] [Job 9] Parameter Summary D [08/Dec/2017:11:24:01 +0500] [Job 9] ----------------- D [08/Dec/2017:11:24:01 +0500] [Job 9] Spooler: cups D [08/Dec/2017:11:24:01 +0500] [Job 9] Printer: HP_Color_Laserjet_3800_hpijs D [08/Dec/2017:11:24:01 +0500] [Job 9] Shell: /@unixroot/usr/bin/sh D [08/Dec/2017:11:24:01 +0500] [Job 9] PPD file: /@unixroot/etc/cups/ppd/HP_Color_Laserjet_3800_hpijs.ppd D [08/Dec/2017:11:24:01 +0500] [Job 9] ATTR file: D [08/Dec/2017:11:24:01 +0500] [Job 9] Printer model: HP Color LaserJet 3800 hpijs pcl3, 3.16.11 D [08/Dec/2017:11:24:01 +0500] [Job 9] Job title: Test Page D [08/Dec/2017:11:24:01 +0500] [Job 9] File(s) to be printed: D [08/Dec/2017:11:24:01 +0500] [Job 9] D [08/Dec/2017:11:24:01 +0500] [Job 9] Ghostscript extra search path (\'GS_LIB\'): /@unixroot/usr/share/cups/fonts D [08/Dec/2017:11:24:01 +0500] [Job 9] Printing system options: D [08/Dec/2017:11:24:01 +0500] [Job 9] Pondering option \'job-uuid=urn:uuid:ae9cdb5a-7f9b-3015-642a-28af8a16ab62\' D [08/Dec/2017:11:24:01 +0500] [Job 9] Unknown option job-uuid=urn:uuid:ae9cdb5a-7f9b-3015-642a-28af8a16ab62. D [08/Dec/2017:11:24:01 +0500] [Job 9] Pondering option \'job-originating-host-name=localhost\' D [08/Dec/2017:11:24:01 +0500] [Job 9] Unknown option job-originating-host-name=localhost. D [08/Dec/2017:11:24:01 +0500] [Job 9] Pondering option \'date-time-at-creation=\' D [08/Dec/2017:11:24:01 +0500] [Job 9] Unknown option date-time-at-creation=. D [08/Dec/2017:11:24:01 +0500] [Job 9] Pondering option \'date-time-at-processing=\' D [08/Dec/2017:11:24:01 +0500] [Job 9] Unknown option date-time-at-processing=. D [08/Dec/2017:11:24:01 +0500] [Job 9] Pondering option \'time-at-creation=1512750239\' D [08/Dec/2017:11:24:01 +0500] [Job 9] Unknown option time-at-creation=1512750239. D [08/Dec/2017:11:24:01 +0500] [Job 9] Pondering option \'time-at-processing=1512750239\' D [08/Dec/2017:11:24:01 +0500] [Job 9] Unknown option time-at-processing=1512750239. D [08/Dec/2017:11:24:01 +0500] [Job 9] CM Color Calibration Mode in CUPS: Off D [08/Dec/2017:11:24:01 +0500] [Job 9] Options from the PPD file: D [08/Dec/2017:11:24:01 +0500] [Job 9] ================================================ D [08/Dec/2017:11:24:01 +0500] [Job 9] File: D [08/Dec/2017:11:24:01 +0500] [Job 9] ================================================ D [08/Dec/2017:11:24:02 +0500] [Job 9] PDF template file doesn\'t have form. It\'s okay. W [08/Dec/2017:11:24:02 +0500] cupsdDoSelect: select() returned -1... pause and retry select() D [08/Dec/2017:11:24:03 +0500] [Job 9] PAGE: 1 1 D [08/Dec/2017:11:24:03 +0500] cupsdMarkDirty(---J-) D [08/Dec/2017:11:24:03 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [08/Dec/2017:11:24:03 +0500] cupsdMarkDirty(---J-) D [08/Dec/2017:11:24:03 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [08/Dec/2017:11:24:03 +0500] Discarding unused job-progress event... D [08/Dec/2017:11:24:03 +0500] [Job 9] Filetype: PDF D [08/Dec/2017:11:24:03 +0500] [Job 9] Storing temporary files in C:\\var\\temp D [08/Dec/2017:11:24:03 +0500] [Job 9] PID 81 (/@unixroot/usr/lib/cups/filter/bannertopdf.exe) exited with no errors. D [08/Dec/2017:11:24:03 +0500] [Job 9] PID 82 (/@unixroot/usr/lib/cups/filter/pdftopdf.exe) exited with no errors. D [08/Dec/2017:11:24:03 +0500] [Client 7] GET /printers/HP_Color_Laserjet_3800_hpijs HTTP/1.1 D [08/Dec/2017:11:24:03 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [08/Dec/2017:11:24:03 +0500] [Client 7] Read: status=200 D [08/Dec/2017:11:24:03 +0500] [Client 7] No authentication data provided. D [08/Dec/2017:11:24:03 +0500] [Client 7] Processing GET /printers/HP_Color_Laserjet_3800_hpijs D [08/Dec/2017:11:24:03 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[8] = "HOME=C:\\var\\temp" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[9] = "NLSPATH=C:\\MPTN\\MSG\\NLS\\%N;C:\\TCPIP\\msg\\ENUS850\\%N;" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@bluelion" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[13] = "TMPDIR=C:\\var\\temp" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[15] = "USER=root" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[16] = "UNIXROOT=C:" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[17] = "ETC=C:\\MPTN\\ETC" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[18] = "CUPS_MAX_MESSAGE=2047" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[19] = "CUPS_SERVER=\\socket\\cups.sock" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[20] = "CUPS_ENCRYPTION=IfRequested" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[21] = "IPP_PORT=631" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[22] = "LANG=en_US.UTF8" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[23] = "REDIRECT_STATUS=1" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[24] = "GATEWAY_INTERFACE=CGI/1.1" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[25] = "SERVER_NAME=localhost" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[26] = "SERVER_PORT=631" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[27] = "REMOTE_ADDR=127.0.0.1" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[28] = "REMOTE_HOST=localhost" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[29] = "SCRIPT_NAME=/printers/HP_Color_Laserjet_3800_hpijs" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[30] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/printers/HP_Color_Laserjet_3800_hpijs" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[31] = "PATH_INFO=/HP_Color_Laserjet_3800_hpijs" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[32] = "SERVER_PROTOCOL=HTTP/1.1" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[33] = "HTTP_COOKIE=org.cups.sid=41f45aede00677dfbe5c986711944887" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[34] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:45.0) Gecko/20100101 Firefox/45.0" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[35] = "REQUEST_METHOD=GET" D [08/Dec/2017:11:24:03 +0500] [CGI] envp[36] = "QUERY_STRING=" D [08/Dec/2017:11:24:03 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/printers.cgi (PID 92) I [08/Dec/2017:11:24:03 +0500] [Client 7] Started "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" (pid=92, file=15) D [08/Dec/2017:11:24:03 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:03 +0500] [CGI] org.cups.sid cookie is \"41f45aede00677dfbe5c986711944887\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetVariable: SECTION=\"printers\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:03 +0500] [Client 11] Accepted from localhost (Domain) D [08/Dec/2017:11:24:03 +0500] [Client 11] Waiting for request. D [08/Dec/2017:11:24:03 +0500] [Client 11] POST / HTTP/1.1 D [08/Dec/2017:11:24:03 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:24:03 +0500] [Client 11] Read: status=200 D [08/Dec/2017:11:24:03 +0500] [Client 11] No authentication data provided. D [08/Dec/2017:11:24:03 +0500] [Client 11] 2.0 CUPS-Get-Default 1 D [08/Dec/2017:11:24:03 +0500] CUPS-Get-Default D [08/Dec/2017:11:24:03 +0500] CUPS-Get-Default client-error-not-found: No default printer. D [08/Dec/2017:11:24:03 +0500] [Client 11] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost D [08/Dec/2017:11:24:03 +0500] [Client 11] Content-Length: 113 D [08/Dec/2017:11:24:03 +0500] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [08/Dec/2017:11:24:03 +0500] [Client 11] con->http=0x20203d00 D [08/Dec/2017:11:24:03 +0500] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x201fb620(IPP_STATE_DATA), pipe_pid=0, file=-1 D [08/Dec/2017:11:24:03 +0500] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [08/Dec/2017:11:24:03 +0500] [Client 11] bytes=0, http_state=0, data_remaining=113 D [08/Dec/2017:11:24:03 +0500] [Client 11] Flushing write buffer. D [08/Dec/2017:11:24:03 +0500] [Client 11] New state is HTTP_STATE_WAITING D [08/Dec/2017:11:24:03 +0500] [Client 11] Waiting for request. D [08/Dec/2017:11:24:03 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:24:03 +0500] [CGI] show_printer(http=0x2003c3c0, printer=\"HP_Color_Laserjet_3800_hpijs\") D [08/Dec/2017:11:24:03 +0500] [Client 11] POST / HTTP/1.1 D [08/Dec/2017:11:24:03 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:24:03 +0500] [Client 11] Read: status=200 D [08/Dec/2017:11:24:03 +0500] [Client 11] No authentication data provided. D [08/Dec/2017:11:24:03 +0500] [Client 11] 2.0 Get-Printer-Attributes 2 D [08/Dec/2017:11:24:03 +0500] Get-Printer-Attributes ipp://localhost/printers/HP_Color_Laserjet_3800_hpijs D [08/Dec/2017:11:24:03 +0500] [Client 11] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_Color_Laserjet_3800_hpijs) from localhost D [08/Dec/2017:11:24:03 +0500] [Client 11] Content-Length: 756 D [08/Dec/2017:11:24:03 +0500] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [08/Dec/2017:11:24:03 +0500] [Client 11] con->http=0x20203d00 D [08/Dec/2017:11:24:03 +0500] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=756, response=0x201fb680(IPP_STATE_DATA), pipe_pid=0, file=-1 D [08/Dec/2017:11:24:03 +0500] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [08/Dec/2017:11:24:03 +0500] [Client 11] bytes=0, http_state=0, data_remaining=756 D [08/Dec/2017:11:24:03 +0500] [Client 11] Flushing write buffer. D [08/Dec/2017:11:24:03 +0500] [Client 11] New state is HTTP_STATE_WAITING D [08/Dec/2017:11:24:03 +0500] [Client 11] Waiting for request. D [08/Dec/2017:11:24:03 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: printer_is_shared[0]=\"1\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: printer_state[0]=\"4\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: printer_name[0]=\"HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: printer_location[0]=\"office\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: printer_info[0]=\"HP Color LaserJet 3800 hpijs pcl3\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: device_uri[0]=\"socket://192.168.100.16\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: color_supported[0]=\"1\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: printer_make_and_model[0]=\"HP Color LaserJet 3800 hpijs pcl3, 3.16.11\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: media_default[0]=\"na_letter_8.5x11in\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: sides_supported[0]=\"one-sided, two-sided-long-edge, two-sided-short-edge\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: sides_default[0]=\"one-sided\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: printer_commands[0]=\"AutoConfigure, Clean, PrintSelfTestPage\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"0\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetVariable: refresh_page=\"10;URL=/printers/HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetVariable: TITLE=\"HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\" D [08/Dec/2017:11:24:03 +0500] [Client 7] CGI data ready to be sent. D [08/Dec/2017:11:24:03 +0500] [Client 7] con->http=0x201f7040 D [08/Dec/2017:11:24:03 +0500] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=92, file=15 D [08/Dec/2017:11:24:03 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:03 +0500] [Client 7] Script header: Content-Type: text/html;charset=utf-8 D [08/Dec/2017:11:24:03 +0500] [Client 7] Script header: D [08/Dec/2017:11:24:03 +0500] [Client 7] Sending status 200 for CGI. D [08/Dec/2017:11:24:03 +0500] [Client 7] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [08/Dec/2017:11:24:03 +0500] [Client 7] con->http=0x201f7040 D [08/Dec/2017:11:24:03 +0500] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=92, file=15 D [08/Dec/2017:11:24:03 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:03 +0500] [Client 7] CGI data ready to be sent. D [08/Dec/2017:11:24:03 +0500] [Client 7] con->http=0x201f7040 D [08/Dec/2017:11:24:03 +0500] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=92, file=15 D [08/Dec/2017:11:24:03 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:03 +0500] [Client 7] con->http=0x201f7040 D [08/Dec/2017:11:24:03 +0500] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=92, file=15 D [08/Dec/2017:11:24:03 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:03 +0500] [Client 7] CGI data ready to be sent. D [08/Dec/2017:11:24:03 +0500] [Client 7] con->http=0x201f7040 D [08/Dec/2017:11:24:03 +0500] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=92, file=15 D [08/Dec/2017:11:24:03 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:03 +0500] [Client 7] con->http=0x201f7040 D [08/Dec/2017:11:24:03 +0500] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=92, file=15 D [08/Dec/2017:11:24:03 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:03 +0500] [Client 7] CGI data ready to be sent. D [08/Dec/2017:11:24:03 +0500] [Client 7] con->http=0x201f7040 D [08/Dec/2017:11:24:03 +0500] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=92, file=15 D [08/Dec/2017:11:24:03 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:03 +0500] [Client 7] con->http=0x201f7040 D [08/Dec/2017:11:24:03 +0500] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=92, file=15 D [08/Dec/2017:11:24:03 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:03 +0500] [Client 7] CGI data ready to be sent. D [08/Dec/2017:11:24:03 +0500] [Client 11] POST / HTTP/1.1 D [08/Dec/2017:11:24:03 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:24:03 +0500] [Client 11] Read: status=200 D [08/Dec/2017:11:24:03 +0500] [Client 11] No authentication data provided. D [08/Dec/2017:11:24:03 +0500] [Client 11] 2.0 Get-Jobs 3 D [08/Dec/2017:11:24:03 +0500] Get-Jobs ipp://localhost:631/printers/HP_Color_Laserjet_3800_hpijs D [08/Dec/2017:11:24:03 +0500] [Client 11] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HP_Color_Laserjet_3800_hpijs) from localhost D [08/Dec/2017:11:24:03 +0500] [Client 11] Content-Length: 403 D [08/Dec/2017:11:24:03 +0500] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [08/Dec/2017:11:24:03 +0500] [CGI] Regular expression \".*Clean.*\" D [08/Dec/2017:11:24:03 +0500] [CGI] matches[0].rm_so=0 D [08/Dec/2017:11:24:03 +0500] [CGI] matches[1].rm_so=-1 D [08/Dec/2017:11:24:03 +0500] [CGI] Regular expression \".*PrintSelfTestPage.*\" D [08/Dec/2017:11:24:03 +0500] [CGI] matches[0].rm_so=0 D [08/Dec/2017:11:24:03 +0500] [CGI] matches[1].rm_so=-1 D [08/Dec/2017:11:24:03 +0500] [Client 7] con->http=0x201f7040 D [08/Dec/2017:11:24:03 +0500] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=92, file=15 D [08/Dec/2017:11:24:03 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:03 +0500] [Client 11] con->http=0x20203d00 D [08/Dec/2017:11:24:03 +0500] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=403, response=0x201fb8a0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [08/Dec/2017:11:24:03 +0500] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [08/Dec/2017:11:24:03 +0500] [Client 11] bytes=0, http_state=0, data_remaining=403 D [08/Dec/2017:11:24:03 +0500] [Client 11] Flushing write buffer. D [08/Dec/2017:11:24:03 +0500] [Client 11] New state is HTTP_STATE_WAITING D [08/Dec/2017:11:24:03 +0500] [Client 11] Waiting for request. D [08/Dec/2017:11:24:03 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:24:03 +0500] [Client 7] con->http=0x201f7040 D [08/Dec/2017:11:24:03 +0500] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=92, file=15 D [08/Dec/2017:11:24:03 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:03 +0500] [Client 7] CGI data ready to be sent. D [08/Dec/2017:11:24:03 +0500] [Client 7] con->http=0x201f7040 D [08/Dec/2017:11:24:03 +0500] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=92, file=15 D [08/Dec/2017:11:24:03 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:03 +0500] [Client 7] con->http=0x201f7040 D [08/Dec/2017:11:24:03 +0500] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=92, file=15 D [08/Dec/2017:11:24:03 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:03 +0500] [CGI] cgiClearVariables called. D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetVariable: ORDER=\"asc\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetVariable: SECTION=\"printers\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetVariable: TOTAL=\"1\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: job_printer_name[0]=\"HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: job_printer_uri[0]=\"/printers/HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: job_name[0]=\"Test Page\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: job_originating_user_name[0]=\"anonymous\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: time_at_completed[0]=\"novalue\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: time_at_creation[0]=\"Fri Dec 8 11:23:59 EST 2017\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: time_at_processing[0]=\"Fri Dec 8 11:23:59 EST 2017\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: job_id[0]=\"9\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: job_state[0]=\"5\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: job_media_sheets_completed[0]=\"1\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: job_k_octets[0]=\"1\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetArray: job_printer_state_message[0]=\"\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetVariable: THISURL=\"/printers/HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:03 +0500] [CGI] cgiSetVariable: SEARCH_DEST=\"HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:03 +0500] [Client 7] CGI data ready to be sent. D [08/Dec/2017:11:24:03 +0500] [Client 7] con->http=0x201f7040 D [08/Dec/2017:11:24:03 +0500] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=92, file=15 D [08/Dec/2017:11:24:03 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:03 +0500] [Client 7] con->http=0x201f7040 D [08/Dec/2017:11:24:03 +0500] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=92, file=15 D [08/Dec/2017:11:24:03 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:03 +0500] [Client 7] CGI data ready to be sent. D [08/Dec/2017:11:24:03 +0500] [Client 11] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [08/Dec/2017:11:24:03 +0500] [Client 11] Closing connection. D [08/Dec/2017:11:24:03 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:24:03 +0500] [Client 7] con->http=0x201f7040 D [08/Dec/2017:11:24:03 +0500] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=92, file=15 D [08/Dec/2017:11:24:03 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:03 +0500] [Client 7] con->http=0x201f7040 D [08/Dec/2017:11:24:03 +0500] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=92, file=15 D [08/Dec/2017:11:24:03 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:03 +0500] [Client 7] CGI data ready to be sent. D [08/Dec/2017:11:24:03 +0500] [Client 7] con->http=0x201f7040 D [08/Dec/2017:11:24:03 +0500] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=92, file=15 D [08/Dec/2017:11:24:03 +0500] [Client 7] Waiting for CGI data. D [08/Dec/2017:11:24:03 +0500] [Client 7] Sending 0-length chunk. D [08/Dec/2017:11:24:03 +0500] [Client 7] Flushing write buffer. D [08/Dec/2017:11:24:03 +0500] [Client 7] New state is HTTP_STATE_WAITING D [08/Dec/2017:11:24:03 +0500] [Client 7] Waiting for request. D [08/Dec/2017:11:24:03 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" W [08/Dec/2017:11:24:03 +0500] cupsdDoSelect: select() returned -1... pause and retry select() D [08/Dec/2017:11:24:04 +0500] [Client 7] GET /cups.css HTTP/1.1 D [08/Dec/2017:11:24:04 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [08/Dec/2017:11:24:04 +0500] [Client 7] Read: status=200 D [08/Dec/2017:11:24:04 +0500] [Client 7] No authentication data provided. D [08/Dec/2017:11:24:04 +0500] [Client 7] Processing GET /cups.css D [08/Dec/2017:11:24:04 +0500] [Client 7] filename="/@unixroot/usr/share/doc/cups/cups.css", type=text/css D [08/Dec/2017:11:24:04 +0500] [Client 7] cupsdSendHeader: code=304, type="(null)", auth_type=0 D [08/Dec/2017:11:24:04 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:24:04 +0500] PID 92 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [08/Dec/2017:11:24:06 +0500] [Job 9] ATTR: marker-levels=11,65,65,13 D [08/Dec/2017:11:24:06 +0500] cupsdMarkDirty(P----) D [08/Dec/2017:11:24:06 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [08/Dec/2017:11:24:06 +0500] [Job 9] new_supply_state=0, change_state=0 D [08/Dec/2017:11:24:06 +0500] Discarding unused printer-state-changed event... D [08/Dec/2017:11:24:06 +0500] [Job 9] new_state=2000, change_state=0 D [08/Dec/2017:11:24:09 +0500] [Job 9] GPL Ghostscript 9.18: Unrecoverable error, exit code 1 D [08/Dec/2017:11:24:09 +0500] [Job 9] Process is dying with \"Unable to determine number of pages, page count: -1 D [08/Dec/2017:11:24:09 +0500] [Job 9] \", exit stat 3 D [08/Dec/2017:11:24:09 +0500] [Job 9] Cleaning up... D [08/Dec/2017:11:24:09 +0500] [Client 9] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [08/Dec/2017:11:24:09 +0500] [Client 9] Closing connection. D [08/Dec/2017:11:24:09 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [08/Dec/2017:11:24:09 +0500] [Job 9] PID 84 (/@unixroot/usr/lib/cups/filter/foomatic-rip.exe) stopped with status 3. D [08/Dec/2017:11:24:09 +0500] [Job 9] PID 87 (/@unixroot/usr/lib/cups/backend/socket.exe) exited with no errors. D [08/Dec/2017:11:24:09 +0500] Discarding unused job-state-changed event... E [08/Dec/2017:11:24:09 +0500] [Job 9] Job stopped due to filter errors; please consult the error_log file for details. D [08/Dec/2017:11:24:09 +0500] cupsdMarkDirty(---J-) D [08/Dec/2017:11:24:09 +0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files" D [08/Dec/2017:11:24:09 +0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [08/Dec/2017:11:24:09 +0500] Discarding unused printer-state-changed event... D [08/Dec/2017:11:24:09 +0500] cupsdMarkDirty(---J-) D [08/Dec/2017:11:24:09 +0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [08/Dec/2017:11:24:09 +0500] [Job 9] The following messages were recorded from 11:24:01 am to 11:24:06 am D [08/Dec/2017:11:24:09 +0500] [Job 9] hrDeviceDesc=\"HP Color LaserJet 3800\" D [08/Dec/2017:11:24:09 +0500] [Job 9] prtMarkerColorantValue.1.1 = \"black\" D [08/Dec/2017:11:24:09 +0500] [Job 9] prtMarkerColorantValue.1.2 = \"cyan\" D [08/Dec/2017:11:24:09 +0500] [Job 9] prtMarkerColorantValue.1.3 = \"magenta\" D [08/Dec/2017:11:24:09 +0500] [Job 9] prtMarkerColorantValue.1.4 = \"yellow\" D [08/Dec/2017:11:24:09 +0500] [Job 9] prtMarkerSuppliesLevel.1.1 = 660 D [08/Dec/2017:11:24:09 +0500] [Job 9] prtMarkerSuppliesLevel.1.2 = 3900 D [08/Dec/2017:11:24:09 +0500] [Job 9] prtMarkerSuppliesLevel.1.3 = 3900 D [08/Dec/2017:11:24:09 +0500] [Job 9] prtMarkerSuppliesLevel.1.4 = 780 D [08/Dec/2017:11:24:09 +0500] [Job 9] End of messages D [08/Dec/2017:11:24:09 +0500] [Job 9] printer-state=3(idle) D [08/Dec/2017:11:24:09 +0500] [Job 9] printer-state-message="Filter failed" D [08/Dec/2017:11:24:09 +0500] [Job 9] printer-state-reasons=none D [08/Dec/2017:11:24:10 +0500] [Job 9] Unloading... D [08/Dec/2017:11:24:14 +0500] [Client 12] Accepted from localhost:56476 (IPv4) D [08/Dec/2017:11:24:14 +0500] [Client 12] Waiting for request. D [08/Dec/2017:11:24:14 +0500] [Client 12] GET /printers/HP_Color_Laserjet_3800_hpijs HTTP/1.1 D [08/Dec/2017:11:24:14 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files" D [08/Dec/2017:11:24:14 +0500] [Client 12] Read: status=200 D [08/Dec/2017:11:24:14 +0500] [Client 12] No authentication data provided. D [08/Dec/2017:11:24:14 +0500] [Client 12] Processing GET /printers/HP_Color_Laserjet_3800_hpijs D [08/Dec/2017:11:24:14 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[8] = "HOME=C:\\var\\temp" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[9] = "NLSPATH=C:\\MPTN\\MSG\\NLS\\%N;C:\\TCPIP\\msg\\ENUS850\\%N;" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@bluelion" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[13] = "TMPDIR=C:\\var\\temp" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[15] = "USER=root" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[16] = "UNIXROOT=C:" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[17] = "ETC=C:\\MPTN\\ETC" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[18] = "CUPS_MAX_MESSAGE=2047" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[19] = "CUPS_SERVER=\\socket\\cups.sock" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[20] = "CUPS_ENCRYPTION=IfRequested" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[21] = "IPP_PORT=631" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[22] = "LANG=en_US.UTF8" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[23] = "REDIRECT_STATUS=1" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[24] = "GATEWAY_INTERFACE=CGI/1.1" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[25] = "SERVER_NAME=localhost" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[26] = "SERVER_PORT=631" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[27] = "REMOTE_ADDR=127.0.0.1" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[28] = "REMOTE_HOST=localhost" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[29] = "SCRIPT_NAME=/printers/HP_Color_Laserjet_3800_hpijs" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[30] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/printers/HP_Color_Laserjet_3800_hpijs" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[31] = "PATH_INFO=/HP_Color_Laserjet_3800_hpijs" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[32] = "SERVER_PROTOCOL=HTTP/1.1" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[33] = "HTTP_COOKIE=org.cups.sid=41f45aede00677dfbe5c986711944887" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[34] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:45.0) Gecko/20100101 Firefox/45.0" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[35] = "REQUEST_METHOD=GET" D [08/Dec/2017:11:24:14 +0500] [CGI] envp[36] = "QUERY_STRING=" D [08/Dec/2017:11:24:14 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/printers.cgi (PID 95) I [08/Dec/2017:11:24:14 +0500] [Client 12] Started "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" (pid=95, file=16) D [08/Dec/2017:11:24:14 +0500] [Client 12] Waiting for CGI data. D [08/Dec/2017:11:24:14 +0500] [CGI] org.cups.sid cookie is \"41f45aede00677dfbe5c986711944887\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetVariable: SECTION=\"printers\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:14 +0500] [Client 7] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [08/Dec/2017:11:24:14 +0500] [Client 7] Closing connection. D [08/Dec/2017:11:24:14 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:24:14 +0500] [Client 13] Accepted from localhost (Domain) D [08/Dec/2017:11:24:14 +0500] [Client 13] Waiting for request. D [08/Dec/2017:11:24:14 +0500] [Client 13] POST / HTTP/1.1 D [08/Dec/2017:11:24:14 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:24:14 +0500] [Client 13] Read: status=200 D [08/Dec/2017:11:24:14 +0500] [Client 13] No authentication data provided. D [08/Dec/2017:11:24:14 +0500] [Client 13] 2.0 CUPS-Get-Default 1 D [08/Dec/2017:11:24:14 +0500] CUPS-Get-Default D [08/Dec/2017:11:24:14 +0500] CUPS-Get-Default client-error-not-found: No default printer. D [08/Dec/2017:11:24:14 +0500] [Client 13] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost D [08/Dec/2017:11:24:14 +0500] [Client 13] Content-Length: 113 D [08/Dec/2017:11:24:14 +0500] [Client 13] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [08/Dec/2017:11:24:14 +0500] [Client 13] con->http=0x201f7040 D [08/Dec/2017:11:24:14 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x2005cb20(IPP_STATE_DATA), pipe_pid=0, file=-1 D [08/Dec/2017:11:24:14 +0500] [Client 13] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [08/Dec/2017:11:24:14 +0500] [Client 13] bytes=0, http_state=0, data_remaining=113 D [08/Dec/2017:11:24:14 +0500] [Client 13] Flushing write buffer. D [08/Dec/2017:11:24:14 +0500] [Client 13] New state is HTTP_STATE_WAITING D [08/Dec/2017:11:24:14 +0500] [Client 13] Waiting for request. D [08/Dec/2017:11:24:14 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:24:14 +0500] [CGI] show_printer(http=0x2003c3c0, printer=\"HP_Color_Laserjet_3800_hpijs\") D [08/Dec/2017:11:24:14 +0500] [Client 13] POST / HTTP/1.1 D [08/Dec/2017:11:24:14 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:24:14 +0500] [Client 13] Read: status=200 D [08/Dec/2017:11:24:14 +0500] [Client 13] No authentication data provided. D [08/Dec/2017:11:24:14 +0500] [Client 13] 2.0 Get-Printer-Attributes 2 D [08/Dec/2017:11:24:14 +0500] Get-Printer-Attributes ipp://localhost/printers/HP_Color_Laserjet_3800_hpijs D [08/Dec/2017:11:24:14 +0500] [Client 13] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_Color_Laserjet_3800_hpijs) from localhost D [08/Dec/2017:11:24:14 +0500] [Client 13] Content-Length: 756 D [08/Dec/2017:11:24:14 +0500] [Client 13] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [08/Dec/2017:11:24:14 +0500] [Client 13] con->http=0x201f7040 D [08/Dec/2017:11:24:14 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=756, response=0x2011ea40(IPP_STATE_DATA), pipe_pid=0, file=-1 D [08/Dec/2017:11:24:14 +0500] [Client 13] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [08/Dec/2017:11:24:14 +0500] [Client 13] bytes=0, http_state=0, data_remaining=756 D [08/Dec/2017:11:24:14 +0500] [Client 13] Flushing write buffer. D [08/Dec/2017:11:24:14 +0500] [Client 13] New state is HTTP_STATE_WAITING D [08/Dec/2017:11:24:14 +0500] [Client 13] Waiting for request. D [08/Dec/2017:11:24:14 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: printer_is_shared[0]=\"1\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: printer_state[0]=\"3\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: printer_name[0]=\"HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: printer_location[0]=\"office\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: printer_info[0]=\"HP Color LaserJet 3800 hpijs pcl3\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: device_uri[0]=\"socket://192.168.100.16\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: color_supported[0]=\"1\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: printer_make_and_model[0]=\"HP Color LaserJet 3800 hpijs pcl3, 3.16.11\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: media_default[0]=\"na_letter_8.5x11in\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: sides_supported[0]=\"one-sided, two-sided-long-edge, two-sided-short-edge\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: sides_default[0]=\"one-sided\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: printer_commands[0]=\"AutoConfigure, Clean, PrintSelfTestPage\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"0\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetVariable: TITLE=\"HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\" D [08/Dec/2017:11:24:14 +0500] [Client 12] CGI data ready to be sent. D [08/Dec/2017:11:24:14 +0500] [Client 12] con->http=0x201fb3a0 D [08/Dec/2017:11:24:14 +0500] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=95, file=16 D [08/Dec/2017:11:24:14 +0500] [Client 12] Waiting for CGI data. D [08/Dec/2017:11:24:14 +0500] [Client 12] Script header: Content-Type: text/html;charset=utf-8 D [08/Dec/2017:11:24:14 +0500] [Client 12] Script header: D [08/Dec/2017:11:24:14 +0500] [Client 12] Sending status 200 for CGI. D [08/Dec/2017:11:24:14 +0500] [Client 12] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [08/Dec/2017:11:24:14 +0500] [Client 13] POST / HTTP/1.1 D [08/Dec/2017:11:24:14 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:24:14 +0500] [Client 13] Read: status=200 D [08/Dec/2017:11:24:14 +0500] [Client 13] No authentication data provided. D [08/Dec/2017:11:24:14 +0500] [Client 13] 2.0 Get-Jobs 3 D [08/Dec/2017:11:24:14 +0500] Get-Jobs ipp://localhost:631/printers/HP_Color_Laserjet_3800_hpijs D [08/Dec/2017:11:24:14 +0500] [Job 9] Loading attributes... D [08/Dec/2017:11:24:14 +0500] [Client 13] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HP_Color_Laserjet_3800_hpijs) from localhost D [08/Dec/2017:11:24:14 +0500] [Client 13] Content-Length: 435 D [08/Dec/2017:11:24:14 +0500] [Client 13] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [08/Dec/2017:11:24:14 +0500] [Client 12] con->http=0x201fb3a0 D [08/Dec/2017:11:24:14 +0500] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=95, file=16 D [08/Dec/2017:11:24:14 +0500] [Client 12] Waiting for CGI data. D [08/Dec/2017:11:24:14 +0500] [Client 12] CGI data ready to be sent. D [08/Dec/2017:11:24:14 +0500] [Client 13] con->http=0x201f7040 D [08/Dec/2017:11:24:14 +0500] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=435, response=0x201fe4a0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [08/Dec/2017:11:24:14 +0500] [Client 13] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [08/Dec/2017:11:24:14 +0500] [Client 13] bytes=0, http_state=0, data_remaining=435 D [08/Dec/2017:11:24:14 +0500] [Client 13] Flushing write buffer. D [08/Dec/2017:11:24:14 +0500] [Client 13] New state is HTTP_STATE_WAITING D [08/Dec/2017:11:24:14 +0500] [Client 13] Waiting for request. D [08/Dec/2017:11:24:14 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:24:14 +0500] [Client 12] con->http=0x201fb3a0 D [08/Dec/2017:11:24:14 +0500] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=95, file=16 D [08/Dec/2017:11:24:14 +0500] [Client 12] Waiting for CGI data. D [08/Dec/2017:11:24:14 +0500] [CGI] Regular expression \".*Clean.*\" D [08/Dec/2017:11:24:14 +0500] [CGI] matches[0].rm_so=0 D [08/Dec/2017:11:24:14 +0500] [CGI] matches[1].rm_so=-1 D [08/Dec/2017:11:24:14 +0500] [CGI] Regular expression \".*PrintSelfTestPage.*\" D [08/Dec/2017:11:24:14 +0500] [CGI] matches[0].rm_so=0 D [08/Dec/2017:11:24:14 +0500] [CGI] matches[1].rm_so=-1 D [08/Dec/2017:11:24:14 +0500] [Client 12] con->http=0x201fb3a0 D [08/Dec/2017:11:24:14 +0500] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=95, file=16 D [08/Dec/2017:11:24:14 +0500] [Client 12] Waiting for CGI data. D [08/Dec/2017:11:24:14 +0500] [CGI] cgiClearVariables called. D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetVariable: ORDER=\"asc\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetVariable: SECTION=\"printers\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetVariable: TOTAL=\"1\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: job_preserved[0]=\"1\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: job_printer_name[0]=\"HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: job_printer_uri[0]=\"/printers/HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: job_name[0]=\"Test Page\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: job_originating_user_name[0]=\"anonymous\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: time_at_completed[0]=\"novalue\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: time_at_creation[0]=\"Fri Dec 8 11:23:59 EST 2017\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: time_at_processing[0]=\"Fri Dec 8 11:23:59 EST 2017\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: job_id[0]=\"9\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: job_state[0]=\"6\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: job_media_sheets_completed[0]=\"1\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: job_k_octets[0]=\"1\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetArray: job_printer_state_message[0]=\"Filter failed\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetVariable: THISURL=\"/printers/HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:14 +0500] [CGI] cgiSetVariable: SEARCH_DEST=\"HP_Color_Laserjet_3800_hpijs\" D [08/Dec/2017:11:24:14 +0500] [Client 12] CGI data ready to be sent. D [08/Dec/2017:11:24:14 +0500] [Client 12] con->http=0x201fb3a0 D [08/Dec/2017:11:24:14 +0500] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=95, file=16 D [08/Dec/2017:11:24:14 +0500] [Client 12] Waiting for CGI data. D [08/Dec/2017:11:24:14 +0500] [Client 12] con->http=0x201fb3a0 D [08/Dec/2017:11:24:14 +0500] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=95, file=16 D [08/Dec/2017:11:24:14 +0500] [Client 12] Waiting for CGI data. D [08/Dec/2017:11:24:14 +0500] [Client 13] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [08/Dec/2017:11:24:14 +0500] [Client 13] Closing connection. D [08/Dec/2017:11:24:14 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:24:14 +0500] [Client 12] CGI data ready to be sent. D [08/Dec/2017:11:24:14 +0500] [Client 12] con->http=0x201fb3a0 D [08/Dec/2017:11:24:14 +0500] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=95, file=16 D [08/Dec/2017:11:24:14 +0500] [Client 12] Waiting for CGI data. D [08/Dec/2017:11:24:14 +0500] [Client 12] con->http=0x201fb3a0 D [08/Dec/2017:11:24:14 +0500] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=95, file=16 D [08/Dec/2017:11:24:14 +0500] [Client 12] Waiting for CGI data. D [08/Dec/2017:11:24:14 +0500] [Client 12] CGI data ready to be sent. D [08/Dec/2017:11:24:14 +0500] [Client 12] con->http=0x201fb3a0 D [08/Dec/2017:11:24:14 +0500] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=95, file=16 D [08/Dec/2017:11:24:14 +0500] [Client 12] Waiting for CGI data. D [08/Dec/2017:11:24:14 +0500] [Client 12] Sending 0-length chunk. D [08/Dec/2017:11:24:14 +0500] [Client 12] Flushing write buffer. D [08/Dec/2017:11:24:14 +0500] [Client 12] New state is HTTP_STATE_WAITING D [08/Dec/2017:11:24:14 +0500] [Client 12] Waiting for request. D [08/Dec/2017:11:24:14 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" W [08/Dec/2017:11:24:14 +0500] cupsdDoSelect: select() returned -1... pause and retry select() D [08/Dec/2017:11:24:15 +0500] [Client 12] GET /cups.css HTTP/1.1 D [08/Dec/2017:11:24:15 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [08/Dec/2017:11:24:15 +0500] [Client 12] Read: status=200 D [08/Dec/2017:11:24:15 +0500] [Client 12] No authentication data provided. D [08/Dec/2017:11:24:15 +0500] [Client 12] Processing GET /cups.css D [08/Dec/2017:11:24:15 +0500] [Client 12] filename="/@unixroot/usr/share/doc/cups/cups.css", type=text/css D [08/Dec/2017:11:24:15 +0500] [Client 12] cupsdSendHeader: code=304, type="(null)", auth_type=0 D [08/Dec/2017:11:24:15 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [08/Dec/2017:11:24:15 +0500] PID 95 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [08/Dec/2017:11:24:25 +0500] [Client 12] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [08/Dec/2017:11:24:25 +0500] [Client 12] Closing connection. D [08/Dec/2017:11:24:25 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" I [08/Dec/2017:11:24:30 +0500] Saving printers.conf... I [08/Dec/2017:11:24:30 +0500] Saving job.cache... D [08/Dec/2017:11:24:30 +0500] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files" D [08/Dec/2017:11:24:30 +0500] Report: clients=0 D [08/Dec/2017:11:24:30 +0500] Report: jobs=3 D [08/Dec/2017:11:24:30 +0500] Report: jobs-active=2 D [08/Dec/2017:11:24:30 +0500] Report: printers=3 D [08/Dec/2017:11:24:30 +0500] Report: stringpool-string-count=20697 D [08/Dec/2017:11:24:30 +0500] Report: stringpool-alloc-bytes=12328 D [08/Dec/2017:11:24:30 +0500] Report: stringpool-total-bytes=386552