LinuxQuestions.org
Share your knowledge at the LQ Wiki.
Go Back   LinuxQuestions.org > Forums > Linux Forums > Linux - Newbie
User Name
Password
Linux - Newbie This Linux forum is for members that are new to Linux.
Just starting out and have a question? If it is not in the man pages or the how-to's this is the place!

Notices


Reply
  Search this Thread
Old 06-05-2012, 10:33 AM   #1
linuxandtsm
Member
 
Registered: May 2011
Posts: 194

Rep: Reputation: Disabled
Printing problem


Hi all,

OS: CentOS 4,
Problem: documents are not getting printed and no error messages but when i click the print status icon, status is complete.

Not sure where to check the logs ?
Thanks in advance!
 
Old 06-05-2012, 03:33 PM   #2
linuxandtsm
Member
 
Registered: May 2011
Posts: 194

Original Poster
Rep: Reputation: Disabled
Hi all,

Here are the log files. If this helps

Code:
# service cups status
cupsd (pid 473) is running...
Code:
#more /var/log/cups/error_log
I [05/Jun/2012:15:08:16 -0400] Scheduler shutting down normally.
n/2012:15:02:44 -0400] Loaded configuration file "/etc/cups/cupsd.conf"
I [05/Jun/2012:15:02:44 -0400] Configured for up to 100 clients.
I [05/Jun/2012:15:02:44 -0400] Allowing up to 100 client connections per host.
I [05/Jun/2012:15:02:44 -0400] Full reload is required.
I [05/Jun/2012:15:02:44 -0400] LoadPPDs: Read "/etc/cups/ppds.dat", 4227 PPDs...
I [05/Jun/2012:15:02:44 -0400] LoadPPDs: No new or changed PPDs...
I [05/Jun/2012:15:02:44 -0400] Full reload complete.
E [05/Jun/2012:15:02:44 -0400] StartListening: Unable to bind socket for address 7f000001:631 - Address already in use.
D [05/Jun/2012:15:08:16 -0400] AddLocation: added location '/'
D [05/Jun/2012:15:08:16 -0400] DenyIP: / deny 00000000/00000000
D [05/Jun/2012:15:08:16 -0400] AllowIP: / allow 7f000001/ffffffff
D [05/Jun/2012:15:08:16 -0400] AddLocation: added location '/admin'
D [05/Jun/2012:15:08:16 -0400] DenyIP: /admin deny 00000000/00000000
D [05/Jun/2012:15:08:16 -0400] AllowIP: /admin allow 7f000001/ffffffff
D [05/Jun/2012:15:08:16 -0400] AddLocation: added location '/printers/usbmpr2045'
D [05/Jun/2012:15:08:16 -0400] DenyIP: /printers/usbmpr2045 deny 00000000/00000000
D [05/Jun/2012:15:08:16 -0400] AllowIP: /printers/usbmpr2045 allow 7f000001/ffffffff
D [05/Jun/2012:15:08:16 -0400] AddLocation: added location '/printers/usbmpr2051'
D [05/Jun/2012:15:08:16 -0400] DenyIP: /printers/usbmpr2051 deny 00000000/00000000
D [05/Jun/2012:15:08:16 -0400] AllowIP: /printers/usbmpr2051 allow 7f000001/ffffffff
D [05/Jun/2012:15:08:16 -0400] AddLocation: added location '/printers/ng_unifyR'
D [05/Jun/2012:15:08:16 -0400] DenyIP: /printers/ng_unifyR deny 00000000/00000000
D [05/Jun/2012:15:08:16 -0400] AllowIP: /printers/ng_unifyR allow 7f000001/ffffffff
D [05/Jun/2012:15:08:16 -0400] AddLocation: added location 'CUPS_INTERNAL_BROWSE_ACL'
D [05/Jun/2012:15:08:16 -0400] AllowHost: CUPS_INTERNAL_BROWSE_ACL allow @LOCAL
I [05/Jun/2012:15:08:16 -0400] Listening to 7f000001:631
I [05/Jun/2012:15:08:16 -0400] Loaded configuration file "/etc/cups/cupsd.conf"
I [05/Jun/2012:15:08:16 -0400] Configured for up to 100 clients.
I [05/Jun/2012:15:08:16 -0400] Allowing up to 100 client connections per host.
I [05/Jun/2012:15:08:16 -0400] Full reload is required.
D [05/Jun/2012:15:08:16 -0400] LoadAllPrinters: Loading printer usbmpr2045...
D [05/Jun/2012:15:08:16 -0400] LoadAllPrinters: Loading printer usbmpr2051...
D [05/Jun/2012:15:08:16 -0400] LoadAllPrinters: Loading printer ng_unifyR...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "http"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "socket"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "usb:/dev/usb/lp0"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "usb:/dev/usb/lp1"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "usb:/dev/usb/lp2"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "usb:/dev/usb/lp3"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "usb:/dev/usb/lp4"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "usb:/dev/usb/lp5"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "usb:/dev/usb/lp6"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "usb:/dev/usb/lp7"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "usb:/dev/usb/lp8"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "usb:/dev/usb/lp9"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "usb:/dev/usb/lp10"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "usb:/dev/usb/lp11"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "usb:/dev/usb/lp12"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "usb:/dev/usb/lp13"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "usb:/dev/usb/lp14"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "usb:/dev/usb/lp15"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "ptal"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "hal"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "lpd"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "epson:/dev/lp0"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "scsi"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "ipp"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "parallel:/dev/lp0"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "serial:/dev/ttyS0?baud=115200"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "serial:/dev/ttyS1?baud=115200"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "serial:/dev/ttyS2?baud=115200"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "serial:/dev/ttyS3?baud=115200"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "serial:/dev/ttyS4?baud=115200"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "serial:/dev/ttyS5?baud=115200"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "serial:/dev/ttyS6?baud=115200"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "serial:/dev/ttyS6?baud=115200"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "serial:/dev/ttyS7?baud=115200"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "canon:/dev/lp0"...
D [05/Jun/2012:15:08:16 -0400] LoadDevices: Added device "smb"...
I [05/Jun/2012:15:08:16 -0400] LoadPPDs: Read "/etc/cups/ppds.dat", 4227 PPDs...
I [05/Jun/2012:15:08:16 -0400] LoadPPDs: No new or changed PPDs...
D [05/Jun/2012:15:08:16 -0400] LoadAllJobs: Scanning /var/spool/cups...
D [05/Jun/2012:15:08:16 -0400] LoadAllJobs: Loading attributes for job 14337...
D [05/Jun/2012:15:08:16 -0400] LoadAllJobs: Loading attributes for job 14395...
D [05/Jun/2012:15:08:16 -0400] LoadAllJobs: Loading attributes for job 14112...
D [05/Jun/2012:15:08:16 -0400] LoadAllJobs: Loading attributes for job 14412...

