samba - Aug 2017 - Printing with smbspool_krb5_wrapper not working in Ubuntu 16.04

Thanks for your reply! I have now filed a bug in launchpad
(https://bugs.launchpad.net/ubuntu/+source/samba/+bug/1708817).

When I compare the cups error_log with 14.04 where it does work I see that 16.04
uses two backslashes when setting KRB5CCNAME while 14.04 does not use
backslashes, maybe that is the problem?

Sometimes the cups error_log also have the below line "HTTP_STATE_WAITING
Closing for error 32 (Broken pipe)" like I show below. Both this line and
the line "Could not determine network interfaces, you must use a interfaces
config line" maybe can give some idea on what goes wrong? The line
"Printing jobs and dirty files" can maybe be ignored since it also
shows up in 14.04 where it works.

D [05/Aug/2017:12:52:04 +0200] [Job 89] SMBSPOOL_KRB5 - Started with uid=0
D [05/Aug/2017:12:52:04 +0200] [Job 89] SMBSPOOL_KRB5 -
AUTH_INFO_REQUIRED=negotiate
D [05/Aug/2017:12:52:04 +0200] [Job 89] SMBSPOOL_KRB5 - Switching to gid=1000
D [05/Aug/2017:12:52:04 +0200] [Job 89] SMBSPOOL_KRB5 - Switching to uid=1000
D [05/Aug/2017:12:52:04 +0200] [Job 89] SMBSPOOL_KRB5 - Setting KRB5CCNAME to
\'FILE:/tmp/krb5cc_1000\'
D [05/Aug/2017:12:52:04 +0200] [Client 12] HTTP_STATE_WAITING Closing for error
32 (Broken pipe)
D [05/Aug/2017:12:52:04 +0200] [Client 12] Closing connection.
D [05/Aug/2017:12:52:04 +0200] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files"
E [05/Aug/2017:12:52:04 +0200] [Job 89] Could not determine network interfaces,
you must use a interfaces config line

Thanks for any ideas!

Andreas Hasenack wrote:
> Unless somebody has an "aha!" moment and figures it out
immediately, could
> you please file a bug in launchpad for this issue and attach the relevant
> config files and logs (for samba and cups)?
>
> On Fri, Aug 4, 2017 at 12:22 PM, Van Svensson wrote:
> 
> > Hello,
> >
> > I have two computers, one with Ubuntu 14.04 and one with Ubuntu 16.04.
On
> > both computers I have configured the printer with cups to print by
using my
> > Kerberos credential in the same way and it works in 14.04 but not on
16.04.
> >
> > For both /usr/bin/smbspool_krb5_wrapper is 700 and I have
> > "AuthInfoRequired negotiate" for both in
/etc/cups/printers.conf. I have
> > made a symbolic link from /usr/lib/cups/backend/smb to
> > /usr/bin/smbspool_krb5_wrapper. I have set "LogLevel debug"
in
> > /etc/cups/cupsd.conf.
> >
> > In 16.04 the print job just stays listed forever in "lpq"
while it goes
> > through to the printer in 14.04.
> >
> > This is how it looks like in /var/log/cups/error_log in 16.04 where it
> > does not work (I have just changed my uid and gid to 1000):
> >
> > [...]
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] SMBSPOOL_KRB5 - Started with
uid=0
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] SMBSPOOL_KRB5 -
> > AUTH_INFO_REQUIRED=negotiate
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] SMBSPOOL_KRB5 - Switching to
> > gid=1000
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] SMBSPOOL_KRB5 - Switching to
> > uid=1000
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] SMBSPOOL_KRB5 - Setting
KRB5CCNAME
> > to \'FILE:/tmp/krb5cc_1000\'
> > E [04/Aug/2017:16:53:36 +0200] [Job 88] Could not determine network
> > interfaces, you must use a interfaces config line
> > D [04/Aug/2017:16:53:36 +0200] cupsdMarkDirty(---J-)
> > D [04/Aug/2017:16:53:36 +0200] cupsdSetBusyState:
newbusy="Printing jobs
> > and dirty files", busy="Printing jobs and dirty files"
> > D [04/Aug/2017:16:53:36 +0200] cupsdMarkDirty(---J-)
> > D [04/Aug/2017:16:53:36 +0200] cupsdSetBusyState:
newbusy="Printing jobs
> > and dirty files", busy="Printing jobs and dirty files"
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] Set job-printer-state-message
to
> > "Could not determine network interfaces, you must use a
interfaces config
> > line", current level=ERROR
> > D [04/Aug/2017:16:53:36 +0200] cupsdMarkDirty(----S)
> > D [04/Aug/2017:16:53:36 +0200] cupsdSetBusyState:
newbusy="Printing jobs
> > and dirty files", busy="Printing jobs and dirty files"
> > D [04/Aug/2017:16:53:36 +0200] cupsdMarkDirty(----S)
> > D [04/Aug/2017:16:53:36 +0200] cupsdSetBusyState:
newbusy="Printing jobs
> > and dirty files", busy="Printing jobs and dirty files"
> > D [04/Aug/2017:16:53:36 +0200] [Notifier] state=3
> > D [04/Aug/2017:16:53:36 +0200] [Notifier] JobProgress
> > D [04/Aug/2017:16:53:36 +0200] [Notifier] state=3
> > D [04/Aug/2017:16:53:36 +0200] [Notifier] state=3
> > D [04/Aug/2017:16:53:36 +0200] [Notifier] state=3
> > D [04/Aug/2017:16:53:36 +0200] [Notifier] PrinterStateChanged
> > D [04/Aug/2017:16:53:36 +0200] [Notifier] state=3
> > D [04/Aug/2017:16:53:36 +0200] [Notifier] state=3
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] PID 4534
> > (/usr/lib/cups/backend/smb) stopped with status 1.
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] Page = 612x792; 12,12 to
600,780
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] slow_collate=0, slow_duplex=0,
> > slow_order=0
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] Before copy_comments -
> > %!PS-Adobe-3.0
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] %!PS-Adobe-3.0
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] %%BoundingBox: 0 0 585 441
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] %%HiResBoundingBox: 0 0 585.00
> > 441.00
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] %%Creator: GPL Ghostscript 910
> > (ps2write)
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] %%LanguageLevel: 2
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] %%CreationDate:
> > D:20170804154259+02\'00\'
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] %%Pages: 1
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] %%EndComments
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] Before copy_prolog -
%%BeginProlog
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] Before copy_setup - %%Page: 1
1
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] Before page loop - %%Page: 1 1
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] Copying page 1...
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] PAGE: 1 1
> > D [04/Aug/2017:16:53:36 +0200] cupsdMarkDirty(---J-)
> > D [04/Aug/2017:16:53:36 +0200] cupsdSetBusyState:
newbusy="Printing jobs
> > and dirty files", busy="Printing jobs and dirty files"
> > D [04/Aug/2017:16:53:36 +0200] cupsdMarkDirty(---J-)
> > D [04/Aug/2017:16:53:36 +0200] cupsdSetBusyState:
newbusy="Printing jobs
> > and dirty files", busy="Printing jobs and dirty files"
> > D [04/Aug/2017:16:53:36 +0200] cupsdMarkDirty(----S)
> > D [04/Aug/2017:16:53:36 +0200] cupsdSetBusyState:
newbusy="Printing jobs
> > and dirty files", busy="Printing jobs and dirty files"
> > D [04/Aug/2017:16:53:36 +0200] [Notifier] state=3
> > D [04/Aug/2017:16:53:36 +0200] [Notifier] state=3
> > D [04/Aug/2017:16:53:36 +0200] [Notifier] JobProgress
> > D [04/Aug/2017:16:53:36 +0200] [Notifier] state=3
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] pagew = 588.0, pagel = 768.0
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] bboxx = 0, bboxy = 0, bboxw =
612,
> > bboxl = 792
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] PageLeft = 12.0, PageRight =
600.0
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] PageTop = 780.0, PageBottom =
12.0
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] PageWidth = 612.0, PageLength
> > 792.0
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] Wrote 1 pages...
> > D [04/Aug/2017:16:53:36 +0200] [Job 88] PID 4533
> > (/usr/lib/cups/filter/pstops) exited with no errors.
> > I [04/Aug/2017:16:53:36 +0200] [Job 88] Backend returned status 1
(failed)
> > [...]
> >
> > Can somebody please help me debug what is the problem in 16.04? Thank
you
> > very much!
> >
> > --
> > To unsubscribe from this list go to the following URL and read the
> > instructions:  https://lists.samba.org/mailman/options/samba
> -- 
> To unsubscribe from this list go to the following URL and read the
> instructions:  https://lists.samba.org/mailman/options/samba

