[CUPS] printer wil niet

Pagina: 1
Acties:
  • 199 views sinds 30-01-2008
  • Reageer

  • Feanaro
  • Registratie: Februari 2002
  • Laatst online: 25-11-2025
Het hele verhaal: ik had een server draaien met debian sarge, hierop ook CUPS en mijn HP 3550 printer werd keurig via IPP gedeeld. Op gegeven moment deed mijn server deed moeilijk (system board mol, bleek later), dus ook printen wilde niet meer.

Nu een nieuw systeempje geregeld, HD hierin gezet en het hele hapje werkt weer. Heb meteen maar geupgrade naar Debian 4.0. Maar mijn printer krijg ik nog steeds niet aan de gang. Volgens deze handleiding heb ik wel de printer aan windows toegevoegd en ik kan ook in http://*serverhostname*:631 komen, maar bij Manage Printers vind ik bij mijn printer de regel
Picasso (Default Printer) "unable to open print file /var/spool/cups/d00080-001 - Permission denied"
plaatje

Heb Google al afgezocht, maar kan nergens een oplossing vinden.

Aurë Entuluva!


  • benoni
  • Registratie: November 2003
  • Niet online
Kun je even kijken onder welke gebruikersnaam de CUPS driver draait

ps aux | grep cups


Vervolgens kijken of de rechten voor de temporary map goed staan voor deze gebruiker:

ls -la /var/spool/cups
total 12
drwx--x--- 3 cupsys lp   4096 Jun  1  2006 .
drwxr-xr-x 7 root   root 4096 May  5 15:38 ..
drwxrwx--T 2 cupsys lp   4096 May 17  2006 tmp


Kun je met chown en chmod aanpassen (waarschijnlijk user cupsys, group lp).

  • Feanaro
  • Registratie: Februari 2002
  • Laatst online: 25-11-2025
dit is mijn output:

root 3344 0.0 1.1 4512 2136 ? Ss 23:21 0:00 /usr/sbin/cupsd
root 3919 0.0 0.3 4500 716 pts/0 S+ 23:35 0:00 grep cups

betekent dit dat cupsd onder root draait? Dan zou het geen problemen op moeten leveren met die temporary directory, toch?

Aurë Entuluva!


  • benoni
  • Registratie: November 2003
  • Niet online
Feanaro schreef op zaterdag 08 september 2007 @ 23:36:
dit is mijn output:

root 3344 0.0 1.1 4512 2136 ? Ss 23:21 0:00 /usr/sbin/cupsd
root 3919 0.0 0.3 4500 716 pts/0 S+ 23:35 0:00 grep cups

betekent dit dat cupsd onder root draait? Dan zou het geen problemen op moeten leveren met die temporary directory, toch?
Ik heb het idee dat de cupsd daemon voor elke printjob een proces start die niet als root draait, en dat er daarom geen toegang wordt verkregen. Waarschijnlijk wordt het printbestand weggeschreven onder een andere gebruikersnaam (root) en probeert het afgesplitste proces (onder gebruikersnaam cupsys?) het bestand te lezen, waar het geen rechten heeft.

Je zou één van deze dingen kunnen proberen:
1. De cupsd daemon onder de juiste gebruikersnaam laten draaien, niet als root.
Op mijn standaard geïnstalleerde Ubuntu server wordt in /etc/init.d/cupsys met chown cupsys:lp `dirname "$PIDFILE"` de gebruikersnaam toegekend aan de PID-file, en start-stop-daemon --start --quiet --oknodo --pidfile "$PIDFILE" --exec $DAEMON neemt dan blijkbaar de eigenaar van de PID-file over om het proces te draaien, want mijn daemon draait onder de naam 'cupsys'. Dit lijkt me ook voor Debian systemen de standaard (kan even niet controleren, geen van mijn Debian systemen draait CUPS). Misschien dat een dpkg-reconfigure de installatie al goed bijwerkt. Je kunt ook de usernaam en groep forceren voor de daemon door '--chuid cupsys:lp' toe te voegen aan de opties van de start-stop-daemon. Dat laatste werkt niet, zie verderop.
2. De toegangsrechten op de map /var/spool/cups world accessible maken (chmod a+rwx). Is uiteraard een lelijker optie omdat het systeem dan minder veilig is.

[ Voor 4% gewijzigd door benoni op 09-09-2007 15:12 ]


  • Feanaro
  • Registratie: Februari 2002
  • Laatst online: 25-11-2025
