My Error Logs for the MP600 Ubuntu9.10 are as follows: david@david-desktop:~$ lpinfo -v direct usb://Canon/MP600 network ipp network beh direct hp network http serial serial:/dev/ttyS0?baud=115200 network socket network lpd direct cnij_usb:/dev/usb/lp0 direct scsi network smb direct parallel:/dev/lp0 direct hpfax Page 1 (Scheduler not running?): {'cups_connection_failure': False} Page 2 (Choose printer): {'cups_dest': , 'cups_instance': None, 'cups_queue': 'MP600', 'cups_queue_listed': True} Page 3 (Check printer sanity): {'cups_device_uri_scheme': u'usb', 'cups_printer_dict': {'device-uri': u'usb://Canon/MP600', 'printer-info': u'Canon MP600', 'printer-is-shared': False, 'printer-location': u'david-desktop', 'printer-make-and-model': u'Canon PIXMA iP4200 - CUPS+Gutenprint (OpenPrinting LSB 3.2) v5.2.5 Simplified', 'printer-state': 3, 'printer-state-message': u'/usr/lib/cups/filter/pstopdf failed', 'printer-state-reasons': [u'none'], 'printer-type': 10661916, 'printer-uri-supported': u'ipp://localhost:631/printers/MP600'}, 'cups_printer_remote': False, 'is_cups_class': False, 'local_cups_queue_attributes': {'auth-info-required': u'none', 'charset-configured': u'utf-8', 'charset-supported': [u'us-ascii', u'utf-8'], 'color-supported': True, 'compression-supported': [u'none', u'gzip'], 'copies-default': 1, 'copies-supported': (1, 9999), 'cups-version': u'1.4.1', 'device-uri': u'usb://Canon/MP600', 'document-format-default': u'application/octet-stream', 'document-format-supported': [u'application/octet-stream', u'application/openofficeps', u'application/pdf', u'application/postscript', u'application/vnd.cups-banner', u'application/vnd.cups-pdf', u'application/vnd.cups-postscript', u'application/vnd.cups-raster', u'application/vnd.cups-raw', u'application/vnd.hp-hpgl', u'application/x-cshell', u'application/x-csource', u'application/x-perl', u'application/x-shell', u'image/gif', u'image/jpeg', u'image/png', u'image/tiff', u'image/x-bitmap', u'image/x-photocd', u'image/x-portable-anymap', u'image/x-portable-bitmap', u'image/x-portable-graymap', u'image/x-portable-pixmap', u'image/x-sgi-rgb', u'image/x-sun-raster', u'image/x-xbitmap', u'image/x-xpixmap', u'image/x-xwindowdump', u'text/css', u'text/html', u'text/plain'], 'finishings-default': 3, 'finishings-supported': [3], 'generated-natural-language-supported': [u'en_US'], 'ipp-versions-supported': [u'1.0', u'1.1', u'2.0', u'2.1'], 'job-hold-until-default': u'no-hold', 'job-hold-until-supported': [u'no-hold', u'indefinite', u'day-time', u'evening', u'night', u'second-shift', u'third-shift', u'weekend'], 'job-k-limit': 0, 'job-page-limit': 0, 'job-priority-default': 50, 'job-priority-supported': [100], 'job-quota-period': 0, 'job-settable-attributes-supported': [u'copies', u'finishings', u'job-hold-until', u'job-priority', u'media', u'multiple-document-handling', u'number-up', u'orientation-requested', u'page-ranges', u'print-quality', u'printer-resolution', u'sides'], 'job-sheets-default': (u'none', u'none'), 'job-sheets-supported': [u'none', u'classified', u'confidential', u'secret', u'standard', u'topsecret', u'unclassified'], 'marker-change-time': 0, 'media-col-supported': [u'media-color', u'media-key', u'media-size', u'media-type'], 'media-default': u'na_letter_8.5x11in', 'media-supported': [u'na_letter_8.5x11in', u'na_legal_8.5x14in', u'na_ledger_11x17in', u'na_executive_7.25x10.5in', u'jpn_hagaki_100x148mm', u'adobe_CD5Inch_116.064x116.064mm', u'adobe_CD3Inch_64.9111x64.9111mm', u'adobe_CDCustom_119.944x119.944mm', u'na_index-4x6_4x6in', u'na_invoice_5.5x8.5in', u'iso_a3_297x420mm', u'iso_a4_210x297mm', u'custom_min_0.352778x0.352778mm', u'custom_max_11.6944x17in'], 'multiple-document-handling-supported': [u'separate-documents-uncollated-copies', u'separate-documents-collated-copies'], 'multiple-document-jobs-supported': True, 'multiple-operation-time-out': 300, 'natural-language-configured': u'en_US', 'notify-attributes-supported': [u'printer-state-change-time', u'notify-lease-expiration-time', u'notify-subscriber-user-name'], 'notify-events-default': [u'job-completed'], 'notify-events-supported': [u'job-completed', u'job-config-changed', u'job-created', u'job-progress', u'job-state-changed', u'job-stopped', u'printer-added', u'printer-changed', u'printer-config-changed', u'printer-deleted', u'printer-finishings-changed', u'printer-media-changed', u'printer-modified', u'printer-restarted', u'printer-shutdown', u'printer-state-changed', u'printer-stopped', u'server-audit', u'server-restarted', u'server-started', u'server-stopped'], 'notify-lease-duration-default': 86400, 'notify-lease-duration-supported': (0, 2147483647), 'notify-max-events-supported': [100], 'notify-pull-method-supported': [u'ippget'], 'notify-schemes-supported': [u'mailto', u'rss'], 'number-up-default': 1, 'number-up-supported': [1, 2, 4, 6, 9, 16], 'operations-supported': [2, 4, 5, 6, 8, 9, 10, 11, 12, 13, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 34, 35, 37, 38, 16385, 16386, 16387, 16388, 16389, 16390, 16391, 16392, 16393, 16394, 16395, 16396, 16397, 16398, 16399, 16423], 'orientation-requested-default': None, 'orientation-requested-supported': [3, 4, 5, 6], 'page-ranges-supported': True, 'pdl-override-supported': [u'not-attempted'], 'port-monitor': u'none', 'port-monitor-supported': [u'none'], 'printer-commands': [u'AutoConfigure', u'Clean', u'PrintSelfTestPage'], 'printer-current-time': '(IPP_TAG_DATE)', 'printer-error-policy': u'retry-job', 'printer-error-policy-supported': [u'abort-job', u'retry-current-job', u'retry-job', u'stop-printer'], 'printer-info': u'Canon MP600', 'printer-is-accepting-jobs': True, 'printer-is-shared': False, 'printer-location': u'david-desktop', 'printer-make-and-model': u'Canon PIXMA iP4200 - CUPS+Gutenprint (OpenPrinting LSB 3.2) v5.2.5 Simplified', 'printer-more-info': u'http://localhost:631/printers/MP600', 'printer-name': u'MP600', 'printer-op-policy': u'default', 'printer-op-policy-supported': [u'authenticated', u'default'], 'printer-settable-attributes-supported': [u'printer-info', u'printer-location'], 'printer-state': 3, 'printer-state-change-time': 1271146671, 'printer-state-message': u'/usr/lib/cups/filter/pstopdf failed', 'printer-state-reasons': [u'none'], 'printer-type': 10661916, 'printer-up-time': 1271146775, 'printer-uri-supported': [u'ipp://localhost:631/printers/MP600'], 'queued-job-count': 1, 'server-is-sharing-printers': True, 'sides-default': u'one-sided', 'sides-supported': [u'one-sided', u'two-sided-long-edge', u'two-sided-short-edge'], 'uri-authentication-supported': [u'requesting-user-name'], 'uri-security-supported': [u'none']}} Page 4 (Check PPD sanity): {'cups_printer_ppd_defaults': {u'C0L0': {u'StpInkType': u'CMYK'}, u'C1L0': {u'StpBrightness': u'None', u'StpColorCorrection': u'None', u'StpContrast': u'None', u'StpImageType': u'TextGraphics', u'StpSaturation': u'None'}, u'General': {u'ColorModel': u'RGB', u'Duplex': u'None', u'InputSlot': u'SelectKey', u'MediaType': u'Plain', u'PageRegion': u'Letter', u'PageSize': u'Letter', u'Resolution': u'601x600dpi', u'StpQuality': u'Standard', u'StpiShrinkOutput': u'Shrink'}}, 'cups_printer_ppd_valid': True, 'missing_pkgs_and_exes': ([], [])} Page 5 (Local or remote?): {'printer_is_remote': False} Page 6 (Choose device): {'cups_device_dict': {'device-class': u'direct', 'device-id': u'MFG:Canon;CMD:BJL,BJRaster3,BSCCe;SOJ:TXT01;MDL:MP600;CLS:PRINTER;DES:Canon MP600;VER:1.04;STA:10;FSI:03;HRI:OTH;MSI:DAT,E3;', 'device-info': u'Canon MP600', 'device-make-and-model': u'Canon MP600'}} Page 7 (Error log checkpoint): {'cups_server_settings': {'BrowseLocalProtocols': 'CUPS dnssd', 'BrowseRemoteProtocols': '', 'DefaultAuthType': 'Basic', 'LogDebugHistory': '999999', 'MaxLogSize': '0', 'SystemGroup': 'lpadmin', '_debug_logging': '1', '_remote_admin': '0', '_remote_any': '0', '_remote_printers': '0', '_share_printers': '1', '_user_cancel_any': '0'}, 'error_log_checkpoint': 344811L} Page 8 (Print test page): {'test_page_attempted': '13/Apr/2010:20:31:37 +0000', 'test_page_job_status': [(True, 369, 'MP600', 'revised Pew Sheet', 'Stopped', {'PageSize': u'A4', 'attributes-charset': u'utf-8', 'attributes-natural-language': u'en-nz', 'copies': 1, 'document-count': 1, 'document-format': u'application/postscript', 'finishings': 3, 'job-hold-until': u'no-hold', 'job-id': 369, 'job-k-octets': 45, 'job-media-progress': 0, 'job-media-sheets-completed': 0, 'job-more-info': u'ipp://localhost:631/jobs/369', 'job-name': u'revised Pew Sheet', 'job-originating-host-name': u'localhost', 'job-originating-user-name': u'david', 'job-preserved': True, 'job-printer-state-message': u'/usr/lib/cups/filter/pstopdf failed', 'job-printer-state-reasons': [u'none'], 'job-printer-up-time': 1271147514, 'job-printer-uri': u'ipp://david-desktop:631/printers/MP600', 'job-priority': 50, 'job-sheets': [u'none', u'none'], 'job-state': 6, 'job-state-reasons': u'job-stopped', 'job-uri': u'ipp://localhost:631/jobs/369', 'job-uuid': u'urn:uuid:3647fff4-d3d5-3492-6d4c-7ed3c3d15db9', 'media': u'na_letter_8.5x11in', 'number-up': 1, 'printer-uri': u'ipp://localhost:631/printers/MP600', 'sides': u'one-sided', 'time-at-completed': None, 'time-at-creation': 1271146260, 'time-at-processing': 1271146671}), (True, 370, 'MP600', 'Test Page', 'Stopped', {'attributes-charset': u'utf-8', 'attributes-natural-language': u'en-nz', 'document-count': 1, 'document-format': u'application/vnd.cups-banner', 'job-hold-until': u'no-hold', 'job-id': 370, 'job-k-octets': 1, 'job-media-progress': 0, 'job-media-sheets-completed': 0, 'job-more-info': u'ipp://localhost:631/jobs/370', 'job-name': u'Test Page', 'job-originating-host-name': u'localhost', 'job-originating-user-name': u'david', 'job-preserved': True, 'job-printer-state-message': u'/usr/lib/cups/filter/bannertops failed', 'job-printer-state-reasons': [u'none'], 'job-printer-up-time': 1271147514, 'job-printer-uri': u'ipp://david-desktop:631/printers/MP600', 'job-priority': 50, 'job-sheets': [u'none', u'none'], 'job-state': 6, 'job-state-reasons': u'job-stopped', 'job-uri': u'ipp://localhost:631/jobs/370', 'job-uuid': u'urn:uuid:0d28c512-19ec-35a5-7cee-8f7bd12b02d9', 'printer-uri': u'ipp://localhost/printers/MP600', 'time-at-completed': None, 'time-at-creation': 1271147497, 'time-at-processing': 1271147497})], 'test_page_successful': False} Page 9 (Error log fetch): {'error_log': ['D [13/Apr/2010:20:23:15 +1200] cupsdSetBusyState: Not busy', 'D [13/Apr/2010:20:23:20 +1200] cupsdReadClient: 14 POST / HTTP/1.1', 'D [13/Apr/2010:20:23:20 +1200] cupsdSetBusyState: Active clients', 'D [13/Apr/2010:20:23:20 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:23:20 +1200] cupsdReadClient: 14 1.1 Get-Jobs 1', 'D [13/Apr/2010:20:23:20 +1200] Get-Jobs ipp://localhost/printers/', 'D [13/Apr/2010:20:23:20 +1200] [Job 369] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost', 'D [13/Apr/2010:20:23:20 +1200] cupsdSetBusyState: Not busy', 'D [13/Apr/2010:20:23:20 +1200] cupsdReadClient: 14 POST / HTTP/1.1', 'D [13/Apr/2010:20:23:20 +1200] cupsdSetBusyState: Active clients', 'D [13/Apr/2010:20:23:20 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:23:20 +1200] cupsdReadClient: 14 1.1 Get-Jobs 1', 'D [13/Apr/2010:20:23:20 +1200] Get-Jobs ipp://localhost/printers/', 'D [13/Apr/2010:20:23:20 +1200] [Job 334] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 335] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 336] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 337] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 338] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 339] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 341] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 342] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 343] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 349] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 350] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 351] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 352] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 353] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 354] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 355] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 360] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 361] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 362] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 363] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 364] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 365] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 366] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 367] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] [Job 368] Loading attributes...', 'D [13/Apr/2010:20:23:20 +1200] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost', 'D [13/Apr/2010:20:23:20 +1200] cupsdSetBusyState: Not busy', 'D [13/Apr/2010:20:23:23 +1200] cupsdReadClient: 14 POST / HTTP/1.1', 'D [13/Apr/2010:20:23:23 +1200] cupsdSetBusyState: Active clients', 'D [13/Apr/2010:20:23:23 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:23:23 +1200] cupsdReadClient: 14 1.1 Create-Printer-Subscription 1', 'D [13/Apr/2010:20:23:23 +1200] Create-Printer-Subscription /', 'D [13/Apr/2010:20:23:23 +1200] cupsdCreateSubscription(con=0x21d36b10(14), uri="/")', 'D [13/Apr/2010:20:23:23 +1200] pullmethod="ippget"', 'D [13/Apr/2010:20:23:23 +1200] notify-lease-duration=86400', 'D [13/Apr/2010:20:23:23 +1200] notify-time-interval=0', 'D [13/Apr/2010:20:23:23 +1200] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)")', 'D [13/Apr/2010:20:23:23 +1200] Added subscription 156 for server', 'D [13/Apr/2010:20:23:23 +1200] cupsdMarkDirty(-----S)', 'D [13/Apr/2010:20:23:23 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:23:23 +1200] Returning IPP successful-ok for Create-Printer-Subscription (/) from localhost', 'D [13/Apr/2010:20:23:23 +1200] cupsdSetBusyState: Dirty files', 'I [13/Apr/2010:20:23:54 +1200] Saving subscriptions.conf...', 'D [13/Apr/2010:20:23:54 +1200] cupsdSetBusyState: Not busy', 'D [13/Apr/2010:20:23:57 +1200] cupsdReadClient: 14 POST / HTTP/1.1', 'D [13/Apr/2010:20:23:57 +1200] cupsdSetBusyState: Active clients', 'D [13/Apr/2010:20:23:57 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:23:57 +1200] cupsdReadClient: 14 1.1 Get-Notifications 1', 'D [13/Apr/2010:20:23:57 +1200] Get-Notifications /', 'D [13/Apr/2010:20:23:57 +1200] cupsdIsAuthorized: username="david"', 'D [13/Apr/2010:20:23:57 +1200] Returning IPP successful-ok for Get-Notifications (/) from localhost', 'D [13/Apr/2010:20:23:57 +1200] cupsdSetBusyState: Not busy', 'D [13/Apr/2010:20:24:45 +1200] cupsdAcceptClient: 16 from localhost (Domain)', 'D [13/Apr/2010:20:24:45 +1200] [Job 334] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 335] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 336] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 337] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 338] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 339] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 341] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 342] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 343] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 349] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 350] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 351] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 352] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 353] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 354] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 355] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 360] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 361] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 362] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 363] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 364] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 365] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 366] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 367] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 368] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] [Job 369] Unloading...', 'D [13/Apr/2010:20:24:45 +1200] Report: clients=4', 'D [13/Apr/2010:20:24:45 +1200] Report: jobs=26', 'D [13/Apr/2010:20:24:45 +1200] Report: jobs-active=1', 'D [13/Apr/2010:20:24:45 +1200] Report: printers=2', 'D [13/Apr/2010:20:24:45 +1200] Report: printers-implicit=0', 'D [13/Apr/2010:20:24:45 +1200] Report: stringpool-string-count=464', 'D [13/Apr/2010:20:24:45 +1200] Report: stringpool-alloc-bytes=7280', 'D [13/Apr/2010:20:24:45 +1200] Report: stringpool-total-bytes=9752', 'D [13/Apr/2010:20:24:45 +1200] cupsdReadClient: 16 POST / HTTP/1.1', 'D [13/Apr/2010:20:24:45 +1200] cupsdSetBusyState: Active clients', 'D [13/Apr/2010:20:24:45 +1200] cupsdAuthorize: No authentication data provided.', 'D [13/Apr/2010:20:24:45 +1200] cupsdReadClient: 16 1.1 CUPS-Get-Devices 1', 'D [13/Apr/2010:20:24:45 +1200] CUPS-Get-Devices', 'D [13/Apr/2010:20:24:45 +1200] cupsdIsAuthorized: username=""', 'D [13/Apr/2010:20:24:45 +1200] Returning HTTP Unauthorized for CUPS-Get-Devices (no URI) from localhost', 'D [13/Apr/2010:20:24:45 +1200] cupsdSendHeader: 16 WWW-Authenticate: Basic realm="CUPS", trc="y"', 'D [13/Apr/2010:20:24:45 +1200] cupsdReadClient: 16 WAITING Closing on EOF', 'D [13/Apr/2010:20:24:45 +1200] cupsdCloseClient: 16', 'D [13/Apr/2010:20:24:45 +1200] cupsdSetBusyState: Not busy', 'D [13/Apr/2010:20:24:45 +1200] cupsdAcceptClient: 16 from localhost (Domain)', 'D [13/Apr/2010:20:24:45 +1200] cupsdAcceptClient: 17 from localhost (Domain)', 'D [13/Apr/2010:20:24:45 +1200] cupsdReadClient: 16 WAITING Closing on EOF', 'D [13/Apr/2010:20:24:45 +1200] cupsdCloseClient: 16', 'D [13/Apr/2010:20:24:45 +1200] cupsdReadClient: 17 POST / HTTP/1.1', 'D [13/Apr/2010:20:24:45 +1200] cupsdSetBusyState: Active clients', 'D [13/Apr/2010:20:24:45 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:24:45 +1200] cupsdReadClient: 17 1.1 CUPS-Get-Devices 1', 'D [13/Apr/2010:20:24:45 +1200] CUPS-Get-Devices', 'D [13/Apr/2010:20:24:45 +1200] cupsdIsAuthorized: username="david"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] argv[0] = "/usr/lib/cups/daemon/cups-deviced"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] argv[1] = "1"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] argv[2] = "0"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] argv[3] = "15"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] argv[4] = "7"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] argv[5] = "requested-attributes=all"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[10] = "SERVER_ADMIN=root@david-desktop"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.1"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[13] = "TZ=Pacific/Auckland"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[14] = "USER=root"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[15] = "CUPS_SERVER=/var/run/cups/cups.sock"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[17] = "IPP_PORT=631"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[18] = "CUPSD_AUTH_TYPE=PeerCred"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[19] = "LANG=en_NZ.UTF8"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[20] = "REDIRECT_STATUS=1"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[22] = "SERVER_NAME=localhost"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[23] = "SERVER_PORT=631"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[24] = "REMOTE_ADDR="', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[25] = "REMOTE_HOST=localhost"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[26] = "SCRIPT_NAME=/"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[28] = "REMOTE_USER=david"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[30] = "HTTP_USER_AGENT=CUPS/1.4.1"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[31] = "REQUEST_METHOD=POST"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[32] = "CONTENT_LENGTH=75"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] envp[33] = "CONTENT_TYPE=application/ipp"', 'D [13/Apr/2010:20:24:45 +1200] [CGI] Started /usr/lib/cups/daemon/cups-deviced (PID 2577)', 'I [13/Apr/2010:20:24:45 +1200] Started "/usr/lib/cups/daemon/cups-deviced" (pid=2577)', 'D [13/Apr/2010:20:24:45 +1200] cupsdSendCommand: 17 file=16', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Started backend /usr/lib/cups/backend/usb (PID 2578)', 'D [13/Apr/2010:20:24:45 +1200] [CGI] list_devices_libusb', 'D [13/Apr/2010:20:24:45 +1200] [CGI] usb_find_busses=2', 'D [13/Apr/2010:20:24:45 +1200] [CGI] usb_find_devices=4', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Started backend /usr/lib/cups/backend/bluetooth (PID 2579)', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Started backend /usr/lib/cups/backend/ipp (PID 2581)', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Started backend /usr/lib/cups/backend/beh (PID 2582)', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Started backend /usr/lib/cups/backend/hp (PID 2583)', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Started backend /usr/lib/cups/backend/hpfax (PID 2584)', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Started backend /usr/lib/cups/backend/http (PID 2585)', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Started backend /usr/lib/cups/backend/snmp (PID 2586)', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Started backend /usr/lib/cups/backend/serial (PID 2587)', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Started backend /usr/lib/cups/backend/socket (PID 2588)', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Started backend /usr/lib/cups/backend/lpd (PID 2589)', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Started backend /usr/lib/cups/backend/dnssd (PID 2590)', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Started backend /usr/lib/cups/backend/cnij_usb (PID 2591)', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Started backend /usr/lib/cups/backend/scsi (PID 2592)', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Started backend /usr/lib/cups/backend/parallel (PID 2593)', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Started backend /usr/lib/cups/backend/smb (PID 2594)', 'D [13/Apr/2010:20:24:45 +1200] [CGI] Flushed attributes...', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Found device "usb://Canon/MP600"...', 'D [13/Apr/2010:20:24:45 +1200] [CGI] Flushed attributes...', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Found device "ipp"...', 'D [13/Apr/2010:20:24:45 +1200] [CGI] Flushed attributes...', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Found device "beh"...', 'D [13/Apr/2010:20:24:45 +1200] [CGI] Flushed attributes...', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Found device "hp"...', 'D [13/Apr/2010:20:24:45 +1200] [CGI] Flushed attributes...', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Found device "http"...', 'D [13/Apr/2010:20:24:45 +1200] [CGI] Flushed attributes...', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Found device "serial:/dev/ttyS0?baud=115200"...', 'D [13/Apr/2010:20:24:45 +1200] Script header: Content-Type: application/ipp', 'D [13/Apr/2010:20:24:45 +1200] Script header:', 'W [13/Apr/2010:20:24:45 +1200] [CGI] Unhandled message: interface=org.freedesktop.DBus.Introspectable, path=/, member=Introspect', 'D [13/Apr/2010:20:24:45 +1200] [CGI] Flushed attributes...', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Found device "socket"...', 'D [13/Apr/2010:20:24:45 +1200] [CGI] Flushed attributes...', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Found device "lpd"...', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] PID 2578 (usb) exited with no errors.', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] PID 2579 (bluetooth) exited with no errors.', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] PID 2581 (ipp) exited with no errors.', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] PID 2582 (beh) exited with no errors.', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] PID 2583 (hp) exited with no errors.', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] PID 2585 (http) exited with no errors.', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] PID 2587 (serial) exited with no errors.', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] PID 2588 (socket) exited with no errors.', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] PID 2589 (lpd) exited with no errors.', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] PID 2591 (cnij_usb) exited with no errors.', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] PID 2592 (scsi) exited with no errors.', 'D [13/Apr/2010:20:24:45 +1200] [CGI] Flushed attributes...', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Found device "cnij_usb:/dev/usb/lp0"...', 'D [13/Apr/2010:20:24:45 +1200] [CGI] Flushed attributes...', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Found device "scsi"...', 'D [13/Apr/2010:20:24:45 +1200] [CGI] Flushed attributes...', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Found device "smb"...', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] PID 2594 (smb) exited with no errors.', 'D [13/Apr/2010:20:24:45 +1200] [CGI] Flushed attributes...', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Found device "parallel:/dev/lp0"...', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] PID 2593 (parallel) exited with no errors.', 'D [13/Apr/2010:20:24:45 +1200] [CGI] Flushed attributes...', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] Found device "hpfax"...', 'D [13/Apr/2010:20:24:45 +1200] [cups-deviced] PID 2584 (hpfax) exited with no errors.', 'D [13/Apr/2010:20:24:47 +1200] [cups-deviced] PID 2586 (snmp) exited with no errors.', 'D [13/Apr/2010:20:25:00 +1200] PID 2577 (/usr/lib/cups/daemon/cups-deviced) exited with no errors.', 'D [13/Apr/2010:20:25:00 +1200] cupsdSetBusyState: Not busy', 'D [13/Apr/2010:20:25:00 +1200] cupsdReadClient: 17 WAITING Closing on EOF', 'D [13/Apr/2010:20:25:00 +1200] cupsdCloseClient: 17', 'D [13/Apr/2010:20:25:01 +1200] cupsdReadClient: 14 POST / HTTP/1.1', 'D [13/Apr/2010:20:25:01 +1200] cupsdSetBusyState: Active clients', 'D [13/Apr/2010:20:25:01 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:25:01 +1200] cupsdReadClient: 14 1.1 Get-Notifications 1', 'D [13/Apr/2010:20:25:01 +1200] Get-Notifications /', 'D [13/Apr/2010:20:25:01 +1200] cupsdIsAuthorized: username="david"', 'D [13/Apr/2010:20:25:01 +1200] Returning IPP successful-ok for Get-Notifications (/) from localhost', 'D [13/Apr/2010:20:25:01 +1200] cupsdSetBusyState: Not busy', 'D [13/Apr/2010:20:25:54 +1200] cupsdAcceptClient: 16 from localhost:631 (IPv4)', 'D [13/Apr/2010:20:25:54 +1200] Report: clients=4', 'D [13/Apr/2010:20:25:54 +1200] Report: jobs=26', 'D [13/Apr/2010:20:25:54 +1200] Report: jobs-active=1', 'D [13/Apr/2010:20:25:54 +1200] Report: printers=2', 'D [13/Apr/2010:20:25:54 +1200] Report: printers-implicit=0', 'D [13/Apr/2010:20:25:54 +1200] Report: stringpool-string-count=464', 'D [13/Apr/2010:20:25:54 +1200] Report: stringpool-alloc-bytes=7280', 'D [13/Apr/2010:20:25:54 +1200] Report: stringpool-total-bytes=9752', 'D [13/Apr/2010:20:25:54 +1200] cupsdReadClient: 16 GET /admin/ HTTP/1.1', 'D [13/Apr/2010:20:25:54 +1200] cupsdSetBusyState: Active clients', 'D [13/Apr/2010:20:25:54 +1200] cupsdAuthorize: No authentication data provided.', 'D [13/Apr/2010:20:25:54 +1200] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[10] = "SERVER_ADMIN=root@david-desktop"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.1"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[13] = "TZ=Pacific/Auckland"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[14] = "USER=root"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[15] = "CUPS_SERVER=/var/run/cups/cups.sock"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[17] = "IPP_PORT=631"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[18] = "LANG=en_US.UTF8"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[19] = "REDIRECT_STATUS=1"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[21] = "SERVER_NAME=localhost"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[22] = "SERVER_PORT=631"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[23] = "REMOTE_ADDR=127.0.0.1"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[24] = "REMOTE_HOST=localhost"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[25] = "SCRIPT_NAME=/admin/"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/admin/"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[28] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.9.1.9) Gecko/20100401 Ubuntu/9.10 (karmic) Firefox/3.5.9"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[29] = "REQUEST_METHOD=GET"', 'D [13/Apr/2010:20:25:54 +1200] [CGI] envp[30] = "QUERY_STRING="', 'D [13/Apr/2010:20:25:54 +1200] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi (PID 2610)', 'I [13/Apr/2010:20:25:54 +1200] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=2610)', 'D [13/Apr/2010:20:25:54 +1200] cupsdSendCommand: 16 file=17', 'D [13/Apr/2010:20:25:54 +1200] [CGI] admin.cgi started...', 'D [13/Apr/2010:20:25:54 +1200] cupsdAcceptClient: 18 from localhost (Domain)', 'D [13/Apr/2010:20:25:54 +1200] [CGI] http=0x2086d368', 'D [13/Apr/2010:20:25:54 +1200] [CGI] No form data, showing main menu...', 'D [13/Apr/2010:20:25:54 +1200] [CGI] /usr/share/cups/drivers/pscript5.dll: No such file or directory', 'D [13/Apr/2010:20:25:54 +1200] cupsdReadClient: 18 POST / HTTP/1.1', 'D [13/Apr/2010:20:25:54 +1200] cupsdAuthorize: No authentication data provided.', 'D [13/Apr/2010:20:25:54 +1200] cupsdReadClient: 18 1.1 Get-Subscriptions 1', 'D [13/Apr/2010:20:25:54 +1200] Get-Subscriptions ipp://localhost/', 'D [13/Apr/2010:20:25:54 +1200] Returning IPP successful-ok for Get-Subscriptions (ipp://localhost/) from localhost', 'D [13/Apr/2010:20:25:54 +1200] [CGI] lang="en_US.UTF8", locale="/en_US"...', 'D [13/Apr/2010:20:25:54 +1200] Script header: Content-Type: text/html;charset=utf-8', 'D [13/Apr/2010:20:25:54 +1200] Script header:', 'D [13/Apr/2010:20:25:54 +1200] [CGI] lang="en_US.UTF8", locale="/en_US"...', 'D [13/Apr/2010:20:25:54 +1200] [CGI] lang="en_US.UTF8", locale="/en_US"...', 'D [13/Apr/2010:20:25:54 +1200] cupsdReadClient: 18 WAITING Closing on EOF', 'D [13/Apr/2010:20:25:54 +1200] cupsdCloseClient: 18', 'D [13/Apr/2010:20:25:54 +1200] PID 2610 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.', 'D [13/Apr/2010:20:25:54 +1200] cupsdSetBusyState: Not busy', 'D [13/Apr/2010:20:26:20 +1200] cupsdReadClient: 16 POST /admin/ HTTP/1.1', 'D [13/Apr/2010:20:26:20 +1200] cupsdSetBusyState: Active clients', 'D [13/Apr/2010:20:26:20 +1200] cupsdAuthorize: No authentication data provided.', 'D [13/Apr/2010:20:26:20 +1200] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[10] = "SERVER_ADMIN=root@david-desktop"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.1"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[13] = "TZ=Pacific/Auckland"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[14] = "USER=root"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[15] = "CUPS_SERVER=/var/run/cups/cups.sock"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[17] = "IPP_PORT=631"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[18] = "LANG=en_US.UTF8"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[19] = "REDIRECT_STATUS=1"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[21] = "SERVER_NAME=localhost"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[22] = "SERVER_PORT=631"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[23] = "REMOTE_ADDR=127.0.0.1"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[24] = "REMOTE_HOST=localhost"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[25] = "SCRIPT_NAME=/admin/"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/admin/"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[28] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.9.1.9) Gecko/20100401 Ubuntu/9.10 (karmic) Firefox/3.5.9"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[29] = "HTTP_REFERER=http://127.0.0.1:631/admin/"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[30] = "REQUEST_METHOD=POST"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[31] = "CONTENT_LENGTH=16"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] envp[32] = "CONTENT_TYPE=application/x-www-form-urlencoded"', 'D [13/Apr/2010:20:26:20 +1200] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi (PID 2611)', 'I [13/Apr/2010:20:26:20 +1200] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=2611)', 'D [13/Apr/2010:20:26:20 +1200] cupsdSendCommand: 16 file=18', 'D [13/Apr/2010:20:26:20 +1200] [CGI] admin.cgi started...', 'D [13/Apr/2010:20:26:20 +1200] [CGI] http=0x20bdd368', 'D [13/Apr/2010:20:26:20 +1200] [CGI] op="config-server"...', 'D [13/Apr/2010:20:26:20 +1200] cupsdAcceptClient: 17 from localhost (Domain)', 'D [13/Apr/2010:20:26:20 +1200] [CGI] lang="en_US.UTF8", locale="/en_US"...', 'D [13/Apr/2010:20:26:20 +1200] Script header: Content-Type: text/html;charset=utf-8', 'D [13/Apr/2010:20:26:20 +1200] Script header:', 'D [13/Apr/2010:20:26:20 +1200] [CGI] lang="en_US.UTF8", locale="/en_US"...', 'D [13/Apr/2010:20:26:20 +1200] [CGI] lang="en_US.UTF8", locale="/en_US"...', 'D [13/Apr/2010:20:26:20 +1200] cupsdReadClient: 17 WAITING Closing on EOF', 'D [13/Apr/2010:20:26:20 +1200] cupsdCloseClient: 17', 'D [13/Apr/2010:20:26:20 +1200] PID 2611 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.', 'D [13/Apr/2010:20:26:20 +1200] cupsdSetBusyState: Not busy', 'D [13/Apr/2010:20:26:54 +1200] cupsdReadClient: 16 WAITING Closing on EOF', 'D [13/Apr/2010:20:26:54 +1200] cupsdCloseClient: 16', 'D [13/Apr/2010:20:26:54 +1200] Report: clients=3', 'D [13/Apr/2010:20:26:54 +1200] Report: jobs=26', 'D [13/Apr/2010:20:26:54 +1200] Report: jobs-active=1', 'D [13/Apr/2010:20:26:54 +1200] Report: printers=2', 'D [13/Apr/2010:20:26:54 +1200] Report: printers-implicit=0', 'D [13/Apr/2010:20:26:54 +1200] Report: stringpool-string-count=464', 'D [13/Apr/2010:20:26:54 +1200] Report: stringpool-alloc-bytes=7280', 'D [13/Apr/2010:20:26:54 +1200] Report: stringpool-total-bytes=9752', 'D [13/Apr/2010:20:27:01 +1200] cupsdReadClient: 14 POST / HTTP/1.1', 'D [13/Apr/2010:20:27:01 +1200] cupsdSetBusyState: Active clients', 'D [13/Apr/2010:20:27:01 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:27:01 +1200] cupsdReadClient: 14 1.1 Get-Notifications 1', 'D [13/Apr/2010:20:27:01 +1200] Get-Notifications /', 'D [13/Apr/2010:20:27:01 +1200] cupsdIsAuthorized: username="david"', 'D [13/Apr/2010:20:27:01 +1200] Returning IPP successful-ok for Get-Notifications (/) from localhost', 'D [13/Apr/2010:20:27:01 +1200] cupsdSetBusyState: Not busy', 'D [13/Apr/2010:20:27:24 +1200] cupsdAcceptClient: 16 from localhost:631 (IPv4)', 'D [13/Apr/2010:20:27:24 +1200] cupsdReadClient: 16 GET /favicon.ico HTTP/1.1', 'D [13/Apr/2010:20:27:24 +1200] cupsdSetBusyState: Active clients', 'D [13/Apr/2010:20:27:24 +1200] cupsdAuthorize: No authentication data provided.', 'D [13/Apr/2010:20:27:24 +1200] cupsdReadClient: 16 Closing because Keep-Alive disabled', 'D [13/Apr/2010:20:27:24 +1200] cupsdCloseClient: 16', 'D [13/Apr/2010:20:27:24 +1200] cupsdSetBusyState: Not busy', 'D [13/Apr/2010:20:27:26 +1200] cupsdAcceptClient: 16 from localhost:631 (IPv4)', 'D [13/Apr/2010:20:27:26 +1200] cupsdReadClient: 16 GET /admin/log/access_log HTTP/1.1', 'D [13/Apr/2010:20:27:26 +1200] cupsdSetBusyState: Active clients', 'D [13/Apr/2010:20:27:26 +1200] cupsdAuthorize: No authentication data provided.', 'D [13/Apr/2010:20:27:26 +1200] cupsdSetBusyState: Not busy', 'D [13/Apr/2010:20:27:27 +1200] Closing client 12 after 300 seconds of inactivity...', 'D [13/Apr/2010:20:27:27 +1200] cupsdCloseClient: 12', 'D [13/Apr/2010:20:27:40 +1200] Closing client 13 after 300 seconds of inactivity...', 'D [13/Apr/2010:20:27:40 +1200] cupsdCloseClient: 13', 'D [13/Apr/2010:20:28:09 +1200] cupsdReadClient: 16 WAITING Closing on EOF', 'D [13/Apr/2010:20:28:09 +1200] cupsdCloseClient: 16', 'D [13/Apr/2010:20:28:09 +1200] Report: clients=1', 'D [13/Apr/2010:20:28:09 +1200] Report: jobs=26', 'D [13/Apr/2010:20:28:09 +1200] Report: jobs-active=1', 'D [13/Apr/2010:20:28:09 +1200] Report: printers=2', 'D [13/Apr/2010:20:28:09 +1200] Report: printers-implicit=0', 'D [13/Apr/2010:20:28:09 +1200] Report: stringpool-string-count=464', 'D [13/Apr/2010:20:28:09 +1200] Report: stringpool-alloc-bytes=7280', 'D [13/Apr/2010:20:28:09 +1200] Report: stringpool-total-bytes=9752', 'D [13/Apr/2010:20:30:01 +1200] cupsdReadClient: 14 POST / HTTP/1.1', 'D [13/Apr/2010:20:30:01 +1200] cupsdSetBusyState: Active clients', 'D [13/Apr/2010:20:30:01 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:30:01 +1200] Report: clients=1', 'D [13/Apr/2010:20:30:01 +1200] Report: jobs=26', 'D [13/Apr/2010:20:30:01 +1200] Report: jobs-active=1', 'D [13/Apr/2010:20:30:01 +1200] Report: printers=2', 'D [13/Apr/2010:20:30:01 +1200] Report: printers-implicit=0', 'D [13/Apr/2010:20:30:01 +1200] Report: stringpool-string-count=466', 'D [13/Apr/2010:20:30:01 +1200] Report: stringpool-alloc-bytes=7312', 'D [13/Apr/2010:20:30:01 +1200] Report: stringpool-total-bytes=9784', 'D [13/Apr/2010:20:30:01 +1200] cupsdReadClient: 14 1.1 Get-Notifications 1', 'D [13/Apr/2010:20:30:01 +1200] Get-Notifications /', 'D [13/Apr/2010:20:30:01 +1200] cupsdIsAuthorized: username="david"', 'D [13/Apr/2010:20:30:01 +1200] Returning IPP successful-ok for Get-Notifications (/) from localhost', 'D [13/Apr/2010:20:30:01 +1200] cupsdSetBusyState: Not busy', 'D [13/Apr/2010:20:31:37 +1200] cupsdAcceptClient: 12 from localhost (Domain)', 'D [13/Apr/2010:20:31:37 +1200] Report: clients=2', 'D [13/Apr/2010:20:31:37 +1200] Report: jobs=26', 'D [13/Apr/2010:20:31:37 +1200] Report: jobs-active=1', 'D [13/Apr/2010:20:31:37 +1200] Report: printers=2', 'D [13/Apr/2010:20:31:37 +1200] Report: printers-implicit=0', 'D [13/Apr/2010:20:31:37 +1200] Report: stringpool-string-count=464', 'D [13/Apr/2010:20:31:37 +1200] Report: stringpool-alloc-bytes=7280', 'D [13/Apr/2010:20:31:37 +1200] Report: stringpool-total-bytes=9752', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 12 POST /printers/MP600 HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: No authentication data provided.', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 12 1.1 Print-Job 1', 'D [13/Apr/2010:20:31:37 +1200] Print-Job ipp://localhost/printers/MP600', 'D [13/Apr/2010:20:31:37 +1200] [Job ???] Auto-typing file...', 'I [13/Apr/2010:20:31:37 +1200] [Job ???] Request file type is application/vnd.cups-banner.', 'D [13/Apr/2010:20:31:37 +1200] cupsdMarkDirty(----J-)', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] add_job: requesting-user-name="david"', 'D [13/Apr/2010:20:31:37 +1200] Adding default job-sheets values "none,none"...', 'I [13/Apr/2010:20:31:37 +1200] [Job 370] Adding start banner page "none".', 'D [13/Apr/2010:20:31:37 +1200] cupsdMarkDirty(-----S)', 'D [13/Apr/2010:20:31:37 +1200] cupsdMarkDirty(----J-)', 'I [13/Apr/2010:20:31:37 +1200] [Job 370] Adding end banner page "none".', 'I [13/Apr/2010:20:31:37 +1200] [Job 370] File of type application/vnd.cups-banner queued by "david".', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] hold_until=0', 'I [13/Apr/2010:20:31:37 +1200] [Job 370] Queued on "MP600" by "david".', 'D [13/Apr/2010:20:31:37 +1200] cupsdMarkDirty(----J-)', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients, printing jobs, and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdMarkDirty(-----S)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] job-sheets=none,none', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] argv[0]="MP600"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] argv[1]="370"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] argv[2]="david"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] argv[3]="Test Page"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] argv[4]="1"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] argv[5]="job-uuid=urn:uuid:0d28c512-19ec-35a5-7cee-8f7bd12b02d9 job-originating-host-name=localhost"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] argv[6]="/var/spool/cups/d00370-001"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[0]="CUPS_CACHEDIR=/var/cache/cups"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[1]="CUPS_DATADIR=/usr/share/cups"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[6]="CUPS_SERVERROOT=/etc/cups"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[7]="CUPS_STATEDIR=/var/run/cups"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[8]="HOME=/var/spool/cups/tmp"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[10]="SERVER_ADMIN=root@david-desktop"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[11]="SOFTWARE=CUPS/1.4.1"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[12]="TMPDIR=/var/spool/cups/tmp"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[13]="TZ=Pacific/Auckland"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[14]="USER=root"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[16]="CUPS_ENCRYPTION=IfRequested"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[17]="IPP_PORT=631"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[18]="CHARSET=utf-8"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[19]="LANG=en_NZ.UTF-8"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[20]="PPD=/etc/cups/ppd/MP600.ppd"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[21]="RIP_MAX_CACHE=515436k"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[22]="CONTENT_TYPE=application/vnd.cups-banner"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[23]="DEVICE_URI=usb://Canon/MP600"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[24]="PRINTER_INFO=Canon MP600"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[25]="PRINTER_LOCATION=david-desktop"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[26]="PRINTER=MP600"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[27]="CUPS_FILETYPE=document"', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] envp[28]="FINAL_CONTENT_TYPE=printer/MP600"', 'I [13/Apr/2010:20:31:37 +1200] [Job 370] Started filter /usr/lib/cups/filter/bannertops (PID 2634)', 'I [13/Apr/2010:20:31:37 +1200] [Job 370] Started filter /usr/lib/cups/filter/pstopdf (PID 2635)', 'I [13/Apr/2010:20:31:37 +1200] [Job 370] Started filter /usr/lib/cups/filter/pdftopdf (PID 2636)', 'I [13/Apr/2010:20:31:37 +1200] [Job 370] Started filter /usr/lib/cups/filter/pdftoraster (PID 2637)', 'I [13/Apr/2010:20:31:37 +1200] [Job 370] Started filter /opt/OpenPrinting-Gutenprint/cups/lib/filter/rastertogutenprint.5.2 (PID 2638)', 'I [13/Apr/2010:20:31:37 +1200] [Job 370] Started backend /usr/lib/cups/backend/usb (PID 2639)', 'D [13/Apr/2010:20:31:37 +1200] cupsdMarkDirty(-----S)', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for Print-Job (ipp://localhost/printers/MP600) from localhost', 'D [13/Apr/2010:20:31:37 +1200] PID 2634 (/usr/lib/cups/filter/bannertops) stopped with status 22!', 'D [13/Apr/2010:20:31:37 +1200] PID 2637 (/usr/lib/cups/filter/pdftoraster) stopped with status 22!', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] /usr/lib/cups/filter/bannertops: Permission denied', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] /usr/lib/cups/filter/pdftoraster: Permission denied', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Printing jobs and dirty files', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] STATE: +connecting-to-device', 'D [13/Apr/2010:20:31:37 +1200] cupsdMarkDirty(-----S)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] /usr/lib/cups/filter/pdftopdf: Permission denied', 'D [13/Apr/2010:20:31:37 +1200] PID 2636 (/usr/lib/cups/filter/pdftopdf) stopped with status 22!', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] /usr/lib/cups/filter/pstopdf: Permission denied', 'D [13/Apr/2010:20:31:37 +1200] PID 2635 (/usr/lib/cups/filter/pstopdf) stopped with status 22!', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Printer using device file "/dev/usblp0"...', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] STATE: -connecting-to-device', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] backendRunLoop(print_fd=0, device_fd=5, snmp_fd=-1, addr=(nil), use_bc=0, side_cb=0x14fb30)', 'D [13/Apr/2010:20:31:37 +1200] cupsdMarkDirty(-----S)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint 5.2.5 Starting', "D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint command line: MP600 '370' 'david' 'Test Page' '1' ", 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint using PPD file /etc/cups/ppd/MP600.ppd', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: CUPS option count is 2 (90 bytes)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: CUPS option 0 job-uuid = urn:uuid:0d28c512-19ec-35a5-7cee-8f7bd12b02d9', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: CUPS option 1 job-originating-host-name = localhost', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Driver Canon PIXMA iP4200', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Using fd 0', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Set options:', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting PageSize to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting MediaType to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting InputSlot to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CDInnerRadius to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CDOuterDiameter to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CDInnerDiameter to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CDXAdjustment to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CDYAdjustment to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting Resolution to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Set string InkType to CMYK', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Set special string InkType to CMYK', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting InkChannels to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting PrintingMode to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting Duplex to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Set string Quality to Standard', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Set special string Quality to Standard', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Set string ColorCorrection to None', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Set special string ColorCorrection to None', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting ChannelBitDepth to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting InputImageType to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting STPIOutputType to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting STPIRawChannels to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting SimpleGamma to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting LinearContrast to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting LUTDumpFile to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CyanCurve to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting MagentaCurve to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting YellowCurve to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting BlackCurve to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting RedCurve to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting GreenCurve to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting BlueCurve to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting WhiteCurve to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting HueMap to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting SatMap to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting LumMap to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting GCRCurve to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh0 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh1 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh2 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh3 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh4 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh5 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh6 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh7 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh8 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh9 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh10 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh11 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh12 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh13 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh14 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh15 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh16 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh17 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh18 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh19 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh20 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh21 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh22 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh23 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh24 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh25 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh26 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh27 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh28 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh29 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh30 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting CurveCh31 to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting DitherAlgorithm to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Set string ImageType to TextGraphics', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Set special string ImageType to TextGraphics', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting JobMode to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Not setting PageNumber to (null)', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: End options', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: About to start printing loop.', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Printed total 0 bytes', 'D [13/Apr/2010:20:31:37 +1200] [Job 370] Gutenprint: Used 0.120 seconds user, 0.000 seconds system, 0.122 seconds elapsed', 'D [13/Apr/2010:20:31:37 +1200] PID 2638 (/opt/OpenPrinting-Gutenprint/cups/lib/filter/rastertogutenprint.5.2) exited with no errors.', 'D [13/Apr/2010:20:31:37 +1200] PID 2639 (/usr/lib/cups/backend/usb) exited with no errors.', 'D [13/Apr/2010:20:31:37 +1200] cupsdMarkDirty(-----S)', 'E [13/Apr/2010:20:31:37 +1200] [Job 370] Job stopped due to filter errors; please consult the error_log file for details.', 'D [13/Apr/2010:20:31:37 +1200] cupsdMarkDirty(----J-)', 'D [13/Apr/2010:20:31:37 +1200] cupsdMarkDirty(-----S)', 'D [13/Apr/2010:20:31:37 +1200] cupsdAcceptClient: 16 from localhost (Domain)', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: No authentication data provided.', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 1.1 Get-Notifications 1', 'D [13/Apr/2010:20:31:37 +1200] Get-Notifications /', 'D [13/Apr/2010:20:31:37 +1200] cupsdIsAuthorized: requesting-user-name="david"', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for Get-Notifications (/) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 WAITING Closing on EOF', 'D [13/Apr/2010:20:31:37 +1200] cupsdCloseClient: 16', 'D [13/Apr/2010:20:31:37 +1200] cupsdAcceptClient: 16 from localhost (Domain)', 'D [13/Apr/2010:20:31:37 +1200] cupsdAcceptClient: 17 from localhost (Domain)', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1', 'D [13/Apr/2010:20:31:37 +1200] CUPS-Get-Printers', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 1.1 CUPS-Get-Classes 1', 'D [13/Apr/2010:20:31:37 +1200] CUPS-Get-Classes', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 1.1 CUPS-Get-Default 1', 'D [13/Apr/2010:20:31:37 +1200] CUPS-Get-Default', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 17 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: No authentication data provided.', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 17 1.1 Get-Jobs 1', 'D [13/Apr/2010:20:31:37 +1200] Get-Jobs ipp://localhost/printers/', 'D [13/Apr/2010:20:31:37 +1200] [Job 369] Loading attributes...', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 17 WAITING Closing on EOF', 'D [13/Apr/2010:20:31:37 +1200] cupsdCloseClient: 17', 'D [13/Apr/2010:20:31:37 +1200] cupsdAcceptClient: 17 from localhost (Domain)', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 17 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: No authentication data provided.', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 17 1.1 Get-Notifications 1', 'D [13/Apr/2010:20:31:37 +1200] Get-Notifications /', 'D [13/Apr/2010:20:31:37 +1200] cupsdIsAuthorized: requesting-user-name="david"', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for Get-Notifications (/) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 17 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: No authentication data provided.', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 17 1.1 Get-Job-Attributes 1', 'D [13/Apr/2010:20:31:37 +1200] Get-Job-Attributes ipp://localhost/jobs/370', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/370) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAcceptClient: 18 from localhost (Domain)', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 18 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: No authentication data provided.', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 18 1.1 Get-Printer-Attributes 1', 'D [13/Apr/2010:20:31:37 +1200] Get-Printer-Attributes ipp://david-desktop:631/printers/MP600', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for Get-Printer-Attributes (ipp://david-desktop:631/printers/MP600) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 18 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: No authentication data provided.', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 18 1.1 Get-Job-Attributes 1', 'D [13/Apr/2010:20:31:37 +1200] Get-Job-Attributes ipp://localhost/jobs/370', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/370) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 14 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 14 1.1 Get-Notifications 1', 'D [13/Apr/2010:20:31:37 +1200] Get-Notifications /', 'D [13/Apr/2010:20:31:37 +1200] cupsdIsAuthorized: username="david"', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for Get-Notifications (/) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1', 'D [13/Apr/2010:20:31:37 +1200] CUPS-Get-Printers', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 1.1 CUPS-Get-Classes 1', 'D [13/Apr/2010:20:31:37 +1200] CUPS-Get-Classes', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 1.1 CUPS-Get-Default 1', 'D [13/Apr/2010:20:31:37 +1200] CUPS-Get-Default', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1', 'D [13/Apr/2010:20:31:37 +1200] CUPS-Get-Printers', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 1.1 CUPS-Get-Classes 1', 'D [13/Apr/2010:20:31:37 +1200] CUPS-Get-Classes', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 1.1 CUPS-Get-Default 1', 'D [13/Apr/2010:20:31:37 +1200] CUPS-Get-Default', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1', 'D [13/Apr/2010:20:31:37 +1200] CUPS-Get-Printers', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 1.1 CUPS-Get-Classes 1', 'D [13/Apr/2010:20:31:37 +1200] CUPS-Get-Classes', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 16 1.1 CUPS-Get-Default 1', 'D [13/Apr/2010:20:31:37 +1200] CUPS-Get-Default', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAcceptClient: 19 from localhost (Domain)', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 18 WAITING Closing on EOF', 'D [13/Apr/2010:20:31:37 +1200] cupsdCloseClient: 18', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 19 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: No authentication data provided.', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 19 1.1 Get-Printer-Attributes 1', 'D [13/Apr/2010:20:31:37 +1200] Get-Printer-Attributes ipp://david-desktop:631/printers/MP600', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for Get-Printer-Attributes (ipp://david-desktop:631/printers/MP600) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 19 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdAuthorize: No authentication data provided.', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 19 1.1 Get-Job-Attributes 1', 'D [13/Apr/2010:20:31:37 +1200] Get-Job-Attributes ipp://localhost/jobs/370', 'D [13/Apr/2010:20:31:37 +1200] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/370) from localhost', 'D [13/Apr/2010:20:31:37 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:37 +1200] cupsdReadClient: 17 WAITING Closing on EOF', 'D [13/Apr/2010:20:31:37 +1200] cupsdCloseClient: 17', 'D [13/Apr/2010:20:31:38 +1200] [Job 370] Unloading...', 'D [13/Apr/2010:20:31:54 +1200] cupsdReadClient: 14 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:54 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:54 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:31:54 +1200] cupsdReadClient: 14 1.1 Get-Job-Attributes 1', 'D [13/Apr/2010:20:31:54 +1200] Get-Job-Attributes ipp://localhost/jobs/369', 'D [13/Apr/2010:20:31:54 +1200] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/369) from localhost', 'D [13/Apr/2010:20:31:54 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:31:54 +1200] cupsdReadClient: 14 POST / HTTP/1.1', 'D [13/Apr/2010:20:31:54 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:31:54 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:31:54 +1200] cupsdReadClient: 14 1.1 Get-Job-Attributes 1', 'D [13/Apr/2010:20:31:54 +1200] Get-Job-Attributes ipp://localhost/jobs/370', 'D [13/Apr/2010:20:31:54 +1200] [Job 370] Loading attributes...', 'D [13/Apr/2010:20:31:54 +1200] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/370) from localhost', 'D [13/Apr/2010:20:31:54 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:32:01 +1200] cupsdReadClient: 14 POST / HTTP/1.1', 'D [13/Apr/2010:20:32:01 +1200] cupsdSetBusyState: Active clients and dirty files', 'D [13/Apr/2010:20:32:01 +1200] cupsdAuthorize: Authorized as david using PeerCred', 'D [13/Apr/2010:20:32:01 +1200] cupsdReadClient: 14 1.1 Cancel-Subscription 1', 'D [13/Apr/2010:20:32:01 +1200] Cancel-Subscription /', 'D [13/Apr/2010:20:32:01 +1200] cupsdIsAuthorized: username="david"', 'D [13/Apr/2010:20:32:01 +1200] cupsdMarkDirty(-----S)', 'D [13/Apr/2010:20:32:01 +1200] Returning IPP successful-ok for Cancel-Subscription (/) from localhost', 'D [13/Apr/2010:20:32:01 +1200] cupsdSetBusyState: Dirty files', 'D [13/Apr/2010:20:32:01 +1200] cupsdAcceptClient: 17 from localhost (Domain)', 'I [13/Apr/2010:20:32:08 +1200] Saving job cache file "/var/cache/cups/job.cache"...', 'I [13/Apr/2010:20:32:08 +1200] Saving subscriptions.conf...', 'D [13/Apr/2010:20:32:08 +1200] cupsdSetBusyState: Not busy', 'D [13/Apr/2010:20:32:11 +1200] cupsdReadClient: 17 GET /admin/log/error_log HTTP/1.1', 'D [13/Apr/2010:20:32:11 +1200] cupsdSetBusyState: Active clients', 'D [13/Apr/2010:20:32:11 +1200] cupsdAuthorize: No authentication data provided.']} Page 10 (Printer state reasons): {'printer-state-message': u'/usr/lib/cups/filter/bannertops failed', 'printer-state-reasons': [u'none']} Page 11 (Locale issues): {'job_page_size': u'A4', 'printer_page_size': u'Letter', 'system_locale_lang': None, 'user_locale_ctype': 'en_NZ', 'user_locale_messages': 'en_NZ'}