GNU logs - #29219, boring messages

Message sent to bug-guix@HIDDEN:

Subject: bug#29219: CUPS filter still can't access Ghostscript (gs)
Resent-From: Mekeor Melire <mekeor.melire@HIDDEN>
Original-Sender: "Debbugs-submit" <debbugs-submit-bounces <at> debbugs.gnu.org>
Resent-CC: bug-guix@HIDDEN
Resent-Date: Wed, 08 Nov 2017 20:52:02 +0000
Resent-Message-ID: <handler.29219.B.151017426720361 <at> debbugs.gnu.org>
Resent-Sender: help-debbugs@HIDDEN
To: 29219 <at> debbugs.gnu.org
From: Mekeor Melire <mekeor.melire@HIDDEN>
Date: Wed, 08 Nov 2017 21:50:52 +0100
Message-ID: <87efp8fqab.fsf@HIDDEN>
MIME-Version: 1.0
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: 8bit
Precedence: list
Errors-To: debbugs-submit-bounces <at> debbugs.gnu.org
Sender: "Debbugs-submit" <debbugs-submit-bounces <at> debbugs.gnu.org>


I added two printers through CUPS' web interface (localhost:631) using
the PPD driver file which I downloaded (from openprinting.org): one
called ljet4 (which calls Ghostscript) and one called Postscript (which
just calls `cat`)⁰. The latter worked.


But although I was expecting commit
1728c411718e3b358c06561d6e80b47d7b331617¹ to fix this issue, when I try
the ljet4² driver, I still get this error in /var/log/cups/error_log³:


    sh: gs: command not found
    Process is dying with \"Unable to determine number of pages, page count: -1
    \", exit stat 3


Is anybody able to reproduce this issue?

How can I be sure that the cups-service I'm running is the recent one
which includes that commit? (I tried pulling guix and rebuilding the
system afterwards and restarting cups-service afterwards.)

What am I doing wrong?


⁰: https://www.openprinting.org/ppd-o-matic.php?driver=Postscript&printer=Kyocera-FS-920&show=1
¹: https://git.savannah.gnu.org/cgit/guix.git/commit/?id=1728c411718e3b358c06561d6e80b47d7b331617
²: https://www.openprinting.org/ppd-o-matic.php?driver=ljet4&printer=Kyocera-FS-920&show=1
³: http://sprunge.us/EMbF


--
mekeor ~ EDD3 DFFA 76F6 11C0 145F 9A99 AC85 BAD8 A2F8 C868

Message sent:

Content-Disposition: inline
Content-Transfer-Encoding: quoted-printable
MIME-Version: 1.0
X-Mailer: MIME-tools 5.505 (Entity 5.505)
Content-Type: text/plain; charset=utf-8
X-Loop: help-debbugs@HIDDEN
From: help-debbugs@HIDDEN (GNU bug Tracking System)
To: Mekeor Melire <mekeor.melire@HIDDEN>
Subject: bug#29219: Acknowledgement (CUPS filter still can't access
 Ghostscript (gs))
Message-ID: <handler.29219.B.151017426720361.ack <at> debbugs.gnu.org>
References: <87efp8fqab.fsf@HIDDEN>
X-Gnu-PR-Message: ack 29219
X-Gnu-PR-Package: guix
Reply-To: 29219 <at> debbugs.gnu.org
Date: Wed, 08 Nov 2017 20:52:02 +0000

Thank you for filing a new bug report with debbugs.gnu.org.

This is an automatically generated reply to let you know your message
has been received.

Your message is being forwarded to the package maintainers and other
interested parties for their attention; they will reply in due course.

Your message has been sent to the package maintainer(s):
 bug-guix@HIDDEN

If you wish to submit further information on this problem, please
send it to 29219 <at> debbugs.gnu.org.

Please do not send mail to help-debbugs@HIDDEN unless you wish
to report a problem with the Bug-tracking system.

--=20
29219: http://debbugs.gnu.org/cgi/bugreport.cgi?bug=3D29219
GNU Bug Tracking System
Contact help-debbugs@HIDDEN with problems

Message sent to bug-guix@HIDDEN:

