Arch Linux

giorgosc61

Member

From: Athens, Greece

Registered: 2005-05-27

Posts: 140

Problem setting up CUPS on HP 3650

Hello.

I have problem with my printer HP 3650.

On my previous installation in ARch Linux everything was working fine.

I had to format and install Arch again. I updated to udev and then installed
pacman -S cups hpijs ghostscript
I have set up cups according to the WIKI but I cannot print neither as root nor as user.
Could it be permissions problem?

Thank you in advance.

---

Powered by Arch Linux

T-Dawg

Forum Fellow

From: Charlotte, NC

Registered: 2005-01-29

Posts: 2,736

Re: Problem setting up CUPS on HP 3650

try setting cups to debug:
http://www.cups.org/articles.php?L198+I0+TFAQ+P1+Q

Dont be afraid to turn it up all the way if need be. It'll just be more thumbing through the logs but, you'll more than likely find the problem.

giorgosc61

Member

From: Athens, Greece

Registered: 2005-05-27

Posts: 140

Re: Problem setting up CUPS on HP 3650

I changed to debug as you told me.

Here some more info:

[root@myhost giorgos]#  ls -l /dev/usb/lp0
crw-rw----  1 root root 180, 0 Jul 20 12:50 /dev/usb/lp0

I have cups in rc.conf and also have usblp in modules in rc.conf

nano /var/log/cups/error_log gives

