Thomas Kaiser <Thomas.Kaiser.RemoveThis@phg-online.de> writes:
> Set the 'LogLevel' in cupsd.conf to debug on both machines and restart the
> CUPS scheduler. Search the PPD on the Mac (in /etc/cups/ppd/) and post all
> lines starting with *Cups.
Thanks for your reply. On the client, /etc/cups/ppd is empty, though
there is a binary file /etc/cups/ppds.dat. I tried copying the lp.ppd
file from the server to the client (as lp.RemoveThis@vihta.ppd) but with no
difference in the result.
I set LogLevel to `debug' as you suggested, and here are the lines
from /var/log/cups/error_log when I submit an unsuccessful job (note
the clocks on the two machines appear to be about 30 secs out of
sync). Sorry for including so much material - I'm not sure how much
might be relevant, as there's nothing in here that looks suspicious to
me (the only error is a Locale problem, which appears to be benign).
I've wrapped some of the longer lines of output.
################ On the client:##############################################
I [05/Jan/2004:21:59:00 +0000] Listening to 7f000001:631
D [05/Jan/2004:21:59:00 +0000] AddLocation: added location 'CUPS_INTERNAL_BROWSE_ACL'
D [05/Jan/2004:21:59:00 +0000] AllowIP: CUPS_INTERNAL_BROWSE_ACL allow 7f000001/ffffffff
D [05/Jan/2004:21:59:00 +0000] AllowHost: CUPS_INTERNAL_BROWSE_ACL allow @LOCAL
D [05/Jan/2004:21:59:00 +0000] DenyIP: CUPS_INTERNAL_BROWSE_ACL deny 00000000/00000000
D [05/Jan/2004:21:59:00 +0000] AddLocation: added location '/'
D [05/Jan/2004:21:59:00 +0000] DenyIP: / deny 00000000/00000000
D [05/Jan/2004:21:59:00 +0000] AllowIP: / allow 7f000001/ffffffff
D [05/Jan/2004:21:59:00 +0000] AddLocation: added location '/admin'
D [05/Jan/2004:21:59:00 +0000] DenyIP: /admin deny 00000000/00000000
D [05/Jan/2004:21:59:00 +0000] AllowIP: /admin allow 7f000001/ffffffff
I [05/Jan/2004:21:59:00 +0000] Loaded configuration file "/private/etc/cups/cupsd.conf"
I [05/Jan/2004:21:59:00 +0000] Configured for up to 100 clients.
I [05/Jan/2004:21:59:00 +0000] Allowing up to 100 client connections per host.
I [05/Jan/2004:21:59:00 +0000] Full reload is required.
D [05/Jan/2004:21:59:00 +0000] LoadDevices: Added device "fax"...
D [05/Jan/2004:21:59:00 +0000] LoadDevices: Added device "fax://dev/cu.modem"...
D [05/Jan/2004:21:59:00 +0000] LoadDevices: Added device "fax://dev/cu.Bluetooth-Modem"...
D [05/Jan/2004:21:59:00 +0000] LoadDevices: Added device "http"...
D [05/Jan/2004:21:59:00 +0000] LoadDevices: Added device "ipp"...
D [05/Jan/2004:21:59:00 +0000] LoadDevices: Added device "lpd"...
D [05/Jan/2004:21:59:00 +0000] LoadDevices: Added device "mdns"...
D [05/Jan/2004:21:59:00 +0000] LoadDevices: Added device "pap"...
D [05/Jan/2004:21:59:00 +0000] LoadDevices: Added device "smb"...
D [05/Jan/2004:21:59:00 +0000] LoadDevices: Added device "socket"...
D [05/Jan/2004:21:59:00 +0000] LoadDevices: Added device "usb"...
I [05/Jan/2004:21:59:00 +0000] LoadPPDs: Read "/private/etc/cups/ppds.dat", 209 PPDs...
I [05/Jan/2004:21:59:00 +0000] LoadPPDs: No new or changed PPDs...
D [05/Jan/2004:21:59:00 +0000] LoadAllJobs: Scanning /private/var/spool/cups...
I [05/Jan/2004:21:59:00 +0000] Full reload complete.
D [05/Jan/2004:21:59:00 +0000] StartListening: NumListeners=1
D [05/Jan/2004:21:59:00 +0000] StartListening: address=7f000001 port=631
D [05/Jan/2004:21:59:00 +0000] ResumeListening: setting input bits...
I [05/Jan/2004:22:07:03 +0000] Added remote printer "lp@vihta"...
D [05/Jan/2004:22:08:29 +0000] AcceptClient() 5 from localhost:631.
D [05/Jan/2004:22:08:29 +0000] ReadClient() 5 POST / HTTP/1.1
D [05/Jan/2004:22:08:29 +0000] ProcessIPPRequest: 5 status_code=1
D [05/Jan/2004:22:08:29 +0000] AcceptClient() 7 from localhost:631.
D [05/Jan/2004:22:08:29 +0000] CloseClient() 5
D [05/Jan/2004:22:08:29 +0000] ReadClient() 7 POST / HTTP/1.1
D [05/Jan/2004:22:08:29 +0000] ProcessIPPRequest: 7 status_code=1
D [05/Jan/2004:22:08:29 +0000] AcceptClient() 5 from localhost:631.
D [05/Jan/2004:22:08:29 +0000] CloseClient() 7
D [05/Jan/2004:22:08:29 +0000] ReadClient() 5 POST / HTTP/1.1
D [05/Jan/2004:22:08:29 +0000] ProcessIPPRequest: 5 status_code=0
D [05/Jan/2004:22:08:29 +0000] AcceptClient() 7 from localhost:631.
D [05/Jan/2004:22:08:29 +0000] ReadClient() 7 POST / HTTP/1.1
D [05/Jan/2004:22:08:29 +0000] ProcessIPPRequest: 7 status_code=1
D [05/Jan/2004:22:08:29 +0000] ReadClient() 7 POST / HTTP/1.1
D [05/Jan/2004:22:08:29 +0000] ProcessIPPRequest: 7 status_code=1
D [05/Jan/2004:22:08:29 +0000] ReadClient() 7 POST / HTTP/1.1
D [05/Jan/2004:22:08:29 +0000] ProcessIPPRequest: 7 status_code=1
D [05/Jan/2004:22:08:29 +0000] ReadClient() 7 POST / HTTP/1.1
D [05/Jan/2004:22:08:29 +0000] ProcessIPPRequest: 7 status_code=1
D [05/Jan/2004:22:08:34 +0000] AcceptClient() 8 from localhost:631.
D [05/Jan/2004:22:08:34 +0000] ReadClient() 8 POST / HTTP/1.1
D [05/Jan/2004:22:08:34 +0000] ProcessIPPRequest: 8 status_code=1
D [05/Jan/2004:22:08:34 +0000] AcceptClient() 9 from localhost:631.
D [05/Jan/2004:22:08:34 +0000] CloseClient() 8
D [05/Jan/2004:22:08:34 +0000] ReadClient() 9 POST / HTTP/1.1
D [05/Jan/2004:22:08:34 +0000] ProcessIPPRequest: 9 status_code=1
D [05/Jan/2004:22:08:34 +0000] AcceptClient() 8 from localhost:631.
D [05/Jan/2004:22:08:34 +0000] CloseClient() 9
D [05/Jan/2004:22:08:34 +0000] ReadClient() 8 POST / HTTP/1.1
D [05/Jan/2004:22:08:34 +0000] ProcessIPPRequest: 8 status_code=0
D [05/Jan/2004:22:08:34 +0000] AcceptClient() 9 from localhost:631.
D [05/Jan/2004:22:08:34 +0000] ReadClient() 9 POST /jobs/ HTTP/1.1
D [05/Jan/2004:22:08:34 +0000] ProcessIPPRequest: 9 status_code=1
D [05/Jan/2004:22:08:34 +0000] ReadClient() 9 POST / HTTP/1.1
D [05/Jan/2004:22:08:34 +0000] ProcessIPPRequest: 9 status_code=1
D [05/Jan/2004:22:08:34 +0000] ReadClient() 9 POST / HTTP/1.1
D [05/Jan/2004:22:08:34 +0000] ProcessIPPRequest: 9 status_code=1
D [05/Jan/2004:22:08:34 +0000] ReadClient() 9 POST / HTTP/1.1
D [05/Jan/2004:22:08:34 +0000] ProcessIPPRequest: 9 status_code=1
D [05/Jan/2004:22:08:34 +0000] ReadClient() 9 POST / HTTP/1.1
D [05/Jan/2004:22:08:34 +0000] ProcessIPPRequest: 9 status_code=1
D [05/Jan/2004:22:08:34 +0000] ReadClient() 9 POST / HTTP/1.1
D [05/Jan/2004:22:08:34 +0000] ProcessIPPRequest: 9 status_code=1
D [05/Jan/2004:22:08:37 +0000] ReadClient() 9 POST / HTTP/1.1
D [05/Jan/2004:22:08:37 +0000] ProcessIPPRequest: 9 status_code=1
D [05/Jan/2004:22:08:44 +0000] ReadClient() 9 POST / HTTP/1.1
<Stuff deleted>
D [05/Jan/2004:22:08:44 +0000] ProcessIPPRequest: 9 status_code=1
D [05/Jan/2004:22:08:58 +0000] ProcessIPPRequest: 9 status_code=1
D [05/Jan/2004:22:08:58 +0000] ReadClient() 9 POST / HTTP/1.1
D [05/Jan/2004:22:08:58 +0000] ProcessIPPRequest: 9 status_code=1
D [05/Jan/2004:22:08:59 +0000] ReadClient() 10 POST /printers/lp@vihta HTTP/1.1
D [05/Jan/2004:22:08:59 +0000] print_job: auto-typing file...
D [05/Jan/2004:22:08:59 +0000] print_job: request file type is application/pdf.
D [05/Jan/2004:22:08:59 +0000] check_quotas: requesting-user-name = 'stephen'
D [05/Jan/2004:22:08:59 +0000] print_job: requesting-user-name = 'stephen'
I [05/Jan/2004:22:08:59 +0000] Job 1 queued on 'lp@vihta' by 'stephen'.
D [05/Jan/2004:22:08:59 +0000] Job 1 hold_until = 0
D [05/Jan/2004:22:08:59 +0000] StartJob(1, 0x180cc00)
D [05/Jan/2004:22:08:59 +0000] StartJob() id = 1, file = 0/1
D [05/Jan/2004:22:08:59 +0000] StartJob: Sending job to queue tagged as raw...
D [05/Jan/2004:22:08:59 +0000] No job-sheets attribute.
D [05/Jan/2004:22:08:59 +0000] banner_page = 0
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "Quality=normal"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "RET=off"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "MediaType=plain"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "AP_D_InputSlot="
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "nocollate"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "media=A4"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.PrinterInfo.PMColorDeviceID..n.=1521266240"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "OutputOrder=Normal"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.PrintSettings.PMLastPage..n.=2147483647"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.JobInfo.PMApplicationName=Preview"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.DocumentTicket.PMSpoolFormat=application/pdf"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.PrintSettings.PMCopies..n.=1"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.PrintSettings.PMCopyCollate..b."
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "job-hold-until=no-hold"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.PrintSettings.PMLayoutTileOrientation..n.=1"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "DestinationPrinterID=lp@vihta"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.PrintSettings.PMLayoutDirection..n.=1"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.PrintSettings.PMFirstPage..n.=1"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.PrintSettings.PMPageRange..a.0..n.=1"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.PrintSettings.PMPageRange..a.1..n.=2147483647"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.PrintSettings.PMColorMatchingMode..n.=0"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.PrintSettings.PMColorSyncProfileID..n.=1261768892"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.JobInfo.PMOutputType=application/postscript"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.PrintSettings.PMTotalBeginPages..n.=1"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.PrintSettings.PMOrientation..n.=1"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.JobInfo.PMJobName=invoice.pdf"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.PrintSettings.PMLayoutColumns..n.=1"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.PrintSettings.PMTotalSidesImaged..n.=1"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "nocom.apple.print.PrintSettings.PMBorder..b."
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.PrintSettings.PMDestinationType..n.=1"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "nocom.apple.print.PrintSettings.PMLayoutNUp..b."
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.PrintSettings.PMColorSpaceModel..n.=2"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.JobInfo.PMJobOwner='Stephen Cornell'"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "com.apple.print.PrintSettings.PMLayoutRows..n.=1"
D [05/Jan/2004:22:08:59 +0000] StartJob: option = "job-priority=50"
D [05/Jan/2004:22:08:59 +0000] StartJob: argv =
"lp@vihta","1","stephen","invoice.pdf","1","Quality=normal RET=off
MediaType=plain AP_D_InputSlot= nocollate media=A4
com.apple.print.PrinterInfo.PMColorDeviceID..n.=1521266240
OutputOrder=Normal
com.apple.print.PrintSettings.PMLastPage..n.=2147483647
com.apple.print.JobInfo.PMApplicationName=Preview
com.apple.print.DocumentTicket.PMSpoolFormat=application/pdf
com.apple.print.PrintSettings.PMCopies..n.=1
com.apple.print.PrintSettings.PMCopyCollate..b. job-hold-until=no-hold
com.apple.print.PrintSettings.PMLayoutTileOrientation..n.=1
DestinationPrinterID=lp@vihta
com.apple.print.PrintSettings.PMLayoutDirection..n.=1
com.apple.print.PrintSettings.PMFirstPage..n.=1
com.apple.print.PrintSettings.PMPageRange..a.0..n.=1
com.apple.print.PrintSettings.PMPageRange..a.1..n.=2147483647
com.apple.print.PrintSettings.PMColorMatchingMode..n.=0
com.apple.print.PrintSettings.PMColorSyncProfileID..n.=1261768892
com.apple.print.JobInfo.PMOutputType=application/postscript
com.apple.print.PrintSettings.PMTotalBeginPages..n.=1
com.apple.print.PrintSettings.PMOrientation..n.=1
com.apple.print.JobInfo.PMJobName=invoice.pdf
com.apple.print.PrintSettings.PMLayoutColumns..n.=1
com.apple.print.PrintSettings.PMTotalSidesImaged..n.=1
nocom.apple.print.PrintSettings.PMBorder..b. com.apple.print.PrintSettings.PMDestinationType..n.=1
nocom.apple.print.PrintSettings.PMLayoutNUp..b. com.apple.print.PrintSettings.PMColorSpaceModel..n.=2
com.apple.print.JobInfo.PMJobOwner='Stephen Cornell'
com.apple.print.PrintSettings.PMLayoutRows..n.=1
job-priority=50","/private/var/spool/cups/d00001-001"
D [05/Jan/2004:22:08:59 +0000] StartJob: envp[0]="PATH=/usr/libexec/cups/filter:/bin:/usr/bin"
D [05/Jan/2004:22:08:59 +0000] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [05/Jan/2004:22:08:59 +0000] StartJob: envp[2]="USER=root"
D [05/Jan/2004:22:08:59 +0000] StartJob: envp[3]="CHARSET=utf-8"
D [05/Jan/2004:22:08:59 +0000] StartJob: envp[4]="LANG=en_US"
D [05/Jan/2004:22:08:59 +0000] StartJob: envp[5]="PPD=/private/etc/cups/ppd/lp@vihta.ppd"
D [05/Jan/2004:22:08:59 +0000] StartJob: envp[6]="CUPS_SERVERROOT=/private/etc/cups"
D [05/Jan/2004:22:08:59 +0000] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [05/Jan/2004:22:08:59 +0000] StartJob: envp[8]="TMPDIR=/private/var/spool/cups/tmp"
D [05/Jan/2004:22:08:59 +0000] StartJob: envp[9]="CONTENT_TYPE=application/pdf"
D [05/Jan/2004:22:08:59 +0000] StartJob: envp[10]="DEVICE_URI=ipp://vihta:631/printers/lp"
D [05/Jan/2004:22:08:59 +0000] StartJob: envp[11]="PRINTER=lp@vihta"
D [05/Jan/2004:22:08:59 +0000] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
D [05/Jan/2004:22:08:59 +0000] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [05/Jan/2004:22:08:59 +0000] StartJob: envp[14]="CUPS_SERVER=localhost"
D [05/Jan/2004:22:08:59 +0000] StartJob: envp[15]="IPP_PORT=631"
D [05/Jan/2004:22:08:59 +0000] StartJob: envp[16]="<CFProcessPath>"
D [05/Jan/2004:22:08:59 +0000] StartJob: statusfds = [ 12 13 ]
D [05/Jan/2004:22:08:59 +0000] StartJob: filterfds[1] = [ 14 -1 ]
D [05/Jan/2004:22:08:59 +0000] StartJob: CFProcessPath=/usr/libexec/cups/backend/ipp
D [05/Jan/2004:22:08:59 +0000] StartJob: backend = "/usr/libexec/cups/backend/ipp"
D [05/Jan/2004:22:08:59 +0000] StartJob: filterfds[0] = [ -1 15 ]
D [05/Jan/2004:22:08:59 +0000] start_process("/usr/libexec/cups/backend/ipp", 0xbffeaa30, 0xbffeb550, 14, 15, 13)
I [05/Jan/2004:22:08:59 +0000] Started backend /usr/libexec/cups/backend/ipp (PID 793) for job 1.
D [05/Jan/2004:22:08:59 +0000] ProcessIPPRequest: 10 status_code=0
D [05/Jan/2004:22:08:59 +0000] ReadClient() 9 POST / HTTP/1.1
D [05/Jan/2004:22:08:59 +0000] ProcessIPPRequest: 9 status_code=1
D [05/Jan/2004:22:08:59 +0000] ReadClient() 9 POST / HTTP/1.1
D [05/Jan/2004:22:08:59 +0000] ProcessIPPRequest: 9 status_code=1
D [05/Jan/2004:22:08:59 +0000] [Job 1] Getting supported attributes...
D [05/Jan/2004:22:08:59 +0000] [Job 1] document-format-supported (30 values)
D [05/Jan/2004:22:08:59 +0000] [Job 1] [0] = "application/msword"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [1] = "application/pdf"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [2] = "application/postscript"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [3] = "application/vnd.cups-form"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [4] = "application/vnd.cups-postscript"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [5] = "application/vnd.cups-raster"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [6] = "application/vnd.cups-raw"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [7] = "application/vnd.hp-hpgl"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [8] = "application/x-cshell"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [9] = "application/x-perl"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [10] = "application/x-shell"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [11] = "image/gif"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [12] = "image/jpeg"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [13] = "image/png"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [14] = "image/tiff"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [15] = "image/x-alias"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [16] = "image/x-bitmap"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [17] = "image/x-photocd"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [18] = "image/x-portable-anymap"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [19] = "image/x-portable-bitmap"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [20] = "image/x-portable-graymap"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [21] = "image/x-portable-pixmap"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [22] = "image/x-sgi-rgb"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [23] = "image/x-sun-raster"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [24] = "image/x-xbitmap"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [25] = "image/x-xpixmap"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [26] = "image/x-xwindowdump"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [27] = "text/html"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [28] = "text/plain"
D [05/Jan/2004:22:08:59 +0000] [Job 1] [29] = "application/octet-stream"
D [05/Jan/2004:22:08:59 +0000] [Job 1] printer-uri = "ipp://vihta:631/printers/lp"
D [05/Jan/2004:22:08:59 +0000] [Job 1] requesting-user-name = "stephen"
D [05/Jan/2004:22:08:59 +0000] [Job 1] job-name = "invoice.pdf"
D [05/Jan/2004:22:08:59 +0000] AcceptClient() 13 from localhost:631.
D [05/Jan/2004:22:08:59 +0000] ReadClient() 13 POST / HTTP/1.1
D [05/Jan/2004:22:08:59 +0000] ProcessIPPRequest: 13 status_code=1
D [05/Jan/2004:22:08:59 +0000] AcceptClient() 14 from localhost:631.
D [05/Jan/2004:22:08:59 +0000] CloseClient() 13
<deleted>
D [05/Jan/2004:22:09:10 +0000] ReadClient() 15 POST / HTTP/1.1
D [05/Jan/2004:22:09:10 +0000] ProcessIPPRequest: 15 status_code=1
D [05/Jan/2004:22:09:10 +0000] ReadClient() 9 POST / HTTP/1.1
D [05/Jan/2004:22:09:10 +0000] ProcessIPPRequest: 9 status_code=1
D [05/Jan/2004:22:09:10 +0000] UpdateJob: job 1, file 0 is complete.
D [05/Jan/2004:22:09:10 +0000] CancelJob: id = 1
D [05/Jan/2004:22:09:10 +0000] StopJob: id = 1, force = 0
D [05/Jan/2004:22:09:10 +0000] StopJob: printer state is 3
D [05/Jan/2004:22:09:15 +0000] ReadClient() 15 POST / HTTP/1.1
D [05/Jan/2004:22:09:15 +0000] ProcessIPPRequest: 15 status_code=1
D [05/Jan/2004:22:09:15 +0000] ReadClient() 15 POST / HTTP/1.1
####################### On the server ###################################
I [05/Jan/2004:22:06:33 +0000] Listening to 0:631
I [05/Jan/2004:22:06:33 +0000] Sending browsing info to c0a801ff:631
D [05/Jan/2004:22:06:33 +0000] AddLocation: added location 'CUPS_INTERNAL_BROWSE_ACL'
D [05/Jan/2004:22:06:33 +0000] AllowIP: CUPS_INTERNAL_BROWSE_ACL allow c0a80100/ffffff00
D [05/Jan/2004:22:06:33 +0000] AddLocation: added location '/'
D [05/Jan/2004:22:06:33 +0000] DenyIP: / deny 00000000/00000000
D [05/Jan/2004:22:06:33 +0000] AllowIP: / allow 7f000001/ffffffff
D [05/Jan/2004:22:06:33 +0000] AddLocation: added location '/printers'
D [05/Jan/2004:22:06:33 +0000] DenyIP: /printers deny 00000000/00000000
D [05/Jan/2004:22:06:33 +0000] AllowIP: /printers allow 7f000001/ffffffff
D [05/Jan/2004:22:06:33 +0000] AllowIP: /printers allow c0a80100/ffffff00
D [05/Jan/2004:22:06:33 +0000] AddLocation: added location '/admin'
D [05/Jan/2004:22:06:33 +0000] DenyIP: /admin deny 00000000/00000000
D [05/Jan/2004:22:06:33 +0000] AllowIP: /admin allow 7f000001/ffffffff
D [05/Jan/2004:22:06:33 +0000] ReadConfiguration() ConfigurationFile="/etc/cups/cupsd.conf"
I [05/Jan/2004:22:06:33 +0000] Configured for up to 100 clients.
I [05/Jan/2004:22:06:33 +0000] LoadPPDs: Read "/etc/cups/ppds.dat", 925 PPDs...
I [05/Jan/2004:22:06:33 +0000] LoadPPDs: No new or changed PPDs...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "parallel:/dev/lp0"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "ipp"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "lpd"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "serial:/dev/ttyS0?baud=115200"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "serial:/dev/ttyS1?baud=115200"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "serial:/dev/ttyS2?baud=115200"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "serial:/dev/ttyS3?baud=115200"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "socket"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "usb:/dev/usb/lp0"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "usb:/dev/usb/lp1"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "usb:/dev/usb/lp2"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "usb:/dev/usb/lp3"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "usb:/dev/usb/lp4"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "usb:/dev/usb/lp5"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "usb:/dev/usb/lp6"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "usb:/dev/usb/lp7"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "usb:/dev/usb/lp8"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "usb:/dev/usb/lp9"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "usb:/dev/usb/lp10"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "usb:/dev/usb/lp11"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "usb:/dev/usb/lp12"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "usb:/dev/usb/lp13"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "usb:/dev/usb/lp14"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "usb:/dev/usb/lp15"...
D [05/Jan/2004:22:06:33 +0000] LoadDevices: Added device "http"...
D [05/Jan/2004:22:06:33 +0000] StartListening: NumListeners=1
D [05/Jan/2004:22:06:33 +0000] StartListening: address=00000000 port=631
D [05/Jan/2004:22:06:33 +0000] ResumeListening: setting input bits...
D [05/Jan/2004:22:06:34 +0000] mallinfo: arena = 771616, used = 764536, free = 7080
D [05/Jan/2004:22:07:34 +0000] mallinfo: arena = 771616, used = 764536, free = 7080
D [05/Jan/2004:22:08:25 +0000] AcceptClient() 3 from 192.168.1.100:631.
D [05/Jan/2004:22:08:25 +0000] ReadClient() 3 GET /printers/lp.ppd HTTP/1.1
D [05/Jan/2004:22:08:25 +0000] SendFile() 3 file=4
D [05/Jan/2004:22:08:29 +0000] CloseClient() 3
D [05/Jan/2004:22:08:31 +0000] AcceptClient() 3 from 192.168.1.100:631.
D [05/Jan/2004:22:08:31 +0000] ReadClient() 3 POST /printers/lp HTTP/1.1
D [05/Jan/2004:22:08:31 +0000] ReadClient() 3 POST /printers/lp HTTP/1.1
D [05/Jan/2004:22:08:31 +0000] print_job: request file type is application/pdf.
D [05/Jan/2004:22:08:31 +0000] check_quotas: requesting-user-name = 'stephen'
D [05/Jan/2004:22:08:31 +0000] print_job: requesting-user-name = 'stephen'
D [05/Jan/2004:22:08:31 +0000] Adding default job-sheets values "none,none"...
I [05/Jan/2004:22:08:31 +0000] Job 46 queued on 'lp' by 'stephen'.
D [05/Jan/2004:22:08:31 +0000] Job 46 hold_until = 0
D [05/Jan/2004:22:08:31 +0000] StartJob(46, 0808bd08)
D [05/Jan/2004:22:08:31 +0000] StartJob() id = 46, file = 0/1
D [05/Jan/2004:22:08:31 +0000] job-sheets=none,none
D [05/Jan/2004:22:08:31 +0000] banner_page = 0
D [05/Jan/2004:22:08:31 +0000] StartJob: argv =
"lp","46","stephen","invoice.pdf","1","Quality=normal RET=off
MediaType=plain AP_D_InputSlot= nocollate media=A4
com.apple.print.PrinterInfo.PMColorDeviceID..n.=1521266240
OutputOrder=Normal
com.apple.print.PrintSettings.PMLastPage..n.=2147483647
com.apple.print.JobInfo.PMApplicationName=Preview
com.apple.print.DocumentTicket.PMSpoolFormat=application/pdf
com.apple.print.PrintSettings.PMCopies..n.=1
com.apple.print.PrintSettings.PMCopyCollate..b. com.apple.print.PrintSettings.PMLayoutTileOrientation..n.=1
DestinationPrinterID=lp@vihta
com.apple.print.PrintSettings.PMLayoutDirection..n.=1
com.apple.print.PrintSettings.PMFirstPage..n.=1
com.apple.print.PrintSettings.PMPageRange..a.0..n.=1
com.apple.print.PrintSettings.PMPageRange..a.1..n.=2147483647
com.apple.print.PrintSettings.PMColorMatchingMode..n.=0
com.apple.print.PrintSettings.PMColorSyncProfileID..n.=1261768892
com.apple.print.JobInfo.PMOutputType=application/postscript
com.apple.print.PrintSettings.PMTotalBeginPages..n.=1 com.apple.pr
D [05/Jan/2004:22:08:31 +0000] StartJob: envp =
"PATH=/usr/lib/cups/filter:/bin:/usr/bin","SOFTWARE=CUPS/1.1","USER=root","CHARSET=utf-8","LANG=en-us","TZ=Europe/London","PPD=/etc/cups/ppd/lp.ppd","CUPS_SERVERROOT=/etc/cups","RIP_MAX_CACHE=8m","TMPDIR=/var/spool/cups/tmp","CONTENT_TYPE=application/pdf","DEVICE_URI=parallel:/dev/lp0","PRINTER=lp","CUPS_DATADIR=/usr/share/cups","CUPS_FONTPATH=/usr/share/cups/fonts","",""
D [05/Jan/2004:22:08:31 +0000] StartJob: statusfds = 4, 6 D
[05/Jan/2004:22:08:31 +0000] StartJob: filterfds[1] = 7, -1 D
[05/Jan/2004:22:08:31 +0000] StartJob: filter =
"/usr/lib/cups/filter/pdftops" D [05/Jan/2004:22:08:31 +0000]
StartJob: filterfds[0] = 8, 9 D [05/Jan/2004:22:08:31 +0000]
start_process("/usr/lib/cups/filter/pdftops", bfff1b7c, bffed02c, 7,
9, 6) I [05/Jan/2004:22:08:31 +0000] Started filter
/usr/lib/cups/filter/pdftops (PID 28311) for job 46. D
[05/Jan/2004:22:08:31 +0000] StartJob: filter =
"/usr/lib/cups/filter/pstops" D [05/Jan/2004:22:08:31 +0000] StartJob:
filterfds[1] = 7, 10 D [05/Jan/2004:22:08:31 +0000]
start_process("/usr/lib/cups/filter/pstops", bfff1b7c, bffed02c, 8,
10, 6) I [05/Jan/2004:22:08:31 +0000] Started filter
/usr/lib/cups/filter/pstops (PID 28312) for job 46. D
[05/Jan/2004:22:08:31 +0000] StartJob: filter =
"/usr/lib/cups/filter/cupsomatic" D [05/Jan/2004:22:08:31 +0000]
StartJob: filterfds[0] = 8, 9 D [05/Jan/2004:22:08:31 +0000]
start_process("/usr/lib/cups/filter/cupsomatic", bfff1b7c, bffed02c,
7, 9, 6) I [05/Jan/2004:22:08:31 +0000] Started filter
/usr/lib/cups/filter/cupsomatic (PID 28313) for job 46. D
[05/Jan/2004:22:08:31 +0000] StartJob: backend =
"/usr/lib/cups/backend/parallel" D [05/Jan/2004:22:08:31 +0000]
StartJob: filterfds[1] = -1, 7 D [05/Jan/2004:22:08:31 +0000]
start_process("/usr/lib/cups/backend/parallel", bfff1b7c, bffed02c, 8,
7, 6) I [05/Jan/2004:22:08:31 +0000] Started backend
/usr/lib/cups/backend/parallel (PID 28314) for job 46. D
[05/Jan/2004:22:08:31 +0000] Page = 595x842; 9,40 to 586,816 D
[05/Jan/2004:22:08:31 +0000] perl: warning: Setting locale failed. D
[05/Jan/2004:22:08:31 +0000] perl: warning: Please check that your
locale settings: D [05/Jan/2004:22:08:31 +0000] LANGUAGE = (unset), D
[05/Jan/2004:22:08:31 +0000] LC_ALL = (unset), D [05/Jan/2004:22:08:31
+0000] LANG = "en-us" D [05/Jan/2004:22:08:31 +0000] are supported and
installed on your system. D [05/Jan/2004:22:08:31 +0000] perl:
warning: Falling back to the standard locale ("C"). D
[05/Jan/2004:22:08:31 +0000] ReadClient() 3 POST /printers/lp HTTP/1.1
D [05/Jan/2004:22:08:31 +0000] pdftops - opened PPD file
"/etc/cups/ppd/lp.ppd"... D [05/Jan/2004:22:08:31 +0000] pdftops -
level = 2, width = 595, length = 842 D [05/Jan/2004:22:08:31 +0000]
ReadClient() 3 POST /printers/lp HTTP/1.1 D [05/Jan/2004:22:08:31
+0000] 0 %%Creator: xpdf/pdftops 0.93a D [05/Jan/2004:22:08:31 +0000]
0 %%LanguageLevel: 2 D [05/Jan/2004:22:08:31 +0000] 0 %%DocumentMedia:
plain 595 842 0 () () D [05/Jan/2004:22:08:31 +0000] 0 %%Pages: 1 D
[05/Jan/2004:22:08:31 +0000] 0 %%EndComments D [05/Jan/2004:22:08:31
+0000] 0 %%BeginDefaults D [05/Jan/2004:22:08:31 +0000] 0 %%PageMedia:
plain D [05/Jan/2004:22:08:31 +0000] 0 %%EndDefaults D
[05/Jan/2004:22:08:31 +0000] 0 %%BeginProlog D [05/Jan/2004:22:08:31
+0000] 0 %%BeginResource: procset xpdf 0.93a 0 D [05/Jan/2004:22:08:31
+0000] 0 %%EndResource D [05/Jan/2004:22:08:31 +0000] 0 %%EndProlog D
[05/Jan/2004:22:08:31 +0000] 0 %%BeginSetup D [05/Jan/2004:22:08:31
+0000] 0 %%BeginResource: font RBXJZZ+Helvetica D
[05/Jan/2004:22:08:31 +0000] Cupsomatic backend version $Revision: 2.5
$ running... D [05/Jan/2004:22:08:31 +0000] called with arguments:
'46','stephen','invoice.pdf','1','Quality=normal RET=off
MediaType=plain AP_D_InputSlot= nocollate media=A4
com.apple.print.PrinterInfo.PMColorDeviceID..n.=1521266240
OutputOrder=Normal
com.apple.print.PrintSettings.PMLastPage..n.=2147483647
com.apple.print.JobInfo.PMApplicationName=Preview
com.apple.print.DocumentTicket.PMSpoolFormat=application/pdf
com.apple.print.PrintSettings.PMCopies..n.=1
com.apple.print.PrintSettings.PMCopyCollate..b. com.apple.print.PrintSettings.PMLayoutTileOrientation..n.=1
DestinationPrinterID=lp@vihta
com.apple.print.PrintSettings.PMLayoutDirection..n.=1
com.apple.print.PrintSettings.PMFirstPage..n.=1
com.apple.print.PrintSettings.PMPageRange..a.0..n.=1
com.apple.print.PrintSettings.PMPageRange..a.1..n.=2147483647
com.apple.print.PrintSettings.PMColorMatchingMode..n.=0
com.apple.print.PrintSettings.PMColorSyncProfileID..n.=1261768892
com.apple.print.JobInfo.PMOutputType=application/postscript
com.apple.print.PrintSettings.PMTotalBeginPages..n.=1 com.apple.p D
[05/Jan/2004:22:08:31 +0000] UpdateJob: job 46, file 0 is complete. D
[05/Jan/2004:22:08:31 +0000] CancelJob: id = 46 D
[05/Jan/2004:22:08:31 +0000] StopJob: id = 46, force = 0 D
[05/Jan/2004:22:08:31 +0000] StopJob: printer state is 3 D
[05/Jan/2004:22:08:34 +0000] mallinfo: arena = 779808, used = 774888,
free = 4920 D [05/Jan/2004:22:08:42 +0000] ReadClient() 3 POST
/printers/lp HTTP/1.1 D [05/Jan/2004:22:08:42 +0000] CloseClient() 3 D
[05/Jan/2004:22:09:34 +0000] mallinfo: arena = 779808, used = 774888,
free = 4920 D [05/Jan/2004:22:10:34 +0000] mallinfo: arena = 779808,
used = 774888, free = 4920
etc.
--
Stephen Cornell cornell.RemoveThis@zoo.cam.ac.uk Tel/fax +44-1223-336644
University of Cambridge, Zoology Department, Downing Street, CAMBRIDGE CB2 3EJ<!-- ~MESSAGE_AFTER~ -->
>> Stay informed about: Problems printing to CUPS server from OS X Panther