Gentoo Forums
Gentoo Forums
Gentoo Forums
Quick Search: in
Update of hplip driver
View unanswered posts
View posts from last 24 hours

 
Reply to topic    Gentoo Forums Forum Index Kernel & Hardware
View previous topic :: View next topic  
Author Message
YuriyRusinov
Apprentice
Apprentice


Joined: 21 Jul 2004
Posts: 208
Location: Saint-Petersburg, Russia

PostPosted: Sun Aug 31, 2014 8:13 pm    Post subject: Update of hplip driver Reply with quote

Hello, colleagues !

I have updated my hplip driver, emerge -pv hplip gives
Code:

[ebuild   R    ] net-print/hplip-3.14.1  USE="X fax hpcups kde libnotify qt4 scanner snmp -doc -hpijs -libusb0 -minimal -parport -policykit -static-ppds" PYTHON_SINGLE_TARGET="python2_7" PYTHON_TARGETS="python2_7" 0 kB

hp-setup -r , hp-setup -i 192.168.10.103 works fine, but test page does not print, my /var/log/cups/error_log is
Quote:

E [31/Aug/2014:23:55:39 +0400] [Job 2516] Stopping unresponsive job.
E [31/Aug/2014:23:55:56 +0400] [Job 2516] Job stopped due to filter errors; please consult the error_log file for details.
D [31/Aug/2014:23:55:56 +0400] [Job 2516] The following messages were recorded from 23:50:45 to 23:55:56
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Unloading...
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Loading attributes...
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Job restarted by user.
D [31/Aug/2014:23:55:56 +0400] [Job 2516] time-at-processing=1409514940
D [31/Aug/2014:23:55:56 +0400] [Job 2516] 4 filters for job:
D [31/Aug/2014:23:55:56 +0400] [Job 2516] pstopdf (application/postscript to application/pdf, cost 0)
D [31/Aug/2014:23:55:56 +0400] [Job 2516] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)
D [31/Aug/2014:23:55:56 +0400] [Job 2516] gstoraster (application/vnd.cups-pdf to application/vnd.cups-raster, cost 99)
D [31/Aug/2014:23:55:56 +0400] [Job 2516] hpcups (application/vnd.cups-raster to printer/HP_LaserJet_Professional_M1212nf_MFP, cost 0)
D [31/Aug/2014:23:55:56 +0400] [Job 2516] job-sheets=none,none
D [31/Aug/2014:23:55:56 +0400] [Job 2516] argv[0]="HP_LaserJet_Professional_M1212nf_MFP"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] argv[1]="2516"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] argv[2]="root"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] argv[3]="tmpuOM77s"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] argv[4]="1"
D [31/Aug/2014:23:55:56 +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=1409514940"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] argv[6]="/var/spool/cups/d02516-001"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[2]="CUPS_DOCROOT=/usr/share/cups/html"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[5]="CUPS_SERVERBIN=/usr/libexec/cups"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[7]="CUPS_STATEDIR=/run/cups"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[8]="HOME=/var/spool/cups/tmp"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[9]="PATH=/usr/libexec/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[10]="SERVER_ADMIN=root@Gentoo"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[11]="SOFTWARE=CUPS/1.7.3"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[13]="USER=root"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[14]="CUPS_MAX_MESSAGE=2047"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[15]="CUPS_SERVER=/run/cups/cups.sock"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[17]="IPP_PORT=631"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[18]="CHARSET=utf-8"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[19]="LANG=en.UTF-8"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[20]="PPD=/etc/cups/ppd/HP_LaserJet_Professional_M1212nf_MFP.ppd"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[21]="RIP_MAX_CACHE=128m"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[22]="CONTENT_TYPE=application/postscript"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[23]="DEVICE_URI=hp:/net/HP_LaserJet_Professional_M1212nf_MFP?ip=192.168.10.103"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[24]="PRINTER_INFO=HP_LaserJet_Professional_M1212nf_MFP"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[25]="PRINTER_LOCATION="
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[26]="PRINTER=HP_LaserJet_Professional_M1212nf_MFP"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[27]="PRINTER_STATE_REASONS=none"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[28]="CUPS_FILETYPE=document"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-raster"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[30]="AUTH_I****"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Started filter /usr/libexec/cups/filter/pstopdf (PID 8935)
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Started filter /usr/libexec/cups/filter/pdftopdf (PID 8936)
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Started filter /usr/libexec/cups/filter/gstoraster (PID 8937)
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Started filter /usr/libexec/cups/filter/hpcups (PID 8938)
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Started backend /usr/libexec/cups/backend/hp (PID 8939)
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Restarted by "root".
D [31/Aug/2014:23:55:56 +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=1409514940 /var/spool/cups/d02516-001
D [31/Aug/2014:23:55:56 +0400] [Job 2516] PPD: /etc/cups/ppd/HP_LaserJet_Professional_M1212nf_MFP.ppd
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Resolution:
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Orientation requested: 0
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Page size: A4
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Width: 595.440002441406, height: 841.679992675781, absolute margins: 18, 15.5, 577.440002441406, 826.179992675781
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Relative margins: 18, 15.5, 18.000000000000, 15.500000000000
D [31/Aug/2014:23:55:56 +0400] [Job 2516] PPD options: -dDEVICEWIDTHPOINTS=595.440002441406 -dDEVICEHEIGHTPOINTS=841.679992675781
D [31/Aug/2014:23:55:56 +0400] [Job 2516] PostScript to be injected: <</.HWMargins[18 15.5 18.000000000000 15.500000000000] /Margins[0 0]>>setpagedevice
D [31/Aug/2014:23:55:56 +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 [31/Aug/2014:23:55:56 +0400] [Job 2516] PID 8935 (/usr/libexec/cups/filter/pstopdf) exited with no errors.
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Calling FindDeviceById(cups-HP_LaserJet_Professional_M1212nf_MFP)
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Failed to send: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Failed to get find device cups-HP_LaserJet_Professional_M1212nf_MFP
D [31/Aug/2014:23:55:56 +0400] [Job 2516] PPD uses qualifier 'Gray.Plain.'
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Calling FindDeviceById(cups-HP_LaserJet_Professional_M1212nf_MFP)
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Failed to send: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Failed to get device cups-HP_LaserJet_Professional_M1212nf_MFP
D [31/Aug/2014:23:55:56 +0400] [Job 2516] no profiles specified in PPD
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Set job-printer-state-message to "no profiles specified in PPD", current level=INFO
D [31/Aug/2014:23:55:56 +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 [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[2]="CUPS_DOCROOT=/usr/share/cups/html"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[5]="CUPS_SERVERBIN=/usr/libexec/cups"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[7]="CUPS_STATEDIR=/run/cups"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[8]="HOME=/var/spool/cups/tmp"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[9]="PATH=/usr/libexec/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[10]="SERVER_ADMIN=root@Gentoo"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[11]="SOFTWARE=CUPS/1.7.3"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[13]="USER=root"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[14]="CUPS_MAX_MESSAGE=2047"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[15]="CUPS_SERVER=/run/cups/cups.sock"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[17]="IPP_PORT=631"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[18]="CHARSET=utf-8"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[19]="LANG=en.UTF-8"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[20]="PPD=/etc/cups/ppd/HP_LaserJet_Professional_M1212nf_MFP.ppd"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[21]="RIP_MAX_CACHE=128m"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[22]="CONTENT_TYPE=application/postscript"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[23]="DEVICE_URI=hp:/net/HP_LaserJet_Professional_M1212nf_MFP?ip=192.168.10.103"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[24]="PRINTER_INFO=HP_LaserJet_Professional_M1212nf_MFP"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[25]="PRINTER_LOCATION="
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[26]="PRINTER=HP_LaserJet_Professional_M1212nf_MFP"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[27]="PRINTER_STATE_REASONS=none"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[28]="CUPS_FILETYPE=document"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-raster"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] envp[30]="AUTH_INFO_REQUIRED=none"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] PID 8936 (/usr/libexec/cups/filter/pdftopdf) exited with no errors.
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Start rendering...
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Set job-printer-state-message to "Start rendering...", current level=INFO
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Processing page 1...
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Set job-printer-state-message to "Processing page 1...", current level=INFO
D [31/Aug/2014:23:55:56 +0400] [Job 2516] STATE: +hplip.plugin-error
D [31/Aug/2014:23:55:56 +0400] [Job 2516] prnt/hpcups/HPCupsFilter.cpp 456: m_Job initialization failed with error = 48
D [31/Aug/2014:23:55:56 +0400] [Job 2516] STATE: +connecting-to-device
D [31/Aug/2014:23:55:56 +0400] [Job 2516] PID 8938 (/usr/libexec/cups/filter/hpcups) stopped with status 1.
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Hint: Try setting the LogLevel to "debug" to find out more.
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Processing page 2...
D [31/Aug/2014:23:55:56 +0400] [Job 2516] STATE: -connecting-to-device
D [31/Aug/2014:23:55:56 +0400] [Job 2516] STATE: -media-empty-error,media-jam-error,hplip.plugin-error,cover-open-error,toner-empty-error,other
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Rendering completed
D [31/Aug/2014:23:55:56 +0400] [Job 2516] PID 8937 (/usr/libexec/cups/filter/gstoraster) exited with no errors.
D [31/Aug/2014:23:55:56 +0400] [Job 2516] ready to print
D [31/Aug/2014:23:55:56 +0400] [Job 2516] PID 8939 (/usr/libexec/cups/backend/hp) exited with no errors.
D [31/Aug/2014:23:55:56 +0400] [Job 2516] End of messages
D [31/Aug/2014:23:55:56 +0400] [Job 2516] printer-state=3(idle)
D [31/Aug/2014:23:55:56 +0400] [Job 2516] printer-state-message="ready to print"
D [31/Aug/2014:23:55:56 +0400] [Job 2516] printer-state-reasons=none
E [01/Sep/2014:00:00:57 +0400] [Job 2516] Stopping unresponsive job.
,
hp-plugin works without errors, but job stops with "Filter failed".

Are there any ideas ?
_________________
Best regards,
Yuriy Rusinov.
Back to top
View user's profile Send private message
NeddySeagoon
Administrator
Administrator


Joined: 05 Jul 2003
Posts: 43195
Location: 56N 3W

PostPosted: Sun Aug 31, 2014 8:25 pm    Post subject: Reply with quote

YuriyRusinov,

Code:
D [31/Aug/2014:23:55:56 +0400] [Job 2516] PID 8938 (/usr/libexec/cups/filter/hpcups) stopped with status 1.
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Hint: Try setting the LogLevel to "debug" to find out more.


stop cupsd
delete your cups log
take the hint above
start cupsd

Put the entire log onto a pastebin.
Restore your previous loglevel and restart cupsd
_________________
Regards,

NeddySeagoon

Computer users fall into two groups:-
those that do backups
those that have never had a hard drive fail.
Back to top
View user's profile Send private message
YuriyRusinov
Apprentice
Apprentice


Joined: 21 Jul 2004
Posts: 208
Location: Saint-Petersburg, Russia

PostPosted: Mon Sep 01, 2014 7:23 pm    Post subject: Reply with quote

Hello !

NeddySeagoon wrote:
YuriyRusinov,

Code:
D [31/Aug/2014:23:55:56 +0400] [Job 2516] PID 8938 (/usr/libexec/cups/filter/hpcups) stopped with status 1.
D [31/Aug/2014:23:55:56 +0400] [Job 2516] Hint: Try setting the LogLevel to "debug" to find out more.


stop cupsd
delete your cups log
take the hint above
start cupsd

Put the entire log onto a pastebin.
Restore your previous loglevel and restart cupsd


I did it, here it is
Quote:

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_USE
_________________
Best regards,
Yuriy Rusinov.
Back to top
View user's profile Send private message
YuriyRusinov
Apprentice
Apprentice


Joined: 21 Jul 2004
Posts: 208
Location: Saint-Petersburg, Russia

PostPosted: Tue Sep 02, 2014 7:35 am    Post subject: Reply with quote

And what this, is this a bug or I make something wrong ?
_________________
Best regards,
Yuriy Rusinov.
Back to top
View user's profile Send private message
Display posts from previous:   
Reply to topic    Gentoo Forums Forum Index Kernel & Hardware All times are GMT
Page 1 of 1

 
Jump to:  
You cannot post new topics in this forum
You cannot reply to topics in this forum
You cannot edit your posts in this forum
You cannot delete your posts in this forum
You cannot vote in polls in this forum