From: Nicolas H. <ni...@hu...> - 2006-12-04 15:00:56
|
Hi, I experience a weird and not-that-much reproducible problem with apt-proxy on Debian etch. Here is the log on the proxy host ("/etc/apt-proxy/apt-proxy-v2.conf" : "debug = all:9") : ============================================================================ 2006/12/04 15:50 CET [Channel,0,192.168.0.173] [CacheEntry] start download:dists/etch/updates/main/binary-i386/Packages.gz 2006/12/04 15:50 CET [Channel,0,192.168.0.173] [DownloadQueue] start next download (security/dists/etch/updates/main/binary-i386/Packages.gz) 2006/12/04 15:50 CET [Channel,0,192.168.0.173] [DownloadQueue] keeping backend server 2006/12/04 15:50 CET [Channel,0,192.168.0.173] [fetcher] fetcher start:Packages.gz 2006/12/04 15:50 CET [Channel,0,192.168.0.173] [Fetcher] Downloading: /var/cache/apt-proxy/security/dists/etch/updates/main/binary-i386/Packages.gz 2006/12/04 15:50 CET [Channel,0,192.168.0.173] [fetcher] Connecting to backend server (security) http://security.debian.org:80 2006/12/04 15:50 CET [Channel,0,192.168.0.173] Starting factory <apt_proxy.fetchers.HttpFetcher instance at 0xa73284cc> 2006/12/04 15:50 CET [Channel,0,192.168.0.173] Starting factory <twisted.protocols.policies.ThrottlingFactory instance at 0xa7328d2c> 2006/12/04 15:50 CET [Uninitialized] [Fetcher] Connected to http://security.debian.org/debian-security 2006/12/04 15:50 CET [Uninitialized] [Fetcher] downloading:dists/etch/updates/main/binary-i386/Packages.gz mtime:1161956310 2006/12/04 15:50 CET [Uninitialized] [http_client] GET:/debian-security/dists/etch/updates/main/binary-i386/Packages.gz 2006/12/04 15:50 CET [Uninitialized] [http_client] host sendHeader:security.debian.org 2006/12/04 15:50 CET [Uninitialized] [http_client] if-modified-since sendHeader:Fri, 27 Oct 2006 13:38:30 GMT 2006/12/04 15:50 CET [Channel,0,192.168.0.173] [CacheEntry] transfer complete: /var/cache/apt-proxy/debian/dists/etch/main/source/Sources.gz 2006/12/04 15:50 CET [Channel,0,192.168.0.173] [Request] finish. Queued: 0 2006/12/04 15:50 CET [Channel,0,192.168.0.173] [CacheEntry] transfer complete: /var/cache/apt-proxy/debian/dists/etch/main/source/Sources.gz 2006/12/04 15:50 CET [Channel,0,192.168.0.173] [Request] finish. Queued: 0 2006/12/04 15:50 CET [Channel,0,192.168.0.173] Unhandled error in Deferred: 2006/12/04 15:50 CET [Channel,0,192.168.0.173] Traceback (most recent call last): File "/usr/lib/python2.4/site-packages/twisted/internet/abstract.py", line 127, in doWrite self.producer.resumeProducing() File "/usr/lib/python2.4/site-packages/twisted/protocols/basic.py", line 442, in resumeProducing self.deferred.callback(self.lastSent) File "/usr/lib/python2.4/site-packages/twisted/internet/defer.py", line 229, in callback self._startRunCallbacks(result) File "/usr/lib/python2.4/site-packages/twisted/internet/defer.py", line 294, in _startRunCallbacks self._runCallbacks() --- <exception caught here> --- File "/usr/lib/python2.4/site-packages/twisted/internet/defer.py", line 307, in _runCallbacks self.result = callback(self.result, *args, **kw) File "/usr/lib/python2.4/site-packages/apt_proxy/cache.py", line 267, in file_transfer_complete request.finish() File "/usr/lib/python2.4/site-packages/apt_proxy/apt_proxy.py", line 324, in finish http.Request.finish(self) File "/usr/lib/python2.4/site-packages/twisted/web/http.py", line 674, in finish self._cleanup() File "/usr/lib/python2.4/site-packages/twisted/web/http.py", line 483, in _cleanup self.channel.requestDone(self) File "/usr/lib/python2.4/site-packages/twisted/web/http.py", line 1101, in requestDone self.requests[0].noLongerQueued() File "/usr/lib/python2.4/site-packages/twisted/web/http.py", line 518, in noLongerQueued self._cleanup() File "/usr/lib/python2.4/site-packages/twisted/web/http.py", line 483, in _cleanup self.channel.requestDone(self) exceptions.AttributeError: Request instance has no attribute 'channel' ============================================================================ Here is the apt-get update output on the client host : ============================================================================ Get:3 http://debian etch/main Sources [1667kB] Get:4 http://debian etch/main Sources [1667kB] 51% [3 Sources gzip 4612096] [4 Sources 160/1667kB 0%] gzip: stdin: unexpected end of file Err http://debian etch/main Sources Sub-process gzip returned an error code (1) Err http://debian etch/main Sources Connection timed out Hit http://debian unstable/main Packages Hit http://debian unstable/main Sources Hit http://debian etch/updates/main Packages Hit http://debian etch/updates/main Sources Fetched 1668kB in 2m9s (12.9kB/s) Failed to fetch http://debian:9999/debian/dists/etch/main/source/Sources.gz Sub-process gzip returned an error code (1) Failed to fetch http://debian:9999/debian/dists/etch/main/source/Sources.gz Connection timed out Reading package lists... Done E: Some index files failed to download, they have been ignored, or old ones used instead. ============================================================================ The "Unhandled error" occurs at the "gzip: stdin: unexpected end of file" step. There is a second error on the client-side, with no obvious exception on the server-side. /etc/apt/sources.list : deb http://debian:9999/debian etch main deb-src http://debian:9999/debian etch main deb http://debian:9999/security etch/updates main deb-src http://debian:9999/security etch/updates main deb http://debian:9999/doloforge unstable main deb-src http://debian:9999/doloforge unstable main /etc/apt-proxy/apt-proxy-v2.conf : [debian] backends = http://ftp.fr.debian.org/debian http://ftp2.fr.debian.org/debian http://ftp.de.debian.org/debian http://ftp2.de.debian.org/debian [security] backends = http://security.debian.org/debian-security http://ftp2.de.debian.org/debian-security [doloforge] backends = http://debian/debarchiver Every package is up-to-date as of today : in sync with etch. -- NH |