D [05/Jun/2012:15:08:16 -0400] LoadAllJobs: Loading attributes for job 14004...
D [05/Jun/2012:15:08:16 -0400] SetJobHoldUntil(14464, "no-hold")
D [05/Jun/2012:15:08:16 -0400] SetJobHoldUntil(14464, "no-hold")
D [05/Jun/2012:15:08:16 -0400] SetJobHoldUntil: hold_until = 0
D [05/Jun/2012:15:08:16 -0400] LoadAllJobs: Loading attributes for job 14426...
D [05/Jun/2012:15:08:16 -0400] LoadAllJobs: Loading attributes for job 13988...
D [05/Jun/2012:15:08:16 -0400] LoadAllJobs: Loading attributes for job 14206...
D [05/Jun/2012:15:08:16 -0400] LoadAllJobs: Loading attributes for job 14174...
D [05/Jun/2012:15:08:16 -0400] LoadAllJobs: Loading attributes for job 14469...
D [05/Jun/2012:15:08:16 -0400] LoadAllJobs: Loading attributes for job 14032...
D [05/Jun/2012:15:08:16 -0400] LoadAllJobs: Loading attributes for job 14150...
D [05/Jun/2012:15:08:16 -0400] LoadAllJobs: Loading attributes for job 14377...
D [05/Jun/2012:15:08:16 -0400] LoadAllJobs: Loading attributes for job 14440...
D [05/Jun/2012:15:08:16 -0400] LoadAllJobs: Loading attributes for job 14231...
D [05/Jun/2012:15:08:16 -0400] LoadAllJobs: Loading attributes for job 14185...
D [05/Jun/2012:15:08:16 -0400] LoadAllJobs: Loading attributes for job 14123...
I [05/Jun/2012:15:08:16 -0400] Full reload complete.
D [05/Jun/2012:15:08:16 -0400] StartListening: NumListeners=1
D [05/Jun/2012:15:08:16 -0400] StartListening: address=7f000001 port=631
D [05/Jun/2012:15:08:16 -0400] ResumeListening: setting input bits...
D [05/Jun/2012:15:08:20 -0400] AcceptClient: 6 from localhost:631.
D [05/Jun/2012:15:08:20 -0400] ReadClient: 6 POST / HTTP/1.1
D [05/Jun/2012:15:08:20 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:08:26 -0400] ReadClient: 6 POST / HTTP/1.1
D [05/Jun/2012:15:08:26 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:08:32 -0400] ReadClient: 6 POST / HTTP/1.1
D [05/Jun/2012:15:08:32 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:08:38 -0400] ReadClient: 6 POST / HTTP/1.1
D [05/Jun/2012:15:08:38 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:08:44 -0400] ReadClient: 6 POST / HTTP/1.1
D [05/Jun/2012:15:08:44 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:08:50 -0400] ReadClient: 6 POST / HTTP/1.1
D [05/Jun/2012:15:08:50 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:08:56 -0400] ReadClient: 6 POST / HTTP/1.1
D [05/Jun/2012:15:08:56 -0400] ProcessIPPRequest: 6 status_code=0

