Go to:
Gentoo Home
Documentation
Forums
Lists
Bugs
Planet
Store
Wiki
Get Gentoo!
Gentoo's Bugzilla – Attachment 384128 Details for
Bug 521996
net-print/hplip-3.14.1 - HP Laserjet M1212nf fails to print: cgiSetArray: job_printer_state_message[0]="Filter failed"
Home
|
New
–
[Ex]
|
Browse
|
Search
|
Privacy Policy
|
[?]
|
Reports
|
Requests
|
Help
|
New Account
|
Log In
[x]
|
Forgot Password
Login:
[x]
Log of errors
cups_error_log (text/plain), 116.42 KB, created by
Yuriy Rusinov
on 2014-09-02 19:00:23 UTC
(
hide
)
Description:
Log of errors
Filename:
MIME Type:
Creator:
Yuriy Rusinov
Created:
2014-09-02 19:00:23 UTC
Size:
116.42 KB
patch
obsolete
>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"
<b>You cannot view the attachment while viewing its details because your browser does not support IFRAMEs. <a href="attachment.cgi?id=384128">View the attachment on a separate page</a>.</b>
View Attachment As Raw
Actions:
View
Attachments on
bug 521996
: 384128
