Page 1 (Scheduler not running?): {'cups_connection_failure': False} Page 2 (Choose printer): {'cups_dest': , 'cups_instance': None, 'cups_queue': 'Photosmart_Pro_B9100', 'cups_queue_listed': True} Page 3 (Check printer sanity): {'cups_device_uri_scheme': u'hp', 'cups_printer_dict': {'device-uri': u'hp:/usb/Photosmart_Pro_B9100_series?serial=MY6A22104B04C1', 'printer-info': u'Photosmart_Pro_B9100', 'printer-is-shared': True, 'printer-location': u'', 'printer-make-and-model': u'HP PS Pro b8800 Series hpijs, hpijs 3.9.2.49 - HPLIP 3.9.2', 'printer-state': 3, 'printer-state-message': u'/usr/lib/cups/filter/foomatic-rip failed', 'printer-state-reasons': [u'none'], 'printer-type': 176156, 'printer-uri-supported': u'ipp://localhost:631/printers/Photosmart_Pro_B9100'}, 'cups_printer_remote': False, 'hplip_output': (['', '\x1b[01mHP Linux Imaging and Printing System (ver. 3.9.2)\x1b[0m', '\x1b[01mDevice Information Utility ver. 5.2\x1b[0m', '', 'Copyright (c) 2001-9 Hewlett-Packard Development Company, LP', 'This software comes with ABSOLUTELY NO WARRANTY.', 'This is free software, and you are welcome to distribute it', 'under certain conditions. See COPYING file for more details.', '', '', '\x1b[01mhp:/usb/Photosmart_Pro_B9100_series?serial=MY6A22104B04C1\x1b[0m', '', '\x1b[01mDevice Parameters (dynamic data):\x1b[0m', '\x1b[01m Parameter Value(s) \x1b[0m', ' ---------------------------- ----------------------------------------------------------', ' agent1-ack False ', ' agent1-desc Light gray and photo black print head ', ' agent1-dvc 0 ', ' agent1-health 1 ', ' agent1-health-desc Not installed ', ' agent1-hp-ink False ', ' agent1-id 0 ', ' agent1-kind 1 ', ' agent1-known False ', ' agent1-level 0 ', ' agent1-level-trigger 0 ', ' agent1-sku C9407A ', ' agent1-type 16 ', ' agent1-virgin False ', ' agent10-ack False ', ' agent10-desc Yellow ink cartridge ', ' agent10-dvc 0 ', ' agent10-health 0 ', ' agent10-health-desc Good/OK ', ' agent10-hp-ink False ', ' agent10-id 0 ', ' agent10-kind 2 ', ' agent10-known False ', ' agent10-level 19 ', ' agent10-level-trigger 6 ', ' agent10-sku C9417A ', ' agent10-type 6 ', ' agent10-virgin False ', ' agent11-ack False ', ' agent11-desc Photo cyan ink cartridge ', ' agent11-dvc 0 ', ' agent11-health 0 ', ' agent11-health-desc Good/OK ', ' agent11-hp-ink False ', ' agent11-id 0 ', ' agent11-kind 2 ', ' agent11-known False ', ' agent11-level 0 ', ' agent11-level-trigger 6 ', ' agent11-sku C9418A ', ' agent11-type 7 ', ' agent11-virgin False ', ' agent12-ack False ', ' agent12-desc Photo magenta ink cartridge ', ' agent12-dvc 0 ', ' agent12-health 0 ', ' agent12-health-desc Good/OK ', ' agent12-hp-ink False ', ' agent12-id 0 ', ' agent12-kind 2 ', ' agent12-known False ', ' agent12-level 0 ', ' agent12-level-trigger 6 ', ' agent12-sku C9419A ', ' agent12-type 8 ', ' agent12-virgin False ', ' agent2-ack False ', ' agent2-desc Black and yellow print head ', ' agent2-dvc 0 ', ' agent2-health 0 ', ' agent2-health-desc Good/OK ', ' agent2-hp-ink False ', ' agent2-id 0 ', ' agent2-kind 1 ', ' agent2-known False ', ' agent2-level 0 ', ' agent2-level-trigger 0 ', ' agent2-sku C9406A ', ' agent2-type 14 ', ' agent2-virgin False ', ' agent3-ack False ', ' agent3-desc Photo magenta and photo cyan print head ', ' agent3-dvc 0 ', ' agent3-health 0 ', ' agent3-health-desc Good/OK ', ' agent3-hp-ink False ', ' agent3-id 0 ', ' agent3-kind 1 ', ' agent3-known False ', ' agent3-level 0 ', ' agent3-level-trigger 0 ', ' agent3-sku C9405A ', ' agent3-type 13 ', ' agent3-virgin False ', ' agent4-ack False ', ' agent4-desc Cyan and magenta print head ', ' agent4-dvc 0 ', ' agent4-health 0 ', ' agent4-health-desc Good/OK ', ' agent4-hp-ink False ', ' agent4-id 0 ', ' agent4-kind 1 ', ' agent4-known False ', ' agent4-level 0 ', ' agent4-level-trigger 0 ', ' agent4-sku C9404A ', ' agent4-type 15 ', ' agent4-virgin False ', ' agent5-ack False ', ' agent5-desc Black ink cartridge ', ' agent5-dvc 0 ', ' agent5-health 0 ', ' agent5-health-desc Good/OK ', ' agent5-hp-ink False ', ' agent5-id 0 ', ' agent5-kind 2 ', ' agent5-known False ', ' agent5-level 17 ', ' agent5-level-trigger 6 ', ' agent5-sku C9412A ', ' agent5-type 1 ', ' agent5-virgin False ', ' agent6-ack False ', ' agent6-desc Photo black ink cartridge ', ' agent6-dvc 0 ', ' agent6-health 0 ', ' agent6-health-desc Good/OK ', ' agent6-hp-ink False ', ' agent6-id 0 ', ' agent6-kind 2 ', ' agent6-known False ', ' agent6-level 0 ', ' agent6-level-trigger 0 ', ' agent6-sku C9413A ', ' agent6-type 19 ', ' agent6-virgin False ', ' agent7-ack False ', ' agent7-desc Light gray ink cartridge ', ' agent7-dvc 0 ', ' agent7-health 0 ', ' agent7-health-desc Good/OK ', ' agent7-hp-ink False ', ' agent7-id 0 ', ' agent7-kind 2 ', ' agent7-known False ', ' agent7-level 0 ', ' agent7-level-trigger 6 ', ' agent7-sku C9414A ', ' agent7-type 17 ', ' agent7-virgin False ', ' agent8-ack False ', ' agent8-desc Cyan ink cartridge ', ' agent8-dvc 0 ', ' agent8-health 0 ', ' agent8-health-desc Good/OK ', ' agent8-hp-ink False ', ' agent8-id 0 ', ' agent8-kind 2 ', ' agent8-known False ', ' agent8-level 58 ', ' agent8-level-trigger 0 ', ' agent8-sku C9415A ', ' agent8-type 4 ', ' agent8-virgin False ', ' agent9-ack False ', ' agent9-desc Magenta ink cartridge ', ' agent9-dvc 0 ', ' agent9-health 0 ', ' agent9-health-desc Good/OK ', ' agent9-hp-ink False ', ' agent9-id 0 ', ' agent9-kind 2 ', ' agent9-known False ', ' agent9-level 22 ', ' agent9-level-trigger 6 ', ' agent9-sku C9416A ', ' agent9-type 5 ', ' agent9-virgin False ', ' back-end hp ', ' cups-printer Photosmart_Pro_B9100 ', ' cups-uri hp:/usb/Photosmart_Pro_B9100_series?serial=MY6A22104B04C1 ', ' dev-file ', ' device-state 1 ', ' device-uri hp:/usb/Photosmart_Pro_B9100_series?serial=MY6A22104B04C1 ', ' deviceid MFG:HP;MDL:Photosmart Pro B9100 ', ' series;CMD:MLC,PCL,PML,DW-PCL,DYN,DESKJET;1284.4DL,4d,4e,1', ' ;802.3;CLS:PRINTER;DES:HP Photosmart Pro B9100 ', ' series;SN:MY6A22104B04C1;S:038800C4000F104102C8db800008eb8', ' 00008fb8000090b8400047c6020048c6020046c6021345c6021644c002', ' 3a41c6021151c6020053c00300;Z:05000001000001000001000001000', ' 000000000000000000000000000000000000000000002,070000000000', ' 00000000000000000000000000000000000000,08010001002ce3da,09', ' 01,0c000000; ', ' duplexer 0 ', ' error-state 105 ', ' host ', ' in-tray1 True ', ' in-tray2 False ', ' is-hp True ', ' media-path 0 ', ' panel 1 ', ' panel-line1 READY ', ' panel-line2 ', ' photo-tray 0 ', ' port 1 ', ' r 0 ', ' revision 3 ', ' rg 000 ', ' rr 000000 ', ' rs 000000000 ', ' serial MY6A22104B04C1 ', ' status-code 1002 ', ' status-desc Print job is continuing ', ' supply-door 1 ', ' top-door 1 ', '\x1b[01m', 'Model Parameters (static data):\x1b[0m', '\x1b[01m Parameter Value(s) \x1b[0m', ' ---------------------------- ----------------------------------------------------------', ' align-type 1 ', ' clean-type 1 ', ' color-cal-type 7 ', ' copy-type 0 ', ' embedded-server-type 1 ', ' fax-type 0 ', ' fw-download False ', ' icon Photosmart_Pro_B9180.png ', ' io-mfp-mode 4 ', ' io-mode 1 ', ' io-support 6 ', ' job-storage 0 ', ' linefeed-cal-type 0 ', ' model Photosmart_Pro_B9100_series ', ' model-ui HP Photosmart Pro b9100 Series ', ' model1 HP Photosmart Pro B9180gp Photo Printer ', ' model2 HP Photosmart Pro B9180 Photo Printer ', ' monitor-type 0 ', ' panel-check-type 1 ', ' pcard-type 0 ', ' plugin 0 ', ' plugin-reason 0 ', ' power-settings 0 ', ' pq-diag-type 0 ', ' r-type 0 ', ' r0-agent1-kind 1 ', ' r0-agent1-sku C9407A ', ' r0-agent1-type 16 ', ' r0-agent10-kind 2 ', ' r0-agent10-sku C9417A ', ' r0-agent10-type 6 ', ' r0-agent11-kind 2 ', ' r0-agent11-sku C9418A ', ' r0-agent11-type 7 ', ' r0-agent12-kind 2 ', ' r0-agent12-sku C9419A ', ' r0-agent12-type 8 ', ' r0-agent2-kind 1 ', ' r0-agent2-sku C9406A ', ' r0-agent2-type 14 ', ' r0-agent3-kind 1 ', ' r0-agent3-sku C9405A ', ' r0-agent3-type 13 ', ' r0-agent4-kind 1 ', ' r0-agent4-sku C9404A ', ' r0-agent4-type 15 ', ' r0-agent5-kind 2 ', ' r0-agent5-sku C9412A ', ' r0-agent5-type 1 ', ' r0-agent6-kind 2 ', ' r0-agent6-sku C9413A ', ' r0-agent6-type 19 ', ' r0-agent7-kind 2 ', ' r0-agent7-sku C9414A ', ' r0-agent7-type 17 ', ' r0-agent8-kind 2 ', ' r0-agent8-sku C9415A ', ' r0-agent8-type 4 ', ' r0-agent9-kind 2 ', ' r0-agent9-sku C9416A ', ' r0-agent9-type 5 ', ' scan-style 0 ', ' scan-type 0 ', ' status-battery-check 0 ', ' status-dynamic-counters 0 ', ' status-type 2 ', ' support-released True ', ' support-subtype 16101 ', ' support-type 2 ', ' support-ver 2.8.4 ', " tech-class ['PSB9100'] ", " tech-subclass ['Normal'] ", ' tech-type 2 ', ' usb-pid 48386 ', ' usb-vid 1008 ', '\x1b[01m', 'Status History (most recent first):\x1b[0m', '\x1b[01m Date/Time Code Status Description User Job ID \x1b[0m', ' -------------------- ----- ---------------------------------------- -------- --------', ' 07/24/09 09:55:27 1002 Print job is continuing paul 0 ', ' 07/24/09 09:54:46 501 Print job has completed paul 65 ', ' 07/24/09 09:54:43 500 Started a print job paul 65 ', ' 07/24/09 09:53:37 1508 Photo magenta cartridge is low on ink paul 0 ', ' 07/24/09 09:53:37 1507 Photo cyan cartridge is low on ink paul 0 ', ' 07/24/09 09:53:37 1506 Yellow cartridge is low on ink paul 0 ', ' 07/24/09 09:53:37 1505 Magenta cartridge is low on ink paul 0 ', ' 07/24/09 09:53:37 1517 paul 0 ', ' 07/24/09 09:53:37 1501 Black cartridge is low on ink paul 0 ', ' 07/24/09 09:53:36 1000 Idle paul 0 ', '', '', 'Done.', ''], ['\x1b[35;01mwarning: No display found.\x1b[0m', '\x1b[31;01merror: hp-info -u/--gui requires Qt4 GUI support. Entering interactive mode.\x1b[0m', ''], 0), 'is_cups_class': False} Page 4 (Check PPD sanity): {'cups_printer_ppd_defaults': {u'General': {u'DryTime': u'Zero', u'Duplex': u'None', u'InputSlot': u'Default', u'PageRegion': u'Letter', u'PageSize': u'Letter', u'PrintoutMode': u'Normal'}, u'PrintoutMode': {u'Quality': u'FromPrintoutMode'}}, '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:HP;MDL:Photosmart Pro B9100 series;CLS:PRINTER;DES:Photosmart Pro B9100 series;SN:MY6A22104B04C1;', 'device-info': u'HP Photosmart Pro B9100 series USB MY6A22104B04C1 HPLIP', 'device-make-and-model': u'HP Photosmart Pro B9100 series'}} Page 7 (Printer state reasons): {'printer-state-message': u'/usr/lib/cups/filter/foomatic-rip failed', 'printer-state-reasons': [u'none']} Page 8 (Error log checkpoint): {'cups_server_settings': {'DefaultAuthType': 'Basic', 'MaxLogSize': '2000000', 'SystemGroup': 'lpadmin', '_debug_logging': '0', '_remote_admin': '0', '_remote_any': '0', '_remote_printers': '0', '_share_printers': '0', '_user_cancel_any': '0'}, 'error_log_checkpoint': 5527, 'error_log_debug_logging_set': True} Page 9 (Print test page): {'test_page_attempted': '24/Jul/2009:09:56:01 +0000', 'test_page_completions': [(66, u'Job stopped due to filter errors; please consult the error_log file for details.')], 'test_page_job_id': [66], 'test_page_job_status': [(False, 60, 'Photosmart_Pro_B9100', 'tmpAkM2Lm', 'Stopped', None), (True, 66, 'Photosmart_Pro_B9100', 'Test Page', 'Stopped', {'attributes-charset': u'utf-8', 'attributes-natural-language': u'en-us', 'document-format': u'application/postscript', 'job-hold-until': u'no-hold', 'job-id': 66, 'job-k-octets': 17, 'job-media-sheets-completed': 0, 'job-more-info': u'ipp://localhost:631/jobs/66', 'job-name': u'Test Page', 'job-originating-host-name': u'localhost', 'job-originating-user-name': u'paul', 'job-preserved': True, 'job-printer-state-message': u'/usr/lib/cups/filter/foomatic-rip failed', 'job-printer-state-reasons': [u'none'], 'job-printer-up-time': 1248443771, 'job-printer-uri': u'ipp://elsa:631/printers/Photosmart_Pro_B9100', 'job-priority': 50, 'job-sheets': [u'none', u'none'], 'job-state': 6, 'job-state-reasons': u'job-stopped', 'job-uri': u'ipp://localhost:631/jobs/66', 'job-uuid': u'urn:uuid:af2026d9-1822-36b1-7122-26b020bb0489', 'printer-uri': u'ipp://localhost/printers/Photosmart_Pro_B9100', 'time-at-completed': None, 'time-at-creation': 1248443761, 'time-at-processing': 1248443761})], 'test_page_successful': False} Page 10 (Error log fetch): {'error_log': ['D [24/Jul/2009:09:55:51 -0400] cupsdReadClient: 9 POST / HTTP/1.1', 'D [24/Jul/2009:09:55:51 -0400] cupsdAuthorize: No authentication data provided.', 'D [24/Jul/2009:09:55:51 -0400] Get-Jobs ipp://localhost/printers/', 'D [24/Jul/2009:09:55:51 -0400] [Job 60] Loading attributes...', 'D [24/Jul/2009:09:55:51 -0400] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)', 'D [24/Jul/2009:09:55:51 -0400] cupsdReadClient: 9 POST / HTTP/1.1', 'D [24/Jul/2009:09:55:51 -0400] cupsdAuthorize: No authentication data provided.', 'D [24/Jul/2009:09:55:51 -0400] Get-Jobs ipp://localhost/printers/', 'D [24/Jul/2009:09:55:51 -0400] [Job 9] Loading attributes...', 'D [24/Jul/2009:09:55:51 -0400] [Job 61] Loading attributes...', 'D [24/Jul/2009:09:55:51 -0400] [Job 63] Loading attributes...', 'D [24/Jul/2009:09:55:51 -0400] [Job 64] Loading attributes...', 'D [24/Jul/2009:09:55:51 -0400] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)', 'D [24/Jul/2009:09:55:51 -0400] cupsdReadClient: 9 POST / HTTP/1.1', 'D [24/Jul/2009:09:55:51 -0400] cupsdAuthorize: No authentication data provided.', 'D [24/Jul/2009:09:55:51 -0400] Create-Printer-Subscription /', 'D [24/Jul/2009:09:55:51 -0400] cupsdCreateSubscription(con=0x7f9d04365620(9), uri="/")', 'D [24/Jul/2009:09:55:51 -0400] pullmethod="ippget"', 'D [24/Jul/2009:09:55:51 -0400] notify-lease-duration=86400', 'D [24/Jul/2009:09:55:51 -0400] notify-time-interval=0', 'D [24/Jul/2009:09:55:51 -0400] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)")', 'D [24/Jul/2009:09:55:51 -0400] Added subscription 73 for server', 'I [24/Jul/2009:09:55:51 -0400] Saving subscriptions.conf...', 'D [24/Jul/2009:09:55:51 -0400] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)', 'D [24/Jul/2009:09:55:52 -0400] cupsdReadClient: 9 POST / HTTP/1.1', 'D [24/Jul/2009:09:55:52 -0400] cupsdAuthorize: No authentication data provided.', 'D [24/Jul/2009:09:55:52 -0400] Get-Notifications /', 'D [24/Jul/2009:09:55:52 -0400] cupsdIsAuthorized: requesting-user-name="paul"', 'D [24/Jul/2009:09:55:52 -0400] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)', 'D [24/Jul/2009:09:56:01 -0400] cupsdAcceptClient: 10 from localhost (Domain)', 'D [24/Jul/2009:09:56:01 -0400] cupsdReadClient: 10 POST /printers/Photosmart_Pro_B9100 HTTP/1.1', 'D [24/Jul/2009:09:56:01 -0400] cupsdAuthorize: No authentication data provided.', 'D [24/Jul/2009:09:56:01 -0400] Print-Job ipp://localhost/printers/Photosmart_Pro_B9100', 'D [24/Jul/2009:09:56:01 -0400] [Job ???] Auto-typing file...', 'I [24/Jul/2009:09:56:01 -0400] [Job ???] Request file type is application/postscript.', 'D [24/Jul/2009:09:56:01 -0400] add_job: requesting-user-name="paul"', 'D [24/Jul/2009:09:56:01 -0400] Adding default job-sheets values "none,none"...', 'I [24/Jul/2009:09:56:01 -0400] [Job 66] Adding start banner page "none".', 'I [24/Jul/2009:09:56:01 -0400] Saving subscriptions.conf...', 'I [24/Jul/2009:09:56:01 -0400] [Job 66] Adding end banner page "none".', 'I [24/Jul/2009:09:56:01 -0400] [Job 66] File of type application/postscript queued by "paul".', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] hold_until=0', 'I [24/Jul/2009:09:56:01 -0400] [Job 66] Queued on "Photosmart_Pro_B9100" by "paul".', 'I [24/Jul/2009:09:56:01 -0400] Saving subscriptions.conf...', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] job-sheets=none,none', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] banner_page = 0', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] argv[0]="Photosmart_Pro_B9100"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] argv[1]="66"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] argv[2]="paul"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] argv[3]="Test Page"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] argv[4]="1"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] argv[5]="job-uuid=urn:uuid:af2026d9-1822-36b1-7122-26b020bb0489"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] argv[6]="/var/spool/cups/d00066-001"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[0]="CUPS_CACHEDIR=/var/cache/cups"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[1]="CUPS_DATADIR=/usr/share/cups"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[6]="CUPS_SERVERROOT=/etc/cups"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[7]="CUPS_STATEDIR=/var/run/cups"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[9]="SERVER_ADMIN=root@elsa"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[10]="SOFTWARE=CUPS/1.3.9"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[11]="TMPDIR=/var/spool/cups/tmp"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[12]="TZ=America/New_York"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[13]="USER=root"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[15]="CUPS_ENCRYPTION=IfRequested"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[16]="IPP_PORT=631"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[17]="CHARSET=utf-8"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[18]="LANG=en_US.UTF8"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[19]="PPD=/etc/cups/ppd/Photosmart_Pro_B9100.ppd"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[20]="RIP_MAX_CACHE=8m"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[21]="CONTENT_TYPE=application/postscript"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[22]="DEVICE_URI=hp:/usb/Photosmart_Pro_B9100_series?serial=MY6A22104B04C1"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[23]="PRINTER=Photosmart_Pro_B9100"', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] envp[24]="FINAL_CONTENT_TYPE=printer/Photosmart_Pro_B9100"', 'I [24/Jul/2009:09:56:01 -0400] [Job 66] Started filter /usr/lib/cups/filter/pstopdf (PID 3517)', 'I [24/Jul/2009:09:56:01 -0400] [Job 66] Started filter /usr/lib/cups/filter/pdftopdf (PID 3520)', 'I [24/Jul/2009:09:56:01 -0400] [Job 66] Started filter /usr/lib/cups/filter/foomatic-rip (PID 3523)', 'I [24/Jul/2009:09:56:01 -0400] [Job 66] Started backend /usr/lib/cups/backend/hp (PID 3524)', 'I [24/Jul/2009:09:56:01 -0400] Saving subscriptions.conf...', 'D [24/Jul/2009:09:56:01 -0400] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] pstopdf 6 args: 66 paul Test Page 1 job-uuid=urn:uuid:af2026d9-1822-36b1-7122-26b020bb0489 /var/spool/cups/d00066-001', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] PPD: /etc/cups/ppd/Photosmart_Pro_B9100.ppd', 'I [24/Jul/2009:09:56:01 -0400] Saving subscriptions.conf...', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Resolution: 1200', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Getting input from file', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] foomatic-rip version 4.0.0.195 running...', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Parsing PPD file ...', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Added option Resolution', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Added option PageSize', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Added option Model', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Added option PrintoutMode', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Added option InputSlot', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Added option Duplex', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Added option DryTime', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Added option Quality', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Added option ImageableArea', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Added option PaperDimension', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Added option Font', 'D [24/Jul/2009:09:56:01 -0400] [Job 66]', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Parameter Summary', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] -----------------', 'D [24/Jul/2009:09:56:01 -0400] [Job 66]', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Spooler: cups', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Printer: Photosmart_Pro_B9100', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Shell: /bin/bash', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] PPD file: /etc/cups/ppd/Photosmart_Pro_B9100.ppd', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] ATTR file:', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Printer model: HP PS Pro b8800 Series hpijs, hpijs 3.9.2.49 - HPLIP 3.9.2', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Job title: Test Page', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] File(s) to be printed:', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] ', 'D [24/Jul/2009:09:56:01 -0400] [Job 66]', "D [24/Jul/2009:09:56:01 -0400] [Job 66] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts", 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Printing system options:', "D [24/Jul/2009:09:56:01 -0400] [Job 66] Pondering option 'job-uuid=urn:uuid:af2026d9-1822-36b1-7122-26b020bb0489'", 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Unknown option job-uuid=urn:uuid:af2026d9-1822-36b1-7122-26b020bb0489.', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Options from the PPD file:', 'D [24/Jul/2009:09:56:01 -0400] [Job 66]', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] ================================================', 'D [24/Jul/2009:09:56:01 -0400] [Job 66]', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] File: ', 'D [24/Jul/2009:09:56:01 -0400] [Job 66]', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] ================================================', 'D [24/Jul/2009:09:56:01 -0400] [Job 66]', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Page size: Letter', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Width: 612.00, height: 792.00, absolute margins: 18.00, 36.00, 594.00, 783.00', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Relative margins: 18.00, 36.00, 18.00, 9.00', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] PPD options: -r1200 -dDEVICEWIDTHPOINTS=612.00 -dDEVICEHEIGHTPOINTS=792.00', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] PostScript to be injected:', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Running cat | /usr/bin/ps2pdf13 -dAutoRotatePages=/None -dAutoFilterColorImages=false -dNOPLATFONTS -dPARANOIDSAFER -sstdout=%stderr -dColorImageFilter=/FlateEncode -dDoNumCopies -dPDFSETTINGS=/printer -r1200 -dDEVICEWIDTHPOINTS=612.00 -dDEVICEHEIGHTPOINTS=792.00 - -', 'D [24/Jul/2009:09:56:01 -0400] cupsdAcceptClient: 12 from localhost (Domain)', 'D [24/Jul/2009:09:56:01 -0400] cupsdReadClient: 12 POST / HTTP/1.1', 'D [24/Jul/2009:09:56:01 -0400] cupsdAuthorize: No authentication data provided.', 'D [24/Jul/2009:09:56:01 -0400] Get-Jobs ipp://localhost/printers/', 'D [24/Jul/2009:09:56:01 -0400] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [24/Jul/2009:09:56:01 -0400] cupsdAcceptClient: 13 from localhost (Domain)', 'D [24/Jul/2009:09:56:01 -0400] cupsdReadClient: 13 POST / HTTP/1.1', 'D [24/Jul/2009:09:56:01 -0400] cupsdAuthorize: No authentication data provided.', 'D [24/Jul/2009:09:56:01 -0400] Get-Notifications /', 'D [24/Jul/2009:09:56:01 -0400] cupsdIsAuthorized: requesting-user-name="paul"', 'D [24/Jul/2009:09:56:01 -0400] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)', 'D [24/Jul/2009:09:56:01 -0400] cupsdCloseClient: 13', 'D [24/Jul/2009:09:56:01 -0400] cupsdAcceptClient: 13 from localhost (Domain)', 'D [24/Jul/2009:09:56:01 -0400] cupsdCloseClient: 12', 'D [24/Jul/2009:09:56:01 -0400] cupsdReadClient: 13 POST / HTTP/1.1', 'D [24/Jul/2009:09:56:01 -0400] cupsdAuthorize: No authentication data provided.', 'D [24/Jul/2009:09:56:01 -0400] Get-Jobs ipp://localhost/printers/', 'D [24/Jul/2009:09:56:01 -0400] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)', 'D [24/Jul/2009:09:56:01 -0400] cupsdCloseClient: 13', 'D [24/Jul/2009:09:56:01 -0400] cupsdAcceptClient: 12 from localhost (Domain)', 'D [24/Jul/2009:09:56:01 -0400] cupsdReadClient: 12 POST / HTTP/1.1', 'D [24/Jul/2009:09:56:01 -0400] cupsdAuthorize: No authentication data provided.', 'D [24/Jul/2009:09:56:01 -0400] Get-Notifications /', 'D [24/Jul/2009:09:56:01 -0400] cupsdIsAuthorized: requesting-user-name="paul"', 'D [24/Jul/2009:09:56:01 -0400] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [24/Jul/2009:09:56:01 -0400] cupsdReadClient: 12 POST / HTTP/1.1', 'D [24/Jul/2009:09:56:01 -0400] cupsdAuthorize: No authentication data provided.', 'D [24/Jul/2009:09:56:01 -0400] Get-Job-Attributes ipp://localhost/jobs/66', 'D [24/Jul/2009:09:56:01 -0400] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [24/Jul/2009:09:56:01 -0400] cupsdReadClient: 9 POST / HTTP/1.1', 'D [24/Jul/2009:09:56:01 -0400] cupsdAuthorize: No authentication data provided.', 'D [24/Jul/2009:09:56:01 -0400] Get-Notifications /', 'D [24/Jul/2009:09:56:01 -0400] cupsdIsAuthorized: requesting-user-name="paul"', 'D [24/Jul/2009:09:56:01 -0400] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)', 'D [24/Jul/2009:09:56:01 -0400] cupsdCloseClient: 12', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] GPL Ghostscript 8.64: Set UseCIEColor for UseDeviceIndependentColor to work properly.', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Filetype: PDF', 'D [24/Jul/2009:09:56:01 -0400] [Job 66] Storing temporary files in /var/spool/cups/tmp', 'D [24/Jul/2009:09:56:01 -0400] PID 3517 (/usr/lib/cups/filter/pstopdf) exited with no errors.', 'D [24/Jul/2009:09:56:02 -0400] PID 3520 (/usr/lib/cups/filter/pdftopdf) exited with no errors.', 'D [24/Jul/2009:09:56:02 -0400] [Job 66] File contains 1 pages', 'D [24/Jul/2009:09:56:02 -0400] [Job 66] Starting renderer with command: gs -dFirstPage=1 -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 -sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="deskjet 5600" -dDuplex=false -r300 -sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=2,PS:MediaPosition=7 -dIjsUseOutputFD -sOutputFile=- /var/spool/cups/tmp/foomatic-T0OOVw', 'D [24/Jul/2009:09:56:02 -0400] [Job 66] Starting process "kid3" (generation 1)', 'D [24/Jul/2009:09:56:02 -0400] [Job 66] Starting process "kid4" (generation 2)', 'D [24/Jul/2009:09:56:02 -0400] [Job 66] JCL: \x1b%-12345X@PJL', 'D [24/Jul/2009:09:56:02 -0400] [Job 66] ', 'D [24/Jul/2009:09:56:02 -0400] [Job 66]', 'D [24/Jul/2009:09:56:02 -0400] [Job 66] Starting process "renderer" (generation 2)', 'D [24/Jul/2009:09:56:02 -0400] [Job 66] unable to open PrintContext object err=2', 'D [24/Jul/2009:09:56:02 -0400] [Job 66] GPL Ghostscript 8.64: Can\'t start ijs server "hpijs"', 'D [24/Jul/2009:09:56:02 -0400] [Job 66] renderer exited with status 1', 'D [24/Jul/2009:09:56:02 -0400] [Job 66] Possible error on renderer command line or PostScript error. Check options.Kid3 exit status: 3', 'E [24/Jul/2009:09:56:02 -0400] PID 3523 (/usr/lib/cups/filter/foomatic-rip) stopped with status 9!', 'I [24/Jul/2009:09:56:02 -0400] Saving subscriptions.conf...', 'D [24/Jul/2009:09:56:02 -0400] PID 3524 (/usr/lib/cups/backend/hp) exited with no errors.', 'D [24/Jul/2009:09:56:02 -0400] [Job 66] File 0 is complete.', 'E [24/Jul/2009:09:56:02 -0400] [Job 66] Job stopped due to filter errors.', 'I [24/Jul/2009:09:56:02 -0400] Saving subscriptions.conf...', 'I [24/Jul/2009:09:56:02 -0400] Saving subscriptions.conf...', 'D [24/Jul/2009:09:56:02 -0400] cupsdAcceptClient: 12 from localhost (Domain)', 'D [24/Jul/2009:09:56:02 -0400] cupsdAcceptClient: 13 from localhost (Domain)', 'D [24/Jul/2009:09:56:02 -0400] cupsdReadClient: 12 POST / HTTP/1.1', 'D [24/Jul/2009:09:56:02 -0400] cupsdAuthorize: No authentication data provided.', 'D [24/Jul/2009:09:56:02 -0400] Get-Notifications /', 'D [24/Jul/2009:09:56:02 -0400] cupsdIsAuthorized: requesting-user-name="paul"', 'D [24/Jul/2009:09:56:02 -0400] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [24/Jul/2009:09:56:02 -0400] cupsdAcceptClient: 14 from localhost (Domain)', 'D [24/Jul/2009:09:56:02 -0400] cupsdReadClient: 13 POST / HTTP/1.1', 'D [24/Jul/2009:09:56:02 -0400] cupsdAuthorize: No authentication data provided.', 'D [24/Jul/2009:09:56:02 -0400] Get-Jobs ipp://localhost/printers/', 'D [24/Jul/2009:09:56:02 -0400] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)', 'D [24/Jul/2009:09:56:02 -0400] cupsdReadClient: 14 POST / HTTP/1.1', 'D [24/Jul/2009:09:56:02 -0400] cupsdAuthorize: No authentication data provided.', 'D [24/Jul/2009:09:56:02 -0400] Get-Jobs ipp://localhost/printers/', 'D [24/Jul/2009:09:56:02 -0400] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok)', 'D [24/Jul/2009:09:56:02 -0400] cupsdCloseClient: 13', 'D [24/Jul/2009:09:56:02 -0400] cupsdCloseClient: 12', 'D [24/Jul/2009:09:56:02 -0400] cupsdCloseClient: 14', 'D [24/Jul/2009:09:56:02 -0400] cupsdAcceptClient: 12 from localhost (Domain)', 'D [24/Jul/2009:09:56:02 -0400] cupsdReadClient: 12 POST / HTTP/1.1', 'D [24/Jul/2009:09:56:02 -0400] cupsdAuthorize: No authentication data provided.', 'D [24/Jul/2009:09:56:02 -0400] Get-Notifications /', 'D [24/Jul/2009:09:56:02 -0400] cupsdIsAuthorized: requesting-user-name="paul"', 'D [24/Jul/2009:09:56:02 -0400] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)', 'D [24/Jul/2009:09:56:02 -0400] cupsdAcceptClient: 13 from localhost (Domain)', 'D [24/Jul/2009:09:56:02 -0400] cupsdReadClient: 13 POST / HTTP/1.1', 'D [24/Jul/2009:09:56:02 -0400] cupsdAuthorize: No authentication data provided.', 'D [24/Jul/2009:09:56:02 -0400] Get-Printer-Attributes ipp://elsa:631/printers/Photosmart_Pro_B9100', 'D [24/Jul/2009:09:56:02 -0400] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)', 'D [24/Jul/2009:09:56:02 -0400] cupsdReadClient: 13 POST / HTTP/1.1', 'D [24/Jul/2009:09:56:02 -0400] cupsdAuthorize: No authentication data provided.', 'D [24/Jul/2009:09:56:02 -0400] Get-Job-Attributes ipp://localhost/jobs/66', 'D [24/Jul/2009:09:56:02 -0400] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)', 'D [24/Jul/2009:09:56:02 -0400] cupsdReadClient: 9 POST / HTTP/1.1', 'D [24/Jul/2009:09:56:02 -0400] cupsdAuthorize: No authentication data provided.', 'D [24/Jul/2009:09:56:02 -0400] Get-Notifications /', 'D [24/Jul/2009:09:56:02 -0400] cupsdIsAuthorized: requesting-user-name="paul"', 'D [24/Jul/2009:09:56:02 -0400] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)', 'D [24/Jul/2009:09:56:02 -0400] cupsdCloseClient: 12', 'D [24/Jul/2009:09:56:03 -0400] [Job 66] Unloading...', 'D [24/Jul/2009:09:56:11 -0400] cupsdReadClient: 9 POST / HTTP/1.1', 'D [24/Jul/2009:09:56:11 -0400] cupsdAuthorize: No authentication data provided.', 'D [24/Jul/2009:09:56:11 -0400] Get-Job-Attributes ipp://localhost/jobs/66', 'D [24/Jul/2009:09:56:11 -0400] [Job 66] Loading attributes...', 'D [24/Jul/2009:09:56:11 -0400] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)', 'D [24/Jul/2009:09:56:11 -0400] cupsdReadClient: 9 POST / HTTP/1.1', 'D [24/Jul/2009:09:56:11 -0400] cupsdAuthorize: No authentication data provided.', 'D [24/Jul/2009:09:56:11 -0400] Cancel-Subscription /', 'D [24/Jul/2009:09:56:11 -0400] cupsdIsAuthorized: requesting-user-name="paul"', 'I [24/Jul/2009:09:56:11 -0400] Saving subscriptions.conf...', 'D [24/Jul/2009:09:56:11 -0400] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)', 'D [24/Jul/2009:09:56:12 -0400] cupsdAcceptClient: 12 from localhost (Domain)', 'D [24/Jul/2009:09:56:12 -0400] cupsdReadClient: 12 GET /admin/log/error_log HTTP/1.1', 'D [24/Jul/2009:09:56:12 -0400] cupsdAuthorize: No authentication data provided.'], 'error_log_debug_logging_unset': True} Page 11 (Locale issues): {'printer_page_size': u'Letter', 'system_locale_lang': None, 'user_locale_ctype': 'en_US', 'user_locale_messages': 'en_US'}