D [05/Jun/2012:15:14:17 -0400] ProcessIPPRequest: 8 status_code=1
D [05/Jun/2012:15:14:17 -0400] ReadClient: 8 POST / HTTP/1.1
D [05/Jun/2012:15:14:17 -0400] ProcessIPPRequest: 8 status_code=0
D [05/Jun/2012:15:14:17 -0400] CloseClient: 8
D [05/Jun/2012:15:14:17 -0400] AcceptClient: 8 from localhost:631.
D [05/Jun/2012:15:14:17 -0400] ReadClient: 8 POST /printers/usbmpr2045 HTTP/1.1
D [05/Jun/2012:15:14:17 -0400] print_job: auto-typing file...
D [05/Jun/2012:15:14:17 -0400] print_job: request file type is application/postscript.
D [05/Jun/2012:15:14:17 -0400] check_quotas: requesting-user-name = 'chemist'
D [05/Jun/2012:15:14:17 -0400] print_job: requesting-user-name = 'chemist'
I [05/Jun/2012:15:14:17 -0400] Adding start banner page "none" to job 14471.
I [05/Jun/2012:15:14:17 -0400] Adding end banner page "none" to job 14471.
I [05/Jun/2012:15:14:17 -0400] Job 14471 queued on 'usbmpr2045' by 'chemist'.
D [05/Jun/2012:15:14:17 -0400] Job 14471 hold_until = 0
D [05/Jun/2012:15:14:17 -0400] StartJob(14471, 0x552abf95e0)
D [05/Jun/2012:15:14:17 -0400] StartJob() id = 14471, file = 0/1
D [05/Jun/2012:15:14:17 -0400] StartJob: Sending job to queue tagged as raw...
D [05/Jun/2012:15:14:17 -0400] job-sheets=none,none
D [05/Jun/2012:15:14:17 -0400] banner_page = 0
D [05/Jun/2012:15:14:17 -0400] StartJob: argv = "usbmpr2045","14471","chemist","tmp_plot","1","cpi=12 lpi=7 page-bottom=86 page-left=57 page-right=57 page-top=72 scaling=100 wra
p nobanner","/var/spool/cups/d14471-001"
D [05/Jun/2012:15:14:17 -0400] StartJob: envp[0]="PATH=/usr/lib64/cups/filter:/bin:/usr/bin"
D [05/Jun/2012:15:14:17 -0400] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [05/Jun/2012:15:14:17 -0400] StartJob: envp[2]="USER=root"
D [05/Jun/2012:15:14:17 -0400] StartJob: envp[3]="CHARSET=utf-8"
D [05/Jun/2012:15:14:17 -0400] StartJob: envp[4]="LANG=en_SG"
D [05/Jun/2012:15:14:17 -0400] StartJob: envp[5]="PPD=/etc/cups/ppd/usbmpr2045.ppd"
D [05/Jun/2012:15:14:17 -0400] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [05/Jun/2012:15:14:17 -0400] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [05/Jun/2012:15:14:17 -0400] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [05/Jun/2012:15:14:17 -0400] StartJob: envp[9]="CONTENT_TYPE=application/postscript"
D [05/Jun/2012:15:14:17 -0400] StartJob: envp[10]="DEVICE_URI=lpd://usbmps0001/usbmpr2045"
D [05/Jun/2012:15:14:17 -0400] StartJob: envp[10]="DEVICE_URI=lpd://usbmps0001/usbmpr2045"
D [05/Jun/2012:15:14:17 -0400] StartJob: envp[11]="PRINTER=usbmpr2045"
D [05/Jun/2012:15:14:17 -0400] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
D [05/Jun/2012:15:14:17 -0400] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [05/Jun/2012:15:14:17 -0400] StartJob: envp[14]="CUPS_SERVER=localhost"
D [05/Jun/2012:15:14:17 -0400] StartJob: envp[15]="IPP_PORT=631"
D [05/Jun/2012:15:14:17 -0400] StartJob: statusfds = [ 9 10 ]
D [05/Jun/2012:15:14:17 -0400] StartJob: filterfds[1] = [ 11 -1 ]
D [05/Jun/2012:15:14:17 -0400] StartJob: backend = "/usr/lib64/cups/backend/lpd"
D [05/Jun/2012:15:14:17 -0400] StartJob: filterfds[0] = [ -1 12 ]
D [05/Jun/2012:15:14:17 -0400] start_process("/usr/lib64/cups/backend/lpd", 0x7fbffee800, 0x7fbffed9e0, 11, 12, 10)
I [05/Jun/2012:15:14:17 -0400] Started backend /usr/lib64/cups/backend/lpd (PID 1555) for job 14471.
D [05/Jun/2012:15:14:17 -0400] ProcessIPPRequest: 8 status_code=0
D [05/Jun/2012:15:14:17 -0400] CloseClient: 8
D [05/Jun/2012:15:14:17 -0400] ReadClient: 6 POST /printers/usbmpr2045 HTTP/1.1
D [05/Jun/2012:15:14:18 -0400] AcceptClient: 8 from localhost:631.
D [05/Jun/2012:15:14:18 -0400] [Job 14471] Connected on ports 515 (local 1023)...
D [05/Jun/2012:15:14:18 -0400] [Job 14471] lpd_command 02 usbmpr2045
D [05/Jun/2012:15:14:18 -0400] [Job 14471] Sending command string (12 bytes)...
D [05/Jun/2012:15:14:18 -0400] [Job 14471] Reading command status...
D [05/Jun/2012:15:14:18 -0400] [Job 14471] lpd_command returning 0
D [05/Jun/2012:15:14:18 -0400] [Job 14471] Control file is:
D [05/Jun/2012:15:14:18 -0400] [Job 14471] Hbloch2
D [05/Jun/2012:15:14:18 -0400] [Job 14471] Pchemist
D [05/Jun/2012:15:14:18 -0400] [Job 14471] Jtmp_plot
D [05/Jun/2012:15:14:18 -0400] [Job 14471] ldfA555bloch2
D [05/Jun/2012:15:14:18 -0400] [Job 14471] UdfA555bloch2
D [05/Jun/2012:15:14:18 -0400] [Job 14471] Ntmp_plot
D [05/Jun/2012:15:14:18 -0400] [Job 14471] lpd_command 02 65 cfA555bloch2
D [05/Jun/2012:15:14:18 -0400] [Job 14471] Sending command string (17 bytes)...
D [05/Jun/2012:15:14:18 -0400] [Job 14471] Reading command status...
D [05/Jun/2012:15:14:18 -0400] [Job 14471] lpd_command returning 0
D [05/Jun/2012:15:14:18 -0400] [Job 14471] lpd_command 03 22654 dfA555bloch2
D [05/Jun/2012:15:14:18 -0400] [Job 14471] Sending command string (20 bytes)...
D [05/Jun/2012:15:14:18 -0400] [Job 14471] Reading command status...
D [05/Jun/2012:15:14:18 -0400] [Job 14471] lpd_command returning 0
D [05/Jun/2012:15:14:18 -0400] ReadClient: 8 POST / HTTP/1.1
D [05/Jun/2012:15:14:18 -0400] ProcessIPPRequest: 8 status_code=1
D [05/Jun/2012:15:14:18 -0400] UpdateJob: job 14471, file 0 is complete.
D [05/Jun/2012:15:14:18 -0400] CancelJob: id = 14471
D [05/Jun/2012:15:14:18 -0400] StopJob: id = 14471, force = 0
D [05/Jun/2012:15:14:18 -0400] StopJob: printer state is 3
D [05/Jun/2012:15:14:18 -0400] ReadClient: 8 POST / HTTP/1.1
D [05/Jun/2012:15:14:18 -0400] ProcessIPPRequest: 8 status_code=1
D [05/Jun/2012:15:14:18 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:14:18 -0400] ReadClient: 6 POST /printers/usbmpr2045 HTTP/1.1
D [05/Jun/2012:15:14:18 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:14:18 -0400] ReadClient: 8 POST / HTTP/1.1
D [05/Jun/2012:15:14:18 -0400] ProcessIPPRequest: 8 status_code=0
D [05/Jun/2012:15:14:18 -0400] AcceptClient: 9 from localhost:631.
D [05/Jun/2012:15:14:18 -0400] CloseClient: 8
D [05/Jun/2012:15:14:18 -0400] CloseClient: 8
D [05/Jun/2012:15:14:18 -0400] ReadClient: 9 POST /printers/ng_unifyR HTTP/1.1
D [05/Jun/2012:15:14:18 -0400] print_job: auto-typing file...
D [05/Jun/2012:15:14:18 -0400] print_job: request file type is application/postscript.
D [05/Jun/2012:15:14:18 -0400] CancelJob: id = 13972
D [05/Jun/2012:15:14:18 -0400] check_quotas: requesting-user-name = 'chemist'
D [05/Jun/2012:15:14:18 -0400] print_job: requesting-user-name = 'chemist'
I [05/Jun/2012:15:14:18 -0400] Adding start banner page "none" to job 14472.
I [05/Jun/2012:15:14:18 -0400] Adding end banner page "none" to job 14472.
I [05/Jun/2012:15:14:18 -0400] Job 14472 queued on 'ng_unifyR' by 'chemist'.
D [05/Jun/2012:15:14:18 -0400] Job 14472 hold_until = 0
D [05/Jun/2012:15:14:18 -0400] StartJob(14472, 0x552ac07070)
D [05/Jun/2012:15:14:18 -0400] StartJob() id = 14472, file = 0/1
D [05/Jun/2012:15:14:18 -0400] StartJob: Sending job to queue tagged as raw...
D [05/Jun/2012:15:14:18 -0400] job-sheets=none,none
D [05/Jun/2012:15:14:18 -0400] banner_page = 0
D [05/Jun/2012:15:14:18 -0400] StartJob: argv = "ng_unifyR","14472","chemist","tmp_plot","1","nobanner","/var/spool/cups/d14472-001"
D [05/Jun/2012:15:14:18 -0400] StartJob: envp[0]="PATH=/usr/lib64/cups/filter:/bin:/usr/bin"
D [05/Jun/2012:15:14:18 -0400] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [05/Jun/2012:15:14:18 -0400] StartJob: envp[2]="USER=root"
D [05/Jun/2012:15:14:18 -0400] StartJob: envp[3]="CHARSET=utf-8"
D [05/Jun/2012:15:14:18 -0400] StartJob: envp[4]="LANG=en_SG"
D [05/Jun/2012:15:14:18 -0400] StartJob: envp[5]="PPD=/etc/cups/ppd/ng_unifyR.ppd"
D [05/Jun/2012:15:14:18 -0400] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [05/Jun/2012:15:14:18 -0400] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [05/Jun/2012:15:14:18 -0400] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [05/Jun/2012:15:14:18 -0400] StartJob: envp[9]="CONTENT_TYPE=application/postscript"
D [05/Jun/2012:15:14:18 -0400] StartJob: envp[10]="DEVICE_URI=lpd://usbmap0001/ng_unifyR"
D [05/Jun/2012:15:14:18 -0400] StartJob: envp[11]="PRINTER=ng_unifyR"
D [05/Jun/2012:15:14:18 -0400] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
D [05/Jun/2012:15:14:18 -0400] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [05/Jun/2012:15:14:18 -0400] StartJob: envp[14]="CUPS_SERVER=localhost"
D [05/Jun/2012:15:14:18 -0400] StartJob: envp[15]="IPP_PORT=631"
D [05/Jun/2012:15:14:18 -0400] StartJob: statusfds = [ 8 11 ]
D [05/Jun/2012:15:14:18 -0400] StartJob: filterfds[1] = [ 12 -1 ]
D [05/Jun/2012:15:14:18 -0400] StartJob: backend = "/usr/lib64/cups/backend/lpd"
D [05/Jun/2012:15:14:18 -0400] StartJob: filterfds[0] = [ -1 13 ]
D [05/Jun/2012:15:14:18 -0400] start_process("/usr/lib64/cups/backend/lpd", 0x7fbffee800, 0x7fbffed9e0, 12, 13, 11)
I [05/Jun/2012:15:14:18 -0400] Started backend /usr/lib64/cups/backend/lpd (PID 1560) for job 14472.
D [05/Jun/2012:15:14:18 -0400] ProcessIPPRequest: 9 status_code=0
D [05/Jun/2012:15:14:18 -0400] ReadClient: 6 POST /printers/ng_unifyR HTTP/1.1
D [05/Jun/2012:15:14:18 -0400] CloseClient: 9
D [05/Jun/2012:15:14:18 -0400] [Job 14472] Connected on ports 515 (local 1022)...
D [05/Jun/2012:15:14:18 -0400] [Job 14472] lpd_command 02 ng_unifyR
D [05/Jun/2012:15:14:18 -0400] [Job 14472] Sending command string (11 bytes)...
D [05/Jun/2012:15:14:18 -0400] [Job 14472] Reading command status...
D [05/Jun/2012:15:14:18 -0400] [Job 14472] lpd_command returning 0
D [05/Jun/2012:15:14:18 -0400] [Job 14472] Control file is:
D [05/Jun/2012:15:14:18 -0400] [Job 14472] Hbloch2
D [05/Jun/2012:15:14:18 -0400] [Job 14472] Pchemist
D [05/Jun/2012:15:14:18 -0400] [Job 14472] Jtmp_plot
D [05/Jun/2012:15:14:18 -0400] [Job 14472] Jtmp_plot
D [05/Jun/2012:15:14:18 -0400] [Job 14472] ldfA560bloch2
D [05/Jun/2012:15:14:18 -0400] [Job 14472] UdfA560bloch2
D [05/Jun/2012:15:14:18 -0400] [Job 14472] Ntmp_plot
D [05/Jun/2012:15:14:18 -0400] [Job 14472] lpd_command 02 65 cfA560bloch2
D [05/Jun/2012:15:14:18 -0400] [Job 14472] Sending command string (17 bytes)...
D [05/Jun/2012:15:14:18 -0400] [Job 14472] Reading command status...
D [05/Jun/2012:15:14:18 -0400] [Job 14472] lpd_command returning 0
D [05/Jun/2012:15:14:18 -0400] [Job 14472] lpd_command 03 22654 dfA560bloch2
D [05/Jun/2012:15:14:18 -0400] [Job 14472] Sending command string (20 bytes)...
D [05/Jun/2012:15:14:18 -0400] [Job 14472] Reading command status...
D [05/Jun/2012:15:14:18 -0400] [Job 14472] lpd_command returning 0
D [05/Jun/2012:15:14:18 -0400] UpdateJob: job 14472, file 0 is complete.
D [05/Jun/2012:15:14:18 -0400] CancelJob: id = 14472
D [05/Jun/2012:15:14:18 -0400] StopJob: id = 14472, force = 0
D [05/Jun/2012:15:14:18 -0400] StopJob: printer state is 3
D [05/Jun/2012:15:14:18 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:14:21 -0400] ReadClient: 6 POST / HTTP/1.1
D [05/Jun/2012:15:14:21 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:14:23 -0400] ReadClient: 6 POST /printers/ng_unifyR HTTP/1.1
D [05/Jun/2012:15:14:23 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:27:39 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:27:45 -0400] ReadClient: 6 POST / HTTP/1.1
D [05/Jun/2012:15:27:45 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:27:51 -0400] ReadClient: 6 POST / HTTP/1.1
D [05/Jun/2012:15:27:51 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:27:57 -0400] ReadClient: 6 POST / HTTP/1.1
D [05/Jun/2012:15:27:57 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:28:03 -0400] ReadClient: 6 POST / HTTP/1.1
D [05/Jun/2012:15:28:03 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:28:09 -0400] ReadClient: 6 POST / HTTP/1.1
D [05/Jun/2012:15:28:09 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:28:15 -0400] ReadClient: 6 POST / HTTP/1.1
D [05/Jun/2012:15:28:15 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:28:21 -0400] ReadClient: 6 POST / HTTP/1.1
D [05/Jun/2012:15:28:21 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:28:27 -0400] ReadClient: 6 POST / HTTP/1.1
D [05/Jun/2012:15:28:27 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:28:33 -0400] ReadClient: 6 POST / HTTP/1.1
D [05/Jun/2012:15:28:33 -0400] ProcessIPPRequest: 6 status_code=0
D [05/Jun/2012:15:28:39 -0400] ReadClient: 6 POST / HTTP/1.1
D [05/Jun/2012:15:28:39 -0400] ProcessIPPRequest: 6 status_code=0
and access log
Code:
localhost - - [05/Jun/2012:15:14:17 -0400] "POST / HTTP/1.1" 200 135
localhost - - [05/Jun/2012:15:14:17 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:14:17 -0400] "POST /printers/usbmpr2045 HTTP/1.1" 200 23041
localhost - - [05/Jun/2012:15:14:18 -0400] "POST / HTTP/1.1" 200 135
localhost - - [05/Jun/2012:15:14:18 -0400] "POST / HTTP/1.1" 200 135
localhost - - [05/Jun/2012:15:14:17 -0400] "POST /printers/usbmpr2045 HTTP/1.1" 200 145
localhost - - [05/Jun/2012:15:14:18 -0400] "POST /printers/usbmpr2045 HTTP/1.1" 200 145
localhost - - [05/Jun/2012:15:14:18 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:14:18 -0400] "POST /printers/ng_unifyR HTTP/1.1" 200 23455
localhost - - [05/Jun/2012:15:14:18 -0400] "POST /printers/ng_unifyR HTTP/1.1" 200 145
localhost - - [05/Jun/2012:15:14:21 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:14:23 -0400] "POST /printers/ng_unifyR HTTP/1.1" 200 145
localhost - - [05/Jun/2012:15:14:27 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:14:33 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:14:39 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:14:45 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:14:51 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:14:57 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:15:03 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:28:57 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:29:03 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:29:09 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:29:15 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:29:21 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:29:27 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:29:33 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:29:39 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:29:45 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:29:51 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:29:57 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:30:03 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:30:09 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:30:15 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:30:21 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:30:27 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:30:33 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:30:39 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:30:45 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:30:51 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:30:57 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:31:03 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:31:09 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:31:15 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:31:21 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:31:27 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:31:33 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:31:39 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:31:45 -0400] "POST / HTTP/1.1" 200 75
localhost - - [05/Jun/2012:15:31:51 -0400] "POST / HTTP/1.1" 200 75
 
