| 1 |
Michael Sullivan wrote: |
| 2 |
> On Thu, 2008-04-03 at 10:22 -0400, David wrote: |
| 3 |
>>> Most recent messages in /var/log/cups/error_log: |
| 4 |
>>> |
| 5 |
>>> I [03/Apr/2008:08:19:57 -0500] Listening to :::631 on fd 2... |
| 6 |
>>> I [03/Apr/2008:08:19:57 -0500] Listening to 0.0.0.0:631 on fd 3... |
| 7 |
>>> I [03/Apr/2008:08:19:57 -0500] Listening to /var/run/cups/cups.sock on |
| 8 |
>>> fd 4... |
| 9 |
>>> I [03/Apr/2008:08:22:20 -0500] Adding start banner page "none" to job |
| 10 |
>>> 31. |
| 11 |
>>> I [03/Apr/2008:08:22:20 -0500] Adding end banner page "none" to job 31. |
| 12 |
>>> I [03/Apr/2008:08:22:20 -0500] Job 31 queued on "Charlie" by "michael". |
| 13 |
>>> |
| 14 |
>>> There don't seem to be any actual errors in the error_log. Strange. Is |
| 15 |
>>> there anything here that can hint as to why nothing is happening? |
| 16 |
>>> |
| 17 |
>>> |
| 18 |
>> # Log general information in error_log - change "info" to "debug" for |
| 19 |
>> # troubleshooting... |
| 20 |
>> LogLevel info |
| 21 |
>> |
| 22 |
> |
| 23 |
> I changed LogLevel to debug, erased /var/log/error_log (just to make |
| 24 |
> sure the information I was looking at was current), and restarted cupsd. |
| 25 |
> I sent a job from 192.168.1.3 (I was wrong - catherine is 192.168.1.4) |
| 26 |
> and checked /var/log/cups/error_log: |
| 27 |
> |
| 28 |
> catherine cups # cat error_log |
| 29 |
> I [03/Apr/2008:09:58:03 -0500] Saving job cache file |
| 30 |
> "/var/cache/cups/job.cache"... |
| 31 |
> I [03/Apr/2008:09:58:04 -0500] Listening to :::631 (IPv6) |
| 32 |
> I [03/Apr/2008:09:58:04 -0500] Listening to 0.0.0.0:631 (IPv4) |
| 33 |
> I [03/Apr/2008:09:58:04 -0500] Listening to /var/run/cups/cups.sock |
| 34 |
> (Domain) |
| 35 |
> I [03/Apr/2008:09:58:04 -0500] Loaded configuration file |
| 36 |
> "/etc/cups/cupsd.conf" |
| 37 |
> I [03/Apr/2008:09:58:04 -0500] Using default TempDir |
| 38 |
> of /var/spool/cups/tmp... |
| 39 |
> I [03/Apr/2008:09:58:04 -0500] Cleaning out old temporary files in |
| 40 |
> "/var/spool/cups/tmp"... |
| 41 |
> I [03/Apr/2008:09:58:04 -0500] Configured for up to 100 clients. |
| 42 |
> I [03/Apr/2008:09:58:04 -0500] Allowing up to 100 client connections per |
| 43 |
> host. |
| 44 |
> I [03/Apr/2008:09:58:04 -0500] Using policy "default" as the default! |
| 45 |
> I [03/Apr/2008:09:58:04 -0500] Full reload is required. |
| 46 |
> I [03/Apr/2008:09:58:04 -0500] Loaded MIME database from '/etc/cups': 35 |
| 47 |
> types, 39 filters... |
| 48 |
> D [03/Apr/2008:09:58:04 -0500] Loading printer Charlie... |
| 49 |
> I [03/Apr/2008:09:58:04 -0500] Loading job cache file |
| 50 |
> "/var/cache/cups/job.cache"... |
| 51 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 1 from cache... |
| 52 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 2 from cache... |
| 53 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 3 from cache... |
| 54 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 4 from cache... |
| 55 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 5 from cache... |
| 56 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 6 from cache... |
| 57 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 7 from cache... |
| 58 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 8 from cache... |
| 59 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 9 from cache... |
| 60 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 10 from cache... |
| 61 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 11 from cache... |
| 62 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 12 from cache... |
| 63 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 13 from cache... |
| 64 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 14 from cache... |
| 65 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 15 from cache... |
| 66 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 16 from cache... |
| 67 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 17 from cache... |
| 68 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 18 from cache... |
| 69 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 19 from cache... |
| 70 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 20 from cache... |
| 71 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 21 from cache... |
| 72 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 22 from cache... |
| 73 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 23 from cache... |
| 74 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 24 from cache... |
| 75 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 25 from cache... |
| 76 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 26 from cache... |
| 77 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 27 from cache... |
| 78 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 28 from cache... |
| 79 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 29 from cache... |
| 80 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 30 from cache... |
| 81 |
> D [03/Apr/2008:09:58:04 -0500] Loading attributes for job 30... |
| 82 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 31 from cache... |
| 83 |
> D [03/Apr/2008:09:58:04 -0500] Loading attributes for job 31... |
| 84 |
> D [03/Apr/2008:09:58:04 -0500] Loading job 32 from cache... |
| 85 |
> D [03/Apr/2008:09:58:04 -0500] Loading attributes for job 32... |
| 86 |
> I [03/Apr/2008:09:58:04 -0500] Full reload complete. |
| 87 |
> I [03/Apr/2008:09:58:04 -0500] Listening to :::631 on fd 2... |
| 88 |
> I [03/Apr/2008:09:58:04 -0500] Listening to 0.0.0.0:631 on fd 3... |
| 89 |
> I [03/Apr/2008:09:58:04 -0500] Listening to /var/run/cups/cups.sock on |
| 90 |
> fd 4... |
| 91 |
> D [03/Apr/2008:09:58:07 -0500] cupsdAcceptClient: 8 from 192.168.1.3:631 |
| 92 |
> (IPv4) |
| 93 |
> D [03/Apr/2008:09:58:07 -0500] cupsdReadClient: 8 POST / HTTP/1.1 |
| 94 |
> D [03/Apr/2008:09:58:07 -0500] cupsdAuthorize: No authentication data |
| 95 |
> provided. |
| 96 |
> D [03/Apr/2008:09:58:07 -0500] CUPS-Get-Printers |
| 97 |
> D [03/Apr/2008:09:58:07 -0500] cupsdProcessIPPRequest: 8 status_code=0 |
| 98 |
> (successful-ok) |
| 99 |
> D [03/Apr/2008:09:58:07 -0500] cupsdReadClient: 8 POST / HTTP/1.1 |
| 100 |
> D [03/Apr/2008:09:58:07 -0500] cupsdAuthorize: No authentication data |
| 101 |
> provided. |
| 102 |
> D [03/Apr/2008:09:58:07 -0500] CUPS-Get-Classes |
| 103 |
> D [03/Apr/2008:09:58:07 -0500] cupsdProcessIPPRequest: 8 status_code=0 |
| 104 |
> (successful-ok) |
| 105 |
> D [03/Apr/2008:09:58:07 -0500] cupsdReadClient: 8 POST / HTTP/1.1 |
| 106 |
> D [03/Apr/2008:09:58:07 -0500] cupsdAuthorize: No authentication data |
| 107 |
> provided. |
| 108 |
> D [03/Apr/2008:09:58:07 -0500] CUPS-Get-Default |
| 109 |
> D [03/Apr/2008:09:58:07 -0500] cupsdProcessIPPRequest: 8 status_code=0 |
| 110 |
> (successful-ok) |
| 111 |
> D [03/Apr/2008:09:58:07 -0500] cupsdCloseClient: 8 |
| 112 |
> D [03/Apr/2008:09:58:07 -0500] cupsdAcceptClient: 8 from 192.168.1.3:631 |
| 113 |
> (IPv4) |
| 114 |
> D [03/Apr/2008:09:58:07 -0500] cupsdReadClient: 8 POST / HTTP/1.1 |
| 115 |
> D [03/Apr/2008:09:58:07 -0500] cupsdAuthorize: No authentication data |
| 116 |
> provided. |
| 117 |
> D [03/Apr/2008:09:58:07 -0500] CUPS-Get-Printers |
| 118 |
> D [03/Apr/2008:09:58:07 -0500] cupsdProcessIPPRequest: 8 status_code=0 |
| 119 |
> (successful-ok) |
| 120 |
> D [03/Apr/2008:09:58:07 -0500] cupsdReadClient: 8 POST / HTTP/1.1 |
| 121 |
> D [03/Apr/2008:09:58:07 -0500] cupsdAuthorize: No authentication data |
| 122 |
> provided. |
| 123 |
> D [03/Apr/2008:09:58:07 -0500] CUPS-Get-Classes |
| 124 |
> D [03/Apr/2008:09:58:07 -0500] cupsdProcessIPPRequest: 8 status_code=0 |
| 125 |
> (successful-ok) |
| 126 |
> D [03/Apr/2008:09:58:07 -0500] cupsdReadClient: 8 POST / HTTP/1.1 |
| 127 |
> D [03/Apr/2008:09:58:07 -0500] cupsdAuthorize: No authentication data |
| 128 |
> provided. |
| 129 |
> D [03/Apr/2008:09:58:07 -0500] CUPS-Get-Default |
| 130 |
> D [03/Apr/2008:09:58:07 -0500] cupsdProcessIPPRequest: 8 status_code=0 |
| 131 |
> (successful-ok) |
| 132 |
> D [03/Apr/2008:09:58:07 -0500] cupsdCloseClient: 8 |
| 133 |
> D [03/Apr/2008:09:58:07 -0500] cupsdAcceptClient: 8 from 192.168.1.3:631 |
| 134 |
> (IPv4) |
| 135 |
> D [03/Apr/2008:09:58:07 -0500] cupsdReadClient: 8 POST / HTTP/1.1 |
| 136 |
> D [03/Apr/2008:09:58:07 -0500] cupsdAuthorize: No authentication data |
| 137 |
> provided. |
| 138 |
> D [03/Apr/2008:09:58:07 -0500] CUPS-Get-Printers |
| 139 |
> D [03/Apr/2008:09:58:07 -0500] cupsdProcessIPPRequest: 8 status_code=0 |
| 140 |
> (successful-ok) |
| 141 |
> D [03/Apr/2008:09:58:07 -0500] cupsdReadClient: 8 POST / HTTP/1.1 |
| 142 |
> D [03/Apr/2008:09:58:07 -0500] cupsdAuthorize: No authentication data |
| 143 |
> provided. |
| 144 |
> D [03/Apr/2008:09:58:07 -0500] CUPS-Get-Classes |
| 145 |
> D [03/Apr/2008:09:58:07 -0500] cupsdProcessIPPRequest: 8 status_code=0 |
| 146 |
> (successful-ok) |
| 147 |
> D [03/Apr/2008:09:58:07 -0500] cupsdReadClient: 8 POST / HTTP/1.1 |
| 148 |
> D [03/Apr/2008:09:58:07 -0500] cupsdAuthorize: No authentication data |
| 149 |
> provided. |
| 150 |
> D [03/Apr/2008:09:58:07 -0500] CUPS-Get-Default |
| 151 |
> D [03/Apr/2008:09:58:07 -0500] cupsdProcessIPPRequest: 8 status_code=0 |
| 152 |
> (successful-ok) |
| 153 |
> D [03/Apr/2008:09:58:07 -0500] cupsdCloseClient: 8 |
| 154 |
> D [03/Apr/2008:09:58:09 -0500] cupsdAcceptClient: 8 from 192.168.1.3:631 |
| 155 |
> (IPv4) |
| 156 |
> D [03/Apr/2008:09:58:09 -0500] cupsdReadClient: 8 POST / HTTP/1.1 |
| 157 |
> D [03/Apr/2008:09:58:09 -0500] cupsdAuthorize: No authentication data |
| 158 |
> provided. |
| 159 |
> D [03/Apr/2008:09:58:09 -0500] CUPS-Get-Printers |
| 160 |
> D [03/Apr/2008:09:58:09 -0500] cupsdProcessIPPRequest: 8 status_code=0 |
| 161 |
> (successful-ok) |
| 162 |
> D [03/Apr/2008:09:58:09 -0500] cupsdReadClient: 8 POST / HTTP/1.1 |
| 163 |
> D [03/Apr/2008:09:58:09 -0500] cupsdAuthorize: No authentication data |
| 164 |
> provided. |
| 165 |
> D [03/Apr/2008:09:58:09 -0500] CUPS-Get-Classes |
| 166 |
> D [03/Apr/2008:09:58:09 -0500] cupsdProcessIPPRequest: 8 status_code=0 |
| 167 |
> (successful-ok) |
| 168 |
> D [03/Apr/2008:09:58:09 -0500] cupsdReadClient: 8 POST / HTTP/1.1 |
| 169 |
> D [03/Apr/2008:09:58:09 -0500] cupsdAuthorize: No authentication data |
| 170 |
> provided. |
| 171 |
> D [03/Apr/2008:09:58:09 -0500] CUPS-Get-Default |
| 172 |
> D [03/Apr/2008:09:58:09 -0500] cupsdProcessIPPRequest: 8 status_code=0 |
| 173 |
> (successful-ok) |
| 174 |
> D [03/Apr/2008:09:58:09 -0500] cupsdCloseClient: 8 |
| 175 |
> D [03/Apr/2008:09:58:13 -0500] cupsdAcceptClient: 8 from 192.168.1.3:631 |
| 176 |
> (IPv4) |
| 177 |
> D [03/Apr/2008:09:58:13 -0500] cupsdReadClient: 8 POST / HTTP/1.1 |
| 178 |
> D [03/Apr/2008:09:58:13 -0500] cupsdAuthorize: No authentication data |
| 179 |
> provided. |
| 180 |
> D [03/Apr/2008:09:58:13 -0500] CUPS-Get-Printers |
| 181 |
> D [03/Apr/2008:09:58:13 -0500] cupsdProcessIPPRequest: 8 status_code=0 |
| 182 |
> (successful-ok) |
| 183 |
> D [03/Apr/2008:09:58:13 -0500] cupsdReadClient: 8 POST / HTTP/1.1 |
| 184 |
> D [03/Apr/2008:09:58:13 -0500] cupsdAuthorize: No authentication data |
| 185 |
> provided. |
| 186 |
> D [03/Apr/2008:09:58:13 -0500] CUPS-Get-Classes |
| 187 |
> D [03/Apr/2008:09:58:13 -0500] cupsdProcessIPPRequest: 8 status_code=0 |
| 188 |
> (successful-ok) |
| 189 |
> D [03/Apr/2008:09:58:13 -0500] cupsdReadClient: 8 POST / HTTP/1.1 |
| 190 |
> D [03/Apr/2008:09:58:13 -0500] cupsdAuthorize: No authentication data |
| 191 |
> provided. |
| 192 |
> D [03/Apr/2008:09:58:13 -0500] CUPS-Get-Default |
| 193 |
> D [03/Apr/2008:09:58:13 -0500] cupsdProcessIPPRequest: 8 status_code=0 |
| 194 |
> (successful-ok) |
| 195 |
> D [03/Apr/2008:09:58:13 -0500] cupsdCloseClient: 8 |
| 196 |
> D [03/Apr/2008:09:58:13 -0500] cupsdAcceptClient: 8 from 192.168.1.3:631 |
| 197 |
> (IPv4) |
| 198 |
> D [03/Apr/2008:09:58:13 -0500] cupsdReadClient: 8 POST /printers/Charlie |
| 199 |
> HTTP/1.1 |
| 200 |
> D [03/Apr/2008:09:58:13 -0500] cupsdAuthorize: No authentication data |
| 201 |
> provided. |
| 202 |
> D [03/Apr/2008:09:58:13 -0500] Print-Job |
| 203 |
> ipp://localhost/printers/Charlie |
| 204 |
> D [03/Apr/2008:09:58:13 -0500] print_job: auto-typing file... |
| 205 |
> D [03/Apr/2008:09:58:13 -0500] print_job: request file type is |
| 206 |
> application/postscript. |
| 207 |
> D [03/Apr/2008:09:58:13 -0500] add_job: requesting-user-name="michael" |
| 208 |
> I [03/Apr/2008:09:58:13 -0500] Adding start banner page "none" to job |
| 209 |
> 33. |
| 210 |
> D [03/Apr/2008:09:58:13 -0500] Discarding unused job-created event... |
| 211 |
> I [03/Apr/2008:09:58:13 -0500] Adding end banner page "none" to job 33. |
| 212 |
> I [03/Apr/2008:09:58:13 -0500] Job 33 queued on "Charlie" by "michael". |
| 213 |
> D [03/Apr/2008:09:58:13 -0500] Job 33 hold_until = 0 |
| 214 |
> D [03/Apr/2008:09:58:13 -0500] cupsdProcessIPPRequest: 8 status_code=0 |
| 215 |
> (successful-ok) |
| 216 |
> |
| 217 |
> Once again, it seems that cups thinks that the document was printed |
| 218 |
> successfully. What am I missing here? |
| 219 |
> |
| 220 |
Can you print from the console on catherine using lp? If so, can you |
| 221 |
print from the console on another machine? |
| 222 |
|
| 223 |
Have you recently upgraded the server? In some cases, documents sent as |
| 224 |
type application/octet-stream silently fail to print (I had this trouble |
| 225 |
a while ago) if the mime-type isn't enabled in /etc/cups/mime.types and |
| 226 |
mime.convs. Those files get replaced with some cups builds, and by |
| 227 |
default octet-stream isn't enabled in the packaged files. Do a |
| 228 |
|
| 229 |
# grep 'application/octet-stream' /etc/cups/mime.* |
| 230 |
|
| 231 |
to check that any line containing that phrase isn't commented. |
| 232 |
|
| 233 |
-Tim |
| 234 |
-- |
| 235 |
gentoo-user@l.g.o mailing list |
Archives