I [09/Nov/2004:18:36:31 +0000] Listening to 0:631
D [09/Nov/2004:18:36:31 +0000] AddLocation: added location '/'
D [09/Nov/2004:18:36:31 +0000] DenyIP: / deny 00000000/00000000
D [09/Nov/2004:18:36:31 +0000] AllowIP: / allow 7f000001/ffffffff
D [09/Nov/2004:18:36:31 +0000] AddLocation: added location '/admin'
D [09/Nov/2004:18:36:31 +0000] DenyIP: /admin deny ffffffff/00000000
D [09/Nov/2004:18:36:31 +0000] AllowIP: /admin allow 00000000/00000000
I [09/Nov/2004:18:36:31 +0000] Loaded configuration file "/etc/cups/cupsd.conf"
I [09/Nov/2004:18:36:31 +0000] Configured for up to 100 clients.
I [09/Nov/2004:18:36:31 +0000] Allowing up to 100 client connections per host.
I [09/Nov/2004:18:36:31 +0000] Full reload is required.
D [09/Nov/2004:18:36:31 +0000] LoadAllPrinters: Loading printer L4...
d [09/Nov/2004:18:36:31 +0000] AddPrinter("L4")
d [09/Nov/2004:18:36:31 +0000] Adding filter application/vnd.cups-raw printer/L4 0 -
d [09/Nov/2004:18:36:31 +0000] FindBest: uri = "/printers/L4"...
d [09/Nov/2004:18:36:31 +0000] FindBest: Location / Limit 7f
d [09/Nov/2004:18:36:31 +0000] FindBest: Location /admin Limit 7f
d [09/Nov/2004:18:36:31 +0000] FindBest: best = "/"
d [09/Nov/2004:18:36:31 +0000] Adding filter application/vnd.cups-raster printer/L4 0 rastertohp
D [09/Nov/2004:18:36:31 +0000] LoadDevices: Added device "ipp"...
D [09/Nov/2004:18:36:31 +0000] LoadDevices: Added device "http"...
D [09/Nov/2004:18:36:31 +0000] LoadDevices: Added device "lpd"...
D [09/Nov/2004:18:36:31 +0000] LoadDevices: Added device "usb:/dev/usblp0"...
D [09/Nov/2004:18:36:31 +0000] LoadDevices: Added device "usb:/dev/usblp1"...
D [09/Nov/2004:18:36:31 +0000] LoadDevices: Added device "usb:/dev/usblp2"...
D [09/Nov/2004:18:36:31 +0000] LoadDevices: Added device "usb:/dev/usblp3"...
D [09/Nov/2004:18:36:31 +0000] LoadDevices: Added device "usb:/dev/usblp4"...
D [09/Nov/2004:18:36:31 +0000] LoadDevices: Added device "usb:/dev/usblp5"...
D [09/Nov/2004:18:36:31 +0000] LoadDevices: Added device "usb:/dev/usblp6"...
D [09/Nov/2004:18:36:31 +0000] LoadDevices: Added device "usb:/dev/usblp7"...
D [09/Nov/2004:18:36:31 +0000] LoadDevices: Added device "usb:/dev/usblp8"...
D [09/Nov/2004:18:36:31 +0000] LoadDevices: Added device "usb:/dev/usblp9"...
D [09/Nov/2004:18:36:31 +0000] LoadDevices: Added device "usb:/dev/usblp10"...
D [09/Nov/2004:18:36:31 +0000] LoadDevices: Added device "usb:/dev/usblp11"...
D [09/Nov/2004:18:36:31 +0000] LoadDevices: Added device "usb:/dev/usblp12"...
D [09/Nov/2004:18:36:31 +0000] LoadDevices: Added device "usb:/dev/usblp13"...
D [09/Nov/2004:18:36:31 +0000] LoadDevices: Added device "usb:/dev/usblp14"...
D [09/Nov/2004:18:36:31 +0000] LoadDevices: Added device "usb:/dev/usblp15"...
I [09/Nov/2004:18:36:31 +0000] LoadPPDs: Read "/etc/cups/ppds.dat", 12 PPDs...
I [09/Nov/2004:18:36:31 +0000] LoadPPDs: No new or changed PPDs...
D [09/Nov/2004:18:36:31 +0000] LoadAllJobs: Scanning /var/spool/cups...
D [09/Nov/2004:18:36:31 +0000] LoadAllJobs: Loading attributes for job 1...
D [09/Nov/2004:18:36:31 +0000] LoadAllJobs: Loading attributes for job 3...
D [09/Nov/2004:18:36:31 +0000] LoadAllJobs: Loading attributes for job 2...
I [09/Nov/2004:18:36:31 +0000] Full reload complete.
D [09/Nov/2004:18:36:31 +0000] StartListening: NumListeners=1
D [09/Nov/2004:18:36:31 +0000] StartListening: address=00000000 port=631
D [09/Nov/2004:18:36:31 +0000] ResumeListening: setting input bits...
d [09/Nov/2004:18:36:31 +0000] ResumeListening: Adding fd 0 to InputSet...
d [09/Nov/2004:18:36:31 +0000] StartBrowsing: Adding fd 2 to InputSet...
d [09/Nov/2004:18:36:31 +0000] StartServer: Adding fd 3 to InputSet...
d [09/Nov/2004:18:36:31 +0000] AddCert: adding certificate for pid 0
d [09/Nov/2004:18:37:02 +0000] AcceptClient(0x4b880) 0 NumClients = 0
D [09/Nov/2004:18:37:02 +0000] AcceptClient() 5 from localhost:631.
d [09/Nov/2004:18:37:02 +0000] AcceptClient: Adding fd 5 to InputSet...
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5, used=0
D [09/Nov/2004:18:37:02 +0000] ReadClient() 5 POST / HTTP/1.1
d [09/Nov/2004:18:37:02 +0000] decode_auth(0x401ed008): Authorization string = ""
d [09/Nov/2004:18:37:02 +0000] decode_auth() 5 username=""
d [09/Nov/2004:18:37:02 +0000] IsAuthorized: con->uri = "/"
d [09/Nov/2004:18:37:02 +0000] FindBest: uri = "/"...
d [09/Nov/2004:18:37:02 +0000] FindBest: Location / Limit 7f
d [09/Nov/2004:18:37:02 +0000] FindBest: Location /admin Limit 7f
d [09/Nov/2004:18:37:02 +0000] FindBest: best = "/"
d [09/Nov/2004:18:37:02 +0000] IsAuthorized: auth = 0, satisfy=0...
d [09/Nov/2004:18:37:02 +0000] POST /
d [09/Nov/2004:18:37:02 +0000] CONTENT_TYPE = application/ipp
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 137, con->file = 0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5, used=0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 129, con->file = 0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5, used=0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 95, con->file = 0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5, used=0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 61, con->file = 0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5, used=23
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 24, con->file = 0
d [09/Nov/2004:18:37:02 +0000] ProcessIPPRequest(0x401ed008[5]): operation_id = 4002
d [09/Nov/2004:18:37:02 +0000] get_printers(0x401ed008[5], 0)
d [09/Nov/2004:18:37:02 +0000] add_printer_state_reasons(0x401ed008[5], 0x57aa8[L4])
d [09/Nov/2004:18:37:02 +0000] add_queued_job_count(0x401ed008[5], 0x57aa8[L4])
d [09/Nov/2004:18:37:02 +0000] copy_attrs(0x5a7e8, 0x55600, 0x5a708, 0)
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5a7e8, 0x55758[printer-name,4,42])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5a7e8, 0x55960[job-sheets-default,4,42])
d [09/Nov/2004:18:37:02 +0000] copy_attrs(0x5a7e8, 0x52260, 0x5a708, 0)
D [09/Nov/2004:18:37:02 +0000] ProcessIPPRequest: 5 status_code=1
d [09/Nov/2004:18:37:02 +0000] ProcessIPPRequest: Adding fd 5 to OutputSet...
d [09/Nov/2004:18:37:02 +0000] WriteClient: Removing fd 5 from OutputSet...
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5, used=0
D [09/Nov/2004:18:37:02 +0000] CloseClient() 5
d [09/Nov/2004:18:37:02 +0000] CloseClient: Removing fd 5 from InputSet and OutputSet...
d [09/Nov/2004:18:37:02 +0000] AcceptClient(0x4b880) 0 NumClients = 0
D [09/Nov/2004:18:37:02 +0000] AcceptClient() 5 from localhost:631.
d [09/Nov/2004:18:37:02 +0000] AcceptClient: Adding fd 5 to InputSet...
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5, used=0
D [09/Nov/2004:18:37:02 +0000] ReadClient() 5 POST / HTTP/1.1
d [09/Nov/2004:18:37:02 +0000] decode_auth(0x401ed008): Authorization string = ""
d [09/Nov/2004:18:37:02 +0000] decode_auth() 5 username=""
d [09/Nov/2004:18:37:02 +0000] IsAuthorized: con->uri = "/"
d [09/Nov/2004:18:37:02 +0000] FindBest: uri = "/"...
d [09/Nov/2004:18:37:02 +0000] FindBest: Location / Limit 7f
d [09/Nov/2004:18:37:02 +0000] FindBest: Location /admin Limit 7f
d [09/Nov/2004:18:37:02 +0000] FindBest: best = "/"
d [09/Nov/2004:18:37:02 +0000] IsAuthorized: auth = 0, satisfy=0...
d [09/Nov/2004:18:37:02 +0000] POST /
d [09/Nov/2004:18:37:02 +0000] CONTENT_TYPE = application/ipp
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 137, con->file = 0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5, used=0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 129, con->file = 0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5, used=0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 95, con->file = 0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5, used=0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 61, con->file = 0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5, used=23
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 24, con->file = 0
d [09/Nov/2004:18:37:02 +0000] ProcessIPPRequest(0x401ed008[5]): operation_id = 4005
d [09/Nov/2004:18:37:02 +0000] get_printers(0x401ed008[5], 1)
D [09/Nov/2004:18:37:02 +0000] ProcessIPPRequest: 5 status_code=1
d [09/Nov/2004:18:37:02 +0000] ProcessIPPRequest: Adding fd 5 to OutputSet...
d [09/Nov/2004:18:37:02 +0000] WriteClient: Removing fd 5 from OutputSet...
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5, used=0
D [09/Nov/2004:18:37:02 +0000] CloseClient() 5
d [09/Nov/2004:18:37:02 +0000] CloseClient: Removing fd 5 from InputSet and OutputSet...
d [09/Nov/2004:18:37:02 +0000] AcceptClient(0x4b880) 0 NumClients = 0
D [09/Nov/2004:18:37:02 +0000] AcceptClient() 5 from localhost:631.
d [09/Nov/2004:18:37:02 +0000] AcceptClient: Adding fd 5 to InputSet...
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5, used=0
D [09/Nov/2004:18:37:02 +0000] ReadClient() 5 POST / HTTP/1.1
d [09/Nov/2004:18:37:02 +0000] decode_auth(0x401ed008): Authorization string = ""
d [09/Nov/2004:18:37:02 +0000] decode_auth() 5 username=""
d [09/Nov/2004:18:37:02 +0000] IsAuthorized: con->uri = "/"
d [09/Nov/2004:18:37:02 +0000] FindBest: uri = "/"...
d [09/Nov/2004:18:37:02 +0000] FindBest: Location / Limit 7f
d [09/Nov/2004:18:37:02 +0000] FindBest: Location /admin Limit 7f
d [09/Nov/2004:18:37:02 +0000] FindBest: best = "/"
d [09/Nov/2004:18:37:02 +0000] IsAuthorized: auth = 0, satisfy=0...
d [09/Nov/2004:18:37:02 +0000] POST /
d [09/Nov/2004:18:37:02 +0000] CONTENT_TYPE = application/ipp
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 77, con->file = 0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5, used=0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 69, con->file = 0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5, used=0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 35, con->file = 0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5, used=0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 1, con->file = 0
d [09/Nov/2004:18:37:02 +0000] ProcessIPPRequest(0x401ed008[5]): operation_id = 4001
d [09/Nov/2004:18:37:02 +0000] get_default(0x401ed008[5])
d [09/Nov/2004:18:37:02 +0000] copy_attrs(0x5e570, 0x55600, (nil), 0)
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x55628[printer-uri-supported,4,45])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x55690[uri-authentication-supported,4,44])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x55700[uri-security-supported,4,44])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x55758[printer-name,4,42])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x557a8[printer-location,4,41])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x557f8[printer-info,4,41])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x55848[printer-more-info,4,45])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x558a8[job-quota-period,4,21])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x558e8[job-k-limit,4,21])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x55920[job-page-limit,4,21])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x55960[job-sheets-default,4,42])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x559c8[device-uri,4,45])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x60f50[color-supported,4,22])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x55a28[pages-per-minute,4,21])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x55a78[printer-make-and-model,4,41])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x5e030[media-supported,4,44])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x5e2a0[media-default,4,44])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x5e2f0[sides-supported,4,44])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x5e388[sides-default,4,44])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x578f8[finishings-supported,4,23])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x5fde8[finishings-default,4,23])
d [09/Nov/2004:18:37:02 +0000] copy_attribute(0x5e570, 0x60e28[printer-type,4,23])
D [09/Nov/2004:18:37:02 +0000] ProcessIPPRequest: 5 status_code=0
d [09/Nov/2004:18:37:02 +0000] ProcessIPPRequest: Adding fd 5 to OutputSet...
d [09/Nov/2004:18:37:02 +0000] WriteClient: Removing fd 5 from OutputSet...
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5, used=0
D [09/Nov/2004:18:37:02 +0000] ReadClient() 5 POST /printers/L4 HTTP/1.1
d [09/Nov/2004:18:37:02 +0000] decode_auth(0x401ed008): Authorization string = ""
d [09/Nov/2004:18:37:02 +0000] decode_auth() 5 username=""
d [09/Nov/2004:18:37:02 +0000] IsAuthorized: con->uri = "/printers/L4"
d [09/Nov/2004:18:37:02 +0000] FindBest: uri = "/printers/L4"...
d [09/Nov/2004:18:37:02 +0000] FindBest: Location / Limit 7f
d [09/Nov/2004:18:37:02 +0000] FindBest: Location /admin Limit 7f
d [09/Nov/2004:18:37:02 +0000] FindBest: best = "/"
d [09/Nov/2004:18:37:02 +0000] IsAuthorized: auth = 0, satisfy=0...
d [09/Nov/2004:18:37:02 +0000] POST /printers/L4
d [09/Nov/2004:18:37:02 +0000] CONTENT_TYPE = application/ipp
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 7374, con->file = 0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5 REQUEST /var/spool/cups/00000000=7
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5 writing 1752 bytes to 7
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5, used=0
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5 con->data_encoding = length, con->data_remaining = 5376, con->file = 7
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5 writing 5376 bytes to 7
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5 Closing data file 7, size = 7128.
d [09/Nov/2004:18:37:02 +0000] ProcessIPPRequest(0x401ed008[5]): operation_id = 0002
d [09/Nov/2004:18:37:02 +0000] print_job(0x401ed008[5], ipp://localhost:631/printers/L4)
D [09/Nov/2004:18:37:02 +0000] print_job: auto-typing file...
D [09/Nov/2004:18:37:02 +0000] print_job: request file type is image/png.
d [09/Nov/2004:18:37:02 +0000] check_quotas(0x401ed008[5], 0x57aa8[L4])
D [09/Nov/2004:18:37:02 +0000] check_quotas: requesting-user-name = 'root'
D [09/Nov/2004:18:37:02 +0000] print_job: requesting-user-name = 'root'
I [09/Nov/2004:18:37:02 +0000] Adding start banner page "none" to job 4.
d [09/Nov/2004:18:37:02 +0000] copy_banner(0x401ed008[5], 0x5bdc8[4], none)
d [09/Nov/2004:18:37:02 +0000] add_file(con=0x401ed008[5], job=4, filetype=image/png, compression=0)
I [09/Nov/2004:18:37:02 +0000] Adding end banner page "none" to job 4.
d [09/Nov/2004:18:37:02 +0000] copy_banner(0x401ed008[5], 0x5bdc8[4], none)
I [09/Nov/2004:18:37:02 +0000] Job 4 queued on 'L4' by 'root'.
D [09/Nov/2004:18:37:02 +0000] Job 4 hold_until = 0
d [09/Nov/2004:18:37:02 +0000] SaveJob: Closing file 7...
D [09/Nov/2004:18:37:02 +0000] StartJob(4, 0x57aa8)
D [09/Nov/2004:18:37:02 +0000] StartJob() id = 4, file = 0/1
D [09/Nov/2004:18:37:02 +0000] job-sheets=none,none
D [09/Nov/2004:18:37:02 +0000] banner_page = 0
D [09/Nov/2004:18:37:02 +0000] StartJob: argv = "L4","4","root","png.png","1","","/var/spool/cups/d00004-001"
D [09/Nov/2004:18:37:02 +0000] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [09/Nov/2004:18:37:02 +0000] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [09/Nov/2004:18:37:02 +0000] StartJob: envp[2]="USER=root"
D [09/Nov/2004:18:37:02 +0000] StartJob: envp[3]="CHARSET=iso-8859-1"
D [09/Nov/2004:18:37:02 +0000] StartJob: envp[4]="LANG=en"
D [09/Nov/2004:18:37:02 +0000] StartJob: envp[5]="PPD=/etc/cups/ppd/L4.ppd"
D [09/Nov/2004:18:37:02 +0000] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [09/Nov/2004:18:37:02 +0000] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [09/Nov/2004:18:37:02 +0000] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [09/Nov/2004:18:37:02 +0000] StartJob: envp[9]="CONTENT_TYPE=image/png"
D [09/Nov/2004:18:37:02 +0000] StartJob: envp[10]="DEVICE_URI=http://192.158.96.41:631/ipp"
D [09/Nov/2004:18:37:02 +0000] StartJob: envp[11]="PRINTER=L4"
D [09/Nov/2004:18:37:02 +0000] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
D [09/Nov/2004:18:37:02 +0000] StartJob: envp[13]="CUPS_FONTPATH=/usr/local/share/ghostscript/fonts"
D [09/Nov/2004:18:37:02 +0000] StartJob: envp[14]="CUPS_SERVER=localhost"
D [09/Nov/2004:18:37:02 +0000] StartJob: envp[15]="IPP_PORT=631"
d [09/Nov/2004:18:37:02 +0000] StartJob: Allocating status buffer...
D [09/Nov/2004:18:37:02 +0000] StartJob: statusfds = [ 7 8 ]
D [09/Nov/2004:18:37:02 +0000] StartJob: filterfds[1] = [ 9 -1 ]
D [09/Nov/2004:18:37:02 +0000] StartJob: filter = "/usr/lib/cups/filter/imagetoraster"
D [09/Nov/2004:18:37:02 +0000] StartJob: filterfds[0] = [ 10 11 ]
D [09/Nov/2004:18:37:02 +0000] start_process("/usr/lib/cups/filter/imagetoraster", 0xbfff01f0, 0xbffef560, 9, 11, 8)
I [09/Nov/2004:18:37:02 +0000] Started filter /usr/lib/cups/filter/imagetoraster (PID 1284) for job 4.
D [09/Nov/2004:18:37:02 +0000] StartJob: filter = "/usr/lib/cups/filter/rastertohp"
D [09/Nov/2004:18:37:02 +0000] StartJob: filterfds[1] = [ 9 12 ]
D [09/Nov/2004:18:37:02 +0000] start_process("/usr/lib/cups/filter/rastertohp", 0xbfff01f0, 0xbffef560, 10, 12, 8)
I [09/Nov/2004:18:37:02 +0000] Started filter /usr/lib/cups/filter/rastertohp (PID 1285) for job 4.
D [09/Nov/2004:18:37:02 +0000] StartJob: backend = "/usr/lib/cups/backend/http"
D [09/Nov/2004:18:37:02 +0000] StartJob: filterfds[0] = [ -1 10 ]
D [09/Nov/2004:18:37:02 +0000] start_process("/usr/lib/cups/backend/http", 0xbfff01f0, 0xbffef560, 9, 10, 8)
I [09/Nov/2004:18:37:02 +0000] Started backend /usr/lib/cups/backend/http (PID 1286) for job 4.
d [09/Nov/2004:18:37:02 +0000] StartJob: Adding fd 7 to InputSet...
d [09/Nov/2004:18:37:02 +0000] add_job_state_reasons(0x401ed008[5], 4)
D [09/Nov/2004:18:37:02 +0000] ProcessIPPRequest: 5 status_code=0
d [09/Nov/2004:18:37:02 +0000] ProcessIPPRequest: Adding fd 5 to OutputSet...
d [09/Nov/2004:18:37:02 +0000] WriteClient: Removing fd 5 from OutputSet...
d [09/Nov/2004:18:37:02 +0000] ReadClient() 5, used=0
D [09/Nov/2004:18:37:02 +0000] CloseClient() 5
d [09/Nov/2004:18:37:02 +0000] CloseClient: Removing fd 5 from InputSet and OutputSet...
I [09/Nov/2004:18:37:03 +0000] [Job 4] L4 4 root png.png 1  /var/spool/cups/d00004-001
D [09/Nov/2004:18:37:03 +0000] [Job 4] Page = 612x792; 18,36 to 594,756
D [09/Nov/2004:18:37:03 +0000] [Job 4] Searching for profile "300dpi/"...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Loading image file...
D [09/Nov/2004:18:37:03 +0000] [Job 4] ImageOpen("/var/spool/cups/d00004-001", -1, -1, 100, 0, 0xbffff9d0)
D [09/Nov/2004:18:37:03 +0000] [Job 4] max_ics=10...
D [09/Nov/2004:18:37:03 +0000] [Job 4] Creating tile array (1x1)
D [09/Nov/2004:18:37:03 +0000] [Job 4] Allocated cache tile 1 (0x37118)...
D [09/Nov/2004:18:37:03 +0000] [Job 4] Clearing cache tile...
D [09/Nov/2004:18:37:03 +0000] [Job 4] Before scaling: xppi=128, yppi=128, zoom=0.00
D [09/Nov/2004:18:37:03 +0000] [Job 4] Before scaling: xprint=8.0, yprint=10.0
D [09/Nov/2004:18:37:03 +0000] [Job 4] Image size is 0.6 x 0.8 inches...
D [09/Nov/2004:18:37:03 +0000] [Job 4] Auto orientation...
D [09/Nov/2004:18:37:03 +0000] [Job 4] xpages = 1, ypages = 1
D [09/Nov/2004:18:37:03 +0000] [Job 4] PageSize = [612 792]
D [09/Nov/2004:18:37:03 +0000] [Job 4] cupsWidth = 2400
D [09/Nov/2004:18:37:03 +0000] [Job 4] cupsHeight = 3000
D [09/Nov/2004:18:37:03 +0000] [Job 4] cupsBitsPerColor = 1
D [09/Nov/2004:18:37:03 +0000] [Job 4] cupsBitsPerPixel = 1
D [09/Nov/2004:18:37:03 +0000] [Job 4] cupsBytesPerLine = 300
D [09/Nov/2004:18:37:03 +0000] [Job 4] cupsColorOrder = 0
D [09/Nov/2004:18:37:03 +0000] [Job 4] cupsColorSpace = 3
D [09/Nov/2004:18:37:03 +0000] [Job 4] img->colorspace = -1
I [09/Nov/2004:18:37:03 +0000] [Job 4] Formatting page 1...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 0% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 4% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 8% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 12% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 17% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 21% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 25% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 29% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 34% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 38% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 42% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 46% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 51% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 55% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 59% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 64% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 68% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 72% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 76% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 81% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 85% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 89% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 93% complete...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Printing page 1, 98% complete...
D [09/Nov/2004:18:37:03 +0000] [Job 4] Freeing memory...
D [09/Nov/2004:18:37:03 +0000] [Job 4] Freeing tiles (0x284e0)...
D [09/Nov/2004:18:37:03 +0000] [Job 4] Freeing tile pointers (0x21c90)...
d [09/Nov/2004:18:37:03 +0000] PID 1284 exited with no errors.
I [09/Nov/2004:18:37:03 +0000] [Job 4] CUPS v1.1.20 is ready to print.
d [09/Nov/2004:18:37:03 +0000] PID 1285 exited with no errors.
I [09/Nov/2004:18:37:03 +0000] [Job 4] Connecting to 192.158.96.41 on port 631...
I [09/Nov/2004:18:37:03 +0000] [Job 4] Connected to 192.158.96.41...
D [09/Nov/2004:18:37:03 +0000] [Job 4] Getting supported attributes...
D [09/Nov/2004:18:37:03 +0000] [Job 4] document-format-supported (4 values)
D [09/Nov/2004:18:37:03 +0000] [Job 4] [0] = "application/octet-stream"
D [09/Nov/2004:18:37:03 +0000] [Job 4] [1] = "application/vnd.hp-pcl"
D [09/Nov/2004:18:37:03 +0000] [Job 4] [2] = "text/plain"
D [09/Nov/2004:18:37:03 +0000] [Job 4] [3] = "application/postscript"
D [09/Nov/2004:18:37:03 +0000] [Job 4] printer-uri = "http://192.158.96.41:631/ipp"
D [09/Nov/2004:18:37:03 +0000] [Job 4] requesting-user-name = "root"
D [09/Nov/2004:18:37:03 +0000] [Job 4] job-name = "png.png"
I [09/Nov/2004:18:37:04 +0000] [Job 4] Print file accepted - job ID 1.
I [09/Nov/2004:18:37:04 +0000] [Job 4] Ready to print.
D [09/Nov/2004:18:37:04 +0000] UpdateJob: job 4, file 0 is complete.
d [09/Nov/2004:18:37:04 +0000] UpdateJob: Removing fd 7 from InputSet...
D [09/Nov/2004:18:37:04 +0000] CancelJob: id = 4
D [09/Nov/2004:18:37:04 +0000] StopJob: id = 4, force = 0
D [09/Nov/2004:18:37:04 +0000] StopJob: printer state is 3
d [09/Nov/2004:18:37:04 +0000] StopJob: Freeing status buffer...
d [09/Nov/2004:18:37:04 +0000] SaveJob: Closing file 7...
d [09/Nov/2004:18:37:04 +0000] SaveJob: Closing file 7...
d [09/Nov/2004:18:37:04 +0000] PID 1286 exited with no errors.
d [09/Nov/2004:18:41:31 +0000] DeleteCert: removing certificate for pid 0
d [09/Nov/2004:18:41:31 +0000] AddCert: adding certificate for pid 0