Subject: bug#29219: CUPS filter still can't access Ghostscript (gs)
Resent-From: ludo@HIDDEN (Ludovic =?UTF-8?Q?Court=C3=A8s?=)
Original-Sender: "Debbugs-submit" <debbugs-submit-bounces <at> debbugs.gnu.org>
Resent-CC: bug-guix@HIDDEN
Resent-Date: Thu, 09 Nov 2017 09:06:02 +0000
Resent-Message-ID: <handler.29219.B29219.15102183468457 <at> debbugs.gnu.org>
Resent-Sender: help-debbugs@HIDDEN
To: Mekeor Melire <mekeor.melire@HIDDEN>
Cc: 29219 <at> debbugs.gnu.org
From: ludo@HIDDEN (Ludovic =?UTF-8?Q?Court=C3=A8s?=)
References: <87efp8fqab.fsf@HIDDEN>
Date: Thu, 09 Nov 2017 10:05:42 +0100
In-Reply-To: <87efp8fqab.fsf@HIDDEN> (Mekeor Melire's message of "Wed, 08
 Nov 2017 21:50:52 +0100")
Message-ID: <87r2t7q0t5.fsf@HIDDEN>
User-Agent: Gnus/5.13 (Gnus v5.13) Emacs/25.3 (gnu/linux)
MIME-Version: 1.0
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: quoted-printable
Precedence: list
Errors-To: debbugs-submit-bounces <at> debbugs.gnu.org
Sender: "Debbugs-submit" <debbugs-submit-bounces <at> debbugs.gnu.org>

Hi,

Mekeor Melire <mekeor.melire@HIDDEN> skribis:

> I added two printers through CUPS' web interface (localhost:631) using
> the PPD driver file which I downloaded (from openprinting.org): one
> called ljet4 (which calls Ghostscript) and one called Postscript (which
> just calls `cat`)=E2=81=B0. The latter worked.
>
>
> But although I was expecting commit
> 1728c411718e3b358c06561d6e80b47d7b331617=C2=B9 to fix this issue, when I =
try
> the ljet4=C2=B2 driver, I still get this error in /var/log/cups/error_log=
=C2=B3:
>
>
>     sh: gs: command not found
>     Process is dying with \"Unable to determine number of pages, page cou=
nt: -1
>     \", exit stat 3
>
>
> Is anybody able to reproduce this issue?
>
> How can I be sure that the cups-service I'm running is the recent one
> which includes that commit? (I tried pulling guix and rebuilding the
> system afterwards and restarting cups-service afterwards.)

Just make sure to reconfigure from a recent-enough commit (run =E2=80=98guix
--version=E2=80=99 as root to see which commit you=E2=80=99re using.)

If it turns out you=E2=80=99re already using CUPS with commit
1728c411718e3b358c06561d6e80b47d7b331617, could you reproduce the issue
while running =E2=80=9Cstrace -p PID -f -s 345 -o log=E2=80=9D, where PID i=
s the PID of
cupsd as shown by =E2=80=98herd status cups=E2=80=99?

TIA!

Ludo=E2=80=99.

Message sent to bug-guix@HIDDEN:

Subject: bug#29219: CUPS filter still can't access Ghostscript (gs)
Resent-From: Mekeor Melire <mekeor.melire@HIDDEN>
Original-Sender: "Debbugs-submit" <debbugs-submit-bounces <at> debbugs.gnu.org>
Resent-CC: bug-guix@HIDDEN
Resent-Date: Thu, 09 Nov 2017 19:20:01 +0000
Resent-Message-ID: <handler.29219.B29219.151025516716886 <at> debbugs.gnu.org>
Resent-Sender: help-debbugs@HIDDEN
To: Ludovic =?UTF-8?Q?Court=C3=A8s?= <ludo@HIDDEN>
Cc: 29219 <at> debbugs.gnu.org
References: <87efp8fqab.fsf@HIDDEN> <87r2t7q0t5.fsf@HIDDEN>
From: Mekeor Melire <mekeor.melire@HIDDEN>
In-reply-to: <87r2t7q0t5.fsf@HIDDEN>
Date: Thu, 09 Nov 2017 20:19:17 +0100
Message-ID: <87a7zvfefe.fsf@HIDDEN>
MIME-Version: 1.0
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: 8bit
Precedence: list
Errors-To: debbugs-submit-bounces <at> debbugs.gnu.org
Sender: "Debbugs-submit" <debbugs-submit-bounces <at> debbugs.gnu.org>


Ludovic Courtès <ludo@HIDDEN> writes:

> Mekeor Melire <mekeor.melire@HIDDEN> skribis:

>> But although I was expecting commit
>> 1728c411718e3b358c06561d6e80b47d7b331617¹ to fix this issue, when I try
>> the ljet4² driver, I still get this error in /var/log/cups/error_log³:

>>     sh: gs: command not found
>>     Process is dying with \"Unable to determine number of pages, page count: -1
>>     \", exit stat 3

> Just make sure to reconfigure from a recent-enough commit (run ‘guix
> --version’ as root to see which commit you’re using.)

Done. I'm running a recent-enough commit. (Namely
46dea1241c801af5aa65e0c7b4e84bb0aff11273.)