Old 06-05-2012, 03:59 PM   #3
linuxandtsm
Member
 
Registered: May 2011
Posts: 194

Original Poster
Rep: Reputation: Disabled
Here is more info...the job is sitting in the print queue and not getting printed.
Is there any way to push this or any workaround this probelm ?

Code:
# lpq -P <printer_name>
<printer_name> is ready
Rank    Owner   Job     File(s)                         Total Size
1st       bob   14464   plot                            27648 bytes
 
Old 06-05-2012, 06:03 PM   #4
michaelk
Moderator
 
Registered: Aug 2002
Posts: 26,521

Rep: Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224
The printer queue may or may not be enabled. You can use the console command cupsenable to start the printer as well as release held jobs.
 
Old 06-06-2012, 11:07 AM   #5
linuxandtsm
Member
 
Registered: May 2011
Posts: 194

Original Poster
Rep: Reputation: Disabled
Thank you michaelk,

Tried "cupsenable" but ended up with no success.
I tried to print two text files as "root" with "lp " command but no print.
I even tried to restart "cups" daemon and then "cupsenable" and same result.
I am attaching the error log file after trying above steps. Could this help to determine the issue
Thanks in advance!
Attached Files
File Type: txt error_log.txt (45.9 KB, 12 views)
 
Old 06-06-2012, 05:05 PM   #6
linuxandtsm
Member
 
