Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jobs queued but not printed #73

Closed
michaelrsweet opened this issue May 7, 2003 · 5 comments
Closed

Jobs queued but not printed #73

michaelrsweet opened this issue May 7, 2003 · 5 comments
Milestone

Comments

@michaelrsweet
Copy link
Collaborator

Version: 1.1.19rc4
CUPS.org User: jimmy.nccom

Solaris 8. Upgrading from 1.1.18, which was working quite well (upgraded because I was hoping for a locale fix and for binary PS feature).

Under 1.1.19rc4, most jobs don't print, but instead are just queued. Sometimes stopping and restarting cupsd causes the queued jobs to print. I can also force it to print by Holding the job, and then Releasing it.

D [07/May/2003:13:10:50 +0800] AcceptClient() 8 from 198.51.175.62:631.
D [07/May/2003:13:10:50 +0800] ReadClient() 8 POST / HTTP/1.1
D [07/May/2003:13:10:50 +0800] ProcessIPPRequest: 8 status_code=0
D [07/May/2003:13:10:50 +0800] ReadClient() 8 GET /printers/laser.ppd HTTP/1.1
D [07/May/2003:13:10:50 +0800] SendFile() 8 file=9
D [07/May/2003:13:10:52 +0800] CloseClient() 8
D [07/May/2003:13:10:52 +0800] AcceptClient() 8 from 198.51.175.62:631.
D [07/May/2003:13:10:52 +0800] ReadClient() 8 POST /printers/laser HTTP/1.1
D [07/May/2003:13:10:52 +0800] print_job: request file type is application/vnd.cups-raw.
D [07/May/2003:13:10:52 +0800] check_quotas: requesting-user-name = 'delline'
D [07/May/2003:13:10:52 +0800] print_job: requesting-user-name = 'delline'
D [07/May/2003:13:10:52 +0800] Adding default job-sheets values "none,none"...
I [07/May/2003:13:10:52 +0800] Job 1 queued on 'laser' by 'delline'.
D [07/May/2003:13:10:52 +0800] Job 1 hold_until = 0
D [07/May/2003:13:10:52 +0800] StartJob(1, 17dae0)
D [07/May/2003:13:10:52 +0800] StartJob() id = 1, file = 0/1
D [07/May/2003:13:10:52 +0800] job-sheets=none,none
D [07/May/2003:13:10:52 +0800] banner_page = 0
D [07/May/2003:13:10:52 +0800] StartJob: argv = "laser","1","delline","030507-02.ps","1","","/var/spool/cups/d00001-001"
D [07/May/2003:13:10:52 +0800] StartJob: envp[0]="PATH=/opt/cups/lib/cups/filter:/bin:/usr/bin"
D [07/May/2003:13:10:52 +0800] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [07/May/2003:13:10:52 +0800] StartJob: envp[2]="USER=root"
D [07/May/2003:13:10:52 +0800] StartJob: envp[3]="CHARSET=iso-8859-1"
D [07/May/2003:13:10:52 +0800] StartJob: envp[4]="LANG=en"
D [07/May/2003:13:10:52 +0800] StartJob: envp[5]="TZ=US/Pacific"
D [07/May/2003:13:10:52 +0800] StartJob: envp[6]="PPD=/etc/cups/ppd/laser.ppd"
D [07/May/2003:13:10:52 +0800] StartJob: envp[7]="CUPS_SERVERROOT=/etc/cups"
D [07/May/2003:13:10:52 +0800] StartJob: envp[8]="RIP_MAX_CACHE=8m"
D [07/May/2003:13:10:52 +0800] StartJob: envp[9]="TMPDIR=/var/spool/cups/tmp"
D [07/May/2003:13:10:52 +0800] StartJob: envp[10]="CONTENT_TYPE=application/vnd.cups-raw"
D [07/May/2003:13:10:52 +0800] StartJob: envp[11]="DEVICE_URI=lpd://ricoh450e/PORT1"
D [07/May/2003:13:10:52 +0800] StartJob: envp[12]="PRINTER=laser"
D [07/May/2003:13:10:52 +0800] StartJob: envp[13]="CUPS_DATADIR=/opt/cups/share/cups"
D [07/May/2003:13:10:52 +0800] StartJob: envp[14]="CUPS_FONTPATH=/opt/cups/share/cups/fonts"
D [07/May/2003:13:10:52 +0800] StartJob: envp[15]="LD_LIBRARY_PATH=/opt/local/lib:/opt/gnome/lib"
D [07/May/2003:13:10:52 +0800] StartJob: statusfds = [ 9 10 ]
D [07/May/2003:13:10:52 +0800] StartJob: filterfds[1] = [ 11 -1 ]
D [07/May/2003:13:10:52 +0800] StartJob: backend = "/opt/cups/lib/cups/backend/lpd"
D [07/May/2003:13:10:52 +0800] StartJob: filterfds[0] = [ -1 12 ]
D [07/May/2003:13:10:52 +0800] start_process("/opt/cups/lib/cups/backend/lpd", ffbdfbf8, ffbdef68, 11, 12, 10)
I [07/May/2003:13:10:52 +0800] Started backend /opt/cups/lib/cups/backend/lpd (PID 12834) for job 1.
D [07/May/2003:13:10:52 +0800] ProcessIPPRequest: 8 status_code=0
D [07/May/2003:13:10:52 +0800] [Job 1] lpd_command 02 PORT1
D [07/May/2003:13:10:52 +0800] [Job 1] Sending command string (7 bytes)...
D [07/May/2003:13:10:52 +0800] [Job 1] Reading command status...
D [07/May/2003:13:10:57 +0800] ReadClient() 8 POST /printers/laser HTTP/1.1
D [07/May/2003:13:10:57 +0800] print_job: request file type is application/vnd.cups-raw.
D [07/May/2003:13:10:57 +0800] check_quotas: requesting-user-name = 'delline'
D [07/May/2003:13:10:57 +0800] print_job: requesting-user-name = 'delline'
D [07/May/2003:13:10:57 +0800] Adding default job-sheets values "none,none"...
I [07/May/2003:13:10:57 +0800] Job 2 queued on 'laser' by 'delline'.
D [07/May/2003:13:10:57 +0800] Job 2 hold_until = 0

