From owner-freebsd-ports@FreeBSD.ORG Sat Aug 16 21:23:11 2014 Return-Path: Delivered-To: freebsd-ports@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:1900:2254:206a::19:1]) (using TLSv1 with cipher ADH-AES256-SHA (256/256 bits)) (No client certificate requested) by hub.freebsd.org (Postfix) with ESMTPS id 566338D4 for ; Sat, 16 Aug 2014 21:23:11 +0000 (UTC) Received: from mail0.byshenk.net (portland1.byshenk.net [69.168.54.16]) by mx1.freebsd.org (Postfix) with ESMTP id 2BEF92CF0 for ; Sat, 16 Aug 2014 21:23:10 +0000 (UTC) Received: by mail0.byshenk.net (Postfix, from userid 1002) id 9891E260A8; Sat, 16 Aug 2014 14:14:56 -0700 (PDT) Date: Sat, 16 Aug 2014 14:14:56 -0700 From: Greg Byshenk To: freebsd-ports@freebsd.org Subject: Re: print/cups-base web interface broken & unable to print Message-ID: <20140816211455.GI63213@mail0.byshenk.net> References: <20140606085156.15b32a6c@tmu.ulm.sysgo.com> <1402046122363-5918422.post@n5.nabble.com> <1402077244120-5918572.post@n5.nabble.com> <1403468571037-5922974.post@n5.nabble.com> <1406966510814-5934106.post@n5.nabble.com> <53EF6578.80307@siol.net> MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline In-Reply-To: <53EF6578.80307@siol.net> User-Agent: Mutt/1.5.23 (2014-03-12) Cc: Karel Miklav , Beeblebrox X-BeenThere: freebsd-ports@freebsd.org X-Mailman-Version: 2.1.18-1 Precedence: list List-Id: Porting software to FreeBSD List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Sat, 16 Aug 2014 21:23:11 -0000 On Sat, Aug 16, 2014 at 04:06:48PM +0200, Karel Miklav wrote: > For the record: I was using different HP inkjet printers with > FreeBSD for years, but printing via CUPS/hpijs stopped working > couple of months ago. Scanning via XSane still works though. > > I did some pkg upgrades and "portmaster cups hplip sane", > checked the regular suspects; device permissons, compiled USB > drivers etc, but have no more time to invest into this. I > print from some other operating system hosted in VirtualBox. I note the same thing, since an update last week. Here's debug level output from cups/error_log from an attempted connection to cups web: d [16/Aug/2014:14:10:45 -0700] cupsdAcceptClient(lis=0x803c91640(9)) Clients=0 D [16/Aug/2014:14:10:45 -0700] [Client 13] Accepted from localhost:631 (IPv4) d [16/Aug/2014:14:10:45 -0700] cupsdAddSelect(fd=13, read_cb=0x40f460, write_cb=0x0, data=0x803e26000) D [16/Aug/2014:14:10:45 -0700] [Client 13] Waiting for request. d [16/Aug/2014:14:10:45 -0700] cupsdCheckJobs: 6 active jobs, sleeping=0, reload=0, curtime=1408223445 d [16/Aug/2014:14:10:45 -0700] cupsdCheckJobs: Job 15 - dest="br7840", printer=0x0, state=6, cancel_time=0, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0 d [16/Aug/2014:14:10:45 -0700] cupsdCheckJobs: Job 16 - dest="br7840", printer=0x0, state=6, cancel_time=0, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0 d [16/Aug/2014:14:10:45 -0700] cupsdCheckJobs: Job 17 - dest="br7840", printer=0x0, state=6, cancel_time=0, hold_until=1408200519, kill_time=0, pending_cost=0, pending_timeout=0 d [16/Aug/2014:14:10:45 -0700] cupsdCheckJobs: Job 18 - dest="br7840", printer=0x0, state=6, cancel_time=0, hold_until=1408202432, kill_time=0, pending_cost=0, pending_timeout=0 d [16/Aug/2014:14:10:45 -0700] cupsdCheckJobs: Job 19 - dest="br7840", printer=0x0, state=6, cancel_time=0, hold_until=1408202786, kill_time=0, pending_cost=0, pending_timeout=0 d [16/Aug/2014:14:10:45 -0700] cupsdCheckJobs: Job 20 - dest="br7840", printer=0x0, state=6, cancel_time=0, hold_until=1408203150, kill_time=0, pending_cost=0, pending_timeout=0 d [16/Aug/2014:14:10:45 -0700] select_timeout: JobHistoryUpdate=0 d [16/Aug/2014:14:10:46 -0700] [Client 13] cupsdReadClient error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0x0(), file=-1 D [16/Aug/2014:14:10:46 -0700] [Client 13] GET / HTTP/1.1 D [16/Aug/2014:14:10:46 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" d [16/Aug/2014:14:10:46 -0700] cupsdFindBest: uri = "/"... d [16/Aug/2014:14:10:46 -0700] cupsdFindBest: Location /admin/conf Limit 7f d [16/Aug/2014:14:10:46 -0700] cupsdFindBest: Location /admin Limit 7f d [16/Aug/2014:14:10:46 -0700] cupsdFindBest: Location / Limit 7f d [16/Aug/2014:14:10:46 -0700] cupsdFindBest: best = / d [16/Aug/2014:14:10:46 -0700] [Client 13] con->uri="/", con->best=0x803c1a4c0(/) d [16/Aug/2014:14:10:46 -0700] [Client 13] Authorization="" D [16/Aug/2014:14:10:46 -0700] [Client 13] No authentication data provided. d [16/Aug/2014:14:10:46 -0700] cupsdIsAuthorized: con->uri="/", con->best=0x803c1a4c0(/) d [16/Aug/2014:14:10:46 -0700] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 d [16/Aug/2014:14:10:46 -0700] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... d [16/Aug/2014:14:10:46 -0700] [Client 13] get_file filestats=0x7fffffff08d0, filename=0x7fffffff1950, len=1024, returning "(null)". d [16/Aug/2014:14:10:46 -0700] [Client 13] cupsdSendError code=404, auth_type=0 D [16/Aug/2014:14:10:46 -0700] [Client 13] Closing because Keep-Alive disabled D [16/Aug/2014:14:10:46 -0700] [Client 13] Closing connection. D [16/Aug/2014:14:10:46 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" d [16/Aug/2014:14:10:46 -0700] cupsdRemoveSelect(fd=13) d [16/Aug/2014:14:10:46 -0700] select_timeout: JobHistoryUpdate=0 d [16/Aug/2014:14:10:46 -0700] cupsdAcceptClient(lis=0x803c91640(9)) Clients=0 D [16/Aug/2014:14:10:46 -0700] [Client 13] Accepted from localhost:631 (IPv4) d [16/Aug/2014:14:10:46 -0700] cupsdAddSelect(fd=13, read_cb=0x40f460, write_cb=0x0, data=0x803e26000) D [16/Aug/2014:14:10:46 -0700] [Client 13] Waiting for request. d [16/Aug/2014:14:10:46 -0700] select_timeout: JobHistoryUpdate=0 d [16/Aug/2014:14:10:46 -0700] [Client 13] cupsdReadClient error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0x0(), file=-1 D [16/Aug/2014:14:10:46 -0700] [Client 13] GET /cups.css HTTP/1.1 D [16/Aug/2014:14:10:46 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" d [16/Aug/2014:14:10:46 -0700] cupsdFindBest: uri = "/cups.css"... d [16/Aug/2014:14:10:46 -0700] cupsdFindBest: Location /admin/conf Limit 7f d [16/Aug/2014:14:10:46 -0700] cupsdFindBest: Location /admin Limit 7f d [16/Aug/2014:14:10:46 -0700] cupsdFindBest: Location / Limit 7f d [16/Aug/2014:14:10:46 -0700] cupsdFindBest: best = / d [16/Aug/2014:14:10:46 -0700] [Client 13] con->uri="/cups.css", con->best=0x803c1a4c0(/) d [16/Aug/2014:14:10:46 -0700] [Client 13] Authorization="" D [16/Aug/2014:14:10:46 -0700] [Client 13] No authentication data provided. d [16/Aug/2014:14:10:46 -0700] cupsdIsAuthorized: con->uri="/cups.css", con->best=0x803c1a4c0(/) d [16/Aug/2014:14:10:46 -0700] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 d [16/Aug/2014:14:10:46 -0700] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... d [16/Aug/2014:14:10:46 -0700] [Client 13] get_file filestats=0x7fffffff08d0, filename=0x7fffffff1950, len=1024, returning "(null)". d [16/Aug/2014:14:10:46 -0700] [Client 13] cupsdSendError code=404, auth_type=0 D [16/Aug/2014:14:10:46 -0700] [Client 13] Closing because Keep-Alive disabled D [16/Aug/2014:14:10:46 -0700] [Client 13] Closing connection. D [16/Aug/2014:14:10:46 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" d [16/Aug/2014:14:10:46 -0700] cupsdRemoveSelect(fd=13) d [16/Aug/2014:14:10:46 -0700] select_timeout: JobHistoryUpdate=0 d [16/Aug/2014:14:10:47 -0700] select_timeout: JobHistoryUpdate=0 d [16/Aug/2014:14:10:47 -0700] select_timeout(0): 86400 seconds to do nothing -- greg byshenk - gbyshenk@byshenk.net - Portland, OR USA