Registered: May 2011
Posts: 194

Original Poster
Rep: Reputation: Disabled
Hi all,

some more info...there are about 502 jobs sitting in the /var/spool/cups

is this a problem ....what exactly /var/spool/cups holds ? already printed jobs ? or not-printed jobs ? or everything ?

Code:
# ls -ltrh /var/spool/cups/ |wc -l
502
 
Old 06-06-2012, 05:58 PM   #7
michaelk
Moderator
 
Registered: Aug 2002
Posts: 26,521

Rep: Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224
Nope, its normal
http://www.cups.org/documentation.php/spec-design.html

Did printing ever work correctly?
If it did then have you made any recent changes?
 
Old 06-07-2012, 09:45 AM   #8
linuxandtsm
Member
 
Registered: May 2011
Posts: 194

Original Poster
Rep: Reputation: Disabled
Hi michaelk,

Yes, it used to work and there are no recent changes made.
I just tried to print one more time and below is the error log. Is there any thing amiss in the log ?
and what is the entry in first column says in the log ?
Code:
D [07/Jun/2012:09:02:19 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:02:25 -0400] ReadClient: 6 POST / HTTP/1.1
D [07/Jun/2012:09:02:25 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:02:31 -0400] ReadClient: 6 POST / HTTP/1.1
D [07/Jun/2012:09:02:31 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:02:37 -0400] ReadClient: 6 POST / HTTP/1.1
D [07/Jun/2012:09:02:37 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:02:43 -0400] ReadClient: 6 POST / HTTP/1.1
D [07/Jun/2012:09:02:43 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:02:49 -0400] ReadClient: 6 POST / HTTP/1.1
D [07/Jun/2012:09:02:49 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:02:55 -0400] ReadClient: 6 POST / HTTP/1.1
D [07/Jun/2012:09:02:55 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:03:01 -0400] ReadClient: 6 POST / HTTP/1.1
D [07/Jun/2012:09:03:01 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:03:07 -0400] ReadClient: 6 POST / HTTP/1.1
D [07/Jun/2012:09:03:07 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:03:13 -0400] ReadClient: 6 POST / HTTP/1.1
D [07/Jun/2012:09:03:13 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:03:16 -0400] AcceptClient: 8 from localhost:631.
D [07/Jun/2012:09:03:16 -0400] ReadClient: 8 POST / HTTP/1.1
D [07/Jun/2012:09:03:16 -0400] ProcessIPPRequest: 8 status_code=1
D [07/Jun/2012:09:03:16 -0400] ReadClient: 8 POST / HTTP/1.1
D [07/Jun/2012:09:03:16 -0400] ProcessIPPRequest: 8 status_code=1
D [07/Jun/2012:09:03:16 -0400] ReadClient: 8 POST / HTTP/1.1
D [07/Jun/2012:09:03:16 -0400] ProcessIPPRequest: 8 status_code=0
D [07/Jun/2012:09:03:16 -0400] AcceptClient: 9 from localhost:631.
D [07/Jun/2012:09:03:16 -0400] CloseClient: 8
D [07/Jun/2012:09:03:16 -0400] ReadClient: 9 POST /printers/usbmpr2045 HTTP/1.1
D [07/Jun/2012:09:03:16 -0400] print_job: auto-typing file...
D [07/Jun/2012:09:03:16 -0400] print_job: request file type is application/postscript.
D [07/Jun/2012:09:03:16 -0400] check_quotas: requesting-user-name = 'chemist'
D [07/Jun/2012:09:03:16 -0400] print_job: requesting-user-name = 'chemist'
I [07/Jun/2012:09:03:16 -0400] Adding start banner page "none" to job 14495.
I [07/Jun/2012:09:03:16 -0400] Adding end banner page "none" to job 14495.
I [07/Jun/2012:09:03:16 -0400] Job 14495 queued on 'usbmpr2045' by 'chemist'.
D [07/Jun/2012:09:03:16 -0400] Job 14495 hold_until = 0
D [07/Jun/2012:09:03:16 -0400] StartJob(14495, 0x552abf7ce0)
D [07/Jun/2012:09:03:16 -0400] StartJob() id = 14495, file = 0/1
D [07/Jun/2012:09:03:16 -0400] StartJob: Sending job to queue tagged as raw...
D [07/Jun/2012:09:03:16 -0400] job-sheets=none,none
D [07/Jun/2012:09:03:16 -0400] banner_page = 0
D [07/Jun/2012:09:03:16 -0400] StartJob: argv = "usbmpr2045","14495","chemist","tmp_plot","1","cpi=12 lpi=7 page-bottom=86 page-left=57 page-right=57 page-top=72 scaling=100 wrap nobanner","/var/spool/cups/d14495-001"
D [07/Jun/2012:09:03:16 -0400] StartJob: envp[0]="PATH=/usr/lib64/cups/filter:/bin:/usr/bin"
D [07/Jun/2012:09:03:16 -0400] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [07/Jun/2012:09:03:16 -0400] StartJob: envp[2]="USER=root"
D [07/Jun/2012:09:03:16 -0400] StartJob: envp[3]="CHARSET=utf-8"
D [07/Jun/2012:09:03:16 -0400] StartJob: envp[4]="LANG=en_SG"
D [07/Jun/2012:09:03:16 -0400] StartJob: envp[5]="PPD=/etc/cups/ppd/usbmpr2045.ppd"
D [07/Jun/2012:09:03:16 -0400] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [07/Jun/2012:09:03:16 -0400] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [07/Jun/2012:09:03:16 -0400] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [07/Jun/2012:09:03:16 -0400] StartJob: envp[9]="CONTENT_TYPE=application/postscript"
D [07/Jun/2012:09:03:16 -0400] StartJob: envp[10]="DEVICE_URI=lpd://usbmps0001/usbmpr2045"
D [07/Jun/2012:09:03:16 -0400] StartJob: envp[11]="PRINTER=usbmpr2045"
D [07/Jun/2012:09:03:16 -0400] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
D [07/Jun/2012:09:03:16 -0400] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [07/Jun/2012:09:03:16 -0400] StartJob: envp[14]="CUPS_SERVER=localhost"
D [07/Jun/2012:09:03:16 -0400] StartJob: envp[15]="IPP_PORT=631"
D [07/Jun/2012:09:03:16 -0400] StartJob: statusfds = [ 8 10 ]
D [07/Jun/2012:09:03:16 -0400] StartJob: filterfds[1] = [ 11 -1 ]
D [07/Jun/2012:09:03:16 -0400] StartJob: backend = "/usr/lib64/cups/backend/lpd"
D [07/Jun/2012:09:03:16 -0400] StartJob: filterfds[0] = [ -1 12 ]
D [07/Jun/2012:09:03:16 -0400] start_process("/usr/lib64/cups/backend/lpd", 0x7fbffee800, 0x7fbffed9e0, 11, 12, 10)
I [07/Jun/2012:09:03:16 -0400] Started backend /usr/lib64/cups/backend/lpd (PID 31238) for job 14495.
D [07/Jun/2012:09:03:16 -0400] ProcessIPPRequest: 9 status_code=0
D [07/Jun/2012:09:03:16 -0400] CloseClient: 9
D [07/Jun/2012:09:03:16 -0400] ReadClient: 6 POST /printers/usbmpr2045 HTTP/1.1
D [07/Jun/2012:09:03:17 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:03:17 -0400] AcceptClient: 9 from localhost:631.
D [07/Jun/2012:09:03:17 -0400] [Job 14495] Connected on ports 515 (local 1023)...
D [07/Jun/2012:09:03:17 -0400] [Job 14495] lpd_command 02 usbmpr2045
D [07/Jun/2012:09:03:17 -0400] [Job 14495] Sending command string (12 bytes)...
D [07/Jun/2012:09:03:17 -0400] [Job 14495] Reading command status...
D [07/Jun/2012:09:03:17 -0400] [Job 14495] lpd_command returning 0
D [07/Jun/2012:09:03:17 -0400] [Job 14495] Control file is:
D [07/Jun/2012:09:03:17 -0400] [Job 14495] Hbloch2
D [07/Jun/2012:09:03:17 -0400] [Job 14495] Pchemist
D [07/Jun/2012:09:03:17 -0400] [Job 14495] Jtmp_plot
D [07/Jun/2012:09:03:17 -0400] [Job 14495] ldfA238bloch2
D [07/Jun/2012:09:03:17 -0400] [Job 14495] UdfA238bloch2
D [07/Jun/2012:09:03:17 -0400] [Job 14495] Ntmp_plot
D [07/Jun/2012:09:03:17 -0400] [Job 14495] lpd_command 02 65 cfA238bloch2
D [07/Jun/2012:09:03:17 -0400] [Job 14495] Sending command string (17 bytes)...
D [07/Jun/2012:09:03:17 -0400] [Job 14495] Reading command status...
D [07/Jun/2012:09:03:17 -0400] [Job 14495] lpd_command returning 0
D [07/Jun/2012:09:03:17 -0400] [Job 14495] lpd_command 03 67984 dfA238bloch2
D [07/Jun/2012:09:03:17 -0400] [Job 14495] Sending command string (20 bytes)...
D [07/Jun/2012:09:03:17 -0400] [Job 14495] Reading command status...
D [07/Jun/2012:09:03:17 -0400] [Job 14495] lpd_command returning 0
D [07/Jun/2012:09:03:17 -0400] ReadClient: 9 POST / HTTP/1.1
D [07/Jun/2012:09:03:17 -0400] ProcessIPPRequest: 9 status_code=1
D [07/Jun/2012:09:03:17 -0400] ReadClient: 9 POST / HTTP/1.1
D [07/Jun/2012:09:03:17 -0400] ReadClient: 6 POST /printers/usbmpr2045 HTTP/1.1
D [07/Jun/2012:09:03:17 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:03:17 -0400] ProcessIPPRequest: 9 status_code=1
D [07/Jun/2012:09:03:17 -0400] UpdateJob: job 14495, file 0 is complete.
D [07/Jun/2012:09:03:17 -0400] CancelJob: id = 14495
D [07/Jun/2012:09:03:17 -0400] StopJob: id = 14495, force = 0
D [07/Jun/2012:09:03:17 -0400] StopJob: printer state is 3
D [07/Jun/2012:09:03:17 -0400] ReadClient: 9 POST / HTTP/1.1
D [07/Jun/2012:09:03:17 -0400] ProcessIPPRequest: 9 status_code=0
D [07/Jun/2012:09:03:17 -0400] AcceptClient: 8 from localhost:631.
D [07/Jun/2012:09:03:17 -0400] CloseClient: 9
D [07/Jun/2012:09:03:17 -0400] ReadClient: 8 POST /printers/ng_unifyR HTTP/1.1
D [07/Jun/2012:09:03:17 -0400] print_job: auto-typing file...
D [07/Jun/2012:09:03:17 -0400] print_job: request file type is application/postscript.
D [07/Jun/2012:09:03:17 -0400] CancelJob: id = 13995
D [07/Jun/2012:09:03:17 -0400] check_quotas: requesting-user-name = 'chemist'
D [07/Jun/2012:09:03:17 -0400] print_job: requesting-user-name = 'chemist'
I [07/Jun/2012:09:03:17 -0400] Adding start banner page "none" to job 14496.
I [07/Jun/2012:09:03:17 -0400] Adding end banner page "none" to job 14496.
I [07/Jun/2012:09:03:17 -0400] Job 14496 queued on 'ng_unifyR' by 'chemist'.
D [07/Jun/2012:09:03:17 -0400] Job 14496 hold_until = 0
D [07/Jun/2012:09:03:17 -0400] StartJob(14496, 0x552abf9a80)
D [07/Jun/2012:09:03:17 -0400] StartJob() id = 14496, file = 0/1
D [07/Jun/2012:09:03:17 -0400] StartJob: Sending job to queue tagged as raw...
D [07/Jun/2012:09:03:17 -0400] job-sheets=none,none
D [07/Jun/2012:09:03:17 -0400] banner_page = 0
D [07/Jun/2012:09:03:17 -0400] StartJob: argv = "ng_unifyR","14496","chemist","tmp_plot","1","nobanner","/var/spool/cups/d14496-001"
D [07/Jun/2012:09:03:17 -0400] StartJob: envp[0]="PATH=/usr/lib64/cups/filter:/bin:/usr/bin"
D [07/Jun/2012:09:03:17 -0400] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [07/Jun/2012:09:03:17 -0400] StartJob: envp[2]="USER=root"
D [07/Jun/2012:09:03:17 -0400] StartJob: envp[3]="CHARSET=utf-8"
D [07/Jun/2012:09:03:17 -0400] StartJob: envp[4]="LANG=en_SG"
D [07/Jun/2012:09:03:17 -0400] StartJob: envp[5]="PPD=/etc/cups/ppd/ng_unifyR.ppd"
D [07/Jun/2012:09:03:17 -0400] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [07/Jun/2012:09:03:17 -0400] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [07/Jun/2012:09:03:17 -0400] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [07/Jun/2012:09:03:17 -0400] StartJob: envp[9]="CONTENT_TYPE=application/postscript"
D [07/Jun/2012:09:03:17 -0400] StartJob: envp[10]="DEVICE_URI=lpd://usbmap0001/ng_unifyR"
D [07/Jun/2012:09:03:17 -0400] StartJob: envp[11]="PRINTER=ng_unifyR"
D [07/Jun/2012:09:03:17 -0400] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
D [07/Jun/2012:09:03:17 -0400] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [07/Jun/2012:09:03:17 -0400] StartJob: envp[14]="CUPS_SERVER=localhost"
D [07/Jun/2012:09:03:17 -0400] StartJob: envp[15]="IPP_PORT=631"
D [07/Jun/2012:09:03:17 -0400] StartJob: statusfds = [ 9 11 ]
D [07/Jun/2012:09:03:17 -0400] StartJob: filterfds[1] = [ 12 -1 ]
D [07/Jun/2012:09:03:17 -0400] StartJob: backend = "/usr/lib64/cups/backend/lpd"
D [07/Jun/2012:09:03:17 -0400] StartJob: filterfds[0] = [ -1 13 ]
D [07/Jun/2012:09:03:17 -0400] start_process("/usr/lib64/cups/backend/lpd", 0x7fbffee800, 0x7fbffed9e0, 12, 13, 11)
I [07/Jun/2012:09:03:17 -0400] Started backend /usr/lib64/cups/backend/lpd (PID 31242) for job 14496.
D [07/Jun/2012:09:03:17 -0400] ProcessIPPRequest: 8 status_code=0
D [07/Jun/2012:09:03:17 -0400] ReadClient: 6 POST /printers/ng_unifyR HTTP/1.1
D [07/Jun/2012:09:03:17 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:03:17 -0400] [Job 14496] Connected on ports 515 (local 1022)...
D [07/Jun/2012:09:03:17 -0400] [Job 14496] lpd_command 02 ng_unifyR
D [07/Jun/2012:09:03:17 -0400] [Job 14496] Sending command string (11 bytes)...
D [07/Jun/2012:09:03:17 -0400] [Job 14496] Reading command status...
D [07/Jun/2012:09:03:17 -0400] [Job 14496] lpd_command returning 0
D [07/Jun/2012:09:03:17 -0400] [Job 14496] Control file is:
D [07/Jun/2012:09:03:17 -0400] [Job 14496] Hbloch2
D [07/Jun/2012:09:03:17 -0400] [Job 14496] Pchemist
D [07/Jun/2012:09:03:17 -0400] [Job 14496] Jtmp_plot
D [07/Jun/2012:09:03:17 -0400] [Job 14496] ldfA242bloch2
D [07/Jun/2012:09:03:17 -0400] [Job 14496] UdfA242bloch2
D [07/Jun/2012:09:03:17 -0400] [Job 14496] Ntmp_plot
D [07/Jun/2012:09:03:17 -0400] [Job 14496] lpd_command 02 65 cfA242bloch2
D [07/Jun/2012:09:03:17 -0400] [Job 14496] Sending command string (17 bytes)...
D [07/Jun/2012:09:03:17 -0400] [Job 14496] Reading command status...
D [07/Jun/2012:09:03:17 -0400] [Job 14496] lpd_command returning 0
D [07/Jun/2012:09:03:17 -0400] [Job 14496] lpd_command 03 67984 dfA242bloch2
D [07/Jun/2012:09:03:17 -0400] [Job 14496] Sending command string (20 bytes)...
D [07/Jun/2012:09:03:17 -0400] [Job 14496] Reading command status...
D [07/Jun/2012:09:03:17 -0400] [Job 14496] lpd_command returning 0
D [07/Jun/2012:09:03:17 -0400] CloseClient: 8
D [07/Jun/2012:09:03:17 -0400] UpdateJob: job 14496, file 0 is complete.
D [07/Jun/2012:09:03:17 -0400] CancelJob: id = 14496
D [07/Jun/2012:09:03:17 -0400] StopJob: id = 14496, force = 0
D [07/Jun/2012:09:03:17 -0400] StopJob: printer state is 3
D [07/Jun/2012:09:03:19 -0400] ReadClient: 6 POST / HTTP/1.1
D [07/Jun/2012:09:03:19 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:03:22 -0400] ReadClient: 6 POST /printers/ng_unifyR HTTP/1.1
D [07/Jun/2012:09:03:22 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:03:25 -0400] ReadClient: 6 POST / HTTP/1.1
D [07/Jun/2012:09:03:25 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:03:31 -0400] ReadClient: 6 POST / HTTP/1.1
D [07/Jun/2012:09:03:31 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:03:37 -0400] ReadClient: 6 POST / HTTP/1.1
D [07/Jun/2012:09:03:37 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:03:43 -0400] ReadClient: 6 POST / HTTP/1.1
D [07/Jun/2012:09:03:43 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:03:49 -0400] ReadClient: 6 POST / HTTP/1.1
D [07/Jun/2012:09:03:49 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:03:55 -0400] ReadClient: 6 POST / HTTP/1.1
D [07/Jun/2012:09:03:55 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:04:01 -0400] ReadClient: 6 POST / HTTP/1.1
D [07/Jun/2012:09:04:01 -0400] ProcessIPPRequest: 6 status_code=0
D [07/Jun/2012:09:04:02 -0400] ReadClient: 6 POST /printers/usbmpr2045 HTTP/1.1
D [07/Jun/2012:09:04:02 -0400] ProcessIPPRequest: 6 status_code=0
 
