Re: [hpoj-devel] Difficulty with PSC 1210

[bbales <bb...@co...>]

>
> So "lp -s -dpsc1210 kfc2.jpg" did not cause any error message on your
> console?

NO, none.
>
> Can you post the output of the following commands:
>
> lpstat -o
> lpstat -p
> lpstat -v
> lpinfo -v
> ps auxwww | grep hp
>
> Can you also set the "LogLevel" to "debug" in /etc/cups/cupsd.conf,
> restart CUPS, send the job again, and then observe what gets loggen in
> /var/log/cups/error_log? Have also the toolbox (/usr/hplip/toolbox or
> /usr/local/hplip/toolbox) running and observe the messages on the status
> tab.
>
>     Till


I think I got it all below.  It appears the /var/log/cups/error_log continues 
to write all the time.  In order to make it usable, I typed in lp -s dpsc1210 
kfc2.jpg and waited until the clock turned over to 18:09 and hit "enter" .  
Then I copied only one minute of error_log.

The hplip toobox errors out as I have version 3.5 of PyQt and toolbox requires 
version 3.8.  Actually, it locks up after complaining about the version of 
PyQt.

[betty@betty 1204]$ su
Password:
[root@betty 1204]#
[root@betty 1204]# cd /home/betty
[root@betty betty]#
[root@betty betty]# /usr/bin/lpstat -o
psc1210-6               betty          2415616   Tue 14 Dec 2004 02:14:02 PM 
CST
psc1210-7               betty           193536   Tue 14 Dec 2004 02:16:48 PM 
CST
psc1210-8               betty           137216   Tue 14 Dec 2004 02:18:23 PM 
CST
psc1210-9               betty           930816   Tue 14 Dec 2004 02:27:03 PM 
CST
psc1210-10              betty           581632   Tue 14 Dec 2004 03:01:19 PM 
CST
psc1210-11              betty           581632   Tue 14 Dec 2004 03:02:33 PM 
CST
psc1210-12              betty           581632   Tue 14 Dec 2004 03:04:08 PM 
CST
psc1210-13              betty           581632   Tue 14 Dec 2004 03:04:56 PM 
CST
psc1210-14              betty           930816   Tue 14 Dec 2004 03:08:53 PM 
CST
psc1210-15              betty           581632   Tue 14 Dec 2004 03:10:40 PM 
CST
psc1210-16              root            249856   Tue 14 Dec 2004 03:51:31 PM 
CST
psc1210-17              root            249856   Tue 14 Dec 2004 04:13:55 PM 
CST
psc1210-18              betty            23552   Tue 14 Dec 2004 04:42:35 PM 
CST
psc1210-19              betty            23552   Tue 14 Dec 2004 04:54:26 PM 
CST
[root@betty betty]#
[root@betty betty]#
[root@betty betty]# /usr/bin/lpstat -p
printer psc1210 now printing psc1210-6.  enabled since Jan 01 00:00
[root@betty betty]#
[root@betty betty]#
[root@betty betty]# /usr/bin/lpstat -v
device for psc1210: hp:/usb/psc_1200_series?serial=CN42QG301Y5H
[root@betty betty]#
[root@betty betty]#
[root@betty betty]# /usr/sbin/lpinfo -v
network socket
direct hp:/usb/psc_1200_series?serial=CN42QG301Y5H
network http
network ipp
network lpd
direct parallel:/dev/lp0
direct ptal
direct scsi
serial serial:/dev/ttyS0?baud=115200
serial serial:/dev/ttyS1?baud=115200
serial serial:/dev/ttyS2?baud=115200
serial serial:/dev/ttyS3?baud=115200
serial serial:/dev/ttyS4?baud=115200
serial serial:/dev/ttyS5?baud=115200
serial serial:/dev/ttyS6?baud=115200
serial serial:/dev/ttyS7?baud=115200
serial serial:/dev/ttyS8?baud=115200
serial serial:/dev/ttyS9?baud=115200
serial serial:/dev/ttyS10?baud=115200
serial serial:/dev/ttyS11?baud=115200
serial serial:/dev/ttyS12?baud=115200
serial serial:/dev/ttyS13?baud=115200
serial serial:/dev/ttyS14?baud=115200
serial serial:/dev/ttyS15?baud=115200
serial serial:/dev/ttyS16?baud=115200
serial serial:/dev/ttyS17?baud=115200
serial serial:/dev/ttyS18?baud=115200
serial serial:/dev/ttyS19?baud=115200
serial serial:/dev/ttyS20?baud=115200
serial serial:/dev/ttyS21?baud=115200
serial serial:/dev/ttyS22?baud=115200
serial serial:/dev/ttyS23?baud=115200
serial serial:/dev/ttyS24?baud=115200
serial serial:/dev/ttyS25?baud=115200
serial serial:/dev/ttyS26?baud=115200
serial serial:/dev/ttyS27?baud=115200
serial serial:/dev/ttyS28?baud=115200
serial serial:/dev/ttyS29?baud=115200
serial serial:/dev/ttyS30?baud=115200
serial serial:/dev/ttyS31?baud=115200
direct usb://Unknown?serial=CN42QG301Y5H
direct usb:/dev/usb/lp1
direct usb:/dev/usb/lp2
direct usb:/dev/usb/lp3
direct usb:/dev/usb/lp4
direct usb:/dev/usb/lp5
direct usb:/dev/usb/lp6
direct usb:/dev/usb/lp7
direct usb:/dev/usb/lp8
direct usb:/dev/usb/lp9
direct usb:/dev/usb/lp10
direct usb:/dev/usb/lp11
direct usb:/dev/usb/lp12
direct usb:/dev/usb/lp13
direct usb:/dev/usb/lp14
direct usb:/dev/usb/lp15
[root@betty betty]#
[root@betty betty]#
[root@betty betty]# ps auxwww |grep hp
root      3574  0.0  0.2 29000  532 ?        S    11:42   0:00 ./hpiod
root      3581  0.0  1.1  8644 2108 ?        S    11:42   0:00 python 
./hpssd.py
root      4188  0.0  0.2  1424  556 ?        S    14:14   0:00 
hp:/usb/psc_1200_series?serial=CN42QG301Y5H 6 betty (stdin) 1  
/var/spool/cups/d00006-001
root      4622  0.0  0.3  3572  628 pts/1    S    17:55   0:00 grep hp
[root@betty betty]#

