I have tracked down a locking/filehandle issue that may have been
causing this. I would be really grateful if you would try version
1.6.2pre1 which is available from www.hindley.org.uk/~mark/debian and
let me know if this is fixed for you.
You will also need to upgrade libwww-curl-perl to version 3.12 which is
in unstable.
Could you backport libwww-curl-perl? 1.6.1 had no testing dependencies, but
1.6.2pre1 does.
Just in case it helps confirm the issue I attach two text files with the full
error log, one for 1.5.3 and one for 1.6.1. The client, with the proxy
environment variable, issues a 'apt-get --download-only install git' It works
with 1.5.3 and it fails with 1.6.1.
If a backport of libwww-curl-perl 3.12is available I will try 1.6.2 package.
I'm running stable now but have Debian and Ubuntu clients, that's why I was
willing to test 1.6.1.
Friendly, Eduardo.
Fri Apr 4 11:04:28 2008|local|debug [16485]: Apt-Cacher started with Debug
output enabled, accepting connections...
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16485]: Connection from 172.16.1.65
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16485]: registred child process:
16494
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: New HTTP connection open
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: testing denied_hosts
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: Client 172.16.1.65 passed
access control rules
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: Processing a new request
line
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: got: GET
http://ftp.us.debian.org/debian/pool/main/g/git/git_4.3.20-10_i386.deb HTTP/1.1
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: Processing a new request
line
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: got: Host: ftp.us.debian.org
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: Processing a new request
line
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: got: User-Agent: Debian
APT-HTTP/1.3
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: Processing a new request
line
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: got:
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: new filename with just
basename: git_4.3.20-10_i386.deb
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: looking for
/var/cache/apt-cacher/packages/git_4.3.20-10_i386.deb
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: Entering critical section :
file download decission
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: HIT
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: Exiting critical section
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: checks done, can return now
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: Entering critical section :
reading the header file
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: Exiting critical section
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: Header sent: HTTP/1.0 200
(OK) OK
Connection: Keep-Alive
Accept-Ranges: bytes
Content-Length: 267416
Content-Type: application/x-debian-package
Last-Modified: Mon, 21 Aug 2006 13:32:11 GMT
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: ready to send contents of
/var/cache/apt-cacher/packages/git_4.3.20-10_i386.deb
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: opening file first:
/var/cache/apt-cacher/packages/git_4.3.20-10_i386.deb
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: read 65536 bytes
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: wrote 65536 (sum: 65536)
bytes
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: read 65536 bytes
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: wrote 65536 (sum: 131072)
bytes
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: read 65536 bytes
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: wrote 65536 (sum: 196608)
bytes
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: read 65536 bytes
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: wrote 65536 (sum: 262144)
bytes
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: read 5272 bytes
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: wrote 5272 (sum: 267416)
bytes
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: read 0 bytes
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: complete file found
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: read 0 bytes
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: Package sent
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: Processing a new request
line
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: got:
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: Processing a new request
line
Fri Apr 4 11:04:40 2008|172.16.1.65|debug [16494]: got:
Fri Apr 4 10:57:51 2008|debug [15951]: Connection from 172.16.1.65
Fri Apr 4 10:57:51 2008|debug [15951]: registred child process: 16003
Fri Apr 4 10:57:51 2008|debug [16003]: New Daemon connection
Fri Apr 4 10:57:51 2008|debug [16003]: testing denied_hosts
Fri Apr 4 10:57:51 2008|debug [16003]: Client 172.16.1.65 passed access
control rules
Fri Apr 4 10:57:51 2008|debug [16003]: Processing a new request line
Fri Apr 4 10:57:51 2008|debug [16003]: got: GET
http://ftp.us.debian.org/debian/pool/main/g/git/git_4.3.20-10_i386.deb HTTP/1.1
Fri Apr 4 10:57:51 2008|debug [16003]: Processing a new request line
Fri Apr 4 10:57:51 2008|debug [16003]: got: Host: ftp.us.debian.org
Fri Apr 4 10:57:51 2008|debug [16003]: Processing a new request line
Fri Apr 4 10:57:51 2008|debug [16003]: got: User-Agent: Debian APT-HTTP/1.3
Fri Apr 4 10:57:51 2008|debug [16003]: Processing a new request line
Fri Apr 4 10:57:51 2008|debug [16003]: got:
Fri Apr 4 10:57:51 2008|debug [16003]: Checking host ftp.us.debian.org in
absolute URI
Fri Apr 4 10:59:51 2008|debug [15951]: Connection from 172.16.1.65
Fri Apr 4 10:59:51 2008|debug [15951]: registred child process: 16133
Fri Apr 4 10:59:51 2008|debug [16133]: New Daemon connection
Fri Apr 4 10:59:51 2008|debug [16133]: testing denied_hosts
Fri Apr 4 10:59:51 2008|debug [16133]: Client 172.16.1.65 passed access
control rules
Fri Apr 4 10:59:51 2008|debug [16133]: Processing a new request line
Fri Apr 4 10:59:51 2008|debug [16133]: got: GET
http://ftp.us.debian.org/debian/pool/main/g/git/git_4.3.20-10_i386.deb HTTP/1.1
Fri Apr 4 10:59:51 2008|debug [16133]: Processing a new request line
Fri Apr 4 10:59:51 2008|debug [16133]: got: Host: ftp.us.debian.org
Fri Apr 4 10:59:51 2008|debug [16133]: Processing a new request line
Fri Apr 4 10:59:51 2008|debug [16133]: got: User-Agent: Debian APT-HTTP/1.3
Fri Apr 4 10:59:51 2008|debug [16133]: Processing a new request line
Fri Apr 4 10:59:51 2008|debug [16133]: got:
Fri Apr 4 10:59:51 2008|debug [16133]: Checking host ftp.us.debian.org in
absolute URI
Fri Apr 4 11:01:02 2008|debug [16003]: Host in Absolute URI is not this server
Fri Apr 4 11:01:02 2008|debug [16003]: Resolved request is
/ftp.us.debian.org/debian/pool/main/g/git/git_4.3.20-10_i386.deb
Fri Apr 4 11:01:02 2008|debug [16003]: new base file: git_4.3.20-10_i386.deb
Fri Apr 4 11:01:02 2008|debug [16003]: looking for
/var/cache/apt-cacher/packages/git_4.3.20-10_i386.deb
Fri Apr 4 11:01:02 2008|debug [16003]: Entering critical section : file
download decision
Fri Apr 4 11:01:02 2008|debug [16003]: file does not exist or download
required, forking fetcher
Fri Apr 4 11:01:02 2008|debug [16003]: MISS
Fri Apr 4 11:01:02 2008|debug [16003]: registered child process: 16285
Fri Apr 4 11:01:02 2008|debug [16003]: checks done, can return now
Fri Apr 4 11:01:02 2008|debug [16285]: fetcher: try to fetch
http://ftp.us.debian.org/debian/pool/main/g/git/git_4.3.20-10_i386.deb
Fri Apr 4 11:01:02 2008|debug [16285]: Exiting critical section
Fri Apr 4 11:01:02 2008|debug [16285]: Init new libcurl object
Fri Apr 4 11:01:02 2008|debug [16285]: download agent: setting up for GET
request
Fri Apr 4 11:01:02 2008|debug [16285]: download agent: getting
http://ftp.us.debian.org/debian/pool/main/g/git/git_4.3.20-10_i386.deb
Fri Apr 4 11:01:03 2008|debug [16285]: libcurl download of headers complete
Fri Apr 4 11:01:03 2008|debug [16285]: Entering critical section : libcurl,
storing the header to /var/cache/apt-cacher/headers/git_4.3.20-10_i386.deb
Fri Apr 4 11:01:03 2008|debug [16285]: Exiting critical section
Fri Apr 4 11:01:04 2008|debug [16003]: Entering critical section : reading the
header file
Fri Apr 4 11:01:04 2008|debug [16003]: Exiting critical section
Fri Apr 4 11:01:04 2008|debug [16003]: Header sent: HTTP/1.0 200 (OK) OK
Connection: Keep-Alive
Accept-Ranges: bytes
ETag: "10a8c006-41498-41b872324ecc0"
Content-Length: 267416
Content-Type: application/x-debian-package
Last-Modified: Mon, 21 Aug 2006 13:32:11 GMT
Fri Apr 4 11:01:04 2008|debug [16003]: ready to send contents of
/var/cache/apt-cacher/packages/git_4.3.20-10_i386.deb
Fri Apr 4 11:01:04 2008|debug [16003]: opening file first:
/var/cache/apt-cacher/packages/git_4.3.20-10_i386.deb
Fri Apr 4 11:01:04 2008|debug [16003]: read 0 bytes
Fri Apr 4 11:01:05 2008|debug [16003]: read 8192 bytes
Fri Apr 4 11:01:25 2008|debug [16285]: libcurl returned
Fri Apr 4 11:01:25 2008|debug [16285]: stored
http://ftp.us.debian.org/debian/pool/main/g/git/git_4.3.20-10_i386.deb as
/var/cache/apt-cacher/packages/git_4.3.20-10_i386.deb
Fri Apr 4 11:01:25 2008|debug [16285]: setting complete flag for
git_4.3.20-10_i386.deb
Fri Apr 4 11:01:25 2008|debug [16285]: fetcher done