Old 06-07-2012, 06:26 PM   #9
michaelk
Moderator
 
Registered: Aug 2002
Posts: 26,521

Rep: Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224Reputation: 6224
What is the make / model of your printer and how is it connected to your computer.
It looks like the lpd backend is not communicating with the printer. I am still trying to find information on the status codes.
 
  


Reply


Thread Tools Search this Thread
Search this Thread:

Advanced Search

Posting Rules
You may not post new threads
You may not post replies
You may not post attachments
You may not edit your posts

BB code is On
Smilies are On
[IMG] code is Off
HTML code is Off



Similar Threads
Thread Thread Starter Forum Replies Last Post
Printing problem holmez Linux - Newbie 2 02-08-2009 02:26 AM
Printing problem cvzyl Linux - Newbie 7 04-02-2006 01:55 PM
Cups Printing problem, Linux to Linux Printing problem folken718 Slackware 2 11-08-2004 11:10 AM
Problem with printing? minm Linux - Newbie 3 11-03-2004 11:08 AM
Printing Problem Zebe SUSE / openSUSE 1 10-12-2004 09:58 AM

LinuxQuestions.org > Forums > Linux Forums > Linux - Newbie

All times are GMT -5. The time now is 02:06 AM.

Main Menu
Advertisement
My LQ
Write for LQ
LinuxQuestions.org is looking for people interested in writing Editorials, Articles, Reviews, and more. If you'd like to contribute content, let us know.
Main Menu
Syndicate
RSS1  Latest Threads
RSS1  LQ News
Twitter: @linuxquestions
Open Source Consulting | Domain Registration