The next is from /var/log/cups/error_log, beginning a bit before 18:09.


D [14/Dec/2004:18:08:40 -0600] CloseClient() 7
D [14/Dec/2004:18:08:46 -0600] AcceptClient() 7 from localhost:631.
D [14/Dec/2004:18:08:46 -0600] ReadClient() 7 POST / HTTP/1.1
D [14/Dec/2004:18:08:46 -0600] ProcessIPPRequest: 7 status_code=1
D [14/Dec/2004:18:08:46 -0600] CloseClient() 7
D [14/Dec/2004:18:08:51 -0600] AcceptClient() 7 from localhost:631.
D [14/Dec/2004:18:08:51 -0600] ReadClient() 7 POST / HTTP/1.1
D [14/Dec/2004:18:08:51 -0600] ProcessIPPRequest: 7 status_code=1
D [14/Dec/2004:18:08:51 -0600] CloseClient() 7
D [14/Dec/2004:18:08:56 -0600] AcceptClient() 7 from localhost:631.
D [14/Dec/2004:18:08:56 -0600] ReadClient() 7 POST / HTTP/1.1
D [14/Dec/2004:18:08:56 -0600] ProcessIPPRequest: 7 status_code=1
D [14/Dec/2004:18:08:56 -0600] CloseClient() 7
D [14/Dec/2004:18:09:00 -0600] AcceptClient() 7 from localhost:631.
D [14/Dec/2004:18:09:00 -0600] ReadClient() 7 POST / HTTP/1.1
D [14/Dec/2004:18:09:00 -0600] ProcessIPPRequest: 7 status_code=0
D [14/Dec/2004:18:09:00 -0600] AcceptClient() 8 from localhost:631.
D [14/Dec/2004:18:09:00 -0600] CloseClient() 7
D [14/Dec/2004:18:09:00 -0600] ReadClient() 8 POST / HTTP/1.1
D [14/Dec/2004:18:09:00 -0600] ProcessIPPRequest: 8 status_code=0
D [14/Dec/2004:18:09:00 -0600] AcceptClient() 7 from localhost:631.
D [14/Dec/2004:18:09:00 -0600] CloseClient() 8
D [14/Dec/2004:18:09:00 -0600] ReadClient() 7 POST / HTTP/1.1
D [14/Dec/2004:18:09:00 -0600] ProcessIPPRequest: 7 status_code=0
D [14/Dec/2004:18:09:00 -0600] ReadClient() 7 POST /printers/psc1210 HTTP/1.1
D [14/Dec/2004:18:09:00 -0600] print_job: auto-typing file...
D [14/Dec/2004:18:09:00 -0600] print_job: request file type is image/jpeg.
D [14/Dec/2004:18:09:00 -0600] check_quotas: requesting-user-name = 'betty'
D [14/Dec/2004:18:09:00 -0600] print_job: requesting-user-name = 'betty'
I [14/Dec/2004:18:09:00 -0600] Job 21 queued on 'psc1210' by 'betty'.
D [14/Dec/2004:18:09:00 -0600] Job 21 hold_until = 0
D [14/Dec/2004:18:09:00 -0600] ProcessIPPRequest: 7 status_code=0
D [14/Dec/2004:18:09:01 -0600] AcceptClient() 8 from localhost:631.
D [14/Dec/2004:18:09:01 -0600] ReadClient() 8 POST / HTTP/1.1
D [14/Dec/2004:18:09:01 -0600] ProcessIPPRequest: 8 status_code=1
D [14/Dec/2004:18:09:01 -0600] CloseClient() 8
D [14/Dec/2004:18:09:01 -0600] CloseClient() 7
D [14/Dec/2004:18:09:02 -0600] ReadClient() 5 POST / HTTP/1.1
D [14/Dec/2004:18:09:02 -0600] ProcessIPPRequest: 5 status_code=1
D [14/Dec/2004:18:09:02 -0600] ReadClient() 5 POST / HTTP/1.1
D [14/Dec/2004:18:09:02 -0600] ProcessIPPRequest: 5 status_code=1
D [14/Dec/2004:18:09:06 -0600] AcceptClient() 7 from localhost:631.
D [14/Dec/2004:18:09:06 -0600] ReadClient() 7 POST / HTTP/1.1
D [14/Dec/2004:18:09:06 -0600] ProcessIPPRequest: 7 status_code=1
D [14/Dec/2004:18:09:06 -0600] CloseClient() 7
D [14/Dec/2004:18:09:11 -0600] AcceptClient() 7 from localhost:631.
D [14/Dec/2004:18:09:11 -0600] ReadClient() 7 POST / HTTP/1.1
D [14/Dec/2004:18:09:11 -0600] ProcessIPPRequest: 7 status_code=1
D [14/Dec/2004:18:09:11 -0600] CloseClient() 7
D [14/Dec/2004:18:09:16 -0600] AcceptClient() 7 from localhost:631.
D [14/Dec/2004:18:09:16 -0600] ReadClient() 7 POST / HTTP/1.1
D [14/Dec/2004:18:09:16 -0600] ProcessIPPRequest: 7 status_code=1
D [14/Dec/2004:18:09:16 -0600] CloseClient() 7
D [14/Dec/2004:18:09:21 -0600] AcceptClient() 7 from localhost:631.
D [14/Dec/2004:18:09:21 -0600] ReadClient() 7 POST / HTTP/1.1
D [14/Dec/2004:18:09:21 -0600] ProcessIPPRequest: 7 status_code=1
D [14/Dec/2004:18:09:21 -0600] CloseClient() 7
D [14/Dec/2004:18:09:26 -0600] AcceptClient() 7 from localhost:631.
D [14/Dec/2004:18:09:26 -0600] ReadClient() 7 POST / HTTP/1.1
D [14/Dec/2004:18:09:26 -0600] ProcessIPPRequest: 7 status_code=1
D [14/Dec/2004:18:09:26 -0600] CloseClient() 7
D [14/Dec/2004:18:09:31 -0600] AcceptClient() 7 from localhost:631.
D [14/Dec/2004:18:09:31 -0600] ReadClient() 7 POST / HTTP/1.1
D [14/Dec/2004:18:09:31 -0600] ProcessIPPRequest: 7 status_code=1
D [14/Dec/2004:18:09:31 -0600] CloseClient() 7
D [14/Dec/2004:18:09:32 -0600] ReadClient() 5 POST / HTTP/1.1
D [14/Dec/2004:18:09:32 -0600] ProcessIPPRequest: 5 status_code=1
D [14/Dec/2004:18:09:32 -0600] ReadClient() 5 POST / HTTP/1.1
D [14/Dec/2004:18:09:32 -0600] ProcessIPPRequest: 5 status_code=1
D [14/Dec/2004:18:09:36 -0600] AcceptClient() 7 from localhost:631.
D [14/Dec/2004:18:09:36 -0600] ReadClient() 7 POST / HTTP/1.1
D [14/Dec/2004:18:09:36 -0600] ProcessIPPRequest: 7 status_code=1
D [14/Dec/2004:18:09:36 -0600] CloseClient() 7
D [14/Dec/2004:18:09:41 -0600] AcceptClient() 7 from localhost:631.
D [14/Dec/2004:18:09:41 -0600] ReadClient() 7 POST / HTTP/1.1
D [14/Dec/2004:18:09:41 -0600] ProcessIPPRequest: 7 status_code=1
D [14/Dec/2004:18:09:41 -0600] CloseClient() 7
D [14/Dec/2004:18:09:46 -0600] AcceptClient() 7 from localhost:631.
D [14/Dec/2004:18:09:46 -0600] ReadClient() 7 POST / HTTP/1.1
D [14/Dec/2004:18:09:46 -0600] ProcessIPPRequest: 7 status_code=1
D [14/Dec/2004:18:09:46 -0600] CloseClient() 7
D [14/Dec/2004:18:09:51 -0600] AcceptClient() 7 from localhost:631.
D [14/Dec/2004:18:09:51 -0600] ReadClient() 7 POST / HTTP/1.1
D [14/Dec/2004:18:09:51 -0600] ProcessIPPRequest: 7 status_code=1
D [14/Dec/2004:18:09:51 -0600] CloseClient() 7
D [14/Dec/2004:18:09:56 -0600] AcceptClient() 7 from localhost:631.
D [14/Dec/2004:18:09:56 -0600] ReadClient() 7 POST / HTTP/1.1
D [14/Dec/2004:18:09:56 -0600] ProcessIPPRequest: 7 status_code=1
D [14/Dec/2004:18:09:56 -0600] CloseClient() 7
D [14/Dec/2004:18:10:01 -0600] AcceptClient() 7 from localhost:631.
D [14/Dec/2004:18:10:01 -0600] ReadClient() 7 POST / HTTP/1.1
D [14/Dec/2004:18:10:01 -0600] ProcessIPPRequest: 7 status_code=1
D [14/Dec/2004:18:10:01 -0600] CloseClient() 7