On Thu, 2008-04-03 at 10:22 -0400, David wrote: > > > > Most recent messages in /var/log/cups/error_log: > > > > I [03/Apr/2008:08:19:57 -0500] Listening to :::631 on fd 2... > > I [03/Apr/2008:08:19:57 -0500] Listening to 0.0.0.0:631 on fd 3... > > I [03/Apr/2008:08:19:57 -0500] Listening to /var/run/cups/cups.sock on > > fd 4... > > I [03/Apr/2008:08:22:20 -0500] Adding start banner page "none" to job > > 31. > > I [03/Apr/2008:08:22:20 -0500] Adding end banner page "none" to job 31. > > I [03/Apr/2008:08:22:20 -0500] Job 31 queued on "Charlie" by "michael". > > > > There don't seem to be any actual errors in the error_log. Strange. Is > > there anything here that can hint as to why nothing is happening? > > > > > > # Log general information in error_log - change "info" to "debug" for > # troubleshooting... > LogLevel info >
I changed LogLevel to debug, erased /var/log/error_log (just to make sure the information I was looking at was current), and restarted cupsd. I sent a job from 192.168.1.3 (I was wrong - catherine is 192.168.1.4) and checked /var/log/cups/error_log: catherine cups # cat error_log I [03/Apr/2008:09:58:03 -0500] Saving job cache file "/var/cache/cups/job.cache"... I [03/Apr/2008:09:58:04 -0500] Listening to :::631 (IPv6) I [03/Apr/2008:09:58:04 -0500] Listening to 0.0.0.0:631 (IPv4) I [03/Apr/2008:09:58:04 -0500] Listening to /var/run/cups/cups.sock (Domain) I [03/Apr/2008:09:58:04 -0500] Loaded configuration file "/etc/cups/cupsd.conf" I [03/Apr/2008:09:58:04 -0500] Using default TempDir of /var/spool/cups/tmp... I [03/Apr/2008:09:58:04 -0500] Cleaning out old temporary files in "/var/spool/cups/tmp"... I [03/Apr/2008:09:58:04 -0500] Configured for up to 100 clients. I [03/Apr/2008:09:58:04 -0500] Allowing up to 100 client connections per host. I [03/Apr/2008:09:58:04 -0500] Using policy "default" as the default! I [03/Apr/2008:09:58:04 -0500] Full reload is required. I [03/Apr/2008:09:58:04 -0500] Loaded MIME database from '/etc/cups': 35 types, 39 filters... D [03/Apr/2008:09:58:04 -0500] Loading printer Charlie... I [03/Apr/2008:09:58:04 -0500] Loading job cache file "/var/cache/cups/job.cache"... D [03/Apr/2008:09:58:04 -0500] Loading job 1 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 2 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 3 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 4 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 5 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 6 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 7 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 8 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 9 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 10 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 11 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 12 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 13 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 14 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 15 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 16 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 17 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 18 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 19 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 20 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 21 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 22 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 23 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 24 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 25 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 26 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 27 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 28 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 29 from cache... D [03/Apr/2008:09:58:04 -0500] Loading job 30 from cache... D [03/Apr/2008:09:58:04 -0500] Loading attributes for job 30... D [03/Apr/2008:09:58:04 -0500] Loading job 31 from cache... D [03/Apr/2008:09:58:04 -0500] Loading attributes for job 31... D [03/Apr/2008:09:58:04 -0500] Loading job 32 from cache... D [03/Apr/2008:09:58:04 -0500] Loading attributes for job 32... I [03/Apr/2008:09:58:04 -0500] Full reload complete. I [03/Apr/2008:09:58:04 -0500] Listening to :::631 on fd 2... I [03/Apr/2008:09:58:04 -0500] Listening to 0.0.0.0:631 on fd 3... I [03/Apr/2008:09:58:04 -0500] Listening to /var/run/cups/cups.sock on fd 4... D [03/Apr/2008:09:58:07 -0500] cupsdAcceptClient: 8 from 192.168.1.3:631 (IPv4) D [03/Apr/2008:09:58:07 -0500] cupsdReadClient: 8 POST / HTTP/1.1 D [03/Apr/2008:09:58:07 -0500] cupsdAuthorize: No authentication data provided. D [03/Apr/2008:09:58:07 -0500] CUPS-Get-Printers D [03/Apr/2008:09:58:07 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [03/Apr/2008:09:58:07 -0500] cupsdReadClient: 8 POST / HTTP/1.1 D [03/Apr/2008:09:58:07 -0500] cupsdAuthorize: No authentication data provided. D [03/Apr/2008:09:58:07 -0500] CUPS-Get-Classes D [03/Apr/2008:09:58:07 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [03/Apr/2008:09:58:07 -0500] cupsdReadClient: 8 POST / HTTP/1.1 D [03/Apr/2008:09:58:07 -0500] cupsdAuthorize: No authentication data provided. D [03/Apr/2008:09:58:07 -0500] CUPS-Get-Default D [03/Apr/2008:09:58:07 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [03/Apr/2008:09:58:07 -0500] cupsdCloseClient: 8 D [03/Apr/2008:09:58:07 -0500] cupsdAcceptClient: 8 from 192.168.1.3:631 (IPv4) D [03/Apr/2008:09:58:07 -0500] cupsdReadClient: 8 POST / HTTP/1.1 D [03/Apr/2008:09:58:07 -0500] cupsdAuthorize: No authentication data provided. D [03/Apr/2008:09:58:07 -0500] CUPS-Get-Printers D [03/Apr/2008:09:58:07 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [03/Apr/2008:09:58:07 -0500] cupsdReadClient: 8 POST / HTTP/1.1 D [03/Apr/2008:09:58:07 -0500] cupsdAuthorize: No authentication data provided. D [03/Apr/2008:09:58:07 -0500] CUPS-Get-Classes D [03/Apr/2008:09:58:07 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [03/Apr/2008:09:58:07 -0500] cupsdReadClient: 8 POST / HTTP/1.1 D [03/Apr/2008:09:58:07 -0500] cupsdAuthorize: No authentication data provided. D [03/Apr/2008:09:58:07 -0500] CUPS-Get-Default D [03/Apr/2008:09:58:07 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [03/Apr/2008:09:58:07 -0500] cupsdCloseClient: 8 D [03/Apr/2008:09:58:07 -0500] cupsdAcceptClient: 8 from 192.168.1.3:631 (IPv4) D [03/Apr/2008:09:58:07 -0500] cupsdReadClient: 8 POST / HTTP/1.1 D [03/Apr/2008:09:58:07 -0500] cupsdAuthorize: No authentication data provided. D [03/Apr/2008:09:58:07 -0500] CUPS-Get-Printers D [03/Apr/2008:09:58:07 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [03/Apr/2008:09:58:07 -0500] cupsdReadClient: 8 POST / HTTP/1.1 D [03/Apr/2008:09:58:07 -0500] cupsdAuthorize: No authentication data provided. D [03/Apr/2008:09:58:07 -0500] CUPS-Get-Classes D [03/Apr/2008:09:58:07 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [03/Apr/2008:09:58:07 -0500] cupsdReadClient: 8 POST / HTTP/1.1 D [03/Apr/2008:09:58:07 -0500] cupsdAuthorize: No authentication data provided. D [03/Apr/2008:09:58:07 -0500] CUPS-Get-Default D [03/Apr/2008:09:58:07 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [03/Apr/2008:09:58:07 -0500] cupsdCloseClient: 8 D [03/Apr/2008:09:58:09 -0500] cupsdAcceptClient: 8 from 192.168.1.3:631 (IPv4) D [03/Apr/2008:09:58:09 -0500] cupsdReadClient: 8 POST / HTTP/1.1 D [03/Apr/2008:09:58:09 -0500] cupsdAuthorize: No authentication data provided. D [03/Apr/2008:09:58:09 -0500] CUPS-Get-Printers D [03/Apr/2008:09:58:09 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [03/Apr/2008:09:58:09 -0500] cupsdReadClient: 8 POST / HTTP/1.1 D [03/Apr/2008:09:58:09 -0500] cupsdAuthorize: No authentication data provided. D [03/Apr/2008:09:58:09 -0500] CUPS-Get-Classes D [03/Apr/2008:09:58:09 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [03/Apr/2008:09:58:09 -0500] cupsdReadClient: 8 POST / HTTP/1.1 D [03/Apr/2008:09:58:09 -0500] cupsdAuthorize: No authentication data provided. D [03/Apr/2008:09:58:09 -0500] CUPS-Get-Default D [03/Apr/2008:09:58:09 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [03/Apr/2008:09:58:09 -0500] cupsdCloseClient: 8 D [03/Apr/2008:09:58:13 -0500] cupsdAcceptClient: 8 from 192.168.1.3:631 (IPv4) D [03/Apr/2008:09:58:13 -0500] cupsdReadClient: 8 POST / HTTP/1.1 D [03/Apr/2008:09:58:13 -0500] cupsdAuthorize: No authentication data provided. D [03/Apr/2008:09:58:13 -0500] CUPS-Get-Printers D [03/Apr/2008:09:58:13 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [03/Apr/2008:09:58:13 -0500] cupsdReadClient: 8 POST / HTTP/1.1 D [03/Apr/2008:09:58:13 -0500] cupsdAuthorize: No authentication data provided. D [03/Apr/2008:09:58:13 -0500] CUPS-Get-Classes D [03/Apr/2008:09:58:13 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [03/Apr/2008:09:58:13 -0500] cupsdReadClient: 8 POST / HTTP/1.1 D [03/Apr/2008:09:58:13 -0500] cupsdAuthorize: No authentication data provided. D [03/Apr/2008:09:58:13 -0500] CUPS-Get-Default D [03/Apr/2008:09:58:13 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [03/Apr/2008:09:58:13 -0500] cupsdCloseClient: 8 D [03/Apr/2008:09:58:13 -0500] cupsdAcceptClient: 8 from 192.168.1.3:631 (IPv4) D [03/Apr/2008:09:58:13 -0500] cupsdReadClient: 8 POST /printers/Charlie HTTP/1.1 D [03/Apr/2008:09:58:13 -0500] cupsdAuthorize: No authentication data provided. D [03/Apr/2008:09:58:13 -0500] Print-Job ipp://localhost/printers/Charlie D [03/Apr/2008:09:58:13 -0500] print_job: auto-typing file... D [03/Apr/2008:09:58:13 -0500] print_job: request file type is application/postscript. D [03/Apr/2008:09:58:13 -0500] add_job: requesting-user-name="michael" I [03/Apr/2008:09:58:13 -0500] Adding start banner page "none" to job 33. D [03/Apr/2008:09:58:13 -0500] Discarding unused job-created event... I [03/Apr/2008:09:58:13 -0500] Adding end banner page "none" to job 33. I [03/Apr/2008:09:58:13 -0500] Job 33 queued on "Charlie" by "michael". D [03/Apr/2008:09:58:13 -0500] Job 33 hold_until = 0 D [03/Apr/2008:09:58:13 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) Once again, it seems that cups thinks that the document was printed successfully. What am I missing here? -- gentoo-user@lists.gentoo.org mailing list
