Ich habe ein eigenartiges Problem. Es kommt immer wieder (so ca. einmal am Tag) vor, dass wenn man druckt, nix passiert. Es werden alle Drucker angezeigt, aber es kommt nix raus und wenn man die Cups-Seite 631 aufruft, dann werden auch keinerlei Jobs angezeigt.
Nun das Eigenartige: Wenn ich nur ganz einfach Cups neu starte, sind wie von Zauberhand alle bis dahin gegebenen Druckaufträge sichtbar und werden anstandslos gedruckt.
Hat wer eine Idee, was da sein könnte, bzw. wo ich beginnen könnte zu suchen. Ich hab mir schon überlegt Cups per Cronjob stündlich neu zu starten. Aber ob das eine Dauerlösung sein kann?
Ich habe ein eigenartiges Problem. Es kommt immer wieder (so ca. einmal
am Tag) vor, dass wenn man druckt, nix passiert. Es werden alle Drucker
angezeigt, aber es kommt nix raus und wenn man die Cups-Seite 631
aufruft, dann werden auch keinerlei Jobs angezeigt.
Nun das Eigenartige: Wenn ich nur ganz einfach Cups neu starte, sind wie
von Zauberhand alle bis dahin gegebenen Druckaufträge sichtbar und
werden anstandslos gedruckt.
Hat wer eine Idee, was da sein könnte, bzw. wo ich beginnen könnte zu
suchen. Ich hab mir schon überlegt Cups per Cronjob stündlich neu zu
starten. Aber ob das eine Dauerlösung sein kann?
Hat wer eine Idee für mich?
zuerst: Analyse der cups logdateien und der syslog unter /var/log
zweitens: über das gelesene Meditieren (bei einem Spaziergang )
wenn dabei nix raus kommt:
drittens: RAM des Servers prüfen: das klingt nämlich so, als würde der
cups einfach still und leise abschmieren: das macht er normalerweise
nicht. Also RAM prüfen.
Ist die Hardware des Servers neu?
Habt ihr ECC Speicher?
Wenn ja, dann sollte es im BIOS einen Speicher geben in dem die Fehler
protokolliert werden.
Und: üblichen Verdächtigen Prüfen:
Freier Plattenplatz
df -h
hat jemand Mist gedruckt? Bei mir schicken die Leute gerne A3 oder A5
an die Drucker … die verwerfen solche MIstjobs einfach: das muss man
aber so in cups einstellen (abort job): aber das Problem sieht man
normalerweise im cups: der Drucker wird nämlich angehalten und man sieht
die auflaufenden Jobs
Danke für die Antwort. Leider bin ich dem Problem immer noch nicht Herr geworden. Einige der möglichen Probleme, die du Holger mir geschrieben hast, kenne ich, aber ich werde nicht ganz schlau daraus.
Der Server läuft ja als VM und alles andere bzw. der restliche LM-Server läuft auch problemlos weiter. Da schließe ich das mit dem RAM eher aus, oder.
Interessant ist, dass er so in Halbtagesschritten meint (laut log), dass ein Reload required ist. Dann kommt im Minutentakt die Fehlermeldung, dass die Drucker nicht erreichbar sind…
I [06/Oct/2017:06:25:33 +0200] Saving subscriptions.conf…
I [06/Oct/2017:06:25:34 +0200] Listening to 0.0.0.0:631 (IPv4)
I [06/Oct/2017:06:25:34 +0200] Listening to [v1.::]:631 (IPv6)
E [06/Oct/2017:06:25:34 +0200] Unknown directive MaxJobTime on line 4 of /etc/cups/cupsd.conf.
I [06/Oct/2017:06:25:34 +0200] Listening to /var/run/cups/cups.sock (Domain)
W [06/Oct/2017:06:25:34 +0200] No limit for Validate-Job defined in policy default and no suitable template found.
W [06/Oct/2017:06:25:34 +0200] No limit for Cancel-Jobs defined in policy default - using Pause-Printer’s policy.
W [06/Oct/2017:06:25:34 +0200] No limit for Cancel-My-Jobs defined in policy default - using Send-Document’s policy.
W [06/Oct/2017:06:25:34 +0200] No limit for Close-Job defined in policy default - using Send-Document’s policy.
W [06/Oct/2017:06:25:34 +0200] No limit for CUPS-Get-Document defined in policy default - using Send-Document’s policy.
W [06/Oct/2017:06:25:34 +0200] No JobPrivateAccess defined in policy default - using defaults.
W [06/Oct/2017:06:25:34 +0200] No JobPrivateValues defined in policy default - using defaults.
W [06/Oct/2017:06:25:34 +0200] No SubscriptionPrivateAccess defined in policy default - using defaults.
W [06/Oct/2017:06:25:34 +0200] No SubscriptionPrivateValues defined in policy default - using defaults.
I [06/Oct/2017:06:25:34 +0200] Remote access is enabled.
I [06/Oct/2017:06:25:34 +0200] Loaded configuration file „/etc/cups/cupsd.conf“
I [06/Oct/2017:06:25:34 +0200] Using default TempDir of /var/spool/cups/tmp…
I [06/Oct/2017:06:25:34 +0200] Configured for up to 100 clients.
I [06/Oct/2017:06:25:34 +0200] Allowing up to 100 client connections per host.
I [06/Oct/2017:06:25:34 +0200] Using policy „default“ as the default.
I [06/Oct/2017:06:25:34 +0200] Full reload is required.
I [06/Oct/2017:06:25:34 +0200] Saving job.cache…
I [06/Oct/2017:06:25:34 +0200] Loaded MIME database from „/usr/share/cups/mime“ and „/etc/cups“: 39 types, 56 filters…
W [06/Oct/2017:06:25:34 +0200] failed to CreateProfile: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
W [06/Oct/2017:06:25:34 +0200] failed to CreateProfile: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
I [06/Oct/2017:06:25:34 +0200] Registering ICC color profiles for „Bibl-Lexmark-MS510dn“
W [06/Oct/2017:06:25:34 +0200] failed to CreateDevice: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
W [06/Oct/2017:06:25:34 +0200] failed to CreateProfile: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
W [06/Oct/2017:06:25:34 +0200] failed to CreateProfile: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
I [06/Oct/2017:06:25:34 +0200] Registering ICC color profiles for „Bibl-Lexmark-MS510dn“
W [06/Oct/2017:06:25:34 +0200] failed to CreateDevice: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
W [06/Oct/2017:06:25:34 +0200] failed to CreateProfile: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
W [06/Oct/2017:06:25:34 +0200] failed to CreateProfile: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
I [06/Oct/2017:06:25:34 +0200] Registering ICC color profiles for „Lehrer-LexmarkMS510“
W [06/Oct/2017:06:25:34 +0200] failed to CreateDevice: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
W [06/Oct/2017:06:25:34 +0200] failed to CreateProfile: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
W [06/Oct/2017:06:25:34 +0200] failed to CreateProfile: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
I [06/Oct/2017:06:25:34 +0200] Registering ICC color profiles for „Lehrer-LexmarkMS510“
W [06/Oct/2017:06:25:34 +0200] failed to CreateDevice: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
W [06/Oct/2017:06:25:34 +0200] failed to CreateProfile: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
W [06/Oct/2017:06:25:34 +0200] failed to CreateProfile: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
I [06/Oct/2017:06:25:34 +0200] Registering ICC color profiles for „LexmarkTest“
W [06/Oct/2017:06:25:34 +0200] failed to CreateDevice: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
W [06/Oct/2017:06:25:34 +0200] failed to CreateDevice: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
W [06/Oct/2017:06:25:35 +0200] failed to CreateProfile: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
W [06/Oct/2017:06:25:35 +0200] failed to CreateProfile: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
I [06/Oct/2017:06:25:35 +0200] Registering ICC color profiles for „PDF-Printer“
W [06/Oct/2017:06:25:35 +0200] failed to CreateDevice: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
I [06/Oct/2017:06:25:35 +0200] Loading job cache file „/var/cache/cups/job.cache“…
I [06/Oct/2017:06:25:35 +0200] Full reload complete.
I [06/Oct/2017:06:25:35 +0200] Listening to 0.0.0.0:631 on fd 10…
I [06/Oct/2017:06:25:35 +0200] Listening to [v1.::]:631 on fd 11…
I [06/Oct/2017:06:25:35 +0200] Listening to /var/run/cups/cups.sock:631 on fd 12…
I [06/Oct/2017:06:25:35 +0200] Resuming new connection processing…
E [06/Oct/2017:06:25:35 +0200] Failed to update TXT record for SW Drucker Bibliothek @ server01: -2
E [06/Oct/2017:06:25:35 +0200] Failed to update TXT record for PC-Raum Bibliothek @ server01: -2
E [06/Oct/2017:06:25:35 +0200] Failed to update TXT record for Farbdrucker der Bibliothek @ server01: -2
E [06/Oct/2017:06:25:35 +0200] Failed to update TXT record for Neuer Drucker im Lehrerzimmer @ server01: -2
E [06/Oct/2017:06:25:35 +0200] Failed to update TXT record for Testdrucker Linuxmuster @ server01: -2
E [06/Oct/2017:06:25:35 +0200] Failed to update TXT record for PDF-Printer @ server01: -2
W [06/Oct/2017:06:25:44 +0200] [Job 1212] The printer is unreachable at this time.
W [06/Oct/2017:06:25:47 +0200] [Job 1212] The printer is unreachable at this time.
W [06/Oct/2017:06:25:50 +0200] [Job 1212] The printer is unreachable at this time.
W [06/Oct/2017:06:25:53 +0200] [Job 1212] The printer is unreachable at this time.
W [06/Oct/2017:06:25:59 +0200] [Job 1212] The printer is unreachable at this time.
I [06/Oct/2017:06:26:04 +0200] Saving printers.conf…
I [06/Oct/2017:06:26:05 +0200] Saving job.cache…
I [06/Oct/2017:06:26:05 +0200] Saving subscriptions.conf…
W [06/Oct/2017:06:26:07 +0200] [Job 1212] The printer is unreachable at this time.
W [06/Oct/2017:06:26:18 +0200] [Job 1212] The printer is unreachable at this time.
W [06/Oct/2017:06:26:21 +0200] [Job 1212] The printer is unreachable at this time.
W [06/Oct/2017:06:26:24 +0200] [Job 1212] The printer is unreachable at this time.
Interessant ist, dass er so in Halbtagesschritten meint (laut log), dass
ein Reload required ist.
das ist doch mal was: vor allem die Regelmäßigkeit.
Ich würde mal duckduckgo befragen nach:
cups reload required apparmor
E [06/Oct/2017:06:25:34 +0200] Unknown directive MaxJobTime on line
4 of /etc/cups/cupsd.conf.
das ist das nächste: einen Eintrag MaxJobTime hab ich in meiner
cupsd.conf nicht …
Kommentier mal die Zeile aus.
W [06/Oct/2017:06:25:34 +0200] No limit for Validate-Job defined in
policy default and no suitable template found.
auch die Zeile wäre mal eine Suche Wert.
I [06/Oct/2017:06:25:34 +0200] Using default TempDir of
/var/spool/cups/tmp…
und hier: das tempdir: existiert es?
Mit welchen Rechten?
W [06/Oct/2017:06:25:34 +0200] failed to CreateProfile:
org.freedesktop.DBus.Error.NoReply:Message did not receive a reply
(timeout by message bus)
auch eine seltsame Meldung → suchen.
E [06/Oct/2017:06:25:35 +0200] Failed to update TXT record for SW
Drucker Bibliothek @ server01: -2
E [06/Oct/2017:06:25:35 +0200] Failed to update TXT record for
PC-Raum Bibliothek @ server01: -2
E [06/Oct/2017:06:25:35 +0200] Failed to update TXT record for
Farbdrucker der Bibliothek @ server01: -2
E [06/Oct/2017:06:25:35 +0200] Failed to update TXT record for Neuer
Drucker im Lehrerzimmer @ server01: -2
E [06/Oct/2017:06:25:35 +0200] Failed to update TXT record for
Testdrucker Linuxmuster @ server01: -2
E [06/Oct/2017:06:25:35 +0200] Failed to update TXT record for
PDF-Printer @ server01: -2
lauter seltsame Meldungen: die sich aber meist auf „ich kann nicht“
beziehen: und das apssiert, wenn Dateien an die er will falsche Rechte
haben ODER: es gibt kein Platz mehr.
Bitte mal
df -h
und danach
df -i
ausführen und schauen, ob da irgendwo 100% steht… der fast.
Die apparmor Meldungen im anderen Post sind auch vielversprechend: die
würde ich, nach Kontrolle des Plattenplatzes, zuerst in duckduckgo suchen.
Vielen Dank für deine Antwort.
Ich habe nun wieder Stunden damit verbracht, den Fehler zu suchen. Platz ist genug auf den Platten, die Rechte habe ich durchgesehen und keine Abweichung gefunden. Leider werde ich aus den weiteren Fehlermeldungen und den “ersuchten” Antworten nicht weiter schlau.
… hatte ich mal beobachtet, als ein Drucker über die Schulkonsole nicht erlaubt war. Darüber findet man dann im Netz auch keine brauchbaren Infos, weil das bei uns ja sehr speziell ist.
Ich würde mal in der Schulkonsole als Administrator die Drucker neu den Rechnern zuweisen.
Wenn Cups nicht mehr „geht“, läuft dann der Dienst dann überhaupt noch, oder ist er beendet? Es gab mal auf der alten Liste jemanden, bei dem hat rsnapshot cups beendet und nicht mehr gescheit neu gestartet…
Die Sache ist mysteriös! Ich habe in der Schulkonsole nachgesehen und entdeckt, dass mir ist aufgefallen, dass ich bei keinem Drucker ein Setting getroffen hatte. Alle waren auf “kein Raum ausgewählt”. Trotzdem konnte drucken.
–>komisch! Warum?
Nun habe ich die Räume festgelegt und die TXT-Fehler sind verschwunden. Die appamor Fehler sind auch nicht mehr aufgetaucht. Da ich am Wochenende nicht direkt in der Schule sein konnte, werde ich das an den Clients erst morgen anschauen können.
Einzig die
I [08/Oct/2017:14:07:12 +0200] Registering ICC color profiles for "Lehrer-LexmarkMS510"
W [08/Oct/2017:14:07:12 +0200] failed to CreateDevice: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
W [08/Oct/2017:14:07:12 +0200] failed to CreateProfile: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
W [08/Oct/2017:14:07:12 +0200] failed to CreateProfile: org.freedesktop.DBus.Error.NoReply:Message did not receive a reply (timeout by message bus)
Fehler sind geblieben. Hmmm…
Das sind die Ausgaben, die Thorsten angesprochen hat:
20:32/0 server01 /var/log/cups # ls -lh /var/cache/ && ls -lh /var/cache/cups
total 104K
drwxr-xr-x 3 www-data www-data 4.0K May 15 12:29 apache2
drwxr-xr-x 3 root root 4.0K Oct 7 01:28 apt
drwxr-xr-x 3 root root 4.0K Oct 8 06:48 apt-xapian-index
drwxrwxr-x 2 root bind 4.0K Oct 3 12:43 bind
drwxrwxr-x 3 root lp 4.0K Oct 8 14:07 cups
drwxr-xr-x 2 root root 4.0K Oct 6 22:55 debconf
drwxr-xr-x 2 root root 4.0K Aug 11 2014 dictionaries-common
drwxr-xr-x 2 root root 4.0K Sep 7 12:55 fontconfig
drwxr-xr-x 2 root root 4.0K Jun 27 2011 fonts
drwx------ 2 root root 4.0K Oct 6 22:55 ldconfig
drwxr-xr-x 4 root root 4.0K Oct 3 12:43 linuxmuster
drwxr-xr-x 3 root root 4.0K Nov 23 2016 linuxmuster-client-servertools
drwxr-xr-x 2 root root 4.0K Jul 12 2016 linuxmuster-ipfire
drwxr-xr-x 6 root root 4.0K Sep 8 22:49 linuxmuster-linbo
drwxr-sr-x 33 man root 4.0K Oct 8 06:48 man
drwxr-xr-x 2 root root 4.0K Mar 15 2017 mindi
drwxr-xr-x 2 root root 4.0K Mar 15 2017 mondo
drwxr-s--- 2 nagios www-data 4.0K Oct 8 20:32 nagios3
drwxr-xr-x 2 root root 4.0K Nov 23 2016 nscd
drwxr-xr-x 3 root root 4.0K Nov 23 2016 postgresql
drwxr-xr-x 2 root root 4.0K Dec 13 2011 pppconfig
drwxr-xr-x 2 root root 4.0K Nov 16 2011 razor
drwxr-xr-x 3 root root 4.0K Oct 5 18:26 samba
drwxr-xr-x 3 root root 4.0K Nov 23 2016 sophomorix
-rw-r--r-- 1 root root 112 Sep 28 13:02 xe-linux-distribution
drwxr-xr-x 5 root root 4.0K Jul 9 2016 xenstore
total 18M
-rw------- 1 root lp 1.7K Oct 6 12:14 10.16.2.20.snmp
-rw------- 1 root lp 1.7K Sep 20 16:23 10.16.2.21.snmp
-rw------- 1 root lp 1.7K Oct 6 13:55 10.16.2.26.snmp
-rw------- 1 root lp 1.7K Oct 5 14:25 10.16.2.27.snmp
-rw-r--r-- 1 root root 5.1K Sep 21 14:02 Bibl-Lexmark-MS510dn.data
-rw-r--r-- 1 root root 1.8K Aug 30 13:08 Bibl-PCRaum.data
-rw-r--r-- 1 root root 18K Sep 7 13:02 HPColorLaserJetM552.data
-rw-r--r-- 1 root root 5.1K Sep 21 12:00 Lehrer-LexmarkMS510.data
-rw-r--r-- 1 root root 6.6K Dec 1 2016 LexmarkTest.data
-rw-r--r-- 1 root root 2.7K Nov 23 2016 PDF-Printer.data
-rw-r--r-- 1 root root 2.7K Nov 23 2016 PDF.data
-rw------- 1 lp lp 149K Jun 26 14:24 help.index
-rw-r----- 1 root lp 63K Oct 8 14:07 job.cache
-rw-r----- 1 root lp 63K Oct 8 14:06 job.cache.O
-rw-r--r-- 1 root root 223 Oct 6 22:54 ppd-updates
-rw------- 1 lp lp 17M Oct 6 22:54 ppds.dat
-rw-r----- 1 root lp 55 Nov 23 2016 remote.cache
drwxrwxr-x 2 root lp 4.0K Jun 4 2015 rss
> root@hp8100-01:~# cat /etc/fstab
> # /etc/fstab: static file system information.
> #
> # Use 'blkid' to print the universally unique identifier for a
> # device; this may be used with UUID= as a more robust way to name devices
> # that works even if disks are added and removed. See fstab(5).
> #
> # <file system> <mount point> <type> <options> <dump> <pass>
> # / was on /dev/sda1 during installation
> /dev/sda1 / ext4 errors=remount-ro 0 0
> /dev/sda2 none swap sw 0 0
> #/dev/sda5 /virtual ext4 defaults 0 0
Das sieht alles gut aus. Mein Verdacht bezüglich quota hat sich nach Betrachtung deiner fstab in Rauch aufgelöst.
Wenn Du jetzt noch deine workstation schickst!
Heute morgen war wieder etwas Interessantes zu beobachten. Obwohl der Server “up and running” war, konnten die PCs aus dem Lehrerzimmer nicht drucken. Ich habe in der access.conf nachgesehen und da haben bei einem Drucker die IPBereiche gefehlt und bei einem anderen waren sie gleich 2x eingetragen.
Ich habe das nun händisch korrigiert. Wird das eh nicht wieder überschrieben?
Warum kann sowas passieren? Hat wer eine Idee?
Da du alle Clients in den Adress-Raum 10.16.100.xxx gruppiert hast bekommst du unter anderem auch Probleme mit dem An/Aus vom Internet/Webfilter/Intranet in der Schulkonsole unter aktueller Raum. Schaltet dort jemand dann sind alle Rechner davon betroffen.
Um das zu veranschaulichen:
du schaltest für Raum 102 die Drucker aus → er trägt 10.16.100.x als
„darf nicht“ in die access.conf (bzw. löscht den Eintrag).
Dann darf bei dir keiner mehr drucken: alle fliegen raus, auch die der
anderen Räume, weil sie in der gleichen Range sind: also IP mäßig im
selben Raum stehen.
Bereinige deine workstations und mach einen import.
Danach alle Druckerzuweisungen in der Schulkonsole löschen, speichern
und neu anlegen.
Jetzt wird mir einiges klar. Ich habe das schon vermutet, nachdem ich die access.conf angeschaut habe. Kann ich die IPs einfach in der Schulkonole ändern und speichern, oder muss ich da noch was beachten?