Printing problems
Anonymous
anonymous at easysw.com
Tue Oct 5 03:12:24 PDT 2004
Technical specs:
Client machine: Windows XP
Print Que Machine: Fedora 2
Printer Server: NetGear PS110
Printer: DECLaser 1152
I am trying to figure out why I am getting extra pages printing out:
ERROR: timeout
OFFENDING COMMAND: timeout
STACK:
The print job is coming from a Windows XP machine going to my linux machine which is connected via ethernet network to a Netgear PS110 Print Server. The printer is an old DECLaser 1152. Printing directly from Linux is ok. But I continue to run into problems printing from Windows XP through CUPS to the DECLaser. It works ok if I use the printer server software on my machine to the DECLaser. I do not get these error message pages going this route. But I would like to avoid installing print server software on all of my Windows machines.
I set a debug level in the cupsd.conf file. This is the output. (I changed some of the text, like machine names, username etc.) I do not really know how to read this file, I am hoping someone here can help me figure out what is causing the timeout errors to appear.
=========================
I [04/Oct/2004:21:47:40 -0500] Full reload is required.
D [04/Oct/2004:21:47:40 -0500] LoadAllPrinters: Loading printer DECLaser...
D [04/Oct/2004:21:47:40 -0500] LoadDevices: Added device "parallel:/dev/lp0"...
D [04/Oct/2004:21:47:40 -0500] LoadDevices: Added device "ptal"...
D [04/Oct/2004:21:47:40 -0500] LoadDevices: Added device "http"...
D [04/Oct/2004:21:47:40 -0500] LoadDevices: Added device "ipp"...
D [04/Oct/2004:21:47:40 -0500] LoadDevices: Added device "lpd"...
D [04/Oct/2004:21:47:40 -0500] LoadDevices: Added device "smb"...
D [04/Oct/2004:21:47:40 -0500] LoadDevices: Added device "scsi"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "serial:/dev/ttyS0?baud=115200"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "serial:/dev/ttyS1?baud=115200"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "serial:/dev/ttyS2?baud=115200"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "serial:/dev/ttyS3?baud=115200"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "serial:/dev/ttyS4?baud=115200"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "serial:/dev/ttyS5?baud=115200"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "serial:/dev/ttyS6?baud=115200"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "serial:/dev/ttyS7?baud=115200"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "socket"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "usb:/dev/usb/lp0"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "usb:/dev/usb/lp1"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "usb:/dev/usb/lp2"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "usb:/dev/usb/lp3"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "usb:/dev/usb/lp4"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "usb:/dev/usb/lp5"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "usb:/dev/usb/lp6"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "usb:/dev/usb/lp7"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "usb:/dev/usb/lp8"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "usb:/dev/usb/lp9"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "usb:/dev/usb/lp10"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "usb:/dev/usb/lp11"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "usb:/dev/usb/lp12"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "usb:/dev/usb/lp13"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "usb:/dev/usb/lp14"...
D [04/Oct/2004:21:47:41 -0500] LoadDevices: Added device "usb:/dev/usb/lp15"...
I [04/Oct/2004:21:47:41 -0500] LoadPPDs: Read "/etc/cups/ppds.dat", 13 PPDs...
I [04/Oct/2004:21:47:41 -0500] LoadPPDs: No new or changed PPDs...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Scanning /var/spool/cups...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 1...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 2...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 3...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 4...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 16...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 5...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 18...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 6...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 17...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 7...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 23...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 8...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 19...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 9...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 25...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 10...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 20...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 11...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 24...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 12...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 21...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 13...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 26...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 14...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 22...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 15...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 63...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 27...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 64...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 28...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 65...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 29...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 66...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 30...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 67...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 31...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 68...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 32...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 69...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 33...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 70...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 34...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 71...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 35...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 72...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 36...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 73...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 37...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 74...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 38...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 75...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 39...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 76...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 40...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 77...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 41...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 78...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 42...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 79...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 43...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 80...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 44...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 81...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 45...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 82...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 46...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 83...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 47...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 84...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 48...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 85...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 49...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 50...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 51...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 52...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 53...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 54...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 55...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 56...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 57...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 58...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 59...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 60...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 61...
D [04/Oct/2004:21:47:41 -0500] LoadAllJobs: Loading attributes for job 62...
I [04/Oct/2004:21:47:41 -0500] Full reload complete.
D [04/Oct/2004:21:47:41 -0500] StartListening: NumListeners=1
D [04/Oct/2004:21:47:41 -0500] StartListening: address=00000000 port=631
D [04/Oct/2004:21:47:41 -0500] ResumeListening: setting input bits...
D [04/Oct/2004:21:48:02 -0500] AcceptClient() 5 from localhost:631.
D [04/Oct/2004:21:48:02 -0500] ReadClient() 5 POST / HTTP/1.1
D [04/Oct/2004:21:48:02 -0500] ProcessIPPRequest: 5 status_code=0
D [04/Oct/2004:21:48:02 -0500] ReadClient() 5 POST / HTTP/1.1
D [04/Oct/2004:21:48:02 -0500] ProcessIPPRequest: 5 status_code=0
D [04/Oct/2004:21:48:02 -0500] CloseClient() 5
D [04/Oct/2004:21:48:16 -0500] AcceptClient() 5 from 192.168.1.9:631.
D [04/Oct/2004:21:48:16 -0500] ReadClient() 5 POST /printers/DECLaser HTTP/1.1
D [04/Oct/2004:21:48:16 -0500] ProcessIPPRequest: 5 status_code=0
D [04/Oct/2004:21:48:16 -0500] AcceptClient() 7 from 192.168.1.9:631.
D [04/Oct/2004:21:48:16 -0500] ReadClient() 7 POST /printers/DECLaser HTTP/1.1
D [04/Oct/2004:21:48:16 -0500] ProcessIPPRequest: 7 status_code=0
D [04/Oct/2004:21:48:16 -0500] ReadClient() 5 POST /printers/DECLaser HTTP/1.1
D [04/Oct/2004:21:48:16 -0500] print_job: auto-typing file...
D [04/Oct/2004:21:48:16 -0500] print_job: request file type is application/postscript.
D [04/Oct/2004:21:48:16 -0500] check_quotas: requesting-user-name = 'USERNAME'
D [04/Oct/2004:21:48:16 -0500] print_job: requesting-user-name = 'USERNAME'
D [04/Oct/2004:21:48:16 -0500] Adding default job-sheets values "none,none"...
I [04/Oct/2004:21:48:16 -0500] Adding start banner page "none" to job 86.
I [04/Oct/2004:21:48:16 -0500] Adding end banner page "none" to job 86.
I [04/Oct/2004:21:48:16 -0500] Job 86 queued on 'DECLaser' by 'USERNAME'.
D [04/Oct/2004:21:48:16 -0500] Job 86 hold_until = 0
D [04/Oct/2004:21:48:16 -0500] StartJob(86, 0x8739158)
D [04/Oct/2004:21:48:16 -0500] StartJob() id = 86, file = 0/1
D [04/Oct/2004:21:48:16 -0500] job-sheets=none,none
D [04/Oct/2004:21:48:16 -0500] banner_page = 0
D [04/Oct/2004:21:48:16 -0500] StartJob: argv = "DECLaser","86","USERNAME","Microsoft Word - AOL Billing Questions.doc","1","","/var/spool/cups/d00086-001"
D [04/Oct/2004:21:48:16 -0500] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [04/Oct/2004:21:48:16 -0500] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [04/Oct/2004:21:48:16 -0500] StartJob: envp[2]="USER=root"
D [04/Oct/2004:21:48:16 -0500] StartJob: envp[3]="CHARSET=utf-8"
D [04/Oct/2004:21:48:16 -0500] StartJob: envp[4]="LANG=en_US"
D [04/Oct/2004:21:48:16 -0500] StartJob: envp[5]="PPD=/etc/cups/ppd/DECLaser.ppd"
D [04/Oct/2004:21:48:16 -0500] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [04/Oct/2004:21:48:16 -0500] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [04/Oct/2004:21:48:16 -0500] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [04/Oct/2004:21:48:16 -0500] StartJob: envp[9]="CONTENT_TYPE=application/postscript"
D [04/Oct/2004:21:48:16 -0500] StartJob: envp[10]="DEVICE_URI=lpd://PS580100/P2"
D [04/Oct/2004:21:48:16 -0500] StartJob: envp[11]="PRINTER=DECLaser"
D [04/Oct/2004:21:48:16 -0500] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
D [04/Oct/2004:21:48:16 -0500] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [04/Oct/2004:21:48:16 -0500] StartJob: envp[14]="CUPS_SERVER=localhost"
D [04/Oct/2004:21:48:16 -0500] StartJob: envp[15]="IPP_PORT=631"
D [04/Oct/2004:21:48:16 -0500] StartJob: statusfds = [ 8 9 ]
D [04/Oct/2004:21:48:16 -0500] StartJob: filterfds[1] = [ 10 -1 ]
D [04/Oct/2004:21:48:16 -0500] StartJob: filter = "/usr/lib/cups/filter/pstops"
D [04/Oct/2004:21:48:16 -0500] StartJob: filterfds[0] = [ 11 12 ]
D [04/Oct/2004:21:48:16 -0500] start_process("/usr/lib/cups/filter/pstops", 0xfef44c20, 0xfef43f90, 10, 12, 9)
I [04/Oct/2004:21:48:16 -0500] Started filter /usr/lib/cups/filter/pstops (PID 24419) for job 86.
D [04/Oct/2004:21:48:16 -0500] StartJob: filter = "/usr/lib/cups/filter/foomatic-rip"
D [04/Oct/2004:21:48:16 -0500] StartJob: filterfds[1] = [ 10 13 ]
D [04/Oct/2004:21:48:16 -0500] start_process("/usr/lib/cups/filter/foomatic-rip", 0xfef44c20, 0xfef43f90, 11, 13, 9)
I [04/Oct/2004:21:48:16 -0500] Started filter /usr/lib/cups/filter/foomatic-rip (PID 24420) for job 86.
D [04/Oct/2004:21:48:16 -0500] StartJob: backend = "/usr/lib/cups/backend/lpd"
D [04/Oct/2004:21:48:16 -0500] StartJob: filterfds[0] = [ -1 11 ]
D [04/Oct/2004:21:48:16 -0500] start_process("/usr/lib/cups/backend/lpd", 0xfef44c20, 0xfef43f90, 10, 11, 9)
I [04/Oct/2004:21:48:16 -0500] Started backend /usr/lib/cups/backend/lpd (PID 24421) for job 86.
D [04/Oct/2004:21:48:16 -0500] ProcessIPPRequest: 5 status_code=0
D [04/Oct/2004:21:48:16 -0500] AcceptClient() 9 from localhost:631.
D [04/Oct/2004:21:48:16 -0500] ReadClient() 9 POST / HTTP/1.1
D [04/Oct/2004:21:48:16 -0500] ProcessIPPRequest: 9 status_code=1
D [04/Oct/2004:21:48:16 -0500] [Job 86] Page = 612x792; 0,0 to 612,792
D [04/Oct/2004:21:48:16 -0500] [Job 86] slowcollate=0, slowduplex=0, sloworder=0
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%Title: Microsoft Word - AOL Billing Questions.doc
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%Creator: PScript5.dll Version 5.2
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%CreationDate: 10/4/2004 21:48:40
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%For: USERNAME
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BoundingBox: (atend)
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%Pages: (atend)
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%Orientation: Portrait
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%PageOrder: Special
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%DocumentNeededResources: (atend)
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%DocumentSuppliedResources: (atend)
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%DocumentData: Clean7Bit
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%TargetDevice: (DEClaser 1152) (2011.113) 12
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%LanguageLevel: 2
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndComments
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BeginDefaults
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%PageBoundingBox: 12 5 595 781
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%ViewingOrientation: 1 0 0 1
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndDefaults
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BeginProlog
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BeginResource: file Pscript_WinNT_ErrorHandler 5.0 0
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndResource
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BeginResource: file Pscript_FatalError 5.0 0
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndResource
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BeginResource: file Pscript_Win_Basic 5.0 0
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndResource
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BeginResource: file Pscript_Win_Utils_L2 5.0 0
D [04/Oct/2004:21:48:16 -0500] [Job 86] foomatic-rip version $Revision: 3.43.2.2 $ running...
D [04/Oct/2004:21:48:16 -0500] [Job 86] Parsing PPD file ...
D [04/Oct/2004:21:48:16 -0500] [Job 86] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"
D [04/Oct/2004:21:48:16 -0500] [Job 86] Added option ColorSpace
D [04/Oct/2004:21:48:16 -0500] [Job 86] Added option PageSize
D [04/Oct/2004:21:48:16 -0500] [Job 86] Added option PageRegion
D [04/Oct/2004:21:48:16 -0500] [Job 86] Added option ImageableArea
D [04/Oct/2004:21:48:16 -0500] [Job 86] Added option PaperDimension
D [04/Oct/2004:21:48:16 -0500] [Job 86] Added option Duplex
D [04/Oct/2004:21:48:16 -0500] [Job 86] Added option Resolution
D [04/Oct/2004:21:48:16 -0500] [Job 86] Added option PreFilter
D [04/Oct/2004:21:48:16 -0500] [Job 86] Added option Font
D [04/Oct/2004:21:48:16 -0500] [Job 86]
D [04/Oct/2004:21:48:16 -0500] [Job 86] Parameter Summary
D [04/Oct/2004:21:48:16 -0500] [Job 86] -----------------
D [04/Oct/2004:21:48:16 -0500] [Job 86]
D [04/Oct/2004:21:48:16 -0500] [Job 86] Spooler: cups
D [04/Oct/2004:21:48:16 -0500] [Job 86] Printer: DECLaser
D [04/Oct/2004:21:48:16 -0500] [Job 86] PPD file: /etc/cups/ppd/DECLaser.ppd
D [04/Oct/2004:21:48:16 -0500] [Job 86] Printer model: DEC LN07 Foomatic/Postscript (recommended)
D [04/Oct/2004:21:48:16 -0500] [Job 86] Job title: Microsoft Word - AOL Billing Questions.doc
D [04/Oct/2004:21:48:16 -0500] [Job 86] File(s) to be printed:
D [04/Oct/2004:21:48:16 -0500] [Job 86] <STDIN>
D [04/Oct/2004:21:48:16 -0500] [Job 86]
D [04/Oct/2004:21:48:16 -0500] [Job 86]
D [04/Oct/2004:21:48:16 -0500] [Job 86] ================================================
D [04/Oct/2004:21:48:16 -0500] [Job 86]
D [04/Oct/2004:21:48:16 -0500] [Job 86] File: <STDIN>
D [04/Oct/2004:21:48:16 -0500] [Job 86]
D [04/Oct/2004:21:48:16 -0500] [Job 86] ================================================
D [04/Oct/2004:21:48:16 -0500] [Job 86]
D [04/Oct/2004:21:48:16 -0500] [Job 86] Reading PostScript input ...
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndResource
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndProlog
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BeginSetup
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BeginNonPPDFeature: JobTimeout 100
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndNonPPDFeature
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BeginNonPPDFeature: WaitTimeout 600
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndNonPPDFeature
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BeginFeature: *PageSize Letter
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndFeature
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BeginFeature: *OutputBin OnlyOne
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndFeature
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BeginFeature: *Option1 False
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndFeature
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BeginFeature: *Option2 None
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndFeature
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BeginFeature: *InstalledMemory None
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndFeature
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BeginFeature: *Resolution 300dpi
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndFeature
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndSetup
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%Page: 1 1
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%Page: 1 1
D [04/Oct/2004:21:48:16 -0500] [Job 86] pw = 612.0, pl = 792.0
D [04/Oct/2004:21:48:16 -0500] [Job 86] PageLeft = 0.0, PageRight = 612.0
D [04/Oct/2004:21:48:16 -0500] [Job 86] PageTop = 792.0, PageBottom = 0.0
D [04/Oct/2004:21:48:16 -0500] [Job 86] PageWidth = 612.0, PageLength = 792.0
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%PageBoundingBox: 12 5 595 781
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndPageComments
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BeginPageSetup
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndPageSetup
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BeginResource: file Pscript_Text 5.0 0
D [04/Oct/2004:21:48:16 -0500] [Job 86] --> This document is DSC-conforming!
D [04/Oct/2004:21:48:16 -0500] [Job 86] Job claims to be DSC-conforming, but "%%BeginProlog" was missing before first line with another "%%Begin..." comment (is this a TeX/LaTeX/dvips-generated PostScript file?). Assuming start of "Prolog" here.
D [04/Oct/2004:21:48:16 -0500] [Job 86]
D [04/Oct/2004:21:48:16 -0500] [Job 86] -----------
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %%BeginProlog
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndResource
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BeginResource: file Pscript_Encoding256 5.0 0
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndResource
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%IncludeResource: font Times-Roman
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BeginResource: file Pscript_Win_Euro_L2 5.0 0
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %%EndProlog
D [04/Oct/2004:21:48:16 -0500] [Job 86]
D [04/Oct/2004:21:48:16 -0500] [Job 86] -----------
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %%BeginSetup
D [04/Oct/2004:21:48:16 -0500] [Job 86] Inserting PostScript code for CUPS' page accounting
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %%BeginFeature: *PageSize Letter
D [04/Oct/2004:21:48:16 -0500] [Job 86] Option: PageSize=Letter --> Option will be set by PostScript interpreter
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %%BeginFeature: *PreFilter No
D [04/Oct/2004:21:48:16 -0500] [Job 86] Option: PreFilter=No --> Setting option
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %% FoomaticRIPOptionSetting: PreFilter=No
D [04/Oct/2004:21:48:16 -0500] [Job 86] Option: PreFilter=No --> Setting option
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %%BeginFeature: *Resolution 300x300dpi
D [04/Oct/2004:21:48:16 -0500] [Job 86] Option: Resolution=300x300dpi --> Setting option
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %% FoomaticRIPOptionSetting: Resolution=300x300dpi
D [04/Oct/2004:21:48:16 -0500] [Job 86] Option: Resolution=300x300dpi --> Setting option
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %%BeginFeature: *Duplex Notcapable
D [04/Oct/2004:21:48:16 -0500] [Job 86] Option: Duplex=Notcapable --> Setting option
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %% FoomaticRIPOptionSetting: Duplex=Notcapable
D [04/Oct/2004:21:48:16 -0500] [Job 86] Option: Duplex=Notcapable --> Setting option
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %%BeginFeature: *PageSize Letter
D [04/Oct/2004:21:48:16 -0500] [Job 86] Option: PageSize=Letter --> Option will be set by PostScript interpreter
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %%BeginFeature: *OutputBin OnlyOne
D [04/Oct/2004:21:48:16 -0500] [Job 86] Unknown option OutputBin=OnlyOne found in the job
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %%BeginFeature: *Option1 False
D [04/Oct/2004:21:48:16 -0500] [Job 86] Unknown option Option1=False found in the job
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %%BeginFeature: *Option2 None
D [04/Oct/2004:21:48:16 -0500] [Job 86] Unknown option Option2=None found in the job
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %%BeginFeature: *InstalledMemory None
D [04/Oct/2004:21:48:16 -0500] [Job 86] Unknown option InstalledMemory=None found in the job
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %%BeginFeature: *Resolution 300dpi
D [04/Oct/2004:21:48:16 -0500] [Job 86] Option: Resolution=300dpi --> Invalid option setting found in job
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %%EndSetup
D [04/Oct/2004:21:48:16 -0500] [Job 86]
D [04/Oct/2004:21:48:16 -0500] [Job 86] -----------
D [04/Oct/2004:21:48:16 -0500] [Job 86] New page: 1 1
D [04/Oct/2004:21:48:16 -0500] [Job 86] Inserting option code into "PageSetup" section.
D [04/Oct/2004:21:48:16 -0500] [Job 86]
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %%BeginPageSetup
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %%EndPageSetup
D [04/Oct/2004:21:48:16 -0500] [Job 86] End of page header
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndResource
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%PageTrailer
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%Page: 2 2
D [04/Oct/2004:21:48:16 -0500] [Job 86] Stopping search for page header options
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found:
D [04/Oct/2004:21:48:16 -0500] [Job 86] 325 1104 M (inquiry, Ron. )[13 25 25 26 12 18 24 13 13 33 26 24 13 0]xS
D [04/Oct/2004:21:48:16 -0500] [Job 86] --> Output goes directly to the renderer now.
D [04/Oct/2004:21:48:16 -0500] [Job 86]
D [04/Oct/2004:21:48:16 -0500] [Job 86]
D [04/Oct/2004:21:48:16 -0500] [Job 86] Starting renderer
D [04/Oct/2004:21:48:16 -0500] [Job 86] renderer PID kid4=24423
D [04/Oct/2004:21:48:16 -0500] [Job 86] JCL: <job data>
D [04/Oct/2004:21:48:16 -0500] [Job 86]
D [04/Oct/2004:21:48:16 -0500] [Job 86] renderer command: level=0; printf "%%!\n%%%% %%%%\n<</HWResolution[300 300]>>setpagedevice\n"; if (( $level > 0 )); then if (( $level < 99 )); then level=" -dLanguageLevel=$level"; else level=""; fi; gs -q -dPARANOIDSAFER -dNOPAUSE -dBATCH -sDEVICE=pswrite$level -sOutputFile=- -; else cat; fi
D [04/Oct/2004:21:48:16 -0500] [Job 86] pw = 612.0, pl = 792.0
D [04/Oct/2004:21:48:16 -0500] [Job 86] PageLeft = 0.0, PageRight = 612.0
D [04/Oct/2004:21:48:16 -0500] [Job 86] PageTop = 792.0, PageBottom = 0.0
D [04/Oct/2004:21:48:16 -0500] [Job 86] PageWidth = 612.0, PageLength = 792.0
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%PageBoundingBox: 12 5 595 781
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndPageComments
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%BeginPageSetup
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%EndPageSetup
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%PageTrailer
D [04/Oct/2004:21:48:16 -0500] [Job 86] 0 %%Trailer
D [04/Oct/2004:21:48:16 -0500] [Job 86] Saw Trailer!
D [04/Oct/2004:21:48:16 -0500] [Job 86] Saw EOF!
D [04/Oct/2004:21:48:16 -0500] [Job 86]
D [04/Oct/2004:21:48:16 -0500] [Job 86] -----------
D [04/Oct/2004:21:48:16 -0500] [Job 86] New page: 2 2
D [04/Oct/2004:21:48:16 -0500] [Job 86] Inserting option code into "PageSetup" section.
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found:
D [04/Oct/2004:21:48:16 -0500] [Job 86] %%Page: 2 2
D [04/Oct/2004:21:48:16 -0500] [Job 86] --> Output goes to the FIFO buffer now.
D [04/Oct/2004:21:48:16 -0500] [Job 86]
D [04/Oct/2004:21:48:16 -0500] [Job 86]
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %%BeginPageSetup
D [04/Oct/2004:21:48:16 -0500] [Job 86] Found: %%EndPageSetup
D [04/Oct/2004:21:48:16 -0500] [Job 86] End of page header
D [04/Oct/2004:21:48:16 -0500] [Job 86] Flushing FIFO.
D [04/Oct/2004:21:48:16 -0500] [Job 86]
D [04/Oct/2004:21:48:16 -0500] [Job 86] Closing renderer
D [04/Oct/2004:21:48:16 -0500] [Job 86] KID3 exited with status 0
D [04/Oct/2004:21:48:16 -0500] [Job 86] tail process done writing data to STDOUT
D [04/Oct/2004:21:48:16 -0500] [Job 86] KID4 finished
D [04/Oct/2004:21:48:16 -0500] [Job 86] KID4 exited with status 0
D [04/Oct/2004:21:48:16 -0500] [Job 86] Renderer exit stat: 0
D [04/Oct/2004:21:48:16 -0500] [Job 86] KID3 finished
D [04/Oct/2004:21:48:16 -0500] [Job 86] Renderer process finished
D [04/Oct/2004:21:48:16 -0500] [Job 86]
D [04/Oct/2004:21:48:16 -0500] [Job 86] Closing foomatic-rip.
D [04/Oct/2004:21:48:16 -0500] [Job 86] Connected on ports 515 (local 0)...
D [04/Oct/2004:21:48:16 -0500] [Job 86] lpd_command 02 P2
D [04/Oct/2004:21:48:16 -0500] [Job 86] Sending command string (4 bytes)...
D [04/Oct/2004:21:48:16 -0500] [Job 86] Reading command status...
D [04/Oct/2004:21:48:16 -0500] [Job 86] lpd_command returning 0
D [04/Oct/2004:21:48:16 -0500] [Job 86] Control file is:
D [04/Oct/2004:21:48:16 -0500] [Job 86] Hlocalhost.net
D [04/Oct/2004:21:48:16 -0500] [Job 86] PUSERNAME
D [04/Oct/2004:21:48:16 -0500] [Job 86] JMicrosoft Word _ AOL Billing Questions_doc
D [04/Oct/2004:21:48:16 -0500] [Job 86] ldfA421localhost.net
D [04/Oct/2004:21:48:16 -0500] [Job 86] UdfA421localhost.net
D [04/Oct/2004:21:48:16 -0500] [Job 86] NMicrosoft Word _ AOL Billing Questions_doc
D [04/Oct/2004:21:48:16 -0500] [Job 86] lpd_command 02 171 cfA421localhost.net
D [04/Oct/2004:21:48:16 -0500] [Job 86] Sending command string (32 bytes)...
D [04/Oct/2004:21:48:16 -0500] [Job 86] Reading command status...
D [04/Oct/2004:21:48:16 -0500] [Job 86] lpd_command returning 0
D [04/Oct/2004:21:48:16 -0500] [Job 86] lpd_command 03 41036 dfA421localhost.net
D [04/Oct/2004:21:48:16 -0500] [Job 86] Sending command string (34 bytes)...
D [04/Oct/2004:21:48:16 -0500] [Job 86] Reading command status...
D [04/Oct/2004:21:48:16 -0500] [Job 86] lpd_command returning 0
D [04/Oct/2004:21:48:16 -0500] ReadClient() 7 POST /printers/DECLaser HTTP/1.1
D [04/Oct/2004:21:48:16 -0500] ProcessIPPRequest: 7 status_code=0
D [04/Oct/2004:21:48:33 -0500] UpdateJob: job 86, file 0 is complete.
D [04/Oct/2004:21:48:33 -0500] CancelJob: id = 86
D [04/Oct/2004:21:48:33 -0500] StopJob: id = 86, force = 0
D [04/Oct/2004:21:48:33 -0500] StopJob: printer state is 3
D [04/Oct/2004:21:48:33 -0500] ReadClient() 9 POST / HTTP/1.1
D [04/Oct/2004:21:48:34 -0500] ProcessIPPRequest: 9 status_code=1
D [04/Oct/2004:21:49:02 -0500] AcceptClient() 8 from localhost:631.
D [04/Oct/2004:21:49:02 -0500] ReadClient() 8 POST / HTTP/1.1
D [04/Oct/2004:21:49:02 -0500] ProcessIPPRequest: 8 status_code=0
D [04/Oct/2004:21:49:02 -0500] ReadClient() 8 POST / HTTP/1.1
D [04/Oct/2004:21:49:02 -0500] ProcessIPPRequest: 8 status_code=0
D [04/Oct/2004:21:49:02 -0500] CloseClient() 8
D [04/Oct/2004:21:50:15 -0500] AcceptClient() 8 from localhost:631.
D [04/Oct/2004:21:50:15 -0500] ReadClient() 8 POST / HTTP/1.1
D [04/Oct/2004:21:50:15 -0500] ProcessIPPRequest: 8 status_code=0
D [04/Oct/2004:21:50:15 -0500] ReadClient() 8 POST / HTTP/1.1
D [04/Oct/2004:21:50:16 -0500] ProcessIPPRequest: 8 status_code=0
D [04/Oct/2004:21:50:16 -0500] CloseClient() 8
D [04/Oct/2004:21:51:15 -0500] AcceptClient() 8 from localhost:631.
D [04/Oct/2004:21:51:15 -0500] ReadClient() 8 POST / HTTP/1.1
D [04/Oct/2004:21:51:15 -0500] ProcessIPPRequest: 8 status_code=0
D [04/Oct/2004:21:51:15 -0500] ReadClient() 8 POST / HTTP/1.1
D [04/Oct/2004:21:51:16 -0500] ProcessIPPRequest: 8 status_code=0
D [04/Oct/2004:21:51:16 -0500] CloseClient() 8
D [04/Oct/2004:21:52:15 -0500] AcceptClient() 8 from localhost:631.
D [04/Oct/2004:21:52:15 -0500] ReadClient() 8 POST / HTTP/1.1
D [04/Oct/2004:21:52:15 -0500] ProcessIPPRequest: 8 status_code=0
D [04/Oct/2004:21:52:15 -0500] ReadClient() 8 POST / HTTP/1.1
D [04/Oct/2004:21:52:16 -0500] ProcessIPPRequest: 8 status_code=0
D [04/Oct/2004:21:52:16 -0500] CloseClient() 8
D [04/Oct/2004:21:53:15 -0500] AcceptClient() 8 from localhost:631.
D [04/Oct/2004:21:53:15 -0500] ReadClient() 8 POST / HTTP/1.1
D [04/Oct/2004:21:53:15 -0500] ProcessIPPRequest: 8 status_code=0
D [04/Oct/2004:21:53:15 -0500] ReadClient() 8 POST / HTTP/1.1
D [04/Oct/2004:21:53:16 -0500] ProcessIPPRequest: 8 status_code=0
D [04/Oct/2004:21:53:16 -0500] CloseClient() 8
D [04/Oct/2004:21:53:17 -0500] Closing client 5 after 300 seconds of inactivity...
D [04/Oct/2004:21:53:17 -0500] CloseClient() 5
D [04/Oct/2004:21:53:17 -0500] Closing client 7 after 300 seconds of inactivity...
D [04/Oct/2004:21:53:17 -0500] CloseClient() 7
D [04/Oct/2004:21:53:35 -0500] Closing client 9 after 300 seconds of inactivity...
D [04/Oct/2004:21:53:35 -0500] CloseClient() 9
D [04/Oct/2004:21:54:15 -0500] AcceptClient() 5 from localhost:631.
====================
More information about the cups
mailing list