Attachment 308577 Details for Bug 450357 – Result of printer troubleshooting process

Result of printer troubleshooting process

troubleshoot.txt (text/plain), 37.60 KB, created by John Gorman on 2008-06-06 22:48:59 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: John Gorman

Created: 2008-06-06 22:48:59 UTC

Size: 37.60 KB

patch

obsolete

>Page 1 (Scheduler not running?):
>{'cups_connection_failure': False}
>Page 2 (Choose printer):
>{'cups_dest': <cups.Dest object at 0x906ca20>,
> 'cups_instance': None,
> 'cups_queue': 'lexmark',
> 'cups_queue_listed': True}
>Page 3 (Check printer sanity):
>{'cups_device_uri_scheme': u'socket',
> 'cups_printer_dict': {'device-uri': u'socket://172.21.1.16:9100',
>                       'printer-info': u'Lexmark',
>                       'printer-is-shared': True,
>                       'printer-location': u'Programmers',
>                       'printer-make-and-model': u'Generic PCL 6/PCL XL Printer Foomatic/pxlmono (recommended)',
>                       'printer-state': 3,
>                       'printer-state-message': u'',
>                       'printer-state-reasons': [u'none'],
>                       'printer-type': 143372,
>                       'printer-uri-supported': u'ipp://localhost:631/printers/lexmark'},
> 'cups_printer_remote': 0,
> 'is_cups_class': False}
>Page 4 (Check PPD sanity):
>{'cups_printer_ppd_valid': True, 'missing_pkgs_and_exes': ([], [])}
>Page 5 (Local or remote?):
>{'printer_is_remote': False}
>Page 6 (Error log checkpoint):
>{'cups_server_settings': {'DefaultAuthType': 'Basic',
>                          'MaxLogSize': '0',
>                          'SystemGroup': 'sys root',
>                          '_debug_logging': '0',
>                          '_remote_admin': '0',
>                          '_remote_any': '0',
>                          '_remote_printers': '1',
>                          '_share_printers': '0',
>                          '_user_cancel_any': '0'},
> 'error_log_checkpoint': 33138L,
> 'error_log_debug_logging_set': True}
>Page 7 (Print test page):
>{'test_page_attempted': True,
> 'test_page_job_id': [35],
> 'test_page_job_status': [(True,
>                           35,
>                           'lexmark',
>                           'Test Page',
>                           'Processing',
>                           {'attributes-charset': u'utf-8',
>                            'attributes-natural-language': u'en',
>                            'document-format': u'application/postscript',
>                            'job-hold-until': u'no-hold',
>                            'job-id': 35,
>                            'job-k-octets': 17,
>                            'job-media-sheets-completed': 2,
>                            'job-more-info': u'ipp://localhost:631/jobs/35',
>                            'job-name': u'Test Page',
>                            'job-originating-host-name': u'localhost',
>                            'job-originating-user-name': u'jgorman',
>                            'job-printer-state-message': u'Printer is now on-line.',
>                            'job-printer-state-reasons': [u'none'],
>                            'job-printer-up-time': 1212791895,
>                            'job-printer-uri': u'ipp://jgorman.mmslan.mms:631/printers/lexmark',
>                            '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/35',
>                            'job-uuid': u'urn:uuid:59ec4c39-7c35-3b8c-6b16-e4a62878b139',
>                            'printer-uri': u'ipp://localhost/printers/lexmark',
>                            'time-at-completed': None,
>                            'time-at-creation': 1212791814,
>                            'time-at-processing': 1212791814})],
> 'test_page_successful': False}
>Page 8 (Error log fetch):
>{'error_log': ['D [06/Jun/2008:19:36:40 -0300] cupsdCloseClient: 8',
>               'D [06/Jun/2008:19:36:40 -0300] cupsdAcceptClient: skipping getpeercon()',
>               'D [06/Jun/2008:19:36:40 -0300] cupsdAcceptClient: 8 from localhost (Domain)',
>               'D [06/Jun/2008:19:36:40 -0300] cupsdReadClient: 8 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:36:40 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:36:40 -0300] Get-Jobs ipp://localhost/jobs/',
>               'D [06/Jun/2008:19:36:40 -0300] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:36:40 -0300] cupsdAcceptClient: skipping getpeercon()',
>               'D [06/Jun/2008:19:36:40 -0300] cupsdAcceptClient: 9 from localhost (Domain)',
>               'D [06/Jun/2008:19:36:40 -0300] cupsdCloseClient: 8',
>               'D [06/Jun/2008:19:36:40 -0300] cupsdReadClient: 9 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:36:40 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:36:40 -0300] Create-Printer-Subscription /',
>               'D [06/Jun/2008:19:36:40 -0300] cupsdCreateSubscription(con=0xb9a147e8(9), uri="/")',
>               'D [06/Jun/2008:19:36:40 -0300] pullmethod="ippget"',
>               'D [06/Jun/2008:19:36:40 -0300] notify-lease-duration=86400',
>               'D [06/Jun/2008:19:36:40 -0300] notify-time-interval=0',
>               'D [06/Jun/2008:19:36:40 -0300] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)")',
>               'D [06/Jun/2008:19:36:40 -0300] Added subscription 12 for server',
>               'I [06/Jun/2008:19:36:40 -0300] Saving subscriptions.conf...',
>               'D [06/Jun/2008:19:36:40 -0300] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:36:40 -0300] cupsdCloseClient: 9',
>               'D [06/Jun/2008:19:36:41 -0300] cupsdAcceptClient: skipping getpeercon()',
>               'D [06/Jun/2008:19:36:41 -0300] cupsdAcceptClient: 8 from localhost (Domain)',
>               'D [06/Jun/2008:19:36:41 -0300] cupsdReadClient: 8 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:36:41 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:36:41 -0300] Get-Notifications /',
>               'D [06/Jun/2008:19:36:41 -0300] cupsdIsAuthorized: requesting-user-name="jgorman"',
>               'D [06/Jun/2008:19:36:41 -0300] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:36:41 -0300] cupsdCloseClient: 8',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdAcceptClient: skipping getpeercon()',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdAcceptClient: 8 from localhost (Domain)',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdReadClient: 8 POST /printers/lexmark HTTP/1.1',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:36:54 -0300] Print-Job ipp://localhost/printers/lexmark',
>               'D [06/Jun/2008:19:36:54 -0300] add_job: setting context of job to UNKNOWN SL',
>               'D [06/Jun/2008:19:36:54 -0300] add_job: requesting-user-name="jgorman"',
>               'D [06/Jun/2008:19:36:54 -0300] Adding default job-sheets values "none,none"...',
>               'I [06/Jun/2008:19:36:54 -0300] [Job 35] Adding start banner page "none".',
>               'I [06/Jun/2008:19:36:54 -0300] Saving subscriptions.conf...',
>               'I [06/Jun/2008:19:36:54 -0300] [Job 35] Adding job file of type application/postscript.',
>               'I [06/Jun/2008:19:36:54 -0300] [Job 35] Adding end banner page "none".',
>               'I [06/Jun/2008:19:36:54 -0300] [Job 35] Queued on "lexmark" by "jgorman".',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] hold_until = 0',
>               'I [06/Jun/2008:19:36:54 -0300] Saving subscriptions.conf...',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] job-sheets=none,none',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] banner_page = 0',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] argv[0]="lexmark"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] argv[1]="35"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] argv[2]="jgorman"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] argv[3]="Test Page"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] argv[4]="1"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] argv[5]="job-uuid=urn:uuid:59ec4c39-7c35-3b8c-6b16-e4a62878b139"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] argv[6]="/var/spool/cups/d00035-001"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[0]="CUPS_CACHEDIR=/var/cache/cups"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[1]="CUPS_DATADIR=/usr/share/cups"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[6]="CUPS_SERVERROOT=/etc/cups"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[7]="CUPS_STATEDIR=/var/run/cups"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[9]="SERVER_ADMIN=root@jgorman.mmslan.mms"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[10]="SOFTWARE=CUPS/1.3.7"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[11]="TMPDIR=/var/spool/cups/tmp"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[12]="USER=root"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[13]="CUPS_SERVER=/var/run/cups/cups.sock"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[14]="CUPS_ENCRYPTION=IfRequested"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[15]="IPP_PORT=631"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[16]="CHARSET=utf-8"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[17]="LANG=en.UTF8"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[18]="PPD=/etc/cups/ppd/lexmark.ppd"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[19]="RIP_MAX_CACHE=8m"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[20]="CONTENT_TYPE=application/postscript"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[21]="DEVICE_URI=socket://172.21.1.16:9100"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[22]="PRINTER=lexmark"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] envp[23]="FINAL_CONTENT_TYPE=printer/lexmark"',
>               'I [06/Jun/2008:19:36:54 -0300] [Job 35] Started filter /usr/lib/cups/filter/pstops (PID 6134)',
>               'I [06/Jun/2008:19:36:54 -0300] [Job 35] Started filter /usr/lib/cups/filter/foomatic-rip (PID 6135)',
>               'I [06/Jun/2008:19:36:54 -0300] [Job 35] Started backend /usr/lib/cups/backend/socket (PID 6136)',
>               'I [06/Jun/2008:19:36:54 -0300] Saving subscriptions.conf...',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Page = 612x792; 18,36 to 594,756',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] slow_collate=0, slow_duplex=0, slow_order=0',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Before copy_comments - %!PS-Adobe-3.0',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] %!PS-Adobe-3.0',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] %%BoundingBox: 0 0 612 792',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] %%Pages: 1',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] %%LanguageLevel: 1',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] %%DocumentData: Clean7Bit',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] %%DocumentSuppliedResources: procset testprint/1.3',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] %%Creator: Michael Sweet, Apple Inc.',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] %%CreationDate: D:20070606214000+0500',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] %%Title: Test Page',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] %%EndComments',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Before copy_prolog - %%BeginProlog',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Before copy_setup - %%Page: 1 1',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Before page loop - %%Page: 1 1',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Copying page 1...',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] pagew = 576.0, pagel = 720.0',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] PageLeft = 18.0, PageRight = 594.0',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] PageTop = 756.0, PageBottom = 36.0',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] PageWidth = 612.0, PageLength = 792.0',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Wrote 1 pages...',
>               'I [06/Jun/2008:19:36:54 -0300] Saving subscriptions.conf...',
>               'D [06/Jun/2008:19:36:54 -0300] PID 6134 (/usr/lib/cups/filter/pstops) exited with no errors.',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdCloseClient: 8',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Connected to 172.21.1.16:9100 (IPv4)...',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] backendRunLoop(print_fd=0, device_fd=5, use_bc=1, side_cb=0xb80e3ef0)',
>               'I [06/Jun/2008:19:36:54 -0300] Saving subscriptions.conf...',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] perl: warning: Setting locale failed.',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] perl: warning: Please check that your locale settings:',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] LANGUAGE = (unset),',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] LC_ALL = (unset),',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] LANG = "en.UTF8"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] are supported and installed on your system.',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] perl: warning: Falling back to the standard locale ("C").',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdAcceptClient: skipping getpeercon()',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdAcceptClient: 8 from localhost (Domain)',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdReadClient: 8 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:36:54 -0300] Get-Notifications /',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdIsAuthorized: requesting-user-name="jgorman"',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdReadClient: 8 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:36:54 -0300] Get-Job-Attributes ipp://localhost/jobs/35',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdAcceptClient: skipping getpeercon()',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdAcceptClient: 11 from localhost (Domain)',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:36:54 -0300] Get-Notifications /',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdIsAuthorized: requesting-user-name="jgorman"',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdCloseClient: 11',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] foomatic-rip version $Revision$ running...',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Parsing PPD file ...',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Added option ColorSpace',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Added option Resolution',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Added option PrintoutMode',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Added option PageSize',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Added option PageRegion',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Added option ImageableArea',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Added option PaperDimension',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Added option InputSlot',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Added option GSResolution',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Added option JCLResolution',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Added option ColorMode',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Added option PrinterResolution',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Added option Font',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35]',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Parameter Summary',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] -----------------',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35]',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Spooler: cups',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Printer: lexmark',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Shell: /bin/sh',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] PPD file: /etc/cups/ppd/lexmark.ppd',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] ATTR file:',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Printer model: Generic PCL 6/PCL XL Printer Foomatic/pxlmono (recommended)',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Job title: Test Page',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] File(s) to be printed:',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] <STDIN>',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35]',
>               "D [06/Jun/2008:19:36:54 -0300] [Job 35] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts",
>               "D [06/Jun/2008:19:36:54 -0300] [Job 35] Pondering option 'job-uuid=urn:uuid:59ec4c39-7c35-3b8c-6b16-e4a62878b139'",
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Unknown option job-uuid=urn:uuid:59ec4c39-7c35-3b8c-6b16-e4a62878b139.',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35]',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] ================================================',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35]',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] File: <STDIN>',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35]',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] ================================================',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35]',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Reading PostScript input ...',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] --> This document is DSC-conforming!',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35]',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] -----------',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Found: %%BeginProlog',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Found: %%EndProlog',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35]',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] -----------',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Found: %%BeginSetup',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Found: %%BeginFeature: *PrintoutMode Normal.Gray',
>               'D [06/Jun/2008:19:36:54 -0300] cupsdCloseClient: 8',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Option: PrintoutMode=Normal.Gray --> Setting option',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Found: %% FoomaticRIPOptionSetting: PrintoutMode=Normal.Gray',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Option: PrintoutMode=Normal.Gray --> Setting option',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Found: %%BeginFeature: *PrinterResolution FromPrintoutMode',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Option: PrinterResolution=FromPrintoutMode --> Setting option',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Found: %% FoomaticRIPOptionSetting: PrinterResolution=FromPrintoutMode',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Option: PrinterResolution=FromPrintoutMode --> Setting option',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Found: %%BeginFeature: *PageRegion Letter',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Option: PageRegion=Letter --> Option will be set by PostScript interpreter',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Found: %%BeginFeature: *InputSlot Default',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Option: InputSlot=Default --> Setting option',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Found: %% FoomaticRIPOptionSetting: InputSlot=Default',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Option: InputSlot=Default --> Setting option',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Found: %%BeginFeature: *ColorMode FromPrintoutMode',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Option: ColorMode=FromPrintoutMode --> Setting option',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Found: %% FoomaticRIPOptionSetting: ColorMode=@PrintoutMode',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Option: ColorMode=FromPrintoutMode --> Setting option',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Found: %%EndSetup',
>               "D [06/Jun/2008:19:36:54 -0300] [Job 35] Inserting PostScript code for CUPS' page accounting",
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35]',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] -----------',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] New page:  1 1',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Inserting option code into "PageSetup" section.',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35]',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Found: %%BeginPageSetup',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Found: %%EndPageSetup',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] End of page header',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Stopping search for page header options',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Found:       lineto\t\t\t\t% Move there...',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] --> Output goes directly to the renderer now.',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35]',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35]',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Starting renderer',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] renderer PID kid4=6141',
>               "D [06/Jun/2008:19:36:54 -0300] [Job 35] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dNOPAUSE -sDEVICE=pxlmono -r600x600 -sOutputFile=- - | perl -p -e 'if (! $did) { s/\\xc0.\\xf8\\x26/\\xc0\\x01\\xf8\\x26/ && $did++; }'",
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Found: %%Trailer',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] --> Continue DSC parsing now.',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35]',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Found: %%Pages: 1',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] --> Continue DSC parsing now.',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35]',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Found: %%BoundingBox: 0 0 612 792',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] --> Continue DSC parsing now.',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35]',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Found: %%EOF',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] --> Continue DSC parsing now.',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35]',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35]',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Closing renderer',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] perl: warning: Setting locale failed.',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] perl: warning: Please check that your locale settings:',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] LANGUAGE = (unset),',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] LC_ALL = (unset),',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] LANG = "en.UTF8"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] are supported and installed on your system.',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] perl: warning: Falling back to the standard locale ("C").',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] Starting process 6142: "foomatic-gswrapper -q -dBATCH -dPARANOIDSAFER -dNOPAUSE -sDEVICE=pxlmono -r600x600 -sOutputFile=- - ..."',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] JCL: \x1b%-12345X@PJL',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] @PJL SET RESOLUTION=600',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] <job data>',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] \x1b%-12345X@PJL RESET',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35]',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] perl: warning: Setting locale failed.',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] perl: warning: Please check that your locale settings:',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] LANGUAGE = (unset),',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] LC_ALL = (unset),',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] LANG = "en.UTF8"',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] are supported and installed on your system.',
>               'D [06/Jun/2008:19:36:54 -0300] [Job 35] perl: warning: Falling back to the standard locale ("C").',
>               "D [06/Jun/2008:19:36:55 -0300] [Job 35] foomatic-gswrapper: gs '-sstdout=%stderr' '-dBATCH' '-dPARANOIDSAFER' '-dNOPAUSE' '-sDEVICE=pxlmono' '-r600x600' '-sOutputFile=%stdout' '-_'",
>               'D [06/Jun/2008:19:36:55 -0300] [Job 35] GPL Ghostscript 8.62 (2008-02-29)',
>               'D [06/Jun/2008:19:36:55 -0300] [Job 35] Copyright (C) 2008 Artifex Software, Inc.  All rights reserved.',
>               'D [06/Jun/2008:19:36:55 -0300] [Job 35] This software comes with NO WARRANTY: see the file PUBLIC for details.',
>               'I [06/Jun/2008:19:36:55 -0300] Saving subscriptions.conf...',
>               'D [06/Jun/2008:19:36:55 -0300] [Job 35] Loading NimbusSanL-Bold font from /usr/share/fonts/default/Type1/n019004l.pfb... 2917592 1145798 8242584 6879638 1 done.',
>               'D [06/Jun/2008:19:36:55 -0300] [Job 35] Loading NimbusSanL-Regu font from /usr/share/fonts/default/Type1/n019003l.pfb... 2934256 1255314 8242584 6880725 1 done.',
>               'D [06/Jun/2008:19:36:55 -0300] [Job 35] Loading NimbusRomNo9L-Regu font from /usr/share/fonts/default/Type1/n021003l.pfb... 3037932 1394696 8242584 6881496 1 done.',
>               'I [06/Jun/2008:19:36:55 -0300] Saving subscriptions.conf...',
>               'D [06/Jun/2008:19:36:55 -0300] [Job 35] Read 3225 bytes of print data...',
>               'D [06/Jun/2008:19:36:55 -0300] [Job 35] Wrote 3225 bytes of print data...',
>               'I [06/Jun/2008:19:36:55 -0300] Saving subscriptions.conf...',
>               'D [06/Jun/2008:19:36:55 -0300] [Job 35] Read 4096 bytes of print data...',
>               'D [06/Jun/2008:19:36:55 -0300] [Job 35] Wrote 4096 bytes of print data...',
>               'D [06/Jun/2008:19:36:55 -0300] [Job 35] Read 4096 bytes of print data...',
>               'D [06/Jun/2008:19:36:55 -0300] [Job 35] Wrote 4096 bytes of print data...',
>               'D [06/Jun/2008:19:36:55 -0300] [Job 35] Read 8192 bytes of print data...',
>               'I [06/Jun/2008:19:36:55 -0300] Saving subscriptions.conf...',
>               'D [06/Jun/2008:19:36:55 -0300] [Job 35] Process 6142 ending: "foomatic-gswrapper -q -dBATCH -dPARANOIDSAFER -dNOPAUSE -sDEVICE=pxlmono -r600x600 -sOutputFile=- - ..."',
>               'D [06/Jun/2008:19:36:55 -0300] [Job 35] KID3 exited with status 0',
>               'D [06/Jun/2008:19:36:55 -0300] [Job 35] Wrote 8192 bytes of print data...',
>               'D [06/Jun/2008:19:36:55 -0300] [Job 35] Read 8192 bytes of print data...',
>               'D [06/Jun/2008:19:36:55 -0300] cupsdAcceptClient: skipping getpeercon()',
>               'D [06/Jun/2008:19:36:55 -0300] cupsdAcceptClient: 11 from localhost (Domain)',
>               'D [06/Jun/2008:19:36:55 -0300] cupsdAcceptClient: skipping getpeercon()',
>               'D [06/Jun/2008:19:36:55 -0300] cupsdAcceptClient: 12 from localhost (Domain)',
>               'D [06/Jun/2008:19:36:55 -0300] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:36:55 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:36:55 -0300] Get-Notifications /',
>               'D [06/Jun/2008:19:36:55 -0300] cupsdIsAuthorized: requesting-user-name="jgorman"',
>               'D [06/Jun/2008:19:36:55 -0300] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:36:55 -0300] cupsdReadClient: 12 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:36:55 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:36:55 -0300] Get-Notifications /',
>               'D [06/Jun/2008:19:36:55 -0300] cupsdIsAuthorized: requesting-user-name="jgorman"',
>               'D [06/Jun/2008:19:36:55 -0300] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:36:55 -0300] cupsdCloseClient: 11',
>               'D [06/Jun/2008:19:36:55 -0300] cupsdCloseClient: 12',
>               'D [06/Jun/2008:19:36:55 -0300] [Job 35] Wrote 8192 bytes of print data...',
>               'D [06/Jun/2008:19:36:55 -0300] [Job 35] Read 8192 bytes of print data...',
>               'D [06/Jun/2008:19:37:01 -0300] cupsdAcceptClient: skipping getpeercon()',
>               'D [06/Jun/2008:19:37:01 -0300] cupsdAcceptClient: 11 from localhost (Domain)',
>               'D [06/Jun/2008:19:37:01 -0300] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:37:01 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:37:01 -0300] CUPS-Get-Printers',
>               'D [06/Jun/2008:19:37:01 -0300] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:37:01 -0300] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:37:01 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:37:01 -0300] CUPS-Get-Classes',
>               'D [06/Jun/2008:19:37:01 -0300] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:37:01 -0300] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:37:01 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:37:01 -0300] CUPS-Get-Default',
>               'D [06/Jun/2008:19:37:01 -0300] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:37:01 -0300] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:37:01 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:37:01 -0300] Get-Printer-Attributes ipp://localhost/printers/lexmark',
>               'D [06/Jun/2008:19:37:01 -0300] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:37:01 -0300] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:37:01 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:37:01 -0300] Get-Jobs ipp://localhost/printers/lexmark',
>               'D [06/Jun/2008:19:37:01 -0300] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:37:01 -0300] cupsdCloseClient: 11',
>               'D [06/Jun/2008:19:37:05 -0300] cupsdAcceptClient: skipping getpeercon()',
>               'D [06/Jun/2008:19:37:05 -0300] cupsdAcceptClient: 11 from localhost (Domain)',
>               'D [06/Jun/2008:19:37:05 -0300] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:37:05 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:37:05 -0300] CUPS-Get-Printers',
>               'D [06/Jun/2008:19:37:05 -0300] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:37:05 -0300] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:37:05 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:37:05 -0300] CUPS-Get-Classes',
>               'D [06/Jun/2008:19:37:05 -0300] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:37:05 -0300] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:37:05 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:37:05 -0300] CUPS-Get-Default',
>               'D [06/Jun/2008:19:37:05 -0300] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:37:05 -0300] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:37:05 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:37:05 -0300] Get-Printer-Attributes ipp://localhost/printers/lexmark',
>               'D [06/Jun/2008:19:37:05 -0300] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:37:05 -0300] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:37:05 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:37:05 -0300] Get-Jobs ipp://localhost/printers/lexmark',
>               'D [06/Jun/2008:19:37:05 -0300] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:37:05 -0300] cupsdCloseClient: 11',
>               'D [06/Jun/2008:19:37:39 -0300] Report: clients=0',
>               'D [06/Jun/2008:19:37:39 -0300] Report: jobs=30',
>               'D [06/Jun/2008:19:37:39 -0300] Report: jobs-active=1',
>               'D [06/Jun/2008:19:37:39 -0300] Report: printers=2',
>               'D [06/Jun/2008:19:37:39 -0300] Report: printers-implicit=0',
>               'D [06/Jun/2008:19:37:39 -0300] Report: stringpool-string-count=924',
>               'D [06/Jun/2008:19:37:39 -0300] Report: stringpool-alloc-bytes=8776',
>               'D [06/Jun/2008:19:37:39 -0300] Report: stringpool-total-bytes=18536',
>               'D [06/Jun/2008:19:37:55 -0300] cupsdAcceptClient: skipping getpeercon()',
>               'D [06/Jun/2008:19:37:55 -0300] cupsdAcceptClient: 11 from localhost (Domain)',
>               'D [06/Jun/2008:19:37:55 -0300] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:37:55 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:37:55 -0300] Get-Notifications /',
>               'D [06/Jun/2008:19:37:55 -0300] cupsdIsAuthorized: requesting-user-name="jgorman"',
>               'D [06/Jun/2008:19:37:55 -0300] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:37:55 -0300] cupsdCloseClient: 11',
>               'D [06/Jun/2008:19:38:15 -0300] cupsdAcceptClient: skipping getpeercon()',
>               'D [06/Jun/2008:19:38:15 -0300] cupsdAcceptClient: 11 from localhost (Domain)',
>               'D [06/Jun/2008:19:38:15 -0300] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:38:15 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:38:15 -0300] Get-Job-Attributes ipp://localhost/jobs/35',
>               'D [06/Jun/2008:19:38:15 -0300] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:38:15 -0300] cupsdAcceptClient: skipping getpeercon()',
>               'D [06/Jun/2008:19:38:15 -0300] cupsdAcceptClient: 12 from localhost (Domain)',
>               'D [06/Jun/2008:19:38:15 -0300] cupsdCloseClient: 11',
>               'D [06/Jun/2008:19:38:15 -0300] cupsdReadClient: 12 POST / HTTP/1.1',
>               'D [06/Jun/2008:19:38:15 -0300] cupsdAuthorize: No authentication data provided.',
>               'D [06/Jun/2008:19:38:15 -0300] Cancel-Subscription /',
>               'D [06/Jun/2008:19:38:15 -0300] cupsdIsAuthorized: requesting-user-name="jgorman"',
>               'I [06/Jun/2008:19:38:15 -0300] Saving subscriptions.conf...',
>               'D [06/Jun/2008:19:38:15 -0300] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)',
>               'D [06/Jun/2008:19:38:15 -0300] cupsdAcceptClient: skipping getpeercon()',
>               'D [06/Jun/2008:19:38:15 -0300] cupsdAcceptClient: 11 from localhost (Domain)',
>               'D [06/Jun/2008:19:38:15 -0300] cupsdCloseClient: 12',
>               'D [06/Jun/2008:19:38:15 -0300] cupsdReadClient: 11 GET /admin/log/error_log HTTP/1.1',
>               'D [06/Jun/2008:19:38:15 -0300] cupsdAuthorize: No authentication data provided.'],
> 'error_log_debug_logging_unset': True}
>Page 9 (Printer state reasons):
>{'printer-state-message': u'Printer is now on-line.',
> 'printer-state-reasons': [u'none']}

Actions: View

Attachments on bug 450357: 308577 | 308796 | 308932 | 308933