Hi Mark, sorry for the delay.
On Mar 08, Mark Hindley wrote: > On Fri, Mar 07, 2014 at 05:17:30PM +0100, Uwe Storbeck wrote: > > So we have 2 different problems, apt-get/aptitude on stable > > and apt-get on testing/unstable throw the HTTP headers into > > the changelog output when using apt-cacher and aptitude on > > testing/unstable fails to get the changelog because it uses a > > different URL than apt-get to fetch it? > > Could you test these 2 patches and see if they help? I've applied both patches and the patch from your 2nd mail to apt-cacher 1.7.6 from stable. Both apt-get and aptitude work as expected now, when I switch off the use of an external proxy. It looks like the primary issue of this bug is fixed by your patches, aptitude changelog does not fail anymore. But when I use squid as an external proxy (use_proxy = 1) apt-get from stable (version 0.9.7.9+deb7) and testing (version 0.9.15.5+b1) and aptitude from stable (version 0.6.8.2-1), but not aptitude from testing (version 0.6.10-1), still throw HTTP headers into the changelog output when apt-cacher has to retrieve the changelog file from the debian server (see attached apt-cacher log). The output in apt-get or aptitude looks like this: HTTP/1.0 200 Connection established Connection: Keep-Alive Date: Thu, 27 Mar 2014 19:56:32 GMT Via: 1.1 c1:3142 (apt-cacher/1.7.6) Accept-Ranges: bytes Content-Length: 12381 Keep-Alive: timeout=15, max=100 X-AptCacher-URL: http://packages.debian.org/changelogs/pool/main/e/ed/ed_1.10-2/changelog ed (1.10-2) unstable; urgency=medium * enable DEB_BUILD_MAINT_OPTIONS = hardening=+all -- Martin Zobel-Helas <zo...@debian.org> Tue, 25 Feb 2014 21:58:25 +0100 ed (1.10-1) unstable; urgency=medium [..] When apt-cacher can deliver the changelog from its cache the output is always displayed as expected, without the HTTP headers. I hope the log helps to also fix the problem with the displayed HTTP headers. Thanks Uwe
Thu Mar 27 20:56:31 2014|debug [1897]: Connection from 172.18.1.2 Thu Mar 27 20:56:31 2014|debug [6133]: New Daemon connection Thu Mar 27 20:56:31 2014|debug [6133]: Test client 172.18.1.2/32 against localhost: 127.0.0.1/8 Thu Mar 27 20:56:31 2014|debug [6133]: Test client 172.18.1.2/32 against localhost: 0:0:0:0:0:0:0:1/128 Thu Mar 27 20:56:31 2014|debug [6133]: Test client 172.18.1.2/32 against allowed: 172.18.1.0/24 Thu Mar 27 20:56:31 2014|debug [6133]: Client 172.18.1.2/32 passed access control rules Thu Mar 27 20:56:31 2014|debug [6133]: Processing a new request line Thu Mar 27 20:56:31 2014|debug [6133]: got: 'GET http://packages.debian.org/changelogs/pool/main/e/ed/ed_1.10-2/changelog HTTP/1.1' Thu Mar 27 20:56:31 2014|debug [6133]: Processing a new request line Thu Mar 27 20:56:31 2014|debug [6133]: got: 'Host: packages.debian.org' Thu Mar 27 20:56:31 2014|debug [6133]: Processing a new request line Thu Mar 27 20:56:31 2014|debug [6133]: got: 'Accept: text/*' Thu Mar 27 20:56:31 2014|debug [6133]: Processing a new request line Thu Mar 27 20:56:31 2014|debug [6133]: got: 'User-Agent: Debian APT-HTTP/1.3 (0.9.15.5)' Thu Mar 27 20:56:31 2014|debug [6133]: Processing a new request line Thu Mar 27 20:56:31 2014|debug [6133]: got: '' Thu Mar 27 20:56:31 2014|debug [6133]: Checking host packages.debian.org in absolute URI Thu Mar 27 20:56:31 2014|debug [6133]: Different ports in absolute URI: 3142 <=> 80 Thu Mar 27 20:56:31 2014|debug [6133]: Host in Absolute URI is not this server Thu Mar 27 20:56:31 2014|debug [6133]: Resolved request is http://packages.debian.org/changelogs/pool/main/e/ed/ed_1.10-2/changelog Thu Mar 27 20:56:31 2014|debug [6133]: Reverse path_map match: packages\.debian\.org -> debian-changelogs Thu Mar 27 20:56:31 2014|debug [6133]: Changelog file: ed_1.10-2_changelog Thu Mar 27 20:56:31 2014|debug [6133]: Locked header /var/cache/apt-cacher/headers/ed_1.10-2_changelog Thu Mar 27 20:56:31 2014|debug [6133]: Freshness checks Thu Mar 27 20:56:31 2014|debug [6133]: Revalidating ed_1.10-2_changelog. Age: 41 Thu Mar 27 20:56:31 2014|debug [6133]: Libcurl candidate: http://packages.debian.org/changelogs/pool/main/e/ed/ed_1.10-2/changelog Thu Mar 27 20:56:31 2014|debug [6133]: Global lock: Connect libcurl Thu Mar 27 20:56:31 2014|debug [6133]: Connection to running libcurl process found on /var/cache/apt-cacher/libcurl.socket Thu Mar 27 20:56:31 2014|debug [6133]: Release global lock Thu Mar 27 20:56:31 2014|debug [4384]: libcurl: new connection Thu Mar 27 20:56:31 2014|debug [4384]: Libcurl: thawed request HEAD http://packages.debian.org/changelogs/pool/main/e/ed/ed_1.10-2/changelog with headers Pragma: Thu Mar 27 20:56:31 2014|debug [4384]: Init new libcurl object Thu Mar 27 20:56:31 2014|debug [4384]: Add curl handle #13: for http://packages.debian.org/changelogs/pool/main/e/ed/ed_1.10-2/changelog Thu Mar 27 20:56:31 2014|debug [4384]: libcurl: setting up for HEAD request Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Re-using existing connection! (#0) with host http-proxy Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Connected to http-proxy (172.18.1.1) port 8080 (#0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: 0x8d39ae0 is at send pipe head! Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: CONNECT => DO handle 0x8d2f478; (connection #0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: DO => DO_DONE handle 0x8d2f478; (connection #0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: DO_DONE => WAITPERFORM handle 0x8d2f478; (connection #0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: WAITPERFORM => PERFORM handle 0x8d2f478; (connection #0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: HTTP 1.0, assume close after body Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: HTTP/1.0 connection set to keep alive! Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: HTTP/1.0 proxy connection set to keep alive! Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Connection #0 to host http-proxy left intact Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Issue another request to this URL: 'https://packages.debian.org/changelogs/pool/main/e/ed/ed_1.10-2/changelog' Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: About to connect() to proxy http-proxy port 8080 (#1) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Trying 172.18.1.1... Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: connected Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Connected to http-proxy (172.18.1.1) port 8080 (#1) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Establish HTTP proxy tunnel to packages.debian.org:443 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: 0x8d39ae0 is at send pipe head! Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: CONNECT => WAITPROXYCONNECT handle 0x8d2f478; (connection #1) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Multi mode finished polling for response from proxy CONNECT Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Proxy replied OK to CONNECT request Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: found 159 certificates in /etc/ssl/certs/ca-certificates.crt Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: WAITPROXYCONNECT => WAITCONNECT handle 0x8d2f478; (connection #1) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: WAITCONNECT => PROTOCONNECT handle 0x8d2f478; (connection #1) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: server certificate verification OK Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: common name: packages.debian.org (matched) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: server certificate expiration date OK Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: server certificate activation date OK Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: certificate public key: RSA Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: certificate version: #3 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: subject: OU=Domain Control Validated,OU=Gandi Standard SSL,CN=packages.debian.org Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: start date: Mon, 10 Feb 2014 00:00:00 GMT Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: expire date: Tue, 10 Feb 2015 23:59:59 GMT Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: issuer: C=FR,O=GANDI SAS,CN=Gandi Standard SSL CA Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: compression: NULL Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: cipher: AES-128-CBC Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: MAC: SHA1 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: PROTOCONNECT => DO handle 0x8d2f478; (connection #1) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: DO => DO_DONE handle 0x8d2f478; (connection #1) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: DO_DONE => WAITPERFORM handle 0x8d2f478; (connection #1) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: WAITPERFORM => PERFORM handle 0x8d2f478; (connection #1) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: HTTP 1.1 or later with persistent connection, pipelining supported Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Closing connection #1 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Issue another request to this URL: 'http://ftp-master.metadata.debian.org/changelogs/main/e/ed/ed_1.10-2_changelog' Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Re-using existing connection! (#0) with host http-proxy Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Connected to http-proxy (172.18.1.1) port 8080 (#0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: 0x8d39ae0 is at send pipe head! Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: CONNECT => DO handle 0x8d2f478; (connection #0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: DO => DO_DONE handle 0x8d2f478; (connection #0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: DO_DONE => WAITPERFORM handle 0x8d2f478; (connection #0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: WAITPERFORM => PERFORM handle 0x8d2f478; (connection #0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: HTTP 1.0, assume close after body Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: HTTP/1.0 connection set to keep alive! Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: HTTP/1.0 proxy connection set to keep alive! Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Connection #0 to host http-proxy left intact Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Issue another request to this URL: 'http://metadata.ftp-master.debian.org/changelogs/main/e/ed/ed_1.10-2_changelog' Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Re-using existing connection! (#0) with host http-proxy Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Connected to http-proxy (172.18.1.1) port 8080 (#0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: 0x8d39ae0 is at send pipe head! Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: CONNECT => DO handle 0x8d2f478; (connection #0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: DO => DO_DONE handle 0x8d2f478; (connection #0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: DO_DONE => WAITPERFORM handle 0x8d2f478; (connection #0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: WAITPERFORM => PERFORM handle 0x8d2f478; (connection #0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: HTTP 1.0, assume close after body Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: HTTP/1.0 connection set to keep alive! Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: HTTP/1.0 proxy connection set to keep alive! Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: PERFORM => DONE handle 0x8d2f478; (connection #0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Connection #0 to host http-proxy left intact Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Expire cleared Thu Mar 27 20:56:31 2014|debug [4384]: curl handle #13 completed, status: 0 Thu Mar 27 20:56:31 2014|debug [4384]: libcurl active transfers: 0 Thu Mar 27 20:56:31 2014|debug [6133]: libcurl reading of headers complete: 302 Thu Mar 27 20:56:31 2014|info [6133]: Got another status line. Redirected?: HTTP/1.0 200 Connection established Thu Mar 27 20:56:31 2014|debug [6133]: libcurl reading of headers complete: 200 Thu Mar 27 20:56:31 2014|info [6133]: Got another status line. Redirected?: HTTP/1.1 302 Found Date: Thu, 27 Mar 2014 19:56:31 GMT Server: Apache Strict-Transport-Security: max-age=5184000 Location: http://ftp-master.metadata.debian.org/changelogs/main/e/ed/ed_1.10-2_changelog Vary: Accept-Encoding Connection: close Content-Type: text/html; charset=iso-8859-1 Thu Mar 27 20:56:31 2014|debug [6133]: libcurl reading of headers complete: 302 Thu Mar 27 20:56:31 2014|info [6133]: Got another status line. Redirected?: HTTP/1.0 301 Moved Permanently Location: http://metadata.ftp-master.debian.org/changelogs/main/e/ed/ed_1.10-2_changelog Content-Type: text/html; charset=iso-8859-1 Content-Length: 366 Accept-Ranges: bytes Date: Thu, 27 Mar 2014 19:56:31 GMT Via: 1.1 varnish Age: 0 X-Served-By: cache-fra1221-FRA X-Cache: MISS X-Cache-Hits: 0 X-Timer: S1395950191.652343035,VS0,VE36 Vary: Accept-Encoding X-Cache: MISS from c1.local.lan X-Cache-Lookup: MISS from c1.local.lan:8080 Connection: keep-alive Proxy-Connection: keep-alive Thu Mar 27 20:56:31 2014|debug [6133]: libcurl reading of headers complete: 301 Thu Mar 27 20:56:31 2014|info [6133]: Got another status line. Redirected?: HTTP/1.0 200 OK Last-Modified: Wed, 26 Feb 2014 03:12:14 GMT ETag: "220516-305d-4f34692437f80" Content-Type: text/plain; charset=utf-8 Content-Length: 12381 Accept-Ranges: bytes Date: Thu, 27 Mar 2014 19:55:50 GMT Via: 1.1 varnish X-Served-By: cache-fra1223-FRA X-Cache: HIT X-Cache-Hits: 1 X-Timer: S1395950150.081004381,VS0,VE0 Age: 2042 X-Cache: HIT from c1.local.lan X-Cache-Lookup: HIT from c1.local.lan:8080 Connection: keep-alive Proxy-Connection: keep-alive Thu Mar 27 20:56:31 2014|debug [6133]: libcurl reading of headers complete: 200 Thu Mar 27 20:56:31 2014|debug [6133]: Found EOF marker and status 0 (No error) Thu Mar 27 20:56:31 2014|debug [6133]: Complete check Thu Mar 27 20:56:31 2014|debug [6133]: Incomplete, no fetcher running, downloading Thu Mar 27 20:56:31 2014|debug [6133]: file does not exist or download required Thu Mar 27 20:56:31 2014|debug [6133]: fetcher: GET http://packages.debian.org/changelogs/pool/main/e/ed/ed_1.10-2/changelog Thu Mar 27 20:56:31 2014|debug [6133]: Libcurl candidate: http://packages.debian.org/changelogs/pool/main/e/ed/ed_1.10-2/changelog Thu Mar 27 20:56:31 2014|debug [6133]: Global lock: Connect libcurl Thu Mar 27 20:56:31 2014|debug [6133]: Connection to running libcurl process found on /var/cache/apt-cacher/libcurl.socket Thu Mar 27 20:56:31 2014|debug [6133]: Release global lock Thu Mar 27 20:56:31 2014|debug [4384]: libcurl: new connection Thu Mar 27 20:56:31 2014|debug [4384]: Libcurl: thawed request GET http://packages.debian.org/changelogs/pool/main/e/ed/ed_1.10-2/changelog with headers Pragma: Thu Mar 27 20:56:31 2014|debug [4384]: Init new libcurl object Thu Mar 27 20:56:31 2014|debug [4384]: Add curl handle #14: for http://packages.debian.org/changelogs/pool/main/e/ed/ed_1.10-2/changelog Thu Mar 27 20:56:31 2014|debug [4384]: libcurl: setting up for GET request Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Re-using existing connection! (#0) with host http-proxy Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Connected to http-proxy (172.18.1.1) port 8080 (#0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: 0x8d39ae0 is at send pipe head! Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: CONNECT => DO handle 0x8d248d0; (connection #0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: DO => DO_DONE handle 0x8d248d0; (connection #0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: DO_DONE => WAITPERFORM handle 0x8d248d0; (connection #0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: WAITPERFORM => PERFORM handle 0x8d248d0; (connection #0) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: HTTP 1.0, assume close after body Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: HTTP/1.0 connection set to keep alive! Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: HTTP/1.0 proxy connection set to keep alive! Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Ignoring the response-body Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Connection #0 to host http-proxy left intact Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Issue another request to this URL: 'https://packages.debian.org/changelogs/pool/main/e/ed/ed_1.10-2/changelog' Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: About to connect() to proxy http-proxy port 8080 (#1) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Trying 172.18.1.1... Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: connected Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Connected to http-proxy (172.18.1.1) port 8080 (#1) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Establish HTTP proxy tunnel to packages.debian.org:443 Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: 0x8d39ae0 is at send pipe head! Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: CONNECT => WAITPROXYCONNECT handle 0x8d248d0; (connection #1) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Multi mode finished polling for response from proxy CONNECT Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: Proxy replied OK to CONNECT request Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: found 159 certificates in /etc/ssl/certs/ca-certificates.crt Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: WAITPROXYCONNECT => WAITCONNECT handle 0x8d248d0; (connection #1) Thu Mar 27 20:56:31 2014|debug [4384]: CURLINFO_TEXT: STATE: WAITCONNECT => PROTOCONNECT handle 0x8d248d0; (connection #1) Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: server certificate verification OK Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: common name: packages.debian.org (matched) Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: server certificate expiration date OK Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: server certificate activation date OK Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: certificate public key: RSA Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: certificate version: #3 Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: subject: OU=Domain Control Validated,OU=Gandi Standard SSL,CN=packages.debian.org Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: start date: Mon, 10 Feb 2014 00:00:00 GMT Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: expire date: Tue, 10 Feb 2015 23:59:59 GMT Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: issuer: C=FR,O=GANDI SAS,CN=Gandi Standard SSL CA Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: compression: NULL Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: cipher: AES-128-CBC Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: MAC: SHA1 Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: STATE: PROTOCONNECT => DO handle 0x8d248d0; (connection #1) Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: STATE: DO => DO_DONE handle 0x8d248d0; (connection #1) Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: STATE: DO_DONE => WAITPERFORM handle 0x8d248d0; (connection #1) Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: STATE: WAITPERFORM => PERFORM handle 0x8d248d0; (connection #1) Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: HTTP 1.1 or later with persistent connection, pipelining supported Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: Closing connection #1 Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: Issue another request to this URL: 'http://ftp-master.metadata.debian.org/changelogs/main/e/ed/ed_1.10-2_changelog' Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: Re-using existing connection! (#0) with host http-proxy Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: Connected to http-proxy (172.18.1.1) port 8080 (#0) Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: 0x8d39ae0 is at send pipe head! Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: STATE: CONNECT => DO handle 0x8d248d0; (connection #0) Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: STATE: DO => DO_DONE handle 0x8d248d0; (connection #0) Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: STATE: DO_DONE => WAITPERFORM handle 0x8d248d0; (connection #0) Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: STATE: WAITPERFORM => PERFORM handle 0x8d248d0; (connection #0) Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: additional stuff not fine transfer.c:1037: 0 0 Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: HTTP 1.0, assume close after body Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: HTTP/1.0 connection set to keep alive! Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: HTTP/1.0 proxy connection set to keep alive! Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: Ignoring the response-body Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: Connection #0 to host http-proxy left intact Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: Issue another request to this URL: 'http://metadata.ftp-master.debian.org/changelogs/main/e/ed/ed_1.10-2_changelog' Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: Re-using existing connection! (#0) with host http-proxy Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: Connected to http-proxy (172.18.1.1) port 8080 (#0) Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: 0x8d39ae0 is at send pipe head! Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: STATE: CONNECT => DO handle 0x8d248d0; (connection #0) Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: STATE: DO => DO_DONE handle 0x8d248d0; (connection #0) Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: STATE: DO_DONE => WAITPERFORM handle 0x8d248d0; (connection #0) Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: STATE: WAITPERFORM => PERFORM handle 0x8d248d0; (connection #0) Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: HTTP 1.0, assume close after body Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: HTTP/1.0 connection set to keep alive! Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: HTTP/1.0 proxy connection set to keep alive! Thu Mar 27 20:56:32 2014|debug [6133]: libcurl reading of headers complete: 302 Thu Mar 27 20:56:32 2014|info [6133]: Got another status line. Redirected?: HTTP/1.0 200 Connection established Thu Mar 27 20:56:32 2014|debug [6133]: libcurl reading of headers complete: 200 Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: STATE: PERFORM => DONE handle 0x8d248d0; (connection #0) Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: Connection #0 to host http-proxy left intact Thu Mar 27 20:56:32 2014|debug [4384]: CURLINFO_TEXT: Expire cleared Thu Mar 27 20:56:32 2014|debug [4384]: curl handle #14 completed, status: 0 Thu Mar 27 20:56:32 2014|debug [6133]: Forked fetcher 6134 Thu Mar 27 20:56:32 2014|debug [4384]: libcurl active transfers: 0 Thu Mar 27 20:56:32 2014|debug [6133]: No Content-Length received for http://packages.debian.org/changelogs/pool/main/e/ed/ed_1.10-2/changelog. You may get better performance using a different upstream server. Thu Mar 27 20:56:32 2014|debug [6133]: Waiting for LOCK_SH on /var/cache/apt-cacher/packages/ed_1.10-2_changelog Thu Mar 27 20:56:32 2014|info [6134]: Got another status line. Redirected?: HTTP/1.1 302 Found Date: Thu, 27 Mar 2014 19:56:32 GMT Server: Apache Strict-Transport-Security: max-age=5184000 Location: http://ftp-master.metadata.debian.org/changelogs/main/e/ed/ed_1.10-2_changelog Vary: Accept-Encoding Content-Length: 332 Connection: close Content-Type: text/html; charset=iso-8859-1 Thu Mar 27 20:56:32 2014|debug [6134]: libcurl reading of headers complete: 302 Thu Mar 27 20:56:32 2014|info [6134]: Got another status line. Redirected?: HTTP/1.0 301 Moved Permanently Location: http://metadata.ftp-master.debian.org/changelogs/main/e/ed/ed_1.10-2_changelog Content-Type: text/html; charset=iso-8859-1 Content-Length: 366 Accept-Ranges: bytes Date: Thu, 27 Mar 2014 19:56:32 GMT Via: 1.1 varnish Age: 0 X-Served-By: cache-fra1221-FRA X-Cache: HIT X-Cache-Hits: 1 X-Timer: S1395950192.102704525,VS0,VE0 Vary: Accept-Encoding X-Cache: MISS from c1.local.lan X-Cache-Lookup: MISS from c1.local.lan:8080 Connection: keep-alive Proxy-Connection: keep-alive Thu Mar 27 20:56:32 2014|debug [6134]: libcurl reading of headers complete: 301 Thu Mar 27 20:56:32 2014|info [6134]: Got another status line. Redirected?: HTTP/1.0 200 OK Last-Modified: Wed, 26 Feb 2014 03:12:14 GMT ETag: "220516-305d-4f34692437f80" Content-Type: text/plain; charset=utf-8 Content-Length: 12381 Accept-Ranges: bytes Date: Thu, 27 Mar 2014 19:55:50 GMT Via: 1.1 varnish X-Served-By: cache-fra1223-FRA X-Cache: HIT X-Cache-Hits: 1 X-Timer: S1395950150.081004381,VS0,VE0 Age: 2043 X-Cache: HIT from c1.local.lan X-Cache-Lookup: HIT from c1.local.lan:8080 Connection: keep-alive Proxy-Connection: keep-alive Thu Mar 27 20:56:32 2014|debug [6134]: libcurl reading of headers complete: 200 Thu Mar 27 20:56:32 2014|debug [6134]: Forked fetcher 6135 Thu Mar 27 20:56:32 2014|debug [6134]: libcurl returned 200 for http://packages.debian.org/changelogs/pool/main/e/ed/ed_1.10-2/changelog Thu Mar 27 20:56:32 2014|debug [6134]: Writing header Thu Mar 27 20:56:32 2014|debug [6135]: Found EOF marker and status 0 (No error) Thu Mar 27 20:56:32 2014|debug [6135]: stored http://packages.debian.org/changelogs/pool/main/e/ed/ed_1.10-2/changelog as /var/cache/apt-cacher/packages/ed_1.10-2_changelog Thu Mar 27 20:56:32 2014|debug [6133]: Got it! Thu Mar 27 20:56:32 2014|debug [6134]: Unlocked header, checks done, can return now Thu Mar 27 20:56:32 2014|debug [6133]: libcurl returned 200 for http://packages.debian.org/changelogs/pool/main/e/ed/ed_1.10-2/changelog Thu Mar 27 20:56:32 2014|debug [6133]: Writing header Thu Mar 27 20:56:32 2014|debug [6133]: Unlocked header, checks done, can return now Thu Mar 27 20:56:32 2014|debug [6134]: Response: 200 OK Thu Mar 27 20:56:32 2014|debug [6134]: Headers: Connection: Keep-Alive Date: Thu, 27 Mar 2014 19:55:50 GMT Via: 1.1 varnish, 1.1 c1:3142 (apt-cacher/1.7.6) Accept-Ranges: bytes ETag: "220516-305d-4f34692437f80" Content-Length: 12381 Content-Type: text/plain; charset=utf-8 Last-Modified: Wed, 26 Feb 2014 03:12:14 GMT Keep-Alive: timeout=15, max=100 Proxy-Connection: keep-alive X-AptCacher-URL: http://packages.debian.org/changelogs/pool/main/e/ed/ed_1.10-2/changelog X-Cache: HIT X-Cache: HIT from c1.local.lan X-Cache-Hits: 1 X-Cache-Lookup: HIT from c1.local.lan:8080 X-Served-By: cache-fra1223-FRA X-Timer: S1395950150.081004381,VS0,VE0 Thu Mar 27 20:56:32 2014|debug [6133]: Response: 200 Connection established Thu Mar 27 20:56:32 2014|debug [6133]: Headers: Connection: Keep-Alive Date: Thu, 27 Mar 2014 19:56:32 GMT Via: 1.1 c1:3142 (apt-cacher/1.7.6) Accept-Ranges: bytes Content-Length: 12381 Keep-Alive: timeout=15, max=100 X-AptCacher-URL: http://packages.debian.org/changelogs/pool/main/e/ed/ed_1.10-2/changelog Thu Mar 27 20:56:32 2014|debug [6134]: ready to send contents of /var/cache/apt-cacher/packages/ed_1.10-2_changelog Thu Mar 27 20:56:32 2014|debug [6133]: ready to send contents of /var/cache/apt-cacher/packages/ed_1.10-2_changelog Thu Mar 27 20:56:32 2014|debug [6133]: sendfile wrote 12381 (sum: 12381) bytes Thu Mar 27 20:56:32 2014|debug [6133]: Got return read lock Thu Mar 27 20:56:32 2014|debug [6134]: sendfile wrote 12381 (sum: 12381) bytes Thu Mar 27 20:56:32 2014|debug [6134]: Got return read lock Thu Mar 27 20:56:32 2014|debug [6133]: Released return read lock Thu Mar 27 20:56:32 2014|debug [6133]: Package sent Thu Mar 27 20:56:32 2014|debug [6134]: Released return read lock Thu Mar 27 20:56:32 2014|debug [6133]: Processing a new request line Thu Mar 27 20:56:32 2014|debug [6134]: Package sent Thu Mar 27 20:56:32 2014|debug [6134]: Processing a new request line Thu Mar 27 20:56:32 2014|debug [6135]: Clean up before exiting. Thu Mar 27 20:56:32 2014|debug [6133]: Clean up before exiting. Thu Mar 27 20:56:32 2014|debug [6134]: Clean up before exiting.
