Gemeinsam genutzter Drucker läuft nicht mehr

[Markus Kohls]

Hallo zusammen,

eine seit 1-2 Jahren verlässliche Konstellation hat nun plötzlich
aufgehört zu laufen.

Ein iMac mit 10.5.8 hat einen USB-Drucker (Brother DCP-7030) per USB
angeschlossen (druckt auch am Wirtsrechner). Dieser ist im Netz per
PrinterSharing freigegeben.

Ein iBook G4 mit 10.4.11 nutz diesen Drucker mit. Im
Druckerdienstprogramm ist er als "Gemeinsam genutzter Drucker" zu sehen
und auswählbar. Sobald man in einer Applikation (egal welche) Apfel-P
drückt, bleiben sie stehen und es tut sich nichts mehr. Stundenlang
Beachball, bis man die Applikation abschießt.

Habe den Drucker auch schon am iBook entfernt und wieder neu
"eingetragen". Kein Erfolg.

localhost:631 sagt "gelangweilt:nimmt Aufträge entgegen". Aber selbst
wenn man die Druckerwarteliste sehen will, bleibt sie stehen. Testpage
über das WebInterface von CUPS führt auch zu keinem Ergebnis.

Einen anderen Benutzer an dem Rechner habe ich angelegt, da ist es das
Gleiche.

Was tun?

Gruß,

markus,

[Thomas Kaiser]

Post by Markus Kohls
Was tun?

Problem eingrenzen versuchen, d.h. mal nach »cupsd.conf loglevel debug«
googlen, in /var/log/system.log gucken, per opensnoop(1m) mal dem cupsd
zuschauen...

Gruss,

Thomas

[Markus Kohls]

Post by Thomas Kaiser
Problem eingrenzen versuchen, d.h. mal nach »cupsd.conf loglevel debug«
googlen, in /var/log/system.log gucken, per opensnoop(1m) mal dem cupsd
zuschauen...

Hat etwas gedauert, die "Not" war nicht zu groß, aber es nervt. Sorry für die Zeilenlänge, aber so ist es lesbarer denke
ich.

Ich habe heute mal auf dem "Server" (10.5.8) die Brother-Software/
-Treiber neu installiert und den Drucker neu freigegeben. Auf dem Client
(10.4.11) auch neue Treiber, alle Drucker gelöscht und neu hinzugefügt.

Das Problem bleibt. Sobald man irgendwas drucken möchte rotiert der
Beachball.

Dann habe ich am Client in /var/log/cups/error_log geguckt.
Da steht dann folgendes:

I [24/Jul/2011:16:23:54 +0200] Scheduler shutting down normally.
I [24/Jul/2011:16:23:57 +0200] Listening to 7f000001:631
I [24/Jul/2011:16:23:57 +0200] Listening to 300ff0:0
I [24/Jul/2011:16:23:57 +0200] Loaded configuration file "/private/etc/cups/cupsd.conf"
W [24/Jul/2011:16:23:57 +0200] Repairing ownership of "/private/etc/cups/cupsd.conf"
I [24/Jul/2011:16:23:57 +0200] Configured for up to 100 clients.
I [24/Jul/2011:16:23:57 +0200] Allowing up to 100 client connections per host.
I [24/Jul/2011:16:23:57 +0200] Full reload is required.
E [24/Jul/2011:16:23:57 +0200] LoadAllPrinters: Unable to open /private/etc/cups/printers.conf - No such file or
directory
E [24/Jul/2011:16:23:57 +0200] LoadAllClasses: Unable to open /private/etc/cups/classes.conf - No such file or directory
I [24/Jul/2011:16:23:57 +0200] Full reload complete.
E [24/Jul/2011:16:24:03 +0200] get_printer_attrs: resource name '/printers/***@192.168.1.123' no good!
I [24/Jul/2011:16:24:13 +0200] Added remote printer "***@192.168.1.123"...
I [24/Jul/2011:16:24:15 +0200] Added remote printer "***@192.168.1.97"...
I [24/Jul/2011:16:24:18 +0200] Added remote printer "***@192.168.1.97"...

Der Rechner .1.97 ist der Fileserver im Keller, die .1.123 ist mein Rechner mit dem Brother.

Der "Server" schreibt im console_log zu dieser Zeit:

