Empty Print File error for non-mac machine

Wed Dec 14 21:39:22 PST 2011

I got it resolved ipp backend permissions was set with 500 instead of 700. Now its working. Thanks

> Do you have SELinux or AppArmor enabled? (I assume this is on Linux)
>
>
> On Dec 14, 2011, at 2:06 AM, chandrashekar wrote:
>
> > Hi,
> >
> > I am able to fix Empty Print File error, but still facing issues in other component this time getting connection error: Permission denied during Connecting printer....
> > Please let me know the solution for the above problem, pasting logs below
> >
> > ] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/Generic-PostScript) from localhost
> > D [03/Jan/2000:09:22:26 +0530] cupsdSetBusyState: Dirty files
> > D [03/Jan/2000:09:22:26 +0530] cupsdReadClient: 11 POST /printers/Generic-PostScript HTTP/1.1
> > D [03/Jan/2000:09:22:26 +0530] cupsdSetBusyState: Active clients and dirty files
> > D [03/Jan/2000:09:22:26 +0530] cupsdAuthorize: No authentication data provided.
> > D [03/Jan/2000:09:22:26 +0530] cupsdReadClient: 11 1.1 Send-Document 1
> > D [03/Jan/2000:09:22:26 +0530] Send-Document ipp://localhost:631/printers/Generic-PostScript
> > D [03/Jan/2000:09:22:26 +0530] cupsdIsAuthorized: requesting-user-name="root"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] Auto-typing file...
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] Request file type is text/plain.
> > D [03/Jan/2000:09:22:26 +0530] cupsdMarkDirty(----J-)
> > I [03/Jan/2000:09:22:26 +0530] [Job 1] File of type text/plain queued by "root".
> > I [03/Jan/2000:09:22:26 +0530] [Job 1] Adding end banner page "none".
> > D [03/Jan/2000:09:22:26 +0530] cupsdMarkDirty(----J-)
> > D [03/Jan/2000:09:22:26 +0530] cupsdMarkDirty(----J-)
> > D [03/Jan/2000:09:22:26 +0530] cupsdSetBusyState: Active clients, printing jobs, and dirty files
> > D [03/Jan/2000:09:22:26 +0530] Discarding unused printer-state-changed event...
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] job-sheets=none,none
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] argv[0]="Generic-PostScript"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] argv[1]="1"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] argv[2]="root"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] argv[3]="File.txt"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] argv[4]="1"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:47dc9639-da07-3020-5ffa-e5f315399cbf job-originating-host-name=localhost time-at-creation=946871546 time-at-processing=946871546 AP_D_InputSlot="
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] argv[6]="/system/cups_shared/var/spool/cups/d00001-001"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[0]="CUPS_CACHEDIR=/system/cups_shared/var/cache/cups"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[1]="CUPS_DATADIR=/system/cups_shared/share/cups"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[2]="CUPS_DOCROOT=/system/cups_shared/share/doc/cups"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[3]="CUPS_FONTPATH=/system/cups_shared/share/cups/fonts"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[4]="CUPS_REQUESTROOT=/system/cups_shared/var/spool/cups"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[5]="CUPS_SERVERBIN=/system/cups_shared/lib/cups"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[6]="CUPS_SERVERROOT=/system/cups_shared/etc/cups"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[7]="CUPS_STATEDIR=/system/cups_shared/var/run/cups"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[8]="HOME=/system/cups_shared/var/spool/cups/tmp"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[9]="LD_LIBRARY_PATH=/vendor/lib:/system/lib"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[10]="PATH=/system/cups_shared/lib/cups/filter:/system/cups_shared/bin:/system/cups_shared/sbin:/bin:/usr/bin"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[11]="SERVER_ADMIN=root at localhost"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[12]="SOFTWARE=CUPS/1.4.6"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[13]="TMPDIR=/system/cups_shared/var/spool/cups/tmp"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[14]="USER=root"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[15]="CUPS_SERVER=/system/cups_shared/var/run/cups/cups.sock"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[16]="CUPS_ENCRYPTION=IfRequested"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[17]="IPP_PORT=631"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[18]="CHARSET=utf-8"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[19]="LANG=en_US.UTF-8"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[20]="PPD=/system/cups_shared/etc/cups/ppd/Generic-PostScript.ppd"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[21]="RIP_MAX_CACHE=8m"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[22]="CONTENT_TYPE=text/plain"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[23]="DEVICE_URI=ipp://107.108.210.93:631/PASSTHRU"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[24]="PRINTER_INFO=Generic-PostScript"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[25]="PRINTER_LOCATION="
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[26]="PRINTER=Generic-PostScript"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[27]="CUPS_FILETYPE=document"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] envp[28]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] Started filter /system/cups_shared/lib/cups/filter/texttops (PID 6485)
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] Started filter /system/cups_shared/lib/cups/filter/pstops (PID 6486)
> > I [03/Jan/2000:09:22:26 +0530] [Job 1] Started backend /system/cups_shared/lib/cups/backend/ipp (PID 6487)
> > D [03/Jan/2000:09:22:26 +0530] Discarding unused job-state-changed event...
> > D [03/Jan/2000:09:22:26 +0530] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/Generic-PostScript) from localhost
> > D [03/Jan/2000:09:22:26 +0530] cupsdSetBusyState: Printing jobs and dirty files
> > D [03/Jan/2000:09:22:26 +0530] cupsdReadClient: 11 WAITING Closing on EOF
> > D [03/Jan/2000:09:22:26 +0530] cupsdCloseClient: 11
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] STATE: +connecting-to-device
> > D [03/Jan/2000:09:22:26 +0530] Discarding unused printer-state-changed event...
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] Looking up "107.108.210.93"...
> > I [03/Jan/2000:09:22:26 +0530] [Job 1] Copying print data...
> > D [03/Jan/2000:09:22:26 +0530] Discarding unused job-progress event...
> > D [03/Jan/2000:09:22:26 +0530] Discarding unused printer-state-changed event...
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] backendRunLoop(print_fd=-1, device_fd=5, snmp_fd=-1, addr=0x14044, use_bc=0, side_cb=0xb4fd)
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] Page = 595x842; 18,36 to 577,806
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] ppd->num_fonts = 70
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] ppd->fonts[0] = AvantGarde-Book
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] ppd->fonts[1] = AvantGarde-BookOblique
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] ppd->fonts[2] = AvantGarde-Demi
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] ppd->fonts[3] = AvantGarde-DemiOblique
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] ppd->fonts[4] = Bookman-Demi
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] ppd->fonts[5] = Bookman-DemiItalic
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] ppd->fonts[6] = Bookman-Light
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] ppd->fonts[7] = Bookman-LightItalic
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] slow_collate=0, slow_duplex=0, slow_order=0
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] Before copy_comments - %!PS-Adobe-3.0
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] %!PS-Adobe-3.0
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] %%BoundingBox: 0 0 595 842
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] %cupsRotation: 0
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] %%Creator: texttops/CUPS v1.4.6
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] %%CreationDate: Mon Jan  3 03:52:26 2000
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] %%Title: (File.txt)
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] %%For: (root)
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] %%Pages: (atend)
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] %%DocumentNeededResources: font Monospace-Bold
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] %%+ font Monospace
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] %%DocumentSuppliedResources: procset texttops 1.1 0
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] %%+ font Monospace-Bold
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] %%+ font Monospace
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] %%EndComments
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] Before copy_prolog - %%BeginProlog
> > D [03/Jan/2000:09:22:26 +0530] PID 6485 (/system/cups_shared/lib/cups/filter/texttops) exited with no errors.
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] Read 1372 bytes of print data...
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] Wrote 1372 bytes of print data...
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] Wrote 1 pages...
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] Read 55 bytes of print data...
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] Wrote 55 bytes of print data...
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] 1 files to send in job...
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] STATE: +connecting-to-device
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] Connecting to 107.108.210.93:631
> > I [03/Jan/2000:09:22:26 +0530] [Job 1] Connecting to printer...
> > D [03/Jan/2000:09:22:26 +0530] [Job 1] Connection error: Permission denied
> > D [03/Jan/2000:09:22:26 +0530] cupsdMarkDirty(P-----)
> > D [03/Jan/2000:09:22:26 +0530] Discarding unused job-progress event...
> > D [03/Jan/2000:09:22:26 +0530] Discarding unused printer-state-changed event...
> > D [03/Jan/2000:09:22:26 +0530] PID 6486 (/system/cups_shared/lib/cups/filter/pstops) exited with no errors.
> > D [03/Jan/2000:09:22:56 +0530] [Job 1] Connecting to 107.108.210.93:631
> > I [03/Jan/2000:09:22:56 +0530] [Job 1] Connecting to printer...
> > D [03/Jan/2000:09:22:56 +0530] [Job 1] Connection error: Permission denied
> > I [03/Jan/2000:09:22:56 +0530] Saving printers.conf...
> > I [03/Jan/2000:09:22:56 +0530] Saving job cache file "/system/cups_shared/var/cache/cups/job.cache"...
> > D [03/Jan/2000:09:22:56 +0530] cupsdSetBusyState: Printing jobs
> > D [03/Jan/2000:09:22:56 +0530] Report: clients=0
> > D [03/Jan/2000:09:22:56 +0530] Report: jobs=1
> > D [03/Jan/2000:09:22:56 +0530] Report: jobs-active=1
> > D [03/Jan/2000:09:22:56 +0530] Report: printers=1
> > D [03/Jan/2000:09:22:56 +0530] Report: printers-implicit=0
> > D [03/Jan/2000:09:22:56 +0530] Report: stringpool-string-count=3796
> > D [03/Jan/2000:09:22:56 +0530] Report: stringpool-alloc-bytes=11232
> > D [03/Jan/2000:09:22:56 +0530] Report: stringpool-total-bytes=73520
> > D [03/Jan/2000:09:23:26 +0530] [Job 1] Connecting to 107.108.210.93:631
> > I [03/Jan/2000:09:23:26 +0530] [Job 1] Connecting to printer...
> > D [03/Jan/2000:09:23:26 +0530] [Job 1] Connection error: Permission denied
> > D [03/Jan/2000:09:23:56 +0530] [Job 1] Connecting to 107.108.210.93:631
> > I [03/Jan/2000:09:23:56 +0530] [Job 1] Connecting to printer...
> > D [03/Jan/2000:09:23:56 +0530] [Job 1] Connection error: Permission denied
> >
> >
> >
> >
> >
> >
> >
> >
> >
> >
> >> The spool directory cannot be read by the print filters.
> >>
> >>
> >> On Dec 13, 2011, at 4:02 AM, chandrashekar wrote:
> >>
> >>> Hi,
> >>>
> >>> I am getting Empty print file error. I am pasting entire log information. Can someone help me what has gone wrong. I am unable to figure it out from this log.
> >>>
> >>>
> >>>
> >>> E [02/Jan/2000:11:37:08 +0530] TMPDIR (/data/local/tmp) has the wrong permissions!
> >>> E [02/Jan/2000:12:07:04 +0530] TMPDIR (/system/cups_shared/var/spool/cups/tmp) has the wrong permissions!
> >>> E [02/Jan/2000:12:07:04 +0530] [Job 1] Empty print file!
> >>> E [02/Jan/2000:12:07:04 +0530] [Job 1] Empty print file!
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] The following messages were recorded from 12:07:04 to 12:07:04
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] job-sheets=none,none
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] argv[0]="Generic-PostScript"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] argv[1]="1"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] argv[2]="root"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] argv[3]="File.txt"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] argv[4]="1"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:47dc9639-da07-3020-5ffa-e5f315399cbf job-originating-host-name=localhost time-at-creation=946793376 time-at-processing=946795024 AP_D_InputSlot="
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] argv[6]="/system/cups_shared/var/spool/cups/d00001-001"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[0]="CUPS_CACHEDIR=/system/cups_shared/var/cache/cups"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[1]="CUPS_DATADIR=/system/cups_shared/share/cups"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[2]="CUPS_DOCROOT=/system/cups_shared/share/doc/cups"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[3]="CUPS_FONTPATH=/system/cups_shared/share/cups/fonts"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[4]="CUPS_REQUESTROOT=/system/cups_shared/var/spool/cups"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[5]="CUPS_SERVERBIN=/system/cups_shared/lib/cups"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[6]="CUPS_SERVERROOT=/system/cups_shared/etc/cups"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[7]="CUPS_STATEDIR=/system/cups_shared/var/run/cups"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[8]="HOME=/system/cups_shared/var/spool/cups/tmp"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[9]="LD_LIBRARY_PATH=/vendor/lib:/system/lib"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[10]="PATH=/system/cups_shared/lib/cups/filter:/system/cups_shared/bin:/system/cups_shared/sbin:/bin:/usr/bin"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[11]="SERVER_ADMIN=root at localhost"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[12]="SOFTWARE=CUPS/1.4.6"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[13]="TMPDIR=/system/cups_shared/var/spool/cups/tmp"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[14]="USER=root"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[15]="CUPS_SERVER=/system/cups_shared/var/run/cups/cups.sock"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[16]="CUPS_ENCRYPTION=IfRequested"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[17]="IPP_PORT=631"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[18]="CHARSET=utf-8"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[19]="LANG=en_US.UTF-8"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[20]="PPD=/system/cups_shared/etc/cups/ppd/Generic-PostScript.ppd"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[21]="RIP_MAX_CACHE=8m"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[22]="CONTENT_TYPE=text/plain"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[23]="DEVICE_URI=ipp://107.108.210.93:631/PASSTHRU"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[24]="PRINTER_INFO=Generic-PostScript"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[25]="PRINTER_LOCATION="
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[26]="PRINTER=Generic-PostScript"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[27]="CUPS_FILETYPE=document"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] envp[28]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] Started filter /system/cups_shared/lib/cups/filter/texttops (PID 5344)
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] Started filter /system/cups_shared/lib/cups/filter/pstops (PID 5345)
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] Started backend /system/cups_shared/lib/cups/backend/ipp (PID 5346)
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] unable to open print file - STATE: +connecting-to-device
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] Looking up "107.108.210.93"...
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] Copying print data...
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] backendRunLoop(print_fd=-1, device_fd=5, snmp_fd=-1, addr=0x14044, use_bc=0, side_cb=0xb4fd)
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] : Permission denied
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] Backend returned status 1 (failed)
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] Printer stopped due to backend errors; please consult the error_log file for details.
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] End of messages
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] printer-state=5(stopped)
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] printer-state-message="Empty print file!"
> >>> D [02/Jan/2000:12:07:04 +0530] [Job 1] printer-state-reasons=paused
> >>>
> >>> _______________________________________________
> >>> cups mailing list
> >>> cups at easysw.com
> >>> http://lists.easysw.com/mailman/listinfo/cups
> >>
> >> _________________________________________________________
> >> Michael Sweet, Senior Printing System Engineer, PWG Chair
> >>
> >
> > _______________________________________________
> > cups mailing list
> > cups at easysw.com
> > http://lists.easysw.com/mailman/listinfo/cups
>
> _________________________________________________________
> Michael Sweet, Senior Printing System Engineer, PWG Chair
>