[Samba] cups_async_callback(504) error after upgrade to 3.2.7

[Jeremy Allison]

On Wed, Jan 07, 2009 at 06:16:58PM -0800, Ed Kasky wrote:
>
> This is what I could cull from the log that looks relevant:
>
> [2009/01/07 18:04:05,  5] printing/print_cups.c:cups_pcap_load_async(366)
>   cups_pcap_load_async: asynchronously loading cups printers
> [2009/01/07 18:04:05, 10] printing/print_cups.c:cups_pcap_load_async(383)
>   cups_pcap_load_async: child pid = 27828
> [2009/01/07 18:04:05, 10] printing/print_cups.c:cups_cache_reload(545)
>   cups_cache_reload: async read on fd 23
> [2009/01/07 18:04:05,  3] printing/pcap.c:pcap_cache_reload(243)
>   reload status: ok
> [2009/01/07 18:04:05,  5] printing/print_cups.c:cups_cache_reload_async(1
>   reloading cups printcap cache
>
> [2009/01/07 18:04:05, 10] printing/print_cups.c:cups_connect(74)
>   socket option IPTOS_LOWDELAY = 0
>   connecting to cups server /var/run/cups/cups.sock:631
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option IPTOS_THROUGHPUT = 0
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option SO_SNDBUF = 16384
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option SO_RCVBUF = 87380
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option SO_SNDLOWAT = 1
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option SO_RCVLOWAT = 1
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option SO_SNDTIMEO = 0
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option SO_RCVTIMEO = 0
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option SO_KEEPALIVE = 1
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option SO_REUSEADDR = 1
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option SO_BROADCAST = 0
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option TCP_NODELAY = 1
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option TCP_KEEPCNT = 9
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option TCP_KEEPIDLE = 7200
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option TCP_KEEPINTVL = 75
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option IPTOS_LOWDELAY = 0
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option IPTOS_THROUGHPUT = 0
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option SO_SNDBUF = 16384
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option SO_RCVBUF = 87380
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option SO_SNDLOWAT = 1
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option SO_RCVLOWAT = 1
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option SO_SNDTIMEO = 0
> [2009/01/07 18:04:05,  5] lib/util_sock.c:print_socket_options(781)
>   socket option SO_RCVTIMEO = 0
>
> [2009/01/07 18:04:05,  5] printing/print_cups.c:cups_async_callback(412)
>   cups_async_callback: callback received for printer data. fd = 23
> [2009/01/07 18:04:05,  2] printing/print_cups.c:cups_async_callback(504)
>   cups_async_callback: failed to read a new printer list

This log shows no data being transferred back up the
async pipe (the send_pcap_info() is never being called).

This would happen if the code in cups_cache_reload_async()
was unable to fetch any printers from the cups server (ie.
tmp_pcap_cache == NULL in the function cups_cache_reload_async()).

You'll need to debug from the CUPS side what's happening
to the get printers request.

Jeremy.