* Re: [Qemu-devel] [Nbd] Hibernate and qemu-nbd
@ 2013-10-18 18:23 Mark Trumpold
0 siblings, 0 replies; 9+ messages in thread
From: Mark Trumpold @ 2013-10-18 18:23 UTC (permalink / raw)
To: Paul Clements, Mark Trumpold
Cc: nbd-general, Stefan Hajnoczi, Paolo Bonzini, qemu-devel,
Wouter Verhelst
>-----Original Message-----
>From: Paul Clements [mailto:paul.clements@us.sios.com]
>Sent: Thursday, October 17, 2013 12:53 PM
>To: 'Mark Trumpold'
>Cc: 'Stefan Hajnoczi', nbd-general@lists.sourceforge.net, 'Wouter Verhelst',
>qemu-devel@nongnu.org, 'Paolo Bonzini'
>Subject: Re: [Nbd] [Qemu-devel] Hibernate and qemu-nbd
>
>Hi Mark,
>
>On Thu, Oct 17, 2013 at 1:07 PM, Mark Trumpold <markt@netqa.com> wrote:
>
>
>> 165 strace -p 2488 -o /var/tmp/qemu.nbd.strace.v2 &
>> 166 strace -p 2492 -o /var/tmp/nbd-client.strace.v2 &
>>
>
>You'll need to include the -f option to strace to get the full child trace
>(at least for nbd-client). In the output you sent, we don't see the child
>nbd-client, which is the one calling the NBD_DO_IT ioctl, which is
>important to see.
>
>--
>Paul
>
Hi all,
I re-ran the test with Paul's suggestion. For brevity I just include this latest test run. This time with the actual terminal output where the test was executed, since it contains PIDs, etc -- though a bit harder to read:
1) Test scenario (actual terminal screen).
2) strace from nbd-client
3) strace from qemu-nbd (quite lengthy -- 1539 lines)
4) kernel oops output when I 'halt' after the test scenario.
Thank you,
Mark T.
1)----------
[root@base:~] FACTORY # qemu-nbd --persistent -p 2000 /root/qemu/q1.img &
[1] 1673
[root@base:~] FACTORY # nbd-client -persist localhost 2000 /dev/nbd0
Negotiation: ..size = 2097152KB
bs=1024, sz=2097152
[root@base:~] FACTORY # pn nbd
USER PID %CPU %MEM VSZ RSS TTY STAT START TIME COMMAND
root 1673 0.0 0.1 21296 6584 pts/0 Sl 17:39 0:00 qemu-nbd --persistent -p 2000 /root/qemu/q1.img
root 1677 0.0 0.0 1724 116 ? S 17:39 0:00 nbd-client -persist localhost 2000 /dev/nbd0
root 1678 0.0 0.0 0 0 ? S< 17:39 0:00 [nbd0]
[root@base:~] FACTORY # ls /var/tmp
cimserver_start.lock root-loop
[root@base:~] FACTORY # strace -f -p 1673 -o /var/tmp/qemu.nbd.strace.v3 &
[2] 1687
[root@base:~] FACTORY # Process 1673 attached - interrupt to quit
[root@base:~] FACTORY # strace -f -p 1677 -o /var/tmp/nbd-client.strace.v3 &
[3] 1696
[root@base:~] FACTORY # Process 1677 attached - interrupt to quit
[root@base:~] FACTORY # mount /dev/nbd0 /mnt
Process 1698 attached (waiting for parent)
Process 1698 resumed (parent 1673 ready)
[root@base:~] FACTORY # Process 1698 detached
[root@base:~] FACTORY # ls /mnt
Process 1703 attached (waiting for parent)
Process 1703 resumed (parent 1673 ready)
lost+found
[root@base:~] FACTORY # echo reboot >/sys/power/disk
[root@base:~] FACTORY # echo disk >/sys/power/state
nbd.c:nbd_receive_request():L517: read failed
Process 1703 detached
Process 1813 attached
[root@base:~] FACTORY # Process 1677 detached
[root@base:~] FACTORY # ls /mnt
2)----------
1677 write(2, "Kernel call returned: Interrupte"..., 45) = 45
1677 write(2, " Reconnecting\n", 14) = 14
1677 close(4) = 0
1677 close(3) = 0
1677 socket(PF_NETLINK, SOCK_RAW, 0) = 3
1677 bind(3, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
1677 getsockname(3, {sa_family=AF_NETLINK, pid=1677, groups=00000000}, [12]) = 0
1677 time(NULL) = 1382118599
1677 sendto(3, "\24\0\0\0\26\0\1\3\307taR\0\0\0\0\0\0\0\0", 20, 0, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
1677 recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"0\0\0\0\24\0\2\0\307taR\215\6\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) = 108
1677 recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"@\0\0\0\24\0\2\0\307taR\215\6\0\0\n\200\200\376\1\0\0\0\24\0\1\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 192
1677 recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"\24\0\0\0\3\0\2\0\307taR\215\6\0\0\0\0\0\0\1\0\0\0\24\0\1\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
1677 close(3) = 0
1677 stat64("/etc/resolv.conf", {st_mode=S_IFREG|0644, st_size=82, ...}) = 0
1677 open("/etc/resolv.conf", O_RDONLY) = 3
1677 fstat64(3, {st_mode=S_IFREG|0644, st_size=82, ...}) = 0
1677 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7736000
1677 read(3, "domain dvt.tachyon.net\nsearch dv"..., 4096) = 82
1677 read(3, "", 4096) = 0
1677 close(3) = 0
1677 munmap(0xb7736000, 4096) = 0
1677 open("/etc/hosts", O_RDONLY|O_CLOEXEC) = 3
1677 fstat64(3, {st_mode=S_IFREG|0644, st_size=220, ...}) = 0
1677 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7736000
1677 read(3, "127.0.0.1 localhost\n127.0.1.1 "..., 4096) = 220
1677 read(3, "", 4096) = 0
1677 close(3) = 0
1677 munmap(0xb7736000, 4096) = 0
1677 socket(PF_INET, SOCK_STREAM, IPPROTO_TCP) = 3
1677 connect(3, {sa_family=AF_INET, sin_port=htons(2000), sin_addr=inet_addr("127.0.0.1")}, 16) = 0
1677 setsockopt(3, SOL_TCP, TCP_NODELAY, [1], 4) = 0
1677 open("/dev/nbd0", O_RDWR|O_LARGEFILE) = 4
1677 write(1, "Negotiation: ", 13) = 13
1677 read(3, "NBDMAGIC", 8) = 8
1677 write(1, ".", 1) = 1
1677 read(3, "\0\0B\2\201\206\22S", 8) = 8
1677 write(1, ".", 1) = 1
1677 read(3, "\0\0\0\0\200\0\0\0", 8) = 8
1677 write(1, "size = 2097152KB", 16) = 16
1677 read(3, "\0\0\0-", 4) = 4
1677 read(3, "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 124) = 124
1677 write(1, "\n", 1) = 1
1677 ioctl(4, NBD_SET_BLKSIZE, 0x400) = 0
1677 ioctl(4, NBD_SET_SIZE_BLOCKS, 0x200000) = 0
1677 write(2, "bs=1024, sz=2097152\n", 20) = 20
1677 ioctl(4, NBD_CLEAR_SOCK, 0x400) = 0
1677 ioctl(4, BLKROSET, 0xbf975cec) = 0
1677 ioctl(4, NBD_SET_SOCK, 0x3) = 0
1677 clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0xb75eb728) = 1813
1677 open("/sys/block/nbd0/pid", O_RDONLY|O_LARGEFILE <unfinished ...>
1813 ioctl(4, NBD_DO_IT <unfinished ...>
1677 <... open resumed> ) = -1 ENOENT (No such file or directory)
1677 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0
1677 rt_sigaction(SIGCHLD, NULL, {SIG_DFL, [], 0}, 8) = 0
1677 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
1677 nanosleep({1, 0}, 0xbf975cf4) = 0
1677 open("/sys/block/nbd0/pid", O_RDONLY|O_LARGEFILE) = 5
1677 read(5, "1813\n", 256) = 5
1677 open("/dev/nbd0", O_RDONLY|O_LARGEFILE) = 6
1677 exit_group(0) = ?
3)----------
1673 select(11, [4 7 8 10], [], [], NULL) = 1 (in [10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\360z\321\366\370J\2\301\0\0\0\0\177\377\0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 609124753}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 609155482}) = 0
1673 gettimeofday({1382118538, 708643}, NULL) = 0
1673 rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [BUS USR1 ALRM IO], 8) = 0
1673 clone(child_stack=0xb6f47454, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0xb6f47bd8, {entry_number:6, base_addr:0xb6f47b70, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}, child_tidptr=0xb6f47bd8) = 1698
1698 set_robust_list(0xb6f47be0, 0xc <unfinished ...>
1673 rt_sigprocmask(SIG_SETMASK, [BUS USR1 ALRM IO], <unfinished ...>
1698 <... set_robust_list resumed> ) = 0
1673 <... rt_sigprocmask resumed> NULL, 8) = 0
1698 gettimeofday( <unfinished ...>
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 708840}, NULL) = 0
1673 <... select resumed> ) = 1 (in [8])
1698 pread64(3, <unfinished ...>
1673 read(8, "\1\0\0\0\0\0\0\0", 512) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 609478841}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 609507770}) = 0
1673 gettimeofday({1382118538, 708994}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... pread64 resumed> "\200\0\0\0\5\237\0\0\200\0\0\0\5\240\0\0\200\0\0\0\5\253\0\0\200\0\0\0\5\241\0\0"..., 65536, 94240768) = 65536
1698 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1698 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 711446}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 futex(0xb7751740, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 711503514}) = 0
1698 futex(0xb7751740, FUTEX_WAKE_PRIVATE, 1) = 1
1673 <... futex resumed> ) = 0
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 19, {9, 288496486} <unfinished ...>
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
1673 <... futex resumed> ) = 0
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... clock_gettime resumed> {1516, 612207516}) = 0
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 612262557}) = 0
1673 gettimeofday({1382118538, 711753}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... pread64 resumed> "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 102825984) = 4096
1698 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1698 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 712074}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 712125051}) = 0
1673 <... write resumed> ) = 8
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 21, {9, 287874949} <unfinished ...>
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\360z\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 612988043}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 613049497}) = 0
1673 gettimeofday({1382118538, 712539}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\200|\321\366\370J\2\301\0\0\0\0\177\377\340\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 613328197}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 613359300}) = 0
1673 gettimeofday({1382118538, 712845}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... pread64 resumed> "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 102883328) = 4096
1698 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1698 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 713026}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 713074996}) = 0
1673 <... write resumed> ) = 8
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 23, {9, 286925004} <unfinished ...>
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\200|\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 613902548}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 613930803}) = 0
1673 gettimeofday({1382118538, 713416}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0H}\321\366\370J\2\301\0\0\0\0\177\377\360\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 614211515}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 614242759}) = 0
1673 gettimeofday({1382118538, 713729}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... pread64 resumed> "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 102887424) = 4096
1698 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1698 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 713902}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 713950313}) = 0
1673 <... write resumed> ) = 8
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 25, {9, 286049687} <unfinished ...>
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0H}\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 614783154}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 614811114}) = 0
1673 gettimeofday({1382118538, 714296}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\330~\321\366\370J\2\301\0\0\0\0\177\375\360\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\330~\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 read(8, "\4\0\0\0\0\0\0\0", 512) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 615248351}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 615276457}) = 0
1673 gettimeofday({1382118538, 714762}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 1 (in [10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\330~\321\366\370J\2\301\0\0\0\0\177\377\200\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... clock_gettime resumed> {1516, 615484472}) = 0
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 615536955}) = 0
1673 gettimeofday({1382118538, 715025}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... pread64 resumed> "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 102858752) = 4096
1698 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1698 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 715233}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 715281979}) = 0
1673 <... write resumed> ) = 8
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 27, {9, 284718021} <unfinished ...>
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\330~\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 616111415}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 616139589}) = 0
1673 gettimeofday({1382118538, 715625}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0H}\321\366\370J\2\301\0\0\0\0\177\376\0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0H}\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 read(8, "\4\0\0\0\0\0\0\0", 512) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 616578964}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 616606960}) = 0
1673 gettimeofday({1382118538, 716092}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 1 (in [10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\200|\321\366\370J\2\301\0\0\0\0\177\374\340\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\200|\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 617010052}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 617038695}) = 0
1673 gettimeofday({1382118538, 716524}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\360z\321\366\370J\2\301\0\0\0\0\0\20\0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 617305327}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 617336444}) = 0
1673 gettimeofday({1382118538, 716823}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... pread64 resumed> "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 1310720) = 4096
1698 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1698 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 717178}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 717226474}) = 0
1673 <... write resumed> ) = 8
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 29, {9, 282773526} <unfinished ...>
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\360z\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 618050439}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 618078391}) = 0
1673 gettimeofday({1382118538, 717564}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\20~\321\366\370J\2\301\0\0\0\0\0\0p\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 <... pread64 resumed> "\7\200\0\0\7\200\1\0\7\200\2\0\7\200\3\0\7\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 356352) = 4096
1673 <... clock_gettime resumed> {1516, 618340900}) = 0
1698 write(5, "\0", 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 <... write resumed> ) = 1
1673 <... clock_gettime resumed> {1516, 618401331}) = 0
1698 gettimeofday( <unfinished ...>
1673 gettimeofday( <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 717892}, NULL) = 0
1673 <... gettimeofday resumed> {1382118538, 717903}, NULL) = 0
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 717938963}) = 0
1673 <... select resumed> ) = 1 (in [4])
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 31, {9, 282061037} <unfinished ...>
1673 read(4, "\0", 16) = 1
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\20~\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\7\200\0\0\7\200\1\0\7\200\2\0\7\200\3\0\7\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 618836823}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 618865007}) = 0
1673 gettimeofday({1382118538, 718351}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\270{\321\366\370J\2\301\0\0\0\0\0\0\360\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 619128823}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 619159842}) = 0
1673 gettimeofday({1382118538, 718645}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... pread64 resumed> "\17\200\0\0\17\200\1\0\17\200\2\0\17\200\3\0\17\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 389120) = 4096
1698 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1698 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 718836}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 718883806}) = 0
1673 <... write resumed> ) = 8
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 33, {9, 281116194} <unfinished ...>
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\270{\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\17\200\0\0\17\200\1\0\17\200\2\0\17\200\3\0\17\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 619707204}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 619739978}) = 0
1673 gettimeofday({1382118538, 719225}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\230x\321\366\370J\2\301\0\0\0\0\0\0 \0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 <... pread64 resumed> "\2\200\0\0\2\200\1\0\2\200\2\0\2\200\3\0\2\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 335872) = 4096
1673 <... clock_gettime resumed> {1516, 619997177}) = 0
1698 write(5, "\0", 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 <... write resumed> ) = 1
1673 <... clock_gettime resumed> {1516, 620061250}) = 0
1698 gettimeofday( <unfinished ...>
1673 gettimeofday( <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 719553}, NULL) = 0
1673 <... gettimeofday resumed> {1382118538, 719563}, NULL) = 0
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 719599100}) = 0
1673 <... select resumed> ) = 1 (in [4])
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 35, {9, 280400900} <unfinished ...>
1673 read(4, "\0", 16) = 1
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\230x\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\2\200\0\0\2\200\1\0\2\200\2\0\2\200\3\0\2\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 620500302}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 620528369}) = 0
1673 gettimeofday({1382118538, 720014}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0(z\321\366\370J\2\301\0\0\0\0\0\1\0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 620787785}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 620823835}) = 0
1673 gettimeofday({1382118538, 720310}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... pread64 resumed> "\20\200\0\0\20\200\1\0\20\200\2\0\20\200\3\0\20\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 393216) = 4096
1698 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1698 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 720618}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 720666745}) = 0
1673 <... write resumed> ) = 8
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 37, {9, 279333255} <unfinished ...>
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0(z\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\20\200\0\0\20\200\1\0\20\200\2\0\20\200\3\0\20\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 621523753}) = 0
1673 gettimeofday({1382118538, 721009}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\320w\321\366\370J\2\301\0\0\0\0\0\0\200\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 <... pread64 resumed> "\10\200\0\0\10\200\1\0\10\200\2\0\10\200\3\0\10\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 360448) = 4096
1673 <... clock_gettime resumed> {1516, 621781496}) = 0
1698 write(5, "\0", 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 <... write resumed> ) = 1
1673 <... clock_gettime resumed> {1516, 621842315}) = 0
1698 gettimeofday( <unfinished ...>
1673 gettimeofday( <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 721333}, NULL) = 0
1673 <... gettimeofday resumed> {1382118538, 721343}, NULL) = 0
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 721379299}) = 0
1673 <... select resumed> ) = 1 (in [4])
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 39, {9, 278620701} <unfinished ...>
1673 read(4, "\0", 16) = 1
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\320w\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\10\200\0\0\10\200\1\0\10\200\2\0\10\200\3\0\10\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 622277834}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 622306397}) = 0
1673 gettimeofday({1382118538, 721792}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\260t\321\366\370J\2\301\0\0\0\0\0\2\0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 622569348}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 622600236}) = 0
1673 gettimeofday({1382118538, 722086}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... pread64 resumed> " \200\0\0 \200\1\0 \200\2\0 \200\3\0 \200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 1114112) = 4096
1698 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1698 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 722290}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 722343718}) = 0
1673 <... write resumed> ) = 8
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 41, {9, 277656282} <unfinished ...>
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\260t\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{" \200\0\0 \200\1\0 \200\2\0 \200\3\0 \200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 623167557}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 623195907}) = 0
1673 gettimeofday({1382118538, 722682}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\350s\321\366\370J\2\301\0\0\0\0\0\2\20\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 623461882}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 623492724}) = 0
1673 gettimeofday({1382118538, 722979}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... pread64 resumed> "!\200\0\0!\200\1\0!\200\2\0!\200\3\0!\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 1118208) = 4096
1698 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1698 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 723113}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 723161197}) = 0
1673 <... write resumed> ) = 8
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 43, {9, 276838803} <unfinished ...>
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\350s\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"!\200\0\0!\200\1\0!\200\2\0!\200\3\0!\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 623982905}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 624016075}) = 0
1673 gettimeofday({1382118538, 723502}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0@v\321\366\370J\2\301\0\0\0\0\0\2 \0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 <... pread64 resumed> "\"\200\0\0\"\200\1\0\"\200\2\0\"\200\3\0\"\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 1122304) = 4096
1673 <... clock_gettime resumed> {1516, 624286340}) = 0
1698 write(5, "\0", 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 <... write resumed> ) = 1
1673 <... clock_gettime resumed> {1516, 624355581}) = 0
1698 gettimeofday( <unfinished ...>
1673 gettimeofday( <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 723846}, NULL) = 0
1673 <... gettimeofday resumed> {1382118538, 723856}, NULL) = 0
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 723893169}) = 0
1673 <... select resumed> ) = 1 (in [4])
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 45, {9, 276106831} <unfinished ...>
1673 read(4, "\0", 16) = 1
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0@v\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\"\200\0\0\"\200\1\0\"\200\2\0\"\200\3\0\"\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 624798169}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 624826115}) = 0
1673 gettimeofday({1382118538, 724312}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\10w\321\366\370J\2\301\0\0\0\0\0\6\0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 625092126}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 625122650}) = 0
1673 gettimeofday({1382118538, 724608}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... pread64 resumed> "`\200\0\0`\200\1\0`\200\2\0`\200\3\0`\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 1245184) = 4096
1698 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1698 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 724786}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 724835065}) = 0
1673 <... write resumed> ) = 8
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 47, {9, 275164935} <unfinished ...>
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\10w\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"`\200\0\0`\200\1\0`\200\2\0`\200\3\0`\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 625662770}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 625690665}) = 0
1673 gettimeofday({1382118538, 725176}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0`y\321\366\370J\2\301\0\0\0\0\0\6\20\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 625960522}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 625991446}) = 0
1673 gettimeofday({1382118538, 725478}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... pread64 resumed> "a\200\0\0a\200\1\0a\200\2\0a\200\3\0a\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 1249280) = 4096
1698 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1698 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 725657}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 725705005}) = 0
1673 <... write resumed> ) = 8
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 49, {9, 274294995} <unfinished ...>
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0`y\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"a\200\0\0a\200\1\0a\200\2\0a\200\3\0a\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 626530977}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 626559013}) = 0
1673 gettimeofday({1382118538, 726045}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0 s\321\366\370J\2\301\0\0\0\0\0\6 \0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 <... pread64 resumed> "b\200\0\0b\200\1\0b\200\2\0b\200\3\0b\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 1253376) = 4096
1673 <... clock_gettime resumed> {1516, 626828419}) = 0
1698 write(5, "\0", 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 <... write resumed> ) = 1
1673 <... clock_gettime resumed> {1516, 626892192}) = 0
1698 gettimeofday( <unfinished ...>
1673 gettimeofday( <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 726384}, NULL) = 0
1673 <... gettimeofday resumed> {1382118538, 726394}, NULL) = 0
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 726430095}) = 0
1673 <... select resumed> ) = 1 (in [4])
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 51, {9, 273569905} <unfinished ...>
1673 read(4, "\0", 16) = 1
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0 s\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"b\200\0\0b\200\1\0b\200\2\0b\200\3\0b\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 627334733}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 627367654}) = 0
1673 gettimeofday({1382118538, 726854}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0xu\321\366\370J\2\301\0\0\0\0\0\4\0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 627634315}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 627665227}) = 0
1673 gettimeofday({1382118538, 727151}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... pread64 resumed> "@\200\0\0@\200\1\0@\200\2\0@\200\3\0@\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 524288) = 4096
1698 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1698 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 727373}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 727421056}) = 0
1673 <... write resumed> ) = 8
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 53, {9, 272578944} <unfinished ...>
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0xu\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"@\200\0\0@\200\1\0@\200\2\0@\200\3\0@\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 628250769}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 628278814}) = 0
1673 gettimeofday({1382118538, 727764}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0xu\321\366\370J\2\301\0\0\0\0\0\0@\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 628544259}) = 0
1698 <... pread64 resumed> "\4\200\0\0\4\200\1\0\4\200\2\0\4\200\3\0\4\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 344064) = 4096
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 write(5, "\0", 1 <unfinished ...>
1673 <... clock_gettime resumed> {1516, 628599889}) = 0
1698 <... write resumed> ) = 1
1673 gettimeofday( <unfinished ...>
1698 gettimeofday( <unfinished ...>
1673 <... gettimeofday resumed> {1382118538, 728108}, NULL) = 0
1698 <... gettimeofday resumed> {1382118538, 728118}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 <... select resumed> ) = 1 (in [4])
1698 <... clock_gettime resumed> {1382118538, 728171757}) = 0
1673 read(4, <unfinished ...>
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 55, {9, 271828243} <unfinished ...>
1673 <... read resumed> "\0", 16) = 1
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0xu\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\4\200\0\0\4\200\1\0\4\200\2\0\4\200\3\0\4\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 629061326}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 629089746}) = 0
1673 gettimeofday({1382118538, 728576}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0 s\321\366\370J\2\301\0\0\0\0\0 \0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 629353298}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 629383854}) = 0
1673 gettimeofday({1382118538, 728870}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... pread64 resumed> "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 2752512) = 4096
1698 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1698 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 729044}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 729091385}) = 0
1673 <... write resumed> ) = 8
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 57, {9, 270908615} <unfinished ...>
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0 s\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 629923669}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 629951322}) = 0
1673 gettimeofday({1382118538, 729436}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0`y\321\366\370J\2\301\0\0\0\0\0\0\0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 <... pread64 resumed> "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 327680) = 4096
1673 <... clock_gettime resumed> {1516, 630205847}) = 0
1698 write(5, "\0", 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 <... write resumed> ) = 1
1673 <... clock_gettime resumed> {1516, 630266159}) = 0
1698 gettimeofday( <unfinished ...>
1673 gettimeofday( <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 729757}, NULL) = 0
1673 <... gettimeofday resumed> {1382118538, 729767}, NULL) = 0
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 729803412}) = 0
1673 <... select resumed> ) = 1 (in [4])
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 59, {9, 270196588} <unfinished ...>
1673 read(4, "\0", 16) = 1
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0`y\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 630703354}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 630730984}) = 0
1673 gettimeofday({1382118538, 730216}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\10w\321\366\370J\2\301\0\0\0\0\0\0\20\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 <... pread64 resumed> "\201\0\0\0\202\0\0\0\203\0\0\0w}\365\37\2\0\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 331776) = 4096
1673 <... clock_gettime resumed> {1516, 630984257}) = 0
1698 write(5, "\0", 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 <... write resumed> ) = 1
1673 <... clock_gettime resumed> {1516, 631042450}) = 0
1698 gettimeofday( <unfinished ...>
1673 gettimeofday( <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 730531}, NULL) = 0
1673 <... gettimeofday resumed> {1382118538, 730540}, NULL) = 0
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 730574637}) = 0
1673 <... select resumed> ) = 1 (in [4])
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 61, {9, 269425363} <unfinished ...>
1673 read(4, "\0", 16) = 1
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\10w\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\201\0\0\0\202\0\0\0\203\0\0\0w}\365\37\2\0\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 631462492}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 631490022}) = 0
1673 gettimeofday({1382118538, 730975}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0@v\321\366\370J\2\301\0\0\0\0\0\0100\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 631750171}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 631780718}) = 0
1673 gettimeofday({1382118538, 731266}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... pread64 resumed> "\0\0\0\0\0\0\0\0b\302^Rb\302^Rb\302^R\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 733184) = 4096
1698 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1698 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 731500}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 731546829}) = 0
1673 <... write resumed> ) = 8
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 63, {9, 268453171} <unfinished ...>
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0@v\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0b\302^Rb\302^Rb\302^R\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 632361112}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 632388374}) = 0
1673 gettimeofday({1382118538, 731873}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\350s\321\366\370J\2\301\0\0\0\0@ \0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 632642793}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 632675696}) = 0
1673 gettimeofday({1382118538, 732161}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... pread64 resumed> "\200\0\0\0\1\25\0\0\200\0\0\0\1\26\0\0\200\0\0\0\1\31\0\0\200\0\0\0\1\32\0\0"..., 65536, 18087936) = 65536
1698 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1698 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 732555}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 futex(0xb7751740, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 732603005}) = 0
1698 futex(0xb7751740, FUTEX_WAKE_PRIVATE, 1) = 1
1673 <... futex resumed> ) = 0
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 65, {9, 267396995} <unfinished ...>
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
1673 <... futex resumed> ) = 0
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... clock_gettime resumed> {1516, 633278678}) = 0
1698 pread64(3, <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 633326588}) = 0
1673 gettimeofday({1382118538, 732814}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... pread64 resumed> "\300;9\230\0\0\0\4\0\0\0\0\0\0\20\0\0\0@\0\0\0\0\1\0\0\0\5\0\0\0\0"..., 4096, 20586496) = 4096
1698 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1698 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 733066}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 733117418}) = 0
1673 <... write resumed> ) = 8
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 67, {9, 266882582} <unfinished ...>
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\350s\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\300;9\230\0\0\0\4\0\0\0\0\0\0\20\0\0\0@\0\0\0\0\1\0\0\0\5\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 633932259}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 633959530}) = 0
1673 gettimeofday({1382118538, 733444}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\1\260t\321\366\370J\2\301\0\0\0\0\0\0\0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1698 pwrite64(3, "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 327680 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 634282454}) = 0
1698 <... pwrite64 resumed> ) = 4096
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 write(5, "\0", 1 <unfinished ...>
1673 <... clock_gettime resumed> {1516, 634318827}) = 0
1698 <... write resumed> ) = 1
1673 gettimeofday( <unfinished ...>
1698 gettimeofday( <unfinished ...>
1673 <... gettimeofday resumed> {1382118538, 733823}, NULL) = 0
1698 <... gettimeofday resumed> {1382118538, 733832}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 <... select resumed> ) = 1 (in [4])
1698 <... clock_gettime resumed> {1382118538, 733884274}) = 0
1673 read(4, <unfinished ...>
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 69, {9, 266115726} <unfinished ...>
1673 <... read resumed> "\0", 16) = 1
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... clock_gettime resumed> {1516, 634558694}) = 0
1698 fdatasync(3 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 634602018}) = 0
1673 gettimeofday({1382118538, 734095}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... fdatasync resumed> ) = 0
1698 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1698 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 734284}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 futex(0xb7751740, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 734330383}) = 0
1698 futex(0xb7751740, FUTEX_WAKE_PRIVATE, 1) = 1
1673 <... futex resumed> ) = 0
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 71, {9, 265669617} <unfinished ...>
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
1673 <... futex resumed> ) = 0
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... clock_gettime resumed> {1516, 635010544}) = 0
1698 fdatasync(3 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 635053317}) = 0
1673 gettimeofday({1382118538, 734539}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... fdatasync resumed> ) = 0
1698 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1698 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 734654}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 futex(0xb7751740, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 734700776}) = 0
1698 futex(0xb7751740, FUTEX_WAKE_PRIVATE, 1) = 1
1673 <... futex resumed> ) = 0
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 73, {9, 265299224} <unfinished ...>
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1698 <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
1673 <... futex resumed> ) = 0
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1698 <... futex resumed> ) = 0
1673 <... clock_gettime resumed> {1516, 635374794}) = 0
1698 fdatasync(3 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 635417088}) = 0
1673 gettimeofday({1382118538, 734903}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... fdatasync resumed> ) = 0
1698 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1698 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1698 <... gettimeofday resumed> {1382118538, 735068}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1698 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1698 <... clock_gettime resumed> {1382118538, 735114919}) = 0
1673 <... write resumed> ) = 8
1698 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 75, {9, 264885081} <unfinished ...>
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\260t\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 635809776}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 635837370}) = 0
1673 gettimeofday({1382118538, 735322}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 1 (in [8])
1673 read(8, "\2\0\0\0\0\0\0\0", 512) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 635962760}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1516, 635989820}) = 0
1673 gettimeofday({1382118538, 735479}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1698 <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
1698 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1) = 0
1698 madvise(0xb6748000, 8368128, MADV_DONTNEED) = 0
1698 _exit(0) = ?
1673 <... select resumed> ) = 1 (in [10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\320w\321\366\370J\2\301\0\0\0\0\0(0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1532, 353078732}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1532, 353108088}) = 0
1673 gettimeofday({1382118554, 452594}, NULL) = 0
1673 rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [BUS USR1 ALRM IO], 8) = 0
1673 clone(child_stack=0xb6f47454, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0xb6f47bd8, {entry_number:6, base_addr:0xb6f47b70, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}, child_tidptr=0xb6f47bd8) = 1703
1703 set_robust_list(0xb6f47be0, 0xc <unfinished ...>
1673 rt_sigprocmask(SIG_SETMASK, [BUS USR1 ALRM IO], <unfinished ...>
1703 <... set_robust_list resumed> ) = 0
1673 <... rt_sigprocmask resumed> NULL, 8) = 0
1703 gettimeofday( <unfinished ...>
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1703 <... gettimeofday resumed> {1382118554, 452774}, NULL) = 0
1673 <... select resumed> ) = 1 (in [8])
1703 pread64(3, <unfinished ...>
1673 read(8, "\1\0\0\0\0\0\0\0", 512) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1532, 353408787}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1532, 353437306}) = 0
1673 gettimeofday({1382118554, 452923}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1703 <... pread64 resumed> "\2\0\0\0\f\0\1\2.\0\0\0\2\0\0\0\f\0\2\2..\0\0\v\0\0\0\350\17\n\2"..., 4096, 2895872) = 4096
1703 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1703 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1703 <... gettimeofday resumed> {1382118554, 455314}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1703 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1703 <... clock_gettime resumed> {1382118554, 455365018}) = 0
1673 <... write resumed> ) = 8
1703 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 77, {9, 544634982} <unfinished ...>
1673 setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\320w\321\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\2\0\0\0\f\0\1\2.\0\0\0\2\0\0\0\f\0\2\2..\0\0\v\0\0\0\350\17\n\2"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1532, 356226846}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1532, 356258046}) = 0
1673 gettimeofday({1382118554, 455750}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 1 (in [8])
1673 read(8, "\2\0\0\0\0\0\0\0", 512) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1532, 356401656}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1532, 356432297}) = 0
1673 gettimeofday({1382118554, 455920}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 1 (in [10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\1 c\215\337\370J\2\301\0\0\0\0@ \0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\300;9\230\0\0\0\4\0\0\0\0\0\0\20\0\0\0@\0\0\0\0\1\0\0\0\6\0\0\0\1"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1703 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1703 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 <... futex resumed> ) = 0
1673 <... clock_gettime resumed> {1537, 942256838}) = 0
1703 pwrite64(3, "\300;9\230\0\0\0\4\0\0\0\0\0\0\20\0\0\0@\0\0\0\0\1\0\0\0\6\0\0\0\1"..., 4096, 20586496 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 <... pwrite64 resumed> ) = 4096
1673 <... clock_gettime resumed> {1537, 942322864}) = 0
1703 write(5, "\0", 1 <unfinished ...>
1673 gettimeofday( <unfinished ...>
1703 <... write resumed> ) = 1
1673 <... gettimeofday resumed> {1382118560, 41847}, NULL) = 0
1703 gettimeofday( <unfinished ...>
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1703 <... gettimeofday resumed> {1382118560, 41880}, NULL) = 0
1673 <... select resumed> ) = 1 (in [4])
1703 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 read(4, <unfinished ...>
1703 <... clock_gettime resumed> {1382118560, 41940277}) = 0
1673 <... read resumed> "\0", 16) = 1
1703 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 79, {9, 958059723} <unfinished ...>
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1703 <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
1673 <... futex resumed> ) = 0
1703 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 <... futex resumed> ) = 0
1673 <... clock_gettime resumed> {1537, 942605949}) = 0
1703 fdatasync(3 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1537, 942664639}) = 0
1673 gettimeofday({1382118560, 42158}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1703 <... fdatasync resumed> ) = 0
1703 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1703 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1703 <... gettimeofday resumed> {1382118560, 43841}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1703 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 futex(0xb7751740, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1703 <... clock_gettime resumed> {1382118560, 43892504}) = 0
1703 futex(0xb7751740, FUTEX_WAKE_PRIVATE, 1) = 1
1673 <... futex resumed> ) = 0
1703 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 81, {9, 956107496} <unfinished ...>
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1703 <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
1673 <... futex resumed> ) = 0
1703 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 <... futex resumed> ) = 0
1673 <... clock_gettime resumed> {1537, 944579954}) = 0
1703 fdatasync(3 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1537, 944624598}) = 0
1673 gettimeofday({1382118560, 44112}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1703 <... fdatasync resumed> ) = 0
1703 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1703 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1703 <... gettimeofday resumed> {1382118560, 44268}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1703 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 futex(0xb7751740, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1703 <... clock_gettime resumed> {1382118560, 44319981}) = 0
1703 futex(0xb7751740, FUTEX_WAKE_PRIVATE, 1) = 1
1673 <... futex resumed> ) = 0
1703 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 83, {9, 955680019} <unfinished ...>
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1703 <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
1673 <... futex resumed> ) = 0
1703 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 <... futex resumed> ) = 0
1673 <... clock_gettime resumed> {1537, 945019331}) = 0
1703 fdatasync(3 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1537, 945065022}) = 0
1673 gettimeofday({1382118560, 44552}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1703 <... fdatasync resumed> ) = 0
1703 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1703 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1703 <... gettimeofday resumed> {1382118560, 44704}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1703 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1703 <... clock_gettime resumed> {1382118560, 44755763}) = 0
1673 <... write resumed> ) = 8
1703 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 85, {9, 955244237} <unfinished ...>
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0 c\215\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1537, 945477402}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1537, 945508623}) = 0
1673 gettimeofday({1382118560, 44997}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\1Xb\215\337\370J\2\301\0\0\0\0@ 0\0\0\0 \0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\300;9\230\0\0\0\1\0\0\0\6\0\0\0\203\0\0\0\10\0\0\0\0\0\0\0\0\0\0\0\0"..., 8192}], msg_controllen=0, msg_flags=0}, 0) = 8192
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1703 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1703 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1703 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1703 pwrite64(3, "\300;9\230\0\0\0\1\0\0\0\6\0\0\0\203\0\0\0\10\0\0\0\0\0\0\0\0\0\0\0\0"..., 8192, 20590592 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 <... pwrite64 resumed> ) = 8192
1673 <... clock_gettime resumed> {1537, 945862755}) = 0
1703 write(5, "\0", 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 <... write resumed> ) = 1
1673 <... clock_gettime resumed> {1537, 945916350}) = 0
1703 gettimeofday( <unfinished ...>
1673 gettimeofday( <unfinished ...>
1703 <... gettimeofday resumed> {1382118560, 45408}, NULL) = 0
1673 <... gettimeofday resumed> {1382118560, 45418}, NULL) = 0
1703 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1703 <... clock_gettime resumed> {1382118560, 45454066}) = 0
1673 <... select resumed> ) = 1 (in [4])
1703 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 87, {9, 954545934} <unfinished ...>
1673 read(4, "\0", 16) = 1
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
1703 <... futex resumed> ) = 0
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 <... clock_gettime resumed> {1537, 946143769}) = 0
1703 <... futex resumed> ) = 0
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 fdatasync(3 <unfinished ...>
1673 <... clock_gettime resumed> {1537, 946189300}) = 0
1673 gettimeofday({1382118560, 45699}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1703 <... fdatasync resumed> ) = 0
1703 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1703 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1703 <... gettimeofday resumed> {1382118560, 46068}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1703 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 futex(0xb7751740, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1703 <... clock_gettime resumed> {1382118560, 46121106}) = 0
1703 futex(0xb7751740, FUTEX_WAKE_PRIVATE, 1) = 1
1673 <... futex resumed> ) = 0
1703 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 89, {9, 953878894} <unfinished ...>
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1703 <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
1673 <... futex resumed> ) = 0
1703 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 <... futex resumed> ) = 0
1673 <... clock_gettime resumed> {1537, 946803542}) = 0
1703 fdatasync(3 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1537, 946852298}) = 0
1673 gettimeofday({1382118560, 46343}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1703 <... fdatasync resumed> ) = 0
1703 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1703 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1703 <... gettimeofday resumed> {1382118560, 46538}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1703 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 futex(0xb7751740, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1703 <... clock_gettime resumed> {1382118560, 46586507}) = 0
1703 futex(0xb7751740, FUTEX_WAKE_PRIVATE, 1) = 1
1673 <... futex resumed> ) = 0
1703 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 91, {9, 953413493} <unfinished ...>
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1703 <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
1673 <... futex resumed> ) = 0
1703 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 <... futex resumed> ) = 0
1673 <... clock_gettime resumed> {1537, 947270203}) = 0
1703 fdatasync(3 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1537, 947314703}) = 0
1673 gettimeofday({1382118560, 46804}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1703 <... fdatasync resumed> ) = 0
1703 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1703 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1703 <... gettimeofday resumed> {1382118560, 47009}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1703 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1703 <... clock_gettime resumed> {1382118560, 47057311}) = 0
1673 <... write resumed> ) = 8
1703 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 93, {9, 952942689} <unfinished ...>
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0Xb\215\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1537, 947762233}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1537, 947792155}) = 0
1673 gettimeofday({1382118560, 47279}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\1\220a\215\337\370J\2\301\0\0\0\0@ P\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\300;9\230\0\0\0\2\0\0\0\6\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1703 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1703 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 read(8, <unfinished ...>
1703 <... futex resumed> ) = 0
1673 <... read resumed> "\2\0\0\0\0\0\0\0", 512) = 8
1703 pwrite64(3, "\300;9\230\0\0\0\2\0\0\0\6\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 20598784 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 <... pwrite64 resumed> ) = 4096
1673 <... clock_gettime resumed> {1537, 948137321}) = 0
1703 write(5, "\0", 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 <... write resumed> ) = 1
1673 <... clock_gettime resumed> {1537, 948186367}) = 0
1703 gettimeofday( <unfinished ...>
1673 gettimeofday( <unfinished ...>
1703 <... gettimeofday resumed> {1382118560, 47678}, NULL) = 0
1673 <... gettimeofday resumed> {1382118560, 47688}, NULL) = 0
1703 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1703 <... clock_gettime resumed> {1382118560, 47730109}) = 0
1673 <... select resumed> ) = 1 (in [4])
1703 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 95, {9, 952269891} <unfinished ...>
1673 read(4, "\0", 16) = 1
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1703 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1703 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 <... futex resumed> ) = 0
1673 <... clock_gettime resumed> {1537, 948437580}) = 0
1703 fdatasync(3 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1537, 948481125}) = 0
1673 gettimeofday({1382118560, 47974}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1703 <... fdatasync resumed> ) = 0
1703 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1703 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1703 <... gettimeofday resumed> {1382118560, 48172}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1703 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 futex(0xb7751740, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1703 <... clock_gettime resumed> {1382118560, 48219375}) = 0
1703 futex(0xb7751740, FUTEX_WAKE_PRIVATE, 1) = 1
1673 <... futex resumed> ) = 0
1703 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 97, {9, 951780625} <unfinished ...>
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1703 <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
1673 <... futex resumed> ) = 0
1703 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 <... futex resumed> ) = 0
1673 <... clock_gettime resumed> {1537, 948894930}) = 0
1703 fdatasync(3 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1537, 948937894}) = 0
1673 gettimeofday({1382118560, 48428}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1703 <... fdatasync resumed> ) = 0
1703 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1703 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1703 <... gettimeofday resumed> {1382118560, 48582}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1703 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 futex(0xb7751740, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1703 <... clock_gettime resumed> {1382118560, 48634891}) = 0
1703 futex(0xb7751740, FUTEX_WAKE_PRIVATE, 1) = 1
1673 <... futex resumed> ) = 0
1703 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 99, {9, 951365109} <unfinished ...>
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1703 <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
1673 <... futex resumed> ) = 0
1703 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 <... futex resumed> ) = 0
1673 <... clock_gettime resumed> {1537, 949316242}) = 0
1703 fdatasync(3 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1537, 949359673}) = 0
1673 gettimeofday({1382118560, 48851}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1703 <... fdatasync resumed> ) = 0
1703 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1703 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1703 <... gettimeofday resumed> {1382118560, 49014}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1703 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1703 <... clock_gettime resumed> {1382118560, 49062747}) = 0
1673 <... write resumed> ) = 8
1703 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 101, {9, 950937253} <unfinished ...>
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\220a\215\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1537, 949771378}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1537, 949801869}) = 0
1673 gettimeofday({1382118560, 49290}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 1 (in [8])
1673 read(8, "\2\0\0\0\0\0\0\0", 512) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1537, 949938023}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1537, 949968127}) = 0
1673 gettimeofday({1382118560, 49456}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 1 (in [10])
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\1\310\260t\366\370J\2\301\0\0\0\0\0\0100\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
1673 recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0b\302^Rb\302^Rb\302^R\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1703 <... futex resumed> ) = 0
1673 <... futex resumed> ) = 1
1703 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 <... futex resumed> ) = 0
1673 <... clock_gettime resumed> {1547, 284290284}) = 0
1703 pwrite64(3, "\0\0\0\0\0\0\0\0b\302^Rb\302^Rb\302^R\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 733184 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 <... pwrite64 resumed> ) = 4096
1673 <... clock_gettime resumed> {1547, 284358591}) = 0
1703 write(5, "\0", 1 <unfinished ...>
1673 gettimeofday( <unfinished ...>
1703 <... write resumed> ) = 1
1673 <... gettimeofday resumed> {1382118569, 383878}, NULL) = 0
1703 gettimeofday( <unfinished ...>
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1703 <... gettimeofday resumed> {1382118569, 383915}, NULL) = 0
1673 <... select resumed> ) = 1 (in [4])
1703 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 read(4, <unfinished ...>
1703 <... clock_gettime resumed> {1382118569, 383976946}) = 0
1673 <... read resumed> "\0", 16) = 1
1703 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 103, {9, 616023054} <unfinished ...>
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1703 <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
1673 <... futex resumed> ) = 0
1703 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 <... futex resumed> ) = 0
1673 <... clock_gettime resumed> {1547, 284645398}) = 0
1703 fdatasync(3 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1547, 284693401}) = 0
1673 gettimeofday({1382118569, 384193}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1703 <... fdatasync resumed> ) = 0
1703 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1703 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1703 <... gettimeofday resumed> {1382118569, 384387}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1703 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 futex(0xb7751740, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1703 <... clock_gettime resumed> {1382118569, 384441254}) = 0
1703 futex(0xb7751740, FUTEX_WAKE_PRIVATE, 1) = 1
1673 <... futex resumed> ) = 0
1703 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 105, {9, 615558746} <unfinished ...>
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1703 <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
1673 <... futex resumed> ) = 0
1703 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 <... futex resumed> ) = 0
1673 <... clock_gettime resumed> {1547, 285151564}) = 0
1703 fdatasync(3 <unfinished ...>
1673 clock_gettime(CLOCK_MONOTONIC, {1547, 285198125}) = 0
1703 <... fdatasync resumed> ) = 0
1673 gettimeofday( <unfinished ...>
1703 write(5, "\0", 1 <unfinished ...>
1673 <... gettimeofday resumed> {1382118569, 384699}, NULL) = 0
1703 <... write resumed> ) = 1
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1703 gettimeofday( <unfinished ...>
1673 <... select resumed> ) = 1 (in [4])
1703 <... gettimeofday resumed> {1382118569, 384768}, NULL) = 0
1673 read(4, <unfinished ...>
1703 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 <... read resumed> "\0", 16) = 1
1703 <... clock_gettime resumed> {1382118569, 384820131}) = 0
1673 futex(0xb7751740, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1703 futex(0xb7751740, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
1703 <... futex resumed> ) = 0
1673 futex(0xb7751744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb7751740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
1703 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 107, {9, 615179869} <unfinished ...>
1673 <... futex resumed> ) = 0
1703 <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 <... clock_gettime resumed> {1547, 285500143}) = 0
1703 <... futex resumed> ) = 0
1673 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
1703 fdatasync(3 <unfinished ...>
1673 <... clock_gettime resumed> {1547, 285546583}) = 0
1673 gettimeofday({1382118569, 385046}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1703 <... fdatasync resumed> ) = 0
1703 write(5, "\0", 1) = 1
1673 <... select resumed> ) = 1 (in [4])
1703 gettimeofday( <unfinished ...>
1673 read(4, <unfinished ...>
1703 <... gettimeofday resumed> {1382118569, 385173}, NULL) = 0
1673 <... read resumed> "\0", 16) = 1
1703 clock_gettime(CLOCK_REALTIME, <unfinished ...>
1673 write(9, "\1\0\0\0\0\0\0\0", 8 <unfinished ...>
1703 <... clock_gettime resumed> {1382118569, 385224827}) = 0
1673 <... write resumed> ) = 8
1703 futex(0xb7751744, FUTEX_WAIT_PRIVATE, 109, {9, 614775173} <unfinished ...>
1673 sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\310\260t\366\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1547, 285949140}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1547, 285981055}) = 0
1673 gettimeofday({1382118569, 385470}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 1 (in [8])
1673 read(8, "\2\0\0\0\0\0\0\0", 512) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1547, 286123551}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1547, 286154627}) = 0
1673 gettimeofday({1382118569, 385644}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1703 <... futex resumed> ) = ? ERESTART_RESTARTBLOCK (To be restarted)
1673 <... select resumed> ) = ? ERESTARTNOHAND (To be restarted)
1703 restart_syscall(<... resuming interrupted call ...> <unfinished ...>
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
1703 <... restart_syscall resumed> ) = -1 ETIMEDOUT (Connection timed out)
1703 futex(0xb7751700, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1673 <... select resumed> ) = 1 (in [10])
1703 <... futex resumed> ) = 0
1673 recvmsg(10, <unfinished ...>
1703 madvise(0xb6748000, 8368128, MADV_DONTNEED <unfinished ...>
1673 <... recvmsg resumed> {msg_name(0)=NULL, msg_iov(1)=[{"\260\225x\21\2\0\0\0\310\260t\366\370J\2\301\0\0\0\0\0\0100\0\2\0\0\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 0
1703 <... madvise resumed> ) = 0
1673 write(2, "nbd.c:nbd_receive_request():L517"..., 46 <unfinished ...>
1703 _exit(0) = ?
1673 <... write resumed> ) = 46
1673 close(10) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1557, 74014817}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1557, 74061538}) = 0
1673 gettimeofday({1382118599, 786373}, NULL) = 0
1673 select(9, [4 6 7 8], [], [], NULL) = 1 (in [8])
1673 read(8, "\1\0\0\0\0\0\0\0", 512) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1557, 74331119}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1557, 74384672}) = 0
1673 gettimeofday({1382118599, 786705}, NULL) = 0
1673 select(9, [4 6 7 8], [], [], NULL) = 1 (in [6])
1673 accept(6, {sa_family=AF_INET, sin_port=htons(38937), sin_addr=inet_addr("127.0.0.1")}, [16]) = 10
1673 fcntl64(10, F_GETFL) = 0x2 (flags O_RDWR)
1673 fcntl64(10, F_SETFL, O_RDWR) = 0
1673 send(10, "NBDMAGIC\0\0B\2\201\206\22S\0\0\0\0\200\0\0\0\0\0\0-\0\0\0\0"..., 152, 0) = 152
1673 fcntl64(10, F_GETFL) = 0x2 (flags O_RDWR)
1673 fcntl64(10, F_SETFL, O_RDWR|O_NONBLOCK) = 0
1673 write(9, "\1\0\0\0\0\0\0\0", 8) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1557, 75703933}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1557, 75736422}) = 0
1673 gettimeofday({1382118599, 788039}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL) = 1 (in [8])
1673 read(8, "\1\0\0\0\0\0\0\0", 512) = 8
1673 clock_gettime(CLOCK_MONOTONIC, {1557, 75904339}) = 0
1673 clock_gettime(CLOCK_MONOTONIC, {1557, 75939190}) = 0
1673 gettimeofday({1382118599, 788253}, NULL) = 0
1673 select(11, [4 7 8 10], [], [], NULL <unfinished ...>
4)----------
Unmounting temporary filesystems...done.a command-line.
Deactivating swap...done.
Unmounting local filesystems...umount2: Device or resource busy
[ 1665.357851] ------------[ cut here ]------------lly in 1 seconds.
[ 1665.362454] kernel BUG at /build/buildd-linux-2.6_2.6.32-45-i386-yQfQSv/linux-2.6-2.6.32/debian/build/source!
[ 1665.374668] invalid opcode: 0000 [#1] SMP
[ 1665.378781] last sysfs file: /sys/devices/virtual/block/nbd0/pid
[ 1665.384765] Modules linked in: ext3 jbd loop snd_hda_codec_realtek snd_hda_intel snd_hda_codec snd_hwdep snd]
[ 1665.419821]
[ 1665.421310] Pid: 1956, comm: umount Not tainted (2.6.32-5-686 #1) CBxx55/56
[ 1665.428246] EIP: 0060:[<c10cdac4>] EFLAGS: 00010246 CPU: 4
[ 1665.433714] EIP is at submit_bh+0x17/0x106
[ 1665.437795] EAX: 000004c1 EBX: f6bd80c0 ECX: 00000004 EDX: 00000005
[ 1665.444041] ESI: f6597c00 EDI: 000004c1 EBP: 00000001 ESP: f6cdde90
[ 1665.450284] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068
[ 1665.455663] Process umount (pid: 1956, ti=f6cdc000 task=f6cff700 task.ti=f6cdc000)
[ 1665.463203] Stack:
[ 1665.465207] f6bd80c0 f6597c00 c31fa000 c10cefe6 f6597c14 f6597c00 f8ae039c f6bd80c0
[ 1665.472850] <0> f6597c00 00000007 f6597cc0 f6597c84 f8adf173 00000000 f6597c00 f8ae1161
[ 1665.480960] <0> f6597c14 f6596200 f6597c00 c31f7400 ffffffe2 f8b1c0d1 c31f7400 f6597000
[ 1665.489252] Call Trace:
[ 1665.491693] [<c10cefe6>] ? sync_dirty_buffer+0x46/0x83
[ 1665.496902] [<f8ae039c>] ? journal_update_superblock+0x67/0x99 [jbd]
[ 1665.503322] [<f8adf173>] ? cleanup_journal_tail+0xb7/0xbc [jbd]
[ 1665.509308] [<f8ae1161>] ? journal_flush+0x99/0x123 [jbd]
[ 1665.514776] [<f8b1c0d1>] ? ext3_mark_recovery_complete+0x21/0x67 [ext3]
[ 1665.521456] [<f8b1d1d8>] ? ext3_remount+0x133/0x28d [ext3]
[ 1665.527011] [<f8b1d0a5>] ? ext3_remount+0x0/0x28d [ext3]
[ 1665.532394] [<c10b4e19>] ? do_remount_sb+0xc2/0xfc
[ 1665.537254] [<c10c6713>] ? do_mount+0x221/0x69e
[ 1665.541858] [<c113c4f3>] ? copy_from_user+0x27/0x10e
[ 1665.546894] [<c10c6bf6>] ? sys_mount+0x66/0x98
[ 1665.551412] [<c10030fb>] ? sysenter_do_call+0x12/0x28
[ 1665.551414] Code: 04 e8 36 fd ff ff 89 d8 3b 1c 24 eb ef 59 89 f0 5b 5e 5f c3 57 89 c7 56 53 89 d3 8b 12 f6
[ 1665.551436] EIP: [<c10cdac4>] submit_bh+0x17/0x106 SS:ESP 0068:f6cdde90
[ 1665.551447] ---[ end trace a739b219971062e9 ]---
/etc/rc0.d/K17umountfs: line 19: 1956 Segmentation fault fstab-decode umount -f -r -d $REG_MTPTS
failed.
Stopping web server: apache2.
Stopping nginx: nginx.
Stopping enhanced syslogd: rsyslogd already stopped.
Will now halt.
^ permalink raw reply [flat|nested] 9+ messages in thread* Re: [Qemu-devel] [Nbd] Hibernate and qemu-nbd
@ 2013-10-17 17:07 Mark Trumpold
2013-10-17 19:53 ` Paul Clements
0 siblings, 1 reply; 9+ messages in thread
From: Mark Trumpold @ 2013-10-17 17:07 UTC (permalink / raw)
To: Stefan Hajnoczi, Mark Trumpold
Cc: nbd-general, Wouter Verhelst, Paul Clements, qemu-devel,
Paolo Bonzini
>-----Original Message-----
>From: Stefan Hajnoczi [mailto:stefanha@gmail.com]
>Sent: Tuesday, October 15, 2013 08:17 AM
>To: 'Mark Trumpold'
>Cc: 'Wouter Verhelst', nbd-general@lists.sourceforge.net, 'Paul Clements',
>qemu-devel@nongnu.org, 'Paolo Bonzini'
>Subject: Re: [Nbd] [Qemu-devel] Hibernate and qemu-nbd
>
>On Thu, Oct 10, 2013 at 11:15 AM, Stefan Hajnoczi <stefanha@gmail.com> wrote:
>> On Fri, Oct 04, 2013 at 07:30:45AM -0700, Mark Trumpold wrote:
>>>
>>>
>>> On 9/26/13 10:18 PM, "Stefan Hajnoczi" <stefanha@gmail.com> wrote:
>>>
>>> >
>>> >Try the qemu-nbd --persistent option. That should prevent it from
>>> >shutting down when nbd-client is disconnected.
>>> >
>>> >Stefan
>>> >
>>>
>>>
>>> Hi Stefan,
>>>
>>> Sorry for the delay..
>>> I tried the following per your suggestion:
>>>
>>> 920 qemu-nbd --persistent -p 2000 /root/qemu/q1.img &
>>> 921 nbd-client -persist localhost 2000 /dev/nbd0
>>> 922 fsck /dev/nbd0
>>> 923 mount /dev/nbd0 /mnt
>>> 924 ls /mnt
>>> 925 umount /dev/nbd0
>>> ::
>>>
>>> 927 echo reboot >/sys/power/disk
>>> 928 echo disk >/sys/power/state
>>> 929 mount /dev/nbd0 /mnt
>>>
>>> This seems to work; that is both sides (client and server) persist
>>> after the hibernate cycle.
>>>
>>> However, if I don't 'umount' '/dev/nbd0' before the hibernate
>>> cycle, and try to 'ls /mnt' after, the 'ls' hangs indefinitely.
>>>
>>> For my real use case we have the root filesystem mounted,
>>> so unmounting is not an option (at least I don't think so).
>>>
>>> I also tried remounting readonly, and also 'blockdev --flushbufs ..'
>>> before the hibernate cycle -- either or both did not help.
>>>
>>> I had thought about trying a 'chroot' and then a 'umount', but
>>> have not yet tried this.
>>>
>>> This one was so close..
>
>Could you try drop the umount/mount and capture the following straces:
>strace -p $(pgrep nbd-client) -o /var/tmp/nbd-client.strace
>strace -p $(pgrep qemu-nbd) -o /var/tmp/qemu-nbd.strace
>
>In particular, let's find out what happens when you run ls /mnt after
>resuming the machine. The nbd-client should reconnect to qemu-nbd and
>I/O requests would flow to qemu-nbd again. But something is stuck;
>the problem might be clear from the strace output.
>
>Stefan
>
Hi Stefan,
Following are:
1) test scenario commands
2) strace from nbd-client
3) strace from qemu-nbd (quite lengthy - 598 lines)
4) kernel oops output when I 'halt' after the test scenario.
Thank you,
Mark T.
1) ==========
162 qemu-nbd --persistent -p 2000 /root/qemu/q1.img &
163 nbd-client -persist localhost 2000 /dev/nbd0
165 strace -p 2488 -o /var/tmp/qemu.nbd.strace.v2 &
166 strace -p 2492 -o /var/tmp/nbd-client.strace.v2 &
168 mount /dev/nbd0 /mnt
169 ls /mnt
172 echo reboot >/sys/power/disk
173 echo disk >/sys/power/state
180 ls /mnt <-- hangs
2) ==========
write(2, "Kernel call returned: Interrupte"..., 45) = 45
write(2, " Reconnecting\n", 14) = 14
close(4) = 0
close(3) = 0
socket(PF_NETLINK, SOCK_RAW, 0) = 3
bind(3, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
getsockname(3, {sa_family=AF_NETLINK, pid=2492, groups=00000000}, [12]) = 0
time(NULL) = 1381947917
sendto(3, "\24\0\0\0\26\0\1\3\r\332^R\0\0\0\0\0\0\0\0", 20, 0, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"0\0\0\0\24\0\2\0\r\332^R\274\t\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) = 108
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"@\0\0\0\24\0\2\0\r\332^R\274\t\0\0\n\200\200\376\1\0\0\0\24\0\1\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 192
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"\24\0\0\0\3\0\2\0\r\332^R\274\t\0\0\0\0\0\0\1\0\0\0\24\0\1\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
close(3) = 0
stat64("/etc/resolv.conf", {st_mode=S_IFREG|0644, st_size=82, ...}) = 0
open("/etc/resolv.conf", O_RDONLY) = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=82, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7734000
read(3, "domain dvt.tachyon.net\nsearch dv"..., 4096) = 82
read(3, "", 4096) = 0
close(3) = 0
munmap(0xb7734000, 4096) = 0
open("/etc/hosts", O_RDONLY|O_CLOEXEC) = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=220, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7734000
read(3, "127.0.0.1 localhost\n127.0.1.1 "..., 4096) = 220
read(3, "", 4096) = 0
close(3) = 0
munmap(0xb7734000, 4096) = 0
socket(PF_INET, SOCK_STREAM, IPPROTO_TCP) = 3
connect(3, {sa_family=AF_INET, sin_port=htons(2000), sin_addr=inet_addr("127.0.0.1")}, 16) = 0
setsockopt(3, SOL_TCP, TCP_NODELAY, [1], 4) = 0
open("/dev/nbd0", O_RDWR|O_LARGEFILE) = 4
write(1, "Negotiation: ", 13) = 13
read(3, "NBDMAGIC", 8) = 8
write(1, ".", 1) = 1
read(3, "\0\0B\2\201\206\22S", 8) = 8
write(1, ".", 1) = 1
read(3, "\0\0\0\0\200\0\0\0", 8) = 8
write(1, "size = 2097152KB", 16) = 16
read(3, "\0\0\0-", 4) = 4
read(3, "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 124) = 124
write(1, "\n", 1) = 1
ioctl(4, NBD_SET_BLKSIZE, 0x400) = 0
ioctl(4, NBD_SET_SIZE_BLOCKS, 0x200000) = 0
write(2, "bs=1024, sz=2097152\n", 20) = 20
ioctl(4, NBD_CLEAR_SOCK, 0x400) = 0
ioctl(4, BLKROSET, 0xbff015bc) = 0
ioctl(4, NBD_SET_SOCK, 0x3) = 0
clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0xb75e9728) = 2617
open("/sys/block/nbd0/pid", O_RDONLY|O_LARGEFILE) = 5
read(5, "2617\n", 256) = 5
open("/dev/nbd0", O_RDONLY|O_LARGEFILE) = 6
exit_group(0) = ?
3)==========
select(11, [4 7 8 10], [], [], NULL) = 1 (in [10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\10w\211\337\370J\2\301\0\0\0\0\177\377\0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 673086168}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 673114055}) = 0
gettimeofday({1381947792, 257455}, NULL) = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [BUS USR1 ALRM IO], 8) = 0
clone(child_stack=0xb6fb4454, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0xb6fb4bd8, {entry_number:6, base_addr:0xb6fb4b70, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}, child_tidptr=0xb6fb4bd8) = 2502
rt_sigprocmask(SIG_SETMASK, [BUS USR1 ALRM IO], NULL, 8) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [4 8])
read(8, "\1\0\0\0\0\0\0\0", 512) = 8
read(4, "\0", 16) = 1
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\10w\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 673733305}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 673760970}) = 0
gettimeofday({1381947792, 258101}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 3 (in [4 8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\270{\211\337\370J\2\301\0\0\0\0\177\377\340\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
read(4, "\0\0", 16) = 2
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\270{\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 674249470}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 674276710}) = 0
gettimeofday({1381947792, 258617}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0`y\211\337\370J\2\301\0\0\0\0\177\377\360\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 674487375}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 674514971}) = 0
gettimeofday({1381947792, 258855}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0`y\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 674877957}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 674905303}) = 0
gettimeofday({1381947792, 259245}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\200|\211\337\370J\2\301\0\0\0\0\177\375\360\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\200|\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
read(8, "\4\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 675321220}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 675347583}) = 0
gettimeofday({1381947792, 259686}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0H}\211\337\370J\2\301\0\0\0\0\177\377\200\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
clock_gettime(CLOCK_MONOTONIC, {4617, 675520432}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 675547085}) = 0
gettimeofday({1381947792, 259886}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0H}\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 675900031}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 675926378}) = 0
gettimeofday({1381947792, 260265}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\20~\211\337\370J\2\301\0\0\0\0\177\376\0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\20~\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
read(8, "\4\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 676367264}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 676393812}) = 0
gettimeofday({1381947792, 260733}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0@v\211\337\370J\2\301\0\0\0\0\177\374\340\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0@v\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 676774764}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 676801483}) = 0
gettimeofday({1381947792, 261140}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0@v\211\337\0\4\0\0\0\0\0\0\0\20\0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 677012536}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 677041784}) = 0
gettimeofday({1381947792, 261394}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0@v\211\337\0\4\0\0", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 677426847}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 677453322}) = 0
gettimeofday({1381947792, 261792}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\20~\211\337\370J\2\301\0\0\0\0\0\0p\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 677657510}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 677683625}) = 0
gettimeofday({1381947792, 262022}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\20~\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\7\200\0\0\7\200\1\0\7\200\2\0\7\200\3\0\7\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 678034541}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 678061142}) = 0
gettimeofday({1381947792, 262400}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0H}\211\337\370J\2\301\0\0\0\0\0\0\360\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 678258703}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 678284454}) = 0
gettimeofday({1381947792, 262623}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0H}\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\17\200\0\0\17\200\1\0\17\200\2\0\17\200\3\0\17\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 678624438}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 678650172}) = 0
gettimeofday({1381947792, 262988}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\200|\211\337\370J\2\301\0\0\0\0\0\0 \0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 678844201}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 678869874}) = 0
gettimeofday({1381947792, 263208}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\200|\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\2\200\0\0\2\200\1\0\2\200\2\0\2\200\3\0\2\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 679215880}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 679241650}) = 0
gettimeofday({1381947792, 263580}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0`y\211\337\370J\2\301\0\0\0\0\0\1\0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 679436097}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 679461880}) = 0
gettimeofday({1381947792, 263800}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0`y\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\20\200\0\0\20\200\1\0\20\200\2\0\20\200\3\0\20\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 679801100}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 679826444}) = 0
gettimeofday({1381947792, 264165}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\270{\211\337\370J\2\301\0\0\0\0\0\0\200\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 680026336}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 680052367}) = 0
gettimeofday({1381947792, 264391}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\270{\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\10\200\0\0\10\200\1\0\10\200\2\0\10\200\3\0\10\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 680393173}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 680419096}) = 0
gettimeofday({1381947792, 264758}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\10w\211\337\370J\2\301\0\0\0\0\0\2\0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 680613875}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 680639741}) = 0
gettimeofday({1381947792, 264978}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\10w\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{" \200\0\0 \200\1\0 \200\2\0 \200\3\0 \200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 680982582}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 681011418}) = 0
gettimeofday({1381947792, 265350}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\230x\211\337\370J\2\301\0\0\0\0\0\2\20\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 681205417}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 681230811}) = 0
gettimeofday({1381947792, 265569}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\230x\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"!\200\0\0!\200\1\0!\200\2\0!\200\3\0!\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 681568208}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 681593995}) = 0
gettimeofday({1381947792, 265932}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\320w\211\337\370J\2\301\0\0\0\0\0\2 \0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 681786255}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 681811824}) = 0
gettimeofday({1381947792, 266150}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\320w\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\"\200\0\0\"\200\1\0\"\200\2\0\"\200\3\0\"\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 682149288}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 682174762}) = 0
gettimeofday({1381947792, 266513}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\330~\211\337\370J\2\301\0\0\0\0\0\6\0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 682367933}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 682393840}) = 0
gettimeofday({1381947792, 266732}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\330~\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"`\200\0\0`\200\1\0`\200\2\0`\200\3\0`\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 682731029}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 682756641}) = 0
gettimeofday({1381947792, 267095}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0(z\211\337\370J\2\301\0\0\0\0\0\6\20\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 682954135}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 682980023}) = 0
gettimeofday({1381947792, 267318}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0(z\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"a\200\0\0a\200\1\0a\200\2\0a\200\3\0a\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 683316790}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 683342430}) = 0
gettimeofday({1381947792, 267681}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\360z\211\337\370J\2\301\0\0\0\0\0\6 \0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 683536301}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 683562449}) = 0
gettimeofday({1381947792, 267901}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\360z\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"b\200\0\0b\200\1\0b\200\2\0b\200\3\0b\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 683900654}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 683926414}) = 0
gettimeofday({1381947792, 268265}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\360z\211\337\370J\2\301\0\0\0\0\0\4\0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 684124398}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 684150097}) = 0
gettimeofday({1381947792, 268488}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\360z\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"@\200\0\0@\200\1\0@\200\2\0@\200\3\0@\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 684487657}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 684515496}) = 0
gettimeofday({1381947792, 268854}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0(z\211\337\370J\2\301\0\0\0\0\0\0@\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 684707712}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 684736889}) = 0
gettimeofday({1381947792, 269076}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0(z\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\4\200\0\0\4\200\1\0\4\200\2\0\4\200\3\0\4\200\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 685080739}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 685106289}) = 0
gettimeofday({1381947792, 269444}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\330~\211\337\370J\2\301\0\0\0\0\0 \0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 685299653}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 685325276}) = 0
gettimeofday({1381947792, 269663}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\330~\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 685674021}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 685699648}) = 0
gettimeofday({1381947792, 270038}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\320w\211\337\370J\2\301\0\0\0\0\0\0\0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 685892851}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 685918482}) = 0
gettimeofday({1381947792, 270257}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\320w\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 686254784}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 686280563}) = 0
gettimeofday({1381947792, 270619}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\230x\211\337\370J\2\301\0\0\0\0\0\0\20\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 686473590}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 686499245}) = 0
gettimeofday({1381947792, 270837}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\230x\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\201\0\0\0\202\0\0\0\203\0\0\0w}\365\37\2\0\4\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 686838545}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 686864137}) = 0
gettimeofday({1381947792, 271202}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\10w\211\337\370J\2\301\0\0\0\0\0\0100\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 687057993}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 687083717}) = 0
gettimeofday({1381947792, 271422}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\10w\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0b\302^Rb\302^Rb\302^R\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 687418549}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 687444145}) = 0
gettimeofday({1381947792, 271782}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 2 (in [8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0\270{\211\337\370J\2\301\0\0\0\0@ \0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 687637477}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 687662888}) = 0
gettimeofday({1381947792, 272001}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0\270{\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\300;9\230\0\0\0\4\0\0\0\0\0\0\20\0\0\0@\0\0\0\0\1\0\0\0\5\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 688029898}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 688055744}) = 0
gettimeofday({1381947792, 272394}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 3 (in [4 8 10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\1`y\211\337\370J\2\301\0\0\0\0\0\0\0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
read(4, "\0\0", 16) = 2
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
clock_gettime(CLOCK_MONOTONIC, {4617, 688361860}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 688387368}) = 0
gettimeofday({1381947792, 272726}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
clock_gettime(CLOCK_MONOTONIC, {4617, 690841914}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 690871850}) = 0
gettimeofday({1381947792, 275210}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
clock_gettime(CLOCK_MONOTONIC, {4617, 691285911}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 691311235}) = 0
gettimeofday({1381947792, 275649}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0`y\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 691847873}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 691874907}) = 0
gettimeofday({1381947792, 276215}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [8])
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4617, 691999716}) = 0
clock_gettime(CLOCK_MONOTONIC, {4617, 692031827}) = 0
gettimeofday({1381947792, 276374}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\0H}\211\337\370J\2\301\0\0\0\0\0(0\0\0\0\20\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
clock_gettime(CLOCK_MONOTONIC, {4619, 157057596}) = 0
clock_gettime(CLOCK_MONOTONIC, {4619, 157089969}) = 0
gettimeofday({1381947793, 741432}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [4])
read(4, "\0", 16) = 1
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
setsockopt(10, SOL_TCP, TCP_CORK, [1], 4) = 0
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\0\0\0\0H}\211\337\370J\2\301", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\2\0\0\0\f\0\1\2.\0\0\0\2\0\0\0\f\0\2\2..\0\0\v\0\0\0\350\17\n\2"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 4096
setsockopt(10, SOL_TCP, TCP_CORK, [0], 4) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4619, 157493390}) = 0
clock_gettime(CLOCK_MONOTONIC, {4619, 157523670}) = 0
gettimeofday({1381947793, 741870}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [8])
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4619, 157663414}) = 0
clock_gettime(CLOCK_MONOTONIC, {4619, 157693026}) = 0
gettimeofday({1381947793, 742035}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = ? ERESTARTNOHAND (To be restarted)
select(11, [4 7 8 10], [], [], NULL) = 1 (in [10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\260\305\335\21\2\0\0\0H}\211\337\370J\2\301\0\0\0\0\0(0\0\2\0\0\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 0
write(2, "nbd.c:nbd_receive_request():L517"..., 46) = 46
close(10) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4720, 294048012}) = 0
clock_gettime(CLOCK_MONOTONIC, {4720, 294099006}) = 0
gettimeofday({1381947917, 351168}, NULL) = 0
select(9, [4 6 7 8], [], [], NULL) = 1 (in [8])
read(8, "\1\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4720, 294357650}) = 0
clock_gettime(CLOCK_MONOTONIC, {4720, 294390180}) = 0
gettimeofday({1381947917, 351448}, NULL) = 0
select(9, [4 6 7 8], [], [], NULL) = 1 (in [6])
accept(6, {sa_family=AF_INET, sin_port=htons(59655), sin_addr=inet_addr("127.0.0.1")}, [16]) = 10
fcntl64(10, F_GETFL) = 0x2 (flags O_RDWR)
fcntl64(10, F_SETFL, O_RDWR) = 0
send(10, "NBDMAGIC\0\0B\2\201\206\22S\0\0\0\0\200\0\0\0\0\0\0-\0\0\0\0"..., 152, 0) = 152
fcntl64(10, F_GETFL) = 0x2 (flags O_RDWR)
fcntl64(10, F_SETFL, O_RDWR|O_NONBLOCK) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4720, 295635776}) = 0
clock_gettime(CLOCK_MONOTONIC, {4720, 295668849}) = 0
gettimeofday({1381947917, 352737}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [8])
read(8, "\1\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4720, 295838190}) = 0
clock_gettime(CLOCK_MONOTONIC, {4720, 295866524}) = 0
gettimeofday({1381947917, 352923}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"%`\225\23\0\0\0\2$}\254\337\0\0\237\337\377\377\377\377\377\377\376\0\0\0\0\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 28
write(2, "nbd.c:nbd_trip():L836: From: 184"..., 88) = 88
write(2, "nbd.c:nbd_trip():L837: requested"..., 65) = 65
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
sendmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"gDf\230\377\377\377\352$}\254\337\0\0\237\337", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4890, 325996113}) = 0
clock_gettime(CLOCK_MONOTONIC, {4890, 326026297}) = 0
gettimeofday({1381948087, 383085}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [8])
read(8, "\2\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4890, 326167801}) = 0
clock_gettime(CLOCK_MONOTONIC, {4890, 326196339}) = 0
gettimeofday({1381948087, 383254}, NULL) = 0
select(11, [4 7 8 10], [], [], NULL) = 1 (in [10])
recvmsg(10, {msg_name(0)=NULL, msg_iov(1)=[{"\260\305\335\21\2\0\0\0$}\254\337\0\0\237\337\377\377\377\377\377\377\376\0\2\0\0\0", 28}], msg_controllen=0, msg_flags=0}, 0) = 0
write(2, "nbd.c:nbd_receive_request():L517"..., 46) = 46
close(10) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
clock_gettime(CLOCK_MONOTONIC, {4891, 945891719}) = 0
clock_gettime(CLOCK_MONOTONIC, {4891, 945921390}) = 0
gettimeofday({1381948089, 2979}, NULL) = 0
select(9, [4 6 7 8], [], [], NULL) = 1 (in [8])
read(8, "\1\0\0\0\0\0\0\0", 512) = 8
clock_gettime(CLOCK_MONOTONIC, {4891, 946055807}) = 0
clock_gettime(CLOCK_MONOTONIC, {4891, 946084089}) = 0
gettimeofday({1381948089, 3142}, NULL) = 0
select(9, [4 6 7 8], [], [], NULL) = ? ERESTARTNOHAND (To be restarted)
--- SIGTERM (Terminated) @ 0 (0) ---
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
sigreturn() = ? (mask now [BUS USR1 ALRM IO])
clock_gettime(CLOCK_MONOTONIC, {4903, 772249684}) = 0
clock_gettime(CLOCK_MONOTONIC, {4903, 772281593}) = 0
gettimeofday({1381948100, 829342}, NULL) = 0
munmap(0xb64b3000, 1052672) = 0
write(9, "\1\0\0\0\0\0\0\0", 8) = 8
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [BUS USR1 ALRM IO], 8) = 0
clone(child_stack=0xb6fb4454, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0xb6fb4bd8, {entry_number:6, base_addr:0xb6fb4b70, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}, child_tidptr=0xb6fb4bd8) = 2632
rt_sigprocmask(SIG_SETMASK, [BUS USR1 ALRM IO], NULL, 8) = 0
select(5, [4], [], NULL, NULL) = 1 (in [4])
read(4, "\0", 16) = 1
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
select(5, [4], [], NULL, NULL) = 1 (in [4])
read(4, "\0", 16) = 1
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
select(5, [4], [], NULL, NULL) = 1 (in [4])
read(4, "\0", 16) = 1
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
select(5, [4], [], NULL, NULL) = 1 (in [4])
read(4, "\0", 16) = 1
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
select(5, [4], [], NULL, NULL) = 1 (in [4])
read(4, "\0", 16) = 1
munmap(0xb65b4000, 2101248) = 0
futex(0xb77be744, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0xb77be740, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
select(5, [4], [], NULL, NULL) = 1 (in [4])
read(4, "\0", 16) = 1
close(3) = 0
timer_delete(0x1) = 0
munmap(0xb6fb5000, 1052672) = 0
exit_group(0) = ?
4)==========
Unmounting temporary filesystems...done. 'e' to edit the
Deactivating swap...done.ng, or 'c' for a command-line.
Unmounting local filesystems...[ 233.985582] ------------[ cut here ]------------
[ 233.990186] kernel BUG at /build/buildd-linux-2.6_2.6.32-45-i386-yQfQSv/linux-2.6-2.6.32/debian/build/source!
[ 234.002400] invalid opcode: 0000 [#1] SMP matically in 1 seconds.
[ 234.006510] last sysfs file: /sys/devices/virtual/block/nbd0/pid
[ 234.012495] Modules linked in: ext3 jbd loop snd_hda_codec_realtek snd_hda_intel snd_hda_codec snd_hwdep snd]
[ 234.047565]
[ 234.049051] Pid: 1934, comm: umount Not tainted (2.6.32-5-686 #1) CBxx55/56
[ 234.055986] EIP: 0060:[<c10cdac4>] EFLAGS: 00010246 CPU: 6
[ 234.061455] EIP is at submit_bh+0x17/0x106
[ 234.065537] EAX: 000004c1 EBX: f6bde080 ECX: f6bde0c0 EDX: 00000005
[ 234.071781] ESI: f65aa600 EDI: 000004c1 EBP: f6611800 ESP: dfbe7f24
[ 234.078024] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068
[ 234.083405] Process umount (pid: 1934, ti=dfbe6000 task=f66df2c0 task.ti=dfbe6000)
[ 234.090945] Stack:
[ 234.092950] f6bde080 f65aa600 f65a8000 c10cefe6 dfe04400 f65aa600 f8ae53a9 f65a8000
[ 234.100586] <0> f8aeb5fc f8aeefd0 f6611800 c10b5540 f6824600 00000003 c10b55da f65a8000
[ 234.108687] <0> c10e3e16 c10b5b77 f65a8000 f6611800 f6611818 c10c5b5b dfbe7f88 00000000
[ 234.116981] Call Trace:
[ 234.119423] [<c10cefe6>] ? sync_dirty_buffer+0x46/0x83
[ 234.124632] [<f8ae53a9>] ? ext3_put_super+0x77/0x1b6 [ext3]
[ 234.130275] [<c10b5540>] ? generic_shutdown_super+0x46/0xc6
[ 234.135915] [<c10b55da>] ? kill_block_super+0x1a/0x2c
[ 234.141038] [<c10e3e16>] ? vfs_quota_off+0x0/0xd
[ 234.145726] [<c10b5b77>] ? deactivate_super+0x4a/0x5f
[ 234.150848] [<c10c5b5b>] ? sys_umount+0x2a5/0x2cb
[ 234.155624] [<c10030fb>] ? sysenter_do_call+0x12/0x28
[ 234.160744] Code: 04 e8 36 fd ff ff 89 d8 3b 1c 24 eb ef 59 89 f0 5b 5e 5f c3 57 89 c7 56 53 89 d3 8b 12 f6
[ 234.176165] EIP: [<c10cdac4>] submit_bh+0x17/0x106 SS:ESP 0068:dfbe7f24
[ 234.176177] ---[ end trace dd7481bfd11921ae ]---
[ 234.176178] ------------[ cut here ]------------
[ 234.176182] WARNING: at /build/buildd-linux-2.6_2.6.32-45-i386-yQfQSv/linux-2.6-2.6.32/debian/build/source_i)
[ 234.176184] Hardware name: CBxx55/56
[ 234.176185] Modules linked in: ext3 jbd loop snd_hda_codec_realtek snd_hda_intel snd_hda_codec snd_hwdep snd]
[ 234.176201] Pid: 1934, comm: umount Tainted: G D 2.6.32-5-686 #1
[ 234.176202] Call Trace:
[ 234.176206] [<c1030949>] ? warn_slowpath_common+0x5e/0x8a
[ 234.176209] [<c103097f>] ? warn_slowpath_null+0xa/0xc
[ 234.176211] [<c10335e0>] ? do_exit+0x30/0x5cf
[ 234.176216] [<c126d6b3>] ? printk+0xe/0x13
[ 234.176218] [<c126fc08>] ? oops_end+0x98/0x9d
[ 234.176221] [<c1004121>] ? do_invalid_op+0x0/0x75
[ 234.176224] [<c100418d>] ? do_invalid_op+0x6c/0x75
[ 234.176227] [<c10cdac4>] ? submit_bh+0x17/0x106
[ 234.176230] [<c10c96c0>] ? writeback_single_inode+0x26a/0x273
[ 234.176234] [<f8aa8599>] ? journal_destroy+0x16c/0x188 [jbd]
[ 234.176237] [<f8aa85ab>] ? journal_destroy+0x17e/0x188 [jbd]
[ 234.176240] [<f8aa85ab>] ? journal_destroy+0x17e/0x188 [jbd]
[ 234.176243] [<c10aef0b>] ? kfree+0xcc/0xde
[ 234.176245] [<c126f2f3>] ? error_code+0x73/0x78
[ 234.176248] [<c10cdac4>] ? submit_bh+0x17/0x106
[ 234.176251] [<c10cefe6>] ? sync_dirty_buffer+0x46/0x83
[ 234.176255] [<f8ae53a9>] ? ext3_put_super+0x77/0x1b6 [ext3]
[ 234.176258] [<c10b5540>] ? generic_shutdown_super+0x46/0xc6
[ 234.176261] [<c10b55da>] ? kill_block_super+0x1a/0x2c
[ 234.176264] [<c10e3e16>] ? vfs_quota_off+0x0/0xd
[ 234.176267] [<c10b5b77>] ? deactivate_super+0x4a/0x5f
[ 234.176270] [<c10c5b5b>] ? sys_umount+0x2a5/0x2cb
[ 234.176272] [<c10030fb>] ? sysenter_do_call+0x12/0x28
[ 234.176274] ---[ end trace dd7481bfd11921af ]---
/etc/rc0.d/K17umountfs: line 19: 1934 Segmentation fault fstab-decode umount -f -r -d $REG_MTPTS
failed.
Stopping web server: apache2.
Stopping nginx: nginx.
Stopping enhanced syslogd: rsyslogd already stopped.
Will now halt.
^ permalink raw reply [flat|nested] 9+ messages in thread* Re: [Qemu-devel] [Nbd] Hibernate and qemu-nbd
2013-10-17 17:07 Mark Trumpold
@ 2013-10-17 19:53 ` Paul Clements
0 siblings, 0 replies; 9+ messages in thread
From: Paul Clements @ 2013-10-17 19:53 UTC (permalink / raw)
To: Mark Trumpold
Cc: nbd-general@lists.sourceforge.net, Stefan Hajnoczi, Paolo Bonzini,
qemu-devel, Wouter Verhelst
[-- Attachment #1: Type: text/plain, Size: 446 bytes --]
Hi Mark,
On Thu, Oct 17, 2013 at 1:07 PM, Mark Trumpold <markt@netqa.com> wrote:
> 165 strace -p 2488 -o /var/tmp/qemu.nbd.strace.v2 &
> 166 strace -p 2492 -o /var/tmp/nbd-client.strace.v2 &
>
You'll need to include the -f option to strace to get the full child trace
(at least for nbd-client). In the output you sent, we don't see the child
nbd-client, which is the one calling the NBD_DO_IT ioctl, which is
important to see.
--
Paul
[-- Attachment #2: Type: text/html, Size: 926 bytes --]
^ permalink raw reply [flat|nested] 9+ messages in thread
* Re: [Qemu-devel] [Nbd] Hibernate and qemu-nbd
@ 2013-09-26 20:48 Mark Trumpold
2013-09-27 5:18 ` Stefan Hajnoczi
0 siblings, 1 reply; 9+ messages in thread
From: Mark Trumpold @ 2013-09-26 20:48 UTC (permalink / raw)
To: Wouter Verhelst, Mark Trumpold
Cc: nbd-general, Stefan Hajnoczi, bonzini, Paul Clements, qemu-devel
>-----Original Message-----
>From: Wouter Verhelst [mailto:w@uter.be]
>Sent: Thursday, September 26, 2013 12:46 PM
>To: 'Mark Trumpold'
>Cc: nbd-general@lists.sourceforge.net, 'Stefan Hajnoczi',
>bonzini@stefanha-thinkpad.redhat.com, 'Paul Clements', qemu-devel@nongnu.org
>Subject: Re: [Nbd] [Qemu-devel] Hibernate and qemu-nbd
>
>On 25-09-13 16:42, Mark Trumpold wrote:
>> Hello Wouter,
>>
>> Thank you for your input.
>>
>> I replayed the test as follows:
>>
>> -> qemu-nbd -p 2000 -persist /root/qemu/q1.img &
>> -> nbd-client localhost 2000 /dev/nbd0
>
>No.
>
>nbd-client -persist localhost 2000 /dev/nbd0
>
>--
>This end should point toward the ground if you want to go to space.
>
>If it starts pointing toward space you are having a bad problem and you
>will not go to space today.
>
> -- http://xkcd.com/1133/
>
Sorry guys, I did the email by memory (bad idea).
Actually, what I did:
849 qemu-nbd -p 2000 /root/qemu/q1.img &
850 nbd-client -persist localhost 2000 /dev/nbd0
851 ps aux | grep nbd
852 echo reboot >/sys/power/disk
853 echo disk >/sys/power/state
At the prompt after the hibernate (test mode: 'reboot')
I see the following:
/build/buildd-qemu_0.12.5+dfsg-3squeeze3-amd64-9wXBnc/qemu-0.12.5+dfsg/nbd.c:nbd_receive_request():L465: read failed
[1]+ Done qemu-nbd -p 2000 /root/qemu/q1.img
================
Looks like 'qemu-nbd' exited on some signal. No other indicators.
I see no other relevant messages in syslog.
In dmesg I see the message (as expected):
Sep 26 13:27:13 debian-test kernel: [606754.367766] Freezing user space processes ...
Sep 26 13:27:13 debian-test kernel: [606754.367840] nbd (pid 8432: nbd-client) got signal 0
Sep 26 13:27:13 debian-test kernel: [606754.367844] block nbd0: shutting down socket
Sep 26 13:27:13 debian-test kernel: [606754.367872] block nbd0: Receive control failed (result -4)
Sep 26 13:27:13 debian-test kernel: [606754.367890] block nbd0: queue cleared
================
Thank you,
Mark T.
^ permalink raw reply [flat|nested] 9+ messages in thread
* Re: [Qemu-devel] [Nbd] Hibernate and qemu-nbd
2013-09-26 20:48 Mark Trumpold
@ 2013-09-27 5:18 ` Stefan Hajnoczi
2013-10-04 14:30 ` Mark Trumpold
0 siblings, 1 reply; 9+ messages in thread
From: Stefan Hajnoczi @ 2013-09-27 5:18 UTC (permalink / raw)
To: Mark Trumpold
Cc: nbd-general, Wouter Verhelst, bonzini, Paul Clements, qemu-devel
On Thu, Sep 26, 2013 at 10:48 PM, Mark Trumpold <markt@netqa.com> wrote:
>
>>-----Original Message-----
>>From: Wouter Verhelst [mailto:w@uter.be]
>>Sent: Thursday, September 26, 2013 12:46 PM
>>To: 'Mark Trumpold'
>>Cc: nbd-general@lists.sourceforge.net, 'Stefan Hajnoczi',
>>bonzini@stefanha-thinkpad.redhat.com, 'Paul Clements', qemu-devel@nongnu.org
>>Subject: Re: [Nbd] [Qemu-devel] Hibernate and qemu-nbd
>>
>>On 25-09-13 16:42, Mark Trumpold wrote:
>>> Hello Wouter,
>>>
>>> Thank you for your input.
>>>
>>> I replayed the test as follows:
>>>
>>> -> qemu-nbd -p 2000 -persist /root/qemu/q1.img &
>>> -> nbd-client localhost 2000 /dev/nbd0
>>
>>No.
>>
>>nbd-client -persist localhost 2000 /dev/nbd0
>>
>>--
>>This end should point toward the ground if you want to go to space.
>>
>>If it starts pointing toward space you are having a bad problem and you
>>will not go to space today.
>>
>> -- http://xkcd.com/1133/
>>
>
> Sorry guys, I did the email by memory (bad idea).
>
> Actually, what I did:
> 849 qemu-nbd -p 2000 /root/qemu/q1.img &
Try the qemu-nbd --persistent option. That should prevent it from
shutting down when nbd-client is disconnected.
Stefan
^ permalink raw reply [flat|nested] 9+ messages in thread
* Re: [Qemu-devel] [Nbd] Hibernate and qemu-nbd
2013-09-27 5:18 ` Stefan Hajnoczi
@ 2013-10-04 14:30 ` Mark Trumpold
2013-10-10 9:15 ` Stefan Hajnoczi
0 siblings, 1 reply; 9+ messages in thread
From: Mark Trumpold @ 2013-10-04 14:30 UTC (permalink / raw)
To: Stefan Hajnoczi
Cc: nbd-general, Wouter Verhelst, bonzini, Paul Clements,
qemu-devel@nongnu.org
On 9/26/13 10:18 PM, "Stefan Hajnoczi" <stefanha@gmail.com> wrote:
>
>Try the qemu-nbd --persistent option. That should prevent it from
>shutting down when nbd-client is disconnected.
>
>Stefan
>
Hi Stefan,
Sorry for the delay..
I tried the following per your suggestion:
920 qemu-nbd --persistent -p 2000 /root/qemu/q1.img &
921 nbd-client -persist localhost 2000 /dev/nbd0
922 fsck /dev/nbd0
923 mount /dev/nbd0 /mnt
924 ls /mnt
925 umount /dev/nbd0
::
927 echo reboot >/sys/power/disk
928 echo disk >/sys/power/state
929 mount /dev/nbd0 /mnt
This seems to work; that is both sides (client and server) persist
after the hibernate cycle.
However, if I don't 'umount' '/dev/nbd0' before the hibernate
cycle, and try to 'ls /mnt' after, the 'ls' hangs indefinitely.
For my real use case we have the root filesystem mounted,
so unmounting is not an option (at least I don't think so).
I also tried remounting readonly, and also 'blockdev --flushbufs ..'
before the hibernate cycle -- either or both did not help.
I had thought about trying a 'chroot' and then a 'umount', but
have not yet tried this.
This one was so close..
Thank you for all your attention on this.
Best Regards,
Mark T.
^ permalink raw reply [flat|nested] 9+ messages in thread
* Re: [Qemu-devel] [Nbd] Hibernate and qemu-nbd
2013-10-04 14:30 ` Mark Trumpold
@ 2013-10-10 9:15 ` Stefan Hajnoczi
2013-10-15 15:17 ` Stefan Hajnoczi
0 siblings, 1 reply; 9+ messages in thread
From: Stefan Hajnoczi @ 2013-10-10 9:15 UTC (permalink / raw)
To: Mark Trumpold
Cc: nbd-general, Wouter Verhelst, bonzini, Paul Clements,
qemu-devel@nongnu.org
On Fri, Oct 04, 2013 at 07:30:45AM -0700, Mark Trumpold wrote:
>
>
> On 9/26/13 10:18 PM, "Stefan Hajnoczi" <stefanha@gmail.com> wrote:
>
> >
> >Try the qemu-nbd --persistent option. That should prevent it from
> >shutting down when nbd-client is disconnected.
> >
> >Stefan
> >
>
>
> Hi Stefan,
>
> Sorry for the delay..
> I tried the following per your suggestion:
>
> 920 qemu-nbd --persistent -p 2000 /root/qemu/q1.img &
> 921 nbd-client -persist localhost 2000 /dev/nbd0
> 922 fsck /dev/nbd0
> 923 mount /dev/nbd0 /mnt
> 924 ls /mnt
> 925 umount /dev/nbd0
> ::
>
> 927 echo reboot >/sys/power/disk
> 928 echo disk >/sys/power/state
> 929 mount /dev/nbd0 /mnt
>
> This seems to work; that is both sides (client and server) persist
> after the hibernate cycle.
>
> However, if I don't 'umount' '/dev/nbd0' before the hibernate
> cycle, and try to 'ls /mnt' after, the 'ls' hangs indefinitely.
>
> For my real use case we have the root filesystem mounted,
> so unmounting is not an option (at least I don't think so).
>
> I also tried remounting readonly, and also 'blockdev --flushbufs ..'
> before the hibernate cycle -- either or both did not help.
>
> I had thought about trying a 'chroot' and then a 'umount', but
> have not yet tried this.
>
> This one was so close..
Too bad. I'm sure it's solvable but would require more debugging and
writing qemu-nbd.c and kernel nbd.c fixes. Unfortunately I don't have
time to look into it myself.
Stefan
^ permalink raw reply [flat|nested] 9+ messages in thread
* Re: [Qemu-devel] [Nbd] Hibernate and qemu-nbd
2013-10-10 9:15 ` Stefan Hajnoczi
@ 2013-10-15 15:17 ` Stefan Hajnoczi
0 siblings, 0 replies; 9+ messages in thread
From: Stefan Hajnoczi @ 2013-10-15 15:17 UTC (permalink / raw)
To: Mark Trumpold
Cc: nbd-general, Wouter Verhelst, Paul Clements,
qemu-devel@nongnu.org, Paolo Bonzini
On Thu, Oct 10, 2013 at 11:15 AM, Stefan Hajnoczi <stefanha@gmail.com> wrote:
> On Fri, Oct 04, 2013 at 07:30:45AM -0700, Mark Trumpold wrote:
>>
>>
>> On 9/26/13 10:18 PM, "Stefan Hajnoczi" <stefanha@gmail.com> wrote:
>>
>> >
>> >Try the qemu-nbd --persistent option. That should prevent it from
>> >shutting down when nbd-client is disconnected.
>> >
>> >Stefan
>> >
>>
>>
>> Hi Stefan,
>>
>> Sorry for the delay..
>> I tried the following per your suggestion:
>>
>> 920 qemu-nbd --persistent -p 2000 /root/qemu/q1.img &
>> 921 nbd-client -persist localhost 2000 /dev/nbd0
>> 922 fsck /dev/nbd0
>> 923 mount /dev/nbd0 /mnt
>> 924 ls /mnt
>> 925 umount /dev/nbd0
>> ::
>>
>> 927 echo reboot >/sys/power/disk
>> 928 echo disk >/sys/power/state
>> 929 mount /dev/nbd0 /mnt
>>
>> This seems to work; that is both sides (client and server) persist
>> after the hibernate cycle.
>>
>> However, if I don't 'umount' '/dev/nbd0' before the hibernate
>> cycle, and try to 'ls /mnt' after, the 'ls' hangs indefinitely.
>>
>> For my real use case we have the root filesystem mounted,
>> so unmounting is not an option (at least I don't think so).
>>
>> I also tried remounting readonly, and also 'blockdev --flushbufs ..'
>> before the hibernate cycle -- either or both did not help.
>>
>> I had thought about trying a 'chroot' and then a 'umount', but
>> have not yet tried this.
>>
>> This one was so close..
Could you try drop the umount/mount and capture the following straces:
strace -p $(pgrep nbd-client) -o /var/tmp/nbd-client.strace
strace -p $(pgrep qemu-nbd) -o /var/tmp/qemu-nbd.strace
In particular, let's find out what happens when you run ls /mnt after
resuming the machine. The nbd-client should reconnect to qemu-nbd and
I/O requests would flow to qemu-nbd again. But something is stuck;
the problem might be clear from the strace output.
Stefan
^ permalink raw reply [flat|nested] 9+ messages in thread
* Re: [Qemu-devel] Hibernate and qemu-nbd
@ 2013-09-25 14:42 Mark Trumpold
2013-09-26 19:46 ` [Qemu-devel] [Nbd] " Wouter Verhelst
0 siblings, 1 reply; 9+ messages in thread
From: Mark Trumpold @ 2013-09-25 14:42 UTC (permalink / raw)
To: Wouter Verhelst
Cc: nbd-general, Stefan Hajnoczi, bonzini, Paul Clements,
qemu-devel@nongnu.org
Hello Wouter,
Thank you for your input.
I replayed the test as follows:
-> qemu-nbd -p 2000 -persist /root/qemu/q1.img &
-> nbd-client localhost 2000 /dev/nbd0
-> echo reboot >/sys/power/disk
-> echo disk >/sys/power/state
The "reboot" is a handy way to test, as it goes through the
complete hibernate cycle and returns to the prompt.
In this case the client DID try to reconnect as you suggest,
however the 'qemu-nbd' server side had exited, so no go.
Regards,
Mark T.
On 9/21/13 2:59 AM, "Wouter Verhelst" <w@uter.be> wrote:
>On 20-09-13 20:00, Mark Trumpold wrote:
>> Stefan,
>>
>> So, I tried the following:
>>
>> -> qemu-nbd -p 2000 /root/qemu/q1.img &
>> -> nbd-client localhost 2000 /dev/nbd0 &
>
>That won't work. nbd-client will only try the reconnect thing if you use
>the "-persist" option.
>
>Also, nbd-client will do a fork(), so the & isn't necessary.
>
>--
>This end should point toward the ground if you want to go to space.
>
>If it starts pointing toward space you are having a bad problem and you
>will not go to space today.
>
> -- http://xkcd.com/1133/
>
^ permalink raw reply [flat|nested] 9+ messages in thread
* Re: [Qemu-devel] [Nbd] Hibernate and qemu-nbd
2013-09-25 14:42 [Qemu-devel] " Mark Trumpold
@ 2013-09-26 19:46 ` Wouter Verhelst
0 siblings, 0 replies; 9+ messages in thread
From: Wouter Verhelst @ 2013-09-26 19:46 UTC (permalink / raw)
To: Mark Trumpold
Cc: nbd-general, Stefan Hajnoczi, bonzini, Paul Clements,
qemu-devel@nongnu.org
On 25-09-13 16:42, Mark Trumpold wrote:
> Hello Wouter,
>
> Thank you for your input.
>
> I replayed the test as follows:
>
> -> qemu-nbd -p 2000 -persist /root/qemu/q1.img &
> -> nbd-client localhost 2000 /dev/nbd0
No.
nbd-client -persist localhost 2000 /dev/nbd0
--
This end should point toward the ground if you want to go to space.
If it starts pointing toward space you are having a bad problem and you
will not go to space today.
-- http://xkcd.com/1133/
^ permalink raw reply [flat|nested] 9+ messages in thread
end of thread, other threads:[~2013-10-18 18:24 UTC | newest]
Thread overview: 9+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
2013-10-18 18:23 [Qemu-devel] [Nbd] Hibernate and qemu-nbd Mark Trumpold
-- strict thread matches above, loose matches on Subject: below --
2013-10-17 17:07 Mark Trumpold
2013-10-17 19:53 ` Paul Clements
2013-09-26 20:48 Mark Trumpold
2013-09-27 5:18 ` Stefan Hajnoczi
2013-10-04 14:30 ` Mark Trumpold
2013-10-10 9:15 ` Stefan Hajnoczi
2013-10-15 15:17 ` Stefan Hajnoczi
2013-09-25 14:42 [Qemu-devel] " Mark Trumpold
2013-09-26 19:46 ` [Qemu-devel] [Nbd] " Wouter Verhelst
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox;
as well as URLs for NNTP newsgroup(s).