I [20/Jul/2005:09:07:23 +0000] Listening to 0:631
I [20/Jul/2005:09:07:23 +0000] Loaded configuration file "/etc/cups/cupsd.conf"
I [20/Jul/2005:09:07:23 +0000] Configured for up to 100 clients.
I [20/Jul/2005:09:07:23 +0000] Allowing up to 100 client connections per host.
I [20/Jul/2005:09:07:23 +0000] Full reload is required.
I [20/Jul/2005:09:07:24 +0000] LoadPPDs: Read "/etc/cups/ppds.dat", 567 PPDs...
I [20/Jul/2005:09:07:24 +0000] LoadPPDs: No new or changed PPDs...
I [20/Jul/2005:09:07:24 +0000] Full reload complete.
I [20/Jul/2005:09:38:49 +0000] Scheduler shutting down normally.
I [20/Jul/2005:12:51:12 +0000] Listening to 0:631
I [20/Jul/2005:12:51:12 +0000] Loaded configuration file "/etc/cups/cupsd.conf"
I [20/Jul/2005:12:51:12 +0000] Configured for up to 100 clients.
I [20/Jul/2005:12:51:12 +0000] Allowing up to 100 client connections per host.
I [20/Jul/2005:12:51:12 +0000] Full reload is required.
I [20/Jul/2005:12:51:13 +0000] LoadPPDs: Read "/etc/cups/ppds.dat", 567 PPDs...
I [20/Jul/2005:12:51:13 +0000] LoadPPDs: No new or changed PPDs...
I [20/Jul/2005:12:51:13 +0000] Full reload complete.
I [20/Jul/2005:13:34:20 +0000] Scheduler shutting down normally.
I [20/Jul/2005:13:34:21 +0000] Listening to 0:631
D [20/Jul/2005:13:34:21 +0000] AddLocation: added location '/'
D [20/Jul/2005:13:34:21 +0000] DenyIP: / deny 00000000/00000000
D [20/Jul/2005:13:34:21 +0000] AllowIP: / allow 7f000001/ffffffff
D [20/Jul/2005:13:34:21 +0000] AddLocation: added location '/admin'
D [20/Jul/2005:13:34:21 +0000] DenyIP: /admin deny 00000000/00000000
D [20/Jul/2005:13:34:21 +0000] AllowIP: /admin allow 7f000001/ffffffff
I [20/Jul/2005:13:34:21 +0000] Loaded configuration file "/etc/cups/cupsd.conf"
I [20/Jul/2005:13:34:21 +0000] Configured for up to 100 clients.
I [20/Jul/2005:13:34:21 +0000] Allowing up to 100 client connections per host.
I [20/Jul/2005:13:34:21 +0000] Full reload is required.
D [20/Jul/2005:13:34:21 +0000] LoadAllPrinters: Loading printer HP3650Printer...
D [20/Jul/2005:13:34:21 +0000] LoadDevices: Added device "ipp"...
D [20/Jul/2005:13:34:21 +0000] LoadDevices: Added device "lpd"...
D [20/Jul/2005:13:34:21 +0000] LoadDevices: Added device "usb://3650?serial=HU44L1N29M6B"...
D [20/Jul/2005:13:34:21 +0000] LoadDevices: Added device "usb:/dev/usb/lp1"...
D [20/Jul/2005:13:34:21 +0000] LoadDevices: Added device "usb:/dev/usb/lp2"...
D [20/Jul/2005:13:34:21 +0000] LoadDevices: Added device "usb:/dev/usb/lp3"...
D [20/Jul/2005:13:34:21 +0000] LoadDevices: Added device "usb:/dev/usb/lp4"...
D [20/Jul/2005:13:34:21 +0000] LoadDevices: Added device "usb:/dev/usb/lp5"...
D [20/Jul/2005:13:34:21 +0000] LoadDevices: Added device "usb:/dev/usb/lp6"...
D [20/Jul/2005:13:34:21 +0000] LoadDevices: Added device "usb:/dev/usb/lp7"...
D [20/Jul/2005:13:34:21 +0000] LoadDevices: Added device "usb:/dev/usb/lp8"...
D [20/Jul/2005:13:34:21 +0000] LoadDevices: Added device "usb:/dev/usb/lp9"...
D [20/Jul/2005:13:34:21 +0000] LoadDevices: Added device "usb:/dev/usb/lp10"...
D [20/Jul/2005:13:34:21 +0000] LoadDevices: Added device "usb:/dev/usb/lp11"...
D [20/Jul/2005:13:34:21 +0000] LoadDevices: Added device "usb:/dev/usb/lp12"...
D [20/Jul/2005:13:34:21 +0000] LoadDevices: Added device "usb:/dev/usb/lp13"...
D [20/Jul/2005:13:34:21 +0000] LoadDevices: Added device "usb:/dev/usb/lp14"...
D [20/Jul/2005:13:34:21 +0000] LoadDevices: Added device "usb:/dev/usb/lp15"...
D [20/Jul/2005:13:34:21 +0000] LoadDevices: Added device "http"...
D [20/Jul/2005:13:34:22 +0000] LoadDevices: Added device "scsi"...
D [20/Jul/2005:13:34:22 +0000] LoadDevices: Added device "parallel:/dev/unknown-parallel0"...
D [20/Jul/2005:13:34:22 +0000] LoadDevices: Added device "serial:/dev/ttyS0?baud=115200"...
D [20/Jul/2005:13:34:22 +0000] LoadDevices: Added device "serial:/dev/ttyS1?baud=115200"...
D [20/Jul/2005:13:34:22 +0000] LoadDevices: Added device "serial:/dev/ttyS2?baud=115200"...
D [20/Jul/2005:13:34:22 +0000] LoadDevices: Added device "serial:/dev/ttyS3?baud=115200"...
D [20/Jul/2005:13:34:22 +0000] LoadDevices: Added device "serial:/dev/ttyS4?baud=115200"...
D [20/Jul/2005:13:34:22 +0000] LoadDevices: Added device "serial:/dev/ttyS5?baud=115200"...
D [20/Jul/2005:13:34:22 +0000] LoadDevices: Added device "serial:/dev/ttyS6?baud=115200"...
D [20/Jul/2005:13:34:22 +0000] LoadDevices: Added device "serial:/dev/ttyS7?baud=115200"...
D [20/Jul/2005:13:34:22 +0000] LoadDevices: Added device "socket"...
I [20/Jul/2005:13:34:22 +0000] LoadPPDs: Read "/etc/cups/ppds.dat", 567 PPDs...
I [20/Jul/2005:13:34:22 +0000] LoadPPDs: No new or changed PPDs...
D [20/Jul/2005:13:34:22 +0000] LoadAllJobs: Scanning /var/spool/cups...
D [20/Jul/2005:13:34:22 +0000] LoadAllJobs: Loading attributes for job 1...
I [20/Jul/2005:13:34:22 +0000] Full reload complete.
D [20/Jul/2005:13:34:22 +0000] StartListening: NumListeners=1
D [20/Jul/2005:13:34:22 +0000] StartListening: address=00000000 port=631
D [20/Jul/2005:13:34:22 +0000] ResumeListening: setting input bits...
D [20/Jul/2005:13:37:40 +0000] AcceptClient: 5 from localhost:631.
D [20/Jul/2005:13:37:40 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:40 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:37:40 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:40 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:37:40 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:40 +0000] ProcessIPPRequest: 5 status_code=0
D [20/Jul/2005:13:37:40 +0000] CloseClient: 5
D [20/Jul/2005:13:37:41 +0000] AcceptClient: 5 from localhost:631.
D [20/Jul/2005:13:37:41 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:41 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:37:41 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:41 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:37:41 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:41 +0000] ProcessIPPRequest: 5 status_code=0
D [20/Jul/2005:13:37:41 +0000] CloseClient: 5
D [20/Jul/2005:13:37:41 +0000] AcceptClient: 5 from localhost:631.
D [20/Jul/2005:13:37:41 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:41 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:37:41 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:41 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:37:41 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:41 +0000] ProcessIPPRequest: 5 status_code=0
D [20/Jul/2005:13:37:41 +0000] CloseClient: 5
D [20/Jul/2005:13:37:43 +0000] AcceptClient: 5 from localhost:631.
D [20/Jul/2005:13:37:43 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:43 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:37:43 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:43 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:37:43 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:43 +0000] ProcessIPPRequest: 5 status_code=0
D [20/Jul/2005:13:37:43 +0000] CloseClient: 5
D [20/Jul/2005:13:37:45 +0000] AcceptClient: 5 from localhost:631.
D [20/Jul/2005:13:37:45 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:45 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:37:45 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:45 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:37:45 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:45 +0000] ProcessIPPRequest: 5 status_code=0
D [20/Jul/2005:13:37:45 +0000] CloseClient: 5
D [20/Jul/2005:13:37:45 +0000] AcceptClient: 5 from localhost:631.
D [20/Jul/2005:13:37:45 +0000] ReadClient: 5 POST /printers/HP3650Printer HTTP/1.1
D [20/Jul/2005:13:37:45 +0000] print_job: auto-typing file...
D [20/Jul/2005:13:37:45 +0000] print_job: request file type is application/postscript.
D [20/Jul/2005:13:37:45 +0000] check_quotas: requesting-user-name = 'giorgos'
D [20/Jul/2005:13:37:45 +0000] print_job: requesting-user-name = 'giorgos'
I [20/Jul/2005:13:37:45 +0000] Adding start banner page "none" to job 2.
I [20/Jul/2005:13:37:45 +0000] Adding end banner page "none" to job 2.
I [20/Jul/2005:13:37:45 +0000] Job 2 queued on 'HP3650Printer' by 'giorgos'.
D [20/Jul/2005:13:37:45 +0000] Job 2 hold_until = 0
D [20/Jul/2005:13:37:45 +0000] StartJob(2, 0x8094a10)
D [20/Jul/2005:13:37:45 +0000] StartJob() id = 2, file = 0/1
D [20/Jul/2005:13:37:45 +0000] job-sheets=none,none
D [20/Jul/2005:13:37:45 +0000] banner_page = 0
D [20/Jul/2005:13:37:45 +0000] StartJob: argv = "HP3650Printer","2","giorgos","(stdin)","1","","/var/spool/cups/d00002-001"
D [20/Jul/2005:13:37:45 +0000] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [20/Jul/2005:13:37:45 +0000] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [20/Jul/2005:13:37:45 +0000] StartJob: envp[2]="USER=root"
D [20/Jul/2005:13:37:45 +0000] StartJob: envp[3]="CHARSET=iso-8859-1"
D [20/Jul/2005:13:37:45 +0000] StartJob: envp[4]="LANG=en"
D [20/Jul/2005:13:37:45 +0000] StartJob: envp[5]="PPD=/etc/cups/ppd/HP3650Printer.ppd"
D [20/Jul/2005:13:37:45 +0000] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [20/Jul/2005:13:37:45 +0000] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [20/Jul/2005:13:37:45 +0000] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [20/Jul/2005:13:37:45 +0000] StartJob: envp[9]="CONTENT_TYPE=application/postscript"
D [20/Jul/2005:13:37:45 +0000] StartJob: envp[10]="DEVICE_URI=usb://3650?serial=HU44L1N29M6B"
D [20/Jul/2005:13:37:45 +0000] StartJob: envp[11]="PRINTER=HP3650Printer"
D [20/Jul/2005:13:37:45 +0000] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
D [20/Jul/2005:13:37:45 +0000] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [20/Jul/2005:13:37:45 +0000] StartJob: envp[14]="CUPS_SERVER=localhost"
D [20/Jul/2005:13:37:45 +0000] StartJob: envp[15]="IPP_PORT=631"
D [20/Jul/2005:13:37:45 +0000] StartJob: statusfds = [ 7 8 ]
D [20/Jul/2005:13:37:45 +0000] StartJob: filterfds[1] = [ 9 -1 ]
D [20/Jul/2005:13:37:45 +0000] StartJob: filter = "/usr/lib/cups/filter/pstops"
D [20/Jul/2005:13:37:45 +0000] StartJob: filterfds[0] = [ 10 11 ]
D [20/Jul/2005:13:37:45 +0000] start_process("/usr/lib/cups/filter/pstops", 0xbf9d4520, 0xbf9d3890, 9, 11, 8)
I [20/Jul/2005:13:37:45 +0000] Started filter /usr/lib/cups/filter/pstops (PID 6390) for job 2.
D [20/Jul/2005:13:37:45 +0000] StartJob: filter = "/usr/lib/cups/filter/foomatic-rip"
D [20/Jul/2005:13:37:45 +0000] StartJob: filterfds[1] = [ 9 12 ]
D [20/Jul/2005:13:37:45 +0000] start_process("/usr/lib/cups/filter/foomatic-rip", 0xbf9d4520, 0xbf9d3890, 10, 12, 8)
I [20/Jul/2005:13:37:45 +0000] Started filter /usr/lib/cups/filter/foomatic-rip (PID 6391) for job 2.
D [20/Jul/2005:13:37:45 +0000] StartJob: backend = "/usr/lib/cups/backend/usb"
D [20/Jul/2005:13:37:45 +0000] StartJob: filterfds[0] = [ -1 10 ]
D [20/Jul/2005:13:37:45 +0000] start_process("/usr/lib/cups/backend/usb", 0xbf9d4520, 0xbf9d3890, 9, 10, 8)
I [20/Jul/2005:13:37:45 +0000] Started backend /usr/lib/cups/backend/usb (PID 6392) for job 2.
D [20/Jul/2005:13:37:45 +0000] ProcessIPPRequest: 5 status_code=0
D [20/Jul/2005:13:37:45 +0000] CloseClient: 5
D [20/Jul/2005:13:37:45 +0000] [Job 2] Printer using device file "/dev/usb/lp0"...
D [20/Jul/2005:13:37:45 +0000] [Job 2] LPGETSTATUS returned a port status of 18...
D [20/Jul/2005:13:37:45 +0000] [Job 2] Page = 595x842; 10,36 to 585,833
D [20/Jul/2005:13:37:45 +0000] [Job 2] slowcollate=0, slowduplex=0, sloworder=0
D [20/Jul/2005:13:37:45 +0000] [Job 2] 0 %%BoundingBox: 0 0 612 792
D [20/Jul/2005:13:37:45 +0000] [Job 2] 0 %%Creator: Mozilla PostScript module (rv:1.7.8/2005051208)
D [20/Jul/2005:13:37:45 +0000] [Job 2] 0 %%DocumentData: Clean8Bit
D [20/Jul/2005:13:37:45 +0000] [Job 2] 0 %%DocumentPaperSizes: Letter
D [20/Jul/2005:13:37:45 +0000] [Job 2] 0 %%Orientation: Portrait
D [20/Jul/2005:13:37:45 +0000] [Job 2] 0 %%Pages: (atend)
D [20/Jul/2005:13:37:45 +0000] [Job 2] 0 %%PageOrder: Ascend
D [20/Jul/2005:13:37:45 +0000] [Job 2] 0 %%EndComments
D [20/Jul/2005:13:37:45 +0000] [Job 2] 0 %%BeginProlog
D [20/Jul/2005:13:37:45 +0000] [Job 2] 0 %%EndProlog
D [20/Jul/2005:13:37:45 +0000] [Job 2] 0 %%Page: 1 1
D [20/Jul/2005:13:37:45 +0000] [Job 2] 0 %%Page: 1 1
D [20/Jul/2005:13:37:45 +0000] [Job 2] pw = 575.6, pl = 797.0
D [20/Jul/2005:13:37:45 +0000] [Job 2] PageLeft = 9.7, PageRight = 585.3
D [20/Jul/2005:13:37:45 +0000] [Job 2] PageTop = 833.0, PageBottom = 36.0
D [20/Jul/2005:13:37:45 +0000] [Job 2] PageWidth = 595.0, PageLength = 842.0
D [20/Jul/2005:13:37:45 +0000] [Job 2] 0 %%BeginPageSetup
D [20/Jul/2005:13:37:45 +0000] [Job 2] 0 %%EndPageSetup
D [20/Jul/2005:13:37:45 +0000] [Job 2] perl: warning: Setting locale failed.
D [20/Jul/2005:13:37:45 +0000] [Job 2] perl: warning: Please check that your locale settings:
D [20/Jul/2005:13:37:45 +0000] [Job 2] LANGUAGE = (unset),
D [20/Jul/2005:13:37:45 +0000] [Job 2] LC_ALL = (unset),
D [20/Jul/2005:13:37:45 +0000] [Job 2] LANG = "en"
D [20/Jul/2005:13:37:45 +0000] [Job 2] are supported and installed on your system.
D [20/Jul/2005:13:37:45 +0000] [Job 2] perl: warning: Falling back to the standard locale ("C").
D [20/Jul/2005:13:37:45 +0000] [Job 2] Can't locate strict.pm in @INC (@INC contains: /usr/lib/perl5/5.8.7/i686-linux-thread-multi /usr/lib/perl5/5.8.7 /usr/lib/perl5/site_perl/5.8.7/i686-linux-thread-multi /usr/lib/perl5/site_perl/5.8.7 /usr/lib/perl5/site_perl .) at /usr/lib/cups/filter/foomatic-rip line 4.
D [20/Jul/2005:13:37:45 +0000] [Job 2] BEGIN failed--compilation aborted at /usr/lib/cups/filter/foomatic-rip line 4.
E [20/Jul/2005:13:37:45 +0000] PID 6391 stopped with status 2!
D [20/Jul/2005:13:37:45 +0000] [Job 2] 0 %%Page: 2 2
D [20/Jul/2005:13:37:45 +0000] [Job 2] pw = 575.6, pl = 797.0
D [20/Jul/2005:13:37:45 +0000] [Job 2] PageLeft = 9.7, PageRight = 585.3
D [20/Jul/2005:13:37:45 +0000] [Job 2] PageTop = 833.0, PageBottom = 36.0
D [20/Jul/2005:13:37:45 +0000] [Job 2] PageWidth = 595.0, PageLength = 842.0
D [20/Jul/2005:13:37:45 +0000] [Job 2] 0 %%BeginPageSetup
D [20/Jul/2005:13:37:45 +0000] [Job 2] 0 %%EndPageSetup
D [20/Jul/2005:13:37:45 +0000] [Job 2] 0 %%Trailer
D [20/Jul/2005:13:37:45 +0000] [Job 2] Saw Trailer!
D [20/Jul/2005:13:37:45 +0000] [Job 2] Saw EOF!
D [20/Jul/2005:13:37:46 +0000] UpdateJob: job 2, file 0 is complete.
D [20/Jul/2005:13:37:46 +0000] CancelJob: id = 2
D [20/Jul/2005:13:37:46 +0000] StopJob: id = 2, force = 0
D [20/Jul/2005:13:37:46 +0000] StopJob: printer state is 3
D [20/Jul/2005:13:37:55 +0000] AcceptClient: 5 from localhost:631.
D [20/Jul/2005:13:37:55 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:55 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:37:55 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:55 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:37:55 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:55 +0000] ProcessIPPRequest: 5 status_code=0
D [20/Jul/2005:13:37:55 +0000] CloseClient: 5
D [20/Jul/2005:13:37:56 +0000] AcceptClient: 5 from localhost:631.
D [20/Jul/2005:13:37:56 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:56 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:37:56 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:56 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:37:56 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:37:56 +0000] ProcessIPPRequest: 5 status_code=0
D [20/Jul/2005:13:37:56 +0000] CloseClient: 5
D [20/Jul/2005:13:38:05 +0000] AcceptClient: 5 from localhost:631.
D [20/Jul/2005:13:38:05 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:38:05 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:38:05 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:38:05 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:38:05 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:38:06 +0000] ProcessIPPRequest: 5 status_code=0
D [20/Jul/2005:13:38:06 +0000] CloseClient: 5
D [20/Jul/2005:13:38:09 +0000] AcceptClient: 5 from localhost:631.
D [20/Jul/2005:13:38:09 +0000] CloseClient: 5
D [20/Jul/2005:13:38:09 +0000] AcceptClient: 5 from localhost:631.
D [20/Jul/2005:13:38:09 +0000] ReadClient: 5 POST /printers/ HTTP/1.1
D [20/Jul/2005:13:38:09 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:38:09 +0000] CloseClient: 5
D [20/Jul/2005:13:38:09 +0000] AcceptClient: 5 from localhost:631.
D [20/Jul/2005:13:38:09 +0000] ReadClient: 5 POST /printers/ HTTP/1.1
D [20/Jul/2005:13:38:09 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:38:09 +0000] CloseClient: 5
D [20/Jul/2005:13:38:09 +0000] AcceptClient: 5 from localhost:631.
D [20/Jul/2005:13:38:09 +0000] ReadClient: 5 POST /classes/ HTTP/1.1
D [20/Jul/2005:13:38:09 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:38:09 +0000] CloseClient: 5
D [20/Jul/2005:13:38:09 +0000] AcceptClient: 5 from localhost:631.
D [20/Jul/2005:13:38:09 +0000] ReadClient: 5 POST /printers/ HTTP/1.1
D [20/Jul/2005:13:38:09 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:38:09 +0000] CloseClient: 5
D [20/Jul/2005:13:38:09 +0000] AcceptClient: 5 from localhost:631.
D [20/Jul/2005:13:38:09 +0000] ReadClient: 5 POST /printers/ HTTP/1.1
D [20/Jul/2005:13:38:09 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:38:09 +0000] CloseClient: 5
D [20/Jul/2005:13:38:14 +0000] AcceptClient: 5 from localhost:631.
D [20/Jul/2005:13:38:14 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:38:14 +0000] ProcessIPPRequest: 5 status_code=0
D [20/Jul/2005:13:38:14 +0000] ReadClient: 5 GET /printers/HP3650Printer.ppd HTTP/1.1
D [20/Jul/2005:13:38:14 +0000] SendFile: 5 file=7
D [20/Jul/2005:13:38:15 +0000] AcceptClient: 7 from localhost:631.
D [20/Jul/2005:13:38:15 +0000] ReadClient: 7 POST /printers/HP3650Printer HTTP/1.1
D [20/Jul/2005:13:38:15 +0000] print_job: auto-typing file...
D [20/Jul/2005:13:38:15 +0000] print_job: request file type is application/postscript.
D [20/Jul/2005:13:38:15 +0000] check_quotas: requesting-user-name = 'giorgos'
D [20/Jul/2005:13:38:15 +0000] print_job: requesting-user-name = 'giorgos'
D [20/Jul/2005:13:38:15 +0000] Adding default job-sheets values "none,none"...
I [20/Jul/2005:13:38:15 +0000] Adding start banner page "none" to job 3.
I [20/Jul/2005:13:38:15 +0000] Adding end banner page "none" to job 3.
I [20/Jul/2005:13:38:15 +0000] Job 3 queued on 'HP3650Printer' by 'giorgos'.
D [20/Jul/2005:13:38:15 +0000] Job 3 hold_until = 0
D [20/Jul/2005:13:38:15 +0000] StartJob(3, 0x8094a10)
D [20/Jul/2005:13:38:15 +0000] StartJob() id = 3, file = 0/1
D [20/Jul/2005:13:38:15 +0000] job-sheets=none,none
D [20/Jul/2005:13:38:15 +0000] banner_page = 0
D [20/Jul/2005:13:38:15 +0000] StartJob: argv = "HP3650Printer","3","giorgos","<STDIN>","1","multiple-document-handling=separate-documents-collated-copies","/var/spool/cups/d00003-001"
D [20/Jul/2005:13:38:15 +0000] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [20/Jul/2005:13:38:15 +0000] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [20/Jul/2005:13:38:15 +0000] StartJob: envp[2]="USER=root"
D [20/Jul/2005:13:38:15 +0000] StartJob: envp[3]="CHARSET=iso-8859-1"
D [20/Jul/2005:13:38:15 +0000] StartJob: envp[4]="LANG=en"
D [20/Jul/2005:13:38:15 +0000] StartJob: envp[5]="PPD=/etc/cups/ppd/HP3650Printer.ppd"
D [20/Jul/2005:13:38:15 +0000] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [20/Jul/2005:13:38:15 +0000] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [20/Jul/2005:13:38:15 +0000] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [20/Jul/2005:13:38:15 +0000] StartJob: envp[9]="CONTENT_TYPE=application/postscript"
D [20/Jul/2005:13:38:15 +0000] StartJob: envp[10]="DEVICE_URI=usb://3650?serial=HU44L1N29M6B"
D [20/Jul/2005:13:38:15 +0000] StartJob: envp[11]="PRINTER=HP3650Printer"
D [20/Jul/2005:13:38:15 +0000] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
D [20/Jul/2005:13:38:15 +0000] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [20/Jul/2005:13:38:15 +0000] StartJob: envp[14]="CUPS_SERVER=localhost"
D [20/Jul/2005:13:38:15 +0000] StartJob: envp[15]="IPP_PORT=631"
D [20/Jul/2005:13:38:15 +0000] StartJob: statusfds = [ 8 9 ]
D [20/Jul/2005:13:38:15 +0000] StartJob: filterfds[1] = [ 10 -1 ]
D [20/Jul/2005:13:38:15 +0000] StartJob: filter = "/usr/lib/cups/filter/pstops"
D [20/Jul/2005:13:38:15 +0000] StartJob: filterfds[0] = [ 11 12 ]
D [20/Jul/2005:13:38:15 +0000] start_process("/usr/lib/cups/filter/pstops", 0xbf9d4520, 0xbf9d3890, 10, 12, 9)
I [20/Jul/2005:13:38:15 +0000] Started filter /usr/lib/cups/filter/pstops (PID 6396) for job 3.
D [20/Jul/2005:13:38:15 +0000] StartJob: filter = "/usr/lib/cups/filter/foomatic-rip"
D [20/Jul/2005:13:38:15 +0000] StartJob: filterfds[1] = [ 10 13 ]
D [20/Jul/2005:13:38:15 +0000] start_process("/usr/lib/cups/filter/foomatic-rip", 0xbf9d4520, 0xbf9d3890, 11, 13, 9)
I [20/Jul/2005:13:38:15 +0000] Started filter /usr/lib/cups/filter/foomatic-rip (PID 6397) for job 3.
D [20/Jul/2005:13:38:15 +0000] StartJob: backend = "/usr/lib/cups/backend/usb"
D [20/Jul/2005:13:38:15 +0000] StartJob: filterfds[0] = [ -1 11 ]
D [20/Jul/2005:13:38:15 +0000] start_process("/usr/lib/cups/backend/usb", 0xbf9d4520, 0xbf9d3890, 10, 11, 9)
I [20/Jul/2005:13:38:15 +0000] Started backend /usr/lib/cups/backend/usb (PID 6398) for job 3.
D [20/Jul/2005:13:38:15 +0000] ProcessIPPRequest: 7 status_code=0
D [20/Jul/2005:13:38:15 +0000] CloseClient: 7
D [20/Jul/2005:13:38:15 +0000] [Job 3] Printer using device file "/dev/usb/lp0"...
D [20/Jul/2005:13:38:15 +0000] [Job 3] LPGETSTATUS returned a port status of 18...
D [20/Jul/2005:13:38:15 +0000] [Job 3] Page = 595x842; 10,36 to 585,833
D [20/Jul/2005:13:38:15 +0000] [Job 3] slowcollate=0, slowduplex=0, sloworder=0
D [20/Jul/2005:13:38:15 +0000] [Job 3] 0 %%BoundingBox: 0 0 612 792
D [20/Jul/2005:13:38:15 +0000] [Job 3] 0 %%Creator: Mozilla PostScript module (rv:1.7.8/2005051208)
D [20/Jul/2005:13:38:15 +0000] [Job 3] 0 %%DocumentData: Clean8Bit
D [20/Jul/2005:13:38:15 +0000] [Job 3] 0 %%DocumentPaperSizes: Letter
D [20/Jul/2005:13:38:15 +0000] [Job 3] 0 %%Orientation: Portrait
D [20/Jul/2005:13:38:15 +0000] [Job 3] 0 %%Pages: (atend)
D [20/Jul/2005:13:38:15 +0000] [Job 3] 0 %%PageOrder: Ascend
D [20/Jul/2005:13:38:15 +0000] [Job 3] 0 %%EndComments
D [20/Jul/2005:13:38:15 +0000] [Job 3] 0 %%BeginProlog
D [20/Jul/2005:13:38:15 +0000] [Job 3] 0 %%EndProlog
D [20/Jul/2005:13:38:15 +0000] [Job 3] 0 %%Page: 1 1
D [20/Jul/2005:13:38:15 +0000] [Job 3] 0 %%Page: 1 1
D [20/Jul/2005:13:38:15 +0000] [Job 3] pw = 575.6, pl = 797.0
D [20/Jul/2005:13:38:15 +0000] [Job 3] PageLeft = 9.7, PageRight = 585.3
D [20/Jul/2005:13:38:15 +0000] [Job 3] PageTop = 833.0, PageBottom = 36.0
D [20/Jul/2005:13:38:15 +0000] [Job 3] PageWidth = 595.0, PageLength = 842.0
D [20/Jul/2005:13:38:15 +0000] [Job 3] 0 %%BeginPageSetup
D [20/Jul/2005:13:38:15 +0000] [Job 3] 0 %%EndPageSetup
D [20/Jul/2005:13:38:15 +0000] [Job 3] perl: warning: Setting locale failed.
D [20/Jul/2005:13:38:15 +0000] [Job 3] perl: warning: Please check that your locale settings:
D [20/Jul/2005:13:38:15 +0000] [Job 3] LANGUAGE = (unset),
D [20/Jul/2005:13:38:15 +0000] [Job 3] LC_ALL = (unset),
D [20/Jul/2005:13:38:15 +0000] [Job 3] LANG = "en"
D [20/Jul/2005:13:38:15 +0000] [Job 3] are supported and installed on your system.
D [20/Jul/2005:13:38:15 +0000] [Job 3] perl: warning: Falling back to the standard locale ("C").
D [20/Jul/2005:13:38:15 +0000] [Job 3] Can't locate strict.pm in @INC (@INC contains: /usr/lib/perl5/5.8.7/i686-linux-thread-multi /usr/lib/perl5/5.8.7 /usr/lib/perl5/site_perl/5.8.7/i686-linux-thread-multi /usr/lib/perl5/site_perl/5.8.7 /usr/lib/perl5/site_perl .) at /usr/lib/cups/filter/foomatic-rip line 4.
D [20/Jul/2005:13:38:15 +0000] [Job 3] BEGIN failed--compilation aborted at /usr/lib/cups/filter/foomatic-rip line 4.
E [20/Jul/2005:13:38:15 +0000] PID 6397 stopped with status 2!
D [20/Jul/2005:13:38:15 +0000] [Job 3] 0 %%Page: 2 2
D [20/Jul/2005:13:38:15 +0000] [Job 3] pw = 575.6, pl = 797.0
D [20/Jul/2005:13:38:15 +0000] [Job 3] PageLeft = 9.7, PageRight = 585.3
D [20/Jul/2005:13:38:15 +0000] [Job 3] PageTop = 833.0, PageBottom = 36.0
D [20/Jul/2005:13:38:15 +0000] [Job 3] PageWidth = 595.0, PageLength = 842.0
D [20/Jul/2005:13:38:15 +0000] [Job 3] 0 %%BeginPageSetup
D [20/Jul/2005:13:38:15 +0000] [Job 3] 0 %%EndPageSetup
D [20/Jul/2005:13:38:15 +0000] [Job 3] 0 %%Trailer
D [20/Jul/2005:13:38:15 +0000] [Job 3] Saw Trailer!
D [20/Jul/2005:13:38:15 +0000] [Job 3] Saw EOF!
D [20/Jul/2005:13:38:15 +0000] UpdateJob: job 3, file 0 is complete.
D [20/Jul/2005:13:38:15 +0000] CancelJob: id = 3
D [20/Jul/2005:13:38:15 +0000] StopJob: id = 3, force = 0
D [20/Jul/2005:13:38:15 +0000] StopJob: printer state is 3
D [20/Jul/2005:13:38:15 +0000] CloseClient: 5
D [20/Jul/2005:13:38:16 +0000] AcceptClient: 5 from localhost:631.
D [20/Jul/2005:13:38:16 +0000] ReadClient: 5 POST /printers/ HTTP/1.1
D [20/Jul/2005:13:38:16 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:38:16 +0000] CloseClient: 5
D [20/Jul/2005:13:38:16 +0000] AcceptClient: 5 from localhost:631.
D [20/Jul/2005:13:38:16 +0000] ReadClient: 5 POST /classes/ HTTP/1.1
D [20/Jul/2005:13:38:16 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:38:16 +0000] CloseClient: 5
D [20/Jul/2005:13:38:16 +0000] AcceptClient: 5 from localhost:631.
D [20/Jul/2005:13:38:16 +0000] ReadClient: 5 POST /printers/ HTTP/1.1
D [20/Jul/2005:13:38:16 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:38:16 +0000] CloseClient: 5
D [20/Jul/2005:13:38:16 +0000] AcceptClient: 5 from localhost:631.
D [20/Jul/2005:13:38:16 +0000] ReadClient: 5 POST / HTTP/1.1
D [20/Jul/2005:13:38:16 +0000] ProcessIPPRequest: 5 status_code=1
D [20/Jul/2005:13:38:16 +0000] CloseClient: 5

---

Powered by Arch Linux

Mr Green

Forum Fellow

From: U.K.

Registered: 2003-12-21

Posts: 5,896

Website

Re: Problem setting up CUPS on HP 3650

It sounds silly but go into CUPS & see if printer is accepting jobs /////

maybe then test printer .....

It may be you have not got your ppd file loaded

HTH

---

Mr Green

giorgosc61

Member

From: Athens, Greece

Registered: 2005-05-27

Posts: 140

Re: Problem setting up CUPS on HP 3650

Thank you for your reply.

In mozilla -> http://myhost:631/ -> Manage Printers -> Print Test Page

And it says

Error:

    client-error-not-possible

I did that during my user session.

---

Powered by Arch Linux