I [13/Jan/2005:18:33:14 +0100] Listening to 0:631
D [13/Jan/2005:18:33:14 +0100] AddLocation: added location '/'
D [13/Jan/2005:18:33:14 +0100] DenyIP: / deny 00000000/00000000
D [13/Jan/2005:18:33:14 +0100] AllowIP: / allow 7f000001/ffffffff
D [13/Jan/2005:18:33:14 +0100] AddLocation: added location '/jobs'
D [13/Jan/2005:18:33:14 +0100] AddLocation: added location '/admin'
D [13/Jan/2005:18:33:14 +0100] DenyIP: /admin deny 00000000/00000000
D [13/Jan/2005:18:33:14 +0100] AllowIP: /admin allow 7f000001/ffffffff
I [13/Jan/2005:18:33:14 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [13/Jan/2005:18:33:14 +0100] Configured for up to 100 clients.
I [13/Jan/2005:18:33:14 +0100] Allowing up to 100 client connections per host.
I [13/Jan/2005:18:33:14 +0100] Full reload is required.
D [13/Jan/2005:18:33:14 +0100] LoadAllPrinters: Loading printer canon-bjc...
d [13/Jan/2005:18:33:14 +0100] AddPrinter("canon-bjc")
d [13/Jan/2005:18:33:14 +0100] Adding filter application/vnd.cups-raw printer/canon-bjc 0 -
d [13/Jan/2005:18:33:14 +0100] FindBest: uri = "/printers/canon-bjc"...
d [13/Jan/2005:18:33:14 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:14 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:14 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:14 +0100] FindBest: best = "/"
d [13/Jan/2005:18:33:14 +0100] Adding filter application/vnd.cups-postscript printer/canon-bjc 0 foomatic-rip
D [13/Jan/2005:18:33:14 +0100] LoadAllPrinters: Loading printer hp-color...
d [13/Jan/2005:18:33:14 +0100] AddPrinter("hp-color")
d [13/Jan/2005:18:33:14 +0100] Adding filter application/vnd.cups-raw printer/hp-color 0 -
d [13/Jan/2005:18:33:14 +0100] FindBest: uri = "/printers/hp-color"...
d [13/Jan/2005:18:33:14 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:14 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:14 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:14 +0100] FindBest: best = "/"
d [13/Jan/2005:18:33:14 +0100] Adding filter application/vnd.cups-postscript printer/hp-color 0 pstopxl
D [13/Jan/2005:18:33:14 +0100] LoadAllPrinters: Loading printer hp-laserjet...
d [13/Jan/2005:18:33:14 +0100] AddPrinter("hp-laserjet")
d [13/Jan/2005:18:33:14 +0100] Adding filter application/vnd.cups-raw printer/hp-laserjet 0 -
d [13/Jan/2005:18:33:14 +0100] FindBest: uri = "/printers/hp-laserjet"...
d [13/Jan/2005:18:33:14 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:14 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:14 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:14 +0100] FindBest: best = "/"
d [13/Jan/2005:18:33:14 +0100] Adding filter application/vnd.cups-postscript printer/hp-laserjet 0 foomatic-rip
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "parallel:/dev/lp0"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "ipp"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "lpd"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "smb"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "socket"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "usb:/dev/usb/lp0"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "usb:/dev/usb/lp1"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "usb:/dev/usb/lp2"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "usb:/dev/usb/lp3"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "usb:/dev/usb/lp4"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "usb:/dev/usb/lp5"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "usb:/dev/usb/lp6"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "usb:/dev/usb/lp7"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "usb:/dev/usb/lp8"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "usb:/dev/usb/lp9"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "usb:/dev/usb/lp10"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "usb:/dev/usb/lp11"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "usb:/dev/usb/lp12"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "usb:/dev/usb/lp13"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "usb:/dev/usb/lp14"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "usb:/dev/usb/lp15"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "http"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "https"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "epson:/dev/lp0"...
D [13/Jan/2005:18:33:14 +0100] LoadDevices: Added device "canon:/dev/lp0"...
I [13/Jan/2005:18:33:14 +0100] LoadPPDs: Read "/etc/cups/ppds.dat", 2348 PPDs...
I [13/Jan/2005:18:33:14 +0100] LoadPPDs: No new or changed PPDs...
D [13/Jan/2005:18:33:14 +0100] LoadAllJobs: Scanning /var/spool/cups...
D [13/Jan/2005:18:33:14 +0100] LoadAllJobs: Loading attributes for job 1...
D [13/Jan/2005:18:33:14 +0100] LoadAllJobs: Loading attributes for job 4...
D [13/Jan/2005:18:33:14 +0100] LoadAllJobs: Loading attributes for job 2...
D [13/Jan/2005:18:33:14 +0100] LoadAllJobs: Loading attributes for job 7...
D [13/Jan/2005:18:33:14 +0100] LoadAllJobs: Loading attributes for job 3...
D [13/Jan/2005:18:33:14 +0100] LoadAllJobs: Loading attributes for job 5...
D [13/Jan/2005:18:33:14 +0100] LoadAllJobs: Loading attributes for job 9...
D [13/Jan/2005:18:33:14 +0100] LoadAllJobs: Loading attributes for job 6...
D [13/Jan/2005:18:33:14 +0100] LoadAllJobs: Loading attributes for job 8...
I [13/Jan/2005:18:33:14 +0100] Full reload complete.
D [13/Jan/2005:18:33:14 +0100] StartListening: NumListeners=1
D [13/Jan/2005:18:33:14 +0100] StartListening: address=00000000 port=631
D [13/Jan/2005:18:33:14 +0100] ResumeListening: setting input bits...
d [13/Jan/2005:18:33:14 +0100] ResumeListening: Adding fd 0 to InputSet...
d [13/Jan/2005:18:33:14 +0100] StartBrowsing: Adding fd 2 to InputSet...
d [13/Jan/2005:18:33:14 +0100] StartServer: Adding fd 3 to InputSet...
d [13/Jan/2005:18:33:14 +0100] AddCert: adding certificate for pid 0
d [13/Jan/2005:18:33:15 +0100] select_timeout: 1 seconds to send browse update
d [13/Jan/2005:18:33:16 +0100] select_timeout: 1 seconds to send browse update
d [13/Jan/2005:18:33:17 +0100] select_timeout: 29 seconds to send browse update
d [13/Jan/2005:18:33:19 +0100] AcceptClient(lis=0x808d138) 0 NumClients = 0
D [13/Jan/2005:18:33:19 +0100] AcceptClient: 5 from localhost:631.
d [13/Jan/2005:18:33:19 +0100] AcceptClient: Adding fd 5 to InputSet...
d [13/Jan/2005:18:33:19 +0100] ReadClient: 5, used=0, file=-1
D [13/Jan/2005:18:33:19 +0100] ReadClient: 5 GET /printers HTTP/1.1
d [13/Jan/2005:18:33:19 +0100] decode_auth(0x402fb008): Authorization string = ""
d [13/Jan/2005:18:33:19 +0100] decode_auth: 5 username=""
d [13/Jan/2005:18:33:19 +0100] IsAuthorized: con->uri = "/printers"
d [13/Jan/2005:18:33:19 +0100] FindBest: uri = "/printers"...
d [13/Jan/2005:18:33:19 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:19 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:19 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:19 +0100] FindBest: best = "/"
d [13/Jan/2005:18:33:19 +0100] IsAuthorized: auth = 0, satisfy=0...
d [13/Jan/2005:18:33:19 +0100] pipe_command: command="/usr/lib/cups/cgi-bin/printers.cgi", options=""
d [13/Jan/2005:18:33:19 +0100] pipe_command: argv[0] = "printers.cgi"
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[0] = "PATH=/bin:/usr/bin"
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[1] = "SERVER_SOFTWARE=CUPS/1.1"
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[2] = "GATEWAY_INTERFACE=CGI/1.1"
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[3] = "SERVER_PROTOCOL=HTTP/1.1"
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[4] = "REDIRECT_STATUS=1"
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[5] = "CUPS_SERVER=localhost"
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[6] = "IPP_PORT=631"
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[7] = "SERVER_NAME=localhost"
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[8] = "SERVER_PORT=631"
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[9] = "REMOTE_ADDR=127.0.0.1"
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[10] = "REMOTE_HOST=localhost"
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[11] = "REMOTE_USER="
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[12] = "LANG=en.ISO8859-15"
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[13] = "TZ=Europe/Berlin"
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[14] = "TMPDIR=/var/spool/cups/tmp"
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[15] = "CUPS_DATADIR=/usr/share/cups"
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[16] = "CUPS_SERVERROOT=/etc/cups"
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[17] = "HTTP_USER_AGENT=Opera/7.54 (X11; Linux i686; U)  [en]"
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[18] = "SCRIPT_NAME=/printers"
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[19] = "REQUEST_METHOD=GET"
d [13/Jan/2005:18:33:19 +0100] pipe_command: envp[20] = "CUPS_ENCRYPTION=IfRequested"
d [13/Jan/2005:18:33:19 +0100] AddCert: adding certificate for pid 3351
D [13/Jan/2005:18:33:19 +0100] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 3351
I [13/Jan/2005:18:33:19 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=3351)
D [13/Jan/2005:18:33:19 +0100] SendCommand: 5 file=7
d [13/Jan/2005:18:33:19 +0100] SendCommand: Adding fd 7 to InputSet...
d [13/Jan/2005:18:33:19 +0100] SendCommand: Adding fd 5 to OutputSet...
d [13/Jan/2005:18:33:19 +0100] AcceptClient(lis=0x808d138) 0 NumClients = 1
D [13/Jan/2005:18:33:19 +0100] AcceptClient: 8 from localhost:631.
d [13/Jan/2005:18:33:19 +0100] AcceptClient: Adding fd 8 to InputSet...
d [13/Jan/2005:18:33:19 +0100] WriteClient: 5 Content-Type: text/html;charset=iso-8859-15
d [13/Jan/2005:18:33:19 +0100] WriteClient: 5 
d [13/Jan/2005:18:33:19 +0100] WriteClient: 5 1171 bytes
d [13/Jan/2005:18:33:19 +0100] ReadClient: 8, used=0, file=-1
D [13/Jan/2005:18:33:19 +0100] ReadClient: 8 POST / HTTP/1.1
d [13/Jan/2005:18:33:19 +0100] decode_auth(0x402fdc3c): Authorization string = ""
d [13/Jan/2005:18:33:19 +0100] decode_auth: 8 username=""
d [13/Jan/2005:18:33:19 +0100] IsAuthorized: con->uri = "/"
d [13/Jan/2005:18:33:19 +0100] FindBest: uri = "/"...
d [13/Jan/2005:18:33:19 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:19 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:19 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:19 +0100] FindBest: best = "/"
d [13/Jan/2005:18:33:19 +0100] IsAuthorized: auth = 0, satisfy=0...
d [13/Jan/2005:18:33:19 +0100] POST /
d [13/Jan/2005:18:33:19 +0100] CONTENT_TYPE = application/ipp
d [13/Jan/2005:18:33:19 +0100] ReadClient: 8 con->data_encoding = length, con->data_remaining = 141, con->file = -1
d [13/Jan/2005:18:33:19 +0100] ProcessIPPRequest(0x402fdc3c[8]): operation_id = 4001
d [13/Jan/2005:18:33:19 +0100] get_default(0x402fdc3c[8])
d [13/Jan/2005:18:33:19 +0100] copy_attrs(0x81af5c0, 0x809b778, 0x81af4f8, 0)
d [13/Jan/2005:18:33:19 +0100] copy_attribute(0x81af5c0, 0x809b7a0[printer-uri-supported,4,45])
d [13/Jan/2005:18:33:19 +0100] copy_attribute(0x81af5c0, 0x809b968[printer-name,4,42])
d [13/Jan/2005:18:33:19 +0100] copy_attrs(0x81af5c0, 0x80924d0, 0x81af4f8, 0)
D [13/Jan/2005:18:33:19 +0100] ProcessIPPRequest: 8 status_code=1
d [13/Jan/2005:18:33:19 +0100] ProcessIPPRequest: Adding fd 8 to OutputSet...
d [13/Jan/2005:18:33:19 +0100] WriteClient: Removing fd 8 from OutputSet...
d [13/Jan/2005:18:33:19 +0100] ReadClient: 8, used=0, file=-1
D [13/Jan/2005:18:33:19 +0100] ReadClient: 8 POST / HTTP/1.1
d [13/Jan/2005:18:33:19 +0100] decode_auth(0x402fdc3c): Authorization string = ""
d [13/Jan/2005:18:33:19 +0100] decode_auth: 8 username=""
d [13/Jan/2005:18:33:19 +0100] IsAuthorized: con->uri = "/"
d [13/Jan/2005:18:33:19 +0100] FindBest: uri = "/"...
d [13/Jan/2005:18:33:19 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:19 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:19 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:19 +0100] FindBest: best = "/"
d [13/Jan/2005:18:33:19 +0100] IsAuthorized: auth = 0, satisfy=0...
d [13/Jan/2005:18:33:19 +0100] POST /
d [13/Jan/2005:18:33:19 +0100] CONTENT_TYPE = application/ipp
d [13/Jan/2005:18:33:19 +0100] ReadClient: 8 con->data_encoding = length, con->data_remaining = 817, con->file = -1
d [13/Jan/2005:18:33:19 +0100] ProcessIPPRequest(0x402fdc3c[8]): operation_id = 4002
d [13/Jan/2005:18:33:19 +0100] get_printers(0x402fdc3c[8], 0)
d [13/Jan/2005:18:33:19 +0100] add_printer_state_reasons(0x402fdc3c[8], 0x8098088[canon-bjc])
d [13/Jan/2005:18:33:19 +0100] add_queued_job_count(0x402fdc3c[8], 0x8098088[canon-bjc])
d [13/Jan/2005:18:33:19 +0100] copy_attrs(0x81af6e8, 0x80959c8, 0x81af9e0, 0)
d [13/Jan/2005:18:33:19 +0100] copy_attribute(0x81af6e8, 0x80959f0[printer-uri-supported,4,45])
d [13/Jan/2005:18:33:19 +0100] copy_attribute(0x81af6e8, 0x8095b28[printer-name,4,42])
d [13/Jan/2005:18:33:19 +0100] copy_attribute(0x81af6e8, 0x8095b78[printer-location,4,41])
d [13/Jan/2005:18:33:19 +0100] copy_attribute(0x81af6e8, 0x8095bc8[printer-info,4,41])
d [13/Jan/2005:18:33:19 +0100] copy_attribute(0x81af6e8, 0x8095db0[device-uri,4,45])
d [13/Jan/2005:18:33:19 +0100] copy_attribute(0x81af6e8, 0x80a2d10[printer-make-and-model,4,41])
d [13/Jan/2005:18:33:19 +0100] copy_attrs(0x81af6e8, 0x80924d0, 0x81af9e0, 0)
d [13/Jan/2005:18:33:19 +0100] add_printer_state_reasons(0x402fdc3c[8], 0x8097b68[hp-color])
d [13/Jan/2005:18:33:19 +0100] add_queued_job_count(0x402fdc3c[8], 0x8097b68[hp-color])
d [13/Jan/2005:18:33:19 +0100] copy_attrs(0x81af6e8, 0x809bdd8, 0x81af9e0, 0)
d [13/Jan/2005:18:33:19 +0100] copy_attribute(0x81af6e8, 0x809be00[printer-uri-supported,4,45])
d [13/Jan/2005:18:33:19 +0100] copy_attribute(0x81af6e8, 0x809bcc0[printer-name,4,42])
d [13/Jan/2005:18:33:19 +0100] copy_attribute(0x81af6e8, 0x809bcf8[printer-location,4,41])
d [13/Jan/2005:18:33:19 +0100] copy_attribute(0x81af6e8, 0x80a2d70[printer-info,4,41])
d [13/Jan/2005:18:33:19 +0100] copy_attribute(0x81af6e8, 0x80a2fb0[device-uri,4,45])
d [13/Jan/2005:18:33:19 +0100] copy_attribute(0x81af6e8, 0x809a9b8[printer-make-and-model,4,41])
d [13/Jan/2005:18:33:19 +0100] copy_attrs(0x81af6e8, 0x80924d0, 0x81af9e0, 0)
d [13/Jan/2005:18:33:19 +0100] add_printer_state_reasons(0x402fdc3c[8], 0x809a2e8[hp-laserjet])
d [13/Jan/2005:18:33:19 +0100] add_queued_job_count(0x402fdc3c[8], 0x809a2e8[hp-laserjet])
d [13/Jan/2005:18:33:19 +0100] copy_attrs(0x81af6e8, 0x809b778, 0x81af9e0, 0)
d [13/Jan/2005:18:33:19 +0100] copy_attribute(0x81af6e8, 0x809b7a0[printer-uri-supported,4,45])
d [13/Jan/2005:18:33:19 +0100] copy_attribute(0x81af6e8, 0x809b968[printer-name,4,42])
d [13/Jan/2005:18:33:19 +0100] copy_attribute(0x81af6e8, 0x809b9a0[printer-location,4,41])
d [13/Jan/2005:18:33:19 +0100] copy_attribute(0x81af6e8, 0x809b868[printer-info,4,41])
d [13/Jan/2005:18:33:19 +0100] copy_attribute(0x81af6e8, 0x809e818[device-uri,4,45])
d [13/Jan/2005:18:33:19 +0100] copy_attribute(0x81af6e8, 0x80ab5f8[printer-make-and-model,4,41])
d [13/Jan/2005:18:33:19 +0100] copy_attrs(0x81af6e8, 0x80924d0, 0x81af9e0, 0)
D [13/Jan/2005:18:33:19 +0100] ProcessIPPRequest: 8 status_code=1
d [13/Jan/2005:18:33:19 +0100] ProcessIPPRequest: Adding fd 8 to OutputSet...
d [13/Jan/2005:18:33:19 +0100] WriteClient: Removing fd 8 from OutputSet...
d [13/Jan/2005:18:33:19 +0100] WriteClient: 5 2048 bytes
d [13/Jan/2005:18:33:19 +0100] PID 3351 exited with no errors.
d [13/Jan/2005:18:33:19 +0100] DeleteCert: removing certificate for pid 3351
d [13/Jan/2005:18:33:19 +0100] WriteClient: 5 2048 bytes
d [13/Jan/2005:18:33:19 +0100] ReadClient: 8, used=0, file=-1
d [13/Jan/2005:18:33:19 +0100] ReadClient: httpGets returned EOF...
D [13/Jan/2005:18:33:19 +0100] CloseClient: 8
d [13/Jan/2005:18:33:19 +0100] CloseClient: Removing fd 8 from InputSet and OutputSet...
d [13/Jan/2005:18:33:19 +0100] WriteClient: 5 1465 bytes
d [13/Jan/2005:18:33:19 +0100] WriteClient: Removing fd 5 from OutputSet...
d [13/Jan/2005:18:33:19 +0100] WriteClient: Removing fd 7 from InputSet...
d [13/Jan/2005:18:33:19 +0100] WriteClient: 5 Closing data file 7.
d [13/Jan/2005:18:33:20 +0100] select_timeout: 26 seconds to send browse update
d [13/Jan/2005:18:33:25 +0100] ReadClient: 5, used=0, file=-1
D [13/Jan/2005:18:33:25 +0100] ReadClient: 5 GET /printers/hp-laserjet?op=print-test-page HTTP/1.1
d [13/Jan/2005:18:33:25 +0100] decode_auth(0x402fb008): Authorization string = ""
d [13/Jan/2005:18:33:25 +0100] decode_auth: 5 username=""
d [13/Jan/2005:18:33:25 +0100] IsAuthorized: con->uri = "/printers/hp-laserjet?op=print-test-page"
d [13/Jan/2005:18:33:25 +0100] FindBest: uri = "/printers/hp-laserjet?op=print-test-page"...
d [13/Jan/2005:18:33:25 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:25 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:25 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:25 +0100] FindBest: best = "/"
d [13/Jan/2005:18:33:25 +0100] IsAuthorized: auth = 0, satisfy=0...
d [13/Jan/2005:18:33:25 +0100] pipe_command: command="/usr/lib/cups/cgi-bin/printers.cgi", options="hp-laserjet?op=print-test-page"
d [13/Jan/2005:18:33:25 +0100] pipe_command: argv[0] = "hp-laserjet"
d [13/Jan/2005:18:33:25 +0100] pipe_command: argv[1] = "op=print-test-page"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[0] = "PATH=/bin:/usr/bin"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[1] = "SERVER_SOFTWARE=CUPS/1.1"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[2] = "GATEWAY_INTERFACE=CGI/1.1"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[3] = "SERVER_PROTOCOL=HTTP/1.1"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[4] = "REDIRECT_STATUS=1"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[5] = "CUPS_SERVER=localhost"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[6] = "IPP_PORT=631"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[7] = "SERVER_NAME=localhost"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[8] = "SERVER_PORT=631"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[9] = "REMOTE_ADDR=127.0.0.1"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[10] = "REMOTE_HOST=localhost"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[11] = "REMOTE_USER="
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[12] = "LANG=en.ISO8859-15"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[13] = "TZ=Europe/Berlin"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[14] = "TMPDIR=/var/spool/cups/tmp"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[15] = "CUPS_DATADIR=/usr/share/cups"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[16] = "CUPS_SERVERROOT=/etc/cups"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[17] = "HTTP_USER_AGENT=Opera/7.54 (X11; Linux i686; U)  [en]"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[18] = "SCRIPT_NAME=/printers/hp-laserjet"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[19] = "REQUEST_METHOD=GET"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[20] = "QUERY_STRING=op=print-test-page"
d [13/Jan/2005:18:33:25 +0100] pipe_command: envp[21] = "CUPS_ENCRYPTION=IfRequested"
d [13/Jan/2005:18:33:25 +0100] AddCert: adding certificate for pid 3352
D [13/Jan/2005:18:33:25 +0100] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 3352
I [13/Jan/2005:18:33:25 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=3352)
D [13/Jan/2005:18:33:25 +0100] SendCommand: 5 file=8
d [13/Jan/2005:18:33:25 +0100] SendCommand: Adding fd 8 to InputSet...
d [13/Jan/2005:18:33:25 +0100] SendCommand: Adding fd 5 to OutputSet...
d [13/Jan/2005:18:33:25 +0100] AcceptClient(lis=0x808d138) 0 NumClients = 1
D [13/Jan/2005:18:33:25 +0100] AcceptClient: 7 from localhost:631.
d [13/Jan/2005:18:33:25 +0100] AcceptClient: Adding fd 7 to InputSet...
d [13/Jan/2005:18:33:25 +0100] WriteClient: 5 Content-Type: text/html;charset=iso-8859-15
d [13/Jan/2005:18:33:25 +0100] WriteClient: 5 
d [13/Jan/2005:18:33:25 +0100] WriteClient: 5 1179 bytes
d [13/Jan/2005:18:33:25 +0100] ReadClient: 7, used=0, file=-1
D [13/Jan/2005:18:33:25 +0100] ReadClient: 7 POST /printers/hp-laserjet HTTP/1.1
d [13/Jan/2005:18:33:25 +0100] decode_auth(0x402fdc3c): Authorization string = ""
d [13/Jan/2005:18:33:25 +0100] decode_auth: 7 username=""
d [13/Jan/2005:18:33:25 +0100] IsAuthorized: con->uri = "/printers/hp-laserjet"
d [13/Jan/2005:18:33:25 +0100] FindBest: uri = "/printers/hp-laserjet"...
d [13/Jan/2005:18:33:25 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:25 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:25 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:25 +0100] FindBest: best = "/"
d [13/Jan/2005:18:33:25 +0100] IsAuthorized: auth = 0, satisfy=0...
d [13/Jan/2005:18:33:25 +0100] POST /printers/hp-laserjet
d [13/Jan/2005:18:33:25 +0100] CONTENT_TYPE = application/ipp
d [13/Jan/2005:18:33:25 +0100] ReadClient: 7 con->data_encoding = length, con->data_remaining = 14762, con->file = -1
d [13/Jan/2005:18:33:25 +0100] ReadClient: 7 REQUEST /var/spool/cups/00000000=9
d [13/Jan/2005:18:33:25 +0100] ReadClient: 7 writing 1718 bytes to 9
d [13/Jan/2005:18:33:25 +0100] ReadClient: 7, used=0, file=9
d [13/Jan/2005:18:33:25 +0100] ReadClient: 7 con->data_encoding = length, con->data_remaining = 12824, con->file = 9
d [13/Jan/2005:18:33:25 +0100] ReadClient: 7 writing 12824 bytes to 9
d [13/Jan/2005:18:33:25 +0100] ReadClient: 7 Closing data file 9, size = 14542.
d [13/Jan/2005:18:33:25 +0100] ProcessIPPRequest(0x402fdc3c[7]): operation_id = 0002
d [13/Jan/2005:18:33:25 +0100] ProcessIPPRequest: URI="ipp://localhost/printers/hp-laserjet"
d [13/Jan/2005:18:33:25 +0100] print_job(0x402fdc3c[7], ipp://localhost/printers/hp-laserjet)
D [13/Jan/2005:18:33:25 +0100] print_job: request file type is application/postscript.
d [13/Jan/2005:18:33:25 +0100] check_quotas(0x402fdc3c[7], 0x809a2e8[hp-laserjet])
D [13/Jan/2005:18:33:25 +0100] check_quotas: requesting-user-name = ''
D [13/Jan/2005:18:33:25 +0100] print_job: requesting-user-name = ''
D [13/Jan/2005:18:33:25 +0100] Adding default job-sheets values "none,none"...
I [13/Jan/2005:18:33:25 +0100] Adding start banner page "none" to job 10.
d [13/Jan/2005:18:33:25 +0100] copy_banner(0x402fdc3c[7], 0x81af4f8[10], none)
d [13/Jan/2005:18:33:25 +0100] add_file(con=0x402fdc3c[7], job=10, filetype=application/postscript, compression=0)
I [13/Jan/2005:18:33:25 +0100] Adding end banner page "none" to job 10.
d [13/Jan/2005:18:33:25 +0100] copy_banner(0x402fdc3c[7], 0x81af4f8[10], none)
I [13/Jan/2005:18:33:25 +0100] Job 10 queued on 'hp-laserjet' by ''.
D [13/Jan/2005:18:33:25 +0100] Job 10 hold_until = 0
d [13/Jan/2005:18:33:25 +0100] SaveJob: Closing file 9...
D [13/Jan/2005:18:33:25 +0100] StartJob(10, 0x809a2e8)
D [13/Jan/2005:18:33:25 +0100] StartJob() id = 10, file = 0/1
D [13/Jan/2005:18:33:25 +0100] job-sheets=none,none
D [13/Jan/2005:18:33:25 +0100] banner_page = 0
D [13/Jan/2005:18:33:25 +0100] StartJob: argv = "hp-laserjet","10","","Test Page","1","","/var/spool/cups/d00010-001"
D [13/Jan/2005:18:33:25 +0100] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [13/Jan/2005:18:33:25 +0100] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [13/Jan/2005:18:33:25 +0100] StartJob: envp[2]="USER=root"
D [13/Jan/2005:18:33:25 +0100] StartJob: envp[3]="CHARSET=iso-8859-15"
D [13/Jan/2005:18:33:25 +0100] StartJob: envp[4]="LANG=en"
D [13/Jan/2005:18:33:25 +0100] StartJob: envp[5]="TZ=Europe/Berlin"
D [13/Jan/2005:18:33:25 +0100] StartJob: envp[6]="PPD=/etc/cups/ppd/hp-laserjet.ppd"
D [13/Jan/2005:18:33:25 +0100] StartJob: envp[7]="CUPS_SERVERROOT=/etc/cups"
D [13/Jan/2005:18:33:25 +0100] StartJob: envp[8]="RIP_MAX_CACHE=8m"
D [13/Jan/2005:18:33:25 +0100] StartJob: envp[9]="TMPDIR=/var/spool/cups/tmp"
D [13/Jan/2005:18:33:25 +0100] StartJob: envp[10]="CONTENT_TYPE=application/postscript"
D [13/Jan/2005:18:33:25 +0100] StartJob: envp[11]="DEVICE_URI=https://zivprint.uni-muenster.de/printers/ein-ps/.printer"
D [13/Jan/2005:18:33:25 +0100] StartJob: envp[12]="PRINTER=hp-laserjet"
D [13/Jan/2005:18:33:25 +0100] StartJob: envp[13]="CUPS_DATADIR=/usr/share/cups"
D [13/Jan/2005:18:33:25 +0100] StartJob: envp[14]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [13/Jan/2005:18:33:25 +0100] StartJob: envp[15]="CUPS_SERVER=localhost"
D [13/Jan/2005:18:33:25 +0100] StartJob: envp[16]="IPP_PORT=631"
d [13/Jan/2005:18:33:25 +0100] StartJob: Allocating status buffer...
D [13/Jan/2005:18:33:25 +0100] StartJob: statusfds = [ 9 10 ]
D [13/Jan/2005:18:33:25 +0100] StartJob: filterfds[1] = [ 11 -1 ]
D [13/Jan/2005:18:33:25 +0100] StartJob: filter = "/usr/lib/cups/filter/pstops"
D [13/Jan/2005:18:33:25 +0100] StartJob: filterfds[0] = [ 12 13 ]
D [13/Jan/2005:18:33:25 +0100] start_process("/usr/lib/cups/filter/pstops", 0xbffefff0, 0xbffef360, 11, 13, 10)
I [13/Jan/2005:18:33:25 +0100] Started filter /usr/lib/cups/filter/pstops (PID 3353) for job 10.
D [13/Jan/2005:18:33:25 +0100] StartJob: filter = "/usr/lib/cups/filter/foomatic-rip"
D [13/Jan/2005:18:33:25 +0100] StartJob: filterfds[1] = [ 11 14 ]
D [13/Jan/2005:18:33:25 +0100] start_process("/usr/lib/cups/filter/foomatic-rip", 0xbffefff0, 0xbffef360, 12, 14, 10)
I [13/Jan/2005:18:33:25 +0100] Started filter /usr/lib/cups/filter/foomatic-rip (PID 3354) for job 10.
D [13/Jan/2005:18:33:25 +0100] StartJob: backend = "/usr/lib/cups/backend/https"
D [13/Jan/2005:18:33:25 +0100] StartJob: filterfds[0] = [ -1 12 ]
D [13/Jan/2005:18:33:25 +0100] start_process("/usr/lib/cups/backend/https", 0xbffefff0, 0xbffef360, 11, 12, 10)
I [13/Jan/2005:18:33:25 +0100] Started backend /usr/lib/cups/backend/https (PID 3355) for job 10.
d [13/Jan/2005:18:33:25 +0100] StartJob: Adding fd 9 to InputSet...
d [13/Jan/2005:18:33:25 +0100] add_job_state_reasons(0x402fdc3c[7], 10)
D [13/Jan/2005:18:33:25 +0100] ProcessIPPRequest: 7 status_code=0
d [13/Jan/2005:18:33:25 +0100] ProcessIPPRequest: Adding fd 7 to OutputSet...
D [13/Jan/2005:18:33:25 +0100] [Job 10] Page = 595x842; 0,0 to 595,842
D [13/Jan/2005:18:33:25 +0100] [Job 10] slowcollate=0, slowduplex=0, sloworder=0
D [13/Jan/2005:18:33:25 +0100] [Job 10] 0 %%BoundingBox: 0 0 612 792
D [13/Jan/2005:18:33:25 +0100] [Job 10] 0 %%Pages: 1
D [13/Jan/2005:18:33:25 +0100] [Job 10] 0 %%LanguageLevel: 1
D [13/Jan/2005:18:33:25 +0100] [Job 10] 0 %%DocumentData: Clean7Bit
D [13/Jan/2005:18:33:25 +0100] [Job 10] 0 %%DocumentSuppliedResources: procset testprint/1.1
D [13/Jan/2005:18:33:25 +0100] [Job 10] 0 %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman
D [13/Jan/2005:18:33:25 +0100] [Job 10] 0 %%Creator: Michael Sweet, Easy Software Products
D [13/Jan/2005:18:33:25 +0100] [Job 10] 0 %%CreationDate: May 11, 1999
D [13/Jan/2005:18:33:25 +0100] [Job 10] 0 %%Title: Test Page
D [13/Jan/2005:18:33:25 +0100] [Job 10] 0 %%EndComments
D [13/Jan/2005:18:33:25 +0100] [Job 10] 0 %%BeginProlog
D [13/Jan/2005:18:33:25 +0100] [Job 10] 0 %%BeginResource procset testprint 1.1 0
D [13/Jan/2005:18:33:25 +0100] [Job 10] 0 %%EndResource
D [13/Jan/2005:18:33:25 +0100] [Job 10] 0 %%EndProlog
D [13/Jan/2005:18:33:25 +0100] [Job 10] 0 %%Page: 1 1
D [13/Jan/2005:18:33:25 +0100] [Job 10] 0 %%Page: 1 1
D [13/Jan/2005:18:33:25 +0100] [Job 10] pw = 595.0, pl = 842.0
D [13/Jan/2005:18:33:25 +0100] [Job 10] PageLeft = 0.0, PageRight = 595.0
D [13/Jan/2005:18:33:25 +0100] [Job 10] PageTop = 842.0, PageBottom = 0.0
D [13/Jan/2005:18:33:25 +0100] [Job 10] PageWidth = 595.0, PageLength = 842.0
d [13/Jan/2005:18:33:25 +0100] WriteClient: Removing fd 7 from OutputSet...
d [13/Jan/2005:18:33:25 +0100] PID 3352 exited with no errors.
d [13/Jan/2005:18:33:25 +0100] DeleteCert: removing certificate for pid 3352
d [13/Jan/2005:18:33:25 +0100] ReadClient: 7, used=0, file=-1
d [13/Jan/2005:18:33:25 +0100] ReadClient: httpGets returned EOF...
D [13/Jan/2005:18:33:25 +0100] CloseClient: 7
d [13/Jan/2005:18:33:25 +0100] CloseClient: Removing fd 7 from InputSet and OutputSet...
d [13/Jan/2005:18:33:25 +0100] WriteClient: Removing fd 5 from OutputSet...
d [13/Jan/2005:18:33:25 +0100] WriteClient: Removing fd 8 from InputSet...
d [13/Jan/2005:18:33:25 +0100] WriteClient: 5 Closing data file 8.
D [13/Jan/2005:18:33:25 +0100] [Job 10] perl: warning: Setting locale failed.
D [13/Jan/2005:18:33:25 +0100] [Job 10] perl: warning: Please check that your locale settings:
D [13/Jan/2005:18:33:25 +0100] [Job 10] LANGUAGE = (unset),
D [13/Jan/2005:18:33:25 +0100] [Job 10] LC_ALL = (unset),
D [13/Jan/2005:18:33:25 +0100] [Job 10] LANG = "en"
D [13/Jan/2005:18:33:25 +0100] [Job 10] are supported and installed on your system.
D [13/Jan/2005:18:33:25 +0100] [Job 10] perl: warning: Falling back to the standard locale ("C").
D [13/Jan/2005:18:33:25 +0100] [Job 10] foomatic-rip version $Revision: 3.43.2.6 $ running...
D [13/Jan/2005:18:33:25 +0100] [Job 10] Parsing PPD file ...
D [13/Jan/2005:18:33:25 +0100] [Job 10] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"
D [13/Jan/2005:18:33:25 +0100] [Job 10] Added option ColorSpace
D [13/Jan/2005:18:33:25 +0100] [Job 10] Added option PageSize
D [13/Jan/2005:18:33:25 +0100] [Job 10] Added option PageRegion
D [13/Jan/2005:18:33:25 +0100] [Job 10] Added option ImageableArea
D [13/Jan/2005:18:33:25 +0100] [Job 10] Added option PaperDimension
D [13/Jan/2005:18:33:25 +0100] [Job 10] Added option InputSlot
D [13/Jan/2005:18:33:25 +0100] [Job 10] Added option Duplex
D [13/Jan/2005:18:33:25 +0100] [Job 10] Added option Resolution
D [13/Jan/2005:18:33:25 +0100] [Job 10] Added option Economode
D [13/Jan/2005:18:33:25 +0100] [Job 10] Added option Copies
D [13/Jan/2005:18:33:25 +0100] [Job 10] Added option REt
D [13/Jan/2005:18:33:25 +0100] [Job 10] Added option TonerDensity
D [13/Jan/2005:18:33:25 +0100] [Job 10] Added option PreFilter
D [13/Jan/2005:18:33:25 +0100] [Job 10] Added option Font
D [13/Jan/2005:18:33:25 +0100] [Job 10] 
D [13/Jan/2005:18:33:25 +0100] [Job 10] Parameter Summary
D [13/Jan/2005:18:33:25 +0100] [Job 10] -----------------
D [13/Jan/2005:18:33:25 +0100] [Job 10] 
D [13/Jan/2005:18:33:25 +0100] [Job 10] Spooler: cups
D [13/Jan/2005:18:33:25 +0100] [Job 10] Printer: hp-laserjet
D [13/Jan/2005:18:33:25 +0100] [Job 10] PPD file: /etc/cups/ppd/hp-laserjet.ppd
D [13/Jan/2005:18:33:25 +0100] [Job 10] Printer model: HP LaserJet 8000 Foomatic/Postscript (recommended)
D [13/Jan/2005:18:33:25 +0100] [Job 10] Job title: Test Page
D [13/Jan/2005:18:33:25 +0100] [Job 10] File(s) to be printed: 
D [13/Jan/2005:18:33:25 +0100] [Job 10] <STDIN>
D [13/Jan/2005:18:33:25 +0100] [Job 10] 
D [13/Jan/2005:18:33:25 +0100] [Job 10] 
D [13/Jan/2005:18:33:25 +0100] [Job 10] ================================================
D [13/Jan/2005:18:33:25 +0100] [Job 10] 
D [13/Jan/2005:18:33:25 +0100] [Job 10] File: <STDIN>
D [13/Jan/2005:18:33:25 +0100] [Job 10] 
D [13/Jan/2005:18:33:25 +0100] [Job 10] ================================================
D [13/Jan/2005:18:33:25 +0100] [Job 10] 
D [13/Jan/2005:18:33:25 +0100] [Job 10] Reading PostScript input ...
D [13/Jan/2005:18:33:25 +0100] [Job 10] --> This document is DSC-conforming!
D [13/Jan/2005:18:33:25 +0100] [Job 10] 
D [13/Jan/2005:18:33:25 +0100] [Job 10] -----------
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %%BeginProlog
D [13/Jan/2005:18:33:25 +0100] [Job 10] 0 %%EOF
D [13/Jan/2005:18:33:25 +0100] [Job 10] Saw EOF!
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %%EndProlog
D [13/Jan/2005:18:33:25 +0100] [Job 10] 
D [13/Jan/2005:18:33:25 +0100] [Job 10] -----------
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %%BeginSetup
D [13/Jan/2005:18:33:25 +0100] [Job 10] Inserting PostScript code for CUPS' page accounting
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %%BeginFeature: *REt Medium
D [13/Jan/2005:18:33:25 +0100] [Job 10] Option: REt=Medium --> Setting option
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %% FoomaticRIPOptionSetting: REt=Medium
D [13/Jan/2005:18:33:25 +0100] [Job 10] Option: REt=Medium --> Setting option
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %%BeginFeature: *TonerDensity 3
D [13/Jan/2005:18:33:25 +0100] [Job 10] Option: TonerDensity=3 --> Setting option
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %% FoomaticRIPOptionSetting: TonerDensity=3
D [13/Jan/2005:18:33:25 +0100] [Job 10] Option: TonerDensity=3 --> Setting option
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %%BeginFeature: *Copies 1
D [13/Jan/2005:18:33:25 +0100] [Job 10] Option: Copies=1 --> Setting option
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %% FoomaticRIPOptionSetting: Copies=1
D [13/Jan/2005:18:33:25 +0100] [Job 10] Option: Copies=1 --> Setting option
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %%BeginFeature: *PageRegion A4
D [13/Jan/2005:18:33:25 +0100] [Job 10] Option: PageRegion=A4 --> Option will be set by PostScript interpreter
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %%BeginFeature: *Economode Off
D [13/Jan/2005:18:33:25 +0100] [Job 10] Option: Economode=Off --> Setting option
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %% FoomaticRIPOptionSetting: Economode=Off
D [13/Jan/2005:18:33:25 +0100] [Job 10] Option: Economode=Off --> Setting option
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %%BeginFeature: *PreFilter No
D [13/Jan/2005:18:33:25 +0100] [Job 10] Option: PreFilter=No --> Setting option
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %% FoomaticRIPOptionSetting: PreFilter=No
D [13/Jan/2005:18:33:25 +0100] [Job 10] Option: PreFilter=No --> Setting option
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %%BeginFeature: *Resolution 1200x1200dpi
D [13/Jan/2005:18:33:25 +0100] [Job 10] Option: Resolution=1200x1200dpi --> Setting option
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %% FoomaticRIPOptionSetting: Resolution=1200x1200dpi
D [13/Jan/2005:18:33:25 +0100] [Job 10] Option: Resolution=1200x1200dpi --> Setting option
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %%BeginFeature: *InputSlot Default
D [13/Jan/2005:18:33:25 +0100] [Job 10] Option: InputSlot=Default --> Setting option
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %% FoomaticRIPOptionSetting: InputSlot=Default
D [13/Jan/2005:18:33:25 +0100] [Job 10] Option: InputSlot=Default --> Setting option
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %%BeginFeature: *Duplex DuplexNoTumble
D [13/Jan/2005:18:33:25 +0100] [Job 10] Option: Duplex=DuplexNoTumble --> Setting option
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %% FoomaticRIPOptionSetting: Duplex=DuplexNoTumble
D [13/Jan/2005:18:33:25 +0100] [Job 10] Option: Duplex=DuplexNoTumble --> Setting option
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found: %%EndSetup
D [13/Jan/2005:18:33:25 +0100] [Job 10] 
D [13/Jan/2005:18:33:25 +0100] [Job 10] -----------
D [13/Jan/2005:18:33:25 +0100] [Job 10] New page:  1 1
D [13/Jan/2005:18:33:25 +0100] [Job 10] Inserting option code into "PageSetup" section.
D [13/Jan/2005:18:33:25 +0100] [Job 10] No page header or page header not DSC-conforming
D [13/Jan/2005:18:33:25 +0100] [Job 10] pw = 595.0, pl = 842.0
D [13/Jan/2005:18:33:25 +0100] [Job 10] PageLeft = 0.0, PageRight = 595.0
D [13/Jan/2005:18:33:25 +0100] [Job 10] PageTop = 842.0, PageBottom = 0.0
D [13/Jan/2005:18:33:25 +0100] [Job 10] PageWidth = 595.0, PageLength = 842.0
d [13/Jan/2005:18:33:25 +0100] PID 3353 exited with no errors.
D [13/Jan/2005:18:33:25 +0100] [Job 10] Stopping search for page header options
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found:
D [13/Jan/2005:18:33:25 +0100] [Job 10] pageHeight sub			% Move down...
D [13/Jan/2005:18:33:25 +0100] [Job 10] --> Output goes directly to the renderer now.
D [13/Jan/2005:18:33:25 +0100] [Job 10] 
D [13/Jan/2005:18:33:25 +0100] [Job 10] 
D [13/Jan/2005:18:33:25 +0100] [Job 10] Starting renderer
D [13/Jan/2005:18:33:25 +0100] [Job 10] JCL: %-12345X@PJL
D [13/Jan/2005:18:33:25 +0100] [Job 10] @PJL SET ECONOMODE=OFF
D [13/Jan/2005:18:33:25 +0100] [Job 10] @PJL SET COPIES=1
D [13/Jan/2005:18:33:25 +0100] [Job 10] @PJL SET RET=MEDIUM
D [13/Jan/2005:18:33:25 +0100] [Job 10] @PJL SET DENSITY=3
D [13/Jan/2005:18:33:25 +0100] [Job 10] <job data> 
D [13/Jan/2005:18:33:25 +0100] [Job 10] %-12345X@PJL RESET
D [13/Jan/2005:18:33:25 +0100] [Job 10] 
D [13/Jan/2005:18:33:25 +0100] [Job 10] renderer PID kid4=3357
D [13/Jan/2005:18:33:25 +0100] [Job 10] renderer command: level=0; printf "%%!\n%%%% %%%%\n<</HWResolution[1200 1200]>>setpagedevice\n<</ManualFeed false>>setpagedevice\n<</Duplex true /Tumble false>>setpagedevice\n"; if (( $level > 0 )); then if (( $level < 99 )); then level=" -dLanguageLevel=$level"; else level=""; fi; gs -q -dPARANOIDSAFER -dNOPAUSE -dBATCH -sDEVICE=pswrite$level -sOutputFile=- -; else cat; fi
D [13/Jan/2005:18:33:25 +0100] [Job 10] 
D [13/Jan/2005:18:33:25 +0100] [Job 10] -----------
D [13/Jan/2005:18:33:25 +0100] [Job 10] New page:  2 2
D [13/Jan/2005:18:33:25 +0100] [Job 10] Inserting option code into "PageSetup" section.
D [13/Jan/2005:18:33:25 +0100] [Job 10] Found:
D [13/Jan/2005:18:33:25 +0100] [Job 10] %%Page: 2 2
D [13/Jan/2005:18:33:25 +0100] [Job 10] --> Output goes to the FIFO buffer now.
D [13/Jan/2005:18:33:25 +0100] [Job 10] 
D [13/Jan/2005:18:33:25 +0100] [Job 10] No page header or page header not DSC-conforming
D [13/Jan/2005:18:33:25 +0100] [Job 10] Flushing FIFO.
D [13/Jan/2005:18:33:25 +0100] [Job 10] 
D [13/Jan/2005:18:33:25 +0100] [Job 10] Closing renderer
D [13/Jan/2005:18:33:25 +0100] [Job 10] tail process done writing data to STDOUT
D [13/Jan/2005:18:33:25 +0100] [Job 10] KID4 finished
D [13/Jan/2005:18:33:25 +0100] [Job 10] KID3 finished
D [13/Jan/2005:18:33:25 +0100] [Job 10] KID3 exited with status 0
D [13/Jan/2005:18:33:25 +0100] [Job 10] KID4 exited with status 0
D [13/Jan/2005:18:33:25 +0100] [Job 10] Renderer exit stat: 0
D [13/Jan/2005:18:33:25 +0100] [Job 10] Renderer process finished
D [13/Jan/2005:18:33:25 +0100] [Job 10] 
D [13/Jan/2005:18:33:25 +0100] [Job 10] Closing foomatic-rip.
d [13/Jan/2005:18:33:25 +0100] PID 3354 exited with no errors.
I [13/Jan/2005:18:33:25 +0100] [Job 10] Connecting to zivprint.uni-muenster.de on port 443...
I [13/Jan/2005:18:33:26 +0100] [Job 10] Connected to zivprint.uni-muenster.de...
D [13/Jan/2005:18:33:26 +0100] [Job 10] Getting supported attributes...
I [13/Jan/2005:18:33:26 +0100] [Job 10] Printer does not support IPP/1.1, trying IPP/1.0...
D [13/Jan/2005:18:33:26 +0100] [Job 10] Getting supported attributes...
D [13/Jan/2005:18:33:26 +0100] [Job 10] document-format-supported (1 values)
D [13/Jan/2005:18:33:26 +0100] [Job 10] [0] = "application/octet-stream"
D [13/Jan/2005:18:33:26 +0100] [Job 10] printer-uri = "https://zivprint.uni-muenster.de:443/printers/ein-ps/.printer"
D [13/Jan/2005:18:33:26 +0100] [Job 10] requesting-user-name = ""
D [13/Jan/2005:18:33:26 +0100] [Job 10] job-name = "Test Page"
d [13/Jan/2005:18:33:27 +0100] select_timeout: 11 seconds to process active jobs
N [13/Jan/2005:18:33:28 +0100] [Job 10] : Print file accepted - job ID 61.
d [13/Jan/2005:18:33:29 +0100] select_timeout: 11 seconds to process active jobs
d [13/Jan/2005:18:33:32 +0100] ReadClient: 5, used=0, file=-1
D [13/Jan/2005:18:33:32 +0100] ReadClient: 5 GET /jobs HTTP/1.1
d [13/Jan/2005:18:33:32 +0100] decode_auth(0x402fb008): Authorization string = ""
d [13/Jan/2005:18:33:32 +0100] decode_auth: 5 username=""
d [13/Jan/2005:18:33:32 +0100] IsAuthorized: con->uri = "/jobs"
d [13/Jan/2005:18:33:32 +0100] FindBest: uri = "/jobs"...
d [13/Jan/2005:18:33:32 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:32 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:32 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:32 +0100] FindBest: best = "/jobs"
d [13/Jan/2005:18:33:32 +0100] IsAuthorized: auth = 0, satisfy=0...
d [13/Jan/2005:18:33:32 +0100] pipe_command: command="/usr/lib/cups/cgi-bin/jobs.cgi", options=""
d [13/Jan/2005:18:33:32 +0100] pipe_command: argv[0] = "jobs.cgi"
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[0] = "PATH=/bin:/usr/bin"
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[1] = "SERVER_SOFTWARE=CUPS/1.1"
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[2] = "GATEWAY_INTERFACE=CGI/1.1"
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[3] = "SERVER_PROTOCOL=HTTP/1.1"
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[4] = "REDIRECT_STATUS=1"
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[5] = "CUPS_SERVER=localhost"
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[6] = "IPP_PORT=631"
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[7] = "SERVER_NAME=localhost"
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[8] = "SERVER_PORT=631"
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[9] = "REMOTE_ADDR=127.0.0.1"
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[10] = "REMOTE_HOST=localhost"
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[11] = "REMOTE_USER="
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[12] = "LANG=en.ISO8859-15"
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[13] = "TZ=Europe/Berlin"
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[14] = "TMPDIR=/var/spool/cups/tmp"
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[15] = "CUPS_DATADIR=/usr/share/cups"
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[16] = "CUPS_SERVERROOT=/etc/cups"
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[17] = "HTTP_USER_AGENT=Opera/7.54 (X11; Linux i686; U)  [en]"
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[18] = "SCRIPT_NAME=/jobs"
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[19] = "REQUEST_METHOD=GET"
d [13/Jan/2005:18:33:32 +0100] pipe_command: envp[20] = "CUPS_ENCRYPTION=IfRequested"
d [13/Jan/2005:18:33:32 +0100] AddCert: adding certificate for pid 3360
D [13/Jan/2005:18:33:32 +0100] CGI /usr/lib/cups/cgi-bin/jobs.cgi started - PID = 3360
I [13/Jan/2005:18:33:32 +0100] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=3360)
D [13/Jan/2005:18:33:32 +0100] SendCommand: 5 file=8
d [13/Jan/2005:18:33:32 +0100] SendCommand: Adding fd 8 to InputSet...
d [13/Jan/2005:18:33:32 +0100] SendCommand: Adding fd 5 to OutputSet...
d [13/Jan/2005:18:33:32 +0100] AcceptClient(lis=0x808d138) 0 NumClients = 1
D [13/Jan/2005:18:33:32 +0100] AcceptClient: 7 from localhost:631.
d [13/Jan/2005:18:33:32 +0100] AcceptClient: Adding fd 7 to InputSet...
d [13/Jan/2005:18:33:32 +0100] WriteClient: 5 Content-Type: text/html;charset=iso-8859-15
d [13/Jan/2005:18:33:32 +0100] WriteClient: 5 
d [13/Jan/2005:18:33:32 +0100] WriteClient: 5 1165 bytes
d [13/Jan/2005:18:33:32 +0100] ReadClient: 7, used=0, file=-1
D [13/Jan/2005:18:33:32 +0100] ReadClient: 7 POST / HTTP/1.1
d [13/Jan/2005:18:33:32 +0100] decode_auth(0x402fdc3c): Authorization string = ""
d [13/Jan/2005:18:33:32 +0100] decode_auth: 7 username=""
d [13/Jan/2005:18:33:32 +0100] IsAuthorized: con->uri = "/"
d [13/Jan/2005:18:33:32 +0100] FindBest: uri = "/"...
d [13/Jan/2005:18:33:32 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:32 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:32 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:32 +0100] FindBest: best = "/"
d [13/Jan/2005:18:33:32 +0100] IsAuthorized: auth = 0, satisfy=0...
d [13/Jan/2005:18:33:32 +0100] POST /
d [13/Jan/2005:18:33:32 +0100] CONTENT_TYPE = application/ipp
d [13/Jan/2005:18:33:32 +0100] ReadClient: 7 con->data_encoding = length, con->data_remaining = 411, con->file = -1
d [13/Jan/2005:18:33:32 +0100] ProcessIPPRequest(0x402fdc3c[7]): operation_id = 000a
d [13/Jan/2005:18:33:32 +0100] ProcessIPPRequest: URI="ipp://localhost/jobs"
d [13/Jan/2005:18:33:32 +0100] get_jobs(0x402fdc3c[7], ipp://localhost/jobs)
d [13/Jan/2005:18:33:32 +0100] get_jobs: job->id = 1
d [13/Jan/2005:18:33:32 +0100] get_jobs: job->id = 2
d [13/Jan/2005:18:33:32 +0100] get_jobs: job->id = 3
d [13/Jan/2005:18:33:32 +0100] get_jobs: job->id = 4
d [13/Jan/2005:18:33:32 +0100] get_jobs: job->id = 5
d [13/Jan/2005:18:33:32 +0100] get_jobs: job->id = 6
d [13/Jan/2005:18:33:32 +0100] get_jobs: job->id = 7
d [13/Jan/2005:18:33:32 +0100] get_jobs: job->id = 8
d [13/Jan/2005:18:33:32 +0100] get_jobs: job->id = 9
d [13/Jan/2005:18:33:32 +0100] get_jobs: job->id = 10
d [13/Jan/2005:18:33:32 +0100] get_jobs: count = 1
d [13/Jan/2005:18:33:32 +0100] copy_attrs(0x81b0f88, 0x81b0718, 0x81b0e80, 2)
d [13/Jan/2005:18:33:32 +0100] copy_attribute(0x81b0f88, 0x81afa70[job-originating-user-name,2,42])
d [13/Jan/2005:18:33:32 +0100] copy_attribute(0x81b0f88, 0x81b0798[job-name,2,42])
d [13/Jan/2005:18:33:32 +0100] copy_attribute(0x81b0f88, 0x81af5f8[job-id,2,21])
d [13/Jan/2005:18:33:32 +0100] copy_attribute(0x81b0f88, 0x81af620[job-state,2,23])
d [13/Jan/2005:18:33:32 +0100] copy_attribute(0x81b0f88, 0x81af690[job-printer-uri,2,45])
d [13/Jan/2005:18:33:32 +0100] copy_attribute(0x81b0f88, 0x81b06d8[job-name,2,42])
d [13/Jan/2005:18:33:32 +0100] copy_attribute(0x81b0f88, 0x81b04f0[job-k-octets,2,21])
d [13/Jan/2005:18:33:32 +0100] copy_attribute(0x81b0f88, 0x81b0518[time-at-creation,2,21])
d [13/Jan/2005:18:33:32 +0100] copy_attribute(0x81b0f88, 0x81ae6a0[time-at-processing,2,21])
d [13/Jan/2005:18:33:32 +0100] copy_attribute(0x81b0f88, 0x81ae6c8[time-at-completed,2,13])
d [13/Jan/2005:18:33:32 +0100] add_job_state_reasons(0x402fdc3c[7], 10)
D [13/Jan/2005:18:33:32 +0100] ProcessIPPRequest: 7 status_code=1
d [13/Jan/2005:18:33:32 +0100] ProcessIPPRequest: Adding fd 7 to OutputSet...
d [13/Jan/2005:18:33:32 +0100] WriteClient: Removing fd 7 from OutputSet...
d [13/Jan/2005:18:33:32 +0100] PID 3360 exited with no errors.
d [13/Jan/2005:18:33:32 +0100] DeleteCert: removing certificate for pid 3360
d [13/Jan/2005:18:33:32 +0100] WriteClient: 5 1394 bytes
d [13/Jan/2005:18:33:32 +0100] ReadClient: 7, used=0, file=-1
d [13/Jan/2005:18:33:32 +0100] ReadClient: httpGets returned EOF...
D [13/Jan/2005:18:33:32 +0100] CloseClient: 7
d [13/Jan/2005:18:33:32 +0100] CloseClient: Removing fd 7 from InputSet and OutputSet...
d [13/Jan/2005:18:33:32 +0100] WriteClient: Removing fd 5 from OutputSet...
d [13/Jan/2005:18:33:32 +0100] WriteClient: Removing fd 8 from InputSet...
d [13/Jan/2005:18:33:32 +0100] WriteClient: 5 Closing data file 8.
d [13/Jan/2005:18:33:33 +0100] select_timeout: 11 seconds to process active jobs
d [13/Jan/2005:18:33:38 +0100] ReadClient: 5, used=0, file=-1
D [13/Jan/2005:18:33:38 +0100] ReadClient: 5 GET /jobs HTTP/1.1
d [13/Jan/2005:18:33:38 +0100] decode_auth(0x402fb008): Authorization string = ""
d [13/Jan/2005:18:33:38 +0100] decode_auth: 5 username=""
d [13/Jan/2005:18:33:38 +0100] IsAuthorized: con->uri = "/jobs"
d [13/Jan/2005:18:33:38 +0100] FindBest: uri = "/jobs"...
d [13/Jan/2005:18:33:38 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:38 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:38 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:38 +0100] FindBest: best = "/jobs"
d [13/Jan/2005:18:33:38 +0100] IsAuthorized: auth = 0, satisfy=0...
d [13/Jan/2005:18:33:38 +0100] pipe_command: command="/usr/lib/cups/cgi-bin/jobs.cgi", options=""
d [13/Jan/2005:18:33:38 +0100] pipe_command: argv[0] = "jobs.cgi"
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[0] = "PATH=/bin:/usr/bin"
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[1] = "SERVER_SOFTWARE=CUPS/1.1"
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[2] = "GATEWAY_INTERFACE=CGI/1.1"
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[3] = "SERVER_PROTOCOL=HTTP/1.1"
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[4] = "REDIRECT_STATUS=1"
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[5] = "CUPS_SERVER=localhost"
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[6] = "IPP_PORT=631"
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[7] = "SERVER_NAME=localhost"
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[8] = "SERVER_PORT=631"
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[9] = "REMOTE_ADDR=127.0.0.1"
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[10] = "REMOTE_HOST=localhost"
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[11] = "REMOTE_USER="
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[12] = "LANG=en.ISO8859-15"
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[13] = "TZ=Europe/Berlin"
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[14] = "TMPDIR=/var/spool/cups/tmp"
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[15] = "CUPS_DATADIR=/usr/share/cups"
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[16] = "CUPS_SERVERROOT=/etc/cups"
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[17] = "HTTP_USER_AGENT=Opera/7.54 (X11; Linux i686; U)  [en]"
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[18] = "SCRIPT_NAME=/jobs"
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[19] = "REQUEST_METHOD=GET"
d [13/Jan/2005:18:33:38 +0100] pipe_command: envp[20] = "CUPS_ENCRYPTION=IfRequested"
d [13/Jan/2005:18:33:38 +0100] AddCert: adding certificate for pid 3361
D [13/Jan/2005:18:33:38 +0100] CGI /usr/lib/cups/cgi-bin/jobs.cgi started - PID = 3361
I [13/Jan/2005:18:33:38 +0100] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=3361)
D [13/Jan/2005:18:33:38 +0100] SendCommand: 5 file=8
d [13/Jan/2005:18:33:38 +0100] SendCommand: Adding fd 8 to InputSet...
d [13/Jan/2005:18:33:38 +0100] SendCommand: Adding fd 5 to OutputSet...
d [13/Jan/2005:18:33:38 +0100] AcceptClient(lis=0x808d138) 0 NumClients = 1
D [13/Jan/2005:18:33:38 +0100] AcceptClient: 7 from localhost:631.
d [13/Jan/2005:18:33:38 +0100] AcceptClient: Adding fd 7 to InputSet...
d [13/Jan/2005:18:33:38 +0100] WriteClient: 5 Content-Type: text/html;charset=iso-8859-15
d [13/Jan/2005:18:33:38 +0100] WriteClient: 5 
d [13/Jan/2005:18:33:38 +0100] WriteClient: 5 1165 bytes
d [13/Jan/2005:18:33:38 +0100] AcceptClient(lis=0x808d138) 0 NumClients = 2
D [13/Jan/2005:18:33:38 +0100] AcceptClient: 10 from localhost:631.
d [13/Jan/2005:18:33:38 +0100] AcceptClient: Adding fd 10 to InputSet...
d [13/Jan/2005:18:33:38 +0100] ReadClient: 7, used=0, file=-1
D [13/Jan/2005:18:33:38 +0100] ReadClient: 7 POST / HTTP/1.1
d [13/Jan/2005:18:33:38 +0100] decode_auth(0x402fdc3c): Authorization string = ""
d [13/Jan/2005:18:33:38 +0100] decode_auth: 7 username=""
d [13/Jan/2005:18:33:38 +0100] IsAuthorized: con->uri = "/"
d [13/Jan/2005:18:33:38 +0100] FindBest: uri = "/"...
d [13/Jan/2005:18:33:38 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:38 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:38 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:38 +0100] FindBest: best = "/"
d [13/Jan/2005:18:33:38 +0100] IsAuthorized: auth = 0, satisfy=0...
d [13/Jan/2005:18:33:38 +0100] POST /
d [13/Jan/2005:18:33:38 +0100] CONTENT_TYPE = application/ipp
d [13/Jan/2005:18:33:38 +0100] ReadClient: 7 con->data_encoding = length, con->data_remaining = 411, con->file = -1
d [13/Jan/2005:18:33:38 +0100] ProcessIPPRequest(0x402fdc3c[7]): operation_id = 000a
d [13/Jan/2005:18:33:38 +0100] ProcessIPPRequest: URI="ipp://localhost/jobs"
d [13/Jan/2005:18:33:38 +0100] get_jobs(0x402fdc3c[7], ipp://localhost/jobs)
d [13/Jan/2005:18:33:38 +0100] get_jobs: job->id = 1
d [13/Jan/2005:18:33:38 +0100] get_jobs: job->id = 2
d [13/Jan/2005:18:33:38 +0100] get_jobs: job->id = 3
d [13/Jan/2005:18:33:38 +0100] get_jobs: job->id = 4
d [13/Jan/2005:18:33:38 +0100] get_jobs: job->id = 5
d [13/Jan/2005:18:33:38 +0100] get_jobs: job->id = 6
d [13/Jan/2005:18:33:38 +0100] get_jobs: job->id = 7
d [13/Jan/2005:18:33:38 +0100] get_jobs: job->id = 8
d [13/Jan/2005:18:33:38 +0100] get_jobs: job->id = 9
d [13/Jan/2005:18:33:38 +0100] get_jobs: job->id = 10
d [13/Jan/2005:18:33:38 +0100] get_jobs: count = 1
d [13/Jan/2005:18:33:38 +0100] copy_attrs(0x81b1360, 0x81b0718, 0x81b13d0, 2)
d [13/Jan/2005:18:33:38 +0100] copy_attribute(0x81b1360, 0x81afa70[job-originating-user-name,2,42])
d [13/Jan/2005:18:33:38 +0100] copy_attribute(0x81b1360, 0x81b0798[job-name,2,42])
d [13/Jan/2005:18:33:38 +0100] copy_attribute(0x81b1360, 0x81af5f8[job-id,2,21])
d [13/Jan/2005:18:33:38 +0100] copy_attribute(0x81b1360, 0x81af620[job-state,2,23])
d [13/Jan/2005:18:33:38 +0100] copy_attribute(0x81b1360, 0x81af690[job-printer-uri,2,45])
d [13/Jan/2005:18:33:38 +0100] copy_attribute(0x81b1360, 0x81b06d8[job-name,2,42])
d [13/Jan/2005:18:33:38 +0100] copy_attribute(0x81b1360, 0x81b04f0[job-k-octets,2,21])
d [13/Jan/2005:18:33:38 +0100] copy_attribute(0x81b1360, 0x81b0518[time-at-creation,2,21])
d [13/Jan/2005:18:33:38 +0100] copy_attribute(0x81b1360, 0x81ae6a0[time-at-processing,2,21])
d [13/Jan/2005:18:33:38 +0100] copy_attribute(0x81b1360, 0x81ae6c8[time-at-completed,2,13])
d [13/Jan/2005:18:33:38 +0100] add_job_state_reasons(0x402fdc3c[7], 10)
D [13/Jan/2005:18:33:38 +0100] ProcessIPPRequest: 7 status_code=1
d [13/Jan/2005:18:33:38 +0100] ProcessIPPRequest: Adding fd 7 to OutputSet...
d [13/Jan/2005:18:33:38 +0100] ReadClient: 10, used=0, file=-1
D [13/Jan/2005:18:33:38 +0100] ReadClient: 10 GET /cups.css HTTP/1.1
d [13/Jan/2005:18:33:38 +0100] decode_auth(0x40300870): Authorization string = ""
d [13/Jan/2005:18:33:38 +0100] decode_auth: 10 username=""
d [13/Jan/2005:18:33:38 +0100] IsAuthorized: con->uri = "/cups.css"
d [13/Jan/2005:18:33:38 +0100] FindBest: uri = "/cups.css"...
d [13/Jan/2005:18:33:38 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:38 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:38 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:38 +0100] FindBest: best = "/"
d [13/Jan/2005:18:33:38 +0100] IsAuthorized: auth = 0, satisfy=0...
d [13/Jan/2005:18:33:38 +0100] get_file: 10 filename=/usr/share/cups/doc-root/cups.css size=87
d [13/Jan/2005:18:33:38 +0100] IsCGI(con=0x40300870, filename="/usr/share/cups/doc-root/cups.css", filestats=0xbfff3180, type=text/plain)
d [13/Jan/2005:18:33:38 +0100] IsCGI: Returning 0...
d [13/Jan/2005:18:33:38 +0100] check_if_modified: 10 If-Modified-Since="Wed, 12 Jan 2005 10:01:25 GMT"
d [13/Jan/2005:18:33:38 +0100] check_if_modified: 10 sizes=0,87 dates=1105520485,1105524085
D [13/Jan/2005:18:33:38 +0100] SendFile: 10 file=11
d [13/Jan/2005:18:33:38 +0100] SendFile: Adding fd 10 to OutputSet...
d [13/Jan/2005:18:33:38 +0100] WriteClient: Removing fd 10 from OutputSet...
d [13/Jan/2005:18:33:38 +0100] WriteClient: 10 Closing data file 11.
d [13/Jan/2005:18:33:38 +0100] WriteClient: Removing fd 7 from OutputSet...
d [13/Jan/2005:18:33:38 +0100] PID 3361 exited with no errors.
d [13/Jan/2005:18:33:38 +0100] DeleteCert: removing certificate for pid 3361
d [13/Jan/2005:18:33:38 +0100] WriteClient: 5 1394 bytes
d [13/Jan/2005:18:33:38 +0100] ReadClient: 7, used=0, file=-1
d [13/Jan/2005:18:33:38 +0100] ReadClient: httpGets returned EOF...
D [13/Jan/2005:18:33:38 +0100] CloseClient: 7
d [13/Jan/2005:18:33:38 +0100] CloseClient: Removing fd 7 from InputSet and OutputSet...
d [13/Jan/2005:18:33:38 +0100] WriteClient: Removing fd 5 from OutputSet...
d [13/Jan/2005:18:33:38 +0100] WriteClient: Removing fd 8 from InputSet...
d [13/Jan/2005:18:33:38 +0100] WriteClient: 5 Closing data file 8.
d [13/Jan/2005:18:33:39 +0100] ReadClient: 5, used=0, file=-1
D [13/Jan/2005:18:33:39 +0100] ReadClient: 5 GET /images/navbar.gif HTTP/1.1
d [13/Jan/2005:18:33:39 +0100] decode_auth(0x402fb008): Authorization string = ""
d [13/Jan/2005:18:33:39 +0100] decode_auth: 5 username=""
d [13/Jan/2005:18:33:39 +0100] IsAuthorized: con->uri = "/images/navbar.gif"
d [13/Jan/2005:18:33:39 +0100] FindBest: uri = "/images/navbar.gif"...
d [13/Jan/2005:18:33:39 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:39 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:39 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:39 +0100] FindBest: best = "/"
d [13/Jan/2005:18:33:39 +0100] IsAuthorized: auth = 0, satisfy=0...
d [13/Jan/2005:18:33:39 +0100] get_file: 5 filename=/usr/share/cups/doc-root/images/navbar.gif size=2869
d [13/Jan/2005:18:33:39 +0100] IsCGI(con=0x402fb008, filename="/usr/share/cups/doc-root/images/navbar.gif", filestats=0xbfff3180, type=image/gif)
d [13/Jan/2005:18:33:39 +0100] IsCGI: Returning 0...
d [13/Jan/2005:18:33:39 +0100] check_if_modified: 5 If-Modified-Since="Wed, 12 Jan 2005 10:01:26 GMT"
d [13/Jan/2005:18:33:39 +0100] check_if_modified: 5 sizes=0,2869 dates=1105520486,1105524086
D [13/Jan/2005:18:33:39 +0100] SendFile: 5 file=7
d [13/Jan/2005:18:33:39 +0100] SendFile: Adding fd 5 to OutputSet...
d [13/Jan/2005:18:33:39 +0100] WriteClient: 5 2048 bytes
d [13/Jan/2005:18:33:39 +0100] WriteClient: Removing fd 5 from OutputSet...
d [13/Jan/2005:18:33:39 +0100] WriteClient: 5 Closing data file 7.
d [13/Jan/2005:18:33:39 +0100] ReadClient: 10, used=0, file=-1
D [13/Jan/2005:18:33:39 +0100] ReadClient: 10 GET /images/left.gif HTTP/1.1
d [13/Jan/2005:18:33:39 +0100] decode_auth(0x402fdc3c): Authorization string = ""
d [13/Jan/2005:18:33:39 +0100] decode_auth: 10 username=""
d [13/Jan/2005:18:33:39 +0100] IsAuthorized: con->uri = "/images/left.gif"
d [13/Jan/2005:18:33:39 +0100] FindBest: uri = "/images/left.gif"...
d [13/Jan/2005:18:33:39 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:39 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:39 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:39 +0100] FindBest: best = "/"
d [13/Jan/2005:18:33:39 +0100] IsAuthorized: auth = 0, satisfy=0...
d [13/Jan/2005:18:33:39 +0100] get_file: 10 filename=/usr/share/cups/doc-root/images/left.gif size=110
d [13/Jan/2005:18:33:39 +0100] IsCGI(con=0x402fdc3c, filename="/usr/share/cups/doc-root/images/left.gif", filestats=0xbfff3180, type=image/gif)
d [13/Jan/2005:18:33:39 +0100] IsCGI: Returning 0...
d [13/Jan/2005:18:33:39 +0100] check_if_modified: 10 If-Modified-Since="Wed, 12 Jan 2005 10:01:26 GMT"
d [13/Jan/2005:18:33:39 +0100] check_if_modified: 10 sizes=0,110 dates=1105520486,1105524086
D [13/Jan/2005:18:33:39 +0100] SendFile: 10 file=7
d [13/Jan/2005:18:33:39 +0100] SendFile: Adding fd 10 to OutputSet...
d [13/Jan/2005:18:33:39 +0100] WriteClient: Removing fd 10 from OutputSet...
d [13/Jan/2005:18:33:39 +0100] WriteClient: 10 Closing data file 7.
d [13/Jan/2005:18:33:39 +0100] AcceptClient(lis=0x808d138) 0 NumClients = 2
D [13/Jan/2005:18:33:39 +0100] AcceptClient: 7 from localhost:631.
d [13/Jan/2005:18:33:39 +0100] AcceptClient: Adding fd 7 to InputSet...
d [13/Jan/2005:18:33:39 +0100] AcceptClient(lis=0x808d138) 0 NumClients = 3
D [13/Jan/2005:18:33:39 +0100] AcceptClient: 8 from localhost:631.
d [13/Jan/2005:18:33:39 +0100] AcceptClient: Adding fd 8 to InputSet...
d [13/Jan/2005:18:33:39 +0100] ReadClient: 5, used=0, file=-1
D [13/Jan/2005:18:33:39 +0100] ReadClient: 5 GET /images/cancel-job.gif HTTP/1.1
d [13/Jan/2005:18:33:39 +0100] decode_auth(0x402fb008): Authorization string = ""
d [13/Jan/2005:18:33:39 +0100] decode_auth: 5 username=""
d [13/Jan/2005:18:33:39 +0100] IsAuthorized: con->uri = "/images/cancel-job.gif"
d [13/Jan/2005:18:33:39 +0100] FindBest: uri = "/images/cancel-job.gif"...
d [13/Jan/2005:18:33:39 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:39 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:39 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:39 +0100] FindBest: best = "/"
d [13/Jan/2005:18:33:39 +0100] IsAuthorized: auth = 0, satisfy=0...
d [13/Jan/2005:18:33:39 +0100] get_file: 5 filename=/usr/share/cups/doc-root/images/cancel-job.gif size=248
d [13/Jan/2005:18:33:39 +0100] IsCGI(con=0x402fb008, filename="/usr/share/cups/doc-root/images/cancel-job.gif", filestats=0xbfff3180, type=image/gif)
d [13/Jan/2005:18:33:39 +0100] IsCGI: Returning 0...
d [13/Jan/2005:18:33:39 +0100] check_if_modified: 5 If-Modified-Since="Wed, 12 Jan 2005 10:01:26 GMT"
d [13/Jan/2005:18:33:39 +0100] check_if_modified: 5 sizes=0,248 dates=1105520486,1105524086
D [13/Jan/2005:18:33:39 +0100] SendFile: 5 file=11
d [13/Jan/2005:18:33:39 +0100] SendFile: Adding fd 5 to OutputSet...
d [13/Jan/2005:18:33:39 +0100] ReadClient: 10, used=0, file=-1
D [13/Jan/2005:18:33:39 +0100] ReadClient: 10 GET /images/show-completed.gif HTTP/1.1
d [13/Jan/2005:18:33:39 +0100] decode_auth(0x402fdc3c): Authorization string = ""
d [13/Jan/2005:18:33:39 +0100] decode_auth: 10 username=""
d [13/Jan/2005:18:33:39 +0100] IsAuthorized: con->uri = "/images/show-completed.gif"
d [13/Jan/2005:18:33:39 +0100] FindBest: uri = "/images/show-completed.gif"...
d [13/Jan/2005:18:33:39 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:39 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:39 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:39 +0100] FindBest: best = "/"
d [13/Jan/2005:18:33:39 +0100] IsAuthorized: auth = 0, satisfy=0...
d [13/Jan/2005:18:33:39 +0100] get_file: 10 filename=/usr/share/cups/doc-root/images/show-completed.gif size=337
d [13/Jan/2005:18:33:39 +0100] IsCGI(con=0x402fdc3c, filename="/usr/share/cups/doc-root/images/show-completed.gif", filestats=0xbfff3180, type=image/gif)
d [13/Jan/2005:18:33:39 +0100] IsCGI: Returning 0...
d [13/Jan/2005:18:33:39 +0100] check_if_modified: 10 If-Modified-Since="Wed, 12 Jan 2005 10:01:26 GMT"
d [13/Jan/2005:18:33:39 +0100] check_if_modified: 10 sizes=0,337 dates=1105520486,1105524086
D [13/Jan/2005:18:33:39 +0100] SendFile: 10 file=12
d [13/Jan/2005:18:33:39 +0100] SendFile: Adding fd 10 to OutputSet...
d [13/Jan/2005:18:33:39 +0100] ReadClient: 7, used=0, file=-1
D [13/Jan/2005:18:33:39 +0100] ReadClient: 7 GET /images/right.gif HTTP/1.1
d [13/Jan/2005:18:33:39 +0100] decode_auth(0x40300870): Authorization string = ""
d [13/Jan/2005:18:33:39 +0100] decode_auth: 7 username=""
d [13/Jan/2005:18:33:39 +0100] IsAuthorized: con->uri = "/images/right.gif"
d [13/Jan/2005:18:33:39 +0100] FindBest: uri = "/images/right.gif"...
d [13/Jan/2005:18:33:39 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:39 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:39 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:39 +0100] FindBest: best = "/"
d [13/Jan/2005:18:33:39 +0100] IsAuthorized: auth = 0, satisfy=0...
d [13/Jan/2005:18:33:39 +0100] get_file: 7 filename=/usr/share/cups/doc-root/images/right.gif size=145
d [13/Jan/2005:18:33:39 +0100] IsCGI(con=0x40300870, filename="/usr/share/cups/doc-root/images/right.gif", filestats=0xbfff3180, type=image/gif)
d [13/Jan/2005:18:33:39 +0100] IsCGI: Returning 0...
d [13/Jan/2005:18:33:39 +0100] check_if_modified: 7 If-Modified-Since="Wed, 12 Jan 2005 10:01:26 GMT"
d [13/Jan/2005:18:33:39 +0100] check_if_modified: 7 sizes=0,145 dates=1105520486,1105524086
D [13/Jan/2005:18:33:39 +0100] SendFile: 7 file=13
d [13/Jan/2005:18:33:39 +0100] SendFile: Adding fd 7 to OutputSet...
d [13/Jan/2005:18:33:39 +0100] ReadClient: 8, used=0, file=-1
D [13/Jan/2005:18:33:39 +0100] ReadClient: 8 GET /images/hold-job.gif HTTP/1.1
d [13/Jan/2005:18:33:39 +0100] decode_auth(0x403034a4): Authorization string = ""
d [13/Jan/2005:18:33:39 +0100] decode_auth: 8 username=""
d [13/Jan/2005:18:33:39 +0100] IsAuthorized: con->uri = "/images/hold-job.gif"
d [13/Jan/2005:18:33:39 +0100] FindBest: uri = "/images/hold-job.gif"...
d [13/Jan/2005:18:33:39 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:39 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:39 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:39 +0100] FindBest: best = "/"
d [13/Jan/2005:18:33:39 +0100] IsAuthorized: auth = 0, satisfy=0...
d [13/Jan/2005:18:33:39 +0100] get_file: 8 filename=/usr/share/cups/doc-root/images/hold-job.gif size=228
d [13/Jan/2005:18:33:39 +0100] IsCGI(con=0x403034a4, filename="/usr/share/cups/doc-root/images/hold-job.gif", filestats=0xbfff3180, type=image/gif)
d [13/Jan/2005:18:33:39 +0100] IsCGI: Returning 0...
d [13/Jan/2005:18:33:39 +0100] check_if_modified: 8 If-Modified-Since="Wed, 12 Jan 2005 10:01:26 GMT"
d [13/Jan/2005:18:33:39 +0100] check_if_modified: 8 sizes=0,228 dates=1105520486,1105524086
D [13/Jan/2005:18:33:39 +0100] SendFile: 8 file=14
d [13/Jan/2005:18:33:39 +0100] SendFile: Adding fd 8 to OutputSet...
d [13/Jan/2005:18:33:39 +0100] WriteClient: Removing fd 5 from OutputSet...
d [13/Jan/2005:18:33:39 +0100] WriteClient: 5 Closing data file 11.
d [13/Jan/2005:18:33:39 +0100] WriteClient: Removing fd 10 from OutputSet...
d [13/Jan/2005:18:33:39 +0100] WriteClient: 10 Closing data file 12.
d [13/Jan/2005:18:33:39 +0100] WriteClient: Removing fd 7 from OutputSet...
d [13/Jan/2005:18:33:39 +0100] WriteClient: 7 Closing data file 13.
d [13/Jan/2005:18:33:39 +0100] WriteClient: Removing fd 8 from OutputSet...
d [13/Jan/2005:18:33:39 +0100] WriteClient: 8 Closing data file 14.
d [13/Jan/2005:18:33:40 +0100] select_timeout: 6 seconds to send browse update
d [13/Jan/2005:18:33:46 +0100] select_timeout: 1 seconds to send browse update
d [13/Jan/2005:18:33:47 +0100] select_timeout: 10 seconds to send browse update
d [13/Jan/2005:18:33:48 +0100] ReadClient: 7, used=0, file=-1
D [13/Jan/2005:18:33:48 +0100] ReadClient: 7 GET /jobs/?op=cancel-job&job_id=10&job_printer_uri=/printers/hp-laserjet HTTP/1.1
d [13/Jan/2005:18:33:48 +0100] decode_auth(0x40300870): Authorization string = ""
d [13/Jan/2005:18:33:48 +0100] decode_auth: 7 username=""
d [13/Jan/2005:18:33:48 +0100] IsAuthorized: con->uri = "/jobs/?op=cancel-job&job_id=10&job_printer_uri=/printers/hp-laserjet"
d [13/Jan/2005:18:33:48 +0100] FindBest: uri = "/jobs/?op=cancel-job&job_id=10&job_printer_uri=/printers/hp-laserjet"...
d [13/Jan/2005:18:33:48 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:48 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:48 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:48 +0100] FindBest: best = "/jobs"
d [13/Jan/2005:18:33:48 +0100] IsAuthorized: auth = 0, satisfy=0...
d [13/Jan/2005:18:33:48 +0100] pipe_command: command="/usr/lib/cups/cgi-bin/jobs.cgi", options="?op=cancel-job&job_id=10&job_printer_uri=/printers/hp-laserjet"
d [13/Jan/2005:18:33:48 +0100] pipe_command: argv[0] = "jobs.cgi"
d [13/Jan/2005:18:33:48 +0100] pipe_command: argv[1] = "op=cancel-job&job_id=10&job_printer_uri=/printers/hp-laserjet"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[0] = "PATH=/bin:/usr/bin"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[1] = "SERVER_SOFTWARE=CUPS/1.1"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[2] = "GATEWAY_INTERFACE=CGI/1.1"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[3] = "SERVER_PROTOCOL=HTTP/1.1"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[4] = "REDIRECT_STATUS=1"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[5] = "CUPS_SERVER=localhost"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[6] = "IPP_PORT=631"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[7] = "SERVER_NAME=localhost"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[8] = "SERVER_PORT=631"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[9] = "REMOTE_ADDR=127.0.0.1"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[10] = "REMOTE_HOST=localhost"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[11] = "REMOTE_USER="
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[12] = "LANG=en.ISO8859-15"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[13] = "TZ=Europe/Berlin"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[14] = "TMPDIR=/var/spool/cups/tmp"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[15] = "CUPS_DATADIR=/usr/share/cups"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[16] = "CUPS_SERVERROOT=/etc/cups"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[17] = "HTTP_USER_AGENT=Opera/7.54 (X11; Linux i686; U)  [en]"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[18] = "SCRIPT_NAME=/jobs/"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[19] = "REQUEST_METHOD=GET"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[20] = "QUERY_STRING=op=cancel-job&job_id=10&job_printer_uri=/printers/hp-laserjet"
d [13/Jan/2005:18:33:48 +0100] pipe_command: envp[21] = "CUPS_ENCRYPTION=IfRequested"
d [13/Jan/2005:18:33:48 +0100] AddCert: adding certificate for pid 3362
D [13/Jan/2005:18:33:48 +0100] CGI /usr/lib/cups/cgi-bin/jobs.cgi started - PID = 3362
I [13/Jan/2005:18:33:48 +0100] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=3362)
D [13/Jan/2005:18:33:48 +0100] SendCommand: 7 file=12
d [13/Jan/2005:18:33:48 +0100] SendCommand: Adding fd 12 to InputSet...
d [13/Jan/2005:18:33:48 +0100] SendCommand: Adding fd 7 to OutputSet...
d [13/Jan/2005:18:33:48 +0100] AcceptClient(lis=0x808d138) 0 NumClients = 4
D [13/Jan/2005:18:33:48 +0100] AcceptClient: 11 from localhost:631.
d [13/Jan/2005:18:33:48 +0100] AcceptClient: Adding fd 11 to InputSet...
d [13/Jan/2005:18:33:48 +0100] WriteClient: 7 Content-Type: text/html;charset=iso-8859-15
d [13/Jan/2005:18:33:48 +0100] WriteClient: 7 
d [13/Jan/2005:18:33:48 +0100] WriteClient: 7 1165 bytes
d [13/Jan/2005:18:33:48 +0100] ReadClient: 11, used=0, file=-1
D [13/Jan/2005:18:33:48 +0100] ReadClient: 11 POST /jobs HTTP/1.1
d [13/Jan/2005:18:33:48 +0100] decode_auth(0x403060d8): Authorization string = ""
d [13/Jan/2005:18:33:48 +0100] decode_auth: 11 username=""
d [13/Jan/2005:18:33:48 +0100] IsAuthorized: con->uri = "/jobs"
d [13/Jan/2005:18:33:48 +0100] FindBest: uri = "/jobs"...
d [13/Jan/2005:18:33:48 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:48 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:48 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:48 +0100] FindBest: best = "/jobs"
d [13/Jan/2005:18:33:48 +0100] IsAuthorized: auth = 0, satisfy=0...
d [13/Jan/2005:18:33:48 +0100] POST /jobs
d [13/Jan/2005:18:33:48 +0100] CONTENT_TYPE = application/ipp
d [13/Jan/2005:18:33:48 +0100] ReadClient: 11 con->data_encoding = length, con->data_remaining = 138, con->file = -1
d [13/Jan/2005:18:33:48 +0100] ProcessIPPRequest(0x403060d8[11]): operation_id = 0008
d [13/Jan/2005:18:33:48 +0100] ProcessIPPRequest: URI="ipp://localhost/jobs/10"
d [13/Jan/2005:18:33:48 +0100] cancel_job(0x403060d8[11], ipp://localhost/jobs/10)
d [13/Jan/2005:18:33:48 +0100] FindBest: uri = "/jobs"...
d [13/Jan/2005:18:33:48 +0100] FindBest: Location / Limit 7f
d [13/Jan/2005:18:33:48 +0100] FindBest: Location /jobs Limit 7f
d [13/Jan/2005:18:33:48 +0100] FindBest: Location /admin Limit 7f
d [13/Jan/2005:18:33:48 +0100] FindBest: best = "/jobs"
d [13/Jan/2005:18:33:48 +0100] validate_user(0x403060d8[11], "", 0xbfff18b0, 1024)
D [13/Jan/2005:18:33:48 +0100] CancelJob: id = 10
D [13/Jan/2005:18:33:48 +0100] StopJob: id = 10, force = 0
D [13/Jan/2005:18:33:48 +0100] StopJob: printer state is 3
d [13/Jan/2005:18:33:48 +0100] StopJob: Removing fd 9 from InputSet...
d [13/Jan/2005:18:33:48 +0100] StopJob: Freeing status buffer...
d [13/Jan/2005:18:33:48 +0100] SaveJob: Closing file 9...
I [13/Jan/2005:18:33:48 +0100] Job 10 was cancelled by ''.
D [13/Jan/2005:18:33:48 +0100] ProcessIPPRequest: 11 status_code=0
d [13/Jan/2005:18:33:48 +0100] ProcessIPPRequest: Adding fd 11 to OutputSet...
d [13/Jan/2005:18:33:48 +0100] WriteClient: Removing fd 11 from OutputSet...
d [13/Jan/2005:18:33:48 +0100] PID 3362 exited with no errors.
d [13/Jan/2005:18:33:48 +0100] DeleteCert: removing certificate for pid 3362
d [13/Jan/2005:18:33:48 +0100] ReadClient: 11, used=0, file=-1
d [13/Jan/2005:18:33:48 +0100] ReadClient: httpGets returned EOF...
D [13/Jan/2005:18:33:48 +0100] CloseClient: 11
d [13/Jan/2005:18:33:48 +0100] CloseClient: Removing fd 11 from InputSet and OutputSet...
d [13/Jan/2005:18:33:48 +0100] WriteClient: Removing fd 7 from OutputSet...
d [13/Jan/2005:18:33:48 +0100] WriteClient: Removing fd 12 from InputSet...
d [13/Jan/2005:18:33:48 +0100] WriteClient: 7 Closing data file 12.
E [13/Jan/2005:18:33:48 +0100] PID 3355 stopped with status 1!
d [13/Jan/2005:18:33:49 +0100] select_timeout: 28 seconds to send browse update
d [13/Jan/2005:18:34:17 +0100] select_timeout: 1 seconds to send browse update
d [13/Jan/2005:18:34:18 +0100] select_timeout: 2 seconds to send browse update
d [13/Jan/2005:18:34:20 +0100] select_timeout: 28 seconds to send browse update
d [13/Jan/2005:18:34:48 +0100] select_timeout: 1 seconds to send browse update
d [13/Jan/2005:18:34:49 +0100] select_timeout: 2 seconds to send browse update
d [13/Jan/2005:18:34:51 +0100] select_timeout: 28 seconds to send browse update
d [13/Jan/2005:18:35:19 +0100] select_timeout: 1 seconds to send browse update
d [13/Jan/2005:18:35:20 +0100] select_timeout: 2 seconds to send browse update
d [13/Jan/2005:18:35:22 +0100] select_timeout: 28 seconds to send browse update