> If it turns out you’re already using CUPS with commit
> 1728c411718e3b358c06561d6e80b47d7b331617, could you reproduce the issue
> while running “strace -p PID -f -s 345 -o log”, where PID is the PID of
> cupsd as shown by ‘herd status cups’?

You can find the log here: <http://sprunge.us/UDOY>. These might be the
interesting lines:

    $ grep -E '[^a-zA-Z0-9]gs' log
    31377 execve("/gnu/store/…-bash-static-4.4.12/bin/sh", ["sh", "-c", "gs -dNODISPLAY -q -c '/pdffile (/var/spool/cups/tmp/foomatic-4ngnEr) (r) file def pdfdict begin pdffile pdfopen begin (PageCount: ) print pdfpagecount == flush currentdict pdfclose end end quit'"], 0x149e810 /* 33 vars */ <unfinished ...>
    31377 stat("/gnu/store/…-cups-server-bin/lib/cups/filter/gs", 0x7ffd7921b5c0) = -1 ENOENT (No such file or directory)
    31377 stat("/gnu/store/…-cups-2.2.4/bin/gs", 0x7ffd7921b5c0) = -1 ENOENT (No such file or directory)
    31377 stat("/gnu/store/…-cups-2.2.4/sbin/gs", 0x7ffd7921b5c0) = -1 ENOENT (No such file or directory)
    31377 stat("/bin/gs", 0x7ffd7921b5c0)   = -1 ENOENT (No such file or directory)
    31377 stat("/usr/bin/gs", 0x7ffd7921b5c0) = -1 ENOENT (No such file or directory)
    31377 write(2, "sh: gs: command not found\n", 26) = 26
    15154 <... read resumed> "sh: gs: command not found\n", 2047) = 26
    15154 write(4, "D [09/Nov/2017:20:06:50 +0100] [Job 13] sh: gs: command not found\n", 66) = 66

Thank you for your help! How could we continue debugging?

--
mekeor ~ EDD3 DFFA 76F6 11C0 145F 9A99 AC85 BAD8 A2F8 C868

Message sent to bug-guix@HIDDEN:

X-Loop: help-debbugs@HIDDEN
Subject: bug#29219: CUPS filter still can't access Ghostscript (gs)
Resent-From: ludo@HIDDEN (Ludovic =?UTF-8?Q?Court=C3=A8s?=)
Original-Sender: "Debbugs-submit" <debbugs-submit-bounces <at> debbugs.gnu.org>
Resent-CC: bug-guix@HIDDEN
Resent-Date: Fri, 10 Nov 2017 12:23:01 +0000
Resent-Message-ID: <handler.29219.B29219.151031656324538 <at> debbugs.gnu.org>
Resent-Sender: help-debbugs@HIDDEN
X-GNU-PR-Message: followup 29219
X-GNU-PR-Package: guix
X-GNU-PR-Keywords: 
To: Mekeor Melire <mekeor.melire@HIDDEN>
Cc: 29219 <at> debbugs.gnu.org
Received: via spool by 29219-submit <at> debbugs.gnu.org id=B29219.151031656324538
          (code B ref 29219); Fri, 10 Nov 2017 12:23:01 +0000