Heb even een dpkg-reconfigure op cupsys gedraaid, dit is wat eruit komt:
Stopping Common Unix Printing System: cupsd.
Deleting root from lpadmin group. If you use NIS/LDAP, change this manually.
/usr/sbin/deluser: The user `root' is not a member of group `lpadmin'.
Starting Common Unix Printing System: cupsd.
Wat is hij nu aan het doen, vraag ik me af? Waarom gooit ie root uit de lpadmin groep?

In /etc/init.d/cupsys zie ik deze regels staan onder start:
chown root:lpadmin /usr/share/cups/model 2>/dev/null || true
chmod 3775 /usr/share/cups/model 2>/dev/null || true
mkdir -p `dirname "$PIDFILE"`
Denk dat het hierom gaat... Hij draait dus onder user root en groep lpadmin, is dit niet goed?

Het vreemde van alles vind ik nog dat het zonder dat ik er iets aan veranderd heb, opeens niet meer werkte allemaal...

[ Voor 34% gewijzigd door Feanaro op 09-09-2007 13:23 ]

Aurë Entuluva!


  • benoni
  • Registratie: November 2003
  • Niet online
Feanaro schreef op zondag 09 september 2007 @ 13:19:
Wat is hij nu aan het doen, vraag ik me af? Waarom gooit ie root uit de lpadmin groep?
Weet ik niet precies, maar root hoeft m.i. niet in de groep te staan omdat root toch al rechten heeft. En het is veiliger om een samengebruikte applicatie als een printer-spooler niet als root te draaien, zodat het niet misbruikt kan worden door malware om root-rechten te verkrijgen op het systeem. Kijk even of je daemon nu onder een eigen gebruikersnaam start en werkt...
Feanaro schreef op zondag 09 september 2007 @ 13:19:
Denk dat het hierom gaat... Hij draait dus onder user root en groep lpadmin, is dit niet goed?
Die eerste twee regels zijn volgens mij voor de map met printerdefinitie files, niet voor de PID-file of de spool-map. Die mogen dus wel als eigenaar root hebben, als ze maar leesbaar zijn voor de andere gebruikers.

Ik gooi er maar even het hele cupsys opstart script van Ubuntu in, kun je 't even vergelijken:
Bash:
1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81
82
83
84
85
86
87
88
89
90
91
92
93
94
95
96
97
98
brederode:~# cat /etc/init.d/cupsys 
#! /bin/sh

PATH=/usr/local/sbin:/usr/local/bin:/sbin:/bin:/usr/sbin:/usr/bin
DAEMON=/usr/sbin/cupsd
NAME=cupsd
PIDFILE=/var/run/cups/$NAME.pid
DESC="Common Unix Printing System"

unset TMPDIR

test -f $DAEMON || exit 0

set -e

if [ -r /etc/default/cupsys ]; then
  . /etc/default/cupsys
fi

. /lib/lsb/init-functions

# Get the timezone set.
if [ -z "$TZ" -a -e /etc/timezone ]; then
    TZ=`cat /etc/timezone`
    export TZ
fi

