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 891672 Details for
Bug 1093523
Unable to print a pdf file from evince on a Brother 2270DW
[?]
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.
troubleshooter output from printing test page
troubleshoottestpage.txt (text/plain), 172.99 KB, created by
Peter H. Jones
on 2014-05-02 01:00:43 UTC
(
hide
)
Description:
troubleshooter output from printing test page
Filename:
MIME Type:
Creator:
Peter H. Jones
Created:
2014-05-02 01:00:43 UTC
Size:
172.99 KB
patch
obsolete
>Page 1 (Scheduler not running?): >{'cups_connection_failure': False} >Page 2 (Is local server publishing?): >{'local_server_exporting_printers': False} >Page 3 (Choose printer): >{'cups_dest': <cups.Dest Brother-HL-2270DW-A-UQAM>, > 'cups_instance': None, > 'cups_queue': u'Brother-HL-2270DW-A-UQAM', > 'cups_queue_listed': True} >Page 4 (Check printer sanity): >{'cups_device_uri_scheme': u'usb', > 'cups_printer_dict': {'device-uri': u'usb://Brother/HL-2270DW%20series?serial=J3N458036', > 'printer-info': u'Brother HL-2270DW series', > 'printer-is-shared': True, > 'printer-location': u'localhost.localdomain', > 'printer-make-and-model': u'Brother HL2280DW for CUPS', > 'printer-state': 3, > 'printer-state-message': u'Sending data to printer.', > 'printer-state-reasons': [u'none'], > 'printer-type': 8392724, > 'printer-uri-supported': u'ipp://localhost:631/printers/Brother-HL-2270DW-A-UQAM'}, > 'cups_printer_remote': False, > 'is_cups_class': False, > 'local_cups_queue_attributes': {'charset-configured': u'utf-8', > 'charset-supported': [u'us-ascii', u'utf-8'], > 'color-supported': False, > 'compression-supported': [u'none', u'gzip'], > 'copies-default': 1, > 'copies-supported': (1, 9999), > 'cups-version': u'1.7.2', > 'device-uri': u'usb://Brother/HL-2270DW%20series?serial=J3N458036', > 'document-format-default': u'application/octet-stream', > 'document-format-supported': [u'application/octet-stream', > u'application/pdf', > u'application/postscript', > u'application/vnd.adobe-reader-postscript', > u'application/vnd.cups-command', > u'application/vnd.cups-pdf', > u'application/vnd.cups-pdf-banner', > u'application/vnd.cups-postscript', > 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'image/x-xwindowdump', > u'text/css', > u'text/html', > u'text/plain'], > 'finishings-default': 3, > 'finishings-supported': [3], > 'generated-natural-language-supported': [u'en-ca'], > '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'orientation-requested', > u'page-ranges', > u'print-color-mode', > 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, 20030100), > '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'orientation-requested', > u'page-ranges', > u'print-color-mode', > 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, 20030100), > 'jpeg-x-dimension-supported': (0, 65535), > 'jpeg-y-dimension-supported': (1, 65535), > 'marker-change-time': 0, > 'media-bottom-margin-supported': [423], > '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'na_letter_8.5x11in', > 'media-left-margin-supported': [635], > 'media-right-margin-supported': [635, > 600, > 1270], > 'media-source-supported': [u'manual', > u'tray1'], > 'media-supported': [u'na_letter_8.5x11in', > u'na_legal_8.5x14in', > u'na_executive_7.25x10.5in', > u'iso_a4_210x297mm', > u'iso_a5_148x210mm', > u'iso_a6_105x148mm', > u'na_number-10_4.125x9.5in', > u'na_monarch_3.875x7.5in', > u'iso_dl_110x220mm', > u'iso_c5_162x229mm', > u'om_env-isob5_176.04x250.12mm', > u'om_env-isob6_124.88x176.04mm'], > 'media-top-margin-supported': [423], > 'multiple-document-handling-supported': [u'separate-documents-uncollated-copies', > u'separate-documents-collated-copies'], > 'multiple-document-jobs-supported': True, > 'multiple-operation-time-out': 300, > 'natural-language-configured': u'en-ca', > '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, > 14], > 'orientation-requested-default': None, > 'orientation-requested-supported': [3, > 4, > 5, > 6], > 'output-bin-default': u'face-down', > 'output-bin-supported': [u'face-down'], > 'page-ranges-supported': True, > 'pages-per-minute': 18, > 'pdf-k-octets-supported': (0, 20030100), > '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'monochrome', > 'print-color-mode-supported': [u'monochrome'], > 'print-quality-default': 4, > 'print-quality-supported': [4], > 'printer-commands': [u'AutoConfigure', > u'Clean', > u'PrintSelfTestPage'], > 'printer-current-time': '(IPP_TAG_DATE)', > 'printer-dns-sd-name': None, > '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/Brother-HL-2270DW-A-UQAM.png', > 'printer-info': u'Brother HL-2270DW series', > 'printer-is-accepting-jobs': True, > 'printer-is-shared': True, > 'printer-location': u'localhost.localdomain', > 'printer-make-and-model': u'Brother HL2280DW for CUPS', > 'printer-more-info': u'http://localhost:631/printers/Brother-HL-2270DW-A-UQAM', > 'printer-name': u'Brother-HL-2270DW-A-UQAM', > 'printer-op-policy': u'default', > 'printer-op-policy-supported': [u'authenticated', > u'default'], > 'printer-resolution-default': (600, 600, 3), > 'printer-resolution-supported': [(600, > 600, > 3), > (2400, > 600, > 3)], > 'printer-settable-attributes-supported': [u'printer-info', > u'printer-location'], > 'printer-state': 3, > 'printer-state-change-time': 1398991337, > 'printer-state-message': u'Sending data to printer.', > 'printer-state-reasons': [u'none'], > 'printer-type': 8392724, > 'printer-up-time': 1398991420, > 'printer-uri-supported': [u'ipp://localhost:631/printers/Brother-HL-2270DW-A-UQAM'], > 'printer-uuid': u'urn:uuid:2dd2c960-29b9-3575-7b88-5fa417a19b4c', > 'queued-job-count': 0, > 'server-is-sharing-printers': False, > '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 5 (Check PPD sanity): >{'cups_printer_ppd_defaults': {u'General': {u'BrMediaType': u'PLAIN', > u'Duplex': u'None', > u'InputSlot': u'TRAY1', > u'PageRegion': u'Letter', > u'PageSize': u'Letter', > u'Resolution': u'600dpi', > u'Sleep': u'PrinterDefault', > u'TonerSaveMode': u'OFF'}}, > 'cups_printer_ppd_valid': True, > 'missing_pkgs_and_exes': ([], [])} >Page 6 (Local or remote?): >{'printer_is_remote': False} >Page 7 (Choose device): >{'cups_device_dict': {'device-class': 'direct', > 'device-id': 'MFG:Brother;CMD:PJL,PCL,PCLXL;MDL:HL-2270DW series;CLS:PRINTER;CID:Brother Laser Type1;', > 'device-info': 'Brother HL-2270DW series', > 'device-make-and-model': 'Brother HL-2270DW series'}} >Page 8 (Printer state reasons): >{'printer-state-message': u'Sending data to printer.', > 'printer-state-reasons': [u'none']} >Page 9 (Verify packages): >{'packages_verification': {'cups': ['..?...... /etc/cups/cupsd.conf.default', > '.M....... c /etc/cups/subscriptions.conf', > '..?...... /usr/lib/cups/backend/dnssd', > '..?...... /usr/lib/cups/backend/ipp', > '..?...... /usr/lib/cups/backend/lpd', > 'missing /var/spool/cups/tmp (Permission denied)'], > 'foomatic': [], > 'gutenprint': [], > 'hpijs': [], > 'hplip': ['..?...... /usr/lib/cups/backend/hpfax'], > 'system-config-printer': []}} >Page 10 (Error log checkpoint): >{'cups_server_settings': {'BrowseLocalProtocols': 'dnssd', > 'DefaultAuthType': 'Basic', > 'JobPrivateAccess': 'default', > 'JobPrivateValues': 'default', > 'MaxLogSize': '0', > 'SubscriptionPrivateAccess': 'default', > 'SubscriptionPrivateValues': 'default', > 'WebInterface': 'Yes', > '_debug_logging': '0', > '_remote_admin': '0', > '_remote_any': '0', > '_share_printers': '0', > '_user_cancel_any': '0'}, > 'error_log_checkpoint': 369130, > 'error_log_debug_logging_set': True} >Page 11 (Print test page): >{'test_page_attempted': '01/May/2014:20:44:54 +0000', > 'test_page_completions': [(81, u'Job completed.')], > 'test_page_job_id': [81], > 'test_page_job_status': [(True, > 81, > 'Brother-HL-2270DW-A-UQAM', > 'Test Page', > 'Completed', > {'attributes-charset': u'utf-8', > 'attributes-natural-language': u'en-ca', > 'document-format': u'application/vnd.cups-pdf-banner', > 'job-hold-until': u'no-hold', > 'job-id': 81, > 'job-k-octets': 1, > 'job-media-progress': 0, > 'job-media-sheets-completed': 0, > 'job-more-info': u'http://localhost:631/jobs/81', > 'job-preserved': True, > 'job-printer-state-message': u'', > 'job-printer-state-reasons': [u'none'], > 'job-printer-up-time': 1398991574, > 'job-printer-uri': u'ipp://localhost:631/printers/Brother-HL-2270DW-A-UQAM', > 'job-priority': 50, > 'job-sheets': [u'none', u'none'], > 'job-state': 9, > 'job-state-reasons': u'processing-to-stop-point', > 'job-uri': u'ipp://localhost:631/jobs/81', > 'job-uuid': u'urn:uuid:9a375a74-f0f1-34b2-7c90-093bcf345630', > 'number-of-documents': 1, > 'printer-uri': u'ipp://localhost/printers/Brother-HL-2270DW-A-UQAM', > 'time-at-completed': 1398991518, > 'time-at-creation': 1398991494, > 'time-at-processing': 1398991494})], > 'test_page_successful': True} >Page 12 (Error log fetch): >{'error_log': ['D [01/May/2014:20:44:31 -0400] [Client 12] Sending file.', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=369130, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=367082, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=365034, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=362986, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=360938, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=358890, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=356842, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=354794, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=352746, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=350698, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=348650, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=346602, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=344554, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=342506, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=340458, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=338410, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=336362, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=334314, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=332266, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=330218, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=328170, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=326122, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=324074, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=322026, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=319978, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=317930, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=315882, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=313834, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=311786, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=309738, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=307690, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=305642, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=303594, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=301546, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=299498, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=297450, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=295402, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=293354, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=291306, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=289258, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=287210, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=285162, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=283114, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=281066, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=279018, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=276970, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=274922, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=272874, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=270826, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=268778, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=266730, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=264682, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=262634, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=260586, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=258538, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=256490, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=254442, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=252394, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=250346, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=248298, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=246250, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=244202, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=242154, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=240106, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=238058, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=236010, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=233962, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=231914, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=229866, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=227818, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=225770, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=223722, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=221674, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=219626, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=217578, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=215530, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=213482, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=211434, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=209386, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=207338, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=205290, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=203242, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=201194, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=199146, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=197098, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=195050, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=193002, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=190954, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=188906, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=186858, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=184810, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=182762, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=180714, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=178666, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=176618, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=174570, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=172522, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=170474, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=168426, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=166378, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=164330, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=162282, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=160234, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=158186, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=156138, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=154090, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=152042, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=149994, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=147946, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=145898, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=143850, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=141802, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=139754, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=137706, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=135658, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=133610, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=131562, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=129514, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127466, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=125418, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=123370, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=121322, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=119274, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=117226, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=115178, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113130, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=111082, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=109034, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=106986, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=104938, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=102890, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=100842, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=98794, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=96746, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=94698, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=92650, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=90602, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=88554, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=86506, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=84458, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=82410, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=80362, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=78314, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=76266, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=74218, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=72170, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=70122, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=68074, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=66026, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=63978, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=61930, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=59882, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=57834, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=55786, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=53738, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=51690, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=49642, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=47594, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=45546, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=43498, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=41450, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=39402, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=37354, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=35306, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=33258, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=31210, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=29162, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=27114, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=25066, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=23018, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20970, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=18922, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=16874, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=14826, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=12778, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=10730, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=8682, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6634, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=4586, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2538, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=490, response=(nil)(), pipe_pid=0, file=14', > 'D [01/May/2014:20:44:31 -0400] [Client 12] Waiting for request.', > 'D [01/May/2014:20:44:31 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:44:31 -0400] [Client 13] POST / HTTP/1.1', > 'D [01/May/2014:20:44:31 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [01/May/2014:20:44:31 -0400] [Client 13] No authentication data provided.', > 'D [01/May/2014:20:44:31 -0400] [Client 13] 1.1 Get-Jobs 87', > 'D [01/May/2014:20:44:31 -0400] Get-Jobs ipp://localhost/printers/', > 'D [01/May/2014:20:44:31 -0400] [Client 13] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost', > 'D [01/May/2014:20:44:31 -0400] [Client 13] Content-Length: 75', > 'D [01/May/2014:20:44:31 -0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7fc3bb630220(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:31 -0400] [Client 13] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:31 -0400] [Client 13] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:31 -0400] [Client 13] Waiting for request.', > 'D [01/May/2014:20:44:31 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:44:31 -0400] [Client 13] POST / HTTP/1.1', > 'D [01/May/2014:20:44:31 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [01/May/2014:20:44:31 -0400] [Client 13] No authentication data provided.', > 'D [01/May/2014:20:44:31 -0400] [Client 13] 1.1 Get-Jobs 88', > 'D [01/May/2014:20:44:31 -0400] Get-Jobs ipp://localhost/printers/', > 'D [01/May/2014:20:44:31 -0400] [Job 66] Loading attributes...', > 'D [01/May/2014:20:44:31 -0400] [Job 67] Loading attributes...', > 'D [01/May/2014:20:44:31 -0400] [Job 68] Loading attributes...', > 'D [01/May/2014:20:44:31 -0400] [Job 69] Loading attributes...', > 'D [01/May/2014:20:44:31 -0400] [Job 70] Loading attributes...', > 'D [01/May/2014:20:44:31 -0400] [Job 71] Loading attributes...', > 'D [01/May/2014:20:44:31 -0400] [Job 72] Loading attributes...', > 'D [01/May/2014:20:44:31 -0400] [Job 73] Loading attributes...', > 'D [01/May/2014:20:44:31 -0400] [Job 74] Loading attributes...', > 'D [01/May/2014:20:44:31 -0400] [Job 75] Loading attributes...', > 'D [01/May/2014:20:44:31 -0400] [Job 76] Loading attributes...', > 'D [01/May/2014:20:44:31 -0400] [Job 77] Loading attributes...', > 'D [01/May/2014:20:44:31 -0400] [Job 78] Loading attributes...', > 'D [01/May/2014:20:44:31 -0400] [Job 79] Loading attributes...', > 'D [01/May/2014:20:44:31 -0400] [Job 80] Loading attributes...', > 'D [01/May/2014:20:44:31 -0400] [Client 13] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost', > 'D [01/May/2014:20:44:31 -0400] [Client 13] Content-Length: 1678', > 'D [01/May/2014:20:44:31 -0400] [Job 66] Removing document files.', > 'D [01/May/2014:20:44:31 -0400] [Job 67] Removing document files.', > 'D [01/May/2014:20:44:31 -0400] [Job 68] Removing document files.', > 'D [01/May/2014:20:44:31 -0400] [Job 69] Removing document files.', > 'D [01/May/2014:20:44:31 -0400] [Job 70] Removing document files.', > 'D [01/May/2014:20:44:31 -0400] [Job 71] Removing document files.', > 'D [01/May/2014:20:44:31 -0400] [Job 72] Removing document files.', > 'D [01/May/2014:20:44:31 -0400] [Job 73] Removing document files.', > 'D [01/May/2014:20:44:31 -0400] [Job 74] Removing document files.', > 'D [01/May/2014:20:44:31 -0400] [Job 75] Removing document files.', > 'D [01/May/2014:20:44:31 -0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1678, response=0x7fc3bb6a6920(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:31 -0400] [Client 13] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:31 -0400] [Client 13] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:31 -0400] [Client 13] Waiting for request.', > 'D [01/May/2014:20:44:31 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:44:31 -0400] [Client 13] POST / HTTP/1.1', > 'D [01/May/2014:20:44:31 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [01/May/2014:20:44:31 -0400] [Client 13] No authentication data provided.', > 'D [01/May/2014:20:44:31 -0400] [Client 13] 1.1 Create-Printer-Subscriptions 89', > 'D [01/May/2014:20:44:31 -0400] Create-Printer-Subscriptions /', > 'D [01/May/2014:20:44:31 -0400] create_subscriptions(con=0x7fc3bb6f4640(13), uri="/")', > 'D [01/May/2014:20:44:31 -0400] pullmethod="ippget"', > 'D [01/May/2014:20:44:31 -0400] notify-lease-duration=86400', > 'D [01/May/2014:20:44:31 -0400] notify-time-interval=0', > 'D [01/May/2014:20:44:31 -0400] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)")', > 'D [01/May/2014:20:44:31 -0400] Added subscription #104 for server.', > 'D [01/May/2014:20:44:31 -0400] cupsdMarkDirty(----S)', > 'D [01/May/2014:20:44:31 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:44:31 -0400] [Client 13] Returning IPP successful-ok for Create-Printer-Subscriptions (/) from localhost', > 'D [01/May/2014:20:44:31 -0400] [Client 13] Content-Length: 107', > 'D [01/May/2014:20:44:31 -0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=107, response=0x7fc3bb630220(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:31 -0400] [Client 13] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:31 -0400] [Client 13] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:31 -0400] [Client 13] Waiting for request.', > 'D [01/May/2014:20:44:31 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:44:32 -0400] [Client 13] POST / HTTP/1.1', > 'D [01/May/2014:20:44:32 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [01/May/2014:20:44:32 -0400] [Client 13] No authentication data provided.', > 'D [01/May/2014:20:44:32 -0400] [Client 13] 1.1 Get-Notifications 90', > 'D [01/May/2014:20:44:32 -0400] Get-Notifications /', > 'D [01/May/2014:20:44:32 -0400] cupsdIsAuthorized: requesting-user-name="jones"', > 'D [01/May/2014:20:44:32 -0400] [Client 13] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [01/May/2014:20:44:32 -0400] [Client 13] Content-Length: 127', > 'D [01/May/2014:20:44:32 -0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7fc3bb6a6920(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:32 -0400] [Client 13] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:32 -0400] [Client 13] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:32 -0400] [Client 13] Waiting for request.', > 'D [01/May/2014:20:44:32 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:44:54 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:44:54 -0400] [Client 14] Accepted from localhost (Domain)', > 'D [01/May/2014:20:44:54 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:44:54 -0400] [Client 14] POST /printers/Brother-HL-2270DW-A-UQAM HTTP/1.1', > 'D [01/May/2014:20:44:54 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [01/May/2014:20:44:54 -0400] [Client 14] No authentication data provided.', > 'D [01/May/2014:20:44:54 -0400] [Client 14] 1.1 Print-Job 91', > 'D [01/May/2014:20:44:54 -0400] Print-Job ipp://localhost/printers/Brother-HL-2270DW-A-UQAM', > 'D [01/May/2014:20:44:54 -0400] [Job ???] Auto-typing file...', > 'I [01/May/2014:20:44:54 -0400] [Job ???] Request file type is application/vnd.cups-pdf-banner.', > 'D [01/May/2014:20:44:54 -0400] add_job: setting context of job to UNKNOWN SL', > 'D [01/May/2014:20:44:54 -0400] cupsdMarkDirty(---J-)', > 'D [01/May/2014:20:44:54 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:44:54 -0400] add_job: requesting-user-name="jones"', > 'D [01/May/2014:20:44:54 -0400] Adding default job-sheets values "none,none"...', > 'I [01/May/2014:20:44:54 -0400] [Job 81] Adding start banner page "none".', > 'D [01/May/2014:20:44:54 -0400] cupsdMarkDirty(----S)', > 'D [01/May/2014:20:44:54 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:44:54 -0400] cupsdMarkDirty(---J-)', > 'D [01/May/2014:20:44:54 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'I [01/May/2014:20:44:54 -0400] [Job 81] Adding end banner page "none".', > 'I [01/May/2014:20:44:54 -0400] [Job 81] File of type application/vnd.cups-pdf-banner queued by "jones".', > 'D [01/May/2014:20:44:54 -0400] [Job 81] hold_until=0', > 'I [01/May/2014:20:44:54 -0400] [Job 81] Queued on "Brother-HL-2270DW-A-UQAM" by "jones".', > 'D [01/May/2014:20:44:54 -0400] [Job 81] time-at-processing=1398991494', > 'D [01/May/2014:20:44:54 -0400] cupsdMarkDirty(---J-)', > 'D [01/May/2014:20:44:54 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:44:54 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:44:54 -0400] cupsdMarkDirty(----S)', > 'D [01/May/2014:20:44:54 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] 4 filters for job:', > 'D [01/May/2014:20:44:54 -0400] [Job 81] bannertopdf (application/vnd.cups-pdf-banner to application/pdf, cost 32)', > 'D [01/May/2014:20:44:54 -0400] [Job 81] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)', > 'D [01/May/2014:20:44:54 -0400] [Job 81] pdftops (application/vnd.cups-pdf to application/vnd.cups-postscript, cost 100)', > 'D [01/May/2014:20:44:54 -0400] [Job 81] brlpdwrapperHL2280DW (application/vnd.cups-postscript to printer/Brother-HL-2270DW-A-UQAM, cost 0)', > 'D [01/May/2014:20:44:54 -0400] [Job 81] job-sheets=none,none', > 'D [01/May/2014:20:44:54 -0400] [Job 81] argv[0]="Brother-HL-2270DW-A-UQAM"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] argv[1]="81"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] argv[2]="jones"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] argv[3]="Test Page"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] argv[4]="1"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] argv[5]="job-uuid=urn:uuid:9a375a74-f0f1-34b2-7c90-093bcf345630 job-originating-host-name=localhost time-at-creation=1398991494 time-at-processing=1398991494"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] argv[6]="/var/spool/cups/d00081-001"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[0]="CUPS_CACHEDIR=/var/cache/cups"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[1]="CUPS_DATADIR=/usr/share/cups"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[6]="CUPS_SERVERROOT=/etc/cups"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[7]="CUPS_STATEDIR=/var/run/cups"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[8]="HOME=/var/spool/cups/tmp"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[9]="PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[10]="SERVER_ADMIN=root@localhost.localdomain"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[11]="SOFTWARE=CUPS/1.7.2"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[12]="TMPDIR=/var/spool/cups/tmp"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[13]="USER=root"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[14]="CUPS_MAX_MESSAGE=2047"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[16]="CUPS_ENCRYPTION=IfRequested"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[17]="IPP_PORT=631"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[18]="CHARSET=utf-8"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[19]="LANG=en_CA.UTF-8"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[20]="PPD=/etc/cups/ppd/Brother-HL-2270DW-A-UQAM.ppd"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[21]="RIP_MAX_CACHE=128m"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[22]="CONTENT_TYPE=application/vnd.cups-pdf-banner"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[23]="DEVICE_URI=usb://Brother/HL-2270DW%20series?serial=J3N458036"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[24]="PRINTER_INFO=Brother HL-2270DW series"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[25]="PRINTER_LOCATION=localhost.localdomain"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[26]="PRINTER=Brother-HL-2270DW-A-UQAM"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[27]="PRINTER_STATE_REASONS=none"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[28]="CUPS_FILETYPE=document"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[29]="FINAL_CONTENT_TYPE=printer/Brother-HL-2270DW-A-UQAM"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] envp[30]="AUTH_I****"', > 'I [01/May/2014:20:44:54 -0400] [Job 81] Started filter /usr/lib/cups/filter/bannertopdf (PID 6502)', > 'I [01/May/2014:20:44:54 -0400] [Job 81] Started filter /usr/lib/cups/filter/pdftopdf (PID 6503)', > 'I [01/May/2014:20:44:54 -0400] [Job 81] Started filter /usr/lib/cups/filter/pdftops (PID 6504)', > 'I [01/May/2014:20:44:54 -0400] [Job 81] Started filter /usr/lib/cups/filter/brlpdwrapperHL2280DW (PID 6505)', > 'I [01/May/2014:20:44:54 -0400] [Job 81] Started backend /usr/lib/cups/backend/usb (PID 6506)', > 'D [01/May/2014:20:44:54 -0400] cupsdMarkDirty(----S)', > 'D [01/May/2014:20:44:54 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:44:54 -0400] [Client 14] Returning IPP successful-ok for Print-Job (ipp://localhost/printers/Brother-HL-2270DW-A-UQAM) from localhost', > 'D [01/May/2014:20:44:54 -0400] [Client 14] Content-Length: 174', > 'D [01/May/2014:20:44:54 -0400] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=174, response=0x7fc3bb5d4620(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:54 -0400] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:54 -0400] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:54 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:44:54 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] pdftops - copying to temp print file "/var/spool/cups/tmp/cupseiCSEh"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] Loading USB quirks from "/usr/share/cups/usb".', > 'D [01/May/2014:20:44:54 -0400] [Job 81] Loaded 68 quirks.', > 'D [01/May/2014:20:44:54 -0400] [Job 81] Printing on printer with URI: usb://Brother/HL-2270DW%20series?serial=J3N458036', > 'D [01/May/2014:20:44:54 -0400] [Job 81] libusb_get_device_list=7', > 'D [01/May/2014:20:44:54 -0400] [Job 81] STATE: +connecting-to-device', > 'D [01/May/2014:20:44:54 -0400] cupsdMarkDirty(---J-)', > 'D [01/May/2014:20:44:54 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:54 -0400] cupsdMarkDirty(----S)', > 'D [01/May/2014:20:44:54 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] STATE: -connecting-to-device', > 'D [01/May/2014:20:44:54 -0400] cupsdMarkDirty(---J-)', > 'D [01/May/2014:20:44:54 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"', > 'D [01/May/2014:20:44:54 -0400] cupsdMarkDirty(----S)', > 'D [01/May/2014:20:44:54 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] Device protocol: 2', > 'I [01/May/2014:20:44:54 -0400] [Job 81] Sending data to printer.', > 'D [01/May/2014:20:44:54 -0400] cupsdMarkDirty(---J-)', > 'D [01/May/2014:20:44:54 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:54 -0400] [Job 81] Set job-printer-state-message to "Sending data to printer.", current level=INFO', > 'D [01/May/2014:20:44:54 -0400] cupsdMarkDirty(----S)', > 'D [01/May/2014:20:44:54 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:55 -0400] cupsdMarkDirty(----S)', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Job 81] PID 6502 (/usr/lib/cups/filter/bannertopdf) exited with no errors.', > "D [01/May/2014:20:44:55 -0400] [Job 81] Switching to Poppler's pdftops instead of Ghostscript for Brother, Minolta, and Konica Minolta to work around bugs in the printer's PS interpreters", > 'D [01/May/2014:20:44:55 -0400] [Job 81] Using image rendering resolution 600 dpi', > 'D [01/May/2014:20:44:55 -0400] [Job 81] Started filter pdftops (PID 6526)', > 'D [01/May/2014:20:44:55 -0400] [Job 81] PID 6503 (/usr/lib/cups/filter/pdftopdf) exited with no errors.', > 'D [01/May/2014:20:44:55 -0400] [Job 81] Started filter pstops (PID 6527)', > 'D [01/May/2014:20:44:55 -0400] [Job 81] Page = 612x792; 18,12 to 594,780', > 'D [01/May/2014:20:44:55 -0400] [Job 81] slow_collate=0, slow_duplex=0, slow_order=0', > 'D [01/May/2014:20:44:55 -0400] [Job 81] Before copy_comments - %!PS-Adobe-3.0', > 'D [01/May/2014:20:44:55 -0400] [Job 81] %!PS-Adobe-3.0', > 'D [01/May/2014:20:44:55 -0400] [Job 81] %Produced by poppler pdftops version: 0.24.3 (http://poppler.freedesktop.org)', > 'D [01/May/2014:20:44:55 -0400] [Job 81] %%Creator: cairo 1.10.2 (http://cairographics.org)', > 'D [01/May/2014:20:44:55 -0400] [Job 81] %%LanguageLevel: 3', > 'D [01/May/2014:20:44:55 -0400] [Job 81] %%DocumentSuppliedResources: (atend)', > 'D [01/May/2014:20:44:55 -0400] [Job 81] %%DocumentMedia: 612x792 612 792 0 () ()', > 'D [01/May/2014:20:44:55 -0400] [Job 81] %%BoundingBox: 0 0 612 792', > 'D [01/May/2014:20:44:55 -0400] [Job 81] %%Pages: 1', > 'D [01/May/2014:20:44:55 -0400] [Job 81] %%EndComments', > 'D [01/May/2014:20:44:55 -0400] [Job 81] Before copy_prolog - %%BeginProlog', > 'D [01/May/2014:20:44:55 -0400] [Job 81] Before copy_setup - %%BeginSetup', > 'D [01/May/2014:20:44:55 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:44:55 -0400] [Client 16] Accepted from localhost (Domain)', > 'D [01/May/2014:20:44:55 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:44:55 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:44:55 -0400] [Client 17] Accepted from localhost (Domain)', > 'D [01/May/2014:20:44:55 -0400] [Client 17] Waiting for request.', > 'D [01/May/2014:20:44:55 -0400] [Client 16] POST / HTTP/1.1', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 16] No authentication data provided.', > 'D [01/May/2014:20:44:55 -0400] [Client 16] 1.1 Get-Notifications 60', > 'D [01/May/2014:20:44:55 -0400] Get-Notifications /', > 'D [01/May/2014:20:44:55 -0400] cupsdIsAuthorized: requesting-user-name="jones"', > 'D [01/May/2014:20:44:55 -0400] [Client 16] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [01/May/2014:20:44:55 -0400] [Client 16] Content-Length: 3683', > 'D [01/May/2014:20:44:55 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3683, response=0x7fc3bb70ee70(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:55 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:55 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:55 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 17] POST / HTTP/1.1', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 17] No authentication data provided.', > 'D [01/May/2014:20:44:55 -0400] [Client 17] 1.1 Get-Notifications 92', > 'D [01/May/2014:20:44:55 -0400] Get-Notifications /', > 'D [01/May/2014:20:44:55 -0400] cupsdIsAuthorized: requesting-user-name="jones"', > 'D [01/May/2014:20:44:55 -0400] [Client 17] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [01/May/2014:20:44:55 -0400] [Client 17] Content-Length: 2021', > 'D [01/May/2014:20:44:55 -0400] [Client 16] POST / HTTP/1.1', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 16] No authentication data provided.', > 'D [01/May/2014:20:44:55 -0400] [Client 16] 1.1 Get-Job-Attributes 61', > 'D [01/May/2014:20:44:55 -0400] Get-Job-Attributes ipp://localhost/jobs/81', > 'D [01/May/2014:20:44:55 -0400] [Client 16] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost', > 'D [01/May/2014:20:44:55 -0400] [Client 16] Content-Length: 851', > 'D [01/May/2014:20:44:55 -0400] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2021, response=0x7fc3bb6f9a40(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:55 -0400] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:55 -0400] [Client 17] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:55 -0400] [Client 17] Waiting for request.', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=851, response=0x7fc3bb710a90(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:55 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:55 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:55 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [01/May/2014:20:44:55 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:44:55 -0400] [Client 18] Accepted from localhost (Domain)', > 'D [01/May/2014:20:44:55 -0400] [Client 18] Waiting for request.', > 'D [01/May/2014:20:44:55 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:44:55 -0400] [Client 19] Accepted from localhost (Domain)', > 'D [01/May/2014:20:44:55 -0400] [Client 19] Waiting for request.', > 'D [01/May/2014:20:44:55 -0400] [Client 18] POST / HTTP/1.1', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 18] No authentication data provided.', > 'D [01/May/2014:20:44:55 -0400] [Client 18] 1.1 Get-Printer-Attributes 62', > 'D [01/May/2014:20:44:55 -0400] Get-Printer-Attributes', > 'D [01/May/2014:20:44:55 -0400] Get-Printer-Attributes client-error-not-found: The printer or class does not exist.', > 'D [01/May/2014:20:44:55 -0400] [Client 18] Returning IPP client-error-not-found for Get-Printer-Attributes () from localhost', > 'D [01/May/2014:20:44:55 -0400] [Client 18] Content-Length: 130', > 'D [01/May/2014:20:44:55 -0400] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=130, response=0x7fc3bb7175e0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:55 -0400] [Client 18] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:55 -0400] [Client 18] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:55 -0400] [Client 18] Waiting for request.', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 19] POST / HTTP/1.1', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 19] No authentication data provided.', > 'D [01/May/2014:20:44:55 -0400] [Client 19] 1.1 Get-Printer-Attributes 93', > 'D [01/May/2014:20:44:55 -0400] Get-Printer-Attributes ipp://localhost/printers/Brother-HL-2270DW-A-UQAM', > 'D [01/May/2014:20:44:55 -0400] [Client 19] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Brother-HL-2270DW-A-UQAM) from localhost', > 'D [01/May/2014:20:44:55 -0400] [Client 19] Content-Length: 7228', > 'D [01/May/2014:20:44:55 -0400] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7228, response=0x7fc3bb6f9a40(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:55 -0400] [Client 19] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:55 -0400] [Client 19] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:55 -0400] [Client 19] Waiting for request.', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [01/May/2014:20:44:55 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:44:55 -0400] [Client 20] Accepted from localhost (Domain)', > 'D [01/May/2014:20:44:55 -0400] [Client 20] Waiting for request.', > 'D [01/May/2014:20:44:55 -0400] [Client 14] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Closing connection.', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 20] POST / HTTP/1.1', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 20] No authentication data provided.', > 'D [01/May/2014:20:44:55 -0400] [Client 20] 1.1 Get-Job-Attributes 63', > 'D [01/May/2014:20:44:55 -0400] Get-Job-Attributes ipp://localhost/jobs/81', > 'D [01/May/2014:20:44:55 -0400] [Client 20] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost', > 'D [01/May/2014:20:44:55 -0400] [Client 20] Content-Length: 192', > 'D [01/May/2014:20:44:55 -0400] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=192, response=0x7fc3bb6fed80(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:55 -0400] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:55 -0400] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:55 -0400] [Client 20] Waiting for request.', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 20] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:44:55 -0400] [Client 20] Closing connection.', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 18] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:44:55 -0400] [Client 18] Closing connection.', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:55 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Accepted from localhost (Domain)', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:44:55 -0400] [Client 14] POST / HTTP/1.1', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 14] No authentication data provided.', > 'D [01/May/2014:20:44:55 -0400] [Client 14] 1.1 Get-Job-Attributes 64', > 'D [01/May/2014:20:44:55 -0400] Get-Job-Attributes ipp://localhost/jobs/81', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Content-Length: 101', > 'D [01/May/2014:20:44:55 -0400] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=101, response=0x7fc3bb6fed80(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:55 -0400] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 14] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Closing connection.', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:55 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Accepted from localhost (Domain)', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:44:55 -0400] [Client 14] POST / HTTP/1.1', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 14] No authentication data provided.', > 'D [01/May/2014:20:44:55 -0400] [Client 14] 1.1 Get-Job-Attributes 65', > 'D [01/May/2014:20:44:55 -0400] Get-Job-Attributes ipp://localhost/jobs/81', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Content-Length: 101', > 'D [01/May/2014:20:44:55 -0400] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=101, response=0x7fc3bb6fed80(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:55 -0400] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 14] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Closing connection.', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:55 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Accepted from localhost (Domain)', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:44:55 -0400] [Client 14] POST / HTTP/1.1', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 14] No authentication data provided.', > 'D [01/May/2014:20:44:55 -0400] [Client 14] 1.1 Get-Job-Attributes 66', > 'D [01/May/2014:20:44:55 -0400] Get-Job-Attributes ipp://localhost/jobs/81', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Content-Length: 101', > 'D [01/May/2014:20:44:55 -0400] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=101, response=0x7fc3bb6fed80(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:55 -0400] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 14] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:44:55 -0400] [Client 14] Closing connection.', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:44:55 -0400] [Client 16] Closing connection.', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 19] POST / HTTP/1.1', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 19] No authentication data provided.', > 'D [01/May/2014:20:44:55 -0400] [Client 19] 1.1 Get-Printer-Attributes 94', > 'D [01/May/2014:20:44:55 -0400] Get-Printer-Attributes ipp://localhost/printers/Brother-HL-2270DW-A-UQAM', > 'D [01/May/2014:20:44:55 -0400] [Client 19] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Brother-HL-2270DW-A-UQAM) from localhost', > 'D [01/May/2014:20:44:55 -0400] [Client 19] Content-Length: 7228', > 'D [01/May/2014:20:44:55 -0400] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7228, response=0x7fc3bb6f9a40(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:55 -0400] [Client 19] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:55 -0400] [Client 19] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:55 -0400] [Client 19] Waiting for request.', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 19] POST / HTTP/1.1', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 19] No authentication data provided.', > 'D [01/May/2014:20:44:55 -0400] [Client 19] 1.1 Get-Printer-Attributes 95', > 'D [01/May/2014:20:44:55 -0400] Get-Printer-Attributes ipp://localhost/printers/Brother-HL-2270DW-A-UQAM', > 'D [01/May/2014:20:44:55 -0400] [Client 19] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Brother-HL-2270DW-A-UQAM) from localhost', > 'D [01/May/2014:20:44:55 -0400] [Client 19] Content-Length: 7228', > 'D [01/May/2014:20:44:55 -0400] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7228, response=0x7fc3bb6ff0d0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:55 -0400] [Client 19] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:55 -0400] [Client 19] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:55 -0400] [Client 19] Waiting for request.', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 19] POST / HTTP/1.1', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:55 -0400] [Client 19] No authentication data provided.', > 'D [01/May/2014:20:44:55 -0400] [Client 19] 1.1 Get-Printer-Attributes 96', > 'D [01/May/2014:20:44:55 -0400] Get-Printer-Attributes ipp://localhost/printers/Brother-HL-2270DW-A-UQAM', > 'D [01/May/2014:20:44:55 -0400] [Client 19] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Brother-HL-2270DW-A-UQAM) from localhost', > 'D [01/May/2014:20:44:55 -0400] [Client 19] Content-Length: 7228', > 'D [01/May/2014:20:44:55 -0400] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7228, response=0x7fc3bb6fed80(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:55 -0400] [Client 19] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:55 -0400] [Client 19] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:55 -0400] [Client 19] Waiting for request.', > 'D [01/May/2014:20:44:55 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [01/May/2014:20:44:56 -0400] [Client 17] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:44:56 -0400] [Client 17] Closing connection.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [01/May/2014:20:44:56 -0400] Generating printcap /etc/printcap...', > 'I [01/May/2014:20:44:56 -0400] Saving job.cache...', > 'I [01/May/2014:20:44:56 -0400] Saving subscriptions.conf...', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:44:56 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Accepted from localhost (Domain)', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] [Client 14] POST / HTTP/1.1', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 14] No authentication data provided.', > 'D [01/May/2014:20:44:56 -0400] [Client 14] 1.1 Get-Notifications 97', > 'D [01/May/2014:20:44:56 -0400] Get-Notifications /', > 'D [01/May/2014:20:44:56 -0400] cupsdIsAuthorized: requesting-user-name="jones"', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Content-Length: 3683', > 'D [01/May/2014:20:44:56 -0400] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3683, response=0x7fc3bb7066f0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:56 -0400] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 14] POST / HTTP/1.1', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 14] No authentication data provided.', > 'D [01/May/2014:20:44:56 -0400] [Client 14] 1.1 Get-Job-Attributes 98', > 'D [01/May/2014:20:44:56 -0400] Get-Job-Attributes ipp://localhost/jobs/81', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Content-Length: 851', > 'D [01/May/2014:20:44:56 -0400] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=851, response=0x7fc3bb6f9a40(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:56 -0400] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"', > 'D [01/May/2014:20:44:56 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Accepted from localhost (Domain)', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] [Client 16] POST / HTTP/1.1', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 16] No authentication data provided.', > 'D [01/May/2014:20:44:56 -0400] [Client 16] 1.1 Get-Job-Attributes 99', > 'D [01/May/2014:20:44:56 -0400] Get-Job-Attributes ipp://localhost/jobs/81', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Content-Length: 75', > 'D [01/May/2014:20:44:56 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7fc3bb70aaa0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:56 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Closing connection.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Accepted from localhost (Domain)', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] [Client 16] POST / HTTP/1.1', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 16] No authentication data provided.', > 'D [01/May/2014:20:44:56 -0400] [Client 16] 1.1 Get-Job-Attributes 100', > 'D [01/May/2014:20:44:56 -0400] Get-Job-Attributes ipp://localhost/jobs/81', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Content-Length: 75', > 'D [01/May/2014:20:44:56 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7fc3bb70aaa0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:56 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Closing connection.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Accepted from localhost (Domain)', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] [Client 16] POST / HTTP/1.1', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 16] No authentication data provided.', > 'D [01/May/2014:20:44:56 -0400] [Client 16] 1.1 Get-Job-Attributes 101', > 'D [01/May/2014:20:44:56 -0400] Get-Job-Attributes ipp://localhost/jobs/81', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Content-Length: 75', > 'D [01/May/2014:20:44:56 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7fc3bb70aaa0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:56 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Closing connection.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Accepted from localhost (Domain)', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] [Client 16] POST / HTTP/1.1', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 16] No authentication data provided.', > 'D [01/May/2014:20:44:56 -0400] [Client 16] 1.1 Get-Job-Attributes 102', > 'D [01/May/2014:20:44:56 -0400] Get-Job-Attributes ipp://localhost/jobs/81', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Content-Length: 75', > 'D [01/May/2014:20:44:56 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7fc3bb70aaa0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:56 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Closing connection.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 14] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Closing connection.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Accepted from localhost (Domain)', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] [Client 14] POST / HTTP/1.1', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 14] No authentication data provided.', > 'D [01/May/2014:20:44:56 -0400] [Client 14] 1.1 Get-Notifications 103', > 'D [01/May/2014:20:44:56 -0400] Get-Notifications /', > 'D [01/May/2014:20:44:56 -0400] cupsdIsAuthorized: requesting-user-name="jones"', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Content-Length: 3683', > 'D [01/May/2014:20:44:56 -0400] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3683, response=0x7fc3bb7066f0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:56 -0400] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 14] POST / HTTP/1.1', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 14] No authentication data provided.', > 'D [01/May/2014:20:44:56 -0400] [Client 14] 1.1 Get-Job-Attributes 104', > 'D [01/May/2014:20:44:56 -0400] Get-Job-Attributes ipp://localhost/jobs/81', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Content-Length: 851', > 'D [01/May/2014:20:44:56 -0400] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=851, response=0x7fc3bb6f9a40(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:56 -0400] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"', > 'D [01/May/2014:20:44:56 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Accepted from localhost (Domain)', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] [Client 16] POST / HTTP/1.1', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 16] No authentication data provided.', > 'D [01/May/2014:20:44:56 -0400] [Client 16] 1.1 Get-Printer-Attributes 105', > 'D [01/May/2014:20:44:56 -0400] Get-Printer-Attributes', > 'D [01/May/2014:20:44:56 -0400] Get-Printer-Attributes client-error-not-found: The printer or class does not exist.', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Returning IPP client-error-not-found for Get-Printer-Attributes () from localhost', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Content-Length: 130', > 'D [01/May/2014:20:44:56 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=130, response=0x7fc3bb70aaa0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:56 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Closing connection.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Accepted from localhost (Domain)', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] [Client 16] POST / HTTP/1.1', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 16] No authentication data provided.', > 'D [01/May/2014:20:44:56 -0400] [Client 16] 1.1 Get-Job-Attributes 106', > 'D [01/May/2014:20:44:56 -0400] Get-Job-Attributes ipp://localhost/jobs/81', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Content-Length: 101', > 'D [01/May/2014:20:44:56 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=101, response=0x7fc3bb70aaa0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:56 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Closing connection.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Accepted from localhost (Domain)', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] [Client 16] POST / HTTP/1.1', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 16] No authentication data provided.', > 'D [01/May/2014:20:44:56 -0400] [Client 16] 1.1 Get-Job-Attributes 107', > 'D [01/May/2014:20:44:56 -0400] Get-Job-Attributes ipp://localhost/jobs/81', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Content-Length: 101', > 'D [01/May/2014:20:44:56 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=101, response=0x7fc3bb70aaa0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:56 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Closing connection.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Accepted from localhost (Domain)', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] [Client 16] POST / HTTP/1.1', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 16] No authentication data provided.', > 'D [01/May/2014:20:44:56 -0400] [Client 16] 1.1 Get-Job-Attributes 108', > 'D [01/May/2014:20:44:56 -0400] Get-Job-Attributes ipp://localhost/jobs/81', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Content-Length: 101', > 'D [01/May/2014:20:44:56 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=101, response=0x7fc3bb70aaa0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:56 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:44:56 -0400] [Client 16] Closing connection.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 14] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Closing connection.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Accepted from localhost (Domain)', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] [Client 13] POST / HTTP/1.1', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 13] No authentication data provided.', > 'D [01/May/2014:20:44:56 -0400] [Client 13] 1.1 Get-Notifications 110', > 'D [01/May/2014:20:44:56 -0400] Get-Notifications /', > 'D [01/May/2014:20:44:56 -0400] cupsdIsAuthorized: requesting-user-name="jones"', > 'D [01/May/2014:20:44:56 -0400] [Client 13] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [01/May/2014:20:44:56 -0400] [Client 13] Content-Length: 1789', > 'D [01/May/2014:20:44:56 -0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1789, response=0x7fc3bb7066b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:56 -0400] [Client 13] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:56 -0400] [Client 13] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:56 -0400] [Client 13] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 14] POST / HTTP/1.1', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 14] No authentication data provided.', > 'D [01/May/2014:20:44:56 -0400] [Client 14] 1.1 CUPS-Get-Printers 109', > 'D [01/May/2014:20:44:56 -0400] CUPS-Get-Printers', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Content-Length: 2316', > 'D [01/May/2014:20:44:56 -0400] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2316, response=0x7fc3bb6f9a40(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:56 -0400] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 14] POST / HTTP/1.1', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 14] No authentication data provided.', > 'D [01/May/2014:20:44:56 -0400] [Client 14] 1.1 CUPS-Get-Classes 111', > 'D [01/May/2014:20:44:56 -0400] CUPS-Get-Classes', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Content-Length: 75', > 'D [01/May/2014:20:44:56 -0400] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7fc3bb707820(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:56 -0400] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 14] POST / HTTP/1.1', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:44:56 -0400] [Client 14] No authentication data provided.', > 'D [01/May/2014:20:44:56 -0400] [Client 14] 1.1 CUPS-Get-Default 112', > 'D [01/May/2014:20:44:56 -0400] CUPS-Get-Default', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Content-Length: 7393', > 'D [01/May/2014:20:44:56 -0400] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7393, response=0x7fc3bb6f9a40(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:44:56 -0400] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:44:56 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:44:56 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"', > 'D [01/May/2014:20:44:57 -0400] [Job 81] Before page loop - %%Page: 1 1', > 'D [01/May/2014:20:44:57 -0400] [Job 81] Copying page 1...', > 'D [01/May/2014:20:44:57 -0400] [Job 81] pagew = 576.0, pagel = 768.0', > 'D [01/May/2014:20:44:57 -0400] [Job 81] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792', > 'D [01/May/2014:20:44:57 -0400] [Job 81] PageLeft = 18.0, PageRight = 594.0', > 'D [01/May/2014:20:44:57 -0400] [Job 81] PageTop = 780.0, PageBottom = 12.0', > 'D [01/May/2014:20:44:57 -0400] [Job 81] PageWidth = 612.0, PageLength = 792.0', > 'D [01/May/2014:20:45:01 -0400] [Client 12] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:45:01 -0400] [Client 12] Closing connection.', > 'D [01/May/2014:20:45:01 -0400] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"', > 'D [01/May/2014:20:45:02 -0400] [Job 81] Wrote 1 pages...', > 'D [01/May/2014:20:45:02 -0400] [Job 81] PID 6527 (pstops) exited with no errors.', > 'D [01/May/2014:20:45:02 -0400] [Job 81] PID 6526 (pdftops) exited with no errors.', > 'D [01/May/2014:20:45:02 -0400] [Job 81] PID 6504 (/usr/lib/cups/filter/pdftops) exited with no errors.', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Read 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Wrote 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Read 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Wrote 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Read 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Wrote 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Read 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Wrote 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Read 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Wrote 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Read 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Wrote 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Read 8192 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Wrote 8192 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Read 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Wrote 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Read 8192 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Wrote 8192 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Read 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Wrote 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Read 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Wrote 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Read 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Wrote 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Read 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Wrote 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Read 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Wrote 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Read 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Wrote 4096 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Read 8192 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Wrote 8192 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Read 8192 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Wrote 8192 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Read 8192 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Wrote 8192 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Read 8192 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Wrote 8192 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Read 6168 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Wrote 6168 bytes of print data...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Sent 108568 bytes...', > 'D [01/May/2014:20:45:17 -0400] [Job 81] PID 6505 (/usr/lib/cups/filter/brlpdwrapperHL2280DW) exited with no errors.', > 'D [01/May/2014:20:45:17 -0400] [Job 81] Waiting for read thread to exit...', > 'D [01/May/2014:20:45:18 -0400] [Job 81] PID 6506 (/usr/lib/cups/backend/usb) exited with no errors.', > 'D [01/May/2014:20:45:18 -0400] [Job 81] time-at-completed=1398991518', > 'D [01/May/2014:20:45:18 -0400] cupsdMarkDirty(----S)', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs"', > 'I [01/May/2014:20:45:18 -0400] [Job 81] Job completed.', > 'D [01/May/2014:20:45:18 -0400] cupsdMarkDirty(---J-)', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:45:18 -0400] cupsdMarkDirty(----S)', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:45:18 -0400] cupsdMarkDirty(---J-)', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:45:18 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Accepted from localhost (Domain)', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Waiting for request.', > 'D [01/May/2014:20:45:18 -0400] [Client 12] POST / HTTP/1.1', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"', > 'D [01/May/2014:20:45:18 -0400] [Client 12] No authentication data provided.', > 'D [01/May/2014:20:45:18 -0400] [Client 12] 1.1 Get-Notifications 67', > 'D [01/May/2014:20:45:18 -0400] Get-Notifications /', > 'D [01/May/2014:20:45:18 -0400] cupsdIsAuthorized: requesting-user-name="jones"', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Content-Length: 1168', > 'D [01/May/2014:20:45:18 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1168, response=0x7fc3bb7044b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:45:18 -0400] [Client 12] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Waiting for request.', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:45:18 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:45:18 -0400] [Client 15] Accepted from localhost (Domain)', > 'D [01/May/2014:20:45:18 -0400] [Client 15] Waiting for request.', > 'D [01/May/2014:20:45:18 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:45:18 -0400] [Client 16] Accepted from localhost (Domain)', > 'D [01/May/2014:20:45:18 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:45:18 -0400] [Client 16] POST / HTTP/1.1', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [01/May/2014:20:45:18 -0400] [Client 16] No authentication data provided.', > 'D [01/May/2014:20:45:18 -0400] [Client 16] 1.1 Get-Notifications 113', > 'D [01/May/2014:20:45:18 -0400] Get-Notifications /', > 'D [01/May/2014:20:45:18 -0400] cupsdIsAuthorized: requesting-user-name="jones"', > 'D [01/May/2014:20:45:18 -0400] [Client 16] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [01/May/2014:20:45:18 -0400] [Client 16] Content-Length: 601', > 'D [01/May/2014:20:45:18 -0400] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=601, response=0x7fc3bb7044b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:45:18 -0400] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:45:18 -0400] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:45:18 -0400] [Client 16] Waiting for request.', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:45:18 -0400] [Client 15] POST / HTTP/1.1', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [01/May/2014:20:45:18 -0400] [Client 15] No authentication data provided.', > 'D [01/May/2014:20:45:18 -0400] [Client 19] POST / HTTP/1.1', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:45:18 -0400] [Client 19] No authentication data provided.', > 'D [01/May/2014:20:45:18 -0400] [Client 19] 1.1 Get-Printer-Attributes 114', > 'D [01/May/2014:20:45:18 -0400] Get-Printer-Attributes ipp://localhost/printers/Brother-HL-2270DW-A-UQAM', > 'D [01/May/2014:20:45:18 -0400] [Client 19] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Brother-HL-2270DW-A-UQAM) from localhost', > 'D [01/May/2014:20:45:18 -0400] [Client 19] Content-Length: 7228', > 'D [01/May/2014:20:45:18 -0400] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7228, response=0x7fc3bb6f90b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:45:18 -0400] [Client 19] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:45:18 -0400] [Client 19] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:45:18 -0400] [Client 19] Waiting for request.', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:45:18 -0400] [Client 15] 1.1 Get-Printer-Attributes 68', > 'D [01/May/2014:20:45:18 -0400] Get-Printer-Attributes ipp://localhost.localdomain/printers/Brother-HL-2270DW-A-UQAM', > 'D [01/May/2014:20:45:18 -0400] [Client 15] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost.localdomain/printers/Brother-HL-2270DW-A-UQAM) from localhost', > 'D [01/May/2014:20:45:18 -0400] [Client 15] Content-Length: 140', > 'D [01/May/2014:20:45:18 -0400] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=140, response=0x7fc3bb6f90b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:45:18 -0400] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:45:18 -0400] [Client 15] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:45:18 -0400] [Client 15] Waiting for request.', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:45:18 -0400] [Client 12] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Closing connection.', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [01/May/2014:20:45:18 -0400] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:45:18 -0400] [Client 16] Closing connection.', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [01/May/2014:20:45:18 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Accepted from localhost (Domain)', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Waiting for request.', > 'D [01/May/2014:20:45:18 -0400] [Client 12] POST / HTTP/1.1', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [01/May/2014:20:45:18 -0400] [Client 12] No authentication data provided.', > 'D [01/May/2014:20:45:18 -0400] [Client 12] 1.1 Get-Notifications 115', > 'D [01/May/2014:20:45:18 -0400] Get-Notifications /', > 'D [01/May/2014:20:45:18 -0400] cupsdIsAuthorized: requesting-user-name="jones"', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Content-Length: 1168', > 'D [01/May/2014:20:45:18 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1168, response=0x7fc3bb7044b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:45:18 -0400] [Client 12] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Waiting for request.', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:45:18 -0400] [Client 12] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Closing connection.', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [01/May/2014:20:45:18 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Accepted from localhost (Domain)', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Waiting for request.', > 'D [01/May/2014:20:45:18 -0400] [Client 12] POST / HTTP/1.1', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [01/May/2014:20:45:18 -0400] [Client 12] No authentication data provided.', > 'D [01/May/2014:20:45:18 -0400] [Client 12] 1.1 Get-Notifications 116', > 'D [01/May/2014:20:45:18 -0400] Get-Notifications /', > 'D [01/May/2014:20:45:18 -0400] cupsdIsAuthorized: requesting-user-name="jones"', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Content-Length: 1168', > 'D [01/May/2014:20:45:18 -0400] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1168, response=0x7fc3bb7044b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:45:18 -0400] [Client 12] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Waiting for request.', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:45:18 -0400] [Client 12] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:45:18 -0400] [Client 12] Closing connection.', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [01/May/2014:20:45:18 -0400] [Client 13] POST / HTTP/1.1', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [01/May/2014:20:45:18 -0400] [Client 13] No authentication data provided.', > 'D [01/May/2014:20:45:18 -0400] [Client 13] 1.1 Get-Notifications 117', > 'D [01/May/2014:20:45:18 -0400] Get-Notifications /', > 'D [01/May/2014:20:45:18 -0400] cupsdIsAuthorized: requesting-user-name="jones"', > 'D [01/May/2014:20:45:18 -0400] [Client 13] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [01/May/2014:20:45:18 -0400] [Client 13] Content-Length: 694', > 'D [01/May/2014:20:45:18 -0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=694, response=0x7fc3bb7042e0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:45:18 -0400] [Client 13] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:45:18 -0400] [Client 13] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:45:18 -0400] [Client 13] Waiting for request.', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:45:18 -0400] [Client 14] POST / HTTP/1.1', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [01/May/2014:20:45:18 -0400] [Client 14] No authentication data provided.', > 'D [01/May/2014:20:45:18 -0400] [Client 14] 1.1 CUPS-Get-Printers 118', > 'D [01/May/2014:20:45:18 -0400] CUPS-Get-Printers', > 'D [01/May/2014:20:45:18 -0400] [Client 14] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [01/May/2014:20:45:18 -0400] [Client 14] Content-Length: 2316', > 'D [01/May/2014:20:45:18 -0400] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2316, response=0x7fc3bb7044b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:45:18 -0400] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:45:18 -0400] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:45:18 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:45:18 -0400] [Client 14] POST / HTTP/1.1', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [01/May/2014:20:45:18 -0400] [Client 14] No authentication data provided.', > 'D [01/May/2014:20:45:18 -0400] [Client 14] 1.1 CUPS-Get-Classes 119', > 'D [01/May/2014:20:45:18 -0400] CUPS-Get-Classes', > 'D [01/May/2014:20:45:18 -0400] [Client 14] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost', > 'D [01/May/2014:20:45:18 -0400] [Client 14] Content-Length: 75', > 'D [01/May/2014:20:45:18 -0400] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7fc3bb707c70(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:45:18 -0400] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:45:18 -0400] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:45:18 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:45:18 -0400] [Client 14] POST / HTTP/1.1', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [01/May/2014:20:45:18 -0400] [Client 14] No authentication data provided.', > 'D [01/May/2014:20:45:18 -0400] [Client 14] 1.1 CUPS-Get-Default 120', > 'D [01/May/2014:20:45:18 -0400] CUPS-Get-Default', > 'D [01/May/2014:20:45:18 -0400] [Client 14] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [01/May/2014:20:45:18 -0400] [Client 14] Content-Length: 7393', > 'D [01/May/2014:20:45:18 -0400] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7393, response=0x7fc3bb7044b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:45:18 -0400] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:45:18 -0400] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:45:18 -0400] [Client 14] Waiting for request.', > 'D [01/May/2014:20:45:18 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:45:19 -0400] [Job 81] Unloading...', > 'I [01/May/2014:20:45:49 -0400] Saving job.cache...', > 'I [01/May/2014:20:45:49 -0400] Saving subscriptions.conf...', > 'D [01/May/2014:20:45:49 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"', > 'D [01/May/2014:20:45:49 -0400] [Job 66] Unloading...', > 'D [01/May/2014:20:45:49 -0400] [Job 67] Unloading...', > 'D [01/May/2014:20:45:49 -0400] [Job 68] Unloading...', > 'D [01/May/2014:20:45:49 -0400] [Job 69] Unloading...', > 'D [01/May/2014:20:45:49 -0400] [Job 70] Unloading...', > 'D [01/May/2014:20:45:49 -0400] [Job 71] Unloading...', > 'D [01/May/2014:20:45:49 -0400] [Job 72] Unloading...', > 'D [01/May/2014:20:45:49 -0400] [Job 73] Unloading...', > 'D [01/May/2014:20:45:49 -0400] [Job 74] Unloading...', > 'D [01/May/2014:20:45:49 -0400] [Job 75] Unloading...', > 'D [01/May/2014:20:45:49 -0400] [Job 76] Unloading...', > 'D [01/May/2014:20:45:49 -0400] [Job 77] Unloading...', > 'D [01/May/2014:20:45:49 -0400] [Job 78] Unloading...', > 'D [01/May/2014:20:45:49 -0400] [Job 79] Unloading...', > 'D [01/May/2014:20:45:49 -0400] [Job 80] Unloading...', > 'D [01/May/2014:20:45:49 -0400] Report: clients=4', > 'D [01/May/2014:20:45:49 -0400] Report: jobs=16', > 'D [01/May/2014:20:45:49 -0400] Report: jobs-active=0', > 'D [01/May/2014:20:45:49 -0400] Report: printers=5', > 'D [01/May/2014:20:45:49 -0400] Report: stringpool-string-count=24480', > 'D [01/May/2014:20:45:49 -0400] Report: stringpool-alloc-bytes=13176', > 'D [01/May/2014:20:45:49 -0400] Report: stringpool-total-bytes=429536', > 'D [01/May/2014:20:46:14 -0400] [Client 13] POST / HTTP/1.1', > 'D [01/May/2014:20:46:14 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [01/May/2014:20:46:14 -0400] [Client 13] No authentication data provided.', > 'D [01/May/2014:20:46:14 -0400] [Client 13] 1.1 Get-Job-Attributes 121', > 'D [01/May/2014:20:46:14 -0400] Get-Job-Attributes ipp://localhost/jobs/81', > 'D [01/May/2014:20:46:14 -0400] [Job 81] Loading attributes...', > 'D [01/May/2014:20:46:14 -0400] [Client 13] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost', > 'D [01/May/2014:20:46:14 -0400] [Client 13] Content-Length: 862', > 'D [01/May/2014:20:46:14 -0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=862, response=0x7fc3bb62f0f0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:46:14 -0400] [Client 13] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:46:14 -0400] [Client 13] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:46:14 -0400] [Client 13] Waiting for request.', > 'D [01/May/2014:20:46:14 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [01/May/2014:20:46:14 -0400] [Client 13] POST / HTTP/1.1', > 'D [01/May/2014:20:46:14 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [01/May/2014:20:46:14 -0400] [Client 13] No authentication data provided.', > 'D [01/May/2014:20:46:14 -0400] [Client 13] 1.1 Cancel-Subscription 122', > 'D [01/May/2014:20:46:14 -0400] Cancel-Subscription /', > 'D [01/May/2014:20:46:14 -0400] cupsdIsAuthorized: requesting-user-name="jones"', > 'D [01/May/2014:20:46:14 -0400] cupsdMarkDirty(----S)', > 'D [01/May/2014:20:46:14 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"', > 'D [01/May/2014:20:46:14 -0400] [Client 13] Returning IPP successful-ok for Cancel-Subscription (/) from localhost', > 'D [01/May/2014:20:46:14 -0400] [Client 13] Content-Length: 75', > 'D [01/May/2014:20:46:14 -0400] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7fc3bb7044e0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [01/May/2014:20:46:14 -0400] [Client 13] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [01/May/2014:20:46:14 -0400] [Client 13] bytes=0, http_state=0, data_remaining=0', > 'D [01/May/2014:20:46:14 -0400] [Client 13] Waiting for request.', > 'D [01/May/2014:20:46:14 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [01/May/2014:20:46:15 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:46:15 -0400] [Client 12] Accepted from localhost (Domain)', > 'D [01/May/2014:20:46:15 -0400] [Client 12] Waiting for request.', > 'I [01/May/2014:20:46:45 -0400] Saving subscriptions.conf...', > 'D [01/May/2014:20:46:45 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"', > 'D [01/May/2014:20:47:05 -0400] [Client 12] PUT /admin/conf/cupsd.conf HTTP/1.1', > 'D [01/May/2014:20:47:05 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [01/May/2014:20:47:05 -0400] [Client 12] No authentication data provided.', > 'D [01/May/2014:20:47:05 -0400] cupsdIsAuthorized: username=""', > 'D [01/May/2014:20:47:05 -0400] [Client 12] WWW-Authenticate: Basic realm="CUPS", trc="y"', > 'D [01/May/2014:20:47:05 -0400] [Client 12] Closing connection.', > 'D [01/May/2014:20:47:05 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [01/May/2014:20:47:05 -0400] Report: clients=4', > 'D [01/May/2014:20:47:05 -0400] Report: jobs=16', > 'D [01/May/2014:20:47:05 -0400] Report: jobs-active=0', > 'D [01/May/2014:20:47:05 -0400] Report: printers=5', > 'D [01/May/2014:20:47:05 -0400] Report: stringpool-string-count=24413', > 'D [01/May/2014:20:47:05 -0400] Report: stringpool-alloc-bytes=13856', > 'D [01/May/2014:20:47:05 -0400] Report: stringpool-total-bytes=428136', > 'D [01/May/2014:20:47:05 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:47:05 -0400] [Client 12] Accepted from localhost (Domain)', > 'D [01/May/2014:20:47:05 -0400] [Client 12] Waiting for request.', > 'D [01/May/2014:20:47:05 -0400] [Client 12] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:47:05 -0400] [Client 12] Closing connection.', > 'D [01/May/2014:20:47:05 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [01/May/2014:20:47:05 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:47:05 -0400] [Client 12] Accepted from localhost (Domain)', > 'D [01/May/2014:20:47:05 -0400] [Client 12] Waiting for request.', > 'D [01/May/2014:20:47:05 -0400] [Client 12] HTTP_STATE_WAITING Closing on EOF', > 'D [01/May/2014:20:47:05 -0400] [Client 12] Closing connection.', > 'D [01/May/2014:20:47:05 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [01/May/2014:20:47:05 -0400] cupsdAcceptClient: skipping getpeercon()', > 'D [01/May/2014:20:47:05 -0400] [Client 12] Accepted from localhost (Domain)', > 'D [01/May/2014:20:47:05 -0400] [Client 12] Waiting for request.', > 'D [01/May/2014:20:47:05 -0400] [Client 12] PUT /admin/conf/cupsd.conf HTTP/1.1', > 'D [01/May/2014:20:47:05 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [01/May/2014:20:47:05 -0400] [Client 12] Authorized as root using PeerCred', > 'D [01/May/2014:20:47:05 -0400] cupsdIsAuthorized: username="root"', > 'I [01/May/2014:20:47:05 -0400] Installing config file "/etc/cups/cupsd.conf"...', > 'D [01/May/2014:20:47:05 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [01/May/2014:20:47:05 -0400] [Client 13] Closing connection.', > 'D [01/May/2014:20:47:05 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [01/May/2014:20:47:05 -0400] [Client 19] Closing connection.', > 'D [01/May/2014:20:47:05 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [01/May/2014:20:47:05 -0400] [Client 14] Closing connection.', > 'D [01/May/2014:20:47:05 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [01/May/2014:20:47:05 -0400] [Client 15] Closing connection.', > 'D [01/May/2014:20:47:05 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [01/May/2014:20:47:05 -0400] [Client 12] Closing connection.', > 'D [01/May/2014:20:47:05 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'W [01/May/2014:20:47:05 -0400] Duplicate listen address "/var/run/cups/cups.sock" ignored.', > 'E [01/May/2014:20:47:05 -0400] Unknown directive JobPrivateAccess on line 84 of /etc/cups/cupsd.conf.', > 'E [01/May/2014:20:47:05 -0400] Unknown directive JobPrivateValues on line 85 of /etc/cups/cupsd.conf.', > 'E [01/May/2014:20:47:05 -0400] Unknown directive SubscriptionPrivateAccess on line 86 of /etc/cups/cupsd.conf.', > 'E [01/May/2014:20:47:05 -0400] Unknown directive SubscriptionPrivateValues on line 87 of /etc/cups/cupsd.conf.', > "W [01/May/2014:20:47:05 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Brother-HL-2040-series-Gray..' already exists", > "W [01/May/2014:20:47:05 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Brother-HL-2270DW-A-UQAM-Gray..' already exists", > "W [01/May/2014:20:47:05 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Deskjet-3510-USB-Gray..' already exists", > "W [01/May/2014:20:47:05 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Deskjet-3510-USB-RGB..' already exists", > "W [01/May/2014:20:47:05 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HL-2040-series-Gray..' already exists", > "W [01/May/2014:20:47:05 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP-Deskjet-3510-wifi-Gray..' already exists", > "W [01/May/2014:20:47:05 -0400] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP-Deskjet-3510-wifi-RGB..' already exists"], > 'error_log_debug_logging_unset': True} >Page 13 (Locale issues): >{'printer_page_size': u'Letter', > 'system_locale_lang': 'en_CA', > 'user_locale_ctype': 'en_CA', > 'user_locale_messages': 'en_CA'}
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 1093523
: 891672 |
891673
|
891674