Received: (at 29219) by debbugs.gnu.org; 10 Nov 2017 12:22:43 +0000
Received: from localhost ([127.0.0.1]:33281 helo=debbugs.gnu.org)
	by debbugs.gnu.org with esmtp (Exim 4.84_2)
	(envelope-from <debbugs-submit-bounces <at> debbugs.gnu.org>)
	id 1eD8KZ-0006Ni-A1
	for submit <at> debbugs.gnu.org; Fri, 10 Nov 2017 07:22:43 -0500
Received: from hera.aquilenet.fr ([141.255.128.1]:52530)
 by debbugs.gnu.org with esmtp (Exim 4.84_2)
 (envelope-from <ludo@HIDDEN>) id 1eD8KX-0006Na-HI
 for 29219 <at> debbugs.gnu.org; Fri, 10 Nov 2017 07:22:42 -0500
Received: from localhost (localhost [127.0.0.1])
 by hera.aquilenet.fr (Postfix) with ESMTP id ABEA0FCC2;
 Fri, 10 Nov 2017 13:22:42 +0100 (CET)
X-Virus-Scanned: Debian amavisd-new at aquilenet.fr
Received: from hera.aquilenet.fr ([127.0.0.1])
 by localhost (hera.aquilenet.fr [127.0.0.1]) (amavisd-new, port 10024)
 with ESMTP id yHHy5jdyvYRv; Fri, 10 Nov 2017 13:22:41 +0100 (CET)
Received: from ribbon (unknown [193.50.110.148])
 by hera.aquilenet.fr (Postfix) with ESMTPSA id 854A6FC68;
 Fri, 10 Nov 2017 13:22:41 +0100 (CET)
From: ludo@HIDDEN (Ludovic =?UTF-8?Q?Court=C3=A8s?=)
References: <87efp8fqab.fsf@HIDDEN> <87r2t7q0t5.fsf@HIDDEN>
 <87a7zvfefe.fsf@HIDDEN>
