NetBSD Problem Report #51245

From www@NetBSD.org  Wed Jun 15 07:56:36 2016
Return-Path: <www@NetBSD.org>
Received: from mail.netbsd.org (mail.netbsd.org [199.233.217.200])
	(using TLSv1.2 with cipher ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits))
	(Client CN "mail.netbsd.org", Issuer "Postmaster NetBSD.org" (verified OK))
	by mollari.NetBSD.org (Postfix) with ESMTPS id A9A0F7A217
	for <gnats-bugs@gnats.NetBSD.org>; Wed, 15 Jun 2016 07:56:36 +0000 (UTC)
Message-Id: <20160615075634.0DD4E7AAB1@mollari.NetBSD.org>
Date: Wed, 15 Jun 2016 07:56:34 +0000 (UTC)
From: topiarz@post.cz
Reply-To: topiarz@post.cz
To: gnats-bugs@NetBSD.org
Subject: cups-pdf does not print anything, but everything looks like it does
X-Send-Pr-Version: www-1.0

>Number:         51245
>Category:       pkg
>Synopsis:       cups-pdf does not print anything, but everything looks like it does
>Confidential:   no
>Severity:       non-critical
>Priority:       low
>Responsible:    pkg-manager
>State:          open
>Class:          sw-bug
>Submitter-Id:   net
>Arrival-Date:   Wed Jun 15 08:00:00 +0000 2016
>Closed-Date:    
>Last-Modified:  Sun Nov 20 01:49:47 +0000 2016
>Originator:     Petr Topiarz
>Release:        pkgsrc-current
>Organization:
Petr Topiarz
>Environment:
NetBSD T40.openunix.eu 6.1.5 NetBSD 6.1.5 (GENERIC) i386
>Description:
I installed cups and cups-pdf, (disabled lpr, enabled cupsd), then printed some content to the PDF printer, but nothing comes out. There is no pdf anywhere in /var/spool/* neither it is anywhere in /home/${user}. I even declared it in /usr/pkg/etc/cups-pdf.conf

T40$ cat /usr/pkg/etc/cups/cups-pdf.conf |grep Out 
### Key: Out
#Out /home/${USER}/PDF
Out /var/spool/cups-pdf/${USER}

Then I enabled debugging in cups, but cannot find where it hangs. Here is the output of /var/log/cups/error_log:


I [15/Jun/2016:09:41:11 +0200] Scheduler shutting down normally.
D [15/Jun/2016:09:41:11 +0200] Discarding unused server-stopped event...
D [15/Jun/2016:09:41:11 +0200] cupsdDeregisterPrinter(p=0xbb503800(mfp), removeit=1)
D [15/Jun/2016:09:41:11 +0200] cupsdDeregisterPrinter(p=0xbb504800(pdf), removeit=1)
X [15/Jun/2016:09:41:11 +0200] kevent() returned Bad file descriptor
I [15/Jun/2016:09:41:11 +0200] Saving job.cache...
I [15/Jun/2016:09:41:14 +0200] Remote access is disabled.
D [15/Jun/2016:09:41:14 +0200] Added auto ServerAlias T40.openunix.eu
D [15/Jun/2016:09:41:14 +0200] Added auto ServerAlias T40
I [15/Jun/2016:09:41:14 +0200] Loaded configuration file "/usr/pkg/etc/cups/cupsd.conf"
D [15/Jun/2016:09:41:14 +0200] Repairing access permissions of "/var/spool/cups"
I [15/Jun/2016:09:41:14 +0200] Using default TempDir of /var/spool/cups/tmp...
D [15/Jun/2016:09:41:14 +0200] Repairing access permissions of "/var/spool/cups/tmp"
I [15/Jun/2016:09:41:14 +0200] Configured for up to 100 clients.
I [15/Jun/2016:09:41:14 +0200] Allowing up to 100 client connections per host.
I [15/Jun/2016:09:41:14 +0200] Using policy "default" as the default.
I [15/Jun/2016:09:41:14 +0200] Full reload is required.
I [15/Jun/2016:09:41:14 +0200] Loaded MIME database from "/usr/pkg/share/cups/mime" and "/usr/pkg/etc/cups": 35 types, 42 filters...
D [15/Jun/2016:09:41:14 +0200] Loading printer mfp...
D [15/Jun/2016:09:41:14 +0200] load_ppd: Loading /var/cache/cups/mfp.data...
D [15/Jun/2016:09:41:14 +0200] cupsdRegisterPrinter(p=0xbb503800(mfp))
D [15/Jun/2016:09:41:14 +0200] load_ppd: Loading /var/cache/cups/mfp.data...
D [15/Jun/2016:09:41:14 +0200] cupsdRegisterPrinter(p=0xbb503800(mfp))
D [15/Jun/2016:09:41:14 +0200] Loading printer pdf...
D [15/Jun/2016:09:41:14 +0200] load_ppd: Loading /var/cache/cups/pdf.data...
D [15/Jun/2016:09:41:14 +0200] cupsdRegisterPrinter(p=0xbb504800(pdf))
I [15/Jun/2016:09:41:14 +0200] Loading job cache file "/var/cache/cups/job.cache"...
D [15/Jun/2016:09:41:14 +0200] [Job 1] Loading from cache...
D [15/Jun/2016:09:41:14 +0200] [Job 2] Loading from cache...
D [15/Jun/2016:09:41:14 +0200] [Job 3] Loading from cache...
D [15/Jun/2016:09:41:14 +0200] [Job 4] Loading from cache...
D [15/Jun/2016:09:41:14 +0200] [Job 5] Loading from cache...
I [15/Jun/2016:09:41:14 +0200] Full reload complete.
D [15/Jun/2016:09:41:14 +0200] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
I [15/Jun/2016:09:41:14 +0200] Cleaning out old files in "/var/spool/cups/tmp"...
D [15/Jun/2016:09:41:14 +0200] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
I [15/Jun/2016:09:41:14 +0200] Cleaning out old files in "/var/cache/cups"...
I [15/Jun/2016:09:41:14 +0200] Listening to [v1.::1]:631 on fd 7...
I [15/Jun/2016:09:41:14 +0200] Listening to 127.0.0.1:631 on fd 8...
I [15/Jun/2016:09:41:14 +0200] Listening to /var/run/cups/cups.sock:631 on fd 9...
I [15/Jun/2016:09:41:14 +0200] Resuming new connection processing...
D [15/Jun/2016:09:41:14 +0200] cupsdRegisterPrinter(p=0xbb503800(mfp))
D [15/Jun/2016:09:41:14 +0200] cupsdRegisterPrinter(p=0xbb504800(pdf))
D [15/Jun/2016:09:41:14 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [15/Jun/2016:09:41:14 +0200] Discarding unused server-started event...
D [15/Jun/2016:09:41:15 +0200] Report: clients=0
D [15/Jun/2016:09:41:15 +0200] Report: jobs=5
D [15/Jun/2016:09:41:15 +0200] Report: jobs-active=0
D [15/Jun/2016:09:41:15 +0200] Report: printers=2
D [15/Jun/2016:09:41:15 +0200] Report: printers-implicit=0
D [15/Jun/2016:09:41:15 +0200] Report: stringpool-string-count=2222
D [15/Jun/2016:09:41:15 +0200] Report: stringpool-alloc-bytes=11616
D [15/Jun/2016:09:41:15 +0200] Report: stringpool-total-bytes=43000
D [15/Jun/2016:09:41:17 +0200] cupsdAcceptClient: 13 from localhost (Domain)
D [15/Jun/2016:09:41:17 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [15/Jun/2016:09:41:17 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [15/Jun/2016:09:41:17 +0200] cupsdAuthorize: No authentication data provided.
D [15/Jun/2016:09:41:17 +0200] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
D [15/Jun/2016:09:41:17 +0200] CUPS-Get-Printers
D [15/Jun/2016:09:41:17 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [15/Jun/2016:09:41:17 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [15/Jun/2016:09:41:17 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [15/Jun/2016:09:41:17 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [15/Jun/2016:09:41:17 +0200] cupsdAuthorize: No authentication data provided.
D [15/Jun/2016:09:41:17 +0200] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
D [15/Jun/2016:09:41:17 +0200] CUPS-Get-Default
D [15/Jun/2016:09:41:17 +0200] CUPS-Get-Default client-error-not-found: No default printer.
D [15/Jun/2016:09:41:17 +0200] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [15/Jun/2016:09:41:17 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [15/Jun/2016:09:41:17 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [15/Jun/2016:09:41:17 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [15/Jun/2016:09:41:17 +0200] cupsdAuthorize: No authentication data provided.
D [15/Jun/2016:09:41:17 +0200] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
D [15/Jun/2016:09:41:17 +0200] CUPS-Get-Printers
D [15/Jun/2016:09:41:17 +0200] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [15/Jun/2016:09:41:17 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [15/Jun/2016:09:41:17 +0200] cupsdReadClient: 13 POST / HTTP/1.1
D [15/Jun/2016:09:41:17 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [15/Jun/2016:09:41:17 +0200] cupsdAuthorize: No authentication data provided.
D [15/Jun/2016:09:41:17 +0200] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
D [15/Jun/2016:09:41:17 +0200] CUPS-Get-Default
D [15/Jun/2016:09:41:17 +0200] CUPS-Get-Default client-error-not-found: No default printer.
D [15/Jun/2016:09:41:17 +0200] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [15/Jun/2016:09:41:17 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [15/Jun/2016:09:41:25 +0200] cupsdAcceptClient: 14 from localhost (Domain)
D [15/Jun/2016:09:41:25 +0200] cupsdReadClient: 14 POST / HTTP/1.1
D [15/Jun/2016:09:41:25 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [15/Jun/2016:09:41:25 +0200] cupsdAuthorize: No authentication data provided.
D [15/Jun/2016:09:41:25 +0200] cupsdReadClient: 14 1.1 Get-Printer-Attributes 1
D [15/Jun/2016:09:41:25 +0200] Get-Printer-Attributes ipp://localhost:631/printers/pdf
D [15/Jun/2016:09:41:25 +0200] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/pdf) from localhost
D [15/Jun/2016:09:41:25 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [15/Jun/2016:09:41:25 +0200] cupsdReadClient: 14 POST /printers/pdf HTTP/1.1
D [15/Jun/2016:09:41:25 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [15/Jun/2016:09:41:25 +0200] cupsdAuthorize: No authentication data provided.
D [15/Jun/2016:09:41:25 +0200] cupsdReadClient: 14 1.1 Create-Job 1
D [15/Jun/2016:09:41:25 +0200] Create-Job ipp://localhost:631/printers/pdf
D [15/Jun/2016:09:41:25 +0200] cupsdMarkDirty(----J-)
D [15/Jun/2016:09:41:25 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [15/Jun/2016:09:41:25 +0200] add_job: requesting-user-name="beowulf"
I [15/Jun/2016:09:41:25 +0200] [Job 6] Adding start banner page "none".
D [15/Jun/2016:09:41:25 +0200] Discarding unused job-created event...
I [15/Jun/2016:09:41:25 +0200] [Job 6] Queued on "pdf" by "beowulf".
D [15/Jun/2016:09:41:25 +0200] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/pdf) from localhost
D [15/Jun/2016:09:41:25 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [15/Jun/2016:09:41:25 +0200] cupsdReadClient: 14 POST /printers/pdf HTTP/1.1
D [15/Jun/2016:09:41:25 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [15/Jun/2016:09:41:25 +0200] cupsdAuthorize: No authentication data provided.
D [15/Jun/2016:09:41:25 +0200] cupsdReadClient: 14 1.1 Send-Document 1
D [15/Jun/2016:09:41:25 +0200] Send-Document ipp://localhost:631/printers/pdf
D [15/Jun/2016:09:41:25 +0200] cupsdIsAuthorized: requesting-user-name="beowulf"
D [15/Jun/2016:09:41:25 +0200] [Job 6] Auto-typing file...
D [15/Jun/2016:09:41:25 +0200] [Job 6] Request file type is application/postscript.
D [15/Jun/2016:09:41:25 +0200] cupsdMarkDirty(----J-)
D [15/Jun/2016:09:41:25 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [15/Jun/2016:09:41:25 +0200] [Job 6] File of type application/postscript queued by "beowulf".
I [15/Jun/2016:09:41:25 +0200] [Job 6] Adding end banner page "none".
D [15/Jun/2016:09:41:25 +0200] cupsdMarkDirty(----J-)
D [15/Jun/2016:09:41:25 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [15/Jun/2016:09:41:25 +0200] cupsdMarkDirty(----J-)
D [15/Jun/2016:09:41:25 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [15/Jun/2016:09:41:25 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [15/Jun/2016:09:41:25 +0200] Discarding unused printer-state-changed event...
D [15/Jun/2016:09:41:25 +0200] [Job 6] job-sheets=none,none
D [15/Jun/2016:09:41:25 +0200] [Job 6] argv[0]="pdf"
D [15/Jun/2016:09:41:25 +0200] [Job 6] argv[1]="6"
D [15/Jun/2016:09:41:25 +0200] [Job 6] argv[2]="beowulf"
D [15/Jun/2016:09:41:25 +0200] [Job 6] argv[3]="(stdin)"
D [15/Jun/2016:09:41:25 +0200] [Job 6] argv[4]="1"
D [15/Jun/2016:09:41:25 +0200] [Job 6] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:d5779068-7c0d-385f-5e24-5f5561c4d6b9 job-originating-host-name=localhost time-at-creation=1465976485 time-at-processing=1465976485"
D [15/Jun/2016:09:41:25 +0200] [Job 6] argv[6]="/var/spool/cups/d00006-001"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[1]="CUPS_DATADIR=/usr/pkg/share/cups"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[2]="CUPS_DOCROOT=/usr/pkg/share/doc/cups"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[3]="CUPS_FONTPATH=/usr/pkg/share/cups/fonts"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[5]="CUPS_SERVERBIN=/usr/pkg/libexec/cups"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[6]="CUPS_SERVERROOT=/usr/pkg/etc/cups"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[8]="HOME=/var/spool/cups/tmp"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[9]="PATH=/usr/pkg/libexec/cups/filter:/usr/pkg/bin:/usr/pkg/sbin:/bin:/usr/bin"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[10]="SERVER_ADMIN=root@T40.openunix.eu"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[11]="SOFTWARE=CUPS/1.5.4"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[13]="USER=root"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[14]="CUPS_MAX_MESSAGE=2047"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[17]="IPP_PORT=631"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[18]="CHARSET=utf-8"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[19]="LANG=cs_CZ.UTF-8"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[20]="PPD=/usr/pkg/etc/cups/ppd/pdf.ppd"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[21]="RIP_MAX_CACHE=128m"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[22]="CONTENT_TYPE=application/postscript"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[23]="DEVICE_URI=cups-pdf:/"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[24]="PRINTER_INFO=pdf"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[25]="PRINTER_LOCATION="
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[26]="PRINTER=pdf"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[27]="PRINTER_STATE_REASONS=none"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[28]="CUPS_FILETYPE=document"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
D [15/Jun/2016:09:41:25 +0200] [Job 6] envp[30]="AUTH_I****"
I [15/Jun/2016:09:41:25 +0200] [Job 6] Started filter /usr/pkg/libexec/cups/filter/pstops (PID 1060)
I [15/Jun/2016:09:41:25 +0200] [Job 6] Started backend /usr/pkg/libexec/cups/backend/cups-pdf (PID 2776)
D [15/Jun/2016:09:41:26 +0200] Discarding unused job-state-changed event...
D [15/Jun/2016:09:41:26 +0200] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/pdf) from localhost
D [15/Jun/2016:09:41:26 +0200] PID 1060 (/usr/pkg/libexec/cups/filter/pstops) exited with no errors.
D [15/Jun/2016:09:41:26 +0200] PID 2776 (/usr/pkg/libexec/cups/backend/cups-pdf) exited with no errors.
D [15/Jun/2016:09:41:26 +0200] [Job 6] Page = 595x842; 0,0 to 595,842
D [15/Jun/2016:09:41:26 +0200] [Job 6] slow_collate=0, slow_duplex=0, slow_order=0
D [15/Jun/2016:09:41:26 +0200] [Job 6] Before copy_comments - %!PS-Adobe-3.0
D [15/Jun/2016:09:41:26 +0200] [Job 6] %!PS-Adobe-3.0
D [15/Jun/2016:09:41:26 +0200] [Job 6] %%Creator: cairo 1.14.6 (http://cairographics.org)
D [15/Jun/2016:09:41:26 +0200] [Job 6] %%CreationDate: Wed Jun 15 09:41:25 2016
D [15/Jun/2016:09:41:26 +0200] [Job 6] %%Pages: 1
D [15/Jun/2016:09:41:26 +0200] [Job 6] %%DocumentData: Clean7Bit
D [15/Jun/2016:09:41:26 +0200] [Job 6] %%LanguageLevel: 2
D [15/Jun/2016:09:41:26 +0200] [Job 6] %%DocumentMedia: A4 595 842 0 () ()
D [15/Jun/2016:09:41:26 +0200] [Job 6] %%Orientation: Portrait
D [15/Jun/2016:09:41:26 +0200] [Job 6] %%BoundingBox: 18 41 577 821
D [15/Jun/2016:09:41:26 +0200] [Job 6] %%EndComments
D [15/Jun/2016:09:41:26 +0200] [Job 6] Before copy_prolog - %%BeginProlog
D [15/Jun/2016:09:41:26 +0200] [Job 6] Before copy_setup - %%BeginSetup
D [15/Jun/2016:09:41:26 +0200] [Job 6] Before page loop - %%Page: 1 1
D [15/Jun/2016:09:41:26 +0200] [Job 6] Copying page 1...
D [15/Jun/2016:09:41:26 +0200] [Job 6] PAGE: 1 1
D [15/Jun/2016:09:41:26 +0200] Discarding unused job-progress event...
D [15/Jun/2016:09:41:26 +0200] [Job 6] pagew = 595.0, pagel = 842.0
D [15/Jun/2016:09:41:26 +0200] [Job 6] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [15/Jun/2016:09:41:26 +0200] [Job 6] PageLeft = 0.0, PageRight = 595.0
D [15/Jun/2016:09:41:26 +0200] [Job 6] PageTop = 842.0, PageBottom = 0.0
D [15/Jun/2016:09:41:26 +0200] [Job 6] PageWidth = 595.0, PageLength = 842.0
D [15/Jun/2016:09:41:26 +0200] [Job 6] cups-pdf cannot be called without root privileges!
D [15/Jun/2016:09:41:26 +0200] [Job 6] Wrote 1 pages...
D [15/Jun/2016:09:41:26 +0200] Discarding unused job-completed event...
I [15/Jun/2016:09:41:26 +0200] [Job 6] Job completed.
D [15/Jun/2016:09:41:26 +0200] cupsdMarkDirty(----J-)
D [15/Jun/2016:09:41:26 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
D [15/Jun/2016:09:41:26 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Jun/2016:09:41:26 +0200] Discarding unused printer-state-changed event...
D [15/Jun/2016:09:41:26 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Jun/2016:09:41:26 +0200] cupsdReadClient: 14 WAITING Closing on EOF
D [15/Jun/2016:09:41:26 +0200] cupsdCloseClient: 14
D [15/Jun/2016:09:41:26 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [15/Jun/2016:09:41:27 +0200] [Job 6] Unloading...
T40# ls /var/spool/                                                                                                                      
cups-pdf/  cups/      ftp/       lock/      output/    postfix/   sockets/   
T40# ls /var/spool/cups-pdf/                                                                                                             

>How-To-Repeat:
install /usr/pkgsrc/cups/cups-pdf
disable /usr/bin/lp*
start cupsd
lpadmin -p pdf -v cups-pdf:/ -E -P /usr/pkg/share/cups/model/CUPS-PDF.ppd 
mkdir /var/spool/cups-pdf
chmod -R 777 /var/spool/cups-pdf/


>Fix:
No idea how to fix that... :-(

>Release-Note:

>Audit-Trail:
From: Thomas Klausner <wiz@NetBSD.org>
To: NetBSD bugtracking <gnats-bugs@NetBSD.org>
Cc: 
Subject: Re: pkg/51245
Date: Thu, 22 Sep 2016 14:18:29 +0200

 ----- Forwarded message from Stephan <stephanwib@googlemail.com> -----

 Date: Thu, 22 Sep 2016 14:00:00 +0200
 From: Stephan <stephanwib@googlemail.com>
 To: tech-pkg@netbsd.org
 Subject: Solution to pkg/51245: cups-pdf does not print anything

 Hi

 The cause of this issue is that /usr/pkg/libexec/cups/backend/cups-pdf
 needs 700 permission in order to work.

 # ls -l /usr/pkg/libexec/cups/backend/cups-pdf
 -rwx------  1 root  wheel  24408 Jul  9 07:03
 /usr/pkg/libexec/cups/backend/cups-pdf

 It is installed with 755 by default. That could be changed in the
 package (if not in the application, I have no time to look into why it
 behaves like this).


 Regards,

 Stephan


 ----- End forwarded message -----

State-Changed-From-To: open->feedback
State-Changed-By: wiz@NetBSD.org
State-Changed-When: Thu, 22 Sep 2016 12:42:41 +0000
State-Changed-Why:
Please try the chmod and let us know if it fixes the problem. Thanks!


From: Petr Topiarz <topiarz@post.cz>
To: gnats-bugs@NetBSD.org
Cc: 
Subject: Re: pkg/51245
Date: Thu, 22 Sep 2016 20:25:51 +0200

 I tried it, and there is some progress. It creates a PDF document that 
 has 2.2kb but is empty.

 Here is what "file" thinks about it:

 x61s$ file PDF/_stdin_.pdf
 PDF/_stdin_.pdf: PDF document, version 1.4

 I used this PPD to add the printer:

 /usr/pkg/share/cups/model/CUPS-PDF.ppd <

 Any idea where to search for the culprit?

 Petr


 On 09/22/16 14:20, Thomas Klausner wrote:
 > The following reply was made to PR pkg/51245; it has been noted by GNATS.
 >
 > From: Thomas Klausner <wiz@NetBSD.org>
 > To: NetBSD bugtracking <gnats-bugs@NetBSD.org>
 > Cc:
 > Subject: Re: pkg/51245
 > Date: Thu, 22 Sep 2016 14:18:29 +0200
 >
 >   ----- Forwarded message from Stephan <stephanwib@googlemail.com> -----
 >   
 >   Date: Thu, 22 Sep 2016 14:00:00 +0200
 >   From: Stephan <stephanwib@googlemail.com>
 >   To: tech-pkg@netbsd.org
 >   Subject: Solution to pkg/51245: cups-pdf does not print anything
 >   
 >   Hi
 >   
 >   The cause of this issue is that /usr/pkg/libexec/cups/backend/cups-pdf
 >   needs 700 permission in order to work.
 >   
 >   # ls -l /usr/pkg/libexec/cups/backend/cups-pdf
 >   -rwx------  1 root  wheel  24408 Jul  9 07:03
 >   /usr/pkg/libexec/cups/backend/cups-pdf
 >   
 >   It is installed with 755 by default. That could be changed in the
 >   package (if not in the application, I have no time to look into why it
 >   behaves like this).
 >   
 >   
 >   Regards,
 >   
 >   Stephan
 >   
 >   
 >   ----- End forwarded message -----
 >   

State-Changed-From-To: feedback->open
State-Changed-By: dholland@NetBSD.org
State-Changed-When: Sun, 20 Nov 2016 01:49:47 +0000
State-Changed-Why:
feedback was received (immediately, two months back; sigh) and it helped
but wasn't sufficient.


>Unformatted:

NetBSD Home
NetBSD PR Database Search

(Contact us) $NetBSD: query-full-pr,v 1.39 2013/11/01 18:47:49 spz Exp $
$NetBSD: gnats_config.sh,v 1.8 2006/05/07 09:23:38 tsutsui Exp $
Copyright © 1994-2014 The NetBSD Foundation, Inc. ALL RIGHTS RESERVED.