Bug#808599: marked as done (cups: lpstat -m Internal Server Error, most certainly due to outdated libcupsppdc1 (incompletely versioned dependencies??))

To: Didier 'OdyX' Raboud <odyx@debian.org>
Subject: Bug#808599: marked as done (cups: lpstat -m Internal Server Error, most certainly due to outdated libcupsppdc1 (incompletely versioned dependencies??))
From: "Debian Bug Tracking System" <owner@bugs.debian.org>
Date: Mon, 26 Nov 2018 07:21:08 +0000
Message-id: <[🔎] handler.808599.D808599.15432167335225.ackdone@bugs.debian.org>
Reply-to: 808599@bugs.debian.org
References: <1630627.WmbkSIP4gD@odyx.org> <20151221115539.GA12676@rhlx01.hs-esslingen.de>

Your message dated Mon, 26 Nov 2018 08:18:47 +0100
with message-id <1630627.WmbkSIP4gD@odyx.org>
and subject line Re: Bug#808599: cups: lpstat -m Internal Server Error, most certainly due to outdated libcupsppdc1 (incompletely versioned dependencies??)
has caused the Debian Bug report #808599,
regarding cups: lpstat -m Internal Server Error, most certainly due to outdated libcupsppdc1 (incompletely versioned dependencies??)
to be marked as done.

This means that you claim that the problem has been dealt with.
If this is not the case it is now your responsibility to reopen the
Bug report if necessary, and/or fix the problem forthwith.

(NB: If you are a system administrator and have no idea what this
message is talking about, this may indicate a serious mail system
misconfiguration somewhere. Please contact owner@bugs.debian.org
immediately.)


-- 
808599: https://bugs.debian.org/cgi-bin/bugreport.cgi?bug=808599
Debian Bug Tracking System
Contact owner@bugs.debian.org with problems

--- Begin Message ---

To: submit@bugs.debian.org
Subject: cups: lpstat -m Internal Server Error, most certainly due to outdated libcupsppdc1 (incompletely versioned dependencies??)
From: Andreas Mohr <andi@lisas.de>
Date: Mon, 21 Dec 2015 12:55:39 +0100
Message-id: <20151221115539.GA12676@rhlx01.hs-esslingen.de>

Package: cups
Version: 2.1.2-1
Severity: important
Justification: larger inconsistency damage caused by incomplete upgrades

Hi,

right after (I believe) upgrading cups to 2.1.2-1 I got
500 Internal Server Error when trying to view the list of PPDs in :631 UI
(e.g. during Add Printer),
i.e.:
localhost - - [21/Dec/2015:11:51:57 +0100] "POST / HTTP/1.1" 200 4999058 CUPS-Get-PPDs -

I figured out that lpstat -m does the same CUPS-Get-PPDs query, with identical failure,
i.e. it's a "fully reproducing semi-minimal test case" ;).


