[cups.general] blank test page from CUPS web-admin

Thu Aug 6 04:13:36 PDT 2009

.... , as well as with any other printing job. Page count and duplexing
is respected. The printer "Service page" is printing without problems.

Log file fragment is below.

Please, share your thoughts to resolve the problem.

Andrew

----------------------------------------------------

D [06/Aug/2009:13:13:50 +0400] Print-Job ipp://localhost:631/printers/FS-1030D
D [06/Aug/2009:13:13:50 +0400] add_job: requesting-user-name="anli"
D [06/Aug/2009:13:13:50 +0400] Adding default job-sheets values "none,none"...
I [06/Aug/2009:13:13:50 +0400] [Job 160] Adding start banner page "none".
I [06/Aug/2009:13:13:50 +0400] Saving subscriptions.conf...
I [06/Aug/2009:13:13:50 +0400] [Job 160] Adding end banner page "none".
I [06/Aug/2009:13:13:50 +0400] [Job 160] File of type application/postscript queued by "anli".
D [06/Aug/2009:13:13:50 +0400] [Job 160] hold_until=0
I [06/Aug/2009:13:13:50 +0400] [Job 160] Queued on "FS-1030D" by "anli".
I [06/Aug/2009:13:13:50 +0400] Saving subscriptions.conf...
D [06/Aug/2009:13:13:50 +0400] [Job 160] job-sheets=none,none
D [06/Aug/2009:13:13:50 +0400] [Job 160] banner_page = 0
D [06/Aug/2009:13:13:50 +0400] [Job 160] argv[0]="FS-1030D"
D [06/Aug/2009:13:13:50 +0400] [Job 160] argv[1]="160"
D [06/Aug/2009:13:13:50 +0400] [Job 160] argv[2]="anli"
D [06/Aug/2009:13:13:50 +0400] [Job 160] argv[3]="Test Page"
D [06/Aug/2009:13:13:50 +0400] [Job 160] argv[4]="1"
D [06/Aug/2009:13:13:50 +0400] [Job 160] argv[5]="job-
uuid=urn:uuid:e6a4fb3c-9a4b-333f-40de-82f8c79c8b94"
D [06/Aug/2009:13:13:50 +0400] [Job 160] argv[6]="/var/spool/cups/d00160-001"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [06/Aug/2009:13:13:50 +0400] [Job 160] 
envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[9]="SERVER_ADMIN=root at anli"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[10]="SOFTWARE=CUPS/1.3.11"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[12]="TZ=Europe/Moscow"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[13]="USER=root"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[16]="IPP_PORT=631"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[17]="CHARSET=utf-8"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[18]="LANG=en.UTF-8"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[19]="PPD=/etc/cups/ppd/FS-1030D.ppd"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[20]="RIP_MAX_CACHE=8m"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[21]="CONTENT_TYPE=application/postscript"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[22]="DEVICE_URI=usb://Kyocera/FS-1030D"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[23]="PRINTER=FS-1030D"
D [06/Aug/2009:13:13:50 +0400] [Job 160] envp[24]="FINAL_CONTENT_TYPE=application/vnd.cups-
postscript"
I [06/Aug/2009:13:13:50 +0400] [Job 160] Started filter /usr/lib/cups/filter/pstopdf (PID 6338)
I [06/Aug/2009:13:13:50 +0400] [Job 160] Started filter /usr/lib/cups/filter/pdftopdf (PID 6341)
I [06/Aug/2009:13:13:51 +0400] [Job 160] Started filter /usr/lib/cups/filter/cpdftocps (PID 6342)
I [06/Aug/2009:13:13:51 +0400] [Job 160] Started backend /usr/lib/cups/backend/usb (PID 6347)
I [06/Aug/2009:13:13:51 +0400] Saving subscriptions.conf...
D [06/Aug/2009:13:13:51 +0400] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [06/Aug/2009:13:13:51 +0400] [Job 160] pstopdf 6 args: 160 anli Test Page 1 job-
uuid=urn:uuid:e6a4fb3c-9a4b-333f-40de-82f8c79c8b94 /var/spool/cups/d00160-001
D [06/Aug/2009:13:13:51 +0400] [Job 160] PPD: /etc/cups/ppd/FS-1030D.ppd
D [06/Aug/2009:13:13:51 +0400] [Job 160] Printer using device file "/dev/usblp0"...
D [06/Aug/2009:13:13:51 +0400] [Job 160] backendRunLoop(print_fd=0, device_fd=5, use_bc=1, 
side_cb=0x7f2b0ba08b20)
D [06/Aug/2009:13:13:51 +0400] [Job 160] Resolution: 600
I [06/Aug/2009:13:13:51 +0400] Saving subscriptions.conf...
D [06/Aug/2009:13:13:51 +0400] cupsdCloseClient: 16
D [06/Aug/2009:13:13:51 +0400] [CGI] lang="en.UTF8", locale="/en"...
D [06/Aug/2009:13:13:51 +0400] [Job 160] Page size: A4
D [06/Aug/2009:13:13:51 +0400] PID 6337 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [06/Aug/2009:13:13:51 +0400] Script header: Content-Type: text/html;charset=utf-8
D [06/Aug/2009:13:13:51 +0400] Script header: 
D [06/Aug/2009:13:13:51 +0400] [CGI] lang="en.UTF8", locale="/en"...
D [06/Aug/2009:13:13:51 +0400] [CGI] lang="en.UTF8", locale="/en"...
D [06/Aug/2009:13:13:51 +0400] [Job 160] Width: 595, height: 842, absolute margins: 12, 10, 583, 832
D [06/Aug/2009:13:13:51 +0400] [Job 160] Relative margins: 12, 10, 12, 10
D [06/Aug/2009:13:13:51 +0400] [Job 160] PPD options: -r600 -dDEVICEWIDTHPOINTS=595 -
dDEVICEHEIGHTPOINTS=842
D [06/Aug/2009:13:13:51 +0400] [Job 160] PostScript to be injected: 
D [06/Aug/2009:13:13:51 +0400] [Job 160] Running cat | /usr/bin/ps2pdf13 -dAutoRotatePages=/None -
dAutoFilterColorImages=false                -dNOPLATFONTS -dPARANOIDSAFER -sstdout=%stderr -
dColorImageFilter=/FlateEncode                 -dPDFSETTINGS=/printer -dDoNumCopies -r600 -
dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 - -
D [06/Aug/2009:13:13:51 +0400] [Job 160] GPL Ghostscript 8.70: Set UseCIEColor for 
UseDeviceIndependentColor to work properly.
D [06/Aug/2009:13:13:51 +0400] cupsdAcceptClient: 15 from localhost (Domain)
D [06/Aug/2009:13:13:51 +0400] cupsdReadClient: 15 POST / HTTP/1.1
D [06/Aug/2009:13:13:51 +0400] cupsdAuthorize: No authentication data provided.
D [06/Aug/2009:13:13:51 +0400] Get-Notifications /
D [06/Aug/2009:13:13:51 +0400] cupsdIsAuthorized: requesting-user-name="anli"
D [06/Aug/2009:13:13:51 +0400] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)
D [06/Aug/2009:13:13:51 +0400] cupsdReadClient: 15 POST / HTTP/1.1
D [06/Aug/2009:13:13:51 +0400] cupsdAuthorize: No authentication data provided.
D [06/Aug/2009:13:13:51 +0400] Get-Job-Attributes ipp://localhost/jobs/160
D [06/Aug/2009:13:13:51 +0400] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)
E [06/Aug/2009:13:13:51 +0400] [Job 160] Weird page contents
I [06/Aug/2009:13:13:51 +0400] Saving subscriptions.conf...
I [06/Aug/2009:13:13:51 +0400] Saving subscriptions.conf...
D [06/Aug/2009:13:13:51 +0400] PID 6338 (/usr/lib/cups/filter/pstopdf) exited with no errors.
D [06/Aug/2009:13:13:51 +0400] PID 6341 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [06/Aug/2009:13:13:51 +0400] [Job 160] perl: warning: Setting locale failed.
D [06/Aug/2009:13:13:51 +0400] [Job 160] perl: warning: Please check that your locale settings:
D [06/Aug/2009:13:13:51 +0400] [Job 160] LANGUAGE = (unset),
D [06/Aug/2009:13:13:51 +0400] [Job 160] LC_ALL = (unset),
D [06/Aug/2009:13:13:51 +0400] [Job 160] LANG = "en.UTF-8"
D [06/Aug/2009:13:13:51 +0400] [Job 160] are supported and installed on your system.
D [06/Aug/2009:13:13:51 +0400] [Job 160] perl: warning: Falling back to the standard locale ("C").
D [06/Aug/2009:13:13:51 +0400] [Job 160] Device copies: 1; device collate: 
D [06/Aug/2009:13:13:51 +0400] [Job 160] pdftops - copying to temp print file "/tmp/4a7a9ecf366ae"
D [06/Aug/2009:13:13:51 +0400] [Job 160] Started filter pdftops (PID 6386)
D [06/Aug/2009:13:13:51 +0400] [Job 160] Started filter pstops (PID 6387)
D [06/Aug/2009:13:13:51 +0400] [Job 160] PID 6386 (pdftops) exited with no errors.
D [06/Aug/2009:13:13:51 +0400] [Job 160] Page = 595x842; 12,10 to 583,832
D [06/Aug/2009:13:13:51 +0400] [Job 160] slow_collate=0, slow_duplex=0, slow_order=0
D [06/Aug/2009:13:13:51 +0400] [Job 160] Before copy_comments - %!PS-Adobe-3.0
D [06/Aug/2009:13:13:51 +0400] [Job 160] %!PS-Adobe-3.0
D [06/Aug/2009:13:13:51 +0400] [Job 160] %%LanguageLevel: 2
D [06/Aug/2009:13:13:51 +0400] [Job 160] %%DocumentSuppliedResources: (atend)
D [06/Aug/2009:13:13:51 +0400] [Job 160] %%DocumentMedia: plain 612 792 0 () ()
D [06/Aug/2009:13:13:51 +0400] [Job 160] %%BoundingBox: 0 0 612 792
D [06/Aug/2009:13:13:51 +0400] [Job 160] %%Pages: 1
D [06/Aug/2009:13:13:51 +0400] [Job 160] %%EndComments
D [06/Aug/2009:13:13:51 +0400] [Job 160] Before copy_prolog - %%BeginDefaults
D [06/Aug/2009:13:13:51 +0400] [Job 160] Before copy_setup - %%BeginSetup
D [06/Aug/2009:13:13:51 +0400] [Job 160] Before page loop - %%Page: 1 1
D [06/Aug/2009:13:13:51 +0400] [Job 160] Copying page 1...
I [06/Aug/2009:13:13:51 +0400] Saving subscriptions.conf...
D [06/Aug/2009:13:13:51 +0400] [Job 160] pagew = 571.0, pagel = 822.0
D [06/Aug/2009:13:13:51 +0400] [Job 160] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [06/Aug/2009:13:13:51 +0400] [Job 160] PageLeft = 12.0, PageRight = 583.0
D [06/Aug/2009:13:13:51 +0400] [Job 160] PageTop = 832.0, PageBottom = 10.0
D [06/Aug/2009:13:13:51 +0400] [Job 160] PageWidth = 595.0, PageLength = 842.0
D [06/Aug/2009:13:13:51 +0400] [Job 160] Wrote 1 pages...
D [06/Aug/2009:13:13:51 +0400] [Job 160] Page = 595x842; 12,10 to 583,832
D [06/Aug/2009:13:13:51 +0400] [Job 160] slow_collate=0, slow_duplex=0, slow_order=0
D [06/Aug/2009:13:13:51 +0400] [Job 160] Skipping PJL header...
D [06/Aug/2009:13:13:51 +0400] [Job 160] Before copy_comments - %!PS-Adobe-3.0
D [06/Aug/2009:13:13:51 +0400] [Job 160] %!PS-Adobe-3.0
D [06/Aug/2009:13:13:51 +0400] [Job 160] %%LanguageLevel: 2
D [06/Aug/2009:13:13:51 +0400] [Job 160] %%DocumentSuppliedResources: (atend)
D [06/Aug/2009:13:13:51 +0400] [Job 160] %%DocumentMedia: plain 612 792 0 () ()
D [06/Aug/2009:13:13:51 +0400] [Job 160] %%For: (anli)
D [06/Aug/2009:13:13:51 +0400] [Job 160] %%Title: (Test Page)
D [06/Aug/2009:13:13:51 +0400] [Job 160] %RBINumCopies: 1
D [06/Aug/2009:13:13:51 +0400] [Job 160] %%Pages: (atend)
D [06/Aug/2009:13:13:51 +0400] [Job 160] %%BoundingBox: (atend)
D [06/Aug/2009:13:13:51 +0400] [Job 160] %%EndComments
D [06/Aug/2009:13:13:51 +0400] [Job 160] Before copy_prolog - %%BeginDefaults
D [06/Aug/2009:13:13:51 +0400] [Job 160] Before copy_setup - %%BeginSetup
D [06/Aug/2009:13:13:51 +0400] [Job 160] Before page loop - %%Page: 1 1
D [06/Aug/2009:13:13:51 +0400] [Job 160] Copying page 1...
I [06/Aug/2009:13:13:51 +0400] Saving subscriptions.conf...
D [06/Aug/2009:13:13:51 +0400] [Job 160] pagew = 571.0, pagel = 822.0
D [06/Aug/2009:13:13:51 +0400] [Job 160] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [06/Aug/2009:13:13:51 +0400] [Job 160] PageLeft = 12.0, PageRight = 583.0
D [06/Aug/2009:13:13:51 +0400] [Job 160] PageTop = 832.0, PageBottom = 10.0
D [06/Aug/2009:13:13:51 +0400] [Job 160] PageWidth = 595.0, PageLength = 842.0
D [06/Aug/2009:13:13:51 +0400] [Job 160] Wrote 1 pages...
D [06/Aug/2009:13:13:51 +0400] [Job 160] Read 8192 bytes of print data...
D [06/Aug/2009:13:13:51 +0400] [Job 160] PID 6387 (pstops) exited with no errors.
D [06/Aug/2009:13:13:51 +0400] PID 6342 (/usr/lib/cups/filter/cpdftocps) exited with no errors.
D [06/Aug/2009:13:13:51 +0400] cupsdCloseClient: 15
D [06/Aug/2009:13:13:51 +0400] cupsdAcceptClient: 15 from localhost (Domain)
D [06/Aug/2009:13:13:51 +0400] cupsdReadClient: 15 POST / HTTP/1.1
D [06/Aug/2009:13:13:51 +0400] cupsdAuthorize: No authentication data provided.
D [06/Aug/2009:13:13:51 +0400] Get-Notifications /
D [06/Aug/2009:13:13:51 +0400] cupsdIsAuthorized: requesting-user-name="anli"
D [06/Aug/2009:13:13:51 +0400] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)
D [06/Aug/2009:13:13:51 +0400] [Job 160] Wrote 8192 bytes of print data...
D [06/Aug/2009:13:13:51 +0400] [Job 160] Read 8192 bytes of print data...
I [06/Aug/2009:13:13:51 +0400] Saving subscriptions.conf...
D [06/Aug/2009:13:13:51 +0400] cupsdCloseClient: 15
D [06/Aug/2009:13:13:51 +0400] cupsdAcceptClient: 15 from localhost (Domain)
D [06/Aug/2009:13:13:51 +0400] cupsdReadClient: 15 POST / HTTP/1.1
D [06/Aug/2009:13:13:51 +0400] cupsdAuthorize: No authentication data provided.
D [06/Aug/2009:13:13:51 +0400] Get-Notifications /
D [06/Aug/2009:13:13:51 +0400] cupsdIsAuthorized: requesting-user-name="anli"
D [06/Aug/2009:13:13:51 +0400] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)
D [06/Aug/2009:13:13:51 +0400] cupsdCloseClient: 15
D [06/Aug/2009:13:13:51 +0400] cupsdAcceptClient: 15 from localhost (Domain)
D [06/Aug/2009:13:13:51 +0400] cupsdReadClient: 15 POST / HTTP/1.1
D [06/Aug/2009:13:13:51 +0400] cupsdAuthorize: No authentication data provided.
D [06/Aug/2009:13:13:51 +0400] Get-Notifications /
D [06/Aug/2009:13:13:51 +0400] cupsdIsAuthorized: requesting-user-name="anli"
D [06/Aug/2009:13:13:51 +0400] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)
D [06/Aug/2009:13:13:51 +0400] cupsdCloseClient: 15
D [06/Aug/2009:13:13:51 +0400] [Job 160] Wrote 8192 bytes of print data...
D [06/Aug/2009:13:13:51 +0400] [Job 160] Read 139 bytes of print data...
D [06/Aug/2009:13:13:51 +0400] [Job 160] Wrote 139 bytes of print data...
D [06/Aug/2009:13:13:51 +0400] PID 6347 (/usr/lib/cups/backend/usb) exited with no errors.
D [06/Aug/2009:13:13:51 +0400] [Job 160] File 0 is complete.
I [06/Aug/2009:13:13:51 +0400] [Job 160] Completed successfully.