Jul 24 16:23:28 maccy mDNSResponder[20]: Excessive update rate for ***@maccy._presence._tcp.local.; delaying
announcement by 4 seconds
Jul 24 16:23:36: --- last message repeated 7 times ---
Jul 24 16:23:36 maccy mDNSResponder[20]: Excessive update rate for ***@maccy._presence._tcp.local.; delaying
announcement by 5 seconds
Jul 24 16:23:38: --- last message repeated 1 time ---
Jul 24 16:23:38 maccy mDNSResponder[20]: Excessive update rate for ***@maccy._presence._tcp.local.; delaying
announcement by 4 seconds
Jul 24 16:23:41: --- last message repeated 2 times ---
Jul 24 16:23:41 maccy mDNSResponder[20]: Excessive update rate for ***@maccy._presence._tcp.local.; delaying
announcement by 5 seconds
Jul 24 16:23:44: --- last message repeated 2 times ---

Im console_log des Client schreibts zu früherer Zeit auch:

2011-07-24 15:19:30.881 PrinterProxy[376] Printer scanningApp for Brother DCP-7030 had error 1282

Ein weiterer Rechner hier im Netz (10.4.8) kann auch nicht mehr über mich drucken, gleicher Beach-Ball-Effekt.
Der Fehler muss also wohl bei mir liegen.

Was kann ich noch tun? "opensnoop(1m)" sagt mich so gar nichts.

Gruß,

markus,

[Thomas Kaiser]

Post by Markus Kohls

Post by Thomas Kaiser
Problem eingrenzen versuchen, d.h. mal nach »cupsd.conf loglevel debug«
googlen, in /var/log/system.log gucken, per opensnoop(1m) mal dem cupsd
zuschauen...

[...]
Das Problem bleibt. Sobald man irgendwas drucken möchte rotiert der
Beachball.
Dann habe ich am Client in /var/log/cups/error_log geguckt.

Da tauchen nirgends Einträge mit 'nem "D" am Anfang auf. D.h. Du hast
den loglevel schon mal nicht geändert, wie empfohlen, dito keine klaren
Angaben ("Fileserver" vs. "Server" -- was hat das mit Files zu tun? Wer
ist der Druckserver und wer nicht?). Dann fehlen am Client die
printers.conf und die classes.conf -- irgendwie ein Indiz, daß da mehr
mulu ist.

Gruss,

Thomas

[Markus Kohls]

Post by Thomas Kaiser
Da tauchen nirgends Einträge mit 'nem "D" am Anfang auf. D.h. Du hast
den loglevel schon mal nicht geändert, wie empfohlen,

Ich habe mal an maccy (Rechner mit angeschlossenem Drucker/s.u.) auf "debug2" gesetzt und cups neu gestartet. Nun bekomme alle Nase lang in cups/eror_log:

d [24/Jul/2011:21:45:14 +0200] cupsdDoSelect: kevent(3, ..., 16384, ...) returned 0...
d [24/Jul/2011:21:45:14 +0200] cupsdSendBrowseList: (148 bytes to "en0") b046 3 ipp://192.168.1.123:631/printers/Brother_DCP_7030 "maccy" "Brother DCP-7030" "Brother DCP-7030 CUPS"
job-sheets=none,none lease-duration=300
d [24/Jul/2011:21:45:14 +0200] cupsdSendBrowseList: (147 bytes to "vmnet1") b046 3 ipp://172.16.105.1:631/printers/Brother_DCP_7030 "maccy" "Brother DCP-7030" "Brother DCP-7030 CUPS"
job-sheets=none,none lease-duration=300
d [24/Jul/2011:21:45:14 +0200] cupsdSendBrowseList: (147 bytes to "vmnet8") b046 3 ipp://192.168.32.1:631/printers/Brother_DCP_7030 "maccy" "Brother DCP-7030" "Brother DCP-7030 CUPS"
job-sheets=none,none lease-duration=300
d [24/Jul/2011:21:45:14 +0200] cupsdSendBrowseList: (146 bytes to "vnic0") b046 3 ipp://10.211.55.2:631/printers/Brother_DCP_7030 "maccy" "Brother DCP-7030" "Brother DCP-7030 CUPS"
job-sheets=none,none lease-duration=300
d [24/Jul/2011:21:45:14 +0200] cupsdSendBrowseList: (146 bytes to "vnic1") b046 3 ipp://10.37.129.2:631/printers/Brother_DCP_7030 "maccy" "Brother DCP-7030" "Brother DCP-7030 CUPS"
job-sheets=none,none lease-duration=300
d [24/Jul/2011:21:45:14 +0200] cupsdDeleteCert: removing certificate for pid 0
d [24/Jul/2011:21:45:14 +0200] cupsdAddCert: adding certificate for pid 0
d [24/Jul/2011:21:45:14 +0200] cupsdAddCert: NumSystemGroups=1
d [24/Jul/2011:21:45:14 +0200] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0
D [24/Jul/2011:21:45:14 +0200] Report: clients=1
D [24/Jul/2011:21:45:14 +0200] Report: jobs=1
D [24/Jul/2011:21:45:14 +0200] Report: jobs-active=0
D [24/Jul/2011:21:45:14 +0200] Report: printers=2
D [24/Jul/2011:21:45:14 +0200] Report: printers-implicit=0
D [24/Jul/2011:21:45:14 +0200] Report: stringpool-string-count=487
D [24/Jul/2011:21:45:14 +0200] Report: stringpool-alloc-bytes=8280
D [24/Jul/2011:21:45:14 +0200] Report: stringpool-total-bytes=9832
d [24/Jul/2011:21:45:14 +0200] select_timeout(0): 31 seconds to send browse update
d [24/Jul/2011:21:45:14 +0200] cupsdDoSelect: polling 9 fds for 31 seconds...

