I [01/Sep/2014:23:12:48 +0400] Listening to [v1.::1]:631 (IPv6)
I [01/Sep/2014:23:12:48 +0400] Listening to 127.0.0.1:631 (IPv4)
I [01/Sep/2014:23:12:48 +0400] Listening to /run/cups/cups.sock (Domain)
I [01/Sep/2014:23:12:48 +0400] Remote access is disabled.
D [01/Sep/2014:23:12:48 +0400] Added auto ServerAlias Gentoo
I [01/Sep/2014:23:12:48 +0400] Loaded configuration file "/etc/cups/cupsd.conf"
I [01/Sep/2014:23:12:48 +0400] Using default TempDir of /var/spool/cups/tmp...
I [01/Sep/2014:23:12:48 +0400] Configured for up to 100 clients.
I [01/Sep/2014:23:12:48 +0400] Allowing up to 100 client connections per host.
I [01/Sep/2014:23:12:48 +0400] Using policy "default" as the default.
I [01/Sep/2014:23:12:48 +0400] Full reload is required.
I [01/Sep/2014:23:12:48 +0400] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 40 types, 57 filters...
D [01/Sep/2014:23:12:48 +0400] Loading printer HP_LaserJet_Professional_M1212nf_MFP...
D [01/Sep/2014:23:12:48 +0400] load_ppd: Loading /var/cache/cups/HP_LaserJet_Professional_M1212nf_MFP.data...
D [01/Sep/2014:23:12:48 +0400] cupsdRegisterPrinter(p=0x7f2f4df41970(HP_LaserJet_Professional_M1212nf_MFP))
D [01/Sep/2014:23:12:48 +0400] Loading printer HP_LaserJet_Professional_M1212nf_MFP_fax...
D [01/Sep/2014:23:12:48 +0400] load_ppd: Loading /var/cache/cups/HP_LaserJet_Professional_M1212nf_MFP_fax.data...
D [01/Sep/2014:23:12:48 +0400] cupsdRegisterPrinter(p=0x7f2f4df36560(HP_LaserJet_Professional_M1212nf_MFP_fax))
I [01/Sep/2014:23:12:48 +0400] Loading job cache file "/var/cache/cups/job.cache"...
D [01/Sep/2014:23:12:48 +0400] [Job 2516] Loading from cache...
D [01/Sep/2014:23:12:48 +0400] [Job 2516] Loading attributes...
D [01/Sep/2014:23:12:48 +0400] [Job 2517] Loading from cache...
D [01/Sep/2014:23:12:48 +0400] [Job 2517] Loading attributes...
D [01/Sep/2014:23:12:48 +0400] [Job 2518] Loading from cache...
D [01/Sep/2014:23:12:48 +0400] [Job 2518] Loading attributes...
I [01/Sep/2014:23:12:48 +0400] Full reload complete.
D [01/Sep/2014:23:12:48 +0400] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
I [01/Sep/2014:23:12:48 +0400] Cleaning out old files in "/var/spool/cups/tmp".
D [01/Sep/2014:23:12:48 +0400] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
I [01/Sep/2014:23:12:48 +0400] Cleaning out old files in "/var/cache/cups".
D [01/Sep/2014:23:12:48 +0400] Calling FindDeviceById(cups-HP_LaserJet_Professional_M1212nf_MFP)
D [01/Sep/2014:23:12:48 +0400] FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [01/Sep/2014:23:12:48 +0400] Using profile ID "HP_LaserJet_Professional_M1212nf_MFP-Gray..".
D [01/Sep/2014:23:12:48 +0400] Calling CreateProfile(HP_LaserJet_Professional_M1212nf_MFP-Gray..,temp)
W [01/Sep/2014:23:12:48 +0400] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [01/Sep/2014:23:12:48 +0400] Using profile ID "HP_LaserJet_Professional_M1212nf_MFP-RGB..".
D [01/Sep/2014:23:12:48 +0400] Calling CreateProfile(HP_LaserJet_Professional_M1212nf_MFP-RGB..,temp)
W [01/Sep/2014:23:12:48 +0400] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
I [01/Sep/2014:23:12:48 +0400] Registering ICC color profiles for "HP_LaserJet_Professional_M1212nf_MFP".
D [01/Sep/2014:23:12:48 +0400] Calling CreateDevice(cups-HP_LaserJet_Professional_M1212nf_MFP,temp)
W [01/Sep/2014:23:12:48 +0400] CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [01/Sep/2014:23:12:48 +0400] Calling FindDeviceById(cups-HP_LaserJet_Professional_M1212nf_MFP_fax)
D [01/Sep/2014:23:12:48 +0400] FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [01/Sep/2014:23:12:48 +0400] Using profile ID "HP_LaserJet_Professional_M1212nf_MFP_fax-Gray..".
D [01/Sep/2014:23:12:48 +0400] Calling CreateProfile(HP_LaserJet_Professional_M1212nf_MFP_fax-Gray..,temp)
W [01/Sep/2014:23:12:48 +0400] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [01/Sep/2014:23:12:48 +0400] Using profile ID "HP_LaserJet_Professional_M1212nf_MFP_fax-RGB..".
D [01/Sep/2014:23:12:48 +0400] Calling CreateProfile(HP_LaserJet_Professional_M1212nf_MFP_fax-RGB..,temp)
W [01/Sep/2014:23:12:48 +0400] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
I [01/Sep/2014:23:12:48 +0400] Registering ICC color profiles for "HP_LaserJet_Professional_M1212nf_MFP_fax".
D [01/Sep/2014:23:12:48 +0400] Calling CreateDevice(cups-HP_LaserJet_Professional_M1212nf_MFP_fax,temp)
W [01/Sep/2014:23:12:48 +0400] CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
E [01/Sep/2014:23:12:48 +0400] Unable to open listen socket for address [v1.::1]:631 - Address family not supported by protocol.
I [01/Sep/2014:23:12:48 +0400] Listening to 127.0.0.1:631 on fd 9...
I [01/Sep/2014:23:12:48 +0400] Listening to /run/cups/cups.sock:631 on fd 10...
I [01/Sep/2014:23:12:48 +0400] Resuming new connection processing...
D [01/Sep/2014:23:12:48 +0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [01/Sep/2014:23:12:48 +0400] Discarding unused server-started event...
D [01/Sep/2014:23:12:49 +0400] Report: clients=0
D [01/Sep/2014:23:12:49 +0400] Report: jobs=3
D [01/Sep/2014:23:12:49 +0400] Report: jobs-active=3
D [01/Sep/2014:23:12:49 +0400] Report: printers=2
D [01/Sep/2014:23:12:49 +0400] Report: stringpool-string-count=1360
D [01/Sep/2014:23:12:49 +0400] Report: stringpool-alloc-bytes=10000
D [01/Sep/2014:23:12:49 +0400] Report: stringpool-total-bytes=26664
D [01/Sep/2014:23:13:02 +0400] [Client 13] Accepted from localhost:631 (IPv4)
D [01/Sep/2014:23:13:02 +0400] [Client 13] Waiting for request.
D [01/Sep/2014:23:13:02 +0400] [Client 13] POST /jobs/ HTTP/1.1
D [01/Sep/2014:23:13:02 +0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [01/Sep/2014:23:13:02 +0400] [Client 13] Authorized as root using Basic
D [01/Sep/2014:23:13:02 +0400] [CGI] argv[0] = "/usr/libexec/cups/cgi-bin/jobs.cgi"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/html"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/libexec/cups"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[7] = "CUPS_STATEDIR=/run/cups"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[9] = "PATH=/usr/libexec/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[10] = "SERVER_ADMIN=root@Gentoo"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[11] = "SOFTWARE=CUPS/1.7.3"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[13] = "USER=root"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[15] = "CUPS_SERVER=/run/cups/cups.sock"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[17] = "IPP_PORT=631"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[18] = "AUTH_TYPE=Basic"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[19] = "LANG=en_US.UTF8"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[22] = "SERVER_NAME=localhost"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[23] = "SERVER_PORT=631"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[24] = "REMOTE_ADDR=127.0.0.1"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[25] = "REMOTE_HOST=localhost"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[26] = "SCRIPT_NAME=/jobs/"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/html/jobs/"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[28] = "REMOTE_USER=root"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[30] = "HTTP_COOKIE=org.cups.sid=c6bc2377d1a6270f59b93e3824a02478"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/37.0.2062.68 Safari/537.36"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[32] = "HTTP_REFERER=http://localhost:631/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[33] = "REQUEST_METHOD=POST"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[34] = "CONTENT_LENGTH=139"
D [01/Sep/2014:23:13:02 +0400] [CGI] envp[35] = "CONTENT_TYPE=application/x-www-form-urlencoded"
D [01/Sep/2014:23:13:02 +0400] [CGI] Started /usr/libexec/cups/cgi-bin/jobs.cgi (PID 29273)
I [01/Sep/2014:23:13:02 +0400] [Client 13] Started "/usr/libexec/cups/cgi-bin/jobs.cgi" (pid=29273)
D [01/Sep/2014:23:13:02 +0400] [Client 13] file=15
D [01/Sep/2014:23:13:02 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:03 +0400] [CGI] org.cups.sid cookie is "c6bc2377d1a6270f59b93e3824a02478"
D [01/Sep/2014:23:13:03 +0400] [CGI] cgiSetVariable: org.cups.sid="c6bc2377d1a6270f59b93e3824a02478"
D [01/Sep/2014:23:13:03 +0400] [CGI] cgiSetVariable: OP="restart-job"
D [01/Sep/2014:23:13:03 +0400] [CGI] cgiSetVariable: job_id="2516"
D [01/Sep/2014:23:13:03 +0400] [CGI] cgiSetVariable: job_printer_uri="/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:03 +0400] [CGI] cgiSetVariable: SECTION="jobs"
D [01/Sep/2014:23:13:03 +0400] [CGI] cgiSetVariable: REFRESH_PAGE=""
D [01/Sep/2014:23:13:03 +0400] [Client 14] Accepted from localhost (Domain)
D [01/Sep/2014:23:13:03 +0400] [Client 14] Waiting for request.
D [01/Sep/2014:23:13:03 +0400] [Client 14] POST /jobs HTTP/1.1
D [01/Sep/2014:23:13:03 +0400] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [01/Sep/2014:23:13:03 +0400] [Client 14] No authentication data provided.
D [01/Sep/2014:23:13:03 +0400] [Client 14] 2.0 Restart-Job 1
D [01/Sep/2014:23:13:03 +0400] Restart-Job ipp://localhost/jobs/2516
D [01/Sep/2014:23:13:03 +0400] cupsdIsAuthorized: requesting-user-name="root"
D [01/Sep/2014:23:13:03 +0400] Discarding unused job-state-changed event...
I [01/Sep/2014:23:13:03 +0400] [Job 2516] Job restarted by user.
D [01/Sep/2014:23:13:03 +0400] cupsdMarkDirty(---J-)
D [01/Sep/2014:23:13:03 +0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [01/Sep/2014:23:13:03 +0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] time-at-processing=1409598783
D [01/Sep/2014:23:13:03 +0400] cupsdMarkDirty(---J-)
D [01/Sep/2014:23:13:03 +0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [01/Sep/2014:23:13:03 +0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [01/Sep/2014:23:13:03 +0400] Discarding unused printer-state-changed event...
D [01/Sep/2014:23:13:03 +0400] [Job 2516] 4 filters for job:
D [01/Sep/2014:23:13:03 +0400] [Job 2516] pstopdf (application/postscript to application/pdf, cost 0)
D [01/Sep/2014:23:13:03 +0400] [Job 2516] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)
D [01/Sep/2014:23:13:03 +0400] [Job 2516] gstoraster (application/vnd.cups-pdf to application/vnd.cups-raster, cost 99)
D [01/Sep/2014:23:13:03 +0400] [Job 2516] hpcups (application/vnd.cups-raster to printer/HP_LaserJet_Professional_M1212nf_MFP, cost 0)
D [01/Sep/2014:23:13:03 +0400] [Job 2516] job-sheets=none,none
D [01/Sep/2014:23:13:03 +0400] [Job 2516] argv[0]="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] argv[1]="2516"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] argv[2]="root"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] argv[3]="tmpuOM77s"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] argv[4]="1"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:12620d24-4db2-3da0-4018-a8d4cc6fb2cd job-originating-host-name=localhost time-at-creation=1409513503 time-at-processing=1409598783"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] argv[6]="/var/spool/cups/d02516-001"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[2]="CUPS_DOCROOT=/usr/share/cups/html"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[5]="CUPS_SERVERBIN=/usr/libexec/cups"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[7]="CUPS_STATEDIR=/run/cups"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[8]="HOME=/var/spool/cups/tmp"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[9]="PATH=/usr/libexec/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[10]="SERVER_ADMIN=root@Gentoo"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[11]="SOFTWARE=CUPS/1.7.3"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[13]="USER=root"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[14]="CUPS_MAX_MESSAGE=2047"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[15]="CUPS_SERVER=/run/cups/cups.sock"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[17]="IPP_PORT=631"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[18]="CHARSET=utf-8"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[19]="LANG=en.UTF-8"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[20]="PPD=/etc/cups/ppd/HP_LaserJet_Professional_M1212nf_MFP.ppd"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[21]="RIP_MAX_CACHE=128m"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[22]="CONTENT_TYPE=application/postscript"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[23]="DEVICE_URI=hp:/net/HP_LaserJet_Professional_M1212nf_MFP?ip=192.168.10.103"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[24]="PRINTER_INFO=HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[25]="PRINTER_LOCATION="
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[26]="PRINTER=HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[27]="PRINTER_STATE_REASONS=none"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[28]="CUPS_FILETYPE=document"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-raster"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] envp[30]="AUTH_I****"
I [01/Sep/2014:23:13:03 +0400] [Job 2516] Started filter /usr/libexec/cups/filter/pstopdf (PID 29274)
I [01/Sep/2014:23:13:03 +0400] [Job 2516] Started filter /usr/libexec/cups/filter/pdftopdf (PID 29275)
I [01/Sep/2014:23:13:03 +0400] [Job 2516] Started filter /usr/libexec/cups/filter/gstoraster (PID 29276)
I [01/Sep/2014:23:13:03 +0400] [Job 2516] Started filter /usr/libexec/cups/filter/hpcups (PID 29277)
I [01/Sep/2014:23:13:03 +0400] [Job 2516] Started backend /usr/libexec/cups/backend/hp (PID 29278)
D [01/Sep/2014:23:13:03 +0400] Discarding unused job-state-changed event...
I [01/Sep/2014:23:13:03 +0400] [Job 2516] Restarted by "root".
D [01/Sep/2014:23:13:03 +0400] [Client 14] Returning IPP successful-ok for Restart-Job (ipp://localhost/jobs/2516) from localhost
D [01/Sep/2014:23:13:03 +0400] [Client 14] Content-Length: 75
D [01/Sep/2014:23:13:03 +0400] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f2f4df8c6f0(IPP_IDLE), pipe_pid=0, file=-1
D [01/Sep/2014:23:13:03 +0400] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [01/Sep/2014:23:13:03 +0400] [Client 14] bytes=0, http_state=0, data_remaining=0
D [01/Sep/2014:23:13:03 +0400] [Client 14] Waiting for request.
D [01/Sep/2014:23:13:03 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
D [01/Sep/2014:23:13:03 +0400] [CGI] cgiSetVariable: refresh_page="5;URL=http://localhost:631/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:03 +0400] [CGI] cgiSetVariable: TITLE="Jobs"
D [01/Sep/2014:23:13:03 +0400] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [01/Sep/2014:23:13:03 +0400] [CGI] cgiSetVariable: REMOTE_USER="root"
D [01/Sep/2014:23:13:03 +0400] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.3"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] pstopdf 6 args: 2516 root tmpuOM77s 1 finishings=3 number-up=1 job-uuid=urn:uuid:12620d24-4db2-3da0-4018-a8d4cc6fb2cd job-originating-host-name=localhost time-at-creation=1409513503 time-at-processing=1409598783 /var/spool/cups/d02516-001
D [01/Sep/2014:23:13:03 +0400] [Job 2516] PPD: /etc/cups/ppd/HP_LaserJet_Professional_M1212nf_MFP.ppd
D [01/Sep/2014:23:13:03 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:03 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, response=(nil)(), pipe_pid=29273, file=15
D [01/Sep/2014:23:13:03 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:03 +0400] Script header: Content-Type: text/html;charset=utf-8
D [01/Sep/2014:23:13:03 +0400] Script header: 
D [01/Sep/2014:23:13:03 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29273, file=15
D [01/Sep/2014:23:13:03 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:03 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:03 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29273, file=15
D [01/Sep/2014:23:13:03 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:03 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29273, file=15
D [01/Sep/2014:23:13:03 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:03 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:03 +0400] [Client 14] HTTP_STATE_WAITING Closing on EOF
D [01/Sep/2014:23:13:03 +0400] [Client 14] Closing connection.
D [01/Sep/2014:23:13:03 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:03 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29273, file=15
D [01/Sep/2014:23:13:03 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:03 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29273, file=15
D [01/Sep/2014:23:13:03 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:03 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:03 +0400] PID 29273 (/usr/libexec/cups/cgi-bin/jobs.cgi) exited with no errors.
D [01/Sep/2014:23:13:03 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29273, file=15
D [01/Sep/2014:23:13:03 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:03 +0400] [Client 13] Waiting for request.
D [01/Sep/2014:23:13:03 +0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:03 +0400] [Job 2516] Resolution: 
D [01/Sep/2014:23:13:03 +0400] [Job 2516] Orientation requested: 0
D [01/Sep/2014:23:13:04 +0400] [Job 2516] Page size: A4
D [01/Sep/2014:23:13:04 +0400] [Job 2516] Width: 595.440002441406, height: 841.679992675781, absolute margins: 18, 15.5, 577.440002441406, 826.179992675781
D [01/Sep/2014:23:13:04 +0400] [Job 2516] Relative margins: 18, 15.5, 18.000000000000, 15.500000000000
D [01/Sep/2014:23:13:04 +0400] [Job 2516] PPD options: -dDEVICEWIDTHPOINTS=595.440002441406 -dDEVICEHEIGHTPOINTS=841.679992675781
D [01/Sep/2014:23:13:04 +0400] [Job 2516] PostScript to be injected: <</.HWMargins[18 15.5 18.000000000000 15.500000000000] /Margins[0 0]>>setpagedevice
D [01/Sep/2014:23:13:04 +0400] [Job 2516] Running cat | /usr/bin/gs -q -dNOPAUSE -dBATCH -sDEVICE=pdfwrite -dCompatibilityLevel=1.3 -dAutoRotatePages=/None -dAutoFilterColorImages=false                -dNOPLATFONTS -dPARANOIDSAFER -dNOINTERPOLATE -sstdout=%stderr -dColorImageFilter=/FlateEncode                 -dPDFSETTINGS=/printer -dUseCIEColor                 -dColorConversionStrategy=/LeaveColorUnchanged -dDoNumCopies -dDEVICEWIDTHPOINTS=595.440002441406 -dDEVICEHEIGHTPOINTS=841.679992675781 -sOutputFile=-  -c <</.HWMargins[18 15.5 18.000000000000 15.500000000000] /Margins[0 0]>>setpagedevice .setpdfwrite -f -
D [01/Sep/2014:23:13:05 +0400] [Client 13] GET /cups.css HTTP/1.1
D [01/Sep/2014:23:13:05 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [01/Sep/2014:23:13:05 +0400] [Client 13] Authorized as root using Basic
D [01/Sep/2014:23:13:05 +0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:05 +0400] [Client 14] Accepted from localhost:631 (IPv4)
D [01/Sep/2014:23:13:05 +0400] [Client 14] Waiting for request.
D [01/Sep/2014:23:13:05 +0400] [Client 14] GET /images/left.gif HTTP/1.1
D [01/Sep/2014:23:13:05 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [01/Sep/2014:23:13:05 +0400] [Client 14] Authorized as root using Basic
D [01/Sep/2014:23:13:05 +0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:05 +0400] [Client 15] Accepted from localhost:631 (IPv4)
D [01/Sep/2014:23:13:05 +0400] [Client 15] Waiting for request.
D [01/Sep/2014:23:13:05 +0400] [Client 15] GET /images/right.gif HTTP/1.1
D [01/Sep/2014:23:13:05 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [01/Sep/2014:23:13:05 +0400] [Client 15] Authorized as root using Basic
D [01/Sep/2014:23:13:05 +0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:11 +0400] [Client 15] GET /printers/HP_LaserJet_Professional_M1212nf_MFP HTTP/1.1
D [01/Sep/2014:23:13:11 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [01/Sep/2014:23:13:11 +0400] [Client 15] Authorized as root using Basic
D [01/Sep/2014:23:13:11 +0400] [CGI] argv[0] = "/usr/libexec/cups/cgi-bin/printers.cgi"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/html"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/libexec/cups"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[7] = "CUPS_STATEDIR=/run/cups"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[9] = "PATH=/usr/libexec/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[10] = "SERVER_ADMIN=root@Gentoo"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[11] = "SOFTWARE=CUPS/1.7.3"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[13] = "USER=root"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[15] = "CUPS_SERVER=/run/cups/cups.sock"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[17] = "IPP_PORT=631"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[18] = "AUTH_TYPE=Basic"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[19] = "LANG=en_US.UTF8"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[22] = "SERVER_NAME=localhost"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[23] = "SERVER_PORT=631"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[24] = "REMOTE_ADDR=127.0.0.1"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[25] = "REMOTE_HOST=localhost"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[26] = "SCRIPT_NAME=/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/html/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[28] = "PATH_INFO=/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[29] = "REMOTE_USER=root"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[30] = "SERVER_PROTOCOL=HTTP/1.1"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[31] = "HTTP_COOKIE=org.cups.sid=c6bc2377d1a6270f59b93e3824a02478"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[32] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/37.0.2062.68 Safari/537.36"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[33] = "HTTP_REFERER=http://localhost:631/jobs/"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[34] = "REQUEST_METHOD=GET"
D [01/Sep/2014:23:13:11 +0400] [CGI] envp[35] = "QUERY_STRING="
D [01/Sep/2014:23:13:11 +0400] [CGI] Started /usr/libexec/cups/cgi-bin/printers.cgi (PID 29383)
I [01/Sep/2014:23:13:11 +0400] [Client 15] Started "/usr/libexec/cups/cgi-bin/printers.cgi" (pid=29383)
D [01/Sep/2014:23:13:11 +0400] [Client 15] file=17
D [01/Sep/2014:23:13:11 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:11 +0400] [CGI] org.cups.sid cookie is "c6bc2377d1a6270f59b93e3824a02478"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: SECTION="printers"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: REFRESH_PAGE=""
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: PRINTER_NAME="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:11 +0400] [Client 18] Accepted from localhost (Domain)
D [01/Sep/2014:23:13:11 +0400] [Client 18] Waiting for request.
D [01/Sep/2014:23:13:11 +0400] [Client 18] POST / HTTP/1.1
D [01/Sep/2014:23:13:11 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:11 +0400] [Client 18] No authentication data provided.
D [01/Sep/2014:23:13:11 +0400] [Client 18] 2.0 CUPS-Get-Default 1
D [01/Sep/2014:23:13:11 +0400] CUPS-Get-Default
D [01/Sep/2014:23:13:11 +0400] CUPS-Get-Default client-error-not-found: No default printer.
D [01/Sep/2014:23:13:11 +0400] [Client 18] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [01/Sep/2014:23:13:11 +0400] [Client 18] Content-Length: 113
D [01/Sep/2014:23:13:11 +0400] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x7f2f4df8d310(IPP_IDLE), pipe_pid=0, file=-1
D [01/Sep/2014:23:13:11 +0400] [Client 18] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [01/Sep/2014:23:13:11 +0400] [Client 18] bytes=0, http_state=0, data_remaining=0
D [01/Sep/2014:23:13:11 +0400] [Client 18] Waiting for request.
D [01/Sep/2014:23:13:11 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:11 +0400] [CGI] show_printer(http=0x7f3f63068020, printer="HP_LaserJet_Professional_M1212nf_MFP")
D [01/Sep/2014:23:13:11 +0400] [Client 18] POST / HTTP/1.1
D [01/Sep/2014:23:13:11 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:11 +0400] [Client 18] No authentication data provided.
D [01/Sep/2014:23:13:11 +0400] [Client 18] 2.0 Get-Printer-Attributes 2
D [01/Sep/2014:23:13:11 +0400] Get-Printer-Attributes ipp://localhost/printers/HP_LaserJet_Professional_M1212nf_MFP
D [01/Sep/2014:23:13:11 +0400] [Client 18] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_LaserJet_Professional_M1212nf_MFP) from localhost
D [01/Sep/2014:23:13:11 +0400] [Client 18] Content-Length: 758
D [01/Sep/2014:23:13:11 +0400] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=758, response=0x7f2f4df8d3a0(IPP_IDLE), pipe_pid=0, file=-1
D [01/Sep/2014:23:13:11 +0400] [Client 18] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [01/Sep/2014:23:13:11 +0400] [Client 18] bytes=0, http_state=0, data_remaining=0
D [01/Sep/2014:23:13:11 +0400] [Client 18] Waiting for request.
D [01/Sep/2014:23:13:11 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: REMOTE_USER="root"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.3"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: REMOTE_USER="root"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.3"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: printer_is_accepting_jobs[0]="1"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: printer_is_shared[0]="1"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: printer_state[0]="4"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: admin_uri[0]="/admin/"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: printer_uri_supported[0]="/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: printer_name[0]="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: printer_location[0]=""
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: printer_info[0]="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_sheets_default[0]="none, none"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: device_uri[0]="hp:/net/HP_LaserJet_Professional_M1212nf_MFP?ip=192.168.10.103"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: color_supported[0]="1"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: printer_make_and_model[0]="HP LaserJet Professional m1212nf MFP, hpcups 3.14.1, requires proprietary plugin"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: media_default[0]="iso_a4_210x297mm"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: sides_supported[0]="one-sided"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: sides_default[0]="one-sided"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: printer_commands[0]="none"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: server_is_sharing_printers[0]="0"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: refresh_page="10;URL=/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: TITLE="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: REMOTE_USER="root"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.3"
D [01/Sep/2014:23:13:11 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:11 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, response=(nil)(), pipe_pid=29383, file=17
D [01/Sep/2014:23:13:11 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:11 +0400] Script header: Content-Type: text/html;charset=utf-8
D [01/Sep/2014:23:13:11 +0400] Script header: 
D [01/Sep/2014:23:13:11 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29383, file=17
D [01/Sep/2014:23:13:11 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:11 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:11 +0400] [CGI] Regular expression ".*Clean.*"
D [01/Sep/2014:23:13:11 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29383, file=17
D [01/Sep/2014:23:13:11 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:11 +0400] [CGI] Regular expression ".*PrintSelfTestPage.*"
D [01/Sep/2014:23:13:11 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29383, file=17
D [01/Sep/2014:23:13:11 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:11 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:11 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29383, file=17
D [01/Sep/2014:23:13:11 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:11 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29383, file=17
D [01/Sep/2014:23:13:11 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:11 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:11 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29383, file=17
D [01/Sep/2014:23:13:11 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:11 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29383, file=17
D [01/Sep/2014:23:13:11 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:11 +0400] [Client 18] POST / HTTP/1.1
D [01/Sep/2014:23:13:11 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:11 +0400] [Client 18] No authentication data provided.
D [01/Sep/2014:23:13:11 +0400] [Client 18] 2.0 Get-Jobs 3
D [01/Sep/2014:23:13:11 +0400] Get-Jobs ipp://localhost:631/printers/HP_LaserJet_Professional_M1212nf_MFP
D [01/Sep/2014:23:13:11 +0400] [Client 18] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HP_LaserJet_Professional_M1212nf_MFP) from localhost
D [01/Sep/2014:23:13:11 +0400] [Client 18] Content-Length: 976
D [01/Sep/2014:23:13:11 +0400] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=976, response=0x7f2f4df8d310(IPP_IDLE), pipe_pid=0, file=-1
D [01/Sep/2014:23:13:11 +0400] [Client 18] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [01/Sep/2014:23:13:11 +0400] [Client 18] bytes=0, http_state=0, data_remaining=0
D [01/Sep/2014:23:13:11 +0400] [Client 18] Waiting for request.
D [01/Sep/2014:23:13:11 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiClearVariables called.
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: ORDER="asc"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: SECTION="printers"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: TOTAL="3"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: REMOTE_USER="root"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.3"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_printer_name[0]="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_printer_uri[0]="/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: time_at_creation[0]="Sun 31 Aug 2014 11:31:43 PM MSK"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: time_at_processing[0]="Mon 01 Sep 2014 11:13:03 PM MSK"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: time_at_completed[0]="novalue"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_id[0]="2516"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_state[0]="5"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_media_sheets_completed[0]="0"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_k_octets[0]="414"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_printer_state_message[0]=""
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: REMOTE_USER="root"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.3"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_preserved[1]="1"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_printer_name[1]="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_printer_uri[1]="/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: time_at_creation[1]="Mon 01 Sep 2014 09:35:38 PM MSK"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: time_at_processing[1]="Mon 01 Sep 2014 09:35:38 PM MSK"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: time_at_completed[1]="novalue"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_id[1]="2517"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_state[1]="6"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_media_sheets_completed[1]="0"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_k_octets[1]="21"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_printer_state_message[1]="Filter failed"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: REMOTE_USER="root"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.3"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_preserved[2]="1"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_printer_name[2]="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_printer_uri[2]="/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: time_at_creation[2]="Mon 01 Sep 2014 09:35:57 PM MSK"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: time_at_processing[2]="Mon 01 Sep 2014 09:35:57 PM MSK"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: time_at_completed[2]="novalue"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_id[2]="2518"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_state[2]="6"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_media_sheets_completed[2]="0"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_k_octets[2]="21"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetArray: job_printer_state_message[2]="Filter failed"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: PRINTER_NAME="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED="/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: THISURL="/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:11 +0400] [CGI] cgiSetVariable: SEARCH_DEST="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:11 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:11 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29383, file=17
D [01/Sep/2014:23:13:11 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:11 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29383, file=17
D [01/Sep/2014:23:13:11 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:11 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:11 +0400] [Client 18] HTTP_STATE_WAITING Closing on EOF
D [01/Sep/2014:23:13:11 +0400] [Client 18] Closing connection.
D [01/Sep/2014:23:13:11 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:11 +0400] PID 29383 (/usr/libexec/cups/cgi-bin/printers.cgi) exited with no errors.
D [01/Sep/2014:23:13:11 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29383, file=17
D [01/Sep/2014:23:13:11 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:11 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29383, file=17
D [01/Sep/2014:23:13:11 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:11 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:11 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29383, file=17
D [01/Sep/2014:23:13:11 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:11 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29383, file=17
D [01/Sep/2014:23:13:11 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:11 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:11 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29383, file=17
D [01/Sep/2014:23:13:11 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:11 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29383, file=17
D [01/Sep/2014:23:13:11 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:11 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:11 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29383, file=17
D [01/Sep/2014:23:13:11 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:11 +0400] [Client 15] Waiting for request.
D [01/Sep/2014:23:13:11 +0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] PID 29274 (/usr/libexec/cups/filter/pstopdf) exited with no errors.
D [01/Sep/2014:23:13:12 +0400] [Job 2516] Calling FindDeviceById(cups-HP_LaserJet_Professional_M1212nf_MFP)
D [01/Sep/2014:23:13:12 +0400] [Job 2516] Failed to send: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [01/Sep/2014:23:13:12 +0400] [Job 2516] Failed to get find device cups-HP_LaserJet_Professional_M1212nf_MFP
D [01/Sep/2014:23:13:12 +0400] [Job 2516] PPD uses qualifier 'Gray.Plain.'
D [01/Sep/2014:23:13:12 +0400] [Job 2516] Calling FindDeviceById(cups-HP_LaserJet_Professional_M1212nf_MFP)
D [01/Sep/2014:23:13:12 +0400] [Job 2516] Failed to send: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [01/Sep/2014:23:13:12 +0400] [Job 2516] Failed to get device cups-HP_LaserJet_Professional_M1212nf_MFP
I [01/Sep/2014:23:13:12 +0400] [Job 2516] no profiles specified in PPD
D [01/Sep/2014:23:13:12 +0400] cupsdMarkDirty(---J-)
D [01/Sep/2014:23:13:12 +0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] Set job-printer-state-message to "no profiles specified in PPD", current level=INFO
D [01/Sep/2014:23:13:12 +0400] [Job 2516] Ghostscript command line: /usr/bin/gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -sDEVICE=cups -sstdout=%stderr -sOutputFile=%stdout -sMediaType=Plain -sOutputType=0 -r600x600 -dMediaPosition=7 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=841 -dcupsBitsPerColor=1 -dcupsColorOrder=0 -dcupsColorSpace=3 -dcupsRowCount=1 -dcupsRowStep=2 -dcupsInteger0=9 -scupsPageSizeName=A4 -I/usr/share/cups/fonts -c '<</.HWMargins[18.000000 15.500000 18.000000 15.500000] /Margins[0 0]>>setpagedevice' -f -_
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[2]="CUPS_DOCROOT=/usr/share/cups/html"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[5]="CUPS_SERVERBIN=/usr/libexec/cups"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[7]="CUPS_STATEDIR=/run/cups"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[8]="HOME=/var/spool/cups/tmp"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[9]="PATH=/usr/libexec/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[10]="SERVER_ADMIN=root@Gentoo"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[11]="SOFTWARE=CUPS/1.7.3"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[13]="USER=root"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[14]="CUPS_MAX_MESSAGE=2047"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[15]="CUPS_SERVER=/run/cups/cups.sock"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[17]="IPP_PORT=631"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[18]="CHARSET=utf-8"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[19]="LANG=en.UTF-8"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[20]="PPD=/etc/cups/ppd/HP_LaserJet_Professional_M1212nf_MFP.ppd"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[21]="RIP_MAX_CACHE=128m"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[22]="CONTENT_TYPE=application/postscript"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[23]="DEVICE_URI=hp:/net/HP_LaserJet_Professional_M1212nf_MFP?ip=192.168.10.103"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[24]="PRINTER_INFO=HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[25]="PRINTER_LOCATION="
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[26]="PRINTER=HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[27]="PRINTER_STATE_REASONS=none"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[28]="CUPS_FILETYPE=document"
D [01/Sep/2014:23:13:12 +0400] Discarding unused job-progress event...
D [01/Sep/2014:23:13:12 +0400] Discarding unused printer-state-changed event...
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-raster"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] envp[30]="AUTH_INFO_REQUIRED=none"
D [01/Sep/2014:23:13:12 +0400] [Job 2516] PID 29275 (/usr/libexec/cups/filter/pdftopdf) exited with no errors.
I [01/Sep/2014:23:13:13 +0400] [Job 2516] Start rendering...
D [01/Sep/2014:23:13:13 +0400] cupsdMarkDirty(---J-)
D [01/Sep/2014:23:13:13 +0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [01/Sep/2014:23:13:13 +0400] [Job 2516] Set job-printer-state-message to "Start rendering...", current level=INFO
D [01/Sep/2014:23:13:13 +0400] Discarding unused job-progress event...
D [01/Sep/2014:23:13:13 +0400] Discarding unused printer-state-changed event...
I [01/Sep/2014:23:13:13 +0400] [Job 2516] Processing page 1...
D [01/Sep/2014:23:13:13 +0400] cupsdMarkDirty(---J-)
D [01/Sep/2014:23:13:13 +0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [01/Sep/2014:23:13:13 +0400] [Job 2516] Set job-printer-state-message to "Processing page 1...", current level=INFO
D [01/Sep/2014:23:13:13 +0400] Discarding unused job-progress event...
D [01/Sep/2014:23:13:13 +0400] Discarding unused printer-state-changed event...
D [01/Sep/2014:23:13:15 +0400] [Job 2516] STATE: +hplip.plugin-error
D [01/Sep/2014:23:13:15 +0400] cupsdMarkDirty(P----)
D [01/Sep/2014:23:13:15 +0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [01/Sep/2014:23:13:15 +0400] cupsdMarkDirty(---J-)
D [01/Sep/2014:23:13:15 +0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [01/Sep/2014:23:13:15 +0400] [Job 2516] prnt/hpcups/HPCupsFilter.cpp 456: m_Job initialization failed with error = 48
D [01/Sep/2014:23:13:15 +0400] [Job 2516] STATE: +connecting-to-device
D [01/Sep/2014:23:13:15 +0400] cupsdMarkDirty(---J-)
D [01/Sep/2014:23:13:15 +0400] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
D [01/Sep/2014:23:13:15 +0400] Discarding unused printer-state-changed event...
D [01/Sep/2014:23:13:15 +0400] [Job 2516] PID 29277 (/usr/libexec/cups/filter/hpcups) stopped with status 1.
I [01/Sep/2014:23:13:15 +0400] [Job 2516] Processing page 2...
D [01/Sep/2014:23:13:15 +0400] Discarding unused job-progress event...
D [01/Sep/2014:23:13:15 +0400] Discarding unused printer-state-changed event...
I [01/Sep/2014:23:13:15 +0400] [Job 2516] Rendering completed
D [01/Sep/2014:23:13:15 +0400] Discarding unused job-progress event...
D [01/Sep/2014:23:13:15 +0400] Discarding unused printer-state-changed event...
D [01/Sep/2014:23:13:15 +0400] [Job 2516] PID 29276 (/usr/libexec/cups/filter/gstoraster) exited with no errors.
D [01/Sep/2014:23:13:15 +0400] [Job 2516] STATE: -connecting-to-device
D [01/Sep/2014:23:13:15 +0400] cupsdMarkDirty(---J-)
D [01/Sep/2014:23:13:15 +0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
D [01/Sep/2014:23:13:15 +0400] [Job 2516] STATE: -media-empty-error,media-jam-error,hplip.plugin-error,cover-open-error,toner-empty-error,other
D [01/Sep/2014:23:13:15 +0400] cupsdMarkDirty(P----)
D [01/Sep/2014:23:13:15 +0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [01/Sep/2014:23:13:15 +0400] cupsdMarkDirty(---J-)
D [01/Sep/2014:23:13:15 +0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [01/Sep/2014:23:13:15 +0400] Discarding unused printer-state-changed event...
I [01/Sep/2014:23:13:23 +0400] [Job 2516] ready to print
D [01/Sep/2014:23:13:23 +0400] Discarding unused job-progress event...
D [01/Sep/2014:23:13:23 +0400] Discarding unused printer-state-changed event...
D [01/Sep/2014:23:13:24 +0400] [Client 15] GET /printers/HP_LaserJet_Professional_M1212nf_MFP HTTP/1.1
D [01/Sep/2014:23:13:24 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [01/Sep/2014:23:13:24 +0400] [Client 15] Authorized as root using Basic
D [01/Sep/2014:23:13:24 +0400] [CGI] argv[0] = "/usr/libexec/cups/cgi-bin/printers.cgi"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/html"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/libexec/cups"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[7] = "CUPS_STATEDIR=/run/cups"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[9] = "PATH=/usr/libexec/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[10] = "SERVER_ADMIN=root@Gentoo"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[11] = "SOFTWARE=CUPS/1.7.3"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[13] = "USER=root"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[15] = "CUPS_SERVER=/run/cups/cups.sock"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[17] = "IPP_PORT=631"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[18] = "AUTH_TYPE=Basic"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[19] = "LANG=en_US.UTF8"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[22] = "SERVER_NAME=localhost"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[23] = "SERVER_PORT=631"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[24] = "REMOTE_ADDR=127.0.0.1"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[25] = "REMOTE_HOST=localhost"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[26] = "SCRIPT_NAME=/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/html/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[28] = "PATH_INFO=/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[29] = "REMOTE_USER=root"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[30] = "SERVER_PROTOCOL=HTTP/1.1"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[31] = "HTTP_COOKIE=org.cups.sid=c6bc2377d1a6270f59b93e3824a02478"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[32] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/37.0.2062.68 Safari/537.36"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[33] = "HTTP_REFERER=http://localhost:631/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[34] = "REQUEST_METHOD=GET"
D [01/Sep/2014:23:13:24 +0400] [CGI] envp[35] = "QUERY_STRING="
D [01/Sep/2014:23:13:24 +0400] [CGI] Started /usr/libexec/cups/cgi-bin/printers.cgi (PID 29432)
I [01/Sep/2014:23:13:24 +0400] [Client 15] Started "/usr/libexec/cups/cgi-bin/printers.cgi" (pid=29432)
D [01/Sep/2014:23:13:24 +0400] [Client 15] file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [CGI] org.cups.sid cookie is "c6bc2377d1a6270f59b93e3824a02478"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: SECTION="printers"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: REFRESH_PAGE=""
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: PRINTER_NAME="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:24 +0400] [Client 18] Accepted from localhost (Domain)
D [01/Sep/2014:23:13:24 +0400] [Client 18] Waiting for request.
D [01/Sep/2014:23:13:24 +0400] [Client 18] POST / HTTP/1.1
D [01/Sep/2014:23:13:24 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:24 +0400] [Client 18] No authentication data provided.
D [01/Sep/2014:23:13:24 +0400] [Client 18] 2.0 CUPS-Get-Default 1
D [01/Sep/2014:23:13:24 +0400] CUPS-Get-Default
D [01/Sep/2014:23:13:24 +0400] CUPS-Get-Default client-error-not-found: No default printer.
D [01/Sep/2014:23:13:24 +0400] [Client 18] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [01/Sep/2014:23:13:24 +0400] [Client 18] Content-Length: 113
D [01/Sep/2014:23:13:24 +0400] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x7f2f4df8d3d0(IPP_IDLE), pipe_pid=0, file=-1
D [01/Sep/2014:23:13:24 +0400] [Client 18] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [01/Sep/2014:23:13:24 +0400] [Client 18] bytes=0, http_state=0, data_remaining=0
D [01/Sep/2014:23:13:24 +0400] [Client 18] Waiting for request.
D [01/Sep/2014:23:13:24 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:24 +0400] [CGI] show_printer(http=0x7f346942c020, printer="HP_LaserJet_Professional_M1212nf_MFP")
D [01/Sep/2014:23:13:24 +0400] [Client 18] POST / HTTP/1.1
D [01/Sep/2014:23:13:24 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:24 +0400] [Client 18] No authentication data provided.
D [01/Sep/2014:23:13:24 +0400] [Client 18] 2.0 Get-Printer-Attributes 2
D [01/Sep/2014:23:13:24 +0400] Get-Printer-Attributes ipp://localhost/printers/HP_LaserJet_Professional_M1212nf_MFP
D [01/Sep/2014:23:13:24 +0400] [Client 18] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_LaserJet_Professional_M1212nf_MFP) from localhost
D [01/Sep/2014:23:13:24 +0400] [Client 18] Content-Length: 758
D [01/Sep/2014:23:13:24 +0400] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=758, response=0x7f2f4df8c6b0(IPP_IDLE), pipe_pid=0, file=-1
D [01/Sep/2014:23:13:24 +0400] [Client 18] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [01/Sep/2014:23:13:24 +0400] [Client 18] bytes=0, http_state=0, data_remaining=0
D [01/Sep/2014:23:13:24 +0400] [Client 18] Waiting for request.
D [01/Sep/2014:23:13:24 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: REMOTE_USER="root"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.3"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: REMOTE_USER="root"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.3"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: printer_is_accepting_jobs[0]="1"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: printer_is_shared[0]="1"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: printer_state[0]="4"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: admin_uri[0]="/admin/"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: printer_uri_supported[0]="/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: printer_name[0]="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: printer_location[0]=""
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: printer_info[0]="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_sheets_default[0]="none, none"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: device_uri[0]="hp:/net/HP_LaserJet_Professional_M1212nf_MFP?ip=192.168.10.103"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: color_supported[0]="1"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: printer_make_and_model[0]="HP LaserJet Professional m1212nf MFP, hpcups 3.14.1, requires proprietary plugin"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: media_default[0]="iso_a4_210x297mm"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: sides_supported[0]="one-sided"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: sides_default[0]="one-sided"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: printer_commands[0]="none"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: server_is_sharing_printers[0]="0"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: refresh_page="10;URL=/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: TITLE="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: REMOTE_USER="root"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.3"
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] Script header: Content-Type: text/html;charset=utf-8
D [01/Sep/2014:23:13:24 +0400] Script header: 
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [CGI] Regular expression ".*Clean.*"
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [CGI] Regular expression ".*PrintSelfTestPage.*"
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 18] POST / HTTP/1.1
D [01/Sep/2014:23:13:24 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:24 +0400] [Client 18] No authentication data provided.
D [01/Sep/2014:23:13:24 +0400] [Client 18] 2.0 Get-Jobs 3
D [01/Sep/2014:23:13:24 +0400] Get-Jobs ipp://localhost:631/printers/HP_LaserJet_Professional_M1212nf_MFP
D [01/Sep/2014:23:13:24 +0400] [Client 18] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HP_LaserJet_Professional_M1212nf_MFP) from localhost
D [01/Sep/2014:23:13:24 +0400] [Client 18] Content-Length: 989
D [01/Sep/2014:23:13:24 +0400] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=989, response=0x7f2f4df981d0(IPP_IDLE), pipe_pid=0, file=-1
D [01/Sep/2014:23:13:24 +0400] [Client 18] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [01/Sep/2014:23:13:24 +0400] [Client 18] bytes=0, http_state=0, data_remaining=0
D [01/Sep/2014:23:13:24 +0400] [Client 18] Waiting for request.
D [01/Sep/2014:23:13:24 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiClearVariables called.
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: ORDER="asc"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: SECTION="printers"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: TOTAL="3"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: REMOTE_USER="root"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.3"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_printer_name[0]="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_printer_uri[0]="/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: time_at_creation[0]="Sun 31 Aug 2014 11:31:43 PM MSK"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: time_at_processing[0]="Mon 01 Sep 2014 11:13:03 PM MSK"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: time_at_completed[0]="novalue"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_id[0]="2516"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_state[0]="5"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_media_sheets_completed[0]="0"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_k_octets[0]="414"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_printer_state_message[0]="Filter failed"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: REMOTE_USER="root"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.3"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_preserved[1]="1"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_printer_name[1]="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_printer_uri[1]="/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: time_at_creation[1]="Mon 01 Sep 2014 09:35:38 PM MSK"
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: time_at_processing[1]="Mon 01 Sep 2014 09:35:38 PM MSK"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: time_at_completed[1]="novalue"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_id[1]="2517"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_state[1]="6"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_media_sheets_completed[1]="0"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_k_octets[1]="21"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_printer_state_message[1]="Filter failed"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: REMOTE_USER="root"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.3"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_preserved[2]="1"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_printer_name[2]="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_printer_uri[2]="/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: time_at_creation[2]="Mon 01 Sep 2014 09:35:57 PM MSK"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: time_at_processing[2]="Mon 01 Sep 2014 09:35:57 PM MSK"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: time_at_completed[2]="novalue"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_id[2]="2518"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_state[2]="6"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_media_sheets_completed[2]="0"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_k_octets[2]="21"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetArray: job_printer_state_message[2]="Filter failed"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: PRINTER_NAME="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED="/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: THISURL="/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:24 +0400] [CGI] cgiSetVariable: SEARCH_DEST="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] PID 29432 (/usr/libexec/cups/cgi-bin/printers.cgi) exited with no errors.
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [Client 18] HTTP_STATE_WAITING Closing on EOF
D [01/Sep/2014:23:13:24 +0400] [Client 18] Closing connection.
D [01/Sep/2014:23:13:24 +0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] CGI data ready to be sent.
D [01/Sep/2014:23:13:24 +0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29432, file=17
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for CGI data.
D [01/Sep/2014:23:13:24 +0400] [Client 15] Waiting for request.
D [01/Sep/2014:23:13:24 +0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [01/Sep/2014:23:13:25 +0400] [Job 2516] PID 29278 (/usr/libexec/cups/backend/hp) exited with no errors.
D [01/Sep/2014:23:13:25 +0400] Discarding unused job-state-changed event...
E [01/Sep/2014:23:13:25 +0400] [Job 2516] Job stopped due to filter errors; please consult the error_log file for details.
D [01/Sep/2014:23:13:25 +0400] cupsdMarkDirty(---J-)
D [01/Sep/2014:23:13:25 +0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [01/Sep/2014:23:13:25 +0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [01/Sep/2014:23:13:25 +0400] Discarding unused printer-state-changed event...
D [01/Sep/2014:23:13:25 +0400] [Client 15] GET /cups.css HTTP/1.1
D [01/Sep/2014:23:13:25 +0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"
D [01/Sep/2014:23:13:26 +0400] [Client 15] Authorized as root using Basic
D [01/Sep/2014:23:13:26 +0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [01/Sep/2014:23:13:26 +0400] [Client 14] GET /images/left.gif HTTP/1.1
D [01/Sep/2014:23:13:26 +0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [01/Sep/2014:23:13:26 +0400] [Client 14] Authorized as root using Basic
D [01/Sep/2014:23:13:26 +0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [01/Sep/2014:23:13:26 +0400] [Client 13] GET /images/right.gif HTTP/1.1
D [01/Sep/2014:23:13:26 +0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [01/Sep/2014:23:13:26 +0400] [Client 13] Authorized as root using Basic
D [01/Sep/2014:23:13:26 +0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [01/Sep/2014:23:13:34 +0400] Saving printers.conf...
I [01/Sep/2014:23:13:34 +0400] Saving job.cache...
D [01/Sep/2014:23:13:34 +0400] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [01/Sep/2014:23:13:36 +0400] [Client 13] GET /printers/HP_LaserJet_Professional_M1212nf_MFP HTTP/1.1
D [01/Sep/2014:23:13:36 +0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [01/Sep/2014:23:13:36 +0400] [Client 13] Authorized as root using Basic
D [01/Sep/2014:23:13:36 +0400] [CGI] argv[0] = "/usr/libexec/cups/cgi-bin/printers.cgi"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/html"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/libexec/cups"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[7] = "CUPS_STATEDIR=/run/cups"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[9] = "PATH=/usr/libexec/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[10] = "SERVER_ADMIN=root@Gentoo"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[11] = "SOFTWARE=CUPS/1.7.3"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[13] = "USER=root"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[15] = "CUPS_SERVER=/run/cups/cups.sock"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[17] = "IPP_PORT=631"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[18] = "AUTH_TYPE=Basic"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[19] = "LANG=en_US.UTF8"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[22] = "SERVER_NAME=localhost"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[23] = "SERVER_PORT=631"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[24] = "REMOTE_ADDR=127.0.0.1"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[25] = "REMOTE_HOST=localhost"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[26] = "SCRIPT_NAME=/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/html/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[28] = "PATH_INFO=/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[29] = "REMOTE_USER=root"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[30] = "SERVER_PROTOCOL=HTTP/1.1"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[31] = "HTTP_COOKIE=org.cups.sid=c6bc2377d1a6270f59b93e3824a02478"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[32] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/37.0.2062.68 Safari/537.36"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[33] = "HTTP_REFERER=http://localhost:631/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[34] = "REQUEST_METHOD=GET"
D [01/Sep/2014:23:13:36 +0400] [CGI] envp[35] = "QUERY_STRING="
D [01/Sep/2014:23:13:36 +0400] [CGI] Started /usr/libexec/cups/cgi-bin/printers.cgi (PID 29487)
I [01/Sep/2014:23:13:36 +0400] [Client 13] Started "/usr/libexec/cups/cgi-bin/printers.cgi" (pid=29487)
D [01/Sep/2014:23:13:36 +0400] [Client 13] file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [CGI] org.cups.sid cookie is "c6bc2377d1a6270f59b93e3824a02478"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: SECTION="printers"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: REFRESH_PAGE=""
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: PRINTER_NAME="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:36 +0400] [Client 17] Accepted from localhost (Domain)
D [01/Sep/2014:23:13:36 +0400] [Client 17] Waiting for request.
D [01/Sep/2014:23:13:36 +0400] [Client 17] POST / HTTP/1.1
D [01/Sep/2014:23:13:36 +0400] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [01/Sep/2014:23:13:36 +0400] [Client 17] No authentication data provided.
D [01/Sep/2014:23:13:36 +0400] [Client 17] 2.0 CUPS-Get-Default 1
D [01/Sep/2014:23:13:36 +0400] CUPS-Get-Default
D [01/Sep/2014:23:13:36 +0400] CUPS-Get-Default client-error-not-found: No default printer.
D [01/Sep/2014:23:13:36 +0400] [Client 17] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [01/Sep/2014:23:13:36 +0400] [Client 17] Content-Length: 113
D [01/Sep/2014:23:13:36 +0400] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x7f2f4df8c6b0(IPP_IDLE), pipe_pid=0, file=-1
D [01/Sep/2014:23:13:36 +0400] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [01/Sep/2014:23:13:36 +0400] [Client 17] bytes=0, http_state=0, data_remaining=0
D [01/Sep/2014:23:13:36 +0400] [Client 17] Waiting for request.
D [01/Sep/2014:23:13:36 +0400] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [01/Sep/2014:23:13:36 +0400] [CGI] show_printer(http=0x7fb6827e9020, printer="HP_LaserJet_Professional_M1212nf_MFP")
D [01/Sep/2014:23:13:36 +0400] [Client 17] POST / HTTP/1.1
D [01/Sep/2014:23:13:36 +0400] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [01/Sep/2014:23:13:36 +0400] [Client 17] No authentication data provided.
D [01/Sep/2014:23:13:36 +0400] [Client 17] 2.0 Get-Printer-Attributes 2
D [01/Sep/2014:23:13:36 +0400] Get-Printer-Attributes ipp://localhost/printers/HP_LaserJet_Professional_M1212nf_MFP
D [01/Sep/2014:23:13:36 +0400] [Client 17] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_LaserJet_Professional_M1212nf_MFP) from localhost
D [01/Sep/2014:23:13:36 +0400] [Client 17] Content-Length: 758
D [01/Sep/2014:23:13:36 +0400] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=758, response=0x7f2f4df8c360(IPP_IDLE), pipe_pid=0, file=-1
D [01/Sep/2014:23:13:36 +0400] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [01/Sep/2014:23:13:36 +0400] [Client 17] bytes=0, http_state=0, data_remaining=0
D [01/Sep/2014:23:13:36 +0400] [Client 17] Waiting for request.
D [01/Sep/2014:23:13:36 +0400] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: REMOTE_USER="root"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.3"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: REMOTE_USER="root"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.3"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: printer_is_accepting_jobs[0]="1"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: printer_is_shared[0]="1"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: printer_state[0]="3"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: admin_uri[0]="/admin/"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: printer_uri_supported[0]="/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: printer_name[0]="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: printer_location[0]=""
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: printer_info[0]="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_sheets_default[0]="none, none"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: device_uri[0]="hp:/net/HP_LaserJet_Professional_M1212nf_MFP?ip=192.168.10.103"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: color_supported[0]="1"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: printer_make_and_model[0]="HP LaserJet Professional m1212nf MFP, hpcups 3.14.1, requires proprietary plugin"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: media_default[0]="iso_a4_210x297mm"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: sides_supported[0]="one-sided"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: sides_default[0]="one-sided"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: printer_commands[0]="none"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: server_is_sharing_printers[0]="0"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: TITLE="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: REMOTE_USER="root"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.3"
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] Script header: Content-Type: text/html;charset=utf-8
D [01/Sep/2014:23:13:36 +0400] Script header: 
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [CGI] Regular expression ".*Clean.*"
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [CGI] Regular expression ".*PrintSelfTestPage.*"
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 17] POST / HTTP/1.1
D [01/Sep/2014:23:13:36 +0400] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [01/Sep/2014:23:13:36 +0400] [Client 17] No authentication data provided.
D [01/Sep/2014:23:13:36 +0400] [Client 17] 2.0 Get-Jobs 3
D [01/Sep/2014:23:13:36 +0400] Get-Jobs ipp://localhost:631/printers/HP_LaserJet_Professional_M1212nf_MFP
D [01/Sep/2014:23:13:36 +0400] [Client 17] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HP_LaserJet_Professional_M1212nf_MFP) from localhost
D [01/Sep/2014:23:13:36 +0400] [Client 17] Content-Length: 1008
D [01/Sep/2014:23:13:36 +0400] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1008, response=0x7f2f4df8c6b0(IPP_IDLE), pipe_pid=0, file=-1
D [01/Sep/2014:23:13:36 +0400] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [01/Sep/2014:23:13:36 +0400] [Client 17] bytes=0, http_state=0, data_remaining=0
D [01/Sep/2014:23:13:36 +0400] [Client 17] Waiting for request.
D [01/Sep/2014:23:13:36 +0400] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiClearVariables called.
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: ORDER="asc"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: SECTION="printers"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: TOTAL="3"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: REMOTE_USER="root"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.3"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_preserved[0]="1"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_printer_name[0]="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_printer_uri[0]="/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: time_at_creation[0]="Sun 31 Aug 2014 11:31:43 PM MSK"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: time_at_processing[0]="Mon 01 Sep 2014 11:13:03 PM MSK"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: time_at_completed[0]="novalue"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_id[0]="2516"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_state[0]="6"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_media_sheets_completed[0]="0"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_k_octets[0]="414"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_printer_state_message[0]="Filter failed"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: REMOTE_USER="root"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.3"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_preserved[1]="1"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_printer_name[1]="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_printer_uri[1]="/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: time_at_creation[1]="Mon 01 Sep 2014 09:35:38 PM MSK"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: time_at_processing[1]="Mon 01 Sep 2014 09:35:38 PM MSK"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: time_at_completed[1]="novalue"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_id[1]="2517"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_state[1]="6"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_media_sheets_completed[1]="0"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_k_octets[1]="21"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_printer_state_message[1]="Filter failed"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: REMOTE_USER="root"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.3"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_preserved[2]="1"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_printer_name[2]="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_printer_uri[2]="/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: time_at_creation[2]="Mon 01 Sep 2014 09:35:57 PM MSK"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: time_at_processing[2]="Mon 01 Sep 2014 09:35:57 PM MSK"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: time_at_completed[2]="novalue"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_id[2]="2518"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_state[2]="6"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_media_sheets_completed[2]="0"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_k_octets[2]="21"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetArray: job_printer_state_message[2]="Filter failed"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: PRINTER_NAME="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED="/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: THISURL="/printers/HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:36 +0400] [CGI] cgiSetVariable: SEARCH_DEST="HP_LaserJet_Professional_M1212nf_MFP"
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 17] HTTP_STATE_WAITING Closing on EOF
D [01/Sep/2014:23:13:36 +0400] [Client 17] Closing connection.
D [01/Sep/2014:23:13:36 +0400] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [01/Sep/2014:23:13:36 +0400] PID 29487 (/usr/libexec/cups/cgi-bin/printers.cgi) exited with no errors.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] CGI data ready to be sent.
D [01/Sep/2014:23:13:36 +0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=29487, file=16
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for CGI data.
D [01/Sep/2014:23:13:36 +0400] [Client 13] Waiting for request.
D [01/Sep/2014:23:13:36 +0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [01/Sep/2014:23:13:38 +0400] [Client 13] GET /cups.css HTTP/1.1
D [01/Sep/2014:23:13:38 +0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [01/Sep/2014:23:13:38 +0400] [Client 13] Authorized as root using Basic
D [01/Sep/2014:23:13:38 +0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [01/Sep/2014:23:13:38 +0400] [Client 14] GET /images/left.gif HTTP/1.1
D [01/Sep/2014:23:13:38 +0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [01/Sep/2014:23:13:38 +0400] [Client 14] Authorized as root using Basic
D [01/Sep/2014:23:13:38 +0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [01/Sep/2014:23:13:38 +0400] [Client 13] GET /images/right.gif HTTP/1.1
D [01/Sep/2014:23:13:38 +0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [01/Sep/2014:23:13:38 +0400] [Client 13] Authorized as root using Basic
D [01/Sep/2014:23:13:38 +0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"