Attachment 305791 Details for Bug 446678 – troubleshoot

troubleshoot

troubleshoot.txt (text/plain), 35.09 KB, created by Alexey Kuznetsov on 2008-05-17 08:43:38 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Alexey Kuznetsov

Created: 2008-05-17 08:43:38 UTC

Size: 35.09 KB

patch

obsolete

>Page 1 (Scheduler not running?):
>{'cups_connection_failure': False}
>Page 2 (Choose printer):
>{'cups_dest': <cups.Dest object at 0x10389300>,
> 'cups_instance': None,
> 'cups_queue': 'HP_LaserJet_1020',
> 'cups_queue_listed': True}
>Page 3 (Check printer sanity):
>{'cups_device_uri_scheme': u'hp',
> 'cups_printer_dict': {'device-uri': u'hp:/usb/HP_LaserJet_1020?serial=JL2NBET',
>                       'printer-info': u'HP_LaserJet_1020',
>                       'printer-is-shared': True,
>                       'printer-location': u'',
>                       'printer-make-and-model': u'HP LaserJet 1020 Foomatic/hpijs-ZJS (recommended)',
>                       'printer-state': 3,
>                       'printer-state-message': u'/usr/lib/cups/filter/foomatic-rip failed',
>                       'printer-state-reasons': [u'connecting-to-device'],
>                       'printer-type': 167940,
>                       'printer-uri-supported': u'ipp://localhost:631/printers/HP_LaserJet_1020'},
> '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 (Printer state reasons):
>{'printer-state-message': u'/usr/lib/cups/filter/foomatic-rip failed',
> 'printer-state-reasons': [u'connecting-to-device']}
>Page 7 (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': '1',
>                          '_user_cancel_any': '0'},
> 'error_log_checkpoint': 83867L,
> 'error_log_debug_logging_set': True}
>Page 8 (Print test page):
>{'test_page_attempted': True,
> 'test_page_completions': [(24, u'Job canceled.')],
> 'test_page_job_id': [24],
> 'test_page_job_status': [(True,
>                           24,
>                           'HP_LaserJet_1020',
>                           'Test Page',
>                           'Canceled',
>                           {'attributes-charset': u'utf-8',
>                            'attributes-natural-language': u'en-us',
>                            'document-format': u'application/postscript',
>                            'job-hold-until': u'no-hold',
>                            'job-id': 24,
>                            'job-k-octets': 17,
>                            'job-media-sheets-completed': 0,
>                            'job-more-info': u'ipp://localhost:631/jobs/24',
>                            'job-name': u'Test Page',
>                            'job-originating-host-name': u'localhost',
>                            'job-originating-user-name': u'axet',
>                            'job-preserved': False,
>                            'job-printer-state-message': u'',
>                            'job-printer-state-reasons': [u'connecting-to-device'],
>                            'job-printer-up-time': 1211013507,
>                            'job-printer-uri': u'ipp://mini.tax.local:631/printers/HP_LaserJet_1020',
>                            'job-priority': 50,
>                            'job-sheets': [u'none', u'none'],
>                            'job-state': 7,
>                            'job-state-reasons': u'job-canceled-by-user',
>                            'job-uri': u'ipp://localhost:631/jobs/24',
>                            'job-uuid': u'urn:uuid:d832fa4f-0f72-325b-4a5d-251707123073',
>                            'printer-uri': u'ipp://localhost/printers/HP_LaserJet_1020',
>                            'time-at-completed': 1211013505,
>                            'time-at-creation': 1211013493,
>                            'time-at-processing': 1211013493})],
> 'test_page_jobs_cancelled': True,
> 'test_page_successful': False}
>Page 9 (Error log fetch):
>{'error_log': ['D [17/May/2008:12:38:11 +0400] cupsdCloseClient: 14',
>               'D [17/May/2008:12:38:11 +0400] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:12:38:11 +0400] cupsdAcceptClient: 14 from localhost (Domain)',
>               'D [17/May/2008:12:38:11 +0400] cupsdReadClient: 14 POST / HTTP/1.1',
>               'D [17/May/2008:12:38:11 +0400] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:12:38:11 +0400] Get-Jobs ipp://localhost/jobs/',
>               'D [17/May/2008:12:38:11 +0400] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok)',
>               'D [17/May/2008:12:38:11 +0400] cupsdCloseClient: 14',
>               'D [17/May/2008:12:38:11 +0400] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:12:38:11 +0400] cupsdAcceptClient: 14 from localhost (Domain)',
>               'D [17/May/2008:12:38:11 +0400] cupsdReadClient: 14 POST / HTTP/1.1',
>               'D [17/May/2008:12:38:11 +0400] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:12:38:11 +0400] Create-Printer-Subscription /',
>               'D [17/May/2008:12:38:11 +0400] cupsdCreateSubscription(con=0x200c4598(14), uri="/")',
>               'D [17/May/2008:12:38:11 +0400] pullmethod="ippget"',
>               'D [17/May/2008:12:38:11 +0400] notify-lease-duration=86400',
>               'D [17/May/2008:12:38:11 +0400] notify-time-interval=0',
>               'D [17/May/2008:12:38:11 +0400] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)")',
>               'D [17/May/2008:12:38:11 +0400] Added subscription 4 for server',
>               'I [17/May/2008:12:38:11 +0400] Saving subscriptions.conf...',
>               'D [17/May/2008:12:38:11 +0400] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok)',
>               'D [17/May/2008:12:38:11 +0400] cupsdCloseClient: 14',
>               'D [17/May/2008:12:38:12 +0400] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:12:38:12 +0400] cupsdAcceptClient: 14 from localhost (Domain)',
>               'D [17/May/2008:12:38:12 +0400] cupsdReadClient: 14 POST / HTTP/1.1',
>               'D [17/May/2008:12:38:12 +0400] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:12:38:12 +0400] Get-Notifications /',
>               'D [17/May/2008:12:38:12 +0400] cupsdIsAuthorized: requesting-user-name="axet"',
>               'D [17/May/2008:12:38:12 +0400] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok)',
>               'D [17/May/2008:12:38:12 +0400] cupsdCloseClient: 14',
>               'D [17/May/2008:12:38:13 +0400] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:12:38:13 +0400] cupsdAcceptClient: 14 from localhost (Domain)',
>               'D [17/May/2008:12:38:13 +0400] cupsdReadClient: 14 POST /printers/HP_LaserJet_1020 HTTP/1.1',
>               'D [17/May/2008:12:38:13 +0400] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:12:38:13 +0400] Print-Job ipp://localhost/printers/HP_LaserJet_1020',
>               'D [17/May/2008:12:38:13 +0400] add_job: setting context of job to UNKNOWN SL',
>               'D [17/May/2008:12:38:13 +0400] add_job: requesting-user-name="axet"',
>               'D [17/May/2008:12:38:13 +0400] Adding default job-sheets values "none,none"...',
>               'I [17/May/2008:12:38:13 +0400] [Job 24] Adding start banner page "none".',
>               'I [17/May/2008:12:38:13 +0400] Saving subscriptions.conf...',
>               'I [17/May/2008:12:38:13 +0400] [Job 24] Adding job file of type application/postscript.',
>               'I [17/May/2008:12:38:13 +0400] [Job 24] Adding end banner page "none".',
>               'I [17/May/2008:12:38:13 +0400] [Job 24] Queued on "HP_LaserJet_1020" by "axet".',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] hold_until = 0',
>               'I [17/May/2008:12:38:13 +0400] Saving subscriptions.conf...',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] job-sheets=none,none',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] banner_page = 0',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] argv[0]="HP_LaserJet_1020"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] argv[1]="24"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] argv[2]="axet"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] argv[3]="Test Page"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] argv[4]="1"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] argv[5]="job-uuid=urn:uuid:d832fa4f-0f72-325b-4a5d-251707123073"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] argv[6]="/var/spool/cups/d00024-001"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[0]="CUPS_CACHEDIR=/var/cache/cups"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[1]="CUPS_DATADIR=/usr/share/cups"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[6]="CUPS_SERVERROOT=/etc/cups"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[7]="CUPS_STATEDIR=/var/run/cups"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[9]="SERVER_ADMIN=root@mini.tax.local"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[10]="SOFTWARE=CUPS/1.3.7"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[11]="TMPDIR=/var/spool/cups/tmp"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[12]="USER=root"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[13]="CUPS_SERVER=/var/run/cups/cups.sock"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[14]="CUPS_ENCRYPTION=IfRequested"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[15]="IPP_PORT=631"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[16]="CHARSET=utf-8"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[17]="LANG=en_US.UTF8"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[18]="PPD=/etc/cups/ppd/HP_LaserJet_1020.ppd"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[19]="RIP_MAX_CACHE=8m"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[20]="CONTENT_TYPE=application/postscript"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[21]="DEVICE_URI=hp:/usb/HP_LaserJet_1020?serial=JL2NBET"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[22]="PRINTER=HP_LaserJet_1020"',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] envp[23]="FINAL_CONTENT_TYPE=printer/HP_LaserJet_1020"',
>               'I [17/May/2008:12:38:13 +0400] [Job 24] Started filter /usr/lib/cups/filter/pstops (PID 2903)',
>               'I [17/May/2008:12:38:13 +0400] [Job 24] Started filter /usr/lib/cups/filter/foomatic-rip (PID 2904)',
>               'I [17/May/2008:12:38:13 +0400] [Job 24] Started backend /usr/lib/cups/backend/hp (PID 2905)',
>               'I [17/May/2008:12:38:13 +0400] Saving subscriptions.conf...',
>               'D [17/May/2008:12:38:13 +0400] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok)',
>               'D [17/May/2008:12:38:13 +0400] PID 2903 (/usr/lib/cups/filter/pstops) exited with no errors.',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] Page = 612x792; 18,14 to 594,778',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] slow_collate=0, slow_duplex=0, slow_order=0',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] Before copy_comments - %!PS-Adobe-3.0',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] %!PS-Adobe-3.0',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] %%BoundingBox: 0 0 612 792',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] %%Pages: 1',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] %%LanguageLevel: 1',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] %%DocumentData: Clean7Bit',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] %%DocumentSuppliedResources: procset testprint/1.3',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] %%Creator: Michael Sweet, Apple Inc.',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] %%CreationDate: D:20070606214000+0500',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] %%Title: Test Page',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] %%EndComments',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] Before copy_prolog - %%BeginProlog',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] Before copy_setup - %%Page: 1 1',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] Before page loop - %%Page: 1 1',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] Copying page 1...',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] pagew = 576.0, pagel = 763.2',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] PageLeft = 18.0, PageRight = 594.0',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] PageTop = 777.6, PageBottom = 14.4',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] PageWidth = 612.0, PageLength = 792.0',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] Wrote 1 pages...',
>               'I [17/May/2008:12:38:13 +0400] Saving subscriptions.conf...',
>               'D [17/May/2008:12:38:13 +0400] cupsdCloseClient: 14',
>               'D [17/May/2008:12:38:13 +0400] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:12:38:13 +0400] cupsdAcceptClient: 14 from localhost (Domain)',
>               'D [17/May/2008:12:38:13 +0400] cupsdReadClient: 14 POST / HTTP/1.1',
>               'D [17/May/2008:12:38:13 +0400] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:12:38:13 +0400] Get-Jobs ipp://localhost/jobs',
>               'D [17/May/2008:12:38:13 +0400] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok)',
>               'D [17/May/2008:12:38:13 +0400] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:12:38:13 +0400] cupsdAcceptClient: 15 from localhost (Domain)',
>               'D [17/May/2008:12:38:13 +0400] cupsdCloseClient: 14',
>               'D [17/May/2008:12:38:13 +0400] cupsdReadClient: 15 POST / HTTP/1.1',
>               'D [17/May/2008:12:38:13 +0400] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:12:38:13 +0400] Get-Jobs ipp://localhost/jobs',
>               'D [17/May/2008:12:38:13 +0400] [Job 23] Loading attributes...',
>               'D [17/May/2008:12:38:13 +0400] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)',
>               'D [17/May/2008:12:38:13 +0400] cupsdCloseClient: 15',
>               'D [17/May/2008:12:38:13 +0400] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:12:38:13 +0400] cupsdAcceptClient: 14 from localhost (Domain)',
>               'D [17/May/2008:12:38:13 +0400] cupsdReadClient: 14 POST / HTTP/1.1',
>               'D [17/May/2008:12:38:13 +0400] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:12:38:13 +0400] Get-Jobs ipp://localhost/jobs',
>               'D [17/May/2008:12:38:13 +0400] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok)',
>               'D [17/May/2008:12:38:13 +0400] cupsdCloseClient: 14',
>               'D [17/May/2008:12:38:13 +0400] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:12:38:13 +0400] cupsdAcceptClient: 14 from localhost (Domain)',
>               'D [17/May/2008:12:38:13 +0400] cupsdReadClient: 14 POST / HTTP/1.1',
>               'D [17/May/2008:12:38:13 +0400] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:12:38:13 +0400] Get-Jobs ipp://localhost/jobs',
>               'D [17/May/2008:12:38:13 +0400] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok)',
>               'D [17/May/2008:12:38:13 +0400] [Job 24] prnt/backend/hp.c 636: INFO: open device failed; will retry in 30 seconds...',
>               'D [17/May/2008:12:38:13 +0400] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:12:38:13 +0400] cupsdAcceptClient: 15 from localhost (Domain)',
>               'D [17/May/2008:12:38:13 +0400] cupsdReadClient: 15 POST / HTTP/1.1',
>               'D [17/May/2008:12:38:13 +0400] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:12:38:13 +0400] Get-Notifications /',
>               'D [17/May/2008:12:38:13 +0400] cupsdIsAuthorized: requesting-user-name="axet"',
>               'D [17/May/2008:12:38:13 +0400] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)',
>               'D [17/May/2008:12:38:13 +0400] cupsdReadClient: 15 POST / HTTP/1.1',
>               'D [17/May/2008:12:38:13 +0400] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:12:38:13 +0400] Get-Job-Attributes ipp://localhost/jobs/24',
>               'D [17/May/2008:12:38:13 +0400] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)',
>               'D [17/May/2008:12:38:13 +0400] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:12:38:13 +0400] cupsdAcceptClient: 17 from localhost (Domain)',
>               'D [17/May/2008:12:38:13 +0400] cupsdReadClient: 17 POST / HTTP/1.1',
>               'D [17/May/2008:12:38:13 +0400] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:12:38:13 +0400] Get-Notifications /',
>               'D [17/May/2008:12:38:13 +0400] cupsdIsAuthorized: requesting-user-name="axet"',
>               'D [17/May/2008:12:38:13 +0400] cupsdProcessIPPRequest: 17 status_code=0 (successful-ok)',
>               'D [17/May/2008:12:38:13 +0400] cupsdCloseClient: 17',
>               'D [17/May/2008:12:38:13 +0400] cupsdCloseClient: 15',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] foomatic-rip version $Revision$ running...',
>               'D [17/May/2008:12:38:14 +0400] cupsdNetIFUpdate: "lo" = localhost...',
>               'D [17/May/2008:12:38:14 +0400] cupsdNetIFUpdate: "br0" = 192.168.54.3...',
>               'D [17/May/2008:12:38:14 +0400] cupsdNetIFUpdate: "ppp0" = 77.51.117.51...',
>               'D [17/May/2008:12:38:14 +0400] cupsdNetIFUpdate: "lo" = localhost...',
>               'D [17/May/2008:12:38:14 +0400] cupsdNetIFUpdate: "eth0" = fe80::211:24ff:fe74:b268%eth0...',
>               'D [17/May/2008:12:38:14 +0400] cupsdNetIFUpdate: "br0" = fe80::211:24ff:fe74:b268%br0...',
>               'D [17/May/2008:12:38:14 +0400] cupsdNetIFUpdate: "tap0" = fe80::2ff:9bff:feec:74e5%tap0...',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Parsing PPD file ...',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Added option ColorSpace',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Added option Resolution',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Added option PageSize',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Added option PageRegion',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Added option Model',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Added option PrintoutMode',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Added option MediaType',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Added option InputSlot',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Added option ImageableArea',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Added option PaperDimension',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Added option Quality',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Added option Font',
>               'D [17/May/2008:12:38:14 +0400] [Job 24]',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Parameter Summary',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] -----------------',
>               'D [17/May/2008:12:38:14 +0400] [Job 24]',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Spooler: cups',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Printer: HP_LaserJet_1020',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Shell: /bin/sh',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] PPD file: /etc/cups/ppd/HP_LaserJet_1020.ppd',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] ATTR file:',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Printer model: HP LaserJet 1020 Foomatic/hpijs-ZJS (recommended)',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Job title: Test Page',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] File(s) to be printed:',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] <STDIN>',
>               'D [17/May/2008:12:38:14 +0400] [Job 24]',
>               "D [17/May/2008:12:38:14 +0400] [Job 24] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts",
>               "D [17/May/2008:12:38:14 +0400] [Job 24] Pondering option 'job-uuid=urn:uuid:d832fa4f-0f72-325b-4a5d-251707123073'",
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Unknown option job-uuid=urn:uuid:d832fa4f-0f72-325b-4a5d-251707123073.',
>               'D [17/May/2008:12:38:14 +0400] [Job 24]',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] ================================================',
>               'D [17/May/2008:12:38:14 +0400] [Job 24]',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] File: <STDIN>',
>               'D [17/May/2008:12:38:14 +0400] [Job 24]',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] ================================================',
>               'D [17/May/2008:12:38:14 +0400] [Job 24]',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Reading PostScript input ...',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] --> This document is DSC-conforming!',
>               'D [17/May/2008:12:38:14 +0400] [Job 24]',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] -----------',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found: %%BeginProlog',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found: %%EndProlog',
>               'D [17/May/2008:12:38:14 +0400] [Job 24]',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] -----------',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found: %%BeginSetup',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found: %%BeginFeature: *PrintoutMode Normal',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Option: PrintoutMode=Normal --> Setting option',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found: %% FoomaticRIPOptionSetting: PrintoutMode=Normal',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Option: PrintoutMode=Normal --> Setting option',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found: %%BeginFeature: *MediaType Plain',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Option: MediaType=Plain --> Setting option',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found: %% FoomaticRIPOptionSetting: MediaType=Plain',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Option: MediaType=Plain --> Setting option',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found: %%BeginFeature: *InputSlot Default',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Option: InputSlot=Default --> Setting option',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found: %% FoomaticRIPOptionSetting: InputSlot=Default',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Option: InputSlot=Default --> Setting option',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found: %%BeginFeature: *Quality FromPrintoutMode',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Option: Quality=FromPrintoutMode --> Setting option',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found: %% FoomaticRIPOptionSetting: Quality=@PrintoutMode',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Option: Quality=FromPrintoutMode --> Setting option',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found: %%BeginFeature: *PageRegion Letter',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Option: PageRegion=Letter --> Option will be set by PostScript interpreter',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found: %% FoomaticRIPOptionSetting: PageSize=Letter',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Option: PageSize=Letter --> Setting option',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found: %%EndSetup',
>               "D [17/May/2008:12:38:14 +0400] [Job 24] Inserting PostScript code for CUPS' page accounting",
>               'D [17/May/2008:12:38:14 +0400] [Job 24]',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] -----------',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] New page:  1 1',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Inserting option code into "PageSetup" section.',
>               'D [17/May/2008:12:38:14 +0400] [Job 24]',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found: %%BeginPageSetup',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found: %%EndPageSetup',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] End of page header',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Stopping search for page header options',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found:       lineto\t\t\t\t% Move there...',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] --> Output goes directly to the renderer now.',
>               'D [17/May/2008:12:38:14 +0400] [Job 24]',
>               'D [17/May/2008:12:38:14 +0400] [Job 24]',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Starting renderer',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] JCL: <job data>',
>               'D [17/May/2008:12:38:14 +0400] [Job 24]',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found: %%Trailer',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] --> Continue DSC parsing now.',
>               'D [17/May/2008:12:38:14 +0400] [Job 24]',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found: %%Pages: 1',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] --> Continue DSC parsing now.',
>               'D [17/May/2008:12:38:14 +0400] [Job 24]',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found: %%BoundingBox: 0 0 612 792',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] --> Continue DSC parsing now.',
>               'D [17/May/2008:12:38:14 +0400] [Job 24]',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Found: %%EOF',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] --> Continue DSC parsing now.',
>               'D [17/May/2008:12:38:14 +0400] [Job 24]',
>               'D [17/May/2008:12:38:14 +0400] [Job 24]',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Closing renderer',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] renderer PID kid4=2910',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="HP LaserJet 1020" -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 -r600 -sIjsParams=Quality:Quality=0,Quality:ColorMode=0,Quality:PenSet=0Plain,PS:MediaPosition=7 -dIjsUseOutputFD -sOutputFile=- -',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Starting process 2911: "foomatic-gswrapper -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -sDev..."',
>               "D [17/May/2008:12:38:14 +0400] [Job 24] foomatic-gswrapper: gs '-sstdout=%stderr' '-dBATCH' '-dPARANOIDSAFER' '-dQUIET' '-dNOPAUSE' '-sDEVICE=ijs' '-sIjsServer=hpijs' '-sDeviceManufacturer=HEWLETT-PACKARD' '-sDeviceModel=HP LaserJet 1020' '-dDEVICEWIDTHPOINTS=612' '-dDEVICEHEIGHTPOINTS=792' '-r600' '-sIjsParams=Quality:Quality=0,Quality:ColorMode=0,Quality:PenSet=0Plain,PS:MediaPosition=7' '-dIjsUseOutputFD' '-sOutputFile=%stdout' '-_'",
>               'D [17/May/2008:12:38:14 +0400] [Job 24] unable to set device=HP LaserJet 1020, err=16',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] **** Unable to open the initial device, quitting.',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] unable to read client data err=-2',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Process 2911 ending: "foomatic-gswrapper -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -sDev..."',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] renderer return value: 1',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] renderer received signal: 1',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] tail process done writing data to STDOUT',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] KID4 finished',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] KID4 exited with status 0',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] KID3 exited with status 3',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Renderer exit stat: 3',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Process dying with "Possible error on renderer command line or PostScript error. Check options.", exit stat: 3',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] error: Illegal seek (29)',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Cleaning up ...',
>               'D [17/May/2008:12:38:14 +0400] [Job 24] Killing process 2910 (KID4) with signal 15',
>               'D [17/May/2008:12:38:18 +0400] [Job 24] Killing process 2910 (KID4) with signal 9',
>               'D [17/May/2008:12:38:18 +0400] [Job 24]',
>               'D [17/May/2008:12:38:18 +0400] [Job 24] Closing foomatic-rip.',
>               'D [17/May/2008:12:38:18 +0400] [Job 24] Renderer process finished',
>               'D [17/May/2008:12:38:18 +0400] [Job 24] Process dying with "Error closing renderer", exit stat: 3',
>               'D [17/May/2008:12:38:18 +0400] [Job 24] error: Bad file descriptor (9)',
>               'D [17/May/2008:12:38:18 +0400] [Job 24] Cleaning up ...',
>               'D [17/May/2008:12:38:18 +0400] [Job 24] Killing process 2909 (KID3) with signal 15',
>               'D [17/May/2008:12:38:23 +0400] [Job 24] Killing process 2909 (KID3) with signal 9',
>               'D [17/May/2008:12:38:23 +0400] [Job 24] Error closing renderer',
>               'D [17/May/2008:12:38:23 +0400] [Job 24]',
>               'D [17/May/2008:12:38:23 +0400] [Job 24] Closing foomatic-rip.',
>               'E [17/May/2008:12:38:23 +0400] PID 2904 (/usr/lib/cups/filter/foomatic-rip) stopped with status 3!',
>               'D [17/May/2008:12:38:25 +0400] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:12:38:25 +0400] cupsdAcceptClient: 15 from localhost (Domain)',
>               'D [17/May/2008:12:38:25 +0400] cupsdReadClient: 15 POST /jobs/ HTTP/1.1',
>               'D [17/May/2008:12:38:25 +0400] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:12:38:25 +0400] Cancel-Job ipp://localhost/jobs/24',
>               'D [17/May/2008:12:38:25 +0400] cupsdIsAuthorized: requesting-user-name="axet"',
>               'I [17/May/2008:12:38:25 +0400] Saving subscriptions.conf...',
>               'I [17/May/2008:12:38:25 +0400] Saving subscriptions.conf...',
>               'I [17/May/2008:12:38:25 +0400] [Job 24] Canceled by "axet".',
>               'D [17/May/2008:12:38:25 +0400] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)',
>               'D [17/May/2008:12:38:25 +0400] PID 2905 (/usr/lib/cups/backend/hp) exited with no errors.',
>               'D [17/May/2008:12:38:25 +0400] cupsdCloseClient: 15',
>               'D [17/May/2008:12:38:26 +0400] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:12:38:26 +0400] cupsdAcceptClient: 15 from localhost (Domain)',
>               'D [17/May/2008:12:38:26 +0400] [Job 24] Unloading...',
>               'D [17/May/2008:12:38:26 +0400] cupsdReadClient: 15 POST / HTTP/1.1',
>               'D [17/May/2008:12:38:26 +0400] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:12:38:26 +0400] Get-Notifications /',
>               'D [17/May/2008:12:38:26 +0400] cupsdIsAuthorized: requesting-user-name="axet"',
>               'D [17/May/2008:12:38:26 +0400] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)',
>               'D [17/May/2008:12:38:26 +0400] cupsdCloseClient: 15',
>               'D [17/May/2008:12:38:26 +0400] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:12:38:26 +0400] cupsdAcceptClient: 15 from localhost (Domain)',
>               'D [17/May/2008:12:38:26 +0400] cupsdReadClient: 15 POST / HTTP/1.1',
>               'D [17/May/2008:12:38:26 +0400] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:12:38:26 +0400] Get-Notifications /',
>               'D [17/May/2008:12:38:26 +0400] cupsdIsAuthorized: requesting-user-name="axet"',
>               'D [17/May/2008:12:38:26 +0400] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)',
>               'D [17/May/2008:12:38:26 +0400] cupsdCloseClient: 15',
>               'D [17/May/2008:12:38:27 +0400] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:12:38:27 +0400] cupsdAcceptClient: 15 from localhost (Domain)',
>               'D [17/May/2008:12:38:27 +0400] cupsdReadClient: 15 POST / HTTP/1.1',
>               'D [17/May/2008:12:38:27 +0400] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:12:38:27 +0400] Get-Job-Attributes ipp://localhost/jobs/24',
>               'D [17/May/2008:12:38:27 +0400] [Job 24] Loading attributes...',
>               'D [17/May/2008:12:38:27 +0400] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)',
>               'D [17/May/2008:12:38:27 +0400] cupsdCloseClient: 15',
>               'D [17/May/2008:12:38:27 +0400] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:12:38:27 +0400] cupsdAcceptClient: 15 from localhost (Domain)',
>               'D [17/May/2008:12:38:27 +0400] cupsdReadClient: 15 POST / HTTP/1.1',
>               'D [17/May/2008:12:38:27 +0400] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:12:38:27 +0400] Cancel-Subscription /',
>               'D [17/May/2008:12:38:27 +0400] cupsdIsAuthorized: requesting-user-name="axet"',
>               'I [17/May/2008:12:38:27 +0400] Saving subscriptions.conf...',
>               'D [17/May/2008:12:38:27 +0400] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)',
>               'D [17/May/2008:12:38:27 +0400] cupsdCloseClient: 15',
>               'D [17/May/2008:12:38:27 +0400] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:12:38:27 +0400] cupsdAcceptClient: 15 from localhost (Domain)',
>               'D [17/May/2008:12:38:27 +0400] cupsdReadClient: 15 GET /admin/log/error_log HTTP/1.1',
>               'D [17/May/2008:12:38:27 +0400] cupsdAuthorize: No authentication data provided.']}

Actions: View

Attachments on bug 446678: 305791 | 307193 | 307211 | 308385 | 308558 | 308559 | 308800 | 308801 | 308803 | 334422 | 334423