I am also having this problem on Ubuntu Gutsy where it works from other
clients but not from the machine that actually runs apt-cacher. I
installed 1.5.4 to no avail.
Output from aptitude:
Err http://192.168.0.2 gutsy/restricted linux-generic 2.6.22.12.15
500 read timeout
E: Failed to fetch
http://192.168.0.2:3142/au.archive.ubuntu.com/ubuntu/pool/restricted/l/linux-meta/linux-generic_2.6.22.12.15_i386.deb:
500 read timeout
Output in /var/log/apt-cacher/error.log:
Wed Sep 26 13:21:26 2007|local|debug [3700]: Apt-Cacher started with
Debug output enabled, accepting connections...
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3700]: Connection from
192.168.0.2
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3706]: New HTTP connection open
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3706]: testing denied_hosts
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3706]: Client 192.168.0.2
passed access control rules
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3706]: Processing a new
request line
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3706]: got: GET
http://192.168.0.2:3142/au.archive.ubuntu.com/ubuntu/pool/restricted/l/linux-meta/linux-generic_2.6.22.12.15_i386.deb
HTTP/1.1
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3706]: Processing a new
request line
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3706]: got: Host:
192.168.0.2:3142
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3706]: Processing a new
request line
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3706]: got: User-Agent:
Ubuntu APT-HTTP/1.3 (0.7.6ubuntu11)
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3706]: Processing a new
request line
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3706]: got:
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3706]: new filename with
just basename: linux-generic_2.6.22.12.15_i386.deb
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3706]: looking for
/var/cache/apt-cacher/packages/linux-generic_2.6.22.12.15_i386.deb
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3706]: Entering critical
section : file download decision
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3706]: no fetcher running,
forcing download
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3706]: file does not exist
or so, creating it
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3706]: MISS
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3700]: registred child
process: 3706
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3707]: Exiting critical section
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3707]: fetcher: try to fetch
http://192.168.0.2:3142/au.archive.ubuntu.com/ubuntu/pool/restricted/l/linux-meta/linux-generic_2.6.22.12.15_i386.deb
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3706]: registered child
process: 3707
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3706]: checks done, can
return now
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3707]: download agent:
getting
http://192.168.0.2:3142/au.archive.ubuntu.com/ubuntu/pool/restricted/l/linux-meta/linux-generic_2.6.22.12.15_i386.deb
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3700]: Connection from
192.168.0.2
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: New HTTP connection open
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3700]: registred child
process: 3708
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: testing denied_hosts
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: Client 192.168.0.2
passed access control rules
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: Processing a new
request line
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: got: GET
/au.archive.ubuntu.com/ubuntu/pool/restricted/l/linux-meta/linux-generic_2.6.22.12.15_i386.deb
HTTP/1.1
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: Processing a new
request line
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: got: TE:
deflate,gzip;q=0.3
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: Processing a new
request line
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: got: Keep-Alive: 300
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: Processing a new
request line
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: got: Connection:
Keep-Alive, TE
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: Processing a new
request line
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: got: Host:
192.168.0.2:3142
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: Processing a new
request line
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: got: User-Agent:
libwww-perl/5.805
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: Processing a new
request line
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: got:
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: new filename with
just basename: linux-generic_2.6.22.12.15_i386.deb
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: looking for
/var/cache/apt-cacher/packages/linux-generic_2.6.22.12.15_i386.deb
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: Entering critical
section : file download decision
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: Exiting critical section
Wed Sep 26 13:21:33 2007|192.168.0.2|debug [3708]: checks done, can
return now
Wed Sep 26 13:23:33 2007|192.168.0.2|debug [3700]: Connection from
192.168.0.2
Wed Sep 26 13:23:33 2007|192.168.0.2|debug [3700]: registred child
process: 3750
Wed Sep 26 13:23:33 2007|192.168.0.2|debug [3750]: New HTTP connection open
Wed Sep 26 13:23:33 2007|192.168.0.2|debug [3750]: testing denied_hosts
Wed Sep 26 13:23:33 2007|192.168.0.2|debug [3750]: Client 192.168.0.2
passed access control rules
Wed Sep 26 13:23:33 2007|192.168.0.2|debug [3750]: Processing a new
request line
Wed Sep 26 13:23:33 2007|192.168.0.2|debug [3750]: got: GET
http://192.168.0.2:3142/au.archive.ubuntu.com/ubuntu/pool/restricted/l/linux-meta/linux-generic_2.6.22.12.15_i386.deb
HTTP/1.1
Wed Sep 26 13:23:33 2007|192.168.0.2|debug [3750]: Processing a new
request line
Wed Sep 26 13:23:33 2007|192.168.0.2|debug [3750]: got: Host:
192.168.0.2:3142
Wed Sep 26 13:23:33 2007|192.168.0.2|debug [3750]: Processing a new
request line
Wed Sep 26 13:23:33 2007|192.168.0.2|debug [3750]: got: User-Agent:
Ubuntu APT-HTTP/1.3 (0.7.6ubuntu11)
Wed Sep 26 13:23:33 2007|192.168.0.2|debug [3750]: Processing a new
request line
Wed Sep 26 13:23:33 2007|192.168.0.2|debug [3750]: got:
Wed Sep 26 13:23:33 2007|192.168.0.2|debug [3750]: new filename with
just basename: linux-generic_2.6.22.12.15_i386.deb
Wed Sep 26 13:23:33 2007|192.168.0.2|debug [3750]: looking for
/var/cache/apt-cacher/packages/linux-generic_2.6.22.12.15_i386.deb
Wed Sep 26 13:23:33 2007|192.168.0.2|debug [3750]: Entering critical
section : file download decision
Wed Sep 26 13:23:33 2007|192.168.0.2|debug [3750]: Exiting critical section
Wed Sep 26 13:23:33 2007|192.168.0.2|debug [3750]: checks done, can
return now
Wed Sep 26 13:24:33 2007|192.168.0.2|debug [3707]: Get is back
Wed Sep 26 13:24:33 2007|192.168.0.2|debug [3707]: Reporting error: 500
Wed Sep 26 13:24:33 2007|192.168.0.2|debug [3707]: Entering critical
section : HTTP error report
Wed Sep 26 13:24:33 2007|192.168.0.2|debug [3707]: Exiting critical section
Wed Sep 26 13:24:33 2007|192.168.0.2|debug [3707]: fetcher exiting
Wed Sep 26 13:24:33 2007|192.168.0.2|debug [3708]: Entering critical
section : reading the header file
Wed Sep 26 13:24:33 2007|192.168.0.2|debug [3708]: Exiting critical section
Wed Sep 26 13:24:33 2007|192.168.0.2|debug [3708]: Header sent: HTTP/1.1
500 read timeout
Connection: Close
Wed Sep 26 13:24:33 2007|192.168.0.2|debug [3708]: Package sent
Wed Sep 26 13:24:34 2007|192.168.0.2|debug [3750]: Entering critical
section : reading the header file
Wed Sep 26 13:24:34 2007|192.168.0.2|debug [3750]: Exiting critical section
Wed Sep 26 13:24:34 2007|192.168.0.2|debug [3750]: Header sent: HTTP/1.1
500 read timeout
Connection: Keep-Alive
Wed Sep 26 13:24:34 2007|192.168.0.2|debug [3750]: Package sent
Wed Sep 26 13:24:34 2007|192.168.0.2|debug [3750]: Processing a new
request line
Wed Sep 26 13:24:34 2007|192.168.0.2|debug [3750]: got:
Wed Sep 26 13:24:34 2007|192.168.0.2|debug [3750]: Processing a new
request line
Wed Sep 26 13:24:34 2007|192.168.0.2|debug [3750]: got:
Wed Sep 26 13:24:34 2007|192.168.0.2|debug [3706]: Entering critical
section : reading the header file
Wed Sep 26 13:24:34 2007|192.168.0.2|debug [3706]: Exiting critical section
Wed Sep 26 13:24:34 2007|192.168.0.2|debug [3706]: Header sent: HTTP/1.1
500 read timeout
Connection: Keep-Alive
Wed Sep 26 13:24:34 2007|192.168.0.2|debug [3706]: Package sent
Wed Sep 26 13:24:34 2007|192.168.0.2|debug [3706]: Processing a new
request line
Wed Sep 26 13:24:34 2007|192.168.0.2|debug [3706]: got:
Wed Sep 26 13:24:34 2007|192.168.0.2|debug [3706]: Processing a new
request line
Wed Sep 26 13:24:34 2007|192.168.0.2|debug [3706]: got:
The weird thing is that if I cut and paste the failed http line to a
browser it sometime works...
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3700]: Connection from
192.168.0.2
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: New HTTP connection open
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: testing denied_hosts
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: Client 192.168.0.2
passed access control rules
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: Processing a new
request line
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: got: GET
/au.archive.ubuntu.com/ubuntu/pool/restricted/l/linux-meta/linux-generic_2.6.22.12.15_i386.deb
HTTP/1.1
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: Processing a new
request line
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: got: Host:
192.168.0.2:3142
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: Processing a new
request line
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: got: User-Agent:
Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.8.1.6) Gecko/20070827
Ubuntu/7.10 (gutsy) Firefox/2.0.0.6
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: Processing a new
request line
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: got: Accept:
text/xml,application/xml,application/xhtml+xml,text/html;q=0.9,text/plain;q=0.8,image/png,*/*;q=0.5
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: Processing a new
request line
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: got: Accept-Language:
en-us,en;q=0.5
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: Processing a new
request line
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: got: Accept-Encoding:
gzip,deflate
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: Processing a new
request line
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: got: Accept-Charset:
ISO-8859-1,utf-8;q=0.7,*;q=0.7
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: Processing a new
request line
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: got: Keep-Alive: 300
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: Processing a new
request line
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: got: Connection:
keep-alive
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: Processing a new
request line
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: got:
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: new filename with
just basename: linux-generic_2.6.22.12.15_i386.deb
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: looking for
/var/cache/apt-cacher/packages/linux-generic_2.6.22.12.15_i386.deb
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: Entering critical
section : file download decision
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: no fetcher running,
forcing download
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: file does not exist
or so, creating it
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: MISS
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3840]: Exiting critical section
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3840]: fetcher: try to fetch
http://au.archive.ubuntu.com/ubuntu/pool/restricted/l/linux-meta/linux-generic_2.6.22.12.15_i386.deb
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3840]: download agent:
getting
http://au.archive.ubuntu.com/ubuntu/pool/restricted/l/linux-meta/linux-generic_2.6.22.12.15_i386.deb
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: registered child
process: 3840
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3839]: checks done, can
return now
Wed Sep 26 13:26:50 2007|192.168.0.2|debug [3700]: registred child
process: 3839
Wed Sep 26 13:26:51 2007|192.168.0.2|debug [3840]: Entering critical
section : Callback, storing the header
Wed Sep 26 13:26:51 2007|192.168.0.2|debug [3840]: Exiting critical section
Wed Sep 26 13:26:51 2007|192.168.0.2|debug [3840]: Get is back
Wed Sep 26 13:26:51 2007|192.168.0.2|debug [3840]: stored
http://au.archive.ubuntu.com/ubuntu/pool/restricted/l/linux-meta/linux-generic_2.6.22.12.15_i386.deb
as /var/cache/apt-cacher/packages/linux-generic_2.6.22.12.15_i386.deb
Wed Sep 26 13:26:51 2007|192.168.0.2|debug [3840]: setting complete flag
for linux-generic_2.6.22.12.15_i386.deb
Wed Sep 26 13:26:51 2007|192.168.0.2|debug [3840]: fetcher exiting
Wed Sep 26 13:26:51 2007|192.168.0.2|debug [3839]: Entering critical
section : reading the header file
Wed Sep 26 13:26:51 2007|192.168.0.2|debug [3839]: Exiting critical section
Wed Sep 26 13:26:51 2007|192.168.0.2|debug [3839]: Header sent: HTTP/1.1
200 OK
Connection: Keep-Alive
Accept-Ranges: bytes
Content-Length: 25126
Content-Type: application/octet-stream
Last-Modified: Mon, 24 Sep 2007 08:03:56 GMT
Wed Sep 26 13:26:51 2007|192.168.0.2|debug [3839]: ready to send
contents of
/var/cache/apt-cacher/packages/linux-generic_2.6.22.12.15_i386.deb
Wed Sep 26 13:26:51 2007|192.168.0.2|debug [3839]: opening file first:
/var/cache/apt-cacher/packages/linux-generic_2.6.22.12.15_i386.deb
Wed Sep 26 13:26:51 2007|192.168.0.2|debug [3839]: read 25126 bytes
Wed Sep 26 13:26:51 2007|192.168.0.2|debug [3839]: wrote 25126 (sum:
25126) bytes
Wed Sep 26 13:26:51 2007|192.168.0.2|debug [3839]: read 0 bytes
Wed Sep 26 13:26:51 2007|192.168.0.2|debug [3839]: complete file found
Wed Sep 26 13:26:51 2007|192.168.0.2|debug [3839]: read 0 bytes
Wed Sep 26 13:26:51 2007|192.168.0.2|debug [3839]: Package sent
Wed Sep 26 13:26:51 2007|192.168.0.2|debug [3839]: Processing a new
request line
Wed Sep 26 13:26:51 2007|192.168.0.2|debug [3839]: got:
Wed Sep 26 13:26:51 2007|192.168.0.2|debug [3839]: Processing a new
request line
/etc/apt-cacher/apt-cacher.conf is vanilla per distro.
/etc/apt/source.list as below:
deb http://192.168.0.2:3142/medibuntu.sos-sts.com/repo/ edgy free
deb http://192.168.0.2:3142/medibuntu.sos-sts.com/repo/ edgy non-free
deb-src http://192.168.0.2:3142/medibuntu.sos-sts.com/repo/ edgy free
deb-src http://192.168.0.2:3142/medibuntu.sos-sts.com/repo/ edgy non-free
deb http://192.168.0.2:3142/au.archive.ubuntu.com/ubuntu/ gutsy-security
restricted main multiverse universe
deb-src http://192.168.0.2:3142/au.archive.ubuntu.com/ubuntu/
gutsy-security restricted main multiverse universe
deb http://192.168.0.2:3142/au.archive.ubuntu.com/ubuntu/ gutsy-updates
restricted main multiverse universe
deb-src http://192.168.0.2:3142/au.archive.ubuntu.com/ubuntu/
gutsy-updates restricted main multiverse universe
deb http://192.168.0.2:3142/au.archive.ubuntu.com/ubuntu/ gutsy-proposed
restricted main multiverse universe
deb-src http://192.168.0.2:3142/au.archive.ubuntu.com/ubuntu/
gutsy-proposed restricted main multiverse universe
deb http://192.168.0.2:3142/au.archive.ubuntu.com/ubuntu/
gutsy-backports restricted main multiverse universe
deb-src http://192.168.0.2:3142/au.archive.ubuntu.com/ubuntu/
gutsy-backports restricted main multiverse universe
deb http://192.168.0.2:3142/au.archive.ubuntu.com/ubuntu/ gutsy main
universe restricted multiverse
deb-src http://192.168.0.2:3142/au.archive.ubuntu.com/ubuntu/ gutsy main
universe restricted multiverse
deb http://192.168.0.2:3142/dl.google.com/linux/deb/ stable non-free
--
To UNSUBSCRIBE, email to [EMAIL PROTECTED]
with a subject of "unsubscribe". Trouble? Contact [EMAIL PROTECTED]
