Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 697960 Details for
Bug 911727
USB printer CANON PIXMA MP500 does not print with CUPS: "Waiting for printer to become available"
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
Troubleshoot file from system-config-printer
troubleshoot.txt (text/plain), 132.13 KB, created by
Patrick Proche
on 2013-02-15 19:26:49 UTC
(
hide
)
Description:
Troubleshoot file from system-config-printer
Filename:
MIME Type:
Creator:
Patrick Proche
Created:
2013-02-15 19:26:49 UTC
Size:
132.13 KB
patch
obsolete
>Page 1 (Scheduler not running?): >{'cups_connection_failure': False} >Page 2 (Choose printer): >{'cups_dest': <cups.Dest MP500 (default)>, > 'cups_instance': None, > 'cups_queue': 'MP500', > 'cups_queue_listed': True} >Page 3 (Check printer sanity): >{'cups_device_uri_scheme': u'usb', > 'cups_printer_dict': {'device-uri': u'usb://Canon/MP500?serial=50F9FF&interface=1', > 'printer-info': u'Canon PIXMA MP500', > 'printer-is-shared': True, > 'printer-location': u'', > 'printer-make-and-model': u'Canon PIXMA MP500 - CUPS+Gutenprint v5.2.9 Simplified', > 'printer-state': 3, > 'printer-state-message': u'Waiting for printer to become available.', > 'printer-state-reasons': [u'none'], > 'printer-type': 167964, > 'printer-uri-supported': u'ipp://localhost:631/printers/MP500'}, > '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.5.4', > 'device-uri': u'usb://Canon/MP500?serial=50F9FF&interface=1', > 'document-format-default': u'application/octet-stream', > 'document-format-supported': [u'application/octet-stream', > 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/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'text/css', > u'text/html', > u'text/plain'], > 'finishings-default': 3, > 'finishings-supported': [3], > 'generated-natural-language-supported': [u'de-de'], > 'ipp-versions-supported': [u'1.0', > u'1.1', > u'2.0', > u'2.1'], > 'ippget-event-life': 15, > 'job-creation-attributes-supported': [u'copies', > u'finishings', > u'ipp-attribute-fidelity', > u'job-hold-until', > u'job-name', > u'job-priority', > u'job-sheets', > u'media', > u'media-col', > u'multiple-document-handling', > u'number-up', > u'output-bin', > u'output-mode', > u'orientation-requested', > u'page-ranges', > u'print-quality', > u'printer-resolution', > u'sides'], > '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-ids-supported': True, > 'job-k-limit': 0, > 'job-k-octets-supported': (0, 68406832), > '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-name', > u'job-priority', > u'media', > u'media-col', > u'multiple-document-handling', > u'number-up', > u'output-bin', > u'output-mode', > 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'], > 'jpeg-k-octets-supported': (0, 68406832), > 'jpeg-x-dimension-supported': (0, 65535), > 'jpeg-y-dimension-supported': (1, 65535), > 'marker-change-time': 0, > 'media-bottom-margin-supported': [0], > 'media-col-supported': [u'media-bottom-margin', > u'media-left-margin', > u'media-right-margin', > u'media-size', > u'media-source', > u'media-top-margin', > u'media-type'], > 'media-default': u'iso_a4_210x297mm', > 'media-left-margin-supported': [0], > 'media-right-margin-supported': [0], > 'media-source-supported': [u'select-key', > u'auto', > u'main', > u'cd', > u'auto', > u'alloc-paper'], > 'media-supported': [u'na_letter_8.5x11in', > u'na_legal_8.5x14in', > u'na_executive_7.25x10.5in', > u'jpn_hagaki_100x148mm', > u'om_cd5-inch_116.06x116.06mm', > u'om_cd3-inch_64.91x64.91mm', > u'om_cdcustom_119.94x119.94mm', > u'oe_w288h432_4x6in', > u'na_invoice_5.5x8.5in', > u'iso_a4_210x297mm', > u'custom_min_0.35x0.35mm', > u'custom_max_8.5x23in'], > 'media-top-margin-supported': [0], > 'media-type-supported': [u'stationery', > u'photographic-glossy', > u'photopaper-plus', > u'photopaper-plus-double', > u'photopaper-matte', > u'photographic-glossy', > u'stationery-coated', > u'ink-jet-hagaki', > u'hagaki', > u'cd', > u'disc-compat', > u'disc-others', > u'tshirt', > u'envelope', > u'photopaper-other', > u'transparency'], > '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'de-de', > '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'dbus', > 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, > 14, > 16, > 17, > 18, > 19, > 20, > 21, > 22, > 23, > 24, > 25, > 26, > 27, > 28, > 34, > 35, > 37, > 38, > 56, > 57, > 59, > 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], > 'output-bin-default': u'face-down', > 'output-bin-supported': [u'face-down'], > 'output-mode-default': u'color', > 'output-mode-supported': [u'monochrome', > u'color'], > 'page-ranges-supported': True, > 'pages-per-minute': 1, > 'pages-per-minute-color': 1, > 'pdf-k-octets-supported': (0, 68406832), > 'pdf-versions-supported': [u'adobe-1.2', > u'adobe-1.3', > u'adobe-1.4', > u'adobe-1.5', > u'adobe-1.6', > u'adobe-1.7', > u'iso-19005-1_2005', > u'iso-32000-1_2008', > u'pwg-5102.3'], > 'pdl-override-supported': [u'attempted'], > 'port-monitor': u'none', > 'port-monitor-supported': [u'none'], > 'print-color-mode-default': u'color', > 'print-color-mode-supported': [u'monochrome', > u'color'], > 'print-quality-default': 4, > 'print-quality-supported': [4, 5], > 'printer-commands': u'none', > 'printer-current-time': '(IPP_TAG_DATE)', > 'printer-dns-sd-name': u'Canon PIXMA MP500 @ localhost', > 'printer-error-policy': u'stop-printer', > 'printer-error-policy-supported': [u'abort-job', > u'retry-current-job', > u'retry-job', > u'stop-printer'], > 'printer-icons': u'http://localhost:631/icons/MP500.png', > 'printer-info': u'Canon PIXMA MP500', > 'printer-is-accepting-jobs': True, > 'printer-is-shared': True, > 'printer-location': u'', > 'printer-make-and-model': u'Canon PIXMA MP500 - CUPS+Gutenprint v5.2.9 Simplified', > 'printer-more-info': u'http://localhost:631/printers/MP500', > 'printer-name': u'MP500', > 'printer-op-policy': u'default', > 'printer-op-policy-supported': [u'authenticated', > u'default'], > 'printer-resolution-default': (601, 600, 3), > 'printer-resolution-supported': [(601, > 600, > 3), > (600, > 600, > 3), > (602, > 600, > 3), > (603, > 600, > 3), > (300, > 300, > 3), > (301, > 300, > 3), > (604, > 600, > 3), > (605, > 600, > 3), > (606, > 600, > 3), > (607, > 600, > 3), > (608, > 600, > 3), > (609, > 600, > 3), > (610, > 600, > 3), > (611, > 600, > 3)], > 'printer-settable-attributes-supported': [u'printer-info', > u'printer-location'], > 'printer-state': 3, > 'printer-state-change-time': 1360953818, > 'printer-state-message': u'Waiting for printer to become available.', > 'printer-state-reasons': [u'none'], > 'printer-type': 167964, > 'printer-up-time': 1360953830, > 'printer-uri-supported': [u'ipp://localhost:631/printers/MP500'], > 'printer-uuid': u'urn:uuid:315d908e-8f45-340e-5c23-fe23fcf53dbc', > 'queued-job-count': 0, > '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'], > 'which-jobs-supported': [u'completed', > u'not-completed', > u'aborted', > u'all', > u'canceled', > u'pending', > u'pending-held', > u'processing', > u'processing-stopped']}} >Page 4 (Check PPD sanity): >{'cups_printer_ppd_defaults': {u'C0L0': {u'StpFullBleed': u'False', > u'StpInkSet': u'None', > 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'A4', > u'PageSize': u'A4', > 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 (Printer state reasons): >{'printer-state-message': u'Waiting for printer to become available.', > 'printer-state-reasons': [u'none']} >Page 7 (Verify packages): >{'packages_verification': {'cups': ['.M....... c /etc/cups/subscriptions.conf'], > 'foomatic': [], > 'gutenprint': [], > 'hpijs': ['package hpijs is not installed'], > 'hplip': ['package hplip is not installed'], > 'system-config-printer': []}} >Page 8 (Error log checkpoint): >{'cups_server_settings': {'BrowseLocalProtocols': 'CUPS dnssd', > 'BrowseRemoteProtocols': 'CUPS', > 'DefaultAuthType': 'Basic', > 'JobPrivateAccess': 'default', > 'JobPrivateValues': 'default', > 'MaxLogSize': '0', > 'SubscriptionPrivateAccess': 'default', > 'SubscriptionPrivateValues': 'default', > 'WebInterface': 'Yes', > '_debug_logging': '0', > '_remote_admin': '0', > '_remote_any': '0', > '_remote_printers': '1', > '_share_printers': '1', > '_user_cancel_any': '0'}, > 'error_log_checkpoint': 20279, > 'error_log_debug_logging_set': True} >Page 9 (Print test page): >{'test_page_attempted': '15/Feb/2013:19:44:10 +0000', > 'test_page_job_id': [121], > 'test_page_job_status': [(True, > 121, > 'MP500', > 'Test Page', > 'Ausf\xc3\xbchrung l\xc3\xa4uft', > {'attributes-charset': u'utf-8', > 'attributes-natural-language': u'de-de', > 'document-count': 1, > 'document-format': u'application/vnd.cups-banner', > 'job-hold-until': u'no-hold', > 'job-id': 121, > 'job-k-octets': 1, > 'job-media-progress': 29, > 'job-media-sheets-completed': 1, > 'job-more-info': u'http://localhost:631/jobs/121', > 'job-printer-state-message': u'Waiting for printer to become available.', > 'job-printer-state-reasons': [u'none'], > 'job-printer-up-time': 1360953856, > 'job-printer-uri': u'ipp://localhost:631/printers/MP500', > 'job-priority': 50, > 'job-sheets': [u'none', u'none'], > 'job-state': 5, > 'job-state-reasons': u'job-printing', > 'job-uri': u'ipp://localhost:631/jobs/121', > 'job-uuid': u'urn:uuid:b8b40fa5-d37a-36d7-5d36-0754a4815bd0', > 'printer-uri': u'ipp://localhost/printers/MP500', > 'time-at-completed': None, > 'time-at-creation': 1360953850, > 'time-at-processing': 1360953850})], > 'test_page_successful': False} >Page 10 (Error log fetch): >{'error_log': ['D [15/Feb/2013:19:44:07 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [15/Feb/2013:19:44:07 +0100] cupsdReadClient: 16 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:07 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [15/Feb/2013:19:44:07 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:07 +0100] cupsdReadClient: 16 1.1 Get-Jobs 1', > 'D [15/Feb/2013:19:44:07 +0100] Get-Jobs ipp://localhost/printers/', > 'D [15/Feb/2013:19:44:07 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost', > 'D [15/Feb/2013:19:44:07 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [15/Feb/2013:19:44:07 +0100] cupsdReadClient: 16 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:07 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [15/Feb/2013:19:44:07 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:07 +0100] cupsdReadClient: 16 1.1 Get-Jobs 1', > 'D [15/Feb/2013:19:44:07 +0100] Get-Jobs ipp://localhost/printers/', > 'D [15/Feb/2013:19:44:07 +0100] [Job 111] Loading attributes...', > 'D [15/Feb/2013:19:44:07 +0100] [Job 112] Loading attributes...', > 'D [15/Feb/2013:19:44:07 +0100] [Job 113] Loading attributes...', > 'D [15/Feb/2013:19:44:07 +0100] [Job 114] Loading attributes...', > 'D [15/Feb/2013:19:44:07 +0100] [Job 115] Loading attributes...', > 'D [15/Feb/2013:19:44:07 +0100] [Job 116] Loading attributes...', > 'D [15/Feb/2013:19:44:07 +0100] [Job 117] Loading attributes...', > 'D [15/Feb/2013:19:44:07 +0100] [Job 118] Loading attributes...', > 'D [15/Feb/2013:19:44:07 +0100] [Job 119] Loading attributes...', > 'D [15/Feb/2013:19:44:07 +0100] [Job 120] Loading attributes...', > 'D [15/Feb/2013:19:44:07 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost', > 'D [15/Feb/2013:19:44:07 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [15/Feb/2013:19:44:07 +0100] cupsdReadClient: 16 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:07 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [15/Feb/2013:19:44:07 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:07 +0100] cupsdReadClient: 16 1.1 Create-Printer-Subscription 1', > 'D [15/Feb/2013:19:44:07 +0100] Create-Printer-Subscription /', > 'D [15/Feb/2013:19:44:07 +0100] cupsdCreateSubscription(con=0x7f9d68bee070(16), uri="/")', > 'D [15/Feb/2013:19:44:07 +0100] pullmethod="ippget"', > 'D [15/Feb/2013:19:44:07 +0100] notify-lease-duration=86400', > 'D [15/Feb/2013:19:44:07 +0100] notify-time-interval=0', > 'D [15/Feb/2013:19:44:07 +0100] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)")', > 'D [15/Feb/2013:19:44:07 +0100] Added subscription #11 for server.', > 'D [15/Feb/2013:19:44:07 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:07 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [15/Feb/2013:19:44:07 +0100] Returning IPP successful-ok for Create-Printer-Subscription (/) from localhost', > 'D [15/Feb/2013:19:44:07 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [15/Feb/2013:19:44:08 +0100] cupsdReadClient: 16 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:08 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [15/Feb/2013:19:44:08 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:08 +0100] cupsdReadClient: 16 1.1 Get-Notifications 1', > 'D [15/Feb/2013:19:44:08 +0100] Get-Notifications /', > 'D [15/Feb/2013:19:44:08 +0100] cupsdIsAuthorized: requesting-user-name="root"', > 'D [15/Feb/2013:19:44:08 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [15/Feb/2013:19:44:08 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdAcceptClient: skipping getpeercon()', > 'D [15/Feb/2013:19:44:10 +0100] cupsdAcceptClient: 17 from localhost (Domain)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdReadClient: 17 POST /printers/MP500 HTTP/1.1', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:10 +0100] cupsdReadClient: 17 1.1 Print-Job 1', > 'D [15/Feb/2013:19:44:10 +0100] Print-Job ipp://localhost/printers/MP500', > 'D [15/Feb/2013:19:44:10 +0100] [Job ???] Auto-typing file...', > 'I [15/Feb/2013:19:44:10 +0100] [Job ???] Request file type is application/vnd.cups-banner.', > 'D [15/Feb/2013:19:44:10 +0100] add_job: setting context of job to UNKNOWN SL', > 'D [15/Feb/2013:19:44:10 +0100] cupsdMarkDirty(----J-)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] add_job: requesting-user-name="root"', > 'D [15/Feb/2013:19:44:10 +0100] Adding default job-sheets values "none,none"...', > 'I [15/Feb/2013:19:44:10 +0100] [Job 121] Adding start banner page "none".', > 'D [15/Feb/2013:19:44:10 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdMarkDirty(----J-)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'I [15/Feb/2013:19:44:10 +0100] [Job 121] Adding end banner page "none".', > 'I [15/Feb/2013:19:44:10 +0100] [Job 121] File of type application/vnd.cups-banner queued by "root".', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] hold_until=0', > 'I [15/Feb/2013:19:44:10 +0100] [Job 121] Queued on "MP500" by "root".', > 'D [15/Feb/2013:19:44:10 +0100] cupsdMarkDirty(----J-)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] job-sheets=none,none', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] argv[0]="MP500"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] argv[1]="121"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] argv[2]="root"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] argv[3]="Test Page"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] argv[4]="1"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] argv[5]="job-uuid=urn:uuid:b8b40fa5-d37a-36d7-5d36-0754a4815bd0 job-originating-host-name=localhost time-at-creation=1360953850 time-at-processing=1360953850"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] argv[6]="/var/spool/cups/d00121-001"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[0]="CUPS_CACHEDIR=/var/cache/cups"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[1]="CUPS_DATADIR=/usr/share/cups"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[6]="CUPS_SERVERROOT=/etc/cups"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[7]="CUPS_STATEDIR=/var/run/cups"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[8]="HOME=/var/spool/cups/tmp"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[9]="PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[10]="SERVER_ADMIN=root@localhost"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[11]="SOFTWARE=CUPS/1.5.4"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[12]="TMPDIR=/var/spool/cups/tmp"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[13]="USER=root"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[14]="CUPS_MAX_MESSAGE=2047"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[16]="CUPS_ENCRYPTION=IfRequested"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[17]="IPP_PORT=631"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[18]="CHARSET=utf-8"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[19]="LANG=de_DE.UTF-8"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[20]="PPD=/etc/cups/ppd/MP500.ppd"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[21]="RIP_MAX_CACHE=128m"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[22]="CONTENT_TYPE=application/vnd.cups-banner"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[23]="DEVICE_URI=usb://Canon/MP500?serial=50F9FF&interface=1"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[24]="PRINTER_INFO=Canon PIXMA MP500"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[25]="PRINTER_LOCATION="', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[26]="PRINTER=MP500"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[27]="PRINTER_STATE_REASONS=none"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[28]="CUPS_FILETYPE=document"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[29]="FINAL_CONTENT_TYPE=printer/MP500"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[30]="AUTH_I****"', > 'I [15/Feb/2013:19:44:10 +0100] [Job 121] Started filter /usr/lib/cups/filter/bannertops (PID 3149)', > 'I [15/Feb/2013:19:44:10 +0100] [Job 121] Started filter /usr/lib/cups/filter/pstops (PID 3150)', > 'I [15/Feb/2013:19:44:10 +0100] [Job 121] Started filter /usr/lib/cups/filter/gstoraster (PID 3151)', > 'I [15/Feb/2013:19:44:10 +0100] [Job 121] Started filter /usr/lib/cups/filter/rastertogutenprint.5.2 (PID 3152)', > 'I [15/Feb/2013:19:44:10 +0100] [Job 121] Started backend /usr/lib/cups/backend/usb (PID 3153)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] Returning IPP successful-ok for Print-Job (ipp://localhost/printers/MP500) from localhost', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Printing on printer with URI: usb://Canon/MP500?serial=50F9FF&interface=1', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] load_banner(filename="/var/spool/cups/d00121-001")', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] libusb_get_device_list=14', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] 1 #CUPS-BANNER', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] 2 Show printer-name printer-info printer-location printer-make-and-model printer-driver-name printer-driver-version paper-size imageable-area', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] 3 Header Printer Test Page', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] 4 Footer Printer Test Page', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] 5 Notice CUPS 1.5.4.', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] 6 Image images/cups.png', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] 7 Image images/color-wheel.png', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Failed to open device, code: -3', > 'I [15/Feb/2013:19:44:10 +0100] [Job 121] Waiting for printer to become available.', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Set job-printer-state-message to "Waiting for printer to become available.", current level=INFO', > 'D [15/Feb/2013:19:44:10 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Page = 595x842; 0,0 to 595,842', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Page = 595x842; 0,0 to 595,842', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] slow_collate=0, slow_duplex=0, slow_order=0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Before copy_comments - %!PS-Adobe-3.0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] %!PS-Adobe-3.0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] %%BoundingBox: 0 0 595 842', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] %cupsRotation: 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] %%Creator: bannertops/CUPS v1.5.4', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] %%CreationDate: Fr 15 Feb 2013 19:44:10 CET', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] %%LanguageLevel: 2', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] %%DocumentData: Clean7Bit', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] %%Title: (Test Page)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] %%For: (root)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] %%Pages: 1', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] %%DocumentSuppliedResources: font Monospace', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] %%+ font Monospace-Bold', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] %%+ font Monospace-BoldOblique', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] %%+ font Monospace-Oblique', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] %%EndComments', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Before copy_prolog - %%BeginProlog', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] PNG image: 128x128x8, color_type=6 (RGB+ALPHA)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] PNG image: 192x128x8, color_type=2 (RGB)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Before copy_setup - %%Page: coverpage 1', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Before page loop - %%Page: coverpage 1', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Copying page 1...', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] pagew = 595.0, pagel = 842.0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] PageLeft = 0.0, PageRight = 595.0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] PageTop = 842.0, PageBottom = 0.0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] PageWidth = 595.0, PageLength = 842.0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Wrote 1 pages...', > "D [15/Feb/2013:19:44:10 +0100] [Job 121] PPD uses qualifier 'RGB.Plain.601x600dpi'", > 'D [15/Feb/2013:19:44:10 +0100] PID 3150 (/usr/lib/cups/filter/pstops) exited with no errors.', > 'D [15/Feb/2013:19:44:10 +0100] PID 3149 (/usr/lib/cups/filter/bannertops) exited with no errors.', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Calling FindDeviceById(MP500)', > "D [15/Feb/2013:19:44:10 +0100] [Job 121] Failed to send: org.freedesktop.ColorManager.NotFound:device id 'MP500' does not exists", > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Failed to get profile filename!', > 'I [15/Feb/2013:19:44:10 +0100] [Job 121] no profiles specified in PPD', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Set job-printer-state-message to "no profiles specified in PPD", current level=INFO', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Ghostscript command line: /usr/bin/gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -dNOMEDIAATTRS -sDEVICE=cups -sstdout=%stderr -sOutputFile=%stdout -I/usr/share/cups/fonts -c -f -_', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[0]="CUPS_CACHEDIR=/var/cache/cups"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[1]="CUPS_DATADIR=/usr/share/cups"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[6]="CUPS_SERVERROOT=/etc/cups"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[7]="CUPS_STATEDIR=/var/run/cups"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[8]="HOME=/var/spool/cups/tmp"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[9]="PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[10]="SERVER_ADMIN=root@localhost"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[11]="SOFTWARE=CUPS/1.5.4"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[12]="TMPDIR=/var/spool/cups/tmp"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[13]="USER=root"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[14]="CUPS_MAX_MESSAGE=2047"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[16]="CUPS_ENCRYPTION=IfRequested"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[17]="IPP_PORT=631"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[18]="CHARSET=utf-8"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[19]="LANG=de_DE.UTF-8"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[20]="PPD=/etc/cups/ppd/MP500.ppd"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[21]="RIP_MAX_CACHE=128m"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[22]="CONTENT_TYPE=application/vnd.cups-banner"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[23]="DEVICE_URI=usb://Canon/MP500?serial=50F9FF&interface=1"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[24]="PRINTER_INFO=Canon PIXMA MP500"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[25]="PRINTER_LOCATION="', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[26]="PRINTER=MP500"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[27]="PRINTER_STATE_REASONS=none"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[28]="CUPS_FILETYPE=document"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[29]="FINAL_CONTENT_TYPE=printer/MP500"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] envp[30]="AUTH_INFO_REQUIRED=none"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: ============================================================', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: VVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVV', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: 5.2.9 Starting', > "D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: command line: MP500 '121' 'root' 'Test Page' '1' <args>", > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: using PPD file /etc/cups/ppd/MP500.ppd', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: CUPS option count is 4 (148 bytes)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: CUPS option 0 job-originating-host-name = localhost', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: CUPS option 1 job-uuid = urn:uuid:b8b40fa5-d37a-36d7-5d36-0754a4815bd0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: CUPS option 2 time-at-creation = 1360953850', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: CUPS option 3 time-at-processing = 1360953850', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Driver Canon PIXMA MP500', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Using fd 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Set options:', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting PageSize to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting MediaType to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting InputSlot to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CDInnerRadius to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CDOuterDiameter to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CDInnerDiameter to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CDXAdjustment to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CDYAdjustment to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting Resolution to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Set string InkType to CMYK', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Set special string InkType to CMYK', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting InkChannels to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting PrintingMode to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Set string InkSet to None', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Set special string InkSet to None', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Set bool FullBleed to False (0)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting Duplex to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Set string Quality to Standard', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Set special string Quality to Standard', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Set string ColorCorrection to None', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Set special string ColorCorrection to None', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting ChannelBitDepth to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting InputImageType to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting STPIOutputType to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting STPIRawChannels to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting SimpleGamma to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting LinearContrast to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting LUTDumpFile to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CyanCurve to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting MagentaCurve to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting YellowCurve to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting BlackCurve to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting RedCurve to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting GreenCurve to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting BlueCurve to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting WhiteCurve to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting HueMap to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting SatMap to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting LumMap to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting GCRCurve to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh0 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh1 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh2 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh3 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh4 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh5 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh6 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh7 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh8 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh9 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh10 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh11 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh12 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh13 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh14 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh15 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh16 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh17 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh18 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh19 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh20 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh21 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh22 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh23 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh24 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh25 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh26 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh27 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh28 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh29 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh30 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting CurveCh31 to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting DitherAlgorithm to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Set string ImageType to TextGraphics', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Set special string ImageType to TextGraphics', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting JobMode to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Not setting PageNumber to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: End options', > 'I [15/Feb/2013:19:44:10 +0100] [Job 121] Start rendering...', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Set job-printer-state-message to "Start rendering...", current level=INFO', > 'D [15/Feb/2013:19:44:10 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:10 +0100] [Job 121] Processing page 1...', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Set job-printer-state-message to "Processing page 1...", current level=INFO', > 'D [15/Feb/2013:19:44:10 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdAcceptClient: skipping getpeercon()', > 'D [15/Feb/2013:19:44:10 +0100] cupsdAcceptClient: 19 from localhost (Domain)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdReadClient: 19 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:10 +0100] cupsdReadClient: 19 1.1 Get-Notifications 1', > 'D [15/Feb/2013:19:44:10 +0100] Get-Notifications /', > 'D [15/Feb/2013:19:44:10 +0100] cupsdIsAuthorized: requesting-user-name="root"', > 'D [15/Feb/2013:19:44:10 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdReadClient: 19 WAITING Closing on EOF', > 'D [15/Feb/2013:19:44:10 +0100] cupsdCloseClient: 19', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdReadClient: 16 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:10 +0100] cupsdReadClient: 16 1.1 Get-Notifications 1', > 'D [15/Feb/2013:19:44:10 +0100] Get-Notifications /', > 'D [15/Feb/2013:19:44:10 +0100] cupsdIsAuthorized: requesting-user-name="root"', > 'D [15/Feb/2013:19:44:10 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: About to start printing loop.', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: ================ Printing page 1 ================', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] PAGE: 1 1', > 'D [15/Feb/2013:19:44:10 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Initialize page', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Set special string ChannelBitDepth to 16', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Set special string PrintingMode to Color', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Set special string InputImageType to RGB', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Clear special parameter Resolution', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Set special parameter Quality to choice 1 (Standard)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Set special string InputSlot to SelectKey', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Set special string MediaType to Plain', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: PageSize = 595x842', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Using page size A4 with (842, 595)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Set special string PageSize to A4', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Set special string JobMode to Job', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Validating options', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Clearing string CDInnerRadius ((null))', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Clearing string Resolution ((null))', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Setting default string Resolution to 600x600dpi', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Clearing string STPIOutputType ((null))', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Setting default string STPIOutputType to (null)', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Done validating options', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: limits w 595 l 10 r 585 h 842 t 9 b 827', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: max limits l -8 r 603 t -6 b 857', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: left margin 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: right margin 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: top margin 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: bottom margin 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: CUPS settings w 4958 l 83 r 83 h 7016 t 75 b 125', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: adjusted w 4958 h 7016', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: End initialize page', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Interim page settings:', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: === BEGIN GUTENPRINT SETTINGS ===', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Driver: bjc-MULTIPASS-MP500', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: L: 10 T: 9 W: 575 H: 818', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Page: 595x842', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Conversion: traditional', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (PageSize) (2) (String) [A4]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (MediaType) (2) (String) [Plain]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (InputSlot) (2) (String) [SelectKey]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (InkType) (2) (String) [CMYK]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (PrintingMode) (2) (String) [Color]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (InkSet) (2) (String) [None]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (Duplex) (2) (String) [None]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (Quality) (2) (String) [Standard]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (ColorCorrection) (2) (String) [None]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (ChannelBitDepth) (2) (String) [16]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (InputImageType) (2) (String) [RGB]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (DitherAlgorithm) (2) (String) [None]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (ImageType) (2) (String) [TextGraphics]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (JobMode) (2) (String) [Job]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (Resolution) (2) (String) [600x600dpi]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (STPIRawChannels) (2) (Int) [1]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (CUPSShrinkPage) (2) (Int) [1]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (FullBleed) (2) (Bool) [0]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (LinearContrast) (2) (Bool) [0]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (Brightness) (2) (Double) [1,000000]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (Contrast) (2) (Double) [1,000000]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (Saturation) (2) (Double) [1,000000]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (AppGamma) (2) (Double) [1,000000]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (CDOuterDiameter) (2) (Dimension) [329]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (CDInnerDiameter) (2) (Dimension) [121]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (CDXAdjustment) (2) (Dimension) [0]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (CDYAdjustment) (2) (Dimension) [0]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: === END GUTENPRINT SETTINGS ===', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Page data:', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: MediaClass = "SelectKey"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: MediaColor = ""', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: MediaType = "Plain"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: OutputType = ""', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: AdvanceDistance = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: AdvanceMedia = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Collate = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: CutMedia = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Duplex = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: HWResolution = [ 600 600 ]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: ImagingBoundingBox = [ 0 0 595 842 ]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: InsertSheet = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Jog = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: LeadingEdge = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Margins = [ 0 0 ]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: ManualFeed = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: MediaPosition = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: MediaWeight = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: MirrorPrint = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: NegativePrint = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: NumCopies = 1', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Orientation = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: OutputFaceUp = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: PageSize = [ 595 842 ]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Separations = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: TraySwitch = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Tumble = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: cupsWidth = 4958', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: cupsHeight = 7017', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: cups->width = 4958', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: cups->height = 7016', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: cups->adjusted_width = 4958', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: cups->adjusted_height = 7016', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: cupsMediaType = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: cupsBitsPerColor = 16', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: cupsBitsPerPixel = 48', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: cupsBytesPerLine = 29748', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: cupsColorOrder = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: cupsColorSpace = 1', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: cupsCompression = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: cupsRowCount = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: cupsRowFeed = 2', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: cupsRowStep = 0', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: shrink page to fit 1', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: === BEGIN GUTENPRINT SETTINGS ===', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Driver: bjc-MULTIPASS-MP500', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: L: 10 T: 9 W: 575 H: 818', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Page: 595x842', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Conversion: traditional', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (PageSize) (2) (String) [A4]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (MediaType) (2) (String) [Plain]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (InputSlot) (2) (String) [SelectKey]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (InkType) (2) (String) [CMYK]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (PrintingMode) (2) (String) [Color]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (InkSet) (2) (String) [None]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (Duplex) (2) (String) [None]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (Quality) (2) (String) [Standard]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (ColorCorrection) (2) (String) [None]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (ChannelBitDepth) (2) (String) [16]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (InputImageType) (2) (String) [RGB]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (DitherAlgorithm) (2) (String) [None]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (ImageType) (2) (String) [TextGraphics]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (JobMode) (2) (String) [Job]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (Resolution) (2) (String) [600x600dpi]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (STPIRawChannels) (2) (Int) [1]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (CUPSShrinkPage) (2) (Int) [1]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (PageNumber) (2) (Int) [0]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (FullBleed) (2) (Bool) [0]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (LinearContrast) (2) (Bool) [0]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (Brightness) (2) (Double) [1,000000]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (Contrast) (2) (Double) [1,000000]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (Saturation) (2) (Double) [1,000000]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (AppGamma) (2) (Double) [1,000000]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (Gamma) (1) (Double) [1,666667]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (CDOuterDiameter) (2) (Dimension) [329]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (CDInnerDiameter) (2) (Dimension) [121]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (CDXAdjustment) (2) (Dimension) [0]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: (CDYAdjustment) (2) (Dimension) [0]', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: === END GUTENPRINT SETTINGS ===', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: End page data', > 'I [15/Feb/2013:19:44:10 +0100] [Job 121] Starting page 1...', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Set job-printer-state-message to "Starting page 1...", current level=INFO', > 'D [15/Feb/2013:19:44:10 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Image_height 7016', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Image_width 4958', > 'D [15/Feb/2013:19:44:10 +0100] cupsdAcceptClient: skipping getpeercon()', > 'D [15/Feb/2013:19:44:10 +0100] cupsdAcceptClient: 19 from localhost (Domain)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdReadClient: 19 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:10 +0100] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1', > 'D [15/Feb/2013:19:44:10 +0100] CUPS-Get-Printers', > 'D [15/Feb/2013:19:44:10 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdReadClient: 19 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:10 +0100] cupsdReadClient: 19 1.1 CUPS-Get-Classes 1', > 'D [15/Feb/2013:19:44:10 +0100] CUPS-Get-Classes', > 'D [15/Feb/2013:19:44:10 +0100] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdReadClient: 19 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:10 +0100] cupsdReadClient: 19 1.1 CUPS-Get-Default 1', > 'D [15/Feb/2013:19:44:10 +0100] CUPS-Get-Default', > 'D [15/Feb/2013:19:44:10 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Image_width 4958', > 'D [15/Feb/2013:19:44:10 +0100] cupsdAcceptClient: skipping getpeercon()', > 'D [15/Feb/2013:19:44:10 +0100] cupsdAcceptClient: 20 from localhost (Domain)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdReadClient: 20 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:10 +0100] cupsdReadClient: 20 1.1 Get-Notifications 1', > 'D [15/Feb/2013:19:44:10 +0100] Get-Notifications /', > 'D [15/Feb/2013:19:44:10 +0100] cupsdIsAuthorized: requesting-user-name="root"', > 'D [15/Feb/2013:19:44:10 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdReadClient: 20 WAITING Closing on EOF', > 'D [15/Feb/2013:19:44:10 +0100] cupsdCloseClient: 20', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdReadClient: 16 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:10 +0100] cupsdReadClient: 16 1.1 Get-Notifications 1', > 'D [15/Feb/2013:19:44:10 +0100] Get-Notifications /', > 'D [15/Feb/2013:19:44:10 +0100] cupsdIsAuthorized: requesting-user-name="root"', > 'D [15/Feb/2013:19:44:10 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Gutenprint: Image_width 4958', > 'I [15/Feb/2013:19:44:10 +0100] [Job 121] Printing page 1, 1%', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 1%", current level=INFO', > 'D [15/Feb/2013:19:44:10 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:10 +0100] [Job 121] ATTR: job-media-progress=1', > 'D [15/Feb/2013:19:44:10 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:11 +0100] [Job 121] Printing page 1, 2%', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 2%", current level=INFO', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] ATTR: job-media-progress=2', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdReadClient: 19 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:11 +0100] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1', > 'D [15/Feb/2013:19:44:11 +0100] CUPS-Get-Printers', > 'D [15/Feb/2013:19:44:11 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdReadClient: 19 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:11 +0100] cupsdReadClient: 19 1.1 CUPS-Get-Classes 1', > 'D [15/Feb/2013:19:44:11 +0100] CUPS-Get-Classes', > 'D [15/Feb/2013:19:44:11 +0100] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdReadClient: 19 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:11 +0100] cupsdReadClient: 19 1.1 CUPS-Get-Default 1', > 'D [15/Feb/2013:19:44:11 +0100] CUPS-Get-Default', > 'D [15/Feb/2013:19:44:11 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'I [15/Feb/2013:19:44:11 +0100] [Job 121] Printing page 1, 3%', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 3%", current level=INFO', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] ATTR: job-media-progress=3', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:11 +0100] [Job 121] Printing page 1, 4%', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 4%", current level=INFO', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] ATTR: job-media-progress=4', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:11 +0100] [Job 121] Printing page 1, 5%', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 5%", current level=INFO', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] ATTR: job-media-progress=5', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:11 +0100] [Job 121] Printing page 1, 6%', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 6%", current level=INFO', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] ATTR: job-media-progress=6', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:11 +0100] [Job 121] Printing page 1, 7%', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 7%", current level=INFO', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] ATTR: job-media-progress=7', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:11 +0100] [Job 121] Printing page 1, 8%', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 8%", current level=INFO', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] ATTR: job-media-progress=8', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:11 +0100] [Job 121] Printing page 1, 9%', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 9%", current level=INFO', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] ATTR: job-media-progress=9', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:11 +0100] [Job 121] Printing page 1, 10%', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 10%", current level=INFO', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] ATTR: job-media-progress=10', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:11 +0100] [Job 121] Printing page 1, 11%', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 11%", current level=INFO', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] ATTR: job-media-progress=11', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:11 +0100] [Job 121] Printing page 1, 12%', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 12%", current level=INFO', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] ATTR: job-media-progress=12', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:11 +0100] [Job 121] Printing page 1, 13%', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 13%", current level=INFO', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] ATTR: job-media-progress=13', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:11 +0100] [Job 121] Printing page 1, 14%', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 14%", current level=INFO', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] ATTR: job-media-progress=14', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:11 +0100] [Job 121] Printing page 1, 15%', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 15%", current level=INFO', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] ATTR: job-media-progress=15', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:11 +0100] [Job 121] Printing page 1, 16%', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 16%", current level=INFO', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:11 +0100] [Job 121] ATTR: job-media-progress=16', > 'D [15/Feb/2013:19:44:11 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:11 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:12 +0100] [Job 121] Printing page 1, 17%', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 17%", current level=INFO', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] ATTR: job-media-progress=17', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:12 +0100] [Job 121] Printing page 1, 18%', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 18%", current level=INFO', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] ATTR: job-media-progress=18', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:12 +0100] [Job 121] Printing page 1, 19%', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 19%", current level=INFO', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] ATTR: job-media-progress=19', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:12 +0100] [Job 121] Printing page 1, 20%', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 20%", current level=INFO', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] ATTR: job-media-progress=20', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:12 +0100] [Job 121] Printing page 1, 21%', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 21%", current level=INFO', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] ATTR: job-media-progress=21', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:12 +0100] [Job 121] Printing page 1, 22%', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 22%", current level=INFO', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] ATTR: job-media-progress=22', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:12 +0100] [Job 121] Printing page 1, 23%', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 23%", current level=INFO', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] ATTR: job-media-progress=23', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:12 +0100] [Job 121] Printing page 1, 24%', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 24%", current level=INFO', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] ATTR: job-media-progress=24', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:12 +0100] [Job 121] Printing page 1, 25%', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 25%", current level=INFO', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] ATTR: job-media-progress=25', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:12 +0100] [Job 121] Printing page 1, 26%', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 26%", current level=INFO', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] ATTR: job-media-progress=26', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:12 +0100] [Job 121] Printing page 1, 27%', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 27%", current level=INFO', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] ATTR: job-media-progress=27', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:12 +0100] [Job 121] Printing page 1, 28%', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 28%", current level=INFO', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] ATTR: job-media-progress=28', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [15/Feb/2013:19:44:12 +0100] [Job 121] Printing page 1, 29%', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] Set job-printer-state-message to "Printing page 1, 29%", current level=INFO', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:12 +0100] [Job 121] ATTR: job-media-progress=29', > 'D [15/Feb/2013:19:44:12 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:13 +0100] cupsdAcceptClient: skipping getpeercon()', > 'D [15/Feb/2013:19:44:13 +0100] cupsdAcceptClient: 20 from localhost (Domain)', > 'D [15/Feb/2013:19:44:13 +0100] cupsdReadClient: 20 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:13 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:13 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:13 +0100] cupsdReadClient: 20 1.1 Get-Notifications 1', > 'D [15/Feb/2013:19:44:13 +0100] Get-Notifications /', > 'D [15/Feb/2013:19:44:13 +0100] cupsdIsAuthorized: requesting-user-name="root"', > 'D [15/Feb/2013:19:44:13 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [15/Feb/2013:19:44:13 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:13 +0100] cupsdReadClient: 20 WAITING Closing on EOF', > 'D [15/Feb/2013:19:44:13 +0100] cupsdCloseClient: 20', > 'D [15/Feb/2013:19:44:13 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:13 +0100] cupsdReadClient: 16 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:13 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:13 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:13 +0100] cupsdReadClient: 16 1.1 Get-Notifications 1', > 'D [15/Feb/2013:19:44:13 +0100] Get-Notifications /', > 'D [15/Feb/2013:19:44:13 +0100] cupsdIsAuthorized: requesting-user-name="root"', > 'D [15/Feb/2013:19:44:13 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [15/Feb/2013:19:44:13 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:13 +0100] cupsdReadClient: 19 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:13 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:13 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:13 +0100] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1', > 'D [15/Feb/2013:19:44:13 +0100] CUPS-Get-Printers', > 'D [15/Feb/2013:19:44:13 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [15/Feb/2013:19:44:13 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:13 +0100] cupsdReadClient: 19 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:13 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:13 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:13 +0100] cupsdReadClient: 19 1.1 CUPS-Get-Classes 1', > 'D [15/Feb/2013:19:44:13 +0100] CUPS-Get-Classes', > 'D [15/Feb/2013:19:44:13 +0100] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost', > 'D [15/Feb/2013:19:44:13 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:13 +0100] cupsdReadClient: 19 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:13 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:13 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:13 +0100] cupsdReadClient: 19 1.1 CUPS-Get-Default 1', > 'D [15/Feb/2013:19:44:13 +0100] CUPS-Get-Default', > 'D [15/Feb/2013:19:44:13 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [15/Feb/2013:19:44:13 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:15 +0100] [Job 121] libusb_get_device_list=14', > 'D [15/Feb/2013:19:44:15 +0100] [Job 121] Failed to open device, code: -3', > 'I [15/Feb/2013:19:44:15 +0100] [Job 121] Waiting for printer to become available.', > 'D [15/Feb/2013:19:44:15 +0100] [Job 121] Set job-printer-state-message to "Waiting for printer to become available.", current level=INFO', > 'D [15/Feb/2013:19:44:15 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:15 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:15 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:15 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:15 +0100] cupsdAcceptClient: skipping getpeercon()', > 'D [15/Feb/2013:19:44:15 +0100] cupsdAcceptClient: 20 from localhost (Domain)', > 'D [15/Feb/2013:19:44:15 +0100] cupsdReadClient: 20 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:15 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:15 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:15 +0100] cupsdReadClient: 20 1.1 Get-Notifications 1', > 'D [15/Feb/2013:19:44:15 +0100] Get-Notifications /', > 'D [15/Feb/2013:19:44:15 +0100] cupsdIsAuthorized: requesting-user-name="root"', > 'D [15/Feb/2013:19:44:15 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [15/Feb/2013:19:44:15 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:15 +0100] cupsdReadClient: 20 WAITING Closing on EOF', > 'D [15/Feb/2013:19:44:15 +0100] cupsdCloseClient: 20', > 'D [15/Feb/2013:19:44:15 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:15 +0100] cupsdReadClient: 16 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:15 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:15 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:15 +0100] cupsdReadClient: 16 1.1 Get-Notifications 1', > 'D [15/Feb/2013:19:44:15 +0100] Get-Notifications /', > 'D [15/Feb/2013:19:44:15 +0100] cupsdIsAuthorized: requesting-user-name="root"', > 'D [15/Feb/2013:19:44:15 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [15/Feb/2013:19:44:15 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:15 +0100] cupsdReadClient: 19 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:15 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:15 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:15 +0100] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1', > 'D [15/Feb/2013:19:44:15 +0100] CUPS-Get-Printers', > 'D [15/Feb/2013:19:44:15 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [15/Feb/2013:19:44:15 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:15 +0100] cupsdReadClient: 19 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:15 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:15 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:15 +0100] cupsdReadClient: 19 1.1 CUPS-Get-Classes 1', > 'D [15/Feb/2013:19:44:15 +0100] CUPS-Get-Classes', > 'D [15/Feb/2013:19:44:15 +0100] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost', > 'D [15/Feb/2013:19:44:15 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:15 +0100] cupsdReadClient: 19 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:15 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:15 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:15 +0100] cupsdReadClient: 19 1.1 CUPS-Get-Default 1', > 'D [15/Feb/2013:19:44:15 +0100] CUPS-Get-Default', > 'D [15/Feb/2013:19:44:15 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [15/Feb/2013:19:44:15 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:16 +0100] cupsdReadClient: 16 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:16 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:16 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:16 +0100] cupsdReadClient: 16 1.1 Get-Job-Attributes 1', > 'D [15/Feb/2013:19:44:16 +0100] Get-Job-Attributes ipp://localhost/jobs/121', > 'D [15/Feb/2013:19:44:16 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/121) from localhost', > 'D [15/Feb/2013:19:44:16 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:16 +0100] cupsdReadClient: 16 POST / HTTP/1.1', > 'D [15/Feb/2013:19:44:16 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:16 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:16 +0100] cupsdReadClient: 16 1.1 Cancel-Subscription 1', > 'D [15/Feb/2013:19:44:16 +0100] Cancel-Subscription /', > 'D [15/Feb/2013:19:44:16 +0100] cupsdIsAuthorized: requesting-user-name="root"', > 'D [15/Feb/2013:19:44:16 +0100] cupsdMarkDirty(-----S)', > 'D [15/Feb/2013:19:44:16 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:16 +0100] Returning IPP successful-ok for Cancel-Subscription (/) from localhost', > 'D [15/Feb/2013:19:44:16 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:16 +0100] cupsdReadClient: 16 PUT /admin/conf/cupsd.conf HTTP/1.1', > 'D [15/Feb/2013:19:44:16 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:16 +0100] cupsdAuthorize: No authentication data provided.', > 'D [15/Feb/2013:19:44:16 +0100] cupsdIsAuthorized: username=""', > 'D [15/Feb/2013:19:44:16 +0100] cupsdSendHeader: 16 WWW-Authenticate: Basic realm="CUPS", trc="y"', > 'D [15/Feb/2013:19:44:16 +0100] cupsdCloseClient: 16', > 'D [15/Feb/2013:19:44:16 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:16 +0100] cupsdAcceptClient: skipping getpeercon()', > 'D [15/Feb/2013:19:44:16 +0100] cupsdAcceptClient: 16 from localhost (Domain)', > 'D [15/Feb/2013:19:44:16 +0100] cupsdReadClient: 16 PUT /admin/conf/cupsd.conf HTTP/1.1', > 'D [15/Feb/2013:19:44:16 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:16 +0100] cupsdAuthorize: Authorized as root using PeerCred', > 'D [15/Feb/2013:19:44:16 +0100] cupsdIsAuthorized: username="root"', > 'I [15/Feb/2013:19:44:16 +0100] Installing config file "/etc/cups/cupsd.conf"...', > 'D [15/Feb/2013:19:44:17 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [15/Feb/2013:19:44:17 +0100] cupsdCloseClient: 17', > 'D [15/Feb/2013:19:44:17 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:17 +0100] cupsdCloseClient: 19', > 'D [15/Feb/2013:19:44:17 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:17 +0100] cupsdCloseClient: 16', > 'D [15/Feb/2013:19:44:17 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [15/Feb/2013:19:44:17 +0100] cupsdDeregisterPrinter(p=0x7f9d68b14840(MP500), removeit=1)', > 'I [15/Feb/2013:19:44:17 +0100] Generating printcap /etc/printcap...', > 'I [15/Feb/2013:19:44:17 +0100] Saving job.cache...', > 'I [15/Feb/2013:19:44:17 +0100] Saving subscriptions.conf...', > 'D [15/Feb/2013:19:44:17 +0100] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs and dirty files"', > 'E [15/Feb/2013:19:44:17 +0100] Unknown directive JobPrivateAccess on line 89 of /etc/cups/cupsd.conf.', > 'E [15/Feb/2013:19:44:17 +0100] Unknown directive JobPrivateValues on line 90 of /etc/cups/cupsd.conf.', > 'E [15/Feb/2013:19:44:17 +0100] Unknown directive SubscriptionPrivateAccess on line 91 of /etc/cups/cupsd.conf.', > 'E [15/Feb/2013:19:44:17 +0100] Unknown directive SubscriptionPrivateValues on line 92 of /etc/cups/cupsd.conf.', > "W [15/Feb/2013:19:44:17 +0100] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'MP500-Gray..' already exists", > "W [15/Feb/2013:19:44:17 +0100] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'MP500-RGB..' already exists", > "W [15/Feb/2013:19:44:17 +0100] failed to CreateDevice: org.freedesktop.ColorManager.AlreadyExists:device id 'cups-MP500' already exists"], > 'error_log_debug_logging_unset': True} >Page 11 (Locale issues): >{'printer_page_size': u'A4', > 'system_locale_lang': None, > 'user_locale_ctype': 'de_DE', > 'user_locale_messages': 'de_DE'}
<b>You cannot view the attachment while viewing its details because your browser does not support IFRAMEs. <a href="attachment.cgi?id=697960">View the attachment on a separate page</a>.</b>
View Attachment As Raw
Actions:
View
Attachments on
bug 911727
: 697960 |
699558
|
708100