With debugging (debug2) turned on I couldn't really pinpoint any specific failure location in log
(perhaps there's some important logging of sub component handling missing?),
but I saw that it consulted cups-driverd,
and cups-driverd(8) mentioned the directories cups-driverd consults for PPDs etc.,
thus I disabled (renamed away) /usr/share/cups/drv ,
at which point lpstat -m magically started successfully listing driver info.

A short time later I then did ldd /usr/lib/cups/daemon/cups-driverd,
which listed libcupsppdc.so.1 as one of its dependencies.
I then realized that I still was at libcupsppdc1:i386 1.5.2-5,
despite many parts of cups having been upgraded to 2.1.2-1.

Right after an upgrade to libcupsppdc1 2.1.2-1
lpstat -m magically started successfully listing driver info again,
even with /usr/share/cups/drv properly restored.

Unfortunately I am sufficiently unable to reverse-verify this,
since when trying to downgrade to 1.5.3-5+deb7u6 (1.5.2-5 seems harder to find) it bails out:

# LANG=C dpkg -i libcupsppdc1_1.5.3-5+deb7u6_i386.deb 
(Reading database ... 233610 files and directories currently installed.)
Preparing to unpack libcupsppdc1_1.5.3-5+deb7u6_i386.deb ...
Unpacking libcupsppdc1:i386 (1.5.3-5+deb7u6) over (1.5.3-5+deb7u6) ...
dpkg: dependency problems prevent configuration of libcupsppdc1:i386:
 libcupsppdc1:i386 depends on libcups2 (= 1.5.3-5+deb7u6); however:
  Version of libcups2:i386 on system is 2.1.2-1.

dpkg: error processing package libcupsppdc1:i386 (--install):
 dependency problems - leaving unconfigured
Errors were encountered while processing:
 libcupsppdc1:i386


BTW, due to this result
it could be quite strongly argued
that if there is a *strictly-versioned* dependency specified
of libcupsppdc1 on same-version libcups2 (which properly prevents downgrade!!),
that it's "rather weird" that OTOH cups has a rather liberal view on its libcupsppdc1
dependency, especially since cups also has a *strictly-versioned* dependency
on libcups2, too,
i.e. both cups-related components do use the same common libcups2 as their toolkit base,
thus a certain (possibly high) level of consistency seems to be required / expected
due to harmful protocol changes.



So, while I'm very confident
that it's this very version upgrade action
that made it work again,
due to properly blocked downgrade
I'm currently relatively unable to verify
that it was not due to "other problems" of the libcupsppdc1 install
(say corrupted files / install etc.).
(I'd estimate this chance to be quite low however)


# dpkg -S /usr/lib/cups/daemon/cups-driverd
cups: /usr/lib/cups/daemon/cups-driverd


cups 2.1.2-1 currently lists:
Replaces: cups-bsd (<< 1.7.2-3~), ghostscript-cups (<< 9.02~)
Depends: libavahi-client3 (>= 0.6.16), libavahi-common3 (>= 0.6.16), libc6 (>= 2.16), libcups2 (= 2.1.2-1), libcupscgi1 (>= 1.4.2), libcupsimage2 (>= 1.4.0), libcupsmime1 (>= 1.4.0), libcupsppdc1 (>= 1.4.0), libgcc1 (>= 1:4.1.1), libstdc++6 (>= 4.1.1), libusb-1.0-0 (>= 2:1.0.8), debconf (>= 1.2.9) | debconf-2.0, libc-bin (>= 2.13), cups-core-drivers (>= 2.1.2-1), cups-daemon (>= 2.1.2-1), poppler-utils (>= 0.12), procps, ghostscript (>= 9.02~), lsb-base (>= 3.2-14~), cups-common (>= 2.1.2-1), cups-server-common (>= 2.1.2-1), cups-client (>= 2.1.2-1), cups-ppdc, cups-filters (>= 1.0.24-3~)
Recommends: avahi-daemon, colord, cups-filters (>= 1.0.42) | foomatic-filters (>= 4.0), printer-driver-gutenprint, cups-filters (>= 1.0.36) | ghostscript-cups (>= 9.02~)
Suggests: cups-bsd, foomatic-db-compressed-ppds | foomatic-db, printer-driver-hpcups, hplip, cups-pdf, udev, smbclient
Breaks: cups-bsd (<< 1.7.2-3~), foomatic-filters (<< 4.0), ghostscript-cups (<< 9.02~)



Thus if old libcupsppdc1 indeed turns (turned) out
to be incompatible with this version upgrade
(BTW any idea what the underlying protocol break reason for this would be?
I.e. whether / why we do have a protocol compatibility issue here...
And perhaps that should be documented...),
then the libcupsppdc1 (>= 1.4.0) requirement part is NOT sufficient.


Note that my system still does have several other older components installed, e.g.
libcupsmime1:i386 1.5.2-5
libcupscgi1:i386 1.5.2-5
, thus here obviously cups also doesn't (care to / need to?) have
a more specific dependency listed.


In closing, I would consider this to likely be
a pretty grave consistency-breaking limitation
of current cups Debian package specifications.


Boy am I personally happy
to have this deal-breaking printer base config problem nailed here after all.



Log details:


d [21/Dec/2015:11:51:32 +0100] select_timeout(-1): 34200 seconds to expire subscription
d [21/Dec/2015:11:51:48 +0100] cupsdAcceptClient(lis=0x80144a90(10)) Clients=0
D [21/Dec/2015:11:51:48 +0100] [Client 6] Accepted from localhost (Domain)
d [21/Dec/2015:11:51:48 +0100] cupsdAddSelect(fd=15, read_cb=0x800bfdd0, write_cb=(nil), data=0x80190fd0)
D [21/Dec/2015:11:51:48 +0100] [Client 6] Waiting for request.
d [21/Dec/2015:11:51:48 +0100] cupsdCheckJobs: 0 active jobs, sleeping=0, ac-power=-1, reload=0, curtime=1450695108
d [21/Dec/2015:11:51:48 +0100] select_timeout: JobHistoryUpdate=0
d [21/Dec/2015:11:51:48 +0100] [Client 6] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
D [21/Dec/2015:11:51:48 +0100] [Client 6] POST / HTTP/1.1
D [21/Dec/2015:11:51:48 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [21/Dec/2015:11:51:48 +0100] [Client 6] Read: status=200
d [21/Dec/2015:11:51:48 +0100] cupsdFindBest: uri="/", limit=10...
d [21/Dec/2015:11:51:48 +0100] cupsdFindBest: Location /admin/conf(11) Limit 7f
d [21/Dec/2015:11:51:48 +0100] cupsdFindBest: Location /admin(6) Limit 7f
d [21/Dec/2015:11:51:48 +0100] cupsdFindBest: Location /(1) Limit 7f
d [21/Dec/2015:11:51:48 +0100] cupsdFindBest: best=/
d [21/Dec/2015:11:51:48 +0100] [Client 6] con->uri="/", con->best=0x80144558(/)
D [21/Dec/2015:11:51:48 +0100] [Client 6] No authentication data provided.
d [21/Dec/2015:11:51:48 +0100] cupsdIsAuthorized: con->uri="/", con->best=0x80144558(/)
d [21/Dec/2015:11:51:48 +0100] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [21/Dec/2015:11:51:48 +0100] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
d [21/Dec/2015:11:51:48 +0100] select_timeout: JobHistoryUpdate=0
d [21/Dec/2015:11:51:48 +0100] [Client 6] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=72, request=0x8018fee8(IPP_STATE_IDLE), file=-1
D [21/Dec/2015:11:51:48 +0100] [Client 6] Read: status=100
d [21/Dec/2015:11:51:48 +0100] select_timeout: JobHistoryUpdate=0
d [21/Dec/2015:11:51:48 +0100] [Client 6] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=64, request=0x8018fee8(IPP_STATE_ATTRIBUTE), file=-1
D [21/Dec/2015:11:51:48 +0100] [Client 6] Read: status=100
d [21/Dec/2015:11:51:48 +0100] select_timeout: JobHistoryUpdate=0
d [21/Dec/2015:11:51:48 +0100] [Client 6] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=35, request=0x8018fee8(IPP_STATE_ATTRIBUTE), file=-1
D [21/Dec/2015:11:51:48 +0100] [Client 6] Read: status=100
d [21/Dec/2015:11:51:48 +0100] select_timeout: JobHistoryUpdate=0
d [21/Dec/2015:11:51:48 +0100] [Client 6] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1, request=0x8018fee8(IPP_STATE_ATTRIBUTE), file=-1
D [21/Dec/2015:11:51:48 +0100] [Client 6] Read: status=100
D [21/Dec/2015:11:51:48 +0100] [Client 6] 2.0 CUPS-Get-PPDs 1
d [21/Dec/2015:11:51:48 +0100] cupsdProcessIPPRequest(0x80190fd0[6]): operation_id = 400c
D [21/Dec/2015:11:51:48 +0100] CUPS-Get-PPDs
d [21/Dec/2015:11:51:48 +0100] get_ppds(0x80190fd0[6])
d [21/Dec/2015:11:51:48 +0100] cupsdFindPolicyOp(p=0x80144e68, op=400c(CUPS-Get-PPDs))
d [21/Dec/2015:11:51:48 +0100] cupsdFindPolicyOp: Found wildcard match...
d [21/Dec/2015:11:51:48 +0100] cupsdIsAuthorized: con->uri="/", con->best=0x80148370((null))
d [21/Dec/2015:11:51:48 +0100] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [21/Dec/2015:11:51:48 +0100] cupsdIsAuthorized: op=0(0x0000)
d [21/Dec/2015:11:51:48 +0100] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
d [21/Dec/2015:11:51:48 +0100] [Client 6] pipe_command: infile=-1, outfile=0x8019160c, command="/usr/lib/cups/daemon/cups-driverd", options="list+1+0+requested-attributes=all", root=0
D [21/Dec/2015:11:51:48 +0100] [CGI] argv[0] = "/usr/lib/cups/daemon/cups-driverd"
D [21/Dec/2015:11:51:48 +0100] [CGI] argv[1] = "list"
D [21/Dec/2015:11:51:48 +0100] [CGI] argv[2] = "1"
D [21/Dec/2015:11:51:48 +0100] [CGI] argv[3] = "0"
D [21/Dec/2015:11:51:48 +0100] [CGI] argv[4] = "requested-attributes=all"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@[HOST]"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/2.1.2"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[13] = "TZ=Europe/Berlin"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[14] = "USER=root"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[15] = "CUPS_MAX_MESSAGE=2047"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[16] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[17] = "CUPS_ENCRYPTION=IfRequested"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[18] = "IPP_PORT=631"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[19] = "LANG=en.UTF8"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[22] = "SERVER_NAME=localhost"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[23] = "SERVER_PORT=631"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[24] = "REMOTE_ADDR=localhost"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[25] = "REMOTE_HOST=localhost"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[26] = "SCRIPT_NAME=/"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[28] = "SERVER_PROTOCOL=HTTP/1.1"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[29] = "HTTP_USER_AGENT=CUPS/2.1.2 (Linux 4.1.0-rc5-00080-ge10470f; i686) IPP/2.0"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[30] = "REQUEST_METHOD=POST"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[31] = "CONTENT_LENGTH=72"
D [21/Dec/2015:11:51:48 +0100] [CGI] envp[32] = "CONTENT_TYPE=application/ipp"
d [21/Dec/2015:11:51:48 +0100] File \"/usr/lib/cups/daemon/cups-driverd\" permissions OK (040755/uid=0/gid=0).
d [21/Dec/2015:11:51:48 +0100] cupsdStartProcess: Preparing to start "/usr/lib/cups/daemon/cups-driverd", arguments:
d [21/Dec/2015:11:51:48 +0100] cupsdStartProcess: argv[0] = "/usr/lib/cups/daemon/cups-exec"
d [21/Dec/2015:11:51:48 +0100] cupsdStartProcess: argv[1] = "-g"
d [21/Dec/2015:11:51:48 +0100] cupsdStartProcess: argv[2] = "7"
d [21/Dec/2015:11:51:48 +0100] cupsdStartProcess: argv[3] = "-n"
d [21/Dec/2015:11:51:48 +0100] cupsdStartProcess: argv[4] = "0"
d [21/Dec/2015:11:51:48 +0100] cupsdStartProcess: argv[5] = "-u"
d [21/Dec/2015:11:51:48 +0100] cupsdStartProcess: argv[6] = "7"
d [21/Dec/2015:11:51:48 +0100] cupsdStartProcess: argv[7] = "none"
d [21/Dec/2015:11:51:48 +0100] cupsdStartProcess: argv[8] = "/usr/lib/cups/daemon/cups-driverd"
d [21/Dec/2015:11:51:48 +0100] cupsdStartProcess: argv[9] = "/usr/lib/cups/daemon/cups-driverd"
d [21/Dec/2015:11:51:48 +0100] cupsdStartProcess: argv[10] = "list"
d [21/Dec/2015:11:51:48 +0100] cupsdStartProcess: argv[11] = "1"
d [21/Dec/2015:11:51:48 +0100] cupsdStartProcess: argv[12] = "0"
d [21/Dec/2015:11:51:48 +0100] cupsdStartProcess: argv[13] = "requested-attributes=all"
d [21/Dec/2015:11:51:48 +0100] cupsdStartProcess: Setting spawn attributes.
d [21/Dec/2015:11:51:48 +0100] cupsdStartProcess: Setting file actions.
d [21/Dec/2015:11:51:48 +0100] cupsdStartProcess: Calling posix_spawn.
d [21/Dec/2015:11:51:48 +0100] cupsdStartProcess: pid=25776
d [21/Dec/2015:11:51:48 +0100] cupsdStartProcess(command="/usr/lib/cups/daemon/cups-driverd", argv=0xbfb78d04, envp=0xbfb7950c, infd=-1, outfd=18, errfd=12, backfd=-1, sidefd=-1, root=0, profile=(nil), job=(nil)(0), pid=0xbfb79370) = 25776
D [21/Dec/2015:11:51:48 +0100] [CGI] Started /usr/lib/cups/daemon/cups-driverd (PID 25776)
I [21/Dec/2015:11:51:48 +0100] [Client 6] Started "/usr/lib/cups/daemon/cups-driverd" (pid=25776, file=17)
d [21/Dec/2015:11:51:48 +0100] cupsdAddSelect(fd=17, read_cb=0x800bd2e0, write_cb=(nil), data=0x80190fd0)
D [21/Dec/2015:11:51:48 +0100] [Client 6] Waiting for CGI data.
d [21/Dec/2015:11:51:48 +0100] select_timeout: JobHistoryUpdate=0
d [21/Dec/2015:11:51:48 +0100] [cups-driverd] list_ppds(request_id=1, limit=0, opt=\"requested-attributes=all\"
d [21/Dec/2015:11:51:48 +0100] select_timeout: JobHistoryUpdate=0
I [21/Dec/2015:11:51:48 +0100] [cups-driverd] Read \"/var/cache/cups/ppds.dat\", 19 PPDs...
I [21/Dec/2015:11:51:48 +0100] [cups-driverd] Read \"/var/cache/cups/ppds.dat\", 19 PPDs...
d [21/Dec/2015:11:51:48 +0100] select_timeout: JobHistoryUpdate=0
d [21/Dec/2015:11:51:48 +0100] [CGI] Directory \"/usr/share/cups/model\" permissions OK (043775/uid=0/gid=101).
d [21/Dec/2015:11:51:48 +0100] select_timeout: JobHistoryUpdate=0
D [21/Dec/2015:11:51:48 +0100] [cups-driverd] Loading \"/usr/share/cups/model\"...
d [21/Dec/2015:11:51:48 +0100] select_timeout: JobHistoryUpdate=0
d [21/Dec/2015:11:51:48 +0100] [CGI] Directory \"/usr/share/cups/drv\" permissions OK (040755/uid=0/gid=0).
d [21/Dec/2015:11:51:48 +0100] select_timeout: JobHistoryUpdate=0
D [21/Dec/2015:11:51:48 +0100] [cups-driverd] Loading \"/usr/share/cups/drv\"...
d [21/Dec/2015:11:51:48 +0100] select_timeout: JobHistoryUpdate=0
d [21/Dec/2015:11:51:48 +0100] [CGI] File \"/usr/share/cups/drv/cupsfilters.drv\" permissions OK (0100644/uid=0/gid=0).
d [21/Dec/2015:11:51:48 +0100] select_timeout: JobHistoryUpdate=0
d [21/Dec/2015:11:51:48 +0100] [Client 6] write_pipe: CGI output on fd 17.
d [21/Dec/2015:11:51:48 +0100] cupsdRemoveSelect(fd=17)
d [21/Dec/2015:11:51:48 +0100] cupsdAddSelect(fd=15, read_cb=(nil), write_cb=0x800bf3f0, data=0x80190fd0)
D [21/Dec/2015:11:51:48 +0100] [Client 6] CGI data ready to be sent.
d [21/Dec/2015:11:51:48 +0100] select_timeout: JobHistoryUpdate=0
D [21/Dec/2015:11:51:48 +0100] [Client 6] con->http=0x80192410
D [21/Dec/2015:11:51:48 +0100] [Client 6] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=25776, file=17
d [21/Dec/2015:11:51:48 +0100] cupsdAddSelect(fd=17, read_cb=0x800bd2e0, write_cb=(nil), data=0x80190fd0)
D [21/Dec/2015:11:51:48 +0100] [Client 6] Waiting for CGI data.
d [21/Dec/2015:11:51:48 +0100] [Client 6] cupsdSendError code=500, auth_type=0
D [21/Dec/2015:11:51:48 +0100] [Client 6] cupsdSendHeader: code=500, type="text/html", auth_type=0
d [21/Dec/2015:11:51:48 +0100] cupsdAddSelect(fd=15, read_cb=0x800bfdd0, write_cb=(nil), data=0x80190fd0)
D [21/Dec/2015:11:51:48 +0100] [Client 6] Waiting for request.
d [21/Dec/2015:11:51:48 +0100] cupsdRemoveSelect(fd=17)
d [21/Dec/2015:11:51:48 +0100] cupsdEndProcess(pid=25776, force=0)
D [21/Dec/2015:11:51:48 +0100] [Client 6] Closing because Keep-Alive is disabled.
D [21/Dec/2015:11:51:48 +0100] [Client 6] Closing connection.
D [21/Dec/2015:11:51:48 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
d [21/Dec/2015:11:51:48 +0100] cupsdRemoveSelect(fd=15)
d [21/Dec/2015:11:51:48 +0100] cupsdRemoveSelect(fd=-1)
d [21/Dec/2015:11:51:48 +0100] select_timeout: JobHistoryUpdate=0
d [21/Dec/2015:11:51:48 +0100] process_children()
d [21/Dec/2015:11:51:48 +0100] cupsdFinishProcess(pid=25776, name=0xbfb95c5c, namelen=1024, job_id=0xbfb95c58(0)) = "/usr/lib/cups/daemon/cups-driverd"
D [21/Dec/2015:11:51:48 +0100] PID 25776 (/usr/lib/cups/daemon/cups-driverd) was terminated normally with signal 15.
d [21/Dec/2015:11:51:48 +0100] select_timeout: JobHistoryUpdate=0
d [21/Dec/2015:11:51:48 +0100] select_timeout(-1): 34184 seconds to expire subscription
d [21/Dec/2015:11:51:57 +0100] cupsdAcceptClient(lis=0x80144a90(10)) Clients=0



Thanks for listening and coding,

Andreas Mohr

-- 
GNU/Linux. It's not the software that's free, it's you.

--- End Message ---

--- Begin Message ---

To: Andreas Mohr <andi@lisas.de>, 808599-done@bugs.debian.org
Subject: Re: Bug#808599: cups: lpstat -m Internal Server Error, most certainly due to outdated libcupsppdc1 (incompletely versioned dependencies??)
From: Didier 'OdyX' Raboud <odyx@debian.org>
Date: Mon, 26 Nov 2018 08:18:47 +0100
Message-id: <1630627.WmbkSIP4gD@odyx.org>
In-reply-to: <20151221115539.GA12676@rhlx01.hs-esslingen.de>
References: <20151221115539.GA12676@rhlx01.hs-esslingen.de>

Version: 2.2.9-1

Hi Andreas,

As CUPS upstream removed libcupsppdc1 (and other) shared libraries in 2.2.9; 
hereby closing; the problem is not going to reappear. Thanks for your detailed 
report!

Cheers,
    OdyX

Le lundi, 21 décembre 2015, 12.55:39 h CET Andreas Mohr a écrit : 
> (…)
> I then realized that I still was at libcupsppdc1:i386 1.5.2-5,
> despite many parts of cups having been upgraded to 2.1.2-1.
> 
> Right after an upgrade to libcupsppdc1 2.1.2-1
> lpstat -m magically started successfully listing driver info again,
> even with /usr/share/cups/drv properly restored.
> 
> Unfortunately I am sufficiently unable to reverse-verify this,
> since when trying to downgrade to 1.5.3-5+deb7u6 (1.5.2-5 seems harder to
> find) it bails out:
> 
> # LANG=C dpkg -i libcupsppdc1_1.5.3-5+deb7u6_i386.deb
> (Reading database ... 233610 files and directories currently installed.)
> Preparing to unpack libcupsppdc1_1.5.3-5+deb7u6_i386.deb ...
> Unpacking libcupsppdc1:i386 (1.5.3-5+deb7u6) over (1.5.3-5+deb7u6) ...
> dpkg: dependency problems prevent configuration of libcupsppdc1:i386:
>  libcupsppdc1:i386 depends on libcups2 (= 1.5.3-5+deb7u6); however:
>   Version of libcups2:i386 on system is 2.1.2-1.
> 
> dpkg: error processing package libcupsppdc1:i386 (--install):
>  dependency problems - leaving unconfigured
> Errors were encountered while processing:
>  libcupsppdc1:i386

--- End Message ---

Reply to:

Prev by Date: Bug#905734: found #905734 hplip/3.17.10
Next by Date: Bug#910882: cups-browsed: The default for UseCUPSGeneratePPDs should be "Yes"
Previous by thread: Bug#905734: found #905734 hplip/3.17.10
Next by thread: Bug#909423: cups-browsed: Unusual behaviour with the default CreateIPPPrinterQueues
Index(es):
- Date
- Thread