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