Re: [squid-users] ICAP within KVM - Transport Endpoint is Not Connected - SOLVED

From: Chris Palmer <chris.palmer_at_pobox.com>
Date: Wed, 12 Jan 2011 09:41:49 +0000

Finally solved this. It was IPv6 related...

The FC14 install had left me an /etc/hosts file with both IPv4 and IPv6
definitions of "localhost". The ICAP URL used localhost. As soon as I
removed the IPv6 definition from the hosts file squid burst into life.
(Neither the kernel nor squid had IPv6 support).

Chris

On 11/01/2011 17:47, Chris Palmer wrote:
> I have squid and c-icap running ok in a normal server. I am now trying
> to migrate them into a KVM virtual machine. However squid appears to be
> misbehaving and I have absolutely no idea why.
>
> Strace on squid reveals that in the KVM, as squid tries to access the
> ICAP server, it doesn't do a "connect". Instead there is a "futex" call
> followed by a "read" which of course fails with ENOTCONN (Transport
> Endpoint is Not Connected).
>
> The host machine is Fedora 14 64-bit + updates + custom 2.6.37 kernel.
> Squid 3.1.10 +ICAP (both built from source) runs ok there. Within this
> host is a guest under KVM - same 64-bit distribution & updates & kernel
> as the host, just rather less installed, with squid & icap built in the
> same way on the guest and with identical config.
>
> On the guest - squid without icap works fine. The icap-client shows icap
> is working, although the squid strace suggests that isn't relevant.
>
> Two squid straces are below - the first on the host (works) and the
> second on the guest (fails). In both cases the squid cache was freshly
> initialised, squid started and one request run through. Up until the
> line of ***** the straces are very similar. Then the guest one goes
> horribly wrong.
>
> Please - can anyone throw any light on how to resolve? I'm running right
> out of ideas....
>
> TIA
> Chris
>
>
>
> TRACE 1 - HOST
> =============
>
>
> Process 20494 attached - interrupt to quit
> epoll_wait(6, {}, 8192, 946) = 0
> epoll_wait(6, {}, 8192, 1000) = 0
> epoll_wait(6, {}, 8192, 979) = 0
> epoll_wait(6, {}, 8192, 19) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 1000) = 0
> epoll_wait(6, {{EPOLLIN, {u32=15, u64=191441432710479887}}}, 8192, 1000) = 1
> accept(15, {sa_family=AF_INET, sin_port=htons(42358),
> sin_addr=inet_addr("192.168.80.1")}, [16]) = 11
> getsockname(11, {sa_family=AF_INET, sin_port=htons(3128),
> sin_addr=inet_addr("192.168.80.1")}, [16]) = 0
> fcntl(11, F_GETFD) = 0
> fcntl(11, F_SETFD, FD_CLOEXEC) = 0
> fcntl(11, F_GETFL) = 0x2 (flags O_RDWR)
> fcntl(11, F_SETFL, O_RDWR|O_NONBLOCK) = 0
> accept(15, 0x29f8350, [28]) = -1 EAGAIN (Resource
> temporarily unavailable)
> getsockname(11, {sa_family=AF_INET, sin_port=htons(3128),
> sin_addr=inet_addr("192.168.80.1")}, [16]) = 0
> epoll_ctl(6, EPOLL_CTL_ADD, 11, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=11,
> u64=7028459065005047819}}) = 0
> epoll_wait(6, {{EPOLLIN, {u32=11, u64=7028459065005047819}}}, 8192, 257) = 1
> read(11, "GET http://www.google.co.uk/ HTT"..., 4095) = 658
> socket(PF_NETLINK, SOCK_RAW, 0) = 13
> bind(13, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(13, {sa_family=AF_NETLINK, pid=20494, groups=00000000},
> [12]) = 0
> sendto(13, "\24\0\0\0\26\0\1\3\251\222)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0\251\222)M\16P\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 408
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0\251\222)M\16P\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(13) = 0
> socket(PF_NETLINK, SOCK_RAW, 0) = 13
> bind(13, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(13, {sa_family=AF_NETLINK, pid=20494, groups=00000000},
> [12]) = 0
> sendto(13, "\24\0\0\0\26\0\1\3\251\222)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0\251\222)M\16P\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 408
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0\251\222)M\16P\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(13) = 0
> socket(PF_NETLINK, SOCK_RAW, 0) = 13
> bind(13, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(13, {sa_family=AF_NETLINK, pid=20494, groups=00000000},
> [12]) = 0
> sendto(13, "\24\0\0\0\26\0\1\3\251\222)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0\251\222)M\16P\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 408
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0\251\222)M\16P\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(13) = 0
> sendto(7, "\4&\1\0\0\1\0\0\0\0\0\0\3www\6google\2co\2uk\0\0\1"..., 34,
> 0, {sa_family=AF_INET, sin_port=htons(53),
> sin_addr=inet_addr("127.0.0.1")}, 16) = 34
> socket(PF_NETLINK, SOCK_RAW, 0) = 13
> bind(13, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(13, {sa_family=AF_NETLINK, pid=20494, groups=00000000},
> [12]) = 0
> sendto(13, "\24\0\0\0\26\0\1\3\251\222)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0\251\222)M\16P\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 408
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0\251\222)M\16P\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(13) = 0
> epoll_wait(6, {{EPOLLIN, {u32=7, u64=181675879670218759}}}, 8192, 257) = 1
> recvfrom(7,
> "\4&\201\200\0\1\0\10\0\4\0\0\3www\6google\2co\2uk\0\0\1"..., 16384, 0,
> {sa_family=AF_INET, sin_port=htons(53),
> sin_addr=inet_addr("127.0.0.1")}, [16]) = 250
> recvfrom(7, 0x8795c0, 16384, 0, 0x2cf1360, 0x2cf5c00) = -1 EAGAIN
> (Resource temporarily unavailable)
> socket(PF_INET, SOCK_STREAM, IPPROTO_TCP) = 13
> fcntl(13, F_GETFD) = 0
> fcntl(13, F_SETFD, FD_CLOEXEC) = 0
> bind(13, {sa_family=AF_INET, sin_port=htons(0),
> sin_addr=inet_addr("0.0.0.0")}, 16) = 0
> fcntl(13, F_GETFL) = 0x2 (flags O_RDWR)
> fcntl(13, F_SETFL, O_RDWR|O_NONBLOCK) = 0
> setsockopt(13, SOL_TCP, TCP_NODELAY, [1], 4) = 0
> socket(PF_NETLINK, SOCK_RAW, 0) = 14
> bind(14, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(14, {sa_family=AF_NETLINK, pid=20494, groups=00000000},
> [12]) = 0
> sendto(14, "\24\0\0\0\26\0\1\3\251\222)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(14, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0\251\222)M\16P\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 408
> recvmsg(14, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0\251\222)M\16P\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(14) = 0
> connect(13, {sa_family=AF_INET, sin_port=htons(80),
> sin_addr=inet_addr("209.85.146.105")}, 16) = -1 EINPROGRESS (Operation
> now in progress)
> epoll_ctl(6, EPOLL_CTL_ADD, 13, {EPOLLOUT|EPOLLERR|EPOLLHUP, {u32=13,
> u64=30064771085}}) = 0
> epoll_wait(6, {{EPOLLOUT, {u32=13, u64=30064771085}}}, 8192, 231) = 1
> getsockopt(13, SOL_SOCKET, SO_ERROR, [0], [4]) = 0
> epoll_ctl(6, EPOLL_CTL_MOD, 13, {EPOLLIN|EPOLLOUT|EPOLLERR|EPOLLHUP,
> {u32=13, u64=7028459511681646605}}) = 0
> epoll_wait(6, {{EPOLLOUT, {u32=13, u64=7028459511681646605}}}, 8192,
> 212) = 1
> write(13, "GET / HTTP/1.1\r\nHost: www.google"..., 643) = 643
> epoll_wait(6, {{EPOLLOUT, {u32=13, u64=7028459511681646605}}}, 8192,
> 211) = 1
> epoll_ctl(6, EPOLL_CTL_MOD, 13, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=13,
> u64=1157442765139738637}}) = 0
> epoll_wait(6, {{EPOLLIN, {u32=13, u64=1157442765139738637}}}, 8192, 211) = 1
> read(13, "HTTP/1.1 200 OK\r\nDate: Sun, 09 J"..., 2046) = 1210
> brk(0) = 0x2d0e000
> brk(0x2d3c000) = 0x2d3c000
> brk(0) = 0x2d3c000
> brk(0x2d5d000) = 0x2d5d000
> socket(PF_INET, SOCK_STREAM, IPPROTO_IP) = 14
> fcntl(14, F_GETFD) = 0
> fcntl(14, F_SETFD, FD_CLOEXEC) = 0
> bind(14, {sa_family=AF_INET, sin_port=htons(0),
> sin_addr=inet_addr("0.0.0.0")}, 16) = 0
> fcntl(14, F_GETFL) = 0x2 (flags O_RDWR)
> fcntl(14, F_SETFL, O_RDWR|O_NONBLOCK) = 0
> setsockopt(14, SOL_TCP, TCP_NODELAY, [1], 4) = 0
> socket(PF_NETLINK, SOCK_RAW, 0) = 16
> bind(16, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(16, {sa_family=AF_NETLINK, pid=20494, groups=00000000},
> [12]) = 0
> sendto(16, "\24\0\0\0\26\0\1\3\251\222)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(16, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0\251\222)M\16P\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 408
> recvmsg(16, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0\251\222)M\16P\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(16) = 0
> ******************************************************************************************************************************
> ************************** This one is correct
> ******************************
> connect(14, {sa_family=AF_INET, sin_port=htons(1344),
> sin_addr=inet_addr("127.0.0.1")}, 16) = -1 EINPROGRESS (Operation now in
> progress)
> epoll_ctl(6, EPOLL_CTL_ADD, 14, {EPOLLOUT|EPOLLERR|EPOLLHUP, {u32=14,
> u64=30064771086}}) = 0
> epoll_wait(6, {{EPOLLIN, {u32=13, u64=1157442765139738637}}, {EPOLLOUT,
> {u32=14, u64=30064771086}}}, 8192, 111) = 2
> read(13,
> "\234\346\263\262\201u\234N\0\331rI\354\310s,\374\35\362\337=\376\273\257pv\34\337x\31Os"...,
> 2046) = 1406
> getsockopt(14, SOL_SOCKET, SO_ERROR, [0], [4]) = 0
> epoll_ctl(6, EPOLL_CTL_MOD, 14, {EPOLLIN|EPOLLOUT|EPOLLERR|EPOLLHUP,
> {u32=14, u64=7028461195308826638}}) = 0
> socket(PF_NETLINK, SOCK_RAW, 0) = 16
> bind(16, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(16, {sa_family=AF_NETLINK, pid=20494, groups=00000000},
> [12]) = 0
> sendto(16, "\24\0\0\0\26\0\1\3\251\222)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(16, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0\251\222)M\16P\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 408
> recvmsg(16, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0\251\222)M\16P\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(16) = 0
> epoll_wait(6, {{EPOLLIN, {u32=13, u64=1157442765139738637}}, {EPOLLOUT,
> {u32=14, u64=7028461195308826638}}}, 8192, 108) = 2
> read(13,
> "\232iy\302\227\32.m\5\207\6\253A\310\216\212B\350C\16\302\306\267c\373\367\344\356\261W\205\226"...,
> 2046) = 1406
> write(14, "OPTIONS icap://localhost:1344/sr"..., 75) = 75
> epoll_wait(6, {{EPOLLIN|EPOLLOUT, {u32=14, u64=7028461195308826638}}},
> 8192, 106) = 1
> read(14, "ICAP/1.0 200 OK\r\nMethods: RESPMO"..., 65535) = 265
> epoll_ctl(6, EPOLL_CTL_MOD, 14, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=14,
> u64=14}}) = 0
> epoll_ctl(6, EPOLL_CTL_DEL, 14, {0, {u32=14, u64=24174090281549838}}) = 0
> epoll_ctl(6, EPOLL_CTL_ADD, 14, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=14,
> u64=7028460920430919694}}) = 0
> socket(PF_NETLINK, SOCK_RAW, 0) = 16
> bind(16, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(16, {sa_family=AF_NETLINK, pid=20494, groups=00000000},
> [12]) = 0
> sendto(16, "\24\0\0\0\26\0\1\3\251\222)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(16, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0\251\222)M\16P\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 408
> recvmsg(16, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0\251\222)M\16P\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(16) = 0
> socket(PF_NETLINK, SOCK_RAW, 0) = 16
> bind(16, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(16, {sa_family=AF_NETLINK, pid=20494, groups=00000000},
> [12]) = 0
> sendto(16, "\24\0\0\0\26\0\1\3\251\222)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(16, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0\251\222)M\16P\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 408
> recvmsg(16, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0\251\222)M\16P\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(16) = 0
> epoll_ctl(6, EPOLL_CTL_MOD, 14, {EPOLLIN|EPOLLOUT|EPOLLERR|EPOLLHUP,
> {u32=14, u64=7028461195308826638}}) = 0
> epoll_wait(6, {{EPOLLOUT, {u32=14, u64=7028461195308826638}}}, 8192,
> 106) = 1
> write(14, "RESPMOD icap://localhost:1344/sr"..., 1065) = 1065
> epoll_wait(6, {{EPOLLOUT, {u32=14, u64=7028461195308826638}}}, 8192,
> 104) = 1
> write(14,
> "400\r\n\37\213\10\0\0\0\0\0\2\377\305<\371{\243\306\222\277\317_\301\340\215-=#$"...,
> 1036) = 1036
> epoll_wait(6, {{EPOLLIN|EPOLLOUT, {u32=14, u64=7028461195308826638}}},
> 8192, 103) = 1
> read(14, "ICAP/1.0 100 Continue\r\n\r\n", 65535) = 25
> epoll_ctl(6, EPOLL_CTL_MOD, 14, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=14,
> u64=14}}) = 0
> epoll_ctl(6, EPOLL_CTL_MOD, 14, {EPOLLIN|EPOLLOUT|EPOLLERR|EPOLLHUP,
> {u32=14, u64=7028459958358245390}}) = 0
> epoll_wait(6, {{EPOLLOUT, {u32=14, u64=7028459958358245390}}}, 8192,
> 103) = 1
> write(14,
> "aca\r\nP\350\221\302\332\212f0}1\6\213\277\320\221\3\4\37\313\211\366\312,\0379@\374"...,
> 2769) = 2769
> epoll_wait(6, {{EPOLLOUT, {u32=14, u64=7028459958358245390}}}, 8192,
> 103) = 1
> epoll_ctl(6, EPOLL_CTL_MOD, 14, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=14,
> u64=1157442765139738638}}) = 0
> epoll_wait(6, {{EPOLLIN, {u32=13, u64=1157442765139738637}}}, 8192, 103) = 1
> read(13,
> "7\314\34\17\316\21\0170\22q\202;\200\300=Id3\371\204\333\4\342Ib\t\261(t+\340/"...,
> 2046) = 1406
> epoll_ctl(6, EPOLL_CTL_MOD, 14, {EPOLLIN|EPOLLOUT|EPOLLERR|EPOLLHUP,
> {u32=14, u64=7028460920430919694}}) = 0
> epoll_wait(6, {{EPOLLOUT, {u32=14, u64=7028460920430919694}}}, 8192, 91) = 1
> write(14,
> "57e\r\n7\314\34\17\316\21\0170\22q\202;\200\300=Id3\371\204\333\4\342Ib\t\261"...,
> 1413) = 1413
> epoll_wait(6, {{EPOLLOUT, {u32=14, u64=7028460920430919694}}}, 8192, 91) = 1
> epoll_ctl(6, EPOLL_CTL_MOD, 14, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=14,
> u64=1157442765139738638}}) = 0
> epoll_wait(6, {{EPOLLIN, {u32=13, u64=1157442765139738637}}}, 8192, 91) = 1
> read(13,
> "\206\271\0169\323Q}-S/_\317o\230x\3\334\243\331d,\324\267\257\0\10\f7\342\264\247\3"...,
> 2046) = 1406
> epoll_ctl(6, EPOLL_CTL_MOD, 14, {EPOLLIN|EPOLLOUT|EPOLLERR|EPOLLHUP,
> {u32=14, u64=7028460920430919694}}) = 0
> epoll_wait(6, {{EPOLLOUT, {u32=14, u64=7028460920430919694}}}, 8192, 90) = 1
> write(14,
> "57e\r\n\206\271\0169\323Q}-S/_\317o\230x\3\334\243\331d,\324\267\257\0\10\f"...,
> 1413) = 1413
> epoll_wait(6, {{EPOLLOUT, {u32=14, u64=7028460920430919694}}}, 8192, 89) = 1
> epoll_ctl(6, EPOLL_CTL_MOD, 14, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=14,
> u64=1157442765139738638}}) = 0
> epoll_wait(6, {{EPOLLIN, {u32=13, u64=1157442765139738637}}}, 8192, 89) = 1
> read(13,
> "t\252\3178\201\257\7jt\274\362\254\37V\376\32\240\200\6\351\22\374f\220'\251
> \2659(x<"..., 2046) = 1366
> epoll_ctl(6, EPOLL_CTL_MOD, 14, {EPOLLIN|EPOLLOUT|EPOLLERR|EPOLLHUP,
> {u32=14, u64=7028460920430919694}}) = 0
> epoll_wait(6, {{EPOLLOUT, {u32=14, u64=7028460920430919694}}}, 8192, 88) = 1
> write(14,
> "556\r\nt\252\3178\201\257\7jt\274\362\254\37V\376\32\240\200\6\351\22\374f\220'\251
> "..., 1378) = 1378
> epoll_wait(6, {{EPOLLOUT, {u32=14, u64=7028460920430919694}}}, 8192, 86) = 1
> epoll_ctl(6, EPOLL_CTL_MOD, 14, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=14,
> u64=1157442765139738638}}) = 0
> epoll_wait(6, {{EPOLLIN, {u32=14, u64=1157442765139738638}}}, 8192, 86) = 1
> read(14, "ICAP/1.0 200 OK\r\nServer: C-ICAP/"..., 65535) = 136
> epoll_wait(6, {{EPOLLIN, {u32=14, u64=1157442765139738638}}}, 8192, 64) = 1
> read(14, "HTTP/1.1 200 OK\r\nDate: Sun, 09 J"..., 65535) = 305
> epoll_wait(6, {{EPOLLIN, {u32=14, u64=1157442765139738638}}}, 8192, 64) = 1
> read(14,
> "fe0\r\n\37\213\10\0\0\0\0\0\2\377\305<\371{\243\306\222\277\317_\301\340\215-=#$"...,
> 65535) = 4071
> epoll_ctl(6, EPOLL_CTL_MOD, 11, {EPOLLIN|EPOLLOUT|EPOLLERR|EPOLLHUP,
> {u32=11, u64=26089688645238795}}) = 0
> epoll_wait(6, {{EPOLLOUT, {u32=11, u64=26089688645238795}}}, 8192, 63) = 1
> write(11, "HTTP/1.0 200 OK\r\nDate: Sun, 09 J"..., 4186) = 4186
> epoll_wait(6, {{EPOLLOUT, {u32=11, u64=26089688645238795}}}, 8192, 63) = 1
> write(11,
> "\21\0170\22q\202;\200\300=Id3\371\204\333\4\342Ib\t\261(t+\340/h\26}=$"...,
> 273) = 273
> epoll_wait(6, {{EPOLLOUT, {u32=11, u64=26089688645238795}}}, 8192, 63) = 1
> epoll_ctl(6, EPOLL_CTL_MOD, 11, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=11,
> u64=1157442765139738635}}) = 0
> epoll_wait(6, {{EPOLLIN, {u32=14, u64=1157442765139738638}}}, 8192, 62) = 1
> read(14,
> "f3c\r\n\346y\r\265-\254\217\340\200\t\363~Ws\310S\34\250\10\2573X\376\312\331l\234"...,
> 65535) = 3907
> epoll_ctl(6, EPOLL_CTL_MOD, 11, {EPOLLIN|EPOLLOUT|EPOLLERR|EPOLLHUP,
> {u32=11, u64=8589934603}}) = 0
> epoll_wait(6, {{EPOLLOUT, {u32=11, u64=8589934603}}}, 8192, 62) = 1
> write(11,
> "\346y\r\265-\254\217\340\200\t\363~Ws\310S\34\250\10\2573X\376\312\331l\234\307\263\33\226+"...,
> 3900) = 3900
> write(8, "1294570153.416 195 192.168.80"..., 116) = 116
> epoll_wait(6, {{EPOLLOUT, {u32=11, u64=8589934603}}}, 8192, 61) = 1
> epoll_ctl(6, EPOLL_CTL_MOD, 11, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=11,
> u64=1157442765139738635}}) = 0
> epoll_wait(6, {{EPOLLIN, {u32=14, u64=1157442765139738638}}}, 8192, 61) = 1
> read(14, "0\r\n\r\n", 65535) = 5
> epoll_wait(6, {}, 8192, 61) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {{EPOLLIN, {u32=11, u64=1157442765139738635}}}, 8192, 742) = 1
> read(11, "GET http://www.google.co.uk/csi?"..., 4095) = 834
> socket(PF_NETLINK, SOCK_RAW, 0) = 16
> bind(16, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(16, {sa_family=AF_NETLINK, pid=20494, groups=00000000},
> [12]) = 0
> sendto(16, "\24\0\0\0\26\0\1\3\251\222)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(16, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0\251\222)M\16P\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 408
> recvmsg(16, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0\251\222)M\16P\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(16) = 0
> epoll_ctl(6, EPOLL_CTL_DEL, 13, {0, {u32=13, u64=24174090281549837}}) = 0
> epoll_ctl(6, EPOLL_CTL_ADD, 13, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=13,
> u64=7028448928882229261}}) = 0
> epoll_ctl(6, EPOLL_CTL_MOD, 13, {EPOLLIN|EPOLLOUT|EPOLLERR|EPOLLHUP,
> {u32=13, u64=7028447348334264333}}) = 0
> epoll_wait(6, {{EPOLLOUT, {u32=13, u64=7028447348334264333}}}, 8192,
> 590) = 1
> write(13, "GET /csi?v=3&s=webhp&action=&e=1"..., 819) = 819
> epoll_wait(6, {{EPOLLOUT, {u32=13, u64=7028447348334264333}}}, 8192,
> 588) = 1
> epoll_ctl(6, EPOLL_CTL_MOD, 13, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=13,
> u64=1157442765139738637}}) = 0
> epoll_wait(6, {{EPOLLIN, {u32=13, u64=1157442765139738637}}}, 8192, 588) = 1
> read(13, "HTTP/1.1 204 No Content\r\nContent"..., 2046) = 215
> epoll_ctl(6, EPOLL_CTL_DEL, 14, {0, {u32=14, u64=24174090281549838}}) = 0
> epoll_ctl(6, EPOLL_CTL_ADD, 14, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=14,
> u64=7028460920430919694}}) = 0
> socket(PF_NETLINK, SOCK_RAW, 0) = 16
> bind(16, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(16, {sa_family=AF_NETLINK, pid=20494, groups=00000000},
> [12]) = 0
> sendto(16, "\24\0\0\0\26\0\1\3\251\222)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(16, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0\251\222)M\16P\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 408
> recvmsg(16, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0\251\222)M\16P\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(16) = 0
> socket(PF_NETLINK, SOCK_RAW, 0) = 16
> bind(16, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(16, {sa_family=AF_NETLINK, pid=20494, groups=00000000},
> [12]) = 0
> sendto(16, "\24\0\0\0\26\0\1\3\251\222)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(16, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0\251\222)M\16P\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 408
> recvmsg(16, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0\251\222)M\16P\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(16) = 0
> epoll_ctl(6, EPOLL_CTL_MOD, 14, {EPOLLIN|EPOLLOUT|EPOLLERR|EPOLLHUP,
> {u32=14, u64=7028461195308826638}}) = 0
> epoll_wait(6, {{EPOLLOUT, {u32=14, u64=7028461195308826638}}}, 8192,
> 545) = 1
> write(14, "RESPMOD icap://localhost:1344/sr"..., 1219) = 1219
> epoll_wait(6, {{EPOLLIN|EPOLLOUT, {u32=14, u64=7028461195308826638}}},
> 8192, 543) = 1
> read(14, "ICAP/1.0 204 Unmodified\r\nServer:"..., 65535) = 105
> epoll_ctl(6, EPOLL_CTL_MOD, 14, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=14,
> u64=14}}) = 0
> epoll_ctl(6, EPOLL_CTL_MOD, 11, {EPOLLIN|EPOLLOUT|EPOLLERR|EPOLLHUP,
> {u32=11, u64=42949672971}}) = 0
> epoll_wait(6, {{EPOLLOUT, {u32=11, u64=42949672971}}}, 8192, 543) = 1
> write(11, "HTTP/1.0 204 No Content\r\nContent"..., 310) = 310
> write(8, "1294570153.678 47 192.168.80"..., 119) = 119
> epoll_wait(6, {{EPOLLOUT, {u32=11, u64=42949672971}}}, 8192, 542) = 1
> epoll_ctl(6, EPOLL_CTL_MOD, 11, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=11,
> u64=1157442765139738635}}) = 0
> epoll_wait(6, {}, 8192, 542) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 257) = 0
> epoll_wait(6, {}, 8192, 1000) = 0
> epoll_wait(6, {}, 8192, 916) = 0
> open("/var/cache/squid/08/5F",
> O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_CLOEXEC) = 16
> getdents(16, /* 2 entries */, 32768) = 48
> getdents(16, /* 0 entries */, 32768) = 0
> close(16) = 0
> epoll_wait(6, {}, 8192, 59) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, <unfinished ...>
> Process 20494 detached
>
>
> =============================================================================================
>
> TRACE 2 - GUEST
> ==============
>
> Process 3249 attached - interrupt to quit
> epoll_wait(6, {}, 8192, 685) = 0
> epoll_wait(6, {}, 8192, 1000) = 0
> epoll_wait(6, {{EPOLLIN, {u32=15, u64=128430757784846351}}}, 8192, 1000) = 1
> accept(15, {sa_family=AF_INET, sin_port=htons(54043),
> sin_addr=inet_addr("192.168.80.1")}, [16]) = 11
> getsockname(11, {sa_family=AF_INET, sin_port=htons(3128),
> sin_addr=inet_addr("192.168.80.3")}, [16]) = 0
> fcntl(11, F_GETFD) = 0
> fcntl(11, F_SETFD, FD_CLOEXEC) = 0
> fcntl(11, F_GETFL) = 0x2 (flags O_RDWR)
> fcntl(11, F_SETFL, O_RDWR|O_NONBLOCK) = 0
> accept(15, 0x1c869d0, [28]) = -1 EAGAIN (Resource
> temporarily unavailable)
> getsockname(11, {sa_family=AF_INET, sin_port=htons(3128),
> sin_addr=inet_addr("192.168.80.3")}, [16]) = 0
> epoll_ctl(6, EPOLL_CTL_ADD, 11, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=11,
> u64=2884708771404185611}}) = 0
> epoll_wait(6, {{EPOLLIN, {u32=11, u64=2884708771404185611}}}, 8192, 25) = 1
> read(11, "GET http://www.google.co.uk/ HTT"..., 4095) = 658
> socket(PF_NETLINK, SOCK_RAW, 0) = 13
> bind(13, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(13, {sa_family=AF_NETLINK, pid=3249, groups=00000000}, [12]) = 0
> sendto(13, "\24\0\0\0\26\0\1\3&\223)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0&\223)M\261\f\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 176
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0&\223)M\261\f\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(13) = 0
> socket(PF_NETLINK, SOCK_RAW, 0) = 13
> bind(13, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(13, {sa_family=AF_NETLINK, pid=3249, groups=00000000}, [12]) = 0
> sendto(13, "\24\0\0\0\26\0\1\3&\223)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0&\223)M\261\f\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 176
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0&\223)M\261\f\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(13) = 0
> socket(PF_NETLINK, SOCK_RAW, 0) = 13
> bind(13, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(13, {sa_family=AF_NETLINK, pid=3249, groups=00000000}, [12]) = 0
> sendto(13, "\24\0\0\0\26\0\1\3&\223)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0&\223)M\261\f\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 176
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0&\223)M\261\f\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(13) = 0
> sendto(7, "\316\227\1\0\0\1\0\0\0\0\0\0\3www\6google\2co\2uk\0\0\1"...,
> 34, 0, {sa_family=AF_INET, sin_port=htons(53),
> sin_addr=inet_addr("192.168.80.1")}, 16) = 34
> socket(PF_NETLINK, SOCK_RAW, 0) = 13
> bind(13, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(13, {sa_family=AF_NETLINK, pid=3249, groups=00000000}, [12]) = 0
> sendto(13, "\24\0\0\0\26\0\1\3&\223)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0&\223)M\261\f\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 176
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0&\223)M\261\f\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(13) = 0
> epoll_wait(6, {{EPOLLIN, {u32=7, u64=118665685780922375}}}, 8192, 22) = 1
> recvfrom(7,
> "\316\227\201\200\0\1\0\10\0\4\0\0\3www\6google\2co\2uk\0\0\1"...,
> 16384, 0, {sa_family=AF_INET, sin_port=htons(53),
> sin_addr=inet_addr("192.168.80.1")}, [16]) = 250
> recvfrom(7, 0x8795a0, 16384, 0, 0x1c8ad50, 0x1c900e0) = -1 EAGAIN
> (Resource temporarily unavailable)
> socket(PF_INET, SOCK_STREAM, IPPROTO_TCP) = 13
> fcntl(13, F_GETFD) = 0
> fcntl(13, F_SETFD, FD_CLOEXEC) = 0
> bind(13, {sa_family=AF_INET, sin_port=htons(0),
> sin_addr=inet_addr("0.0.0.0")}, 16) = 0
> fcntl(13, F_GETFL) = 0x2 (flags O_RDWR)
> fcntl(13, F_SETFL, O_RDWR|O_NONBLOCK) = 0
> setsockopt(13, SOL_TCP, TCP_NODELAY, [1], 4) = 0
> socket(PF_NETLINK, SOCK_RAW, 0) = 14
> bind(14, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(14, {sa_family=AF_NETLINK, pid=3249, groups=00000000}, [12]) = 0
> sendto(14, "\24\0\0\0\26\0\1\3&\223)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(14, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0&\223)M\261\f\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 176
> recvmsg(14, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0&\223)M\261\f\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(14) = 0
> connect(13, {sa_family=AF_INET, sin_port=htons(80),
> sin_addr=inet_addr("209.85.146.104")}, 16) = -1 EINPROGRESS (Operation
> now in progress)
> epoll_ctl(6, EPOLL_CTL_ADD, 13, {EPOLLOUT|EPOLLERR|EPOLLHUP, {u32=13,
> u64=30064771085}}) = 0
> epoll_wait(6, {}, 8192, 14) = 0
> epoll_wait(6, {{EPOLLOUT, {u32=13, u64=30064771085}}}, 8192, 973) = 1
> getsockopt(13, SOL_SOCKET, SO_ERROR, [0], [4]) = 0
> epoll_ctl(6, EPOLL_CTL_MOD, 13, {EPOLLIN|EPOLLOUT|EPOLLERR|EPOLLHUP,
> {u32=13, u64=2884709218080784397}}) = 0
> epoll_wait(6, {{EPOLLOUT, {u32=13, u64=2884709218080784397}}}, 8192,
> 967) = 1
> write(13, "GET / HTTP/1.1\r\nHost: www.google"..., 643) = 643
> epoll_wait(6, {{EPOLLOUT, {u32=13, u64=2884709218080784397}}}, 8192,
> 965) = 1
> epoll_ctl(6, EPOLL_CTL_MOD, 13, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=13,
> u64=1157442765139738637}}) = 0
> epoll_wait(6, {{EPOLLIN, {u32=13, u64=1157442765139738637}}}, 8192, 965) = 1
> read(13, "HTTP/1.1 200 OK\r\nDate: Sun, 09 J"..., 2046) = 1406
> brk(0) = 0x1c9f000
> brk(0x1cc6000) = 0x1cc6000
> brk(0) = 0x1cc6000
> brk(0x1ce7000) = 0x1ce7000
> socket(PF_INET, SOCK_STREAM, IPPROTO_IP) = 14
> fcntl(14, F_GETFD) = 0
> fcntl(14, F_SETFD, FD_CLOEXEC) = 0
> bind(14, {sa_family=AF_INET, sin_port=htons(0),
> sin_addr=inet_addr("0.0.0.0")}, 16) = 0
> fcntl(14, F_GETFL) = 0x2 (flags O_RDWR)
> fcntl(14, F_SETFL, O_RDWR|O_NONBLOCK) = 0
> setsockopt(14, SOL_TCP, TCP_NODELAY, [1], 4) = 0
> socket(PF_NETLINK, SOCK_RAW, 0) = 16
> bind(16, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(16, {sa_family=AF_NETLINK, pid=3249, groups=00000000}, [12]) = 0
> sendto(16, "\24\0\0\0\26\0\1\3'\223)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(16, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0'\223)M\261\f\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 176
> recvmsg(16, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0'\223)M\261\f\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(16) = 0
> ***************************************************************************************************************************
> ****************************** This one is wrong from here on
> ***************************
> futex(0x7ff49aaf6d24, FUTEX_WAKE_PRIVATE, 2147483647) = 0
> read(14, 0x7fff28078c20, 65535) = -1 ENOTCONN (Transport
> endpoint is not connected)
> close(14) = 0
> stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=3661, ...}) = 0
> write(5, "2011/01/09 10:51:19| essential I"..., 129) = 129
> read(13,
> "\16\2\232\216\277\366@\342\351\3\216\2002\212\300\371,\352\31\214kIA\346\210\351\30(f\362\207\f"...,
> 65535) = 2626
> read(13, 0x7fff28078d10, 65535) = -1 EAGAIN (Resource
> temporarily unavailable)
> open("/usr/local/squid/share/errors/en-us/ERR_ICAP_FAILURE", O_RDONLY) = 14
> fcntl(14, F_GETFD) = 0
> fcntl(14, F_SETFD, FD_CLOEXEC) = 0
> read(14, "<!DOCTYPE html PUBLIC \"-//W3C//D"..., 4096) = 1122
> read(14, "", 4096) = 0
> close(14) = 0
> epoll_ctl(6, EPOLL_CTL_MOD, 11, {EPOLLIN|EPOLLOUT|EPOLLERR|EPOLLHUP,
> {u32=11, u64=26089688645238795}}) = 0
> epoll_ctl(6, EPOLL_CTL_DEL, 13, {0, {u32=13, u64=128506143050825741}}) = 0
> close(13) = 0
> epoll_wait(6, {{EPOLLOUT, {u32=11, u64=26089688645238795}}}, 8192, 898) = 1
> write(11, "HTTP/1.0 500 Internal Server Err"..., 3154) = 3154
> write(8, "1294570279.079 106 192.168.80"..., 116) = 116
> epoll_wait(6, {{EPOLLOUT, {u32=11, u64=26089688645238795}}}, 8192, 894) = 1
> epoll_ctl(6, EPOLL_CTL_MOD, 11, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=11,
> u64=1157442765139738635}}) = 0
> epoll_wait(6, {{EPOLLIN, {u32=11, u64=1157442765139738635}}}, 8192, 892) = 1
> read(11, "GET http://www.squid-cache.org/A"..., 4095) = 441
> socket(PF_NETLINK, SOCK_RAW, 0) = 13
> bind(13, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(13, {sa_family=AF_NETLINK, pid=3249, groups=00000000}, [12]) = 0
> sendto(13, "\24\0\0\0\26\0\1\3'\223)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0'\223)M\261\f\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 176
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0'\223)M\261\f\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(13) = 0
> socket(PF_NETLINK, SOCK_RAW, 0) = 13
> bind(13, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(13, {sa_family=AF_NETLINK, pid=3249, groups=00000000}, [12]) = 0
> sendto(13, "\24\0\0\0\26\0\1\3'\223)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0'\223)M\261\f\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 176
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0'\223)M\261\f\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(13) = 0
> socket(PF_NETLINK, SOCK_RAW, 0) = 13
> bind(13, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(13, {sa_family=AF_NETLINK, pid=3249, groups=00000000}, [12]) = 0
> sendto(13, "\24\0\0\0\26\0\1\3'\223)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0'\223)M\261\f\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 176
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0'\223)M\261\f\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(13) = 0
> sendto(7, "\10n\1\0\0\1\0\0\0\0\0\0\3www\vsquid-cache\3org"..., 37, 0,
> {sa_family=AF_INET, sin_port=htons(53),
> sin_addr=inet_addr("192.168.80.1")}, 16) = 37
> socket(PF_NETLINK, SOCK_RAW, 0) = 13
> bind(13, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(13, {sa_family=AF_NETLINK, pid=3249, groups=00000000}, [12]) = 0
> sendto(13, "\24\0\0\0\26\0\1\3'\223)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0'\223)M\261\f\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 176
> recvmsg(13, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0'\223)M\261\f\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(13) = 0
> epoll_wait(6, {{EPOLLIN, {u32=7, u64=118665685780922375}}}, 8192, 880) = 1
> recvfrom(7, "\10n\201\200\0\1\0\3\0\6\0\5\3www\vsquid-cache\3org"...,
> 16384, 0, {sa_family=AF_INET, sin_port=htons(53),
> sin_addr=inet_addr("192.168.80.1")}, [16]) = 330
> recvfrom(7, 0x8795a0, 16384, 0, 0x1c8ab70, 0x1c8fe20) = -1 EAGAIN
> (Resource temporarily unavailable)
> socket(PF_INET, SOCK_STREAM, IPPROTO_TCP) = 13
> fcntl(13, F_GETFD) = 0
> fcntl(13, F_SETFD, FD_CLOEXEC) = 0
> bind(13, {sa_family=AF_INET, sin_port=htons(0),
> sin_addr=inet_addr("0.0.0.0")}, 16) = 0
> fcntl(13, F_GETFL) = 0x2 (flags O_RDWR)
> fcntl(13, F_SETFL, O_RDWR|O_NONBLOCK) = 0
> setsockopt(13, SOL_TCP, TCP_NODELAY, [1], 4) = 0
> socket(PF_NETLINK, SOCK_RAW, 0) = 14
> bind(14, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
> getsockname(14, {sa_family=AF_NETLINK, pid=3249, groups=00000000}, [12]) = 0
> sendto(14, "\24\0\0\0\26\0\1\3'\223)M\0\0\0\0\0\0\0\0", 20, 0,
> {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
> recvmsg(14, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"8\0\0\0\24\0\2\0'\223)M\261\f\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 176
> recvmsg(14, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
> groups=00000000},
> msg_iov(1)=[{"\24\0\0\0\3\0\2\0'\223)M\261\f\0\0\0\0\0\0\1\0\0\0\10\0\1\0\177\0\0\1"...,
> 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
> close(14) = 0
> connect(13, {sa_family=AF_INET, sin_port=htons(80),
> sin_addr=inet_addr("198.186.193.234")}, 16) = -1 EINPROGRESS (Operation
> now in progress)
> epoll_ctl(6, EPOLL_CTL_ADD, 13, {EPOLLOUT|EPOLLERR|EPOLLHUP, {u32=13,
> u64=30064771085}}) = 0
> epoll_wait(6, {{EPOLLOUT, {u32=13, u64=30064771085}}}, 8192, 874) = 1
> getsockopt(13, SOL_SOCKET, SO_ERROR, [0], [4]) = 0
> epoll_ctl(6, EPOLL_CTL_MOD, 13, {EPOLLIN|EPOLLOUT|EPOLLERR|EPOLLHUP,
> {u32=13, u64=2884709218080784397}}) = 0
> epoll_wait(6, {{EPOLLOUT, {u32=13, u64=2884709218080784397}}}, 8192,
> 776) = 1
> write(13, "GET /Artwork/SN.png HTTP/1.1\r\nHo"..., 423) = 423
> epoll_wait(6, {{EPOLLOUT, {u32=13, u64=2884709218080784397}}}, 8192,
> 774) = 1
> epoll_ctl(6, EPOLL_CTL_MOD, 13, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=13,
> u64=1157442765139738637}}) = 0
> epoll_wait(6, {{EPOLLIN, {u32=13, u64=1157442765139738637}}}, 8192, 774) = 1
> read(13, "HTTP/1.1 200 OK\r\nDate: Sun, 09 J"..., 2046) = 1406
> read(13, 0x7fff28078d10, 65535) = -1 EAGAIN (Resource
> temporarily unavailable)
> open("/usr/local/squid/share/errors/en-us/ERR_ICAP_FAILURE", O_RDONLY) = 14
> fcntl(14, F_GETFD) = 0
> fcntl(14, F_SETFD, FD_CLOEXEC) = 0
> read(14, "<!DOCTYPE html PUBLIC \"-//W3C//D"..., 4096) = 1122
> read(14, "", 4096) = 0
> close(14) = 0
> epoll_ctl(6, EPOLL_CTL_MOD, 11, {EPOLLIN|EPOLLOUT|EPOLLERR|EPOLLHUP,
> {u32=11, u64=26089688645238795}}) = 0
> epoll_ctl(6, EPOLL_CTL_DEL, 13, {0, {u32=13, u64=128506143050825741}}) = 0
> close(13) = 0
> epoll_wait(6, {{EPOLLOUT, {u32=11, u64=26089688645238795}}}, 8192, 665) = 1
> write(11, "HTTP/1.0 500 Internal Server Err"..., 3155) = 3155
> write(8, "1294570279.311 217 192.168.80"..., 134) = 134
> epoll_wait(6, {{EPOLLOUT, {u32=11, u64=26089688645238795}}}, 8192, 663) = 1
> epoll_ctl(6, EPOLL_CTL_MOD, 11, {EPOLLIN|EPOLLERR|EPOLLHUP, {u32=11,
> u64=1157442765139738635}}) = 0
> epoll_wait(6, {}, 8192, 662) = 0
> epoll_wait(6, {}, 8192, 25) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 980) = 0
> epoll_wait(6, {}, 8192, 18) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 0) = 0
> epoll_wait(6, {}, 8192, 1000) = 0
> epoll_wait(6, <unfinished ...>
> Process 3249 detached
>
Received on Wed Jan 12 2011 - 09:41:50 MST

This archive was generated by hypermail 2.2.0 : Wed Jan 12 2011 - 12:00:02 MST