Attachment 305776 Details for Bug 446358 – troubleshoot.txt

troubleshoot.txt

troubleshoot.txt (text/plain), 30.43 KB, created by Rodd Clarkson on 2008-05-16 23:15:33 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Rodd Clarkson

Created: 2008-05-16 23:15:33 UTC

Size: 30.43 KB

patch

obsolete

>Page 1 (Scheduler not running?):
>{'cups_connection_failure': False}
>Page 2 (Choose printer):
>{'cups_dest': <cups.Dest object at 0x955da00>,
> 'cups_instance': None,
> 'cups_queue': 'hl5250dn',
> 'cups_queue_listed': True}
>Page 3 (Check printer sanity):
>{'cups_device_uri_scheme': u'ipp',
> 'cups_printer_dict': {'device-uri': u'ipp://192.168.1.250/ipp',
>                       'printer-info': u'Brother HL-5250DN Laser Printer',
>                       'printer-is-shared': True,
>                       'printer-location': u'Back Room',
>                       'printer-make-and-model': u'Brother HL-5250DN BR-Script3',
>                       'printer-state': 3,
>                       'printer-state-message': u'',
>                       'printer-state-reasons': [u'none'],
>                       'printer-type': 45140,
>                       'printer-uri-supported': u'ipp://localhost:631/printers/hl5250dn'},
> 'cups_printer_remote': 0,
> 'is_cups_class': False,
> 'remote_server_name': u'192.168.1.250',
> 'remote_server_port': 631}
>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 (Check network server sanity):
>{'remote_server_connect_ipp': True,
> 'remote_server_cups': False,
> 'remote_server_name_resolves': ['192.168.1.250',
>                                 '192.168.1.250',
>                                 '192.168.1.250'],
> 'remote_server_traceroute': ('traceroute to 192.168.1.250 (192.168.1.250), 30 hops max, 40 byte packets\n 1  192.168.1.250 (192.168.1.250)  165.026 ms  164.848 ms  164.931 ms\n',
>                              ''),
> 'remote_server_try_connect': u'192.168.1.250'}
>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': '0',
>                          '_user_cancel_any': '0'},
> 'error_log_checkpoint': 4162L,
> 'error_log_debug_logging_set': True}
>Page 8 (Print test page):
>{'test_page_job_status': [(False,
>                           30,
>                           'hl5250dn',
>                           'evince-print',
>                           'Completed',
>                           None)],
> 'test_page_successful': True}
>Page 9 (Error log fetch):
>{'error_log': ['D [17/May/2008:09:10:06 +1000] cupsdCloseClient: 8',
>               'D [17/May/2008:09:10:06 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:10:06 +1000] cupsdAcceptClient: 8 from localhost (Domain)',
>               'D [17/May/2008:09:10:06 +1000] cupsdReadClient: 8 POST / HTTP/1.1',
>               'D [17/May/2008:09:10:06 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:10:06 +1000] Get-Jobs ipp://localhost/jobs/',
>               'D [17/May/2008:09:10:06 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:10:06 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:10:06 +1000] cupsdAcceptClient: 9 from localhost (Domain)',
>               'D [17/May/2008:09:10:06 +1000] cupsdCloseClient: 8',
>               'D [17/May/2008:09:10:06 +1000] cupsdReadClient: 9 POST / HTTP/1.1',
>               'D [17/May/2008:09:10:06 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:10:06 +1000] Create-Printer-Subscription /',
>               'D [17/May/2008:09:10:06 +1000] cupsdCreateSubscription(con=0xb95b52c8(9), uri="/")',
>               'D [17/May/2008:09:10:06 +1000] pullmethod="ippget"',
>               'D [17/May/2008:09:10:06 +1000] notify-lease-duration=86400',
>               'D [17/May/2008:09:10:06 +1000] notify-time-interval=0',
>               'D [17/May/2008:09:10:06 +1000] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)")',
>               'D [17/May/2008:09:10:06 +1000] Added subscription 10 for server',
>               'I [17/May/2008:09:10:06 +1000] Saving subscriptions.conf...',
>               'D [17/May/2008:09:10:06 +1000] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:10:06 +1000] cupsdCloseClient: 9',
>               'D [17/May/2008:09:10:07 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:10:07 +1000] cupsdAcceptClient: 8 from localhost (Domain)',
>               'D [17/May/2008:09:10:07 +1000] cupsdReadClient: 8 POST / HTTP/1.1',
>               'D [17/May/2008:09:10:07 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:10:07 +1000] Get-Notifications /',
>               'D [17/May/2008:09:10:07 +1000] cupsdIsAuthorized: requesting-user-name="rodd"',
>               'D [17/May/2008:09:10:07 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:10:07 +1000] cupsdCloseClient: 8',
>               'D [17/May/2008:09:11:07 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:11:07 +1000] cupsdAcceptClient: 8 from localhost (Domain)',
>               'D [17/May/2008:09:11:07 +1000] Report: clients=1',
>               'D [17/May/2008:09:11:07 +1000] Report: jobs=29',
>               'D [17/May/2008:09:11:07 +1000] Report: jobs-active=0',
>               'D [17/May/2008:09:11:07 +1000] Report: printers=2',
>               'D [17/May/2008:09:11:07 +1000] Report: printers-implicit=0',
>               'D [17/May/2008:09:11:07 +1000] Report: stringpool-string-count=779',
>               'D [17/May/2008:09:11:07 +1000] Report: stringpool-alloc-bytes=7304',
>               'D [17/May/2008:09:11:07 +1000] Report: stringpool-total-bytes=14224',
>               'D [17/May/2008:09:11:07 +1000] cupsdReadClient: 8 POST / HTTP/1.1',
>               'D [17/May/2008:09:11:07 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:11:07 +1000] Get-Notifications /',
>               'D [17/May/2008:09:11:07 +1000] cupsdIsAuthorized: requesting-user-name="rodd"',
>               'D [17/May/2008:09:11:07 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:11:07 +1000] cupsdCloseClient: 8',
>               'D [17/May/2008:09:11:39 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:11:39 +1000] cupsdAcceptClient: 8 from localhost (Domain)',
>               'D [17/May/2008:09:11:39 +1000] cupsdReadClient: 8 POST / HTTP/1.1',
>               'D [17/May/2008:09:11:39 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:11:39 +1000] CUPS-Get-Printers',
>               'D [17/May/2008:09:11:39 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:11:39 +1000] cupsdReadClient: 8 POST / HTTP/1.1',
>               'D [17/May/2008:09:11:39 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:11:39 +1000] CUPS-Get-Classes',
>               'D [17/May/2008:09:11:39 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:11:39 +1000] cupsdReadClient: 8 POST / HTTP/1.1',
>               'D [17/May/2008:09:11:39 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:11:39 +1000] CUPS-Get-Default',
>               'D [17/May/2008:09:11:39 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:11:39 +1000] cupsdCloseClient: 8',
>               'D [17/May/2008:09:11:51 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:11:51 +1000] cupsdAcceptClient: 8 from localhost (Domain)',
>               'D [17/May/2008:09:11:51 +1000] cupsdReadClient: 8 POST / HTTP/1.1',
>               'D [17/May/2008:09:11:51 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:11:51 +1000] CUPS-Get-Printers',
>               'D [17/May/2008:09:11:51 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:11:51 +1000] cupsdReadClient: 8 POST / HTTP/1.1',
>               'D [17/May/2008:09:11:51 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:11:51 +1000] CUPS-Get-Classes',
>               'D [17/May/2008:09:11:51 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:11:51 +1000] cupsdReadClient: 8 POST / HTTP/1.1',
>               'D [17/May/2008:09:11:51 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:11:51 +1000] CUPS-Get-Default',
>               'D [17/May/2008:09:11:51 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:11:51 +1000] cupsdCloseClient: 8',
>               'D [17/May/2008:09:11:51 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:11:51 +1000] cupsdAcceptClient: 8 from localhost (Domain)',
>               'D [17/May/2008:09:11:51 +1000] cupsdReadClient: 8 POST / HTTP/1.1',
>               'D [17/May/2008:09:11:51 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:11:51 +1000] Get-Printer-Attributes ipp://localhost/printers/hl5250dn_duplex',
>               'D [17/May/2008:09:11:51 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:11:51 +1000] cupsdReadClient: 8 GET /printers/hl5250dn_duplex.ppd HTTP/1.1',
>               'D [17/May/2008:09:11:51 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:11:51 +1000] cupsdCloseClient: 8',
>               'D [17/May/2008:09:12:07 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:12:07 +1000] cupsdAcceptClient: 8 from localhost (Domain)',
>               'D [17/May/2008:09:12:07 +1000] Report: clients=1',
>               'D [17/May/2008:09:12:07 +1000] Report: jobs=29',
>               'D [17/May/2008:09:12:07 +1000] Report: jobs-active=0',
>               'D [17/May/2008:09:12:07 +1000] Report: printers=2',
>               'D [17/May/2008:09:12:07 +1000] Report: printers-implicit=0',
>               'D [17/May/2008:09:12:07 +1000] Report: stringpool-string-count=783',
>               'D [17/May/2008:09:12:07 +1000] Report: stringpool-alloc-bytes=7304',
>               'D [17/May/2008:09:12:07 +1000] Report: stringpool-total-bytes=14288',
>               'D [17/May/2008:09:12:07 +1000] cupsdReadClient: 8 POST / HTTP/1.1',
>               'D [17/May/2008:09:12:07 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:12:07 +1000] Get-Notifications /',
>               'D [17/May/2008:09:12:07 +1000] cupsdIsAuthorized: requesting-user-name="rodd"',
>               'D [17/May/2008:09:12:07 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:12:07 +1000] cupsdCloseClient: 8',
>               'D [17/May/2008:09:12:43 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:12:43 +1000] cupsdAcceptClient: 8 from localhost (Domain)',
>               'D [17/May/2008:09:12:43 +1000] cupsdReadClient: 8 POST / HTTP/1.1',
>               'D [17/May/2008:09:12:43 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:12:43 +1000] CUPS-Get-Printers',
>               'D [17/May/2008:09:12:43 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:12:43 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:12:43 +1000] cupsdAcceptClient: 9 from localhost:631 (IPv4)',
>               'D [17/May/2008:09:12:43 +1000] cupsdCloseClient: 8',
>               'D [17/May/2008:09:12:43 +1000] cupsdReadClient: 9 GET /printers/hl5250dn.ppd HTTP/1.1',
>               'D [17/May/2008:09:12:43 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:12:43 +1000] cupsdCloseClient: 9',
>               'D [17/May/2008:09:12:46 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:12:46 +1000] cupsdAcceptClient: 8 from localhost (Domain)',
>               'D [17/May/2008:09:12:46 +1000] cupsdReadClient: 8 POST / HTTP/1.1',
>               'D [17/May/2008:09:12:46 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:12:46 +1000] CUPS-Get-Printers',
>               'D [17/May/2008:09:12:46 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:12:46 +1000] cupsdCloseClient: 8',
>               'D [17/May/2008:09:12:49 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:12:49 +1000] cupsdAcceptClient: 8 from localhost (Domain)',
>               'D [17/May/2008:09:12:49 +1000] cupsdReadClient: 8 POST / HTTP/1.1',
>               'D [17/May/2008:09:12:49 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:12:49 +1000] CUPS-Get-Printers',
>               'D [17/May/2008:09:12:49 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:12:50 +1000] cupsdCloseClient: 8',
>               'D [17/May/2008:09:12:50 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:12:50 +1000] cupsdAcceptClient: 8 from localhost (Domain)',
>               'D [17/May/2008:09:12:50 +1000] cupsdReadClient: 8 POST /printers/hl5250dn HTTP/1.1',
>               'D [17/May/2008:09:12:50 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:12:50 +1000] Print-Job ipp://localhost:631/printers/hl5250dn',
>               'D [17/May/2008:09:12:50 +1000] print_job: auto-typing file...',
>               'D [17/May/2008:09:12:50 +1000] add_job: setting context of job to UNKNOWN SL',
>               'D [17/May/2008:09:12:50 +1000] add_job: requesting-user-name="rodd"',
>               'I [17/May/2008:09:12:50 +1000] [Job 30] Adding start banner page "none".',
>               'I [17/May/2008:09:12:50 +1000] Saving subscriptions.conf...',
>               'I [17/May/2008:09:12:50 +1000] [Job 30] Adding job file of type application/postscript.',
>               'I [17/May/2008:09:12:50 +1000] [Job 30] Adding end banner page "none".',
>               'I [17/May/2008:09:12:50 +1000] [Job 30] Queued on "hl5250dn" by "rodd".',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] hold_until = 0',
>               'D [17/May/2008:09:12:50 +1000] Discarding unused printer-state-changed event...',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] job-sheets=none,none',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] banner_page = 0',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] argv[0]="hl5250dn"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] argv[1]="30"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] argv[2]="rodd"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] argv[3]="evince-print"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] argv[4]="1"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] argv[5]="ScreenLock PageSize=Letter Sleep=PrinterDefault CAPT=Middle BRLanguageLevel=L3 noManualFeed Duplex=DuplexNoTumble TonerSaveMode=Off InputSlot=AutoSelect number-up=1 BRMediaType=Thin job-uuid=urn:uuid:45a658f4-bca9-35d3-77a6-1e5eae14fe65 media=A4"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] argv[6]="/var/spool/cups/d00030-001"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[0]="CUPS_CACHEDIR=/var/cache/cups"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[1]="CUPS_DATADIR=/usr/share/cups"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[6]="CUPS_SERVERROOT=/etc/cups"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[7]="CUPS_STATEDIR=/var/run/cups"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[9]="SERVER_ADMIN=root@localhost.localdomain"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[10]="SOFTWARE=CUPS/1.3.7"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[11]="TMPDIR=/var/spool/cups/tmp"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[12]="USER=root"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[13]="CUPS_SERVER=/var/run/cups/cups.sock"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[14]="CUPS_ENCRYPTION=IfRequested"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[15]="IPP_PORT=631"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[16]="CHARSET=utf-8"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[17]="LANG=en_US.UTF8"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[18]="PPD=/etc/cups/ppd/hl5250dn.ppd"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[19]="RIP_MAX_CACHE=8m"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[20]="CONTENT_TYPE=application/postscript"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[21]="DEVICE_URI=ipp://192.168.1.250/ipp"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[22]="PRINTER=hl5250dn"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] envp[23]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"',
>               'I [17/May/2008:09:12:50 +1000] [Job 30] Started filter /usr/lib/cups/filter/pstops (PID 18874)',
>               'I [17/May/2008:09:12:50 +1000] [Job 30] Started backend /usr/lib/cups/backend/ipp (PID 18875)',
>               'I [17/May/2008:09:12:50 +1000] Saving subscriptions.conf...',
>               'D [17/May/2008:09:12:50 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:12:50 +1000] cupsdCloseClient: 8',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] Page = 612x792; 12,12 to 600,780',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] slow_collate=0, slow_duplex=0, slow_order=0',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] Before copy_comments - %!PS-Adobe-3.0',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] %!PS-Adobe-3.0',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] %%Creator: cairo 1.6.4 (http://cairographics.org)',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] %%CreationDate: Sat May 17 09:12:50 2008',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] %%Pages: 1',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] %%BoundingBox: 0 0 612 792',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] %%DocumentData: Clean7Bit',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] %%LanguageLevel: 2',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] %%EndComments',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] Before copy_prolog - %%BeginProlog',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] Before copy_setup - 11 dict begin',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] Before page loop - %%Page: 1 1',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] Copying page 1...',
>               'I [17/May/2008:09:12:50 +1000] Saving subscriptions.conf...',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] pagew = 587.9, pagel = 767.9',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] PageLeft = 12.0, PageRight = 599.9',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] PageTop = 780.0, PageBottom = 12.1',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] PageWidth = 612.0, PageLength = 792.0',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] Wrote 1 pages...',
>               'D [17/May/2008:09:12:50 +1000] PID 18874 (/usr/lib/cups/filter/pstops) exited with no errors.',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] 1 files to send in job...',
>               'D [17/May/2008:09:12:50 +1000] Discarding unused printer-state-changed event...',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] Connected to 192.168.1.250:631 (IPv4)...',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] Getting supported attributes...',
>               'D [17/May/2008:09:12:50 +1000] Discarding unused printer-state-changed event...',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] document-format-supported (5 values)',
>               'D [17/May/2008:09:12:50 +1000] [0] = "application/octet-stream"',
>               'D [17/May/2008:09:12:50 +1000] [1] = "application/vnd.hp-PCL"',
>               'D [17/May/2008:09:12:50 +1000] [2] = "application/postscript"',
>               'D [17/May/2008:09:12:50 +1000] [3] = "application/vnd.brother-ibm"',
>               'D [17/May/2008:09:12:50 +1000] [4] = "application/vnd.brother-epson"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] printer-uri = "ipp://192.168.1.250:631/ipp"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] requesting-user-name = "rodd"',
>               'D [17/May/2008:09:12:50 +1000] [Job 30] job-name = "evince-print"',
>               'D [17/May/2008:09:12:50 +1000] Discarding unused printer-state-changed event...',
>               'D [17/May/2008:09:12:50 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:12:50 +1000] cupsdAcceptClient: 11 from localhost (Domain)',
>               'D [17/May/2008:09:12:50 +1000] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [17/May/2008:09:12:50 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:12:50 +1000] Get-Jobs ipp://localhost/jobs/',
>               'D [17/May/2008:09:12:50 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:12:50 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:12:50 +1000] cupsdAcceptClient: 12 from localhost (Domain)',
>               'D [17/May/2008:09:12:50 +1000] cupsdReadClient: 12 POST / HTTP/1.1',
>               'D [17/May/2008:09:12:50 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:12:50 +1000] Get-Notifications /',
>               'D [17/May/2008:09:12:50 +1000] cupsdIsAuthorized: requesting-user-name="rodd"',
>               'D [17/May/2008:09:12:50 +1000] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:12:50 +1000] cupsdCloseClient: 11',
>               'D [17/May/2008:09:12:50 +1000] cupsdCloseClient: 12',
>               'D [17/May/2008:09:12:50 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:12:50 +1000] cupsdAcceptClient: 11 from localhost (Domain)',
>               'D [17/May/2008:09:12:50 +1000] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [17/May/2008:09:12:50 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:12:50 +1000] Create-Printer-Subscription /',
>               'D [17/May/2008:09:12:50 +1000] cupsdCreateSubscription(con=0xb95b5b60(11), uri="/")',
>               'D [17/May/2008:09:12:50 +1000] pullmethod="ippget"',
>               'D [17/May/2008:09:12:50 +1000] notify-lease-duration=86400',
>               'D [17/May/2008:09:12:50 +1000] notify-time-interval=0',
>               'D [17/May/2008:09:12:50 +1000] cupsdAddSubscription(mask=1790f, dest=(nil)(), job=(nil)(0), uri="(null)")',
>               'D [17/May/2008:09:12:50 +1000] Added subscription 11 for server',
>               'I [17/May/2008:09:12:50 +1000] Saving subscriptions.conf...',
>               'D [17/May/2008:09:12:50 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:12:50 +1000] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [17/May/2008:09:12:50 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:12:50 +1000] Get-Jobs ipp://localhost/jobs/',
>               'D [17/May/2008:09:12:50 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:12:50 +1000] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [17/May/2008:09:12:50 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:12:50 +1000] CUPS-Get-Printers',
>               'D [17/May/2008:09:12:50 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:12:50 +1000] cupsdCloseClient: 11',
>               'N [17/May/2008:09:12:51 +1000] [Job 30] Print file accepted - job ID 5008.',
>               'I [17/May/2008:09:12:51 +1000] Saving subscriptions.conf...',
>               'I [17/May/2008:09:12:51 +1000] Saving subscriptions.conf...',
>               'D [17/May/2008:09:12:51 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:12:51 +1000] cupsdAcceptClient: 11 from localhost (Domain)',
>               'D [17/May/2008:09:12:51 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:12:51 +1000] cupsdAcceptClient: 12 from localhost (Domain)',
>               'D [17/May/2008:09:12:51 +1000] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [17/May/2008:09:12:51 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:12:51 +1000] Get-Notifications /',
>               'D [17/May/2008:09:12:51 +1000] cupsdIsAuthorized: requesting-user-name="rodd"',
>               'D [17/May/2008:09:12:51 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:12:51 +1000] cupsdReadClient: 12 POST / HTTP/1.1',
>               'D [17/May/2008:09:12:51 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:12:51 +1000] Get-Notifications /',
>               'D [17/May/2008:09:12:51 +1000] cupsdIsAuthorized: requesting-user-name="rodd"',
>               'D [17/May/2008:09:12:51 +1000] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:12:51 +1000] cupsdCloseClient: 11',
>               'D [17/May/2008:09:12:51 +1000] cupsdCloseClient: 12',
>               'I [17/May/2008:09:12:53 +1000] Saving subscriptions.conf...',
>               'I [17/May/2008:09:12:53 +1000] Saving subscriptions.conf...',
>               'D [17/May/2008:09:12:53 +1000] PID 18875 (/usr/lib/cups/backend/ipp) exited with no errors.',
>               'D [17/May/2008:09:12:53 +1000] [Job 30] File 0 is complete.',
>               'I [17/May/2008:09:12:53 +1000] [Job 30] Completed successfully.',
>               'I [17/May/2008:09:12:53 +1000] Saving subscriptions.conf...',
>               'I [17/May/2008:09:12:53 +1000] Saving subscriptions.conf...',
>               'D [17/May/2008:09:12:53 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:12:53 +1000] cupsdAcceptClient: 11 from localhost (Domain)',
>               'D [17/May/2008:09:12:53 +1000] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [17/May/2008:09:12:53 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:12:53 +1000] Get-Notifications /',
>               'D [17/May/2008:09:12:53 +1000] cupsdIsAuthorized: requesting-user-name="rodd"',
>               'D [17/May/2008:09:12:53 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:12:53 +1000] cupsdCloseClient: 11',
>               'D [17/May/2008:09:12:53 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:12:53 +1000] cupsdAcceptClient: 11 from localhost (Domain)',
>               'D [17/May/2008:09:12:53 +1000] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [17/May/2008:09:12:53 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:12:53 +1000] Get-Notifications /',
>               'D [17/May/2008:09:12:53 +1000] cupsdIsAuthorized: requesting-user-name="rodd"',
>               'D [17/May/2008:09:12:53 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:12:53 +1000] cupsdCloseClient: 11',
>               'D [17/May/2008:09:12:54 +1000] [Job 30] Unloading...',
>               'D [17/May/2008:09:13:28 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:13:28 +1000] cupsdAcceptClient: 11 from localhost (Domain)',
>               'D [17/May/2008:09:13:28 +1000] Report: clients=1',
>               'D [17/May/2008:09:13:28 +1000] Report: jobs=30',
>               'D [17/May/2008:09:13:28 +1000] Report: jobs-active=0',
>               'D [17/May/2008:09:13:28 +1000] Report: printers=2',
>               'D [17/May/2008:09:13:28 +1000] Report: printers-implicit=0',
>               'D [17/May/2008:09:13:28 +1000] Report: stringpool-string-count=1106',
>               'D [17/May/2008:09:13:28 +1000] Report: stringpool-alloc-bytes=8136',
>               'D [17/May/2008:09:13:28 +1000] Report: stringpool-total-bytes=21272',
>               'D [17/May/2008:09:13:28 +1000] cupsdCloseClient: 11',
>               'D [17/May/2008:09:13:28 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:13:28 +1000] cupsdAcceptClient: 11 from localhost (Domain)',
>               'D [17/May/2008:09:13:28 +1000] cupsdReadClient: 11 POST / HTTP/1.1',
>               'D [17/May/2008:09:13:28 +1000] cupsdAuthorize: No authentication data provided.',
>               'D [17/May/2008:09:13:28 +1000] Cancel-Subscription /',
>               'D [17/May/2008:09:13:28 +1000] cupsdIsAuthorized: requesting-user-name="rodd"',
>               'I [17/May/2008:09:13:28 +1000] Saving subscriptions.conf...',
>               'D [17/May/2008:09:13:28 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)',
>               'D [17/May/2008:09:13:28 +1000] cupsdAcceptClient: skipping getpeercon()',
>               'D [17/May/2008:09:13:28 +1000] cupsdAcceptClient: 12 from localhost (Domain)',
>               'D [17/May/2008:09:13:28 +1000] cupsdCloseClient: 11',
>               'D [17/May/2008:09:13:28 +1000] cupsdReadClient: 12 GET /admin/log/error_log HTTP/1.1',
>               'D [17/May/2008:09:13:28 +1000] cupsdAuthorize: No authentication data provided.'],
> 'error_log_debug_logging_unset': True}

Actions: View

Attachments on bug 446358: 305776 | 305777