Page 1 (Scheduler not running?): {'cups_connection_failure': False} Page 2 (Choose printer): {'cups_dest': , 'cups_instance': None, 'cups_queue': 'Q1', 'cups_queue_listed': True} Page 3 (Check printer sanity): {'cups_device_uri_scheme': u'usb', 'cups_printer_dict': {'device-uri': u'usb://CUSTOM%20Engineering%20/Q1', 'printer-info': u'Custom Q1', 'printer-is-shared': True, 'printer-location': u'Kassa1', 'printer-make-and-model': u'Q1', 'printer-state': 3, 'printer-state-message': u'', 'printer-state-reasons': [u'none'], 'printer-type': 61444, 'printer-uri-supported': u'ipp://localhost:631/printers/Q1'}, 'cups_printer_remote': False, 'is_cups_class': False} Page 4 (Check PPD sanity): {'cups_printer_ppd_defaults': {u'General': {u'HalftoningAlgorithm': u'Accurate', u'PageRegion': u'X80MMY160MM', u'PageSize': u'X80MMY160MM'}, u'Q1OTIONS': {u'PageCutType': u'1TotalCutEndPage', u'PrintDensity': u'4Density0', u'PrinterQuality': u'1Normal'}}, 'cups_printer_ppd_valid': True, 'missing_pkgs_and_exes': ([], [])} Page 5 (Local or remote?): {'printer_is_remote': False} Page 6 (Choose device): {'cups_device_dict': {'device-class': u'direct', 'device-id': u'MFG:CUSTOM Engineering ;CMD:ESCPOS;MDL:Q1 ;CLS:PRINTER;', 'device-info': u'CUSTOM Engineering Q1 USB #1', 'device-make-and-model': u'CUSTOM Engineering Q1'}} Page 7 (Error log checkpoint): {'cups_server_settings': {'DefaultAuthType': 'Basic', 'SystemGroup': 'lpadmin', '_debug_logging': '0', '_remote_admin': '0', '_remote_any': '0', '_remote_printers': '0', '_share_printers': '0', '_user_cancel_any': '0'}, 'error_log_checkpoint': 5098L, 'error_log_debug_logging_set': True} Page 8 (Print test page): {'test_page_attempted': '30/jul/2009:11:22:36 +0000', 'test_page_completions': [(9, u'Job completed.')], 'test_page_job_id': [9], 'test_page_job_status': [(True, 9, 'Q1', 'Test Page', 'F\xc3\xa4rdig', {'attributes-charset': u'utf-8', 'attributes-natural-language': u'sv-se', 'document-format': u'application/postscript', 'job-hold-until': u'no-hold', 'job-id': 9, 'job-k-octets': 17, 'job-media-sheets-completed': 0, 'job-more-info': u'ipp://localhost:631/jobs/9', 'job-name': u'Test Page', 'job-originating-host-name': u'localhost', 'job-originating-user-name': u'ben', 'job-preserved': False, 'job-printer-state-message': u'Processing page 2...', 'job-printer-state-reasons': [u'none'], 'job-printer-up-time': 1248945766, 'job-printer-uri': u'ipp://Lillen:631/printers/Q1', 'job-priority': 50, 'job-sheets': [u'none', u'none'], 'job-state': 9, 'job-state-reasons': u'job-completed-successfully', 'job-uri': u'ipp://localhost:631/jobs/9', 'job-uuid': u'urn:uuid:bb08bfd6-0921-32ef-49c2-53506d00353b', 'printer-uri': u'ipp://localhost/printers/Q1', 'time-at-completed': 1248945760, 'time-at-creation': 1248945756, 'time-at-processing': 1248945756})], 'test_page_successful': False} Page 9 (Error log fetch): {'error_log': ['D [30/Jul/2009:11:22:33 +0200] cupsdReadClient: 8 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:33 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:33 +0200] Get-Jobs ipp://localhost/printers/', 'D [30/Jul/2009:11:22:33 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:33 +0200] cupsdReadClient: 8 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:33 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:33 +0200] Get-Jobs ipp://localhost/printers/', 'D [30/Jul/2009:11:22:33 +0200] [Job 6] Loading attributes...', 'D [30/Jul/2009:11:22:33 +0200] [Job 7] Loading attributes...', 'D [30/Jul/2009:11:22:33 +0200] [Job 8] Loading attributes...', 'D [30/Jul/2009:11:22:33 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:33 +0200] cupsdReadClient: 8 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:33 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:33 +0200] Create-Printer-Subscription /', 'D [30/Jul/2009:11:22:33 +0200] cupsdCreateSubscription(con=0xb90d45a8(8), uri="/")', 'D [30/Jul/2009:11:22:33 +0200] pullmethod="ippget"', 'D [30/Jul/2009:11:22:33 +0200] notify-lease-duration=86400', 'D [30/Jul/2009:11:22:33 +0200] notify-time-interval=0', 'D [30/Jul/2009:11:22:33 +0200] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)")', 'D [30/Jul/2009:11:22:33 +0200] Added subscription 6 for server', 'I [30/Jul/2009:11:22:33 +0200] Saving subscriptions.conf...', 'D [30/Jul/2009:11:22:33 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:34 +0200] cupsdReadClient: 8 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:34 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:34 +0200] Get-Notifications /', 'D [30/Jul/2009:11:22:34 +0200] cupsdIsAuthorized: requesting-user-name="ben"', 'D [30/Jul/2009:11:22:34 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:36 +0200] cupsdAcceptClient: 9 from localhost (Domain)', 'D [30/Jul/2009:11:22:36 +0200] cupsdReadClient: 9 POST /printers/Q1 HTTP/1.1', 'D [30/Jul/2009:11:22:36 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:36 +0200] Print-Job ipp://localhost/printers/Q1', 'D [30/Jul/2009:11:22:36 +0200] [Job ???] Auto-typing file...', 'I [30/Jul/2009:11:22:36 +0200] [Job ???] Request file type is application/postscript.', 'D [30/Jul/2009:11:22:36 +0200] add_job: requesting-user-name="ben"', 'D [30/Jul/2009:11:22:36 +0200] Adding default job-sheets values "none,none"...', 'I [30/Jul/2009:11:22:36 +0200] [Job 9] Adding start banner page "none".', 'I [30/Jul/2009:11:22:36 +0200] Saving subscriptions.conf...', 'I [30/Jul/2009:11:22:36 +0200] [Job 9] Adding end banner page "none".', 'I [30/Jul/2009:11:22:36 +0200] [Job 9] File of type application/postscript queued by "ben".', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] hold_until=0', 'I [30/Jul/2009:11:22:36 +0200] [Job 9] Queued on "Q1" by "ben".', 'I [30/Jul/2009:11:22:36 +0200] Saving subscriptions.conf...', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] job-sheets=none,none', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] banner_page = 0', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] argv[0]="Q1"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] argv[1]="9"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] argv[2]="ben"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] argv[3]="Test Page"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] argv[4]="1"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] argv[5]="job-uuid=urn:uuid:bb08bfd6-0921-32ef-49c2-53506d00353b"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] argv[6]="/var/spool/cups/d00009-001"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[0]="CUPS_CACHEDIR=/var/cache/cups"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[1]="CUPS_DATADIR=/usr/share/cups"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[6]="CUPS_SERVERROOT=/etc/cups"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[7]="CUPS_STATEDIR=/var/run/cups"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[9]="SERVER_ADMIN=root@Lillen"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[10]="SOFTWARE=CUPS/1.3.9"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[11]="TMPDIR=/var/spool/cups/tmp"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[12]="TZ=Europe/Stockholm"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[13]="USER=root"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[15]="CUPS_ENCRYPTION=IfRequested"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[16]="IPP_PORT=631"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[17]="CHARSET=utf-8"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[18]="LANG=sv_SE.UTF8"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[19]="PPD=/etc/cups/ppd/Q1.ppd"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[20]="RIP_MAX_CACHE=8m"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[21]="CONTENT_TYPE=application/postscript"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[22]="DEVICE_URI=usb://CUSTOM%20Engineering%20/Q1"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[23]="PRINTER=Q1"', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] envp[24]="FINAL_CONTENT_TYPE=printer/Q1"', 'I [30/Jul/2009:11:22:36 +0200] [Job 9] Started filter /usr/lib/cups/filter/pstopdf (PID 15072)', 'I [30/Jul/2009:11:22:36 +0200] [Job 9] Started filter /usr/lib/cups/filter/pdftopdf (PID 15073)', 'I [30/Jul/2009:11:22:36 +0200] [Job 9] Started filter /usr/lib/cups/filter/pdftoraster (PID 15074)', 'I [30/Jul/2009:11:22:36 +0200] [Job 9] Started filter /usr/lib/cups/filter/rastertoQ1 (PID 15075)', 'I [30/Jul/2009:11:22:36 +0200] [Job 9] Started backend /usr/lib/cups/backend/usb (PID 15076)', 'I [30/Jul/2009:11:22:36 +0200] Saving subscriptions.conf...', 'D [30/Jul/2009:11:22:36 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] Printer using device file "/dev/usblp0"...', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] backendRunLoop(print_fd=0, device_fd=5, use_bc=1, side_cb=0xb7fa6a80)', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] pstopdf 6 args: 9 ben Test Page 1 job-uuid=urn:uuid:bb08bfd6-0921-32ef-49c2-53506d00353b /var/spool/cups/d00009-001', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] PPD: /etc/cups/ppd/Q1.ppd', 'I [30/Jul/2009:11:22:36 +0200] Saving subscriptions.conf...', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] Resolution:', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] Page size: X80MMY160MM', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] Width: , height: , absolute margins: , , ,', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] Relative margins: , , ,', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] PPD options:', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] PostScript to be injected:', 'D [30/Jul/2009:11:22:36 +0200] [Job 9] Running cat | /usr/bin/ps2pdf13 -dAutoRotatePages=/None -dAutoFilterColorImages=false -dNOPLATFONTS -dPARANOIDSAFER -sstdout=%stderr -dColorImageFilter=/FlateEncode -dDoNumCopies -dPDFSETTINGS=/printer - -', 'D [30/Jul/2009:11:22:36 +0200] cupsdAcceptClient: 12 from localhost (Domain)', 'D [30/Jul/2009:11:22:36 +0200] cupsdReadClient: 12 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:36 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:36 +0200] Get-Jobs ipp://localhost/printers/', 'D [30/Jul/2009:11:22:36 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:36 +0200] cupsdAcceptClient: 13 from localhost (Domain)', 'D [30/Jul/2009:11:22:36 +0200] cupsdReadClient: 13 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:36 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:36 +0200] Get-Notifications /', 'D [30/Jul/2009:11:22:36 +0200] cupsdIsAuthorized: requesting-user-name="ben"', 'D [30/Jul/2009:11:22:36 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:37 +0200] cupsdAcceptClient: 14 from localhost (Domain)', 'D [30/Jul/2009:11:22:37 +0200] cupsdReadClient: 14 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:37 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:37 +0200] Get-Notifications /', 'D [30/Jul/2009:11:22:37 +0200] cupsdIsAuthorized: requesting-user-name="ben"', 'D [30/Jul/2009:11:22:37 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:37 +0200] cupsdCloseClient: 12', 'D [30/Jul/2009:11:22:37 +0200] cupsdAcceptClient: 12 from localhost (Domain)', 'D [30/Jul/2009:11:22:37 +0200] cupsdReadClient: 12 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:37 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:37 +0200] Get-Notifications /', 'D [30/Jul/2009:11:22:37 +0200] cupsdIsAuthorized: requesting-user-name="ben"', 'D [30/Jul/2009:11:22:37 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:37 +0200] cupsdAcceptClient: 15 from localhost (Domain)', 'D [30/Jul/2009:11:22:37 +0200] cupsdReadClient: 15 POST / HTTP/1.1', 'E [30/Jul/2009:11:22:37 +0200] cupsdAuthorize: Local authentication certificate not found!', 'D [30/Jul/2009:11:22:37 +0200] Get-Printer-Attributes ipp://localhost/printers/Q1', 'D [30/Jul/2009:11:22:37 +0200] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:37 +0200] cupsdCloseClient: 14', 'D [30/Jul/2009:11:22:37 +0200] cupsdReadClient: 8 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:37 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:37 +0200] Get-Notifications /', 'D [30/Jul/2009:11:22:37 +0200] cupsdIsAuthorized: requesting-user-name="ben"', 'D [30/Jul/2009:11:22:37 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:37 +0200] cupsdReadClient: 12 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:37 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:37 +0200] Get-Job-Attributes ipp://localhost/jobs/9', 'D [30/Jul/2009:11:22:37 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:37 +0200] cupsdCloseClient: 12', 'D [30/Jul/2009:11:22:37 +0200] cupsdReadClient: 15 POST / HTTP/1.1', 'E [30/Jul/2009:11:22:37 +0200] cupsdAuthorize: Local authentication certificate not found!', 'D [30/Jul/2009:11:22:37 +0200] Get-Printer-Attributes ipp://localhost/printers/Q1', 'D [30/Jul/2009:11:22:37 +0200] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:37 +0200] cupsdCloseClient: 13', 'D [30/Jul/2009:11:22:37 +0200] [Job 9] GPL Ghostscript 8.64: Set UseCIEColor for UseDeviceIndependentColor to work properly.', 'D [30/Jul/2009:11:22:38 +0200] PID 15072 (/usr/lib/cups/filter/pstopdf) exited with no errors.', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] Ghostscript command line: /usr/bin/gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -sDEVICE=cups -sstdout=%stderr -sOutputFile=%stdout -I/usr/share/cups/fonts -r199x199 -dDEVICEWIDTHPOINTS=565 -dDEVICEHEIGHTPOINTS=437 -dcupsBitsPerColor=1 -dcupsColorOrder=0 -dcupsColorSpace=3 -scupsPageSizeName=X80MMY160MM -c -f -_', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[0]="CUPS_CACHEDIR=/var/cache/cups"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[1]="CUPS_DATADIR=/usr/share/cups"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[6]="CUPS_SERVERROOT=/etc/cups"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[7]="CUPS_STATEDIR=/var/run/cups"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[9]="SERVER_ADMIN=root@Lillen"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[10]="SOFTWARE=CUPS/1.3.9"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[11]="TZ=Europe/Stockholm"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[12]="USER=root"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[13]="CUPS_SERVER=/var/run/cups/cups.sock"', 'D [30/Jul/2009:11:22:38 +0200] PID 15073 (/usr/lib/cups/filter/pdftopdf) exited with no errors.', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[14]="CUPS_ENCRYPTION=IfRequested"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[15]="IPP_PORT=631"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[16]="CHARSET=utf-8"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[17]="LANG=sv_SE.UTF8"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[18]="PPD=/etc/cups/ppd/Q1.ppd"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[19]="RIP_MAX_CACHE=8m"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[20]="CONTENT_TYPE=application/postscript"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[21]="DEVICE_URI=usb://CUSTOM%20Engineering%20/Q1"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[22]="PRINTER=Q1"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] envp[23]="FINAL_CONTENT_TYPE=printer/Q1"', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] num_components = 1, depth = 1', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] cupsColorSpace = 3, cupsColorOrder = 0', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] cupsBitsPerPixel = 1, cupsBitsPerColor = 1', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] max_gray = 1, dither_grays = 2', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] max_color = 0, dither_colors = 0', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] Updating PageSize to [565 437]...', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] Setting initial media size, [565 437] = 1562x1208 pixels...', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] Setting cupsBitsPerColor to 1...', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] Setting cupsColorOrder to 0...', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] Setting cupsColorSpace to 3...', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] Setting cupsPageSizeName to "X80MMY160MM"...', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] num_components = 1, depth = 1', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] cupsColorSpace = 3, cupsColorOrder = 0', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] cupsBitsPerPixel = 1, cupsBitsPerColor = 1', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] max_gray = 1, dither_grays = 2', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] max_color = 0, dither_colors = 0', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] Setting initial media size, [565 437] = 1562x1208 pixels...', 'I [30/Jul/2009:11:22:38 +0200] Saving subscriptions.conf...', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] num_components = 1, depth = 1', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] cupsColorSpace = 3, cupsColorOrder = 0', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] cupsBitsPerPixel = 1, cupsBitsPerColor = 1', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] max_gray = 1, dither_grays = 2', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] max_color = 0, dither_colors = 0', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] Setting LeadingEdge to 0...', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] num_components = 1, depth = 1', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] cupsColorSpace = 3, cupsColorOrder = 0', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] cupsBitsPerPixel = 1, cupsBitsPerColor = 1', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] max_gray = 1, dither_grays = 2', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] max_color = 0, dither_colors = 0', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] Updating PageSize to [565 437]...', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] size = X80MMY160MM', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] margins[] = [ 0.000000 0.000000 0.000000 0.000000 ]', 'D [30/Jul/2009:11:22:38 +0200] PID 15074 (/usr/lib/cups/filter/pdftoraster) exited with no errors.', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] Setting LeadingEdge to 0...', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] num_components = 1, depth = 1', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] cupsColorSpace = 3, cupsColorOrder = 0', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] cupsBitsPerPixel = 1, cupsBitsPerColor = 1', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] max_gray = 1, dither_grays = 2', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] max_color = 0, dither_colors = 0', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] Setting LeadingEdge to 0...', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] num_components = 1, depth = 1', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] cupsColorSpace = 3, cupsColorOrder = 0', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] cupsBitsPerPixel = 1, cupsBitsPerColor = 1', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] max_gray = 1, dither_grays = 2', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] max_color = 0, dither_colors = 0', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] Updating PageSize to [595 842]...', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] size = Custom', 'D [30/Jul/2009:11:22:38 +0200] [Job 9] margins[] = [ 0.000000 0.000000 0.000000 0.000000 ]', 'D [30/Jul/2009:11:22:39 +0200] cupsdAcceptClient: 12 from localhost (Domain)', 'D [30/Jul/2009:11:22:39 +0200] cupsdReadClient: 12 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:39 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:39 +0200] Get-Jobs ipp://localhost/printers/', 'D [30/Jul/2009:11:22:39 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:39 +0200] cupsdAcceptClient: 13 from localhost (Domain)', 'D [30/Jul/2009:11:22:39 +0200] cupsdReadClient: 13 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:39 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:39 +0200] Get-Notifications /', 'D [30/Jul/2009:11:22:39 +0200] cupsdIsAuthorized: requesting-user-name="ben"', 'D [30/Jul/2009:11:22:39 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:39 +0200] cupsdAcceptClient: 14 from localhost (Domain)', 'D [30/Jul/2009:11:22:39 +0200] cupsdReadClient: 15 POST / HTTP/1.1', 'E [30/Jul/2009:11:22:39 +0200] cupsdAuthorize: Local authentication certificate not found!', 'D [30/Jul/2009:11:22:39 +0200] Get-Printer-Attributes ipp://localhost/printers/Q1', 'D [30/Jul/2009:11:22:39 +0200] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:39 +0200] cupsdCloseClient: 12', 'D [30/Jul/2009:11:22:39 +0200] cupsdAcceptClient: 12 from localhost (Domain)', 'D [30/Jul/2009:11:22:39 +0200] cupsdReadClient: 14 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:39 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:39 +0200] Get-Notifications /', 'D [30/Jul/2009:11:22:39 +0200] cupsdIsAuthorized: requesting-user-name="ben"', 'D [30/Jul/2009:11:22:39 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:39 +0200] cupsdReadClient: 12 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:39 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:39 +0200] Get-Notifications /', 'D [30/Jul/2009:11:22:39 +0200] cupsdIsAuthorized: requesting-user-name="ben"', 'D [30/Jul/2009:11:22:39 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:39 +0200] cupsdCloseClient: 14', 'D [30/Jul/2009:11:22:39 +0200] cupsdCloseClient: 12', 'D [30/Jul/2009:11:22:39 +0200] cupsdReadClient: 8 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:39 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:39 +0200] Get-Notifications /', 'D [30/Jul/2009:11:22:39 +0200] cupsdIsAuthorized: requesting-user-name="ben"', 'D [30/Jul/2009:11:22:39 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:39 +0200] cupsdCloseClient: 13', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] cups_print_chunked - flip = 0, height = 2327', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Read 4096 bytes of print data...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Wrote 4096 bytes of print data...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Read 8192 bytes of print data...', 'I [30/Jul/2009:11:22:39 +0200] Saving subscriptions.conf...', 'I [30/Jul/2009:11:22:39 +0200] Saving subscriptions.conf...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting AdvanceDistance to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting AdvanceMedia to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting Collate to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting CutMedia to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting Duplex to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting Jog to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting LeadingEdge to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting Margins to 0 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting MirrorPrint to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting NegativePrint to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting OutputFaceUp to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting Separations to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting TraySwitch to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting Tumble to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsMediaType to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsBitsPerColor to 1...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsColorOrder to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsColorSpace to 3...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsCompression to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsRowCount to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsRowFeed to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsRowStep to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsBorderlessScalingFactor to 1.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger0 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger1 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger2 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger3 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger4 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger5 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger6 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger7 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger8 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger9 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger10 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger11 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger12 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger13 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger14 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger15 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal0 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal1 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal2 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal3 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal4 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal5 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal6 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal7 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal8 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal9 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal10 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal11 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal12 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal13 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal14 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal15 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString0 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString1 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString2 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString3 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString4 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString5 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString6 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString7 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString8 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString9 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString10 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString11 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString12 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString13 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString14 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString15 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsMarkerType to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsRenderingIntent to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsPageSizeName to "X80MMY160MM"...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] num_components = 1, depth = 1', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] cupsColorSpace = 3, cupsColorOrder = 0', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] cupsBitsPerPixel = 1, cupsBitsPerColor = 1', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] max_gray = 1, dither_grays = 2', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] max_color = 0, dither_colors = 0', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Updating PageSize to [595 842]...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] size = Custom', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] margins[] = [ 0.000000 0.000000 0.000000 0.000000 ]', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting AdvanceDistance to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting AdvanceMedia to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting Collate to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting CutMedia to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting Duplex to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting Jog to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting LeadingEdge to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting Margins to 0 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting MirrorPrint to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting NegativePrint to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting OutputFaceUp to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting Separations to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting TraySwitch to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting Tumble to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsMediaType to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsBitsPerColor to 1...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsColorOrder to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsColorSpace to 3...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsCompression to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsRowCount to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsRowFeed to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsRowStep to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsBorderlessScalingFactor to 1.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger0 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger1 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger2 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger3 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger4 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger5 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger6 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger7 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger8 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger9 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger10 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger11 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger12 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger13 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger14 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsInteger15 to 0...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal0 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal1 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal2 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal3 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal4 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal5 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal6 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal7 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal8 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal9 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal10 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal11 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal12 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal13 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal14 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsReal15 to 0.0000...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString0 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString1 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString2 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString3 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString4 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString5 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString6 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString7 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString8 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString9 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString10 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString11 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString12 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString13 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString14 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsString15 to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsMarkerType to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsRenderingIntent to ""...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Setting cupsPageSizeName to "X80MMY160MM"...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] num_components = 1, depth = 1', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] cupsColorSpace = 3, cupsColorOrder = 0', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] cupsBitsPerPixel = 1, cupsBitsPerColor = 1', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] max_gray = 1, dither_grays = 2', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] max_color = 0, dither_colors = 0', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Updating PageSize to [565 437]...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] size = X80MMY160MM', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] margins[] = [ 0.000000 0.000000 0.000000 0.000000 ]', 'D [30/Jul/2009:11:22:39 +0200] PID 15075 (/usr/lib/cups/filter/rastertoQ1) exited with no errors.', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Wrote 8192 bytes of print data...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Read 8192 bytes of print data...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Wrote 8192 bytes of print data...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Read 8192 bytes of print data...', 'D [30/Jul/2009:11:22:39 +0200] cupsdAcceptClient: 12 from localhost (Domain)', 'D [30/Jul/2009:11:22:39 +0200] cupsdAcceptClient: 13 from localhost (Domain)', 'D [30/Jul/2009:11:22:39 +0200] cupsdReadClient: 12 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:39 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:39 +0200] Get-Notifications /', 'D [30/Jul/2009:11:22:39 +0200] cupsdIsAuthorized: requesting-user-name="ben"', 'D [30/Jul/2009:11:22:39 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:39 +0200] cupsdAcceptClient: 14 from localhost (Domain)', 'D [30/Jul/2009:11:22:39 +0200] cupsdReadClient: 13 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:39 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:39 +0200] Get-Notifications /', 'D [30/Jul/2009:11:22:39 +0200] cupsdIsAuthorized: requesting-user-name="ben"', 'D [30/Jul/2009:11:22:39 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:39 +0200] cupsdReadClient: 14 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:39 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:39 +0200] Get-Jobs ipp://localhost/printers/', 'D [30/Jul/2009:11:22:39 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:39 +0200] cupsdCloseClient: 14', 'D [30/Jul/2009:11:22:39 +0200] cupsdReadClient: 15 POST / HTTP/1.1', 'E [30/Jul/2009:11:22:39 +0200] cupsdAuthorize: Local authentication certificate not found!', 'D [30/Jul/2009:11:22:39 +0200] Get-Printer-Attributes ipp://localhost/printers/Q1', 'D [30/Jul/2009:11:22:39 +0200] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:39 +0200] cupsdAcceptClient: 14 from localhost (Domain)', 'D [30/Jul/2009:11:22:39 +0200] cupsdCloseClient: 13', 'D [30/Jul/2009:11:22:39 +0200] cupsdReadClient: 14 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:39 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:39 +0200] Get-Notifications /', 'D [30/Jul/2009:11:22:39 +0200] cupsdIsAuthorized: requesting-user-name="ben"', 'D [30/Jul/2009:11:22:39 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:39 +0200] cupsdCloseClient: 14', 'D [30/Jul/2009:11:22:39 +0200] cupsdReadClient: 8 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:39 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:39 +0200] Get-Notifications /', 'D [30/Jul/2009:11:22:39 +0200] cupsdIsAuthorized: requesting-user-name="ben"', 'D [30/Jul/2009:11:22:39 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Wrote 8192 bytes of print data...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Read 8192 bytes of print data...', 'D [30/Jul/2009:11:22:39 +0200] cupsdReadClient: 15 POST / HTTP/1.1', 'E [30/Jul/2009:11:22:39 +0200] cupsdAuthorize: Local authentication certificate not found!', 'D [30/Jul/2009:11:22:39 +0200] Get-Printer-Attributes ipp://localhost/printers/Q1', 'D [30/Jul/2009:11:22:39 +0200] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Wrote 8192 bytes of print data...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Read 8192 bytes of print data...', 'D [30/Jul/2009:11:22:39 +0200] cupsdCloseClient: 12', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Wrote 8192 bytes of print data...', 'D [30/Jul/2009:11:22:39 +0200] [Job 9] Read 8192 bytes of print data...', 'D [30/Jul/2009:11:22:40 +0200] [Job 9] Wrote 8192 bytes of print data...', 'D [30/Jul/2009:11:22:40 +0200] [Job 9] Read 8192 bytes of print data...', 'D [30/Jul/2009:11:22:40 +0200] [Job 9] Wrote 8192 bytes of print data...', 'D [30/Jul/2009:11:22:40 +0200] [Job 9] Read 8192 bytes of print data...', 'D [30/Jul/2009:11:22:40 +0200] [Job 9] Wrote 8192 bytes of print data...', 'D [30/Jul/2009:11:22:40 +0200] [Job 9] Read 5960 bytes of print data...', 'D [30/Jul/2009:11:22:40 +0200] [Job 9] Wrote 5960 bytes of print data...', 'D [30/Jul/2009:11:22:40 +0200] PID 15076 (/usr/lib/cups/backend/usb) exited with no errors.', 'D [30/Jul/2009:11:22:40 +0200] [Job 9] File 0 is complete.', 'I [30/Jul/2009:11:22:40 +0200] [Job 9] Completed successfully.', 'I [30/Jul/2009:11:22:40 +0200] Saving subscriptions.conf...', 'I [30/Jul/2009:11:22:40 +0200] Saving subscriptions.conf...', 'D [30/Jul/2009:11:22:40 +0200] cupsdAcceptClient: 12 from localhost (Domain)', 'D [30/Jul/2009:11:22:40 +0200] cupsdAcceptClient: 13 from localhost (Domain)', 'D [30/Jul/2009:11:22:40 +0200] cupsdReadClient: 12 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:40 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:40 +0200] Get-Jobs ipp://localhost/printers/', 'D [30/Jul/2009:11:22:40 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:40 +0200] cupsdAcceptClient: 14 from localhost (Domain)', 'D [30/Jul/2009:11:22:40 +0200] cupsdReadClient: 13 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:40 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:40 +0200] Get-Notifications /', 'D [30/Jul/2009:11:22:40 +0200] cupsdIsAuthorized: requesting-user-name="ben"', 'D [30/Jul/2009:11:22:40 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:40 +0200] cupsdReadClient: 14 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:40 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:40 +0200] Get-Notifications /', 'D [30/Jul/2009:11:22:40 +0200] cupsdIsAuthorized: requesting-user-name="ben"', 'D [30/Jul/2009:11:22:40 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:40 +0200] cupsdCloseClient: 12', 'D [30/Jul/2009:11:22:40 +0200] cupsdAcceptClient: 12 from localhost (Domain)', 'D [30/Jul/2009:11:22:40 +0200] cupsdReadClient: 12 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:40 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:40 +0200] Get-Notifications /', 'D [30/Jul/2009:11:22:40 +0200] cupsdIsAuthorized: requesting-user-name="ben"', 'D [30/Jul/2009:11:22:40 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:40 +0200] cupsdCloseClient: 13', 'D [30/Jul/2009:11:22:40 +0200] cupsdReadClient: 8 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:40 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:40 +0200] Get-Notifications /', 'D [30/Jul/2009:11:22:40 +0200] cupsdIsAuthorized: requesting-user-name="ben"', 'D [30/Jul/2009:11:22:40 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:40 +0200] cupsdReadClient: 15 POST / HTTP/1.1', 'E [30/Jul/2009:11:22:40 +0200] cupsdAuthorize: Local authentication certificate not found!', 'D [30/Jul/2009:11:22:40 +0200] Get-Printer-Attributes ipp://localhost/printers/Q1', 'D [30/Jul/2009:11:22:40 +0200] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:40 +0200] cupsdCloseClient: 14', 'D [30/Jul/2009:11:22:40 +0200] cupsdCloseClient: 12', 'D [30/Jul/2009:11:22:41 +0200] [Job 9] Unloading...', 'D [30/Jul/2009:11:22:46 +0200] cupsdReadClient: 8 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:46 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:46 +0200] Get-Job-Attributes ipp://localhost/jobs/9', 'D [30/Jul/2009:11:22:46 +0200] [Job 9] Loading attributes...', 'D [30/Jul/2009:11:22:46 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:46 +0200] cupsdReadClient: 8 POST / HTTP/1.1', 'D [30/Jul/2009:11:22:46 +0200] cupsdAuthorize: No authentication data provided.', 'D [30/Jul/2009:11:22:46 +0200] Cancel-Subscription /', 'D [30/Jul/2009:11:22:46 +0200] cupsdIsAuthorized: requesting-user-name="ben"', 'I [30/Jul/2009:11:22:46 +0200] Saving subscriptions.conf...', 'D [30/Jul/2009:11:22:46 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)', 'D [30/Jul/2009:11:22:46 +0200] cupsdAcceptClient: 12 from localhost (Domain)', 'D [30/Jul/2009:11:22:46 +0200] cupsdCloseClient: 9', 'D [30/Jul/2009:11:22:46 +0200] cupsdReadClient: 12 GET /admin/log/error_log HTTP/1.1', 'D [30/Jul/2009:11:22:46 +0200] cupsdAuthorize: No authentication data provided.'], 'error_log_debug_logging_unset': True} Page 10 (Locale issues): {'printer_page_size': u'X80MMY160MM', 'system_locale_lang': None, 'user_locale_ctype': 'sv_SE', 'user_locale_messages': 'sv_SE'}