On Sat, 5 Aug 2017 13:06:18 +0200
Van Svensson via samba <samba at lists.samba.org> wrote:
> Thanks for your reply! I have now filed a bug in launchpad
> (https://bugs.launchpad.net/ubuntu/+source/samba/+bug/1708817).
> 
> When I compare the cups error_log with 14.04 where it does work I see
> that 16.04 uses two backslashes when setting KRB5CCNAME while 14.04
> does not use backslashes, maybe that is the problem?
> 
> Sometimes the cups error_log also have the below line
> "HTTP_STATE_WAITING Closing for error 32 (Broken pipe)" like I
show
> below. Both this line and the line "Could not determine network
> interfaces, you must use a interfaces config line" maybe can give
> some idea on what goes wrong? The line "Printing jobs and dirty
> files" can maybe be ignored since it also shows up in 14.04 where it
> works.
> 
It might help if you could tell us what versions of Samba you are using
(it might shock you, but some people don't use Ubuntu ), it would also
be a good idea to post your smb.conf files.

Rowland

Rowland Penny wrote:
> On Sat, 5 Aug 2017 13:06:18 +0200
> Van Svensson via samba <samba at lists.samba.org> wrote:
> 
> > Thanks for your reply! I have now filed a bug in launchpad
> > (https://bugs.launchpad.net/ubuntu/+source/samba/+bug/1708817).
> > 
> > When I compare the cups error_log with 14.04 where it does work I see
> > that 16.04 uses two backslashes when setting KRB5CCNAME while 14.04
> > does not use backslashes, maybe that is the problem?
> > 
> > Sometimes the cups error_log also have the below line
> > "HTTP_STATE_WAITING Closing for error 32 (Broken pipe)" like
I show
> > below. Both this line and the line "Could not determine network
> > interfaces, you must use a interfaces config line" maybe can give
> > some idea on what goes wrong? The line "Printing jobs and dirty
> > files" can maybe be ignored since it also shows up in 14.04 where
it
> > works.
> 
> It might help if you could tell us what versions of Samba you are using
> (it might shock you, but some people don't use Ubuntu ), it would also
> be a good idea to post your smb.conf files.
I attach the smb.conf file which is exactly the same for both Ubuntu 14.04 and
16.04. On 14.04 I have Samba version 2:4.3.11+dfsg-0ubuntu0.14.04.10 and on
16.04 I have 2:4.3.11+dfsg-0ubuntu0.16.04.9.

Thanks / Van