Der Client spuckt nach einem Neustart (auch mit "debug2") folgendes aus.
I [24/Jul/2011:21:48:26 +0200] Listening to 7f000001:631
I [24/Jul/2011:21:48:26 +0200] Listening to 300f60:0
I [24/Jul/2011:21:48:26 +0200] Loaded configuration file "/private/etc/cups/cupsd.conf"
W [24/Jul/2011:21:48:26 +0200] Repairing ownership of "/private/etc/cups"
W [24/Jul/2011:21:48:26 +0200] Repairing ownership of "/private/etc/cups/certs"
W [24/Jul/2011:21:48:26 +0200] Repairing ownership of "/private/etc/cups/ppd"
W [24/Jul/2011:21:48:26 +0200] Repairing ownership of "/private/etc/cups/cupsd.conf"
W [24/Jul/2011:21:48:26 +0200] Repairing ownership of "/private/var/spool/cups"
W [24/Jul/2011:21:48:26 +0200] Repairing ownership of "/private/var/spool/cups/tmp"
I [24/Jul/2011:21:48:26 +0200] Configured for up to 100 clients.
I [24/Jul/2011:21:48:26 +0200] Allowing up to 100 client connections per host.
I [24/Jul/2011:21:48:26 +0200] Full reload is required.
E [24/Jul/2011:21:48:26 +0200] LoadAllPrinters: Unable to open /private/etc/cups/printers.conf - No such file or directory
E [24/Jul/2011:21:48:26 +0200] LoadAllClasses: Unable to open /private/etc/cups/classes.conf - No such file or directory
I [24/Jul/2011:21:48:26 +0200] Full reload complete.
I [24/Jul/2011:21:48:43 +0200] Added remote printer "***@192.168.1.97"...
I [24/Jul/2011:21:48:46 +0200] Added remote printer "***@192.168.1.97"...
I [24/Jul/2011:21:48:51 +0200] Added remote printer "***@192.168.1.123"...

Vielleicht kannst Du daran ja schon was erkennen...oder mir sagen, wie ich den Zweien was interpretierbares entlocken kann.

Nach wie vor lässt sich der Drucker am Clienten "sehen" und anlegen, aber wenn man dann drucken möchte ist bei der Auswahl des Drucker Schluss.

Post by Thomas Kaiser
Angaben ("Fileserver" vs. "Server" -- was hat das mit Files zu tun? Wer
ist der Druckserver und wer nicht?).

Den Fileserver (.1.97) hatte ich nur erwähnt weil da noch andere "Drucker" in dem Log standen.

.1.123 alias maccy ist mein Rechner (10.5.8) mit dem Drucker per USB und .1.20 alias booky2 ist der Client (10.4.11) der drucken soll.

Post by Thomas Kaiser
printers.conf und die classes.conf -- irgendwie ein Indiz, daß da mehr
mulu ist.

Ich habe schon "ewig" 10.5.8 drauf und es hat ja lange zeit auch Bestens funktioniert...an irgendwelchen conf-Dateien fummle ich normalerweise (nicht mehr) rum.

Gruß,

markus,