case "$1" in
  start)
        log_begin_msg "Starting $DESC: $NAME"
        chown root:lpadmin /usr/share/ppd/custom 2>/dev/null || true
        chmod 3775 /usr/share/ppd/custom 2>/dev/null || true
        mkdir -p `dirname "$PIDFILE"`
        chown cupsys:lp `dirname "$PIDFILE"`

        # create the logs file since cupsd can't
        for l in access_log page_log error_log; do
            [ -e /var/log/cups/$l ] || touch /var/log/cups/$l
            chmod 640 /var/log/cups/$l
            chown cupsys:lpadmin /var/log/cups/$l
        done

        if [ "$LOAD_LP_MODULE" = "yes" -a -f /usr/lib/cups/backend/parallel \
             -a -f /proc/devices -a -f /proc/modules \
             -a -x /sbin/modprobe ]; then
          modprobe -q lp || true
          modprobe -q ppdev || true # for ISO-1284 device name detection
        fi

        start-stop-daemon --start --quiet --oknodo --pidfile "$PIDFILE" --exec $DAEMON

        # Correct the permissions after starting the CUPS daemon
        for l in access_log page_log error_log; do
            chmod 640 /var/log/cups/$l || true
            chown cupsys:lpadmin /var/log/cups/$l || true
        done

        log_end_msg $?
        ;;
  stop)
        log_begin_msg "Stopping $DESC: $NAME"
        start-stop-daemon --stop --quiet --retry 5 --oknodo --pidfile $PIDFILE --name $NAME
        log_end_msg $?
        ;;
  restart|force-reload)
        log_begin_msg "Restarting $DESC: $NAME"
        if start-stop-daemon --stop --quiet --retry 5 --oknodo --pidfile $PIDFILE --name $NAME; then
                start-stop-daemon --start --quiet --pidfile "$PIDFILE" --exec $DAEMON
                # Correct the permissions after starting the CUPS daemon
                for l in access_log page_log error_log; do
                    chmod 640 /var/log/cups/$l || true
                    chown cupsys:lpadmin /var/log/cups/$l || true
                done
        fi
        log_end_msg $?
        ;;
  status)
        echo -n "Status of $DESC: "
        if [ ! -r "$PIDFILE" ]; then
                echo "$NAME is not running."
                exit 3
        fi
        if read pid < "$PIDFILE" && ps -p "$pid" > /dev/null 2>&1; then
                echo "$NAME is running."
                exit 0
        else
                echo "$NAME is not running but $PIDFILE exists."
                exit 1
        fi
        ;;
  *)
        N=/etc/init.d/${0##*/}
        echo "Usage: $N {start|stop|restart|force-reload|status}" >&2
        exit 1
        ;;
esac

exit 0

[ Voor 86% gewijzigd door benoni op 09-09-2007 13:37 ]


  • Feanaro
  • Registratie: Februari 2002
  • Laatst online: 25-11-2025
Ik snap alleen niet goed waar ik nu moet zorgen dat cupsys onder een andere user moet draaien...

de inhoud van de /var/spool/cups/ directory is trouwens root:lpadmin, met rechten: -rw------ (is dat niet 700?)

Aurë Entuluva!


  • benoni
  • Registratie: November 2003
  • Niet online
Feanaro schreef op zondag 09 september 2007 @ 13:33:
Ik snap alleen niet goed waar ik nu moet zorgen dat cupsys onder een andere user moet draaien...
Dit had ik al stiekem bijge-edit in een post hiervoor:
Je kunt ook de usernaam en groep forceren voor de daemon door '--chuid cupsys:lp' toe te voegen aan de opties van de start-stop-daemon. Moet je wel even kijken of die gebruiker bestaat: 'cat /etc/passwd | grep cups', 'cat /etc/group | grep cups'.

Dat pas ie dus uit zichzelf aan... start als root, verandert in 'cupsys'. zie verderop.
de inhoud van de /var/spool/cups/ directory is trouwens root:lpadmin, met rechten: -rw------ (is dat niet 700?)
Dat ziet er naar uit dat een CUPS-daemon is voorbereid die helemaal onder root draait. Heb je dat specifiek aangegeven bij het installeren?

[ Voor 34% gewijzigd door benoni op 09-09-2007 15:13 ]


  • Feanaro
  • Registratie: Februari 2002
  • Laatst online: 25-11-2025
Heb niets specifiek aangegeven bij de installatie, het zal wel standaard (van Sarge) geweest zijn, denk ik dan. Heeft heeft eerst wel gewerkt.

Maar als ik jouw conf file vergelijk met de mijne, zie ik dat deze regels bij mij ontbreken onder start:
chown cupsys:lp `dirname "$PIDFILE"`

# create the logs file since cupsd can't
for l in access_log page_log error_log; do
[ -e /var/log/cups/$l ] || touch /var/log/cups/$l
chmod 640 /var/log/cups/$l
chown cupsys:lpadmin /var/log/cups/$l
done
Als ik deze "chown cupsys:lp `dirname "$PIDFILE"`" toevoeg, zou ie volgens jou onder cupsys:lp moeten draaien? Volgens mij heb ik die user groep niet, dus dan kan ik er bij mij lp:lpadmin van maken?
(en dan uiteraard de spool dir helemaal lp:lpadmin)

[ Voor 3% gewijzigd door Feanaro op 09-09-2007 13:41 ]

Aurë Entuluva!


  • benoni
  • Registratie: November 2003
  • Niet online
Die chown van de PID-file doet 't 'm volgens mij.

Je kunt even in /etc/passwd en /etc/group kijken of je geen 'cupsys' en groep 'lp' hebt.

  • Feanaro
  • Registratie: Februari 2002
  • Laatst online: 25-11-2025
had al even in passwd en group gekeken, ik heb een user lp en een groep lp en lpadmin... rechten dan op lp:lp zetten? (PID-file)

Aurë Entuluva!


  • benoni
  • Registratie: November 2003
  • Niet online
Feanaro schreef op zondag 09 september 2007 @ 13:51:
had al even in passwd en group gekeken, ik heb een user lp en een groep lp en lpadmin... rechten dan op lp:lp zetten? (PID-file)
Probeer maar, kan geen kwaad lijkt me, en anders kun je altijd nog een gebruiker aanmaken.

  • Feanaro
  • Registratie: Februari 2002
  • Laatst online: 25-11-2025
Heb die regel toegevoegd aan m'n /etc/init.d/cupsys, lp:lp

En ik heb de rechten van de /var/spool/cups op lp gezet.. die zou er nu bij kunnen, zou je zeggen...
Nog steeds dezelfde error trouwens.


Edit: dit vond ik nog in /etc/cups/cupsd.conf

[q]#
# User/Group: the user and group the server runs under. Normally this
# must be lp and lpadmin, however you can configure things for another
# user or group as needed.
#
# Note: the server must be run initially as root to support the
# default IPP port of 631. It changes users whenever an external
# program is run, or if the RunAsUser directive is specified...
#

User lp
Group lpadmin
[/q}

heb dit maar geuncommend... Alleen snap ik die laatste "Note:" niet zo goed... Moet ik 'm dan toch als root draaien?

[ Voor 59% gewijzigd door Feanaro op 09-09-2007 14:01 ]

Aurë Entuluva!


  • benoni
  • Registratie: November 2003
  • Niet online
Feanaro schreef op zondag 09 september 2007 @ 13:56:
Nog steeds dezelfde error trouwens.
Dan moeten we er toch achter zien te komen welk proces er eigenlijk de foutmelding genereert. Heb je een /var/log/cups/error_log? Kun je tijdens het printen met 'lsof | grep /var/spool/cups' kijken welk proces er gebruik maakt van de spoolmap? (kan zijn dat 't wat te snel gaat om te kunnen uitlezen met lsof)

  • Feanaro
  • Registratie: Februari 2002
  • Laatst online: 25-11-2025
Hij zegt in z'n error log:
I [09/Sep/2007:13:55:16 +0200] Loaded configuration file "/etc/cups/cupsd.conf"
I [09/Sep/2007:13:55:16 +0200] Using default TempDir of /var/spool/cups/tmp...
W [09/Sep/2007:13:55:16 +0200] Repairing ownership of "/var/spool/cups"
W [09/Sep/2007:13:55:16 +0200] Repairing ownership of "/var/spool/cups/tmp"
I [09/Sep/2007:13:55:16 +0200] Cleaning out old temporary files in "/var/spool/cups/tmp"...
Wat dus betekent dat ie de eigenaar van /var/spool/cups terug zet op root:root, iedere keer dat ie opstart :-S

Aurë Entuluva!


  • benoni
  • Registratie: November 2003
  • Niet online
Feanaro schreef op zondag 09 september 2007 @ 13:56:
Edit: dit vond ik nog in /etc/cups/cupsd.conf
# Note: the server must be run initially as root to support the
# default IPP port of 631. It changes users whenever an external
# program is run, or if the RunAsUser directive is specified...
Ooh... nu snap ik waarom bij mij de start-stop-daemon 'm start onder root, en dattie uiteindelijk toch onder de cupsys gebruiker draait 8)7
User lp
Group lpadmin
heb dit maar geuncommend... Alleen snap ik die laatste "Note:" niet zo goed... Moet ik 'm dan toch als root draaien?
Maak er maar lp en lp van dan. De truc is dat ie als root opstart om een poortnummer onder 1024 te mogen adresseren, en daarna het proces verhuist naar de gebruikersnaam zoals ingesteld in de voorkeuren. Als je geen 'lpadmin' groep hebt gaat dat fout, en wellicht dat ie daarom terugvalt op 'root'. In mijn (nieuwere) config file zie ik trouwens geen User en Group directives, maar de 'lpadmin' wordt gebruikt voor de SystemGroup directive:
code:
1
2
# Administrator user group...
SystemGroup lpadmin

Die 'lpadmin' is dus voor toegang tot de te schrijven logfiles, niet voor de spoolmap. Elders lees ik ook dat Group en SystemGroup niet hetzelfde mogen zijn, in dat geval wordt de Group voorkeur teruggezet naar 'root', en blijkbaar 'repareert' ie daardoor ook het eigenaarschap van de spoolfolder mappen... je krijgt gedetailleerder meldingen in je error_log als je 'LogLevel debug' in /etc/cups/cupsd.conf zet en de daemon herstart.

Er is wat uitleg bij, met 'man cupsd.conf':
code:
1
2
3
4
5
6
7
8
Group group-name-or-number
  Specifies the group name or ID that will be used when executing external programs.

User user-name
  Specifies the user name or ID that is used when running external programs.

SystemGroup group-name [group-name ...]
  Specifies the group(s) to use for System class authentication.


Bovenstaande lezend, neem ik aan dat de printerdriver via /usr/lib/cups/cgi-bin/printers.cgi zou worden gestart met gebruikersnaam lp en groep lp, en dat dit proces dan toegang zou moeten hebben tot de spoolmap.

[ Voor 47% gewijzigd door benoni op 09-09-2007 15:08 ]


  • Feanaro
  • Registratie: Februari 2002
  • Laatst online: 25-11-2025
lpadmin heb ik wel hoor, staat in /etc/group

Maar ook lp en lp geeft geen resultaat.

test page printen geeft geen vreemde zaken in error_log:
I [09/Sep/2007:14:17:51 +0200] Full reload complete.
I [09/Sep/2007:14:17:51 +0200] Listening to :::631 on fd 2...
I [09/Sep/2007:14:17:51 +0200] Listening to 0.0.0.0:631 on fd 3...
I [09/Sep/2007:14:17:55 +0200] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=28630)
I [09/Sep/2007:14:18:00 +0200] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=28632)
I [09/Sep/2007:14:18:01 +0200] Adding start banner page "none" to job 84.
I [09/Sep/2007:14:18:01 +0200] Adding end banner page "none" to job 84.
I [09/Sep/2007:14:18:01 +0200] Job 84 queued on "Picasso" by "guest".
I [09/Sep/2007:14:18:03 +0200] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=28633)
Moet er nu alleen vandoor, ga er vanavond wel weer even naar kijken...

