I'm using A4 plain paper in single sided mode. Output from logging follows: D [12/Oct/2009:02:02:03 +1030] cupsdAcceptClient: 12 from localhost (Domain) D [12/Oct/2009:02:02:04 +1030] Report: clients=1 D [12/Oct/2009:02:02:04 +1030] Report: jobs=28 D [12/Oct/2009:02:02:04 +1030] Report: jobs-active=0 D [12/Oct/2009:02:02:04 +1030] Report: printers=1 D [12/Oct/2009:02:02:04 +1030] Report: printers-implicit=0 D [12/Oct/2009:02:02:04 +1030] Report: stringpool-string-count=348 D [12/Oct/2009:02:02:04 +1030] Report: stringpool-alloc-bytes=7440 D [12/Oct/2009:02:02:04 +1030] Report: stringpool-total-bytes=8168 D [12/Oct/2009:02:02:04 +1030] cupsdReadClient: 12 POST / HTTP/1.1 D [12/Oct/2009:02:02:04 +1030] cupsdSetBusyState: Active clients D [12/Oct/2009:02:02:04 +1030] cupsdAuthorize: No authentication data provided. D [12/Oct/2009:02:02:04 +1030] cupsdReadClient: 12 1.1 CUPS-Get-Printers 1 D [12/Oct/2009:02:02:04 +1030] CUPS-Get-Printers D [12/Oct/2009:02:02:04 +1030] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [12/Oct/2009:02:02:04 +1030] cupsdSetBusyState: Not busy D [12/Oct/2009:02:02:04 +1030] cupsdReadClient: 12 POST / HTTP/1.1 D [12/Oct/2009:02:02:04 +1030] cupsdSetBusyState: Active clients D [12/Oct/2009:02:02:04 +1030] cupsdAuthorize: No authentication data provided. D [12/Oct/2009:02:02:04 +1030] cupsdReadClient: 12 1.1 CUPS-Get-Default 1 D [12/Oct/2009:02:02:04 +1030] CUPS-Get-Default D [12/Oct/2009:02:02:04 +1030] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [12/Oct/2009:02:02:04 +1030] cupsdSetBusyState: Not busy D [12/Oct/2009:02:02:04 +1030] cupsdReadClient: 12 WAITING Closing on EOF D [12/Oct/2009:02:02:04 +1030] cupsdCloseClient: 12 D [12/Oct/2009:02:15:08 +1030] cupsdAcceptClient: 12 from localhost (Domain) D [12/Oct/2009:02:15:08 +1030] Report: clients=1 D [12/Oct/2009:02:15:08 +1030] Report: jobs=28 D [12/Oct/2009:02:15:08 +1030] Report: jobs-active=0 D [12/Oct/2009:02:15:08 +1030] Report: printers=1 D [12/Oct/2009:02:15:08 +1030] Report: printers-implicit=0 D [12/Oct/2009:02:15:08 +1030] Report: stringpool-string-count=348 D [12/Oct/2009:02:15:08 +1030] Report: stringpool-alloc-bytes=7440 D [12/Oct/2009:02:15:08 +1030] Report: stringpool-total-bytes=8168 D [12/Oct/2009:02:15:08 +1030] cupsdReadClient: 12 POST / HTTP/1.1 D [12/Oct/2009:02:15:08 +1030] cupsdSetBusyState: Active clients D [12/Oct/2009:02:15:08 +1030] cupsdAuthorize: No authentication data provided. D [12/Oct/2009:02:15:08 +1030] cupsdReadClient: 12 1.1 CUPS-Get-Printers 1 D [12/Oct/2009:02:15:08 +1030] CUPS-Get-Printers D [12/Oct/2009:02:15:08 +1030] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [12/Oct/2009:02:15:08 +1030] cupsdSetBusyState: Not busy D [12/Oct/2009:02:15:08 +1030] cupsdReadClient: 12 POST / HTTP/1.1 D [12/Oct/2009:02:15:08 +1030] cupsdSetBusyState: Active clients D [12/Oct/2009:02:15:08 +1030] cupsdAuthorize: No authentication data provided. D [12/Oct/2009:02:15:08 +1030] cupsdReadClient: 12 1.1 CUPS-Get-Default 1 D [12/Oct/2009:02:15:08 +1030] CUPS-Get-Default D [12/Oct/2009:02:15:08 +1030] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [12/Oct/2009:02:15:08 +1030] cupsdSetBusyState: Not busy D [12/Oct/2009:02:15:10 +1030] cupsdReadClient: 12 POST / HTTP/1.1 D [12/Oct/2009:02:15:10 +1030] cupsdSetBusyState: Active clients D [12/Oct/2009:02:15:10 +1030] cupsdAuthorize: No authentication data provided. D [12/Oct/2009:02:15:10 +1030] cupsdReadClient: 12 1.1 CUPS-Get-Printers 1 D [12/Oct/2009:02:15:10 +1030] CUPS-Get-Printers D [12/Oct/2009:02:15:10 +1030] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [12/Oct/2009:02:15:10 +1030] cupsdSetBusyState: Not busy D [12/Oct/2009:02:15:10 +1030] cupsdReadClient: 12 POST / HTTP/1.1 D [12/Oct/2009:02:15:10 +1030] cupsdSetBusyState: Active clients D [12/Oct/2009:02:15:10 +1030] cupsdAuthorize: No authentication data provided. D [12/Oct/2009:02:15:10 +1030] cupsdReadClient: 12 1.1 CUPS-Get-Default 1 D [12/Oct/2009:02:15:10 +1030] CUPS-Get-Default D [12/Oct/2009:02:15:10 +1030] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [12/Oct/2009:02:15:10 +1030] cupsdSetBusyState: Not busy D [12/Oct/2009:02:15:28 +1030] cupsdReadClient: 12 POST /printers/Officejet_Pro_ 8000_A809 HTTP/1.1 D [12/Oct/2009:02:15:28 +1030] cupsdSetBusyState: Active clients D [12/Oct/2009:02:15:28 +1030] cupsdAuthorize: No authentication data provided. D [12/Oct/2009:02:15:28 +1030] cupsdReadClient: 12 1.1 Create-Job 1 D [12/Oct/2009:02:15:28 +1030] Create-Job ipp://localhost:631/printers/Officejet _Pro_8000_A809 D [12/Oct/2009:02:15:28 +1030] cupsdMarkDirty(----J-) D [12/Oct/2009:02:15:28 +1030] cupsdSetBusyState: Active clients and dirty files D [12/Oct/2009:02:15:28 +1030] add_job: requesting-user-name="amarsh04" D [12/Oct/2009:02:15:28 +1030] Adding default job-sheets values "none,none"... I [12/Oct/2009:02:15:28 +1030] [Job 1293] Adding start banner page "none". D [12/Oct/2009:02:15:29 +1030] Discarding unused job-created event... I [12/Oct/2009:02:15:29 +1030] [Job 1293] Queued on "Officejet_Pro_8000_A809" by "amarsh04". D [12/Oct/2009:02:15:29 +1030] Returning IPP successful-ok for Create-Job (ipp:/ /localhost:631/printers/Officejet_Pro_8000_A809) from localhost D [12/Oct/2009:02:15:29 +1030] cupsdSetBusyState: Dirty files D [12/Oct/2009:02:15:29 +1030] cupsdReadClient: 12 POST /printers/Officejet_Pro_ 8000_A809 HTTP/1.1 D [12/Oct/2009:02:15:29 +1030] cupsdSetBusyState: Active clients and dirty files D [12/Oct/2009:02:15:29 +1030] cupsdAuthorize: No authentication data provided. D [12/Oct/2009:02:15:29 +1030] cupsdReadClient: 12 1.1 Send-Document 1 D [12/Oct/2009:02:15:29 +1030] Send-Document ipp://localhost:631/printers/Office jet_Pro_8000_A809 D [12/Oct/2009:02:15:29 +1030] cupsdIsAuthorized: requesting-user-name="amarsh04 " D [12/Oct/2009:02:15:29 +1030] [Job 1293] Auto-typing file... D [12/Oct/2009:02:15:29 +1030] [Job 1293] Request file type is application/posts cript. D [12/Oct/2009:02:15:29 +1030] cupsdMarkDirty(----J-) I [12/Oct/2009:02:15:29 +1030] [Job 1293] File of type application/postscript qu eued by "amarsh04". I [12/Oct/2009:02:15:29 +1030] [Job 1293] Adding end banner page "none". D [12/Oct/2009:02:15:29 +1030] cupsdMarkDirty(----J-) D [12/Oct/2009:02:15:29 +1030] cupsdMarkDirty(----J-) D [12/Oct/2009:02:15:29 +1030] cupsdSetBusyState: Active clients, printing jobs, and dirty files D [12/Oct/2009:02:15:29 +1030] Discarding unused printer-state-changed event... D [12/Oct/2009:02:15:29 +1030] [Job 1293] job-sheets=none,none D [12/Oct/2009:02:15:29 +1030] [Job 1293] argv[0]="Officejet_Pro_8000_A809" D [12/Oct/2009:02:15:29 +1030] [Job 1293] argv[1]="1293" D [12/Oct/2009:02:15:29 +1030] [Job 1293] argv[2]="amarsh04" D [12/Oct/2009:02:15:29 +1030] [Job 1293] argv[3]="jupiter-english" D [12/Oct/2009:02:15:29 +1030] [Job 1293] argv[4]="1" D [12/Oct/2009:02:15:29 +1030] [Job 1293] argv[5]="InputSlot=Auto PageSize=A4 jo b-uuid=urn:uuid:13e93e10-384c-3b40-7616-188811d7d27b job-originating-host-name=l ocalhost" D [12/Oct/2009:02:15:29 +1030] [Job 1293] argv[6]="/var/spool/cups/d01293-001" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[0]="CUPS_CACHEDIR=/var/cache/cups " D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[1]="CUPS_DATADIR=/usr/share/cups" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[2]="CUPS_DOCROOT=/usr/share/cups/ doc-root" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[3]="CUPS_FONTPATH=/usr/share/cups /fonts" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[4]="CUPS_REQUESTROOT=/var/spool/c ups" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[6]="CUPS_SERVERROOT=/etc/cups" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[7]="CUPS_STATEDIR=/var/run/cups" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[8]="HOME=/var/spool/cups/tmp" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[9]="PATH=/usr/lib/cups/filter:/us r/bin:/usr/sbin:/bin:/usr/bin" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[10]="SERVER_ADMIN=root@victoria" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[11]="SOFTWARE=CUPS/1.4.1" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[12]="TMPDIR=/var/spool/cups/tmp" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[13]="TZ=Australia/Adelaide" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[14]="USER=root" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[15]="CUPS_SERVER=/var/run/cups/cu ps.sock" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[16]="CUPS_ENCRYPTION=IfRequested" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[17]="IPP_PORT=631" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[18]="CHARSET=utf-8" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[19]="LANG=en_AU.UTF-8" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[20]="PPD=/etc/cups/ppd/Officejet_ Pro_8000_A809.ppd" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[21]="RIP_MAX_CACHE=96330k" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[22]="CONTENT_TYPE=application/pos tscript" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[23]="DEVICE_URI=hp:/net/Officejet _Pro_8000_A809?ip=192.168.1.102" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[24]="PRINTER_INFO=dual-sided colo ur inkjet printer" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[25]="PRINTER_LOCATION=above the r adio" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[26]="PRINTER=Officejet_Pro_8000_A 809" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[27]="CUPS_FILETYPE=document" D [12/Oct/2009:02:15:29 +1030] [Job 1293] envp[28]="FINAL_CONTENT_TYPE=printer/O fficejet_Pro_8000_A809" I [12/Oct/2009:02:15:29 +1030] [Job 1293] Started filter /usr/lib/cups/filter/ps topdf (PID 2393) I [12/Oct/2009:02:15:29 +1030] [Job 1293] Started filter /usr/lib/cups/filter/pd ftopdf (PID 2394) I [12/Oct/2009:02:15:29 +1030] [Job 1293] Started filter /usr/lib/cups/filter/pd ftoraster (PID 2395) I [12/Oct/2009:02:15:29 +1030] [Job 1293] Started filter /usr/lib/cups/filter/hp cups (PID 2396) I [12/Oct/2009:02:15:31 +1030] [Job 1293] Started backend /usr/lib/cups/backend/ hp (PID 2398) D [12/Oct/2009:02:15:31 +1030] Discarding unused job-state-changed event... D [12/Oct/2009:02:15:31 +1030] Returning IPP successful-ok for Send-Document (ip p://localhost:631/printers/Officejet_Pro_8000_A809) from localhost D [12/Oct/2009:02:15:31 +1030] [Job 1293] pstopdf 6 args: 1293 amarsh04 jupiter- english 1 InputSlot=Auto PageSize=A4 job-uuid=urn:uuid:13e93e10-384c-3b40-7616-1 88811d7d27b job-originating-host-name=localhost /var/spool/cups/d01293-001 D [12/Oct/2009:02:15:31 +1030] [Job 1293] PPD: /etc/cups/ppd/Officejet_Pro_8000_ A809.ppd D [12/Oct/2009:02:15:31 +1030] cupsdSetBusyState: Printing jobs and dirty files D [12/Oct/2009:02:15:39 +1030] [Job 1293] Resolution: D [12/Oct/2009:02:15:47 +1030] [Job 1293] Page size: A4 D [12/Oct/2009:02:15:47 +1030] [Job 1293] Width: 595.440002441406, height: 841.6 79992675781, absolute margins: 9, 9, 586.440002441406, 832.679992675781 D [12/Oct/2009:02:15:47 +1030] [Job 1293] Relative margins: 9, 9, 9.000000000000 , 9.000000000000 D [12/Oct/2009:02:15:47 +1030] [Job 1293] PPD options: -dDEVICEWIDTHPOINTS=595.4 40002441406 -dDEVICEHEIGHTPOINTS=841.679992675781 D [12/Oct/2009:02:15:47 +1030] [Job 1293] PostScript to be injected: D [12/Oct/2009:02:15:47 +1030] [Job 1293] Running cat | /usr/bin/ps2pdf13 -dAuto RotatePages=/None -dAutoFilterColorImages=false -dNOPLATFONTS -dP ARANOIDSAFER -sstdout=%stderr -dColorImageFilter=/FlateEncode -d PDFSETTINGS=/printer -dDoNumCopies -dDEVICEWIDTHPOINTS=595.440002441406 -dDEVICE HEIGHTPOINTS=841.679992675781 - - D [12/Oct/2009:02:15:57 +1030] [Job 1293] GPL Ghostscript 8.70: Set UseCIEColor for UseDeviceIndependentColor to work properly. I [12/Oct/2009:02:15:58 +1030] Saving job cache file "/var/cache/cups/job.cache" ... D [12/Oct/2009:02:15:58 +1030] cupsdSetBusyState: Printing jobs D [12/Oct/2009:02:16:02 +1030] PID 2393 (/usr/lib/cups/filter/pstopdf) exited wi th no errors. D [12/Oct/2009:02:16:02 +1030] PID 2394 (/usr/lib/cups/filter/pdftopdf) exited w ith no errors. D [12/Oct/2009:02:16:02 +1030] [Job 1293] Ghostscript command line: /usr/bin/gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -sDEVICE=cups -sstdout=%stderr -sOutpu tFile=%stdout -I/usr/share/cups/fonts -sMediaType=Automatic -r600x600 -dMediaPos ition=7 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=841 -dcupsMediaType=5 -dcup sBitsPerColor=8 -dcupsColorOrder=0 -dcupsColorSpace=1 -dcupsRowCount=3 -scupsPag eSizeName=A4 -c -f -_ D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[0]="CUPS_CACHEDIR=/var/cache/cups " D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[1]="CUPS_DATADIR=/usr/share/cups" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[2]="CUPS_DOCROOT=/usr/share/cups/ doc-root" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[3]="CUPS_FONTPATH=/usr/share/cups /fonts" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[4]="CUPS_REQUESTROOT=/var/spool/c ups" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[6]="CUPS_SERVERROOT=/etc/cups" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[7]="CUPS_STATEDIR=/var/run/cups" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[8]="HOME=/var/spool/cups/tmp" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[9]="PATH=/usr/lib/cups/filter:/us r/bin:/usr/sbin:/bin:/usr/bin" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[10]="SERVER_ADMIN=root@victoria" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[11]="SOFTWARE=CUPS/1.4.1" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[12]="TMPDIR=/var/spool/cups/tmp" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[13]="TZ=Australia/Adelaide" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[14]="USER=root" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[15]="CUPS_SERVER=/var/run/cups/cu ps.sock" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[16]="CUPS_ENCRYPTION=IfRequested" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[17]="IPP_PORT=631" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[18]="CHARSET=utf-8" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[19]="LANG=en_AU.UTF-8" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[20]="PPD=/etc/cups/ppd/Officejet_ Pro_8000_A809.ppd" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[21]="RIP_MAX_CACHE=96330k" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[22]="CONTENT_TYPE=application/pos tscript" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[23]="DEVICE_URI=hp:/net/Officejet _Pro_8000_A809?ip=192.168.1.102" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[24]="PRINTER_INFO=dual-sided colo ur inkjet printer" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[25]="PRINTER_LOCATION=above the r adio" D [12/Oct/2009:02:16:02 +1030] [Job 1293] envp[26]="PRINTER=Officejet_Pro_8000_A 809" D [12/Oct/2009:02:16:03 +1030] [Job 1293] envp[27]="CUPS_FILETYPE=document" D [12/Oct/2009:02:16:03 +1030] [Job 1293] envp[28]="FINAL_CONTENT_TYPE=printer/O fficejet_Pro_8000_A809" D [12/Oct/2009:02:16:03 +1030] PID 2395 (/usr/lib/cups/filter/pdftoraster) exite d with no errors. D [12/Oct/2009:02:16:06 +1030] [Job 1293] num_components = 1, depth = 1 D [12/Oct/2009:02:16:06 +1030] [Job 1293] cupsColorSpace = 3, cupsColorOrder = 0 D [12/Oct/2009:02:16:06 +1030] [Job 1293] cupsBitsPerPixel = 1, cupsBitsPerColor = 1 D [12/Oct/2009:02:16:06 +1030] [Job 1293] max_gray = 1, dither_grays = 2 D [12/Oct/2009:02:16:06 +1030] [Job 1293] max_color = 0, dither_colors = 0 D [12/Oct/2009:02:16:06 +1030] [Job 1293] Updating PageSize to [595 841]... D [12/Oct/2009:02:16:06 +1030] [Job 1293] Setting initial media size, [595 841] = 4958x7008 pixels... D [12/Oct/2009:02:16:06 +1030] [Job 1293] Setting MediaType to "Automatic"... D [12/Oct/2009:02:16:06 +1030] [Job 1293] Setting MediaPosition to 7... D [12/Oct/2009:02:16:06 +1030] [Job 1293] Setting cupsMediaType to 5... D [12/Oct/2009:02:16:06 +1030] [Job 1293] Setting cupsBitsPerColor to 8... D [12/Oct/2009:02:16:06 +1030] [Job 1293] Setting cupsColorOrder to 0... D [12/Oct/2009:02:16:06 +1030] [Job 1293] Setting cupsColorSpace to 1... D [12/Oct/2009:02:16:06 +1030] [Job 1293] Setting cupsRowCount to 3... D [12/Oct/2009:02:16:06 +1030] [Job 1293] Setting cupsPageSizeName to "A4"... D [12/Oct/2009:02:16:06 +1030] [Job 1293] num_components = 3, depth = 24 D [12/Oct/2009:02:16:06 +1030] [Job 1293] cupsColorSpace = 1, cupsColorOrder = 0 D [12/Oct/2009:02:16:06 +1030] [Job 1293] cupsBitsPerPixel = 24, cupsBitsPerColo r = 8 D [12/Oct/2009:02:16:06 +1030] [Job 1293] max_gray = 0, dither_grays = 0 D [12/Oct/2009:02:16:06 +1030] [Job 1293] max_color = 255, dither_colors = 256 D [12/Oct/2009:02:16:06 +1030] [Job 1293] Setting initial media size, [595 841] = 4958x7008 pixels... I [12/Oct/2009:02:16:06 +1030] [Job 1293] Processing page 1... D [12/Oct/2009:02:16:06 +1030] Discarding unused printer-state-changed event... D [12/Oct/2009:02:16:06 +1030] Discarding unused job-progress event... D [12/Oct/2009:02:16:06 +1030] [Job 1293] num_components = 3, depth = 24 D [12/Oct/2009:02:16:06 +1030] [Job 1293] cupsColorSpace = 1, cupsColorOrder = 0 D [12/Oct/2009:02:16:06 +1030] [Job 1293] cupsBitsPerPixel = 24, cupsBitsPerColo r = 8 D [12/Oct/2009:02:16:06 +1030] [Job 1293] max_gray = 0, dither_grays = 0 D [12/Oct/2009:02:16:06 +1030] [Job 1293] max_color = 255, dither_colors = 256 D [12/Oct/2009:02:16:06 +1030] [Job 1293] Setting LeadingEdge to 0... D [12/Oct/2009:02:16:06 +1030] [Job 1293] num_components = 3, depth = 24 D [12/Oct/2009:02:16:06 +1030] [Job 1293] cupsColorSpace = 1, cupsColorOrder = 0 D [12/Oct/2009:02:16:06 +1030] [Job 1293] cupsBitsPerPixel = 24, cupsBitsPerColo r = 8 D [12/Oct/2009:02:16:06 +1030] [Job 1293] max_gray = 0, dither_grays = 0 D [12/Oct/2009:02:16:06 +1030] [Job 1293] max_color = 255, dither_colors = 256 D [12/Oct/2009:02:16:06 +1030] [Job 1293] Updating PageSize to [595 841]... D [12/Oct/2009:02:16:06 +1030] [Job 1293] size = A4 D [12/Oct/2009:02:16:06 +1030] [Job 1293] margins[] = [ 0.125000 0.125000 0.1250 00 0.125000 ] D [12/Oct/2009:02:16:08 +1030] Report: clients=1 D [12/Oct/2009:02:16:08 +1030] Report: jobs=29 D [12/Oct/2009:02:16:08 +1030] Report: jobs-active=1 D [12/Oct/2009:02:16:08 +1030] Report: printers=1 D [12/Oct/2009:02:16:08 +1030] Report: printers-implicit=0 D [12/Oct/2009:02:16:08 +1030] Report: stringpool-string-count=405 D [12/Oct/2009:02:16:08 +1030] Report: stringpool-alloc-bytes=8280 D [12/Oct/2009:02:16:08 +1030] Report: stringpool-total-bytes=9368 D [12/Oct/2009:02:16:08 +1030] [Job 1293] Setting LeadingEdge to 0... D [12/Oct/2009:02:16:09 +1030] [Job 1293] num_components = 3, depth = 24 D [12/Oct/2009:02:16:09 +1030] [Job 1293] cupsColorSpace = 1, cupsColorOrder = 0 D [12/Oct/2009:02:16:09 +1030] [Job 1293] cupsBitsPerPixel = 24, cupsBitsPerColo r = 8 D [12/Oct/2009:02:16:09 +1030] [Job 1293] max_gray = 0, dither_grays = 0 D [12/Oct/2009:02:16:09 +1030] [Job 1293] max_color = 255, dither_colors = 256 D [12/Oct/2009:02:16:09 +1030] [Job 1293] Setting LeadingEdge to 0... D [12/Oct/2009:02:16:09 +1030] [Job 1293] num_components = 3, depth = 24 D [12/Oct/2009:02:16:09 +1030] [Job 1293] cupsColorSpace = 1, cupsColorOrder = 0 D [12/Oct/2009:02:16:09 +1030] [Job 1293] cupsBitsPerPixel = 24, cupsBitsPerColo r = 8 D [12/Oct/2009:02:16:09 +1030] [Job 1293] max_gray = 0, dither_grays = 0 D [12/Oct/2009:02:16:09 +1030] [Job 1293] max_color = 255, dither_colors = 256 D [12/Oct/2009:02:16:09 +1030] [Job 1293] Updating PageSize to [595 842]... D [12/Oct/2009:02:16:09 +1030] [Job 1293] size = A4 D [12/Oct/2009:02:16:09 +1030] [Job 1293] margins[] = [ 0.125000 0.125000 0.1250 00 0.125000 ] D [12/Oct/2009:02:16:20 +1030] [Job 1293] cups_print_chunked - flip = 0, height = 6864 D [12/Oct/2009:02:16:20 +1030] [Job 1293] prnt/hpijs/hpcups.cpp 156: unable to s et Quality=0, MediaType=5, ColorMode=2, err=2 D [12/Oct/2009:02:16:20 +1030] [Job 1293] prnt/hpijs/hpcups.cpp 158: following w ill be used Quality=0, MediaType=0, ColorMode=2, D [12/Oct/2009:02:16:20 +1030] [Job 1293] STATE: +connecting-to-device D [12/Oct/2009:02:16:20 +1030] Discarding unused printer-state-changed event... D [12/Oct/2009:02:16:25 +1030] [Job 1293] STATE: -connecting-to-device D [12/Oct/2009:02:16:25 +1030] Discarding unused printer-state-changed event... I [12/Oct/2009:02:17:01 +1030] [Job 1293] Processing page 2... D [12/Oct/2009:02:17:01 +1030] Discarding unused printer-state-changed event... D [12/Oct/2009:02:17:01 +1030] Discarding unused job-progress event... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting AdvanceDistance to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting AdvanceMedia to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting Collate to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting CutMedia to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting Duplex to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting Jog to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting LeadingEdge to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting Margins to 0 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting MirrorPrint to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting NegativePrint to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting OutputFaceUp to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting Separations to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting TraySwitch to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting Tumble to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsMediaType to 5... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsBitsPerColor to 8... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsColorOrder to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsColorSpace to 1... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsCompression to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsRowCount to 3... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsRowFeed to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsRowStep to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsBorderlessScalingFactor to 1.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger0 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger1 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger2 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger3 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger4 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger5 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger6 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger7 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger8 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger9 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger10 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger11 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger12 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger13 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger14 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger15 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal0 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal1 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal2 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal3 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal4 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal5 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal6 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal7 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal8 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal9 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal10 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal11 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal12 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal13 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal14 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal15 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString0 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString1 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString2 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString3 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString4 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString5 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString6 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString7 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString8 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString9 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString10 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString11 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString12 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString13 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString14 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString15 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsMarkerType to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsRenderingIntent to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsPageSizeName to "A4"... D [12/Oct/2009:02:17:01 +1030] [Job 1293] num_components = 3, depth = 24 D [12/Oct/2009:02:17:01 +1030] [Job 1293] cupsColorSpace = 1, cupsColorOrder = 0 D [12/Oct/2009:02:17:01 +1030] [Job 1293] cupsBitsPerPixel = 24, cupsBitsPerColo r = 8 D [12/Oct/2009:02:17:01 +1030] [Job 1293] max_gray = 0, dither_grays = 0 D [12/Oct/2009:02:17:01 +1030] [Job 1293] max_color = 255, dither_colors = 256 D [12/Oct/2009:02:17:01 +1030] [Job 1293] Updating PageSize to [595 842]... D [12/Oct/2009:02:17:01 +1030] [Job 1293] size = A4 D [12/Oct/2009:02:17:01 +1030] [Job 1293] margins[] = [ 0.125000 0.125000 0.1250 00 0.125000 ] D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting AdvanceDistance to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting AdvanceMedia to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting Collate to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting CutMedia to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting Duplex to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting Jog to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting LeadingEdge to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting Margins to 0 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting MirrorPrint to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting NegativePrint to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting OutputFaceUp to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting Separations to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting TraySwitch to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting Tumble to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsMediaType to 5... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsBitsPerColor to 8... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsColorOrder to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsColorSpace to 1... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsCompression to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsRowCount to 3... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsRowFeed to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsRowStep to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsBorderlessScalingFactor to 1.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger0 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger1 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger2 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger3 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger4 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger5 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger6 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger7 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger8 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger9 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger10 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger11 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger12 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger13 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger14 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsInteger15 to 0... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal0 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal1 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal2 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal3 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal4 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal5 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal6 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal7 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal8 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal9 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal10 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal11 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal12 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal13 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal14 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsReal15 to 0.0000... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString0 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString1 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString2 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString3 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString4 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString5 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString6 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString7 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString8 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString9 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString10 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString11 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString12 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString13 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString14 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsString15 to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsMarkerType to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsRenderingIntent to ""... D [12/Oct/2009:02:17:01 +1030] [Job 1293] Setting cupsPageSizeName to "A4"... D [12/Oct/2009:02:17:01 +1030] [Job 1293] num_components = 3, depth = 24 D [12/Oct/2009:02:17:01 +1030] [Job 1293] cupsColorSpace = 1, cupsColorOrder = 0 D [12/Oct/2009:02:17:01 +1030] [Job 1293] cupsBitsPerPixel = 24, cupsBitsPerColo r = 8 D [12/Oct/2009:02:17:01 +1030] [Job 1293] max_gray = 0, dither_grays = 0 D [12/Oct/2009:02:17:01 +1030] [Job 1293] max_color = 255, dither_colors = 256 D [12/Oct/2009:02:17:01 +1030] [Job 1293] Updating PageSize to [595 842]... D [12/Oct/2009:02:17:01 +1030] [Job 1293] size = A4 D [12/Oct/2009:02:17:01 +1030] [Job 1293] margins[] = [ 0.125000 0.125000 0.1250 00 0.125000 ] D [12/Oct/2009:02:17:02 +1030] PID 2396 (/usr/lib/cups/filter/hpcups) exited wit h no errors. D [12/Oct/2009:02:17:04 +1030] [Job 1293] prnt/hpijs/hpcups.cpp 681: hpcups: ret urning status 0 from mainINFO: ready to print D [12/Oct/2009:02:17:05 +1030] PID 2398 (/usr/lib/cups/backend/hp) exited with n o errors.