Package: apt-cacher
Version: 1.5.3
Severity: normal

This is an important problem for me, but seemingly idiosyncratic.

Earlier today aptitude update failed with
Failed to fetch 
http://localhost:3142/linux.csua.berkeley.edu/debian/dists/testing/Release.gpg  
Connection failed
Failed to fetch 
http://localhost:3142/linux.csua.berkeley.edu/debian/dists/unstable/Release.gpg 
 Connection failed
Failed to fetch 
http://localhost:3142/linux.csua.berkeley.edu/debian/dists/testing/main/binary-i386/Packages.gz
  Connection failed
Failed to fetch 
http://localhost:3142/linux.csua.berkeley.edu/debian/dists/testing/contrib/binary-i386/Packages.gz
  Connection failed
Failed to fetch 
http://localhost:3142/linux.csua.berkeley.edu/debian/dists/testing/non-free/binary-i386/Packages.gz
  Connection failed
Failed to fetch 
http://localhost:3142/linux.csua.berkeley.edu/debian/dists/unstable/main/binary-i386/Packages.gz
  Connection failed
Failed to fetch 
http://localhost:3142/linux.csua.berkeley.edu/debian/dists/unstable/main/source/Sources.gz
  Connection failed
Failed to fetch 
http://localhost:3142/linux.csua.berkeley.edu/debian/dists/unstable/contrib/source/Sources.gz
  Connection failed
Failed to fetch 
http://localhost:3142/linux.csua.berkeley.edu/debian/dists/unstable/non-free/source/Sources.gz
  Connection failed

The subsequent apt-get dist-upgrade failed with
WARNING: The following packages cannot be authenticated!
  libc6-pic libc6-dev libc6-dbg libc6 grace python-netcdf libnetcdf3
<snip>
E: There are problems and -y was used without --force-yes
and nothing happened.

The apt-cacher error log seems to indicate timeouts contacting the
original archive, and I suspected I might have caught it during an
update.  However, the timeouts occur hours later, and I'm not sure the
error code 500 indicates a timeout in this case.  In earlier cases I
see a several minute delay between the request and the timeout;
apparently not in the latest time (though it's possible the requests
are overlapped).  Also, the errors recurred about 6 hours later when
the daily cleanup job ran.

I suspect the same underlying cause may be at work here as in my
report 354428, but the symptoms differ.  I'm getting different error
messages, and the Packages.bz2 files seem to be OK.

I had debugging enabled from the previous incident.  Here's a piece of
the error log; I'll send the full thing offlist.

Sat Mar 25 03:00:08 2006|127.0.0.1|debug [5089]: Processing a new request line
Sat Mar 25 03:00:08 2006|127.0.0.1|debug [5089]: got: 
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [22471]: Connection from 127.0.0.1
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [22471]: registred child process: 5282
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [5282]: New HTTP connection open
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [5282]: client is localhost
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [5282]: Client 127.0.0.1 passed access 
control rules
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [5282]: Processing a new request line
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [5282]: got: GET 
/linux.csua.berkeley.edu/debian/dists/testing/contrib/binary-i386/Packages.bz2 
HTTP/1.1
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [5282]: Processing a new request line
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [5282]: got: Host: localhost:3142
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [5282]: Processing a new request line
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [5282]: got: Connection: keep-alive
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [5282]: Processing a new request line
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [5282]: got: If-Modified-Since: Thu, 
23 Mar 2006 20:26:32 GMT
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [5282]: Processing a new request line
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [5282]: got: User-Agent: Debian 
APT-HTTP/1.3
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [5282]: Processing a new request line
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [5282]: got: 
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [5282]: new long filename: 
linux.csua.berkeley.edu_debian_dists_testing_contrib_binary-i386_Packages.bz2
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [5282]: looking for 
/usr/local/var/mirrors/apt-cacher/packages/linux.csua.berkeley.edu_debian_dists_testing_contrib_binary-i386_Packages.bz2
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [5282]: known as index file: 
Packages.bz2
Sat Mar 25 03:00:10 2006|127.0.0.1|debug [5282]: download agent: getting 
http://linux.csua.berkeley.edu/debian/dists/testing/contrib/binary-i386/Packages.bz2
Sat Mar 25 03:01:10 2006|127.0.0.1|debug [5139]: unlinking 
linux.csua.berkeley.edu_debian_dists_testing_main_binary-i386_Packages.bz2 
because it differs from server's version
Sat Mar 25 03:01:10 2006|127.0.0.1|debug [5139]: EXPIRED
Sat Mar 25 03:01:10 2006|127.0.0.1|debug [5139]: Entering critical section : 
file download decission
Sat Mar 25 03:01:10 2006|127.0.0.1|debug [5139]: file does not exist or so, 
creating it
Sat Mar 25 03:01:10 2006|127.0.0.1|debug [5139]: registred child process: 5286
Sat Mar 25 03:01:10 2006|127.0.0.1|debug [5139]: checks done, can return now
Sat Mar 25 03:01:10 2006|127.0.0.1|debug [5286]: Exiting critical section
Sat Mar 25 03:01:10 2006|127.0.0.1|debug [5286]: fetcher: try to fetch 
http://linux.csua.berkeley.edu/debian/dists/testing/main/binary-i386/Packages.bz2
Sat Mar 25 03:01:10 2006|127.0.0.1|debug [5286]: download agent: getting 
http://linux.csua.berkeley.edu/debian/dists/testing/main/binary-i386/Packages.bz2

later ...

Sat Mar 25 03:04:10 2006|127.0.0.1|debug [5286]: Get is back
Sat Mar 25 03:04:10 2006|127.0.0.1|debug [5286]: Reporting error: 500
Sat Mar 25 03:04:10 2006|127.0.0.1|debug [5286]: Entering critical section : 
HTTP error report
Sat Mar 25 03:04:10 2006|127.0.0.1|debug [5286]: Exiting critical section
Sat Mar 25 03:04:10 2006|127.0.0.1|debug [5286]: fetcher exiting

Sat Mar 25 03:04:10 2006|127.0.0.1|debug [5139]: Package sent
Sat Mar 25 03:04:10 2006|127.0.0.1|debug [5139]: Processing a new request line
Sat Mar 25 03:04:10 2006|127.0.0.1|debug [5139]: got: 
Sat Mar 25 03:04:10 2006|127.0.0.1|debug [5139]: Processing a new request line
Sat Mar 25 03:04:10 2006|127.0.0.1|debug [5139]: got: 
Sat Mar 25 03:04:11 2006|127.0.0.1|debug [5126]: abort (timeout)

-- System Information:
Debian Release: testing/unstable
  APT prefers testing
  APT policy: (990, 'testing'), (990, 'stable'), (50, 'unstable')
Architecture: i386 (i686)
Shell:  /bin/sh linked to /bin/bash
Kernel: Linux 2.4.27advncdfs
Locale: LANG=en_US, LC_CTYPE=en_US (charmap=ISO-8859-1)

Versions of packages apt-cacher depends on:
ii  bzip2                         1.0.3-2    high-quality block-sorting file co
ii  libwww-perl                   5.805-1    WWW client/server library for Perl
ii  perl                          5.8.8-2    Larry Wall's Practical Extraction 

apt-cacher recommends no packages.

-- no debconf information


-- 
To UNSUBSCRIBE, email to [EMAIL PROTECTED]
with a subject of "unsubscribe". Trouble? Contact [EMAIL PROTECTED]

Reply via email to