Aurë Entuluva!


  • Feanaro
  • Registratie: Februari 2002
  • Laatst online: 25-11-2025
Heb nu de error modus op "debug" gezet, dit geeft inderdaad wat meer output. Zo gaf hij een error omdat de Group en SystemGroup variabele dezelfde waren, waarna hij de group naar "root" terug zet. Nu heb ik zowel user als group op "lp" gezet en ben hiermee van die error af.

error log:
D [10/Sep/2007:21:01:42 +0200] [CGI] Skip first part...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 750...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 758 on character ':'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Output second part...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 758...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting "{job_state=7" at 772, result=0...
D [10/Sep/2007:21:01:42 +0200] [CGI] Skip first part...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 772...
D [10/Sep/2007:21:01:42 +0200] [CGI] "{time_at_completed}" at 806...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 807 on character ':'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Output second part...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 807...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting "{job_state=8" at 820, result=0...
D [10/Sep/2007:21:01:42 +0200] [CGI] Skip first part...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 820...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 828 on character ':'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Output second part...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 828...
D [10/Sep/2007:21:01:42 +0200] [CGI] "{time_at_completed}" at 863...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 864 on character '}'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Finished "{job_state=8", out=(nil)...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 865 on character '}'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Finished "{job_state=7", out=(nil)...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 866 on character '}'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Finished "{job_state=6", out=(nil)...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 867 on character '}'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Finished "{job_state=5", out=(nil)...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 868 on character '}'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Finished "{job_state=4", out=(nil)...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 869 on character '}'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Finished "{job_state=3", out=0x4024f3a0...
D [10/Sep/2007:21:01:42 +0200] PID 13075 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting "{job_preserved>0" at 903, result=1...
D [10/Sep/2007:21:01:42 +0200] [CGI] Output first part...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 903...
D [10/Sep/2007:21:01:42 +0200] [CGI] "{job_id}" at 954...
D [10/Sep/2007:21:01:42 +0200] [CGI] "{job_printer_uri}" at 992...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 1075 on character ':'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Skip second part...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 1075...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 1076 on character '}'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Finished "{job_preserved>0", out=0x4024f3a0...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting "{job_state=4" at 1090, result=0...
D [10/Sep/2007:21:01:42 +0200] [CGI] Skip first part...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 1090...
D [10/Sep/2007:21:01:42 +0200] [CGI] "{job_id}" at 1141...
D [10/Sep/2007:21:01:42 +0200] [CGI] "{job_printer_uri}" at 1179...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 1262 on character ':'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Output second part...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 1262...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 1263 on character '}'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Finished "{job_state=4", out=0x4024f3a0...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting "{job_state=3" at 1277, result=1...
D [10/Sep/2007:21:01:42 +0200] cupsdCloseClient: 17
D [10/Sep/2007:21:01:42 +0200] [CGI] Output first part...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 1277...
D [10/Sep/2007:21:01:42 +0200] [CGI] "{job_id}" at 1325...
D [10/Sep/2007:21:01:42 +0200] [CGI] "{job_printer_uri}" at 1363...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 1440 on character ':'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Skip second part...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 1440...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 1441 on character '}'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Finished "{job_state=3", out=0x4024f3a0...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting "{job_state<7" at 1455, result=1...
D [10/Sep/2007:21:01:42 +0200] [CGI] Output first part...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 1455...
D [10/Sep/2007:21:01:42 +0200] [CGI] "{job_id}" at 1505...
D [10/Sep/2007:21:01:42 +0200] [CGI] "{job_printer_uri}" at 1543...
D [10/Sep/2007:21:01:42 +0200] [CGI] "{job_id}" at 1671...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 1747 on character ':'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Skip second part...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 1747...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 1748 on character '}'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Finished "{job_state<7", out=0x4024f3a0...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 1768 on character '}'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Finished looping on "job_id"...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 1779 on character '}'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Finished "{#job_id=0", out=0x4024f3a0...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 1780 on EOF...
D [10/Sep/2007:21:01:42 +0200] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl")
D [10/Sep/2007:21:01:42 +0200] [CGI] locale="en_gb"...
D [10/Sep/2007:21:01:42 +0200] [CGI] Template file is "/usr/share/cups/templates/pager.tmpl" ...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 0...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting "{PREVURL?" at 87, result=0...
D [10/Sep/2007:21:01:42 +0200] [CGI] Skip first part...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 87...
D [10/Sep/2007:21:01:42 +0200] [CGI] "{PREVURL}" at 105...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 191 on character ':'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Output second part...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 191...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 198 on character '}'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Finished "{PREVURL?", out=0x4024f3a0...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting "{ORDER=dec" at 247, result=0...
D [10/Sep/2007:21:01:42 +0200] [CGI] Skip first part...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 247...
D [10/Sep/2007:21:01:42 +0200] [CGI] "{THISURL}" at 265...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 367 on character ':'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Output second part...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 367...
D [10/Sep/2007:21:01:42 +0200] [CGI] "{THISURL}" at 385...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 489 on character '}'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Finished "{ORDER=dec", out=0x4024f3a0...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting "{NEXTURL?" at 535, result=0...
D [10/Sep/2007:21:01:42 +0200] [CGI] Skip first part...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 535...
D [10/Sep/2007:21:01:42 +0200] [CGI] "{NEXTURL}" at 553...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 631 on character ':'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Output second part...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 631...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 638 on character '}'...
D [10/Sep/2007:21:01:42 +0200] [CGI] Finished "{NEXTURL?", out=0x4024f3a0...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 659 on EOF...
D [10/Sep/2007:21:01:42 +0200] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl")
D [10/Sep/2007:21:01:42 +0200] [CGI] locale="en_gb"...
D [10/Sep/2007:21:01:42 +0200] [CGI] Template file is "/usr/share/cups/templates/trailer.tmp l"...
D [10/Sep/2007:21:01:42 +0200] [CGI] Starting at file position 0...
D [10/Sep/2007:21:01:42 +0200] [CGI] Returning at file position 614 on EOF...
D [10/Sep/2007:21:01:45 +0200] cupsdReadClient: 11 GET /jobs/?op=restart-job&job_id=86&job_p rinter_uri=/printers/Picasso HTTP/1.1
D [10/Sep/2007:21:01:45 +0200] cupsdReadClient: 11 Browser asked for language "en-gb.notused "...
D [10/Sep/2007:21:01:45 +0200] cupsdAuthorize: username="ralf"
D [10/Sep/2007:21:01:45 +0200] CGI /usr/lib/cups/cgi-bin/jobs.cgi started - PID = 13076
I [10/Sep/2007:21:01:45 +0200] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=13076)
D [10/Sep/2007:21:01:45 +0200] cupsdSendCommand: 11 file=16
D [10/Sep/2007:21:01:45 +0200] cupsdAcceptClient: 17 from localhost:631 (IPv4)
D [10/Sep/2007:21:01:45 +0200] cupsdReadClient: 17 POST /jobs HTTP/1.1
D [10/Sep/2007:21:01:45 +0200] cupsdAuthorize: No authentication data provided.
D [10/Sep/2007:21:01:45 +0200] cupsdSendError: 17 code=401 (Unauthorized)
D [10/Sep/2007:21:01:45 +0200] cupsdCloseClient: 17
D [10/Sep/2007:21:01:45 +0200] cupsdAcceptClient: 17 from localhost:631 (IPv4)
D [10/Sep/2007:21:01:45 +0200] cupsdReadClient: 17 POST /jobs HTTP/1.1
D [10/Sep/2007:21:01:45 +0200] cupsdAuthorize: username="ralf"
D [10/Sep/2007:21:01:45 +0200] Restart-Job ipp://localhost/jobs/86
D [10/Sep/2007:21:01:45 +0200] Restart-Job client-error-not-possible: Job #86 is not complet e!
D [10/Sep/2007:21:01:45 +0200] cupsdProcessIPPRequest: 17 status_code=404 (client-error-not- possible)
D [10/Sep/2007:21:01:45 +0200] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl")
D [10/Sep/2007:21:01:45 +0200] [CGI] locale="en_gb"...
D [10/Sep/2007:21:01:45 +0200] [CGI] Template file is "/usr/share/cups/templates/header.tmpl "...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting at file position 0...
D [10/Sep/2007:21:01:45 +0200] [CGI] "{title}" at 205...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting "{refresh_page?" at 374, result=0...
D [10/Sep/2007:21:01:45 +0200] [CGI] Skip first part...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting at file position 374...
D [10/Sep/2007:21:01:45 +0200] [CGI] "{refresh_page}" at 424...
D [10/Sep/2007:21:01:45 +0200] [CGI] Returning at file position 427 on character ':'...
D [10/Sep/2007:21:01:45 +0200] [CGI] Output second part...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting at file position 427...
D [10/Sep/2007:21:01:45 +0200] [CGI] Returning at file position 428 on character '}'...
D [10/Sep/2007:21:01:45 +0200] [CGI] Finished "{refresh_page?", out=0x4024f3a0...
D [10/Sep/2007:21:01:45 +0200] [CGI] "{title}" at 671...
D [10/Sep/2007:21:01:45 +0200] [CGI] "{title}" at 952...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting "{SECTION=admin" at 1411, result=0...
D [10/Sep/2007:21:01:45 +0200] [CGI] Skip first part...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting at file position 1411...
D [10/Sep/2007:21:01:45 +0200] [CGI] Returning at file position 1412 on character ':'...
D [10/Sep/2007:21:01:45 +0200] [CGI] Output second part...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting at file position 1412...
D [10/Sep/2007:21:01:45 +0200] [CGI] Returning at file position 1415 on character '}'...
D [10/Sep/2007:21:01:45 +0200] [CGI] Finished "{SECTION=admin", out=0x4024f3a0...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting "{SECTION=classes" at 1678, result=0...
D [10/Sep/2007:21:01:45 +0200] [CGI] Skip first part...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting at file position 1678...
D [10/Sep/2007:21:01:45 +0200] [CGI] Returning at file position 1679 on character ':'...
D [10/Sep/2007:21:01:45 +0200] [CGI] Output second part...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting at file position 1679...
D [10/Sep/2007:21:01:45 +0200] [CGI] Returning at file position 1682 on character '}'...
D [10/Sep/2007:21:01:45 +0200] [CGI] Finished "{SECTION=classes", out=0x4024f3a0...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting "{SECTION=help" at 1938, result=0...
D [10/Sep/2007:21:01:45 +0200] [CGI] Skip first part...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting at file position 1938...
D [10/Sep/2007:21:01:45 +0200] [CGI] Returning at file position 1939 on character ':'...
D [10/Sep/2007:21:01:45 +0200] [CGI] Output second part...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting at file position 1939...
D [10/Sep/2007:21:01:45 +0200] [CGI] Returning at file position 1942 on character '}'...
D [10/Sep/2007:21:01:45 +0200] [CGI] Finished "{SECTION=help", out=0x4024f3a0...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting "{SECTION=jobs" at 2206, result=1...
D [10/Sep/2007:21:01:45 +0200] [CGI] Output first part...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting at file position 2206...
D [10/Sep/2007:21:01:45 +0200] [CGI] Returning at file position 2207 on character ':'...
D [10/Sep/2007:21:01:45 +0200] [CGI] Skip second part...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting at file position 2207...
D [10/Sep/2007:21:01:45 +0200] [CGI] Returning at file position 2210 on character '}'...
D [10/Sep/2007:21:01:45 +0200] [CGI] Finished "{SECTION=jobs", out=0x4024f3a0...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting "{SECTION=printers" at 2464, result=0...
D [10/Sep/2007:21:01:45 +0200] [CGI] Skip first part...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting at file position 2464...
D [10/Sep/2007:21:01:45 +0200] [CGI] Returning at file position 2465 on character ':'...
D [10/Sep/2007:21:01:45 +0200] [CGI] Output second part...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting at file position 2465...
D [10/Sep/2007:21:01:45 +0200] [CGI] Returning at file position 2468 on character '}'...
D [10/Sep/2007:21:01:45 +0200] [CGI] Finished "{SECTION=printers", out=0x4024f3a0...
D [10/Sep/2007:21:01:45 +0200] [CGI] Returning at file position 2830 on EOF...
D [10/Sep/2007:21:01:45 +0200] [CGI] cgiCopyTemplateLang(tmpl="error.tmpl")
D [10/Sep/2007:21:01:45 +0200] [CGI] locale="en_gb"...
D [10/Sep/2007:21:01:45 +0200] [CGI] Template file is "/usr/share/cups/templates/error.tmpl" ...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting at file position 0...
D [10/Sep/2007:21:01:45 +0200] cupsdCloseClient: 17
D [10/Sep/2007:21:01:45 +0200] PID 13076 (/usr/lib/cups/cgi-bin/jobs.cgi) exited with no err ors.
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting "{?message?" at 13, result=0...
D [10/Sep/2007:21:01:45 +0200] [CGI] Skip first part...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting at file position 13...
D [10/Sep/2007:21:01:45 +0200] [CGI] "{message}" at 22...
D [10/Sep/2007:21:01:45 +0200] [CGI] Returning at file position 23 on character ':'...
D [10/Sep/2007:21:01:45 +0200] [CGI] Output second part...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting at file position 23...
D [10/Sep/2007:21:01:45 +0200] [CGI] Returning at file position 30 on character '}'...
D [10/Sep/2007:21:01:45 +0200] [CGI] Finished "{?message?", out=0x4024f3a0...
D [10/Sep/2007:21:01:45 +0200] [CGI] "{error}" at 55...
D [10/Sep/2007:21:01:45 +0200] [CGI] Returning at file position 69 on EOF...
D [10/Sep/2007:21:01:45 +0200] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl")
D [10/Sep/2007:21:01:45 +0200] [CGI] locale="en_gb"...
D [10/Sep/2007:21:01:45 +0200] [CGI] Template file is "/usr/share/cups/templates/trailer.tmp l"...
D [10/Sep/2007:21:01:45 +0200] [CGI] Starting at file position 0...
D [10/Sep/2007:21:01:45 +0200] [CGI] Returning at file position 614 on EOF...
Echter, als ik met de webinterface een test page probeer te printen, dan krijg ik nog steeds niets, de job blijft "pending," er komt niets uit mijn printertje rollen...

