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

From: Chris Palmer <chris.palmer_at_pobox.com>
Date: Tue, 11 Jan 2011 17:47:27 +0000

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 Tue Jan 11 2011 - 17:47:27 MST

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