Page 1 (Scheduler not running?): {'cups_connection_failure': False} Page 2 (Choose printer): {'cups_dest': , 'cups_instance': None, 'cups_queue': 'deskjet520', 'cups_queue_listed': True} Page 3 (Check printer sanity): {'cups_device_uri_scheme': u'parallel', 'cups_printer_dict': {'device-uri': u'parallel:/dev/lp0', 'printer-info': u'deskjet520', 'printer-is-shared': False, 'printer-location': u'office', 'printer-make-and-model': u'HP DeskJet 520 Foomatic/pcl3 (recommended)', 'printer-state': 3, 'printer-state-message': u'', 'printer-state-reasons': [u'none'], 'printer-type': 10620932, 'printer-uri-supported': u'ipp://localhost:631/printers/deskjet520'}, 'cups_printer_remote': False, 'is_cups_class': False, 'local_cups_queue_attributes': {'auth-info-required': u'none', 'charset-configured': u'utf-8', 'charset-supported': [u'us-ascii', u'utf-8'], 'color-supported': False, 'compression-supported': [u'none', u'gzip'], 'copies-default': 1, 'copies-supported': (1, 9999), 'cups-version': u'1.4.1', 'device-uri': u'parallel:/dev/lp0', 'document-format-default': u'application/octet-stream', 'document-format-supported': [u'application/octet-stream', u'application/openofficeps', u'application/pdf', u'application/postscript', u'application/vnd.cups-banner', u'application/vnd.cups-pdf', u'application/vnd.cups-postscript', u'application/vnd.cups-raw', u'application/vnd.hp-hpgl', u'application/x-cshell', u'application/x-csource', u'application/x-perl', u'application/x-shell', u'image/gif', u'image/jpeg', u'image/png', u'image/tiff', u'image/x-bitmap', u'image/x-photocd', u'image/x-portable-anymap', u'image/x-portable-bitmap', u'image/x-portable-graymap', u'image/x-portable-pixmap', u'image/x-sgi-rgb', u'image/x-sun-raster', u'image/x-xbitmap', u'image/x-xpixmap', u'image/x-xwindowdump', u'text/css', u'text/html', u'text/plain'], 'finishings-default': 3, 'finishings-supported': [3], 'generated-natural-language-supported': [u'en_US'], 'ipp-versions-supported': [u'1.0', u'1.1', u'2.0', u'2.1'], 'job-hold-until-default': u'no-hold', 'job-hold-until-supported': [u'no-hold', u'indefinite', u'day-time', u'evening', u'night', u'second-shift', u'third-shift', u'weekend'], 'job-k-limit': 0, 'job-page-limit': 0, 'job-priority-default': 50, 'job-priority-supported': [100], 'job-quota-period': 0, 'job-settable-attributes-supported': [u'copies', u'finishings', u'job-hold-until', u'job-priority', u'media', u'multiple-document-handling', u'number-up', u'orientation-requested', u'page-ranges', u'print-quality', u'printer-resolution', u'sides'], 'job-sheets-default': (u'none', u'none'), 'job-sheets-supported': [u'none', u'classified', u'confidential', u'secret', u'standard', u'topsecret', u'unclassified'], 'marker-change-time': 0, 'media-col-supported': [u'media-color', u'media-key', u'media-size', u'media-type'], 'media-default': u'na_letter_8.5x11in', 'media-supported': [u'na_letter_8.5x11in', u'iso_a4_210x297mm', u'iso_a5_148x210mm', u'jis_b5_182x257mm', u'na_number-10_4.125x9.5in', u'iso_c5_162x229mm', u'iso_dl_110x220mm', u'iso_b5_176x250mm', u'na_monarch_3.875x7.5in', u'na_executive_7.25x10.5in', u'na_legal_8.5x14in'], 'multiple-document-handling-supported': [u'separate-documents-uncollated-copies', u'separate-documents-collated-copies'], 'multiple-document-jobs-supported': True, 'multiple-operation-time-out': 300, 'natural-language-configured': u'en_US', 'notify-attributes-supported': [u'printer-state-change-time', u'notify-lease-expiration-time', u'notify-subscriber-user-name'], 'notify-events-default': [u'job-completed'], 'notify-events-supported': [u'job-completed', u'job-config-changed', u'job-created', u'job-progress', u'job-state-changed', u'job-stopped', u'printer-added', u'printer-changed', u'printer-config-changed', u'printer-deleted', u'printer-finishings-changed', u'printer-media-changed', u'printer-modified', u'printer-restarted', u'printer-shutdown', u'printer-state-changed', u'printer-stopped', u'server-audit', u'server-restarted', u'server-started', u'server-stopped'], 'notify-lease-duration-default': 86400, 'notify-lease-duration-supported': (0, 2147483647), 'notify-max-events-supported': [100], 'notify-pull-method-supported': [u'ippget'], 'notify-schemes-supported': [u'mailto', u'rss'], 'number-up-default': 1, 'number-up-supported': [1, 2, 4, 6, 9, 16], 'operations-supported': [2, 4, 5, 6, 8, 9, 10, 11, 12, 13, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 34, 35, 37, 38, 16385, 16386, 16387, 16388, 16389, 16390, 16391, 16392, 16393, 16394, 16395, 16396, 16397, 16398, 16399, 16423], 'orientation-requested-default': None, 'orientation-requested-supported': [3, 4, 5, 6], 'page-ranges-supported': True, 'pages-per-minute': 1, 'pdl-override-supported': [u'not-attempted'], 'port-monitor': u'none', 'port-monitor-supported': [u'none'], 'printer-commands': [u'AutoConfigure', u'Clean', u'PrintSelfTestPage'], 'printer-current-time': '(IPP_TAG_DATE)', 'printer-error-policy': u'retry-job', 'printer-error-policy-supported': [u'abort-job', u'retry-current-job', u'retry-job', u'stop-printer'], 'printer-info': u'deskjet520', 'printer-is-accepting-jobs': True, 'printer-is-shared': False, 'printer-location': u'office', 'printer-make-and-model': u'HP DeskJet 520 Foomatic/pcl3 (recommended)', 'printer-more-info': u'http://localhost:631/printers/deskjet520', 'printer-name': u'deskjet520', 'printer-op-policy': u'default', 'printer-op-policy-supported': [u'default'], 'printer-settable-attributes-supported': [u'printer-info', u'printer-location'], 'printer-state': 3, 'printer-state-change-time': 1260561400, 'printer-state-message': u'', 'printer-state-reasons': [u'none'], 'printer-type': 10620932, 'printer-up-time': 1260990426, 'printer-uri-supported': [u'ipp://localhost:631/printers/deskjet520'], 'queued-job-count': 0, 'server-is-sharing-printers': False, 'uri-authentication-supported': [u'requesting-user-name'], 'uri-security-supported': [u'none']}} Page 4 (Check PPD sanity): {'cups_printer_ppd_defaults': {u'Adjustment': {u'DitherPPI': u'60', u'MemLimit': u'8388608'}, u'General': {u'InputSlot': u'Tray', u'Manual': u'False', u'PageRegion': u'Letter', u'PageSize': u'Letter', u'PrintoutMode': u'PlainNormal', u'Resolution': u'300x300dpi'}, u'Miscellaneous': {u'CompressionMethod': u'Default', u'LeadingEdge': u'Default'}, u'PrintoutMode': {u'IntensityRendering': u'FromPrintoutMode', u'MediaType': u'FromPrintoutMode', u'Passes': u'FromPrintoutMode', u'Quality': u'FromPrintoutMode', u'RasterGraphicsQuality': 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'', 'device-info': u'LPT #1', 'device-make-and-model': u'unknown'}} Page 7 (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': 4212L, 'error_log_debug_logging_set': True} Page 8 (Print test page): {'test_page_attempted': '16/Dec/2009:11:07:30 +0000', 'test_page_job_id': [279], 'test_page_job_status': [(True, 279, 'deskjet520', 'Test Page', 'Processing', {'attributes-charset': u'utf-8', 'attributes-natural-language': u'en-us', 'document-count': 1, 'document-format': u'application/vnd.cups-banner', 'job-hold-until': u'no-hold', 'job-id': 279, 'job-k-octets': 1, 'job-media-progress': 0, 'job-media-sheets-completed': 0, 'job-more-info': u'ipp://localhost:631/jobs/279', 'job-name': u'Test Page', 'job-originating-host-name': u'localhost', 'job-originating-user-name': u'rocky', 'job-printer-state-message': u'', 'job-printer-state-reasons': [u'none'], 'job-printer-up-time': 1260990469, 'job-printer-uri': u'ipp://system76-pc:0/printers/deskjet520', 'job-priority': 50, 'job-sheets': [u'none', u'none'], 'job-state': 5, 'job-state-reasons': u'job-printing', 'job-uri': u'ipp://localhost:631/jobs/279', 'job-uuid': u'urn:uuid:0a3e79dd-4aeb-3bd0-4e65-9716c4e3a52e', 'printer-uri': u'ipp://localhost/printers/deskjet520', 'time-at-completed': None, 'time-at-creation': 1260990450, 'time-at-processing': 1260990450})], 'test_page_successful': False} Page 9 (Error log fetch): {'error_log': ['D [16/Dec/2009:11:07:21 -0800] cupsdSetBusyState: Dirty files', 'D [16/Dec/2009:11:07:21 -0800] cupsdReadClient: 12 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:21 -0800] cupsdSetBusyState: Active clients and dirty files', 'D [16/Dec/2009:11:07:21 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:21 -0800] cupsdReadClient: 12 1.1 Get-Jobs 1', 'D [16/Dec/2009:11:07:21 -0800] Get-Jobs ipp://localhost/printers/', 'D [16/Dec/2009:11:07:21 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost', 'D [16/Dec/2009:11:07:21 -0800] cupsdSetBusyState: Dirty files', 'D [16/Dec/2009:11:07:21 -0800] cupsdReadClient: 12 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:21 -0800] cupsdSetBusyState: Active clients and dirty files', 'D [16/Dec/2009:11:07:21 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:21 -0800] cupsdReadClient: 12 1.1 Get-Jobs 1', 'D [16/Dec/2009:11:07:21 -0800] Get-Jobs ipp://localhost/printers/', 'D [16/Dec/2009:11:07:21 -0800] [Job 120] Loading attributes...', 'D [16/Dec/2009:11:07:21 -0800] [Job 121] Loading attributes...', 'D [16/Dec/2009:11:07:21 -0800] [Job 124] Loading attributes...', 'D [16/Dec/2009:11:07:21 -0800] [Job 125] Loading attributes...', 'D [16/Dec/2009:11:07:21 -0800] [Job 126] Loading attributes...', 'D [16/Dec/2009:11:07:21 -0800] [Job 141] Loading attributes...', 'D [16/Dec/2009:11:07:21 -0800] [Job 197] Loading attributes...', 'D [16/Dec/2009:11:07:21 -0800] [Job 256] Loading attributes...', 'D [16/Dec/2009:11:07:21 -0800] [Job 257] Loading attributes...', 'D [16/Dec/2009:11:07:21 -0800] [Job 258] Loading attributes...', 'D [16/Dec/2009:11:07:21 -0800] [Job 259] Loading attributes...', 'D [16/Dec/2009:11:07:21 -0800] [Job 260] Loading attributes...', 'D [16/Dec/2009:11:07:21 -0800] [Job 262] Loading attributes...', 'D [16/Dec/2009:11:07:21 -0800] [Job 263] Loading attributes...', 'D [16/Dec/2009:11:07:21 -0800] [Job 266] Loading attributes...', 'D [16/Dec/2009:11:07:21 -0800] [Job 267] Loading attributes...', 'D [16/Dec/2009:11:07:21 -0800] [Job 268] Loading attributes...', 'D [16/Dec/2009:11:07:21 -0800] [Job 269] Loading attributes...', 'E [16/Dec/2009:11:07:21 -0800] [Job 269] Unable to queue job for destination "HP-DeskJet-520"!', 'D [16/Dec/2009:11:07:21 -0800] [Job 271] Loading attributes...', 'E [16/Dec/2009:11:07:21 -0800] [Job 271] Unable to queue job for destination "HP-DeskJet-520"!', 'D [16/Dec/2009:11:07:21 -0800] [Job 273] Loading attributes...', 'E [16/Dec/2009:11:07:21 -0800] [Job 273] Unable to queue job for destination "HP-DeskJet-520"!', 'D [16/Dec/2009:11:07:21 -0800] [Job 274] Loading attributes...', 'D [16/Dec/2009:11:07:21 -0800] [Job 275] Loading attributes...', 'E [16/Dec/2009:11:07:21 -0800] [Job 275] Unable to queue job for destination "HP-DeskJet-520"!', 'D [16/Dec/2009:11:07:21 -0800] [Job 277] Loading attributes...', 'D [16/Dec/2009:11:07:21 -0800] [Job 278] Loading attributes...', 'D [16/Dec/2009:11:07:21 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost', 'D [16/Dec/2009:11:07:21 -0800] cupsdSetBusyState: Dirty files', 'D [16/Dec/2009:11:07:21 -0800] cupsdReadClient: 12 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:21 -0800] cupsdSetBusyState: Active clients and dirty files', 'D [16/Dec/2009:11:07:21 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:21 -0800] cupsdReadClient: 12 1.1 Create-Printer-Subscription 1', 'D [16/Dec/2009:11:07:21 -0800] Create-Printer-Subscription /', 'D [16/Dec/2009:11:07:21 -0800] cupsdCreateSubscription(con=0xe4ef88(12), uri="/")', 'D [16/Dec/2009:11:07:21 -0800] pullmethod="ippget"', 'D [16/Dec/2009:11:07:21 -0800] notify-lease-duration=86400', 'D [16/Dec/2009:11:07:21 -0800] notify-time-interval=0', 'D [16/Dec/2009:11:07:21 -0800] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)")', 'D [16/Dec/2009:11:07:21 -0800] Added subscription 157 for server', 'D [16/Dec/2009:11:07:21 -0800] cupsdMarkDirty(-----S)', 'D [16/Dec/2009:11:07:21 -0800] Returning IPP successful-ok for Create-Printer-Subscription (/) from localhost', 'D [16/Dec/2009:11:07:21 -0800] cupsdSetBusyState: Dirty files', 'D [16/Dec/2009:11:07:22 -0800] cupsdReadClient: 12 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:22 -0800] cupsdSetBusyState: Active clients and dirty files', 'D [16/Dec/2009:11:07:22 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:22 -0800] cupsdReadClient: 12 1.1 Get-Notifications 1', 'D [16/Dec/2009:11:07:22 -0800] Get-Notifications /', 'D [16/Dec/2009:11:07:22 -0800] cupsdIsAuthorized: requesting-user-name="rocky"', 'D [16/Dec/2009:11:07:22 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost', 'D [16/Dec/2009:11:07:22 -0800] cupsdSetBusyState: Dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdAcceptClient: 14 from localhost (Domain)', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 14 POST /printers/deskjet520 HTTP/1.1', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Active clients and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 14 1.1 Print-Job 1', 'D [16/Dec/2009:11:07:30 -0800] Print-Job ipp://localhost/printers/deskjet520', 'D [16/Dec/2009:11:07:30 -0800] [Job ???] Auto-typing file...', 'I [16/Dec/2009:11:07:30 -0800] [Job ???] Request file type is application/vnd.cups-banner.', 'D [16/Dec/2009:11:07:30 -0800] cupsdMarkDirty(----J-)', 'D [16/Dec/2009:11:07:30 -0800] add_job: requesting-user-name="rocky"', 'D [16/Dec/2009:11:07:30 -0800] Adding default job-sheets values "none,none"...', 'I [16/Dec/2009:11:07:30 -0800] [Job 279] Adding start banner page "none".', 'D [16/Dec/2009:11:07:30 -0800] cupsdMarkDirty(-----S)', 'D [16/Dec/2009:11:07:30 -0800] cupsdMarkDirty(----J-)', 'I [16/Dec/2009:11:07:30 -0800] [Job 279] Adding end banner page "none".', 'I [16/Dec/2009:11:07:30 -0800] [Job 279] File of type application/vnd.cups-banner queued by "rocky".', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] hold_until=0', 'I [16/Dec/2009:11:07:30 -0800] [Job 279] Queued on "deskjet520" by "rocky".', 'D [16/Dec/2009:11:07:30 -0800] cupsdMarkDirty(----J-)', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdMarkDirty(-----S)', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] job-sheets=none,none', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] argv[0]="deskjet520"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] argv[1]="279"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] argv[2]="rocky"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] argv[3]="Test Page"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] argv[4]="1"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] argv[5]="job-uuid=urn:uuid:0a3e79dd-4aeb-3bd0-4e65-9716c4e3a52e job-originating-host-name=localhost"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] argv[6]="/var/spool/cups/d00279-001"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[0]="CUPS_CACHEDIR=/var/cache/cups"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[1]="CUPS_DATADIR=/usr/share/cups"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[6]="CUPS_SERVERROOT=/etc/cups"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[7]="CUPS_STATEDIR=/var/run/cups"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[8]="HOME=/var/spool/cups/tmp"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[10]="SERVER_ADMIN=root@system76-pc"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[11]="SOFTWARE=CUPS/1.4.1"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[12]="TMPDIR=/var/spool/cups/tmp"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[13]="TZ=America/Los_Angeles"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[14]="USER=root"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[16]="CUPS_ENCRYPTION=IfRequested"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[17]="IPP_PORT=631"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[18]="CHARSET=utf-8"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[19]="LANG=en_US.UTF-8"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[20]="PPD=/etc/cups/ppd/deskjet520.ppd"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[21]="RIP_MAX_CACHE=512968k"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[22]="CONTENT_TYPE=application/vnd.cups-banner"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[23]="DEVICE_URI=parallel:/dev/lp0"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[24]="PRINTER_INFO=deskjet520"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[25]="PRINTER_LOCATION=office"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[26]="PRINTER=deskjet520"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[27]="CUPS_FILETYPE=document"', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] envp[28]="FINAL_CONTENT_TYPE=printer/deskjet520"', 'I [16/Dec/2009:11:07:30 -0800] [Job 279] Started filter /usr/lib/cups/filter/bannertops (PID 2341)', 'I [16/Dec/2009:11:07:30 -0800] [Job 279] Started filter /usr/lib/cups/filter/pstopdf (PID 2342)', 'I [16/Dec/2009:11:07:30 -0800] [Job 279] Started filter /usr/lib/cups/filter/pdftopdf (PID 2343)', 'I [16/Dec/2009:11:07:30 -0800] [Job 279] Started filter /usr/lib/cups/filter/foomatic-rip (PID 2344)', 'I [16/Dec/2009:11:07:30 -0800] [Job 279] Started backend /usr/lib/cups/backend/parallel (PID 2345)', 'D [16/Dec/2009:11:07:30 -0800] cupsdMarkDirty(-----S)', 'D [16/Dec/2009:11:07:30 -0800] Returning IPP successful-ok for Print-Job (ipp://localhost/printers/deskjet520) from localhost', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Printing jobs and dirty files', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] STATE: +connecting-to-device', 'D [16/Dec/2009:11:07:30 -0800] cupsdMarkDirty(-----S)', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] pstopdf 5 args: 279 rocky Test Page 1 job-uuid=urn:uuid:0a3e79dd-4aeb-3bd0-4e65-9716c4e3a52e job-originating-host-name=localhost', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] PPD: /etc/cups/ppd/deskjet520.ppd', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] STATE: -connecting-to-device', 'D [16/Dec/2009:11:07:30 -0800] cupsdMarkDirty(-----S)', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] backendRunLoop(print_fd=0, device_fd=5, snmp_fd=-1, addr=(nil), use_bc=0, side_cb=0xc18130)', 'D [16/Dec/2009:11:07:30 -0800] cupsdAcceptClient: 16 from localhost (Domain)', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:30 -0800] cupsdAcceptClient: 17 from localhost (Domain)', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 17 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:30 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 1.1 Get-Jobs 1', 'D [16/Dec/2009:11:07:30 -0800] Get-Jobs ipp://localhost/printers/', 'D [16/Dec/2009:11:07:30 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 17 1.1 Get-Notifications 1', 'D [16/Dec/2009:11:07:30 -0800] Get-Notifications /', 'D [16/Dec/2009:11:07:30 -0800] cupsdIsAuthorized: requesting-user-name="rocky"', 'D [16/Dec/2009:11:07:30 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Printing jobs and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 WAITING Closing on EOF', 'D [16/Dec/2009:11:07:30 -0800] cupsdCloseClient: 16', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 17 WAITING Closing on EOF', 'D [16/Dec/2009:11:07:30 -0800] cupsdCloseClient: 17', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 12 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] load_banner(filename="/var/spool/cups/d00279-001")', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Page = 612x792; 18,48 to 594,783', 'D [16/Dec/2009:11:07:30 -0800] cupsdAcceptClient: 16 from localhost (Domain)', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:30 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 12 1.1 Get-Notifications 1', 'D [16/Dec/2009:11:07:30 -0800] Get-Notifications /', 'D [16/Dec/2009:11:07:30 -0800] cupsdIsAuthorized: requesting-user-name="rocky"', 'D [16/Dec/2009:11:07:30 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1', 'D [16/Dec/2009:11:07:30 -0800] CUPS-Get-Printers', 'D [16/Dec/2009:11:07:30 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Printing jobs and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 1.1 CUPS-Get-Classes 1', 'D [16/Dec/2009:11:07:30 -0800] CUPS-Get-Classes', 'D [16/Dec/2009:11:07:30 -0800] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Printing jobs and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 1.1 CUPS-Get-Default 1', 'D [16/Dec/2009:11:07:30 -0800] CUPS-Get-Default', 'D [16/Dec/2009:11:07:30 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Printing jobs and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1', 'D [16/Dec/2009:11:07:30 -0800] CUPS-Get-Printers', 'D [16/Dec/2009:11:07:30 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Printing jobs and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 1.1 CUPS-Get-Classes 1', 'D [16/Dec/2009:11:07:30 -0800] CUPS-Get-Classes', 'D [16/Dec/2009:11:07:30 -0800] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Printing jobs and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 1.1 CUPS-Get-Default 1', 'D [16/Dec/2009:11:07:30 -0800] CUPS-Get-Default', 'D [16/Dec/2009:11:07:30 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Printing jobs and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1', 'D [16/Dec/2009:11:07:30 -0800] CUPS-Get-Printers', 'D [16/Dec/2009:11:07:30 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Printing jobs and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 1.1 CUPS-Get-Classes 1', 'D [16/Dec/2009:11:07:30 -0800] CUPS-Get-Classes', 'D [16/Dec/2009:11:07:30 -0800] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Printing jobs and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 16 1.1 CUPS-Get-Default 1', 'D [16/Dec/2009:11:07:30 -0800] CUPS-Get-Default', 'D [16/Dec/2009:11:07:30 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Printing jobs and dirty files', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Getting input from file', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] foomatic-rip version 4.0.3.215 running...', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Parsing PPD file ...', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Added option PrintoutMode', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Added option PageSize', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Added option ImageableArea', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Added option PaperDimension', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Added option Resolution', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Added option ColorModel', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Added option InputSlot', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Added option Manual', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Added option Model', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Added option DitherPPI', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Added option MemLimit', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Added option ConfigureEveryPage', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Added option CompressionMethod', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Added option LeadingEdge', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Added option Quality', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Added option MediaType', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Added option IntensityRendering', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Added option RasterGraphicsQuality', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Added option Passes', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Added option Font', 'D [16/Dec/2009:11:07:30 -0800] [Job 279]', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Parameter Summary', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] -----------------', 'D [16/Dec/2009:11:07:30 -0800] [Job 279]', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Spooler: cups', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Printer: deskjet520', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Shell: /bin/bash', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] PPD file: /etc/cups/ppd/deskjet520.ppd', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] ATTR file:', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Printer model: HP DeskJet 520 Foomatic/pcl3 (recommended)', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Job title: Test Page', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] File(s) to be printed:', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] ', 'D [16/Dec/2009:11:07:30 -0800] [Job 279]', "D [16/Dec/2009:11:07:30 -0800] [Job 279] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts", 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Printing system options:', "D [16/Dec/2009:11:07:30 -0800] [Job 279] Pondering option 'job-uuid=urn:uuid:0a3e79dd-4aeb-3bd0-4e65-9716c4e3a52e'", 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Unknown option job-uuid=urn:uuid:0a3e79dd-4aeb-3bd0-4e65-9716c4e3a52e.', "D [16/Dec/2009:11:07:30 -0800] [Job 279] Pondering option 'job-originating-host-name=localhost'", 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Unknown option job-originating-host-name=localhost.', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Options from the PPD file:', 'D [16/Dec/2009:11:07:30 -0800] [Job 279]', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] ================================================', 'D [16/Dec/2009:11:07:30 -0800] [Job 279]', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] File: ', 'D [16/Dec/2009:11:07:30 -0800] [Job 279]', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] ================================================', 'D [16/Dec/2009:11:07:30 -0800] [Job 279]', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] PNG image: 128x128x8, color_type=6 (RGB+ALPHA)', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] PNG image: 192x128x8, color_type=2 (RGB)', 'D [16/Dec/2009:11:07:30 -0800] PID 2341 (/usr/lib/cups/filter/bannertops) exited with no errors.', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Resolution: 300x300', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Page size: Letter', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Width: 612, height: 792, absolute margins: 18, 48, 594, 783', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Relative margins: 18, 48, 18, 9', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] PPD options: -r300 -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] PostScript to be injected:', 'D [16/Dec/2009:11:07:30 -0800] [Job 279] Running cat | /usr/bin/ps2pdf13 -dAutoRotatePages=/None -dAutoFilterColorImages=false -dNOPLATFONTS -dPARANOIDSAFER -sstdout=%stderr -dColorImageFilter=/FlateEncode -dPDFSETTINGS=/printer -dDoNumCopies -r300 -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 - -', 'D [16/Dec/2009:11:07:30 -0800] cupsdAcceptClient: 17 from localhost (Domain)', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 17 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 17 1.1 Create-Printer-Subscription 1', 'D [16/Dec/2009:11:07:30 -0800] Create-Printer-Subscription /', 'D [16/Dec/2009:11:07:30 -0800] cupsdCreateSubscription(con=0xb77bdef8(17), uri="/")', 'D [16/Dec/2009:11:07:30 -0800] pullmethod="ippget"', 'D [16/Dec/2009:11:07:30 -0800] notify-lease-duration=86400', 'D [16/Dec/2009:11:07:30 -0800] notify-time-interval=0', 'D [16/Dec/2009:11:07:30 -0800] cupsdAddSubscription(mask=798f, dest=(nil)(), job=(nil)(0), uri="(null)")', 'D [16/Dec/2009:11:07:30 -0800] Added subscription 158 for server', 'D [16/Dec/2009:11:07:30 -0800] cupsdMarkDirty(-----S)', 'D [16/Dec/2009:11:07:30 -0800] Returning IPP successful-ok for Create-Printer-Subscription (/) from localhost', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Printing jobs and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 17 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1', 'D [16/Dec/2009:11:07:30 -0800] CUPS-Get-Printers', 'D [16/Dec/2009:11:07:30 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Printing jobs and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 17 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1', 'D [16/Dec/2009:11:07:30 -0800] CUPS-Get-Printers', 'D [16/Dec/2009:11:07:30 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Printing jobs and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 17 WAITING Closing on EOF', 'D [16/Dec/2009:11:07:30 -0800] cupsdCloseClient: 17', 'D [16/Dec/2009:11:07:30 -0800] cupsdAcceptClient: 17 from localhost (Domain)', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 17 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 17 1.1 Get-Jobs 1', 'D [16/Dec/2009:11:07:30 -0800] Get-Jobs ipp://localhost/printers/', 'D [16/Dec/2009:11:07:30 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Printing jobs and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdAcceptClient: 18 from localhost (Domain)', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 18 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 18 1.1 Get-Printer-Attributes 1', 'D [16/Dec/2009:11:07:30 -0800] Get-Printer-Attributes ipp://system76-pc:0/printers/deskjet520', 'D [16/Dec/2009:11:07:30 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://system76-pc:0/printers/deskjet520) from localhost', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Printing jobs and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 18 WAITING Closing on EOF', 'D [16/Dec/2009:11:07:30 -0800] cupsdCloseClient: 18', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 17 WAITING Closing on EOF', 'D [16/Dec/2009:11:07:30 -0800] cupsdCloseClient: 17', 'D [16/Dec/2009:11:07:30 -0800] cupsdAcceptClient: 17 from localhost (Domain)', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 17 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 17 1.1 Get-Jobs 1', 'D [16/Dec/2009:11:07:30 -0800] Get-Jobs ipp://localhost/printers/', 'D [16/Dec/2009:11:07:30 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost', 'D [16/Dec/2009:11:07:30 -0800] cupsdSetBusyState: Printing jobs and dirty files', 'D [16/Dec/2009:11:07:30 -0800] cupsdReadClient: 17 WAITING Closing on EOF', 'D [16/Dec/2009:11:07:30 -0800] cupsdCloseClient: 17', 'D [16/Dec/2009:11:07:31 -0800] [Job 279] GPL Ghostscript 8.70: Set UseCIEColor for UseDeviceIndependentColor to work properly.', 'D [16/Dec/2009:11:07:31 -0800] cupsdAcceptClient: 17 from localhost (Domain)', 'D [16/Dec/2009:11:07:31 -0800] cupsdReadClient: 17 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:31 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [16/Dec/2009:11:07:31 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:31 -0800] cupsdReadClient: 17 1.1 Get-Notifications 1', 'D [16/Dec/2009:11:07:31 -0800] Get-Notifications /', 'D [16/Dec/2009:11:07:31 -0800] cupsdIsAuthorized: requesting-user-name="rocky"', 'D [16/Dec/2009:11:07:31 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost', 'D [16/Dec/2009:11:07:31 -0800] cupsdSetBusyState: Printing jobs and dirty files', 'D [16/Dec/2009:11:07:31 -0800] cupsdReadClient: 17 WAITING Closing on EOF', 'D [16/Dec/2009:11:07:31 -0800] cupsdCloseClient: 17', 'D [16/Dec/2009:11:07:33 -0800] PID 2342 (/usr/lib/cups/filter/pstopdf) exited with no errors.', 'D [16/Dec/2009:11:07:33 -0800] PID 2343 (/usr/lib/cups/filter/pdftopdf) exited with no errors.', 'D [16/Dec/2009:11:07:33 -0800] [Job 279] Filetype: PDF', 'D [16/Dec/2009:11:07:33 -0800] [Job 279] Storing temporary files in /var/spool/cups/tmp', 'D [16/Dec/2009:11:07:33 -0800] [Job 279] File contains 1 pages', 'D [16/Dec/2009:11:07:33 -0800] [Job 279] Starting renderer with command: gs -dFirstPage=1 -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=pcl3 -sSubdevice=hpdj520 -r300x300 -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 -sColorModel=Gray -sPrintQuality=0 -sMedium=0 -dMediaPosition=1 -sIntensityRendering=halftones -dRasterGraphicsQuality=2 -dShingling=0 -sOutputFile=- /var/spool/cups/tmp/foomatic-ny3bNp', 'D [16/Dec/2009:11:07:33 -0800] [Job 279] Starting process "kid3" (generation 1)', 'D [16/Dec/2009:11:07:33 -0800] [Job 279] Starting process "kid4" (generation 2)', 'D [16/Dec/2009:11:07:33 -0800] [Job 279] Starting process "renderer" (generation 2)', 'D [16/Dec/2009:11:07:33 -0800] [Job 279] JCL: \x1b%-12345X@PJL', 'D [16/Dec/2009:11:07:33 -0800] [Job 279] ', 'D [16/Dec/2009:11:07:33 -0800] [Job 279]', 'D [16/Dec/2009:11:07:33 -0800] [Job 279] Read 8192 bytes of print data...', 'D [16/Dec/2009:11:07:33 -0800] [Job 279] renderer exited with status 0', 'D [16/Dec/2009:11:07:33 -0800] [Job 279] kid4 exited with status 0', 'D [16/Dec/2009:11:07:33 -0800] [Job 279] kid3 finished', 'D [16/Dec/2009:11:07:33 -0800] [Job 279] Kid3 exit status: 0', 'D [16/Dec/2009:11:07:33 -0800] [Job 279]', 'D [16/Dec/2009:11:07:33 -0800] [Job 279] Closing foomatic-rip.', 'D [16/Dec/2009:11:07:33 -0800] PID 2344 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.', 'I [16/Dec/2009:11:07:47 -0800] Generating printcap /var/run/cups/printcap...', 'I [16/Dec/2009:11:07:47 -0800] Saving job cache file "/var/cache/cups/job.cache"...', 'I [16/Dec/2009:11:07:47 -0800] Saving subscriptions.conf...', 'D [16/Dec/2009:11:07:47 -0800] cupsdSetBusyState: Printing jobs', 'D [16/Dec/2009:11:07:49 -0800] cupsdReadClient: 12 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:49 -0800] cupsdSetBusyState: Active clients and printing jobs', 'D [16/Dec/2009:11:07:49 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:49 -0800] cupsdReadClient: 12 1.1 Get-Job-Attributes 1', 'D [16/Dec/2009:11:07:49 -0800] Get-Job-Attributes ipp://localhost/jobs/279', 'D [16/Dec/2009:11:07:49 -0800] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/279) from localhost', 'D [16/Dec/2009:11:07:49 -0800] cupsdSetBusyState: Printing jobs', 'D [16/Dec/2009:11:07:49 -0800] cupsdReadClient: 12 POST / HTTP/1.1', 'D [16/Dec/2009:11:07:49 -0800] cupsdSetBusyState: Active clients and printing jobs', 'D [16/Dec/2009:11:07:49 -0800] cupsdAuthorize: No authentication data provided.', 'D [16/Dec/2009:11:07:49 -0800] cupsdReadClient: 12 1.1 Cancel-Subscription 1', 'D [16/Dec/2009:11:07:49 -0800] Cancel-Subscription /', 'D [16/Dec/2009:11:07:49 -0800] cupsdIsAuthorized: requesting-user-name="rocky"', 'D [16/Dec/2009:11:07:49 -0800] cupsdMarkDirty(-----S)', 'D [16/Dec/2009:11:07:49 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [16/Dec/2009:11:07:49 -0800] Returning IPP successful-ok for Cancel-Subscription (/) from localhost', 'D [16/Dec/2009:11:07:49 -0800] cupsdSetBusyState: Printing jobs and dirty files', 'D [16/Dec/2009:11:07:49 -0800] cupsdAcceptClient: 17 from localhost (Domain)', 'D [16/Dec/2009:11:07:49 -0800] cupsdReadClient: 17 GET /admin/log/error_log HTTP/1.1', 'D [16/Dec/2009:11:07:49 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [16/Dec/2009:11:07:49 -0800] cupsdAuthorize: No authentication data provided.'], 'error_log_debug_logging_unset': True} Page 10 (Locale issues): {'printer_page_size': u'Letter', 'system_locale_lang': None, 'user_locale_ctype': 'en_US', 'user_locale_messages': 'en_US'}