Aurë Entuluva!


  • benoni
  • Registratie: November 2003
  • Niet online
Uhmm... tja... wel vreemd dat de output verdwijnt zonder een foutmelding. Wellicht komt er een melding in een ander bestand in /var/log terecht. Ik weet 't verder ook even niet precies wat er nu nog mis gaat dus ik gooi een paar dingen in de groep die misschien voor de hand liggen maar evengoed een opening kunnen bieden :>

1. Heb je alle relevante pakketten in huis:
apt-get install hpijs hplip libcupsys2 libcupsimage2 libcupsimage2-dev printconf cups-pdf foomatic-db foomatic-db-engine foomatic-db-hpijs


2. Printers opnieuw proberen te configureren:
dpkg-reconfigure printconf

Zou terug moeten zeggen: Configuring HP DeskJet 3550 on usb:/dev/usb/lp0 with hpijs driver as queue "deskjet_3550" of zoiets.

3. Kijken of de gebruiker lp rechten heeft om te schrijven naar de printer (in /dev/usb/lp0 o.i.d.).

  • Feanaro
  • Registratie: Februari 2002
  • Laatst online: 25-11-2025
Gelukt! Die tweede tip bleek zinvol. Het bleek dat ik dat printerconf pakketje niet op m'n systeem had staan. Vreemd dat het zonder eerst wel werkte... Die detecteerde bij de installatie direct mijn printer en deze komt in m'n cups systeem naast m'n oude te staan... Hij print nu een test pagina uit.

Nu moet ik nog zien dat ik die oude printer weg krijg en de nieuwe op default zet... Maar daar krijg ik steeds 403 forbidden errors op...

Aurë Entuluva!

Pagina: 1