X-URL: http://www.fdn.fr/~lcourtes/
X-Revolutionary-Date: 20 Brumaire an 226 de la =?UTF-8?Q?R=C3=A9volution?=
X-PGP-Key-ID: 0x090B11993D9AEBB5
X-PGP-Key: http://www.fdn.fr/~lcourtes/ludovic.asc
X-PGP-Fingerprint: 3CE4 6455 8A84 FDC6 9DB4  0CFB 090B 1199 3D9A EBB5
X-OS: x86_64-pc-linux-gnu
Date: Fri, 10 Nov 2017 13:22:39 +0100
In-Reply-To: <87a7zvfefe.fsf@HIDDEN> (Mekeor Melire's message of "Thu, 09
 Nov 2017 20:19:17 +0100")
Message-ID: <871sl6migg.fsf@HIDDEN>
User-Agent: Gnus/5.13 (Gnus v5.13) Emacs/25.3 (gnu/linux)
MIME-Version: 1.0
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: quoted-printable
X-Spam-Score: 1.0 (+)
X-BeenThere: debbugs-submit <at> debbugs.gnu.org
X-Mailman-Version: 2.1.18
Precedence: list
List-Id: <debbugs-submit.debbugs.gnu.org>
List-Unsubscribe: <https://debbugs.gnu.org/cgi-bin/mailman/options/debbugs-submit>, 
 <mailto:debbugs-submit-request <at> debbugs.gnu.org?subject=unsubscribe>
List-Archive: <https://debbugs.gnu.org/cgi-bin/mailman/private/debbugs-submit/>
List-Post: <mailto:debbugs-submit <at> debbugs.gnu.org>
List-Help: <mailto:debbugs-submit-request <at> debbugs.gnu.org?subject=help>
List-Subscribe: <https://debbugs.gnu.org/cgi-bin/mailman/listinfo/debbugs-submit>, 
 <mailto:debbugs-submit-request <at> debbugs.gnu.org?subject=subscribe>
Errors-To: debbugs-submit-bounces <at> debbugs.gnu.org
Sender: "Debbugs-submit" <debbugs-submit-bounces <at> debbugs.gnu.org>
X-Spam-Score: 1.0 (+)

Hi Mekeor,

Mekeor Melire <mekeor.melire@HIDDEN> skribis:

> Ludovic Court=C3=A8s <ludo@HIDDEN> writes:
>
>> Mekeor Melire <mekeor.melire@HIDDEN> skribis:
>
>>> But although I was expecting commit
>>> 1728c411718e3b358c06561d6e80b47d7b331617=C2=B9 to fix this issue, when =
I try
>>> the ljet4=C2=B2 driver, I still get this error in /var/log/cups/error_l=
og=C2=B3:
>
>>>     sh: gs: command not found
>>>     Process is dying with \"Unable to determine number of pages, page c=
ount: -1
>>>     \", exit stat 3
>
>> Just make sure to reconfigure from a recent-enough commit (run =E2=80=98=
guix
>> --version=E2=80=99 as root to see which commit you=E2=80=99re using.)
>
> Done. I'm running a recent-enough commit. (Namely
> 46dea1241c801af5aa65e0c7b4e84bb0aff11273.)
>
>> If it turns out you=E2=80=99re already using CUPS with commit
>> 1728c411718e3b358c06561d6e80b47d7b331617, could you reproduce the issue
>> while running =E2=80=9Cstrace -p PID -f -s 345 -o log=E2=80=9D, where PI=
D is the PID of
>> cupsd as shown by =E2=80=98herd status cups=E2=80=99?
>
> You can find the log here: <http://sprunge.us/UDOY>. These might be the
> interesting lines:
>
>     $ grep -E '[^a-zA-Z0-9]gs' log
>     31377 execve("/gnu/store/=E2=80=A6-bash-static-4.4.12/bin/sh", ["sh",=
 "-c", "gs -dNODISPLAY -q -c '/pdffile (/var/spool/cups/tmp/foomatic-4ngnEr=
) (r) file def pdfdict begin pdffile pdfopen begin (PageCount: ) print pdfp=
agecount =3D=3D flush currentdict pdfclose end end quit'"], 0x149e810 /* 33=
 vars */ <unfinished ...>
>     31377 stat("/gnu/store/=E2=80=A6-cups-server-bin/lib/cups/filter/gs",=
 0x7ffd7921b5c0) =3D -1 ENOENT (No such file or directory)
>     31377 stat("/gnu/store/=E2=80=A6-cups-2.2.4/bin/gs", 0x7ffd7921b5c0) =
=3D -1 ENOENT (No such file or directory)
>     31377 stat("/gnu/store/=E2=80=A6-cups-2.2.4/sbin/gs", 0x7ffd7921b5c0)=
 =3D -1 ENOENT (No such file or directory)
>     31377 stat("/bin/gs", 0x7ffd7921b5c0)   =3D -1 ENOENT (No such file o=
r directory)
>     31377 stat("/usr/bin/gs", 0x7ffd7921b5c0) =3D -1 ENOENT (No such file=
 or directory)
>     31377 write(2, "sh: gs: command not found\n", 26) =3D 26
>     15154 <... read resumed> "sh: gs: command not found\n", 2047) =3D 26
>     15154 write(4, "D [09/Nov/2017:20:06:50 +0100] [Job 13] sh: gs: comma=
nd not found\n", 66) =3D 66
>
> Thank you for your help! How could we continue debugging?

IIUC, process 31377 is started by cupsd itself.  Commit
1728c411718e3b358c06561d6e80b47d7b331617 wrapped the cups-filters
programs but not cupsd itself.  Perhaps we need to wrap cupsd similarly?
Could you grep the source of cups itself to see if it contains this =E2=80=
=98gs=E2=80=99
invocation?

In the meantime, can you try adding ghostcript to your system profile?
That should work around the problem.

Thanks,
Ludo=E2=80=99.

Last modified: Mon, 25 Nov 2019 12:00:02 UTC

GNU bug tracking system
Copyright (C) 1999 Darren O. Benham, 1997 nCipher Corporation Ltd, 1994-97 Ian Jackson.