And that's it. It shows in the queue, but doesn't print.

I need to revert to 1.1.18 now before my users kill me.

@michaelrsweet
Copy link
Collaborator Author

CUPS.org User: mike

Are jobs not printing at all, or do they start printing after a while (e.g. 5 minutes)?

@michaelrsweet
Copy link
Collaborator Author

CUPS.org User: jimmy.nccom

OK, I see we do get a StartJob, but it doesn't print. Here's a more complete debug output...

D [07/May/2003:13:33:26 +0800] AcceptClient() 10 from print.nccom.com:631.
D [07/May/2003:13:33:26 +0800] CloseClient() 8
D [07/May/2003:13:33:26 +0800] ReadClient() 10 POST /printers/laser HTTP/1.1
D [07/May/2003:13:33:26 +0800] print_job: auto-typing file...
D [07/May/2003:13:33:26 +0800] print_job: request file type is text/plain.
D [07/May/2003:13:33:26 +0800] check_quotas: requesting-user-name = 'jimmy'
D [07/May/2003:13:33:26 +0800] print_job: requesting-user-name = 'jimmy'
I [07/May/2003:13:33:26 +0800] Job 5 queued on 'laser' by 'jimmy'.
D [07/May/2003:13:33:26 +0800] Job 5 hold_until = 0
D [07/May/2003:13:33:26 +0800] StartJob(5, 16ead8)
D [07/May/2003:13:33:26 +0800] StartJob() id = 5, file = 0/1
D [07/May/2003:13:33:26 +0800] job-sheets=none,none
D [07/May/2003:13:33:26 +0800] banner_page = 0
D [07/May/2003:13:33:26 +0800] StartJob: argv = "laser","5","jimmy","group","1","Duplex=DuplexNoTumble nomirror position=top-left penwidth=10000 noprettyprint nowrap pa
ge-left=20 page-right=10 page-bottom=10 page-top=10","/var/spool/cups/d00005-001"
D [07/May/2003:13:33:26 +0800] StartJob: envp[0]="PATH=/opt/cups/lib/cups/filter:/bin:/usr/bin"
D [07/May/2003:13:33:26 +0800] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [07/May/2003:13:33:26 +0800] StartJob: envp[2]="USER=root"
D [07/May/2003:13:33:26 +0800] StartJob: envp[3]="CHARSET=iso-8859-1"
D [07/May/2003:13:33:26 +0800] StartJob: envp[4]="LANG=en"
D [07/May/2003:13:33:26 +0800] StartJob: envp[5]="TZ=US/Pacific"
D [07/May/2003:13:33:26 +0800] StartJob: envp[6]="PPD=/etc/cups/ppd/laser.ppd"
D [07/May/2003:13:33:26 +0800] StartJob: envp[7]="CUPS_SERVERROOT=/etc/cups"
D [07/May/2003:13:33:26 +0800] StartJob: envp[8]="RIP_MAX_CACHE=8m"
D [07/May/2003:13:33:26 +0800] StartJob: envp[9]="TMPDIR=/var/spool/cups/tmp"
D [07/May/2003:13:33:26 +0800] StartJob: envp[10]="CONTENT_TYPE=text/plain"
D [07/May/2003:13:33:26 +0800] StartJob: envp[11]="DEVICE_URI=lpd://ricoh450e/PORT1"
D [07/May/2003:13:33:26 +0800] StartJob: envp[12]="PRINTER=laser"
D [07/May/2003:13:33:26 +0800] StartJob: envp[13]="CUPS_DATADIR=/opt/cups/share/cups"
D [07/May/2003:13:33:26 +0800] StartJob: envp[14]="CUPS_FONTPATH=/opt/cups/share/cups/fonts"
D [07/May/2003:13:33:26 +0800] StartJob: envp[15]="LD_LIBRARY_PATH=/opt/local/lib:/opt/gnome/lib"
D [07/May/2003:13:33:26 +0800] StartJob: statusfds = [ 8 11 ]
D [07/May/2003:13:33:26 +0800] StartJob: filterfds[1] = [ 13 -1 ]
D [07/May/2003:13:33:26 +0800] StartJob: filter = "/opt/cups/lib/cups/filter/texttops"
D [07/May/2003:13:33:26 +0800] StartJob: filterfds[0] = [ 14 15 ]
D [07/May/2003:13:33:26 +0800] start_process("/opt/cups/lib/cups/filter/texttops", ffbdfbf8, ffbdef68, 13, 15, 11)
I [07/May/2003:13:33:26 +0800] Started filter /opt/cups/lib/cups/filter/texttops (PID 13144) for job 5.
D [07/May/2003:13:33:26 +0800] StartJob: filter = "/opt/cups/lib/cups/filter/pstops"
D [07/May/2003:13:33:26 +0800] StartJob: filterfds[1] = [ 13 16 ]
D [07/May/2003:13:33:26 +0800] start_process("/opt/cups/lib/cups/filter/pstops", ffbdfbf8, ffbdef68, 14, 16, 11)
I [07/May/2003:13:33:27 +0800] Started filter /opt/cups/lib/cups/filter/pstops (PID 13145) for job 5.
D [07/May/2003:13:33:27 +0800] StartJob: backend = "/opt/cups/lib/cups/backend/lpd"
D [07/May/2003:13:33:27 +0800] StartJob: filterfds[0] = [ -1 14 ]
D [07/May/2003:13:33:27 +0800] start_process("/opt/cups/lib/cups/backend/lpd", ffbdfbf8, ffbdef68, 13, 14, 11)
I [07/May/2003:13:33:27 +0800] Started backend /opt/cups/lib/cups/backend/lpd (PID 13146) for job 5.
D [07/May/2003:13:33:27 +0800] ProcessIPPRequest: 10 status_code=0
D [07/May/2003:13:33:27 +0800] [Job 5] Page = 612x792; 6,19 to 594,780
D [07/May/2003:13:33:27 +0800] [Job 5] ppd->num_fonts = 251
D [07/May/2003:13:33:27 +0800] [Job 5] ppd->fonts[0] = AlbertusMT-Italic
D [07/May/2003:13:33:27 +0800] [Job 5] ppd->fonts[1] = AlbertusMT-Light
D [07/May/2003:13:33:27 +0800] [Job 5] ppd->fonts[2] = AlbertusMT
D [07/May/2003:13:33:27 +0800] [Job 5] ppd->fonts[3] = AntiqueOlive-Bold
[lots more fonts in here]
D [07/May/2003:13:33:27 +0800] [Job 5] ppd->fonts[64] = Courier-Bold
D [07/May/2003:13:33:27 +0800] [Job 5] ppd->fonts[65] = Courier-BoldOblique
D [07/May/2003:13:33:27 +0800] [Job 5] ppd->fonts[66] = Courier-Oblique
D [07/May/2003:13:33:27 +0800] [Job 5] ppd->fonts[67] = Courier
D [07/May/2003:13:33:27 +0800] [Job 5] Page = 612x792; 6,19 to 594,780
D [07/May/2003:13:33:27 +0800] [Job 5] slowcollate=0, slowduplex=0, sloworder=0
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%BoundingBox: 0 0 612 792
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%Creator: texttops/CUPS v1.1.19rc4
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%CreationDate: Wed May 07 13:33:26 2003
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%Title: group
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%For: jimmy
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%Pages: (atend)
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%DocumentNeededResources: font Courier-Bold
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%+ font Courier
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%DocumentSuppliedResources: procset texttops 1.1 0
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%+ font Courier-Bold
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%+ font Courier
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%EndComments
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%BeginProlog
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%BeginResource: font Courier-Bold
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%CreationDate: Wed Dec 22 1999
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%EndResource
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%BeginResource: font Courier
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%CreationDate: Wed Dec 22 1999
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%EndResource
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%BeginResource: procset texttops 1.1 0
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%EndResource
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%EndProlog
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%Page: 1 1
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%Page: 1 1
D [07/May/2003:13:33:27 +0800] [Job 5] pw = 582.0, pl = 772.0
D [07/May/2003:13:33:27 +0800] [Job 5] PageLeft = 20.0, PageRight = 602.0
D [07/May/2003:13:33:27 +0800] [Job 5] PageTop = 782.0, PageBottom = 10.0
D [07/May/2003:13:33:27 +0800] [Job 5] PageWidth = 612.0, PageLength = 792.0
D [07/May/2003:13:33:27 +0800] [Job 5] 0 %%Trailer
D [07/May/2003:13:33:27 +0800] [Job 5] Saw Trailer!
D [07/May/2003:13:33:27 +0800] [Job 5] pw = 582.0, pl = 772.0
D [07/May/2003:13:33:27 +0800] [Job 5] PageLeft = 20.0, PageRight = 602.0
D [07/May/2003:13:33:27 +0800] [Job 5] PageTop = 782.0, PageBottom = 10.0
D [07/May/2003:13:33:27 +0800] [Job 5] PageWidth = 612.0, PageLength = 792.0
D [07/May/2003:13:33:27 +0800] [Job 5] Saw EOF!
D [07/May/2003:13:33:27 +0800] [Job 5] lpd_command 02 PORT1
D [07/May/2003:13:33:27 +0800] [Job 5] Sending command string (7 bytes)...
D [07/May/2003:13:33:27 +0800] [Job 5] Reading command status...
D [07/May/2003:13:33:29 +0800] CloseClient() 10
D [07/May/2003:13:35:10 +0800] Closing client 6 after 300 seconds of inactivity...

The job shows in the queue. If I hold it and release it, it will print.

@michaelrsweet
Copy link
Collaborator Author

CUPS.org User: jimmy.nccom

I will leave it in the queue for a while and see if it prints (while telling my users to not expect printing to work for another 30 minutes or so).

@michaelrsweet
Copy link
Collaborator Author

CUPS.org User: mike

What information is displayed in the web interface (http://localhost:631/printers)? It looks like the LPD backend is waiting for a response from the Ricoh...

@michaelrsweet
Copy link
Collaborator Author

CUPS.org User: jimmy.nccom

I rebooted the printer and the problem went away. Not sure if it's just a coincidence that something hosed the printer at the same time I upgraded to the new version, but go ahead and close this ticket and I'll refile if I can prove a causal link. Thanks and sorry for the trouble.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

1 participant