Page 1 (Scheduler not running?): {'cups_connection_failure': False} Page 2 (Choose printer): {'cups_dest': , 'cups_instance': None, 'cups_queue': 'LaserJet', 'cups_queue_listed': True} Page 3 (Check printer sanity): {'cups_device_uri_scheme': u'hal', 'cups_printer_dict': {'device-uri': u'hal:///org/freedesktop/Hal/devices/usb_device_3f0_517_noserial_if0_printer_noserial', 'printer-info': u'HP LaserJet', 'printer-is-shared': True, 'printer-location': u'Debian', 'printer-make-and-model': u'HP LaserJet 1000 Foomatic/foo2zjs (recommended)', 'printer-state': 3, 'printer-state-message': u'', 'printer-state-reasons': [u'none'], 'printer-type': 36868, 'printer-uri-supported': u'ipp://localhost:631/printers/LaserJet'}, 'cups_printer_remote': 0, 'is_cups_class': False} Page 4 (Check PPD sanity): {'cups_printer_ppd_valid': True, 'missing_pkgs_and_exes': ([], [])} Page 5 (Local or remote?): {'printer_is_remote': False} Page 6 (Error log checkpoint): {'cups_server_settings': {'DefaultAuthType': 'Basic', 'SystemGroup': 'lpadmin', '_debug_logging': '1', '_remote_admin': '0', '_remote_any': '0', '_remote_printers': '1', '_share_printers': '0', '_user_cancel_any': '0'}, 'error_log_checkpoint': 8411L, 'error_log_debug_logging_set': True} Page 7 (Print test page): {'test_page_attempted': True, 'test_page_completions': [(21, u'Job stopped due to backend errors; please consult the error_log file for details.')], 'test_page_job_id': [21], 'test_page_job_status': [(True, 20, 'LaserJet', 'Test Page', 'Zru\xc5\xa1eno', {'job-k-octets': 17, 'job-media-sheets-completed': 0, 'job-name': u'Test Page', 'job-originating-user-name': u'root', 'job-preserved': False, 'job-printer-uri': u'ipp://Debian:631/printers/LaserJet', 'job-priority': 50, 'job-state': 7, 'time-at-completed': 1236970415, 'time-at-creation': 1236970223, 'time-at-processing': 1236970223}), (True, 21, 'LaserJet', 'Test Page', 'Zastaveno', {'job-k-octets': 17, 'job-media-sheets-completed': 0, 'job-name': u'Test Page', 'job-originating-user-name': u'guest', 'job-preserved': True, 'job-printer-uri': u'ipp://Debian:631/printers/LaserJet', 'job-priority': 50, 'job-state': 6, 'time-at-creation': 1236970426, 'time-at-processing': 1236970426})], 'test_page_jobs_cancelled': True, 'test_page_successful': False} Page 8 (Error log fetch): {'error_log': ['D [13/Mar/2009:19:53:32 +0100] cupsdCloseClient: 8', 'D [13/Mar/2009:19:53:32 +0100] cupsdAcceptClient: 8 from localhost (Domain)', 'D [13/Mar/2009:19:53:32 +0100] cupsdReadClient: 8 POST / HTTP/1.1', 'D [13/Mar/2009:19:53:32 +0100] cupsdAuthorize: No authentication data provided.', 'D [13/Mar/2009:19:53:32 +0100] Get-Jobs ipp://localhost/jobs/', 'D [13/Mar/2009:19:53:32 +0100] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:32 +0100] cupsdCloseClient: 8', 'D [13/Mar/2009:19:53:32 +0100] cupsdAcceptClient: 8 from localhost (Domain)', 'D [13/Mar/2009:19:53:32 +0100] cupsdReadClient: 8 POST / HTTP/1.1', 'D [13/Mar/2009:19:53:32 +0100] cupsdAuthorize: No authentication data provided.', 'D [13/Mar/2009:19:53:32 +0100] Create-Printer-Subscription /', 'D [13/Mar/2009:19:53:32 +0100] cupsdCreateSubscription(con=0x91e8610(8), uri="/")', 'D [13/Mar/2009:19:53:32 +0100] pullmethod="ippget"', 'D [13/Mar/2009:19:53:32 +0100] notify-lease-duration=86400', 'D [13/Mar/2009:19:53:32 +0100] notify-time-interval=0', 'D [13/Mar/2009:19:53:32 +0100] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)")', 'D [13/Mar/2009:19:53:32 +0100] Added subscription 19 for server', 'I [13/Mar/2009:19:53:32 +0100] Saving subscriptions.conf...', 'D [13/Mar/2009:19:53:32 +0100] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:32 +0100] cupsdCloseClient: 8', 'D [13/Mar/2009:19:53:33 +0100] cupsdAcceptClient: 8 from localhost (Domain)', 'D [13/Mar/2009:19:53:33 +0100] cupsdReadClient: 8 POST / HTTP/1.1', 'D [13/Mar/2009:19:53:33 +0100] cupsdAuthorize: No authentication data provided.', 'D [13/Mar/2009:19:53:33 +0100] Get-Notifications /', 'D [13/Mar/2009:19:53:33 +0100] cupsdIsAuthorized: requesting-user-name="root"', 'D [13/Mar/2009:19:53:33 +0100] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:33 +0100] cupsdCloseClient: 8', 'D [13/Mar/2009:19:53:35 +0100] cupsdAcceptClient: 8 from localhost (Domain)', 'D [13/Mar/2009:19:53:35 +0100] cupsdReadClient: 8 POST /jobs/ HTTP/1.1', 'D [13/Mar/2009:19:53:35 +0100] cupsdAuthorize: No authentication data provided.', 'D [13/Mar/2009:19:53:35 +0100] Cancel-Job ipp://localhost/jobs/20', 'D [13/Mar/2009:19:53:35 +0100] cupsdIsAuthorized: requesting-user-name="root"', 'I [13/Mar/2009:19:53:35 +0100] Saving subscriptions.conf...', 'I [13/Mar/2009:19:53:35 +0100] [Job 20] Canceled by "root".', 'D [13/Mar/2009:19:53:35 +0100] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:35 +0100] cupsdCloseClient: 8', 'D [13/Mar/2009:19:53:39 +0100] cupsdAcceptClient: 8 from localhost (Domain)', 'D [13/Mar/2009:19:53:39 +0100] cupsdReadClient: 8 POST /jobs/ HTTP/1.1', 'D [13/Mar/2009:19:53:39 +0100] cupsdAuthorize: No authentication data provided.', 'D [13/Mar/2009:19:53:39 +0100] Cancel-Job ipp://localhost/jobs/20', 'D [13/Mar/2009:19:53:39 +0100] cupsdIsAuthorized: requesting-user-name="root"', "D [13/Mar/2009:19:53:39 +0100] Cancel-Job client-error-not-possible: Job #20 is already canceled - can't cancel.", 'D [13/Mar/2009:19:53:39 +0100] cupsdProcessIPPRequest: 8 status_code=404 (client-error-not-possible)', 'D [13/Mar/2009:19:53:39 +0100] cupsdCloseClient: 8', 'D [13/Mar/2009:19:53:46 +0100] cupsdAcceptClient: 8 from localhost (Domain)', 'D [13/Mar/2009:19:53:46 +0100] cupsdReadClient: 8 POST /printers/LaserJet HTTP/1.1', 'D [13/Mar/2009:19:53:46 +0100] cupsdAuthorize: No authentication data provided.', 'D [13/Mar/2009:19:53:46 +0100] Print-Job ipp://localhost/printers/LaserJet', 'D [13/Mar/2009:19:53:46 +0100] add_job: requesting-user-name="guest"', 'D [13/Mar/2009:19:53:46 +0100] Adding default job-sheets values "none,none"...', 'I [13/Mar/2009:19:53:46 +0100] [Job 21] Adding start banner page "none".', 'I [13/Mar/2009:19:53:46 +0100] Saving subscriptions.conf...', 'I [13/Mar/2009:19:53:46 +0100] [Job 21] Adding end banner page "none".', 'I [13/Mar/2009:19:53:46 +0100] [Job 21] File of type application/postscript queued by "guest".', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] hold_until=0', 'I [13/Mar/2009:19:53:46 +0100] Saving subscriptions.conf...', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] job-sheets=none,none', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] banner_page = 0', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] argv[0]="LaserJet"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] argv[1]="21"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] argv[2]="guest"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] argv[3]="Test Page"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] argv[4]="1"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] argv[5]="job-uuid=urn:uuid:a061dbda-92cb-3e6c-4aeb-6665e08ffdca"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] argv[6]="/var/spool/cups/d00021-001"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[0]="CUPS_CACHEDIR=/var/cache/cups"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[1]="CUPS_DATADIR=/usr/share/cups"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[6]="CUPS_SERVERROOT=/etc/cups"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[7]="CUPS_STATEDIR=/var/run/cups"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[9]="SERVER_ADMIN=root@Debian"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[10]="SOFTWARE=CUPS/1.3.8"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[11]="TMPDIR=/var/spool/cups/tmp"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[12]="TZ=Europe/Prague"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[13]="USER=root"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[15]="CUPS_ENCRYPTION=IfRequested"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[16]="IPP_PORT=631"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[17]="CHARSET=utf-8"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[18]="LANG=cs_CZ.UTF8"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[19]="PPD=/etc/cups/ppd/LaserJet.ppd"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[20]="RIP_MAX_CACHE=8m"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[21]="CONTENT_TYPE=application/postscript"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[22]="DEVICE_URI=hal:///org/freedesktop/Hal/devices/usb_device_3f0_517_noserial_if0_printer_noserial"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[23]="PRINTER=LaserJet"', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] envp[24]="FINAL_CONTENT_TYPE=printer/LaserJet"', 'I [13/Mar/2009:19:53:46 +0100] [Job 21] Started filter /usr/lib/cups/filter/pstops (PID 4187)', 'I [13/Mar/2009:19:53:46 +0100] [Job 21] Started filter /usr/lib/cups/filter/foomatic-rip (PID 4188)', 'I [13/Mar/2009:19:53:46 +0100] [Job 21] Started backend /usr/lib/cups/backend/hal (PID 4189)', 'I [13/Mar/2009:19:53:46 +0100] Saving subscriptions.conf...', 'D [13/Mar/2009:19:53:46 +0100] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:46 +0100] PID 4187 (/usr/lib/cups/filter/pstops) exited with no errors.', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] Page = 595x842; 11,11 to 584,831', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] slow_collate=0, slow_duplex=0, slow_order=0', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] Before copy_comments - %!PS-Adobe-3.0', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] %!PS-Adobe-3.0', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] %%BoundingBox: 0 0 612 792', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] %%Pages: 1', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] %%LanguageLevel: 1', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] %%DocumentData: Clean7Bit', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] %%DocumentSuppliedResources: procset testprint/1.3', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] %%Creator: Michael Sweet, Apple Inc.', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] %%CreationDate: D:20070606214000+0500', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] %%Title: Test Page', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] %%EndComments', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] Before copy_prolog - %%BeginProlog', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] Before copy_setup - %%Page: 1 1', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] Before page loop - %%Page: 1 1', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] Copying page 1...', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] pagew = 572.3, pagel = 819.3', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] PageLeft = 11.3, PageRight = 583.7', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] PageTop = 830.7, PageBottom = 11.3', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] PageWidth = 595.0, PageLength = 842.0', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] Wrote 1 pages...', 'I [13/Mar/2009:19:53:46 +0100] Saving subscriptions.conf...', 'D [13/Mar/2009:19:53:46 +0100] [Job 21] LPGETSTATUS returned a port status of 18...', 'D [13/Mar/2009:19:53:46 +0100] cupsdCloseClient: 8', 'D [13/Mar/2009:19:53:46 +0100] cupsdAcceptClient: 8 from localhost (Domain)', 'D [13/Mar/2009:19:53:46 +0100] cupsdReadClient: 8 POST / HTTP/1.1', 'D [13/Mar/2009:19:53:46 +0100] cupsdAuthorize: No authentication data provided.', 'D [13/Mar/2009:19:53:46 +0100] Get-Notifications /', 'D [13/Mar/2009:19:53:46 +0100] cupsdIsAuthorized: requesting-user-name="root"', 'D [13/Mar/2009:19:53:46 +0100] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:46 +0100] cupsdAcceptClient: 10 from localhost (Domain)', 'D [13/Mar/2009:19:53:46 +0100] cupsdReadClient: 10 POST / HTTP/1.1', 'D [13/Mar/2009:19:53:46 +0100] cupsdAuthorize: No authentication data provided.', 'D [13/Mar/2009:19:53:46 +0100] Get-Notifications /', 'D [13/Mar/2009:19:53:46 +0100] cupsdIsAuthorized: requesting-user-name="root"', 'D [13/Mar/2009:19:53:46 +0100] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:46 +0100] cupsdAcceptClient: 12 from localhost (Domain)', 'D [13/Mar/2009:19:53:46 +0100] cupsdReadClient: 12 POST / HTTP/1.1', 'E [13/Mar/2009:19:53:46 +0100] cupsdAuthorize: Local authentication certificate not found!', 'D [13/Mar/2009:19:53:46 +0100] CUPS-Get-Printers', 'D [13/Mar/2009:19:53:46 +0100] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:46 +0100] cupsdReadClient: 12 POST / HTTP/1.1', 'E [13/Mar/2009:19:53:46 +0100] cupsdAuthorize: Local authentication certificate not found!', 'D [13/Mar/2009:19:53:46 +0100] CUPS-Get-Classes', 'D [13/Mar/2009:19:53:46 +0100] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:46 +0100] cupsdReadClient: 12 POST / HTTP/1.1', 'E [13/Mar/2009:19:53:46 +0100] cupsdAuthorize: Local authentication certificate not found!', 'D [13/Mar/2009:19:53:46 +0100] CUPS-Get-Default', 'D [13/Mar/2009:19:53:46 +0100] CUPS-Get-Default client-error-not-found: No default printer', 'D [13/Mar/2009:19:53:46 +0100] cupsdProcessIPPRequest: 12 status_code=406 (client-error-not-found)', 'D [13/Mar/2009:19:53:46 +0100] cupsdReadClient: 12 POST / HTTP/1.1', 'E [13/Mar/2009:19:53:46 +0100] cupsdAuthorize: Local authentication certificate not found!', 'D [13/Mar/2009:19:53:46 +0100] CUPS-Get-Printers', 'D [13/Mar/2009:19:53:46 +0100] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:46 +0100] cupsdReadClient: 12 POST / HTTP/1.1', 'E [13/Mar/2009:19:53:46 +0100] cupsdAuthorize: Local authentication certificate not found!', 'D [13/Mar/2009:19:53:46 +0100] CUPS-Get-Classes', 'D [13/Mar/2009:19:53:46 +0100] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:46 +0100] cupsdReadClient: 12 POST / HTTP/1.1', 'E [13/Mar/2009:19:53:46 +0100] cupsdAuthorize: Local authentication certificate not found!', 'D [13/Mar/2009:19:53:46 +0100] CUPS-Get-Default', 'D [13/Mar/2009:19:53:46 +0100] CUPS-Get-Default client-error-not-found: No default printer', 'D [13/Mar/2009:19:53:46 +0100] cupsdProcessIPPRequest: 12 status_code=406 (client-error-not-found)', 'D [13/Mar/2009:19:53:46 +0100] cupsdCloseClient: 10', 'D [13/Mar/2009:19:53:46 +0100] cupsdAcceptClient: 10 from localhost (Domain)', 'D [13/Mar/2009:19:53:46 +0100] cupsdReadClient: 10 POST / HTTP/1.1', 'D [13/Mar/2009:19:53:46 +0100] cupsdAuthorize: No authentication data provided.', 'D [13/Mar/2009:19:53:46 +0100] Get-Notifications /', 'D [13/Mar/2009:19:53:46 +0100] cupsdIsAuthorized: requesting-user-name="root"', 'D [13/Mar/2009:19:53:46 +0100] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:46 +0100] cupsdCloseClient: 10', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] foomatic-rip version $Revision$ running...', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Parsing PPD file ...', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Added option ColorSpace', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Added option PageSize', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Added option PageRegion', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Added option ImageableArea', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Added option PaperDimension', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Added option InputSlot', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Added option MediaType', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Added option Resolution', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Added option Quality', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Added option ColorMode', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Added option Copies', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Added option PrinterType', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Added option Nup', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Added option NupOrient', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Added option Font', 'D [13/Mar/2009:19:53:47 +0100] [Job 21]', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Parameter Summary', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] -----------------', 'D [13/Mar/2009:19:53:47 +0100] [Job 21]', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Spooler: cups', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Printer: LaserJet', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Shell: /bin/sh', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] PPD file: /etc/cups/ppd/LaserJet.ppd', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] ATTR file:', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Printer model: HP LaserJet 1000 Foomatic/foo2zjs (recommended)', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Job title: Test Page', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] File(s) to be printed:', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] ', 'D [13/Mar/2009:19:53:47 +0100] [Job 21]', "D [13/Mar/2009:19:53:47 +0100] [Job 21] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts", "D [13/Mar/2009:19:53:47 +0100] [Job 21] Pondering option 'job-uuid=urn:uuid:a061dbda-92cb-3e6c-4aeb-6665e08ffdca'", 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Unknown option job-uuid=urn:uuid:a061dbda-92cb-3e6c-4aeb-6665e08ffdca.', 'D [13/Mar/2009:19:53:47 +0100] [Job 21]', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] ================================================', 'D [13/Mar/2009:19:53:47 +0100] [Job 21]', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] File: ', 'D [13/Mar/2009:19:53:47 +0100] [Job 21]', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] ================================================', 'D [13/Mar/2009:19:53:47 +0100] [Job 21]', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Reading PostScript input ...', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] --> This document is DSC-conforming!', 'D [13/Mar/2009:19:53:47 +0100] [Job 21]', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] -----------', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %%BeginProlog', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %%EndProlog', 'D [13/Mar/2009:19:53:47 +0100] [Job 21]', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] -----------', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %%BeginSetup', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %%BeginFeature: *Quality normal', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Option: Quality=normal --> Setting option', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %% FoomaticRIPOptionSetting: Quality=normal', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Option: Quality=normal --> Setting option', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %%BeginFeature: *PageRegion A4', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Option: PageRegion=A4 --> Option will be set by PostScript interpreter', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %% FoomaticRIPOptionSetting: PageSize=A4', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Option: PageSize=A4 --> Setting option', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %%BeginFeature: *InputSlot Auto', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Option: InputSlot=Auto --> Setting option', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %% FoomaticRIPOptionSetting: InputSlot=Auto', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Option: InputSlot=Auto --> Setting option', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %%BeginFeature: *MediaType Standard', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Option: MediaType=Standard --> Setting option', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %% FoomaticRIPOptionSetting: MediaType=Standard', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Option: MediaType=Standard --> Setting option', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %%BeginFeature: *Nup 1up', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Option: Nup=1up --> Setting option', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %% FoomaticRIPOptionSetting: Nup=1up', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Option: Nup=1up --> Setting option', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %%BeginFeature: *NupOrient port', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Option: NupOrient=port --> Setting option', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %% FoomaticRIPOptionSetting: NupOrient=port', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Option: NupOrient=port --> Setting option', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %%BeginFeature: *Copies 1', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Option: Copies=1 --> Setting option', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %% FoomaticRIPOptionSetting: Copies=1', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Option: Copies=1 --> Setting option', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %%EndSetup', "D [13/Mar/2009:19:53:47 +0100] [Job 21] Inserting PostScript code for CUPS' page accounting", 'D [13/Mar/2009:19:53:47 +0100] [Job 21]', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] -----------', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] New page: 1 1', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Inserting option code into "PageSetup" section.', 'D [13/Mar/2009:19:53:47 +0100] [Job 21]', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %%BeginPageSetup', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %%EndPageSetup', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] End of page header', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Stopping search for page header options', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: lineto\t\t\t\t% Move there...', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] --> Output goes directly to the renderer now.', 'D [13/Mar/2009:19:53:47 +0100] [Job 21]', 'D [13/Mar/2009:19:53:47 +0100] [Job 21]', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Starting renderer', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] JCL: ', 'D [13/Mar/2009:19:53:47 +0100] [Job 21]', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %%Trailer', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] --> Continue DSC parsing now.', 'D [13/Mar/2009:19:53:47 +0100] [Job 21]', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %%Pages: 1', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] --> Continue DSC parsing now.', 'D [13/Mar/2009:19:53:47 +0100] [Job 21]', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %%BoundingBox: 0 0 612 792', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] --> Continue DSC parsing now.', 'D [13/Mar/2009:19:53:47 +0100] [Job 21]', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Found: %%EOF', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] --> Continue DSC parsing now.', 'D [13/Mar/2009:19:53:47 +0100] [Job 21]', 'D [13/Mar/2009:19:53:47 +0100] [Job 21]', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] Closing renderer', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] renderer PID kid4=4195', 'D [13/Mar/2009:19:53:47 +0100] [Job 21] renderer command: foo2zjs-wrapper -P -r600x600 -p9 -s7 -m1 -n1', 'E [13/Mar/2009:19:53:48 +0100] [Job 21] Unable to write print data: Input/output error', 'I [13/Mar/2009:19:53:48 +0100] Saving subscriptions.conf...', 'E [13/Mar/2009:19:53:48 +0100] PID 4189 (/usr/lib/cups/backend/hal) stopped with status 1!', 'D [13/Mar/2009:19:53:48 +0100] [Job 21] KID3 exited with status 0', 'D [13/Mar/2009:19:53:48 +0100] [Job 21] KID4 exited with status 9', 'D [13/Mar/2009:19:53:48 +0100] [Job 21] Renderer exit stat: 9', 'D [13/Mar/2009:19:53:48 +0100] [Job 21] Process dying with "error closing *main::STDOUT", exit stat: 9', 'D [13/Mar/2009:19:53:48 +0100] [Job 21] error: P\xc5\x99eru\xc5\xa1ena roura (SIGPIPE) (32)', 'D [13/Mar/2009:19:53:48 +0100] [Job 21] error closing *main::STDOUT', 'D [13/Mar/2009:19:53:48 +0100] [Job 21] KID3 finished', 'D [13/Mar/2009:19:53:48 +0100] [Job 21] Renderer process finished', 'D [13/Mar/2009:19:53:48 +0100] [Job 21] Killing process 4194 (KID3)', 'D [13/Mar/2009:19:53:48 +0100] [Job 21] Process dying with "Error closing renderer", exit stat: 9', 'D [13/Mar/2009:19:53:48 +0100] [Job 21] error: Chybn\xc3\xbd popisova\xc4\x8d souboru (9)', 'D [13/Mar/2009:19:53:48 +0100] [Job 21] Error closing renderer', 'E [13/Mar/2009:19:53:48 +0100] PID 4188 (/usr/lib/cups/filter/foomatic-rip) stopped with status 9!', 'D [13/Mar/2009:19:53:48 +0100] [Job 21] File 0 is complete.', 'I [13/Mar/2009:19:53:48 +0100] [Job 21] Backend returned status 1 (failed)', 'I [13/Mar/2009:19:53:48 +0100] Saving subscriptions.conf...', 'I [13/Mar/2009:19:53:48 +0100] Saving subscriptions.conf...', 'I [13/Mar/2009:19:53:48 +0100] Saving printers.conf...', 'I [13/Mar/2009:19:53:48 +0100] Saving subscriptions.conf...', 'D [13/Mar/2009:19:53:48 +0100] cupsdAcceptClient: 10 from localhost (Domain)', 'D [13/Mar/2009:19:53:48 +0100] cupsdReadClient: 10 POST / HTTP/1.1', 'D [13/Mar/2009:19:53:48 +0100] cupsdAuthorize: No authentication data provided.', 'D [13/Mar/2009:19:53:48 +0100] Get-Notifications /', 'D [13/Mar/2009:19:53:48 +0100] cupsdIsAuthorized: requesting-user-name="root"', 'D [13/Mar/2009:19:53:48 +0100] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:48 +0100] cupsdAcceptClient: 13 from localhost (Domain)', 'D [13/Mar/2009:19:53:48 +0100] cupsdReadClient: 13 POST / HTTP/1.1', 'D [13/Mar/2009:19:53:48 +0100] cupsdAuthorize: No authentication data provided.', 'D [13/Mar/2009:19:53:48 +0100] Get-Notifications /', 'D [13/Mar/2009:19:53:48 +0100] cupsdIsAuthorized: requesting-user-name="root"', 'D [13/Mar/2009:19:53:48 +0100] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:48 +0100] cupsdReadClient: 12 POST / HTTP/1.1', 'E [13/Mar/2009:19:53:48 +0100] cupsdAuthorize: Local authentication certificate not found!', 'D [13/Mar/2009:19:53:48 +0100] CUPS-Get-Printers', 'D [13/Mar/2009:19:53:48 +0100] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:48 +0100] cupsdReadClient: 12 POST / HTTP/1.1', 'E [13/Mar/2009:19:53:48 +0100] cupsdAuthorize: Local authentication certificate not found!', 'D [13/Mar/2009:19:53:48 +0100] CUPS-Get-Classes', 'D [13/Mar/2009:19:53:48 +0100] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:48 +0100] cupsdReadClient: 12 POST / HTTP/1.1', 'E [13/Mar/2009:19:53:48 +0100] cupsdAuthorize: Local authentication certificate not found!', 'D [13/Mar/2009:19:53:48 +0100] CUPS-Get-Default', 'D [13/Mar/2009:19:53:48 +0100] CUPS-Get-Default client-error-not-found: No default printer', 'D [13/Mar/2009:19:53:48 +0100] cupsdProcessIPPRequest: 12 status_code=406 (client-error-not-found)', 'D [13/Mar/2009:19:53:49 +0100] cupsdReadClient: 12 POST / HTTP/1.1', 'E [13/Mar/2009:19:53:49 +0100] cupsdAuthorize: Local authentication certificate not found!', 'D [13/Mar/2009:19:53:49 +0100] [Job 21] Unloading...', 'D [13/Mar/2009:19:53:49 +0100] CUPS-Get-Printers', 'D [13/Mar/2009:19:53:49 +0100] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:49 +0100] cupsdReadClient: 12 POST / HTTP/1.1', 'E [13/Mar/2009:19:53:49 +0100] cupsdAuthorize: Local authentication certificate not found!', 'D [13/Mar/2009:19:53:49 +0100] CUPS-Get-Classes', 'D [13/Mar/2009:19:53:49 +0100] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:49 +0100] cupsdReadClient: 12 POST / HTTP/1.1', 'E [13/Mar/2009:19:53:49 +0100] cupsdAuthorize: Local authentication certificate not found!', 'D [13/Mar/2009:19:53:49 +0100] CUPS-Get-Default', 'D [13/Mar/2009:19:53:49 +0100] CUPS-Get-Default client-error-not-found: No default printer', 'D [13/Mar/2009:19:53:49 +0100] cupsdProcessIPPRequest: 12 status_code=406 (client-error-not-found)', 'D [13/Mar/2009:19:53:49 +0100] cupsdReadClient: 12 POST / HTTP/1.1', 'E [13/Mar/2009:19:53:49 +0100] cupsdAuthorize: Local authentication certificate not found!', 'D [13/Mar/2009:19:53:49 +0100] CUPS-Get-Printers', 'D [13/Mar/2009:19:53:49 +0100] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:49 +0100] cupsdReadClient: 12 POST / HTTP/1.1', 'E [13/Mar/2009:19:53:49 +0100] cupsdAuthorize: Local authentication certificate not found!', 'D [13/Mar/2009:19:53:49 +0100] CUPS-Get-Classes', 'D [13/Mar/2009:19:53:49 +0100] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:49 +0100] cupsdReadClient: 12 POST / HTTP/1.1', 'E [13/Mar/2009:19:53:49 +0100] cupsdAuthorize: Local authentication certificate not found!', 'D [13/Mar/2009:19:53:49 +0100] CUPS-Get-Default', 'D [13/Mar/2009:19:53:49 +0100] CUPS-Get-Default client-error-not-found: No default printer', 'D [13/Mar/2009:19:53:49 +0100] cupsdProcessIPPRequest: 12 status_code=406 (client-error-not-found)', 'D [13/Mar/2009:19:53:49 +0100] cupsdCloseClient: 13', 'D [13/Mar/2009:19:53:49 +0100] cupsdAcceptClient: 13 from localhost (Domain)', 'D [13/Mar/2009:19:53:49 +0100] cupsdReadClient: 13 POST / HTTP/1.1', 'D [13/Mar/2009:19:53:49 +0100] cupsdAuthorize: No authentication data provided.', 'D [13/Mar/2009:19:53:49 +0100] Get-Notifications /', 'D [13/Mar/2009:19:53:49 +0100] cupsdIsAuthorized: requesting-user-name="root"', 'D [13/Mar/2009:19:53:49 +0100] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:49 +0100] cupsdCloseClient: 13', 'D [13/Mar/2009:19:53:49 +0100] cupsdCloseClient: 10', 'D [13/Mar/2009:19:53:51 +0100] cupsdAcceptClient: 10 from localhost (Domain)', 'D [13/Mar/2009:19:53:51 +0100] cupsdReadClient: 10 POST / HTTP/1.1', 'D [13/Mar/2009:19:53:51 +0100] cupsdAuthorize: No authentication data provided.', 'D [13/Mar/2009:19:53:51 +0100] Get-Jobs ipp://localhost/jobs/', 'D [13/Mar/2009:19:53:51 +0100] [Job 21] Loading attributes...', 'D [13/Mar/2009:19:53:51 +0100] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:51 +0100] cupsdCloseClient: 10', 'D [13/Mar/2009:19:53:51 +0100] cupsdAcceptClient: 10 from localhost (Domain)', 'D [13/Mar/2009:19:53:51 +0100] cupsdReadClient: 10 POST / HTTP/1.1', 'D [13/Mar/2009:19:53:51 +0100] cupsdAuthorize: No authentication data provided.', 'D [13/Mar/2009:19:53:51 +0100] Cancel-Subscription /', 'D [13/Mar/2009:19:53:51 +0100] cupsdIsAuthorized: requesting-user-name="root"', 'I [13/Mar/2009:19:53:51 +0100] Saving subscriptions.conf...', 'D [13/Mar/2009:19:53:51 +0100] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)', 'D [13/Mar/2009:19:53:51 +0100] cupsdAcceptClient: 13 from localhost (Domain)', 'D [13/Mar/2009:19:53:51 +0100] cupsdCloseClient: 10', 'D [13/Mar/2009:19:53:51 +0100] cupsdReadClient: 13 GET /admin/log/error_log HTTP/1.1', 'D [13/Mar/2009:19:53:51 +0100] cupsdAuthorize: No authentication data provided.'], 'error_log_debug_logging_unset': True} Page 9 (Printer state reasons): {'printer-state-message': u'Unable to write print data: Input/output error', 'printer-state-reasons': [u'paused']}