LinuxQuestions.org

LinuxQuestions.org (/questions/)
-   Linux - Newbie (https://www.linuxquestions.org/questions/linux-newbie-8/)
-   -   Printing problem (https://www.linuxquestions.org/questions/linux-newbie-8/printing-problem-948638/)

linuxandtsm 06-05-2012 09:33 AM
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!

linuxandtsm 06-05-2012 02:33 PM
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

linuxandtsm 06-05-2012 02:59 PM
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

michaelk 06-05-2012 05:03 PM
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.

linuxandtsm 06-06-2012 10:07 AM
1 Attachment(s)
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!

linuxandtsm 06-06-2012 04:05 PM
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

michaelk 06-06-2012 04:58 PM
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?

linuxandtsm 06-07-2012 08:45 AM
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

michaelk 06-07-2012 05:26 PM
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.


All times are GMT -5. The time now is 04:41 PM.