[ltt-dev] [UST issue]: Waiting for ustd to shutdown...Segmentation fault
srikanth krishnakar
skrishnakar at gmail.com
Fri Jul 30 12:01:10 EDT 2010
Hello Alexis,
Thanks for your Quick Response !!!
There is no trace data collected as per the size of the metadata_* files &
ust_*
I am getting the following error while running the lttv as shown below:
-----------------------------
root at x86-generic:~# lttv -m textDump -t
/home/root/.usttraces/x86-generic-20100730150429647915588*/
2886_5499715367809623712/ *
** (process:2907): WARNING **: Unsupported trace version : 2.6
** (process:2907): WARNING **: parse_trace_header error
** (process:2907): WARNING **: Unsupported trace version : 2.6
** (process:2907): WARNING **: parse_trace_header error
** (process:2907): WARNING **: Unsupported trace version : 2.6
** (process:2907): WARNING **: parse_trace_header error
** (process:2907): WARNING **: Unsupported trace version : 2.6
** (process:2907): WARNING **: parse_trace_header error
** (process:2907): WARNING **: Trace
/home/root/.usttraces/x86-generic-20100730150429647915588/2886_5499715367809623712
has no metadata tracefile
** (process:2907): CRITICAL **: cannot open trace
/home/root/.usttraces/x86-generic-20100730150429647915588/2886_5499715367809623712/
*Segmentation fault*
root at x86-generic:~#
-----------------------------
When looking into the size of trace files:
root at x86-generic:~# du -sh
/home/root/.usttraces/x86-generic-20100730150429647915588/2886_5499715367809623712/*
4.0K
/home/root/.usttraces/x86-generic-20100730150429647915588/2886_5499715367809623712/metadata_0
4.0K
/home/root/.usttraces/x86-generic-20100730150429647915588/2886_5499715367809623712/metadata_1
4.0K
/home/root/.usttraces/x86-generic-20100730150429647915588/2886_5499715367809623712/ust_0
4.0K
/home/root/.usttraces/x86-generic-20100730150429647915588/2886_5499715367809623712/ust_1
root at x86-generic:~#
They all seem to be empty.
For reference I have attached the strace log for usttrace.
Thanks,
Srikanth
---------
On Fri, Jul 30, 2010 at 9:12 PM, Alexis Hallé <alexis.halle at polymtl.ca>wrote:
> Hi Srikanth,
>
> 2010/7/30 srikanth krishnakar <skrishnakar at gmail.com>:
> > Hello Pierre/Mathieu,
> >
> > I have followed the instructions for UST(user space tracing) from
> > http://lttng.org/files/ust/manual/ust.html
> >
> > 1. Installed liburcu & ust
> >
> > liburcu-0.46
> > ust-0.5
> >
> > 2. Compiled the usttest.c
> >
> > root at x86:~/ust-test# gcc -o usttest -lust usttest.c
> >
> > root at x86-generic:~/ust-test# ldd ./usttest
> > linux-gate.so.1 => (0xb789f000)
> > libust.so.0 => /usr/lib/libust.so.0 (0xb787a000)
> > libc.so.6 => /lib/libc.so.6 (0xb772d000)
> > /lib/ld-linux.so.2 (0xb78a0000)
> > liburcu-bp.so.0 => /usr/lib/liburcu-bp.so.0 (0xb772a000)
> > librt.so.1 => /lib/librt.so.1 (0xb7715000)
> > libpthread.so.0 => /lib/libpthread.so.0 (0xb76fb000)
> > root at x86-generic:~/ust-test#
> >
> > Package Versions:
> >
> > ###################
> > GLIBC: 2.11.1
> > GCC: 4.4
> > libglib-2.0-0
> > ###################
> >
> > 3. Now start tracing :
> >
> > root at x86-generic:~/ust-test# usttrace ./usttest
> > Waiting for ustd to shutdown...
>
> The usttrace script starts a daemon on-the-fly for this tracing
> session. When it is done tracing it sends this daemon a SIGTERM signal
> and waits for it to shutdown. This message is perfectly normal.
>
> > Trace was output in:
> > /home/root/.usttraces/x86-generic-20100730144935535976601
> > root at x86-generic:~/ust-test#
> >
> > Seems some issue in executing of ustctl..
> >
> > Now checking the presence of trace file:
> >
> > root at x86-generic:~/.usttraces/x86-generic-20100730144935535976601# du
> -sh *
> > 20K 2851_5499711528108666788
> > 0 app.log
> > 0 ustd.log
> > root at x86-generic:~/.usttraces/x86-generic-20100730144935535976601#
> >
> > 4. Dumping the result on console :
> >
> >
> > root at x86-generic:~# lttv -m textDump -t
> > /home/root/.usttraces/x86-generic-20100730144935535976601
> >
> > ** (process:2862): WARNING **: Unsupported trace version : 2.6
> >
> > ** (process:2862): WARNING **: parse_trace_header error
> >
> > ** (process:2862): WARNING **: Unsupported trace version : 2.6
> >
> > ** (process:2862): WARNING **: parse_trace_header error
> >
> > ** (process:2862): WARNING **: Unsupported trace version : 2.6
> >
> > ** (process:2862): WARNING **: parse_trace_header error
> >
> > ** (process:2862): WARNING **: Unsupported trace version : 2.6
> >
> > ** (process:2862): WARNING **: parse_trace_header error
> >
> > ** (process:2862): WARNING **: Trace
> > /home/root/.usttraces/x86-20100730144935535976601 has no metadata
> tracefile
> >
> > ** (process:2862): CRITICAL **: cannot open trace
> > /home/root/.usttraces/x86-20100730144935535976601
> > Segmentation fault
> > root at x86-generic:~#
> >
> > DMESG OUTPUT:
> >
> > root at x86-generic:~# dmesg | grep -ir ltt
> > [ 5108.713591] lttv.real[2862]: segfault at 40 ip b7732222 sp bfd88e90
> error
> > 4 in libglib-2.0.so.0.2200.1[b7714000+b6000]
> > root at x86-generic:~#
> >
> > What is mismatching section/package that is causing this segfault ?
> >
> > Your inputs are appreciated !! Thanks !!
>
> When you look inside
> /home/root/.usttraces/x86-generic-20100730144935535976601, you can see
> a directory named 2851_5499711528108666788. This is the directory
> containing the actual trace files, and it is this path that you must
> pass to lttv with the -t switch.
>
> >
> >
> > Regards,
> > Srikanth Krishnakar
> > **********************
> >
> > _______________________________________________
> > ltt-dev mailing list
> > ltt-dev at lists.casi.polymtl.ca
> > http://lists.casi.polymtl.ca/cgi-bin/mailman/listinfo/ltt-dev
> >
> >
>
> Regards,
>
> --
> Alexis Hallé
> Software Engineering student
> Intern, Tracing & Monitoring Project
> École Polytechnique de Montréal
>
--
"The Good You Do, The Best You GET"
Regards
Srikanth Krishnakar
**********************
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.casi.polymtl.ca/pipermail/lttng-dev/attachments/20100730/3accccaf/attachment-0003.htm>
-------------- next part --------------
2873 execve("/usr/bin/usttrace", ["/usr/bin/usttrace", "./usttest"], [/* 16 vars */]) = 0
2873 brk(0) = 0x9164000
2873 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7893000
2873 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
2873 open("/etc/ld.so.cache", O_RDONLY) = -1 ENOENT (No such file or directory)
2873 open("/lib/tls/i686/sse2/libncurses.so.5", O_RDONLY) = -1 ENOENT (No such file or directory)
2873 stat64("/lib/tls/i686/sse2", 0xbfca9a58) = -1 ENOENT (No such file or directory)
2873 open("/lib/tls/i686/libncurses.so.5", O_RDONLY) = -1 ENOENT (No such file or directory)
2873 stat64("/lib/tls/i686", 0xbfca9a58) = -1 ENOENT (No such file or directory)
2873 open("/lib/tls/sse2/libncurses.so.5", O_RDONLY) = -1 ENOENT (No such file or directory)
2873 stat64("/lib/tls/sse2", 0xbfca9a58) = -1 ENOENT (No such file or directory)
2873 open("/lib/tls/libncurses.so.5", O_RDONLY) = -1 ENOENT (No such file or directory)
2873 stat64("/lib/tls", 0xbfca9a58) = -1 ENOENT (No such file or directory)
2873 open("/lib/i686/sse2/libncurses.so.5", O_RDONLY) = -1 ENOENT (No such file or directory)
2873 stat64("/lib/i686/sse2", 0xbfca9a58) = -1 ENOENT (No such file or directory)
2873 open("/lib/i686/libncurses.so.5", O_RDONLY) = -1 ENOENT (No such file or directory)
2873 stat64("/lib/i686", 0xbfca9a58) = -1 ENOENT (No such file or directory)
2873 open("/lib/sse2/libncurses.so.5", O_RDONLY) = -1 ENOENT (No such file or directory)
2873 stat64("/lib/sse2", 0xbfca9a58) = -1 ENOENT (No such file or directory)
2873 open("/lib/libncurses.so.5", O_RDONLY) = -1 ENOENT (No such file or directory)
2873 stat64("/lib", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
2873 open("/usr/lib/tls/i686/sse2/libncurses.so.5", O_RDONLY) = -1 ENOENT (No such file or directory)
2873 stat64("/usr/lib/tls/i686/sse2", 0xbfca9a58) = -1 ENOENT (No such file or directory)
2873 open("/usr/lib/tls/i686/libncurses.so.5", O_RDONLY) = -1 ENOENT (No such file or directory)
2873 stat64("/usr/lib/tls/i686", 0xbfca9a58) = -1 ENOENT (No such file or directory)
2873 open("/usr/lib/tls/sse2/libncurses.so.5", O_RDONLY) = -1 ENOENT (No such file or directory)
2873 stat64("/usr/lib/tls/sse2", 0xbfca9a58) = -1 ENOENT (No such file or directory)
2873 open("/usr/lib/tls/libncurses.so.5", O_RDONLY) = -1 ENOENT (No such file or directory)
2873 stat64("/usr/lib/tls", 0xbfca9a58) = -1 ENOENT (No such file or directory)
2873 open("/usr/lib/i686/sse2/libncurses.so.5", O_RDONLY) = -1 ENOENT (No such file or directory)
2873 stat64("/usr/lib/i686/sse2", 0xbfca9a58) = -1 ENOENT (No such file or directory)
2873 open("/usr/lib/i686/libncurses.so.5", O_RDONLY) = -1 ENOENT (No such file or directory)
2873 stat64("/usr/lib/i686", 0xbfca9a58) = -1 ENOENT (No such file or directory)
2873 open("/usr/lib/sse2/libncurses.so.5", O_RDONLY) = -1 ENOENT (No such file or directory)
2873 stat64("/usr/lib/sse2", 0xbfca9a58) = -1 ENOENT (No such file or directory)
2873 open("/usr/lib/libncurses.so.5", O_RDONLY) = 3
2873 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\200\250"..., 512) = 512
2873 fstat64(3, {st_mode=S_IFREG|0755, st_size=1190110, ...}) = 0
2873 old_mmap(NULL, 264260, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7852000
2873 old_mmap(0xb7890000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x3e000) = 0xb7890000
2873 close(3) = 0
2873 open("/lib/libdl.so.2", O_RDONLY) = 3
2873 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0 \n\0\000"..., 512) = 512
2873 fstat64(3, {st_mode=S_IFREG|0755, st_size=103495, ...}) = 0
2873 old_mmap(NULL, 12408, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb784e000
2873 old_mmap(0xb7850000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1000) = 0xb7850000
2873 close(3) = 0
2873 open("/lib/libc.so.6", O_RDONLY) = 3
2873 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\360m\1"..., 512) = 512
2873 fstat64(3, {st_mode=S_IFREG|0755, st_size=7907670, ...}) = 0
2873 old_mmap(NULL, 1362312, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7701000
2873 old_mmap(0xb7848000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x147000) = 0xb7848000
2873 old_mmap(0xb784b000, 10632, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb784b000
2873 close(3) = 0
2873 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7700000
2873 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb76ff000
2873 set_thread_area({entry_number:-1 -> 6, base_addr:0xb7700b30, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}) = 0
2873 mprotect(0xb7848000, 8192, PROT_READ) = 0
2873 mprotect(0xb7850000, 4096, PROT_READ) = 0
2873 mprotect(0xb78b3000, 4096, PROT_READ) = 0
2873 open("/dev/tty", O_RDWR|O_NONBLOCK|O_LARGEFILE) = 3
2873 close(3) = 0
2873 brk(0) = 0x9164000
2873 brk(0x9165000) = 0x9165000
2873 brk(0x9166000) = 0x9166000
2873 getuid32() = 0
2873 getgid32() = 0
2873 geteuid32() = 0
2873 getegid32() = 0
2873 time(NULL) = 1280502269
2873 brk(0x9167000) = 0x9167000
2873 brk(0x9168000) = 0x9168000
2873 open("/proc/meminfo", O_RDONLY) = 3
2873 fstat64(3, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
2873 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb76fe000
2873 read(3, "MemTotal: 1024376 kB\nMemF"..., 1024) = 1024
2873 close(3) = 0
2873 munmap(0xb76fe000, 4096) = 0
2873 brk(0x9169000) = 0x9169000
2873 rt_sigaction(SIGCHLD, {SIG_DFL}, {SIG_DFL}, 8) = 0
2873 rt_sigaction(SIGCHLD, {SIG_DFL}, {SIG_DFL}, 8) = 0
2873 rt_sigaction(SIGINT, {SIG_DFL}, {SIG_DFL}, 8) = 0
2873 rt_sigaction(SIGINT, {SIG_DFL}, {SIG_DFL}, 8) = 0
2873 rt_sigaction(SIGQUIT, {SIG_DFL}, {SIG_DFL}, 8) = 0
2873 rt_sigaction(SIGQUIT, {SIG_DFL}, {SIG_DFL}, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0
2873 rt_sigaction(SIGQUIT, {SIG_IGN}, {SIG_DFL}, 8) = 0
2873 uname({sys="Linux", node="x86-generic", ...}) = 0
2873 stat64("/home/root/ust-test", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
2873 stat64(".", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
2873 getpid() = 2873
2873 getppid() = 2872
2873 getpgrp() = 2872
2873 rt_sigaction(SIGCHLD, {0x807d490, [], 0}, {SIG_DFL}, 8) = 0
2873 getrlimit(RLIMIT_NPROC, {rlim_cur=7999, rlim_max=7999}) = 0
2873 brk(0x916a000) = 0x916a000
2873 open("/usr/bin/usttrace", O_RDONLY|O_LARGEFILE) = 3
2873 ioctl(3, SNDCTL_TMR_TIMEBASE or TCGETS, 0xbfca9e7c) = -1 ENOTTY (Inappropriate ioctl for device)
2873 _llseek(3, 0, [0], SEEK_CUR) = 0
2873 read(3, "#!/bin/bash\n\n# usttrace by Pier"..., 80) = 80
2873 _llseek(3, 0, [0], SEEK_SET) = 0
2873 getrlimit(RLIMIT_NOFILE, {rlim_cur=1024, rlim_max=1024}) = 0
2873 dup2(3, 255) = 255
2873 close(3) = 0
2873 fcntl64(255, F_SETFD, FD_CLOEXEC) = 0
2873 fcntl64(255, F_GETFL) = 0x8000 (flags O_RDONLY|O_LARGEFILE)
2873 fstat64(255, {st_mode=S_IFREG|0755, st_size=4496, ...}) = 0
2873 _llseek(255, 0, [0], SEEK_CUR) = 0
2873 brk(0x916c000) = 0x916c000
2873 brk(0x916d000) = 0x916d000
2873 read(255, "#!/bin/bash\n\n# usttrace by Pier"..., 4496) = 4496
2873 brk(0x916e000) = 0x916e000
2873 brk(0x916f000) = 0x916f000
2873 pipe([3, 4]) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, [INT CHLD], [], 8) = 0
2873 _llseek(255, -4073, [423], SEEK_CUR) = 0
2873 clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0xb7700b98) = 2874
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigaction(SIGCHLD, {0x807d490, [], 0}, <unfinished ...>
2874 close(255 <unfinished ...>
2873 <... rt_sigaction resumed> {0x807d490, [], 0}, 8) = 0
2874 <... close resumed> ) = 0
2873 close(4 <unfinished ...>
2874 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2873 <... close resumed> ) = 0
2874 <... rt_sigprocmask resumed> NULL, 8) = 0
2873 read(3, <unfinished ...>
2874 rt_sigaction(SIGTSTP, {SIG_DFL}, {SIG_DFL}, 8) = 0
2874 rt_sigaction(SIGTTIN, {SIG_DFL}, {SIG_DFL}, 8) = 0
2874 rt_sigaction(SIGTTOU, {SIG_DFL}, {SIG_DFL}, 8) = 0
2874 rt_sigaction(SIGINT, {SIG_DFL}, {SIG_DFL}, 8) = 0
2874 rt_sigaction(SIGQUIT, {SIG_DFL}, {SIG_IGN}, 8) = 0
2874 rt_sigaction(SIGCHLD, {SIG_DFL}, {0x807d490, [], 0}, 8) = 0
2874 rt_sigaction(SIGCHLD, {0x807d490, [], 0}, {SIG_DFL}, 8) = 0
2874 rt_sigaction(SIGINT, {0x80900e0, [], 0}, {SIG_DFL}, 8) = 0
2874 dup2(4, 1) = 1
2874 close(4) = 0
2874 close(3) = 0
2874 stat64(".", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
2874 stat64("/usr/local/bin/dirname", 0xbfca9780) = -1 ENOENT (No such file or directory)
2874 stat64("/usr/bin/dirname", {st_mode=S_IFREG|0755, st_size=14368, ...}) = 0
2874 stat64("/usr/bin/dirname", {st_mode=S_IFREG|0755, st_size=14368, ...}) = 0
2874 rt_sigaction(SIGINT, {SIG_DFL}, {0x80900e0, [], 0}, 8) = 0
2874 rt_sigaction(SIGQUIT, {SIG_DFL}, {SIG_DFL}, 8) = 0
2874 rt_sigaction(SIGCHLD, {SIG_DFL}, {0x807d490, [], 0}, 8) = 0
2874 execve("/usr/bin/dirname", ["dirname", "/usr/bin/usttrace"], [/* 16 vars */]) = 0
2874 brk(0) = 0x9a88000
2874 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb77f8000
2874 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
2874 open("/etc/ld.so.cache", O_RDONLY) = -1 ENOENT (No such file or directory)
2874 open("/lib/tls/i686/sse2/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2874 stat64("/lib/tls/i686/sse2", 0xbff509e8) = -1 ENOENT (No such file or directory)
2874 open("/lib/tls/i686/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2874 stat64("/lib/tls/i686", 0xbff509e8) = -1 ENOENT (No such file or directory)
2874 open("/lib/tls/sse2/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2874 stat64("/lib/tls/sse2", 0xbff509e8) = -1 ENOENT (No such file or directory)
2874 open("/lib/tls/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2874 stat64("/lib/tls", 0xbff509e8) = -1 ENOENT (No such file or directory)
2874 open("/lib/i686/sse2/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2874 stat64("/lib/i686/sse2", 0xbff509e8) = -1 ENOENT (No such file or directory)
2874 open("/lib/i686/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2874 stat64("/lib/i686", 0xbff509e8) = -1 ENOENT (No such file or directory)
2874 open("/lib/sse2/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2874 stat64("/lib/sse2", 0xbff509e8) = -1 ENOENT (No such file or directory)
2874 open("/lib/libc.so.6", O_RDONLY) = 3
2874 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\360m\1"..., 512) = 512
2874 fstat64(3, {st_mode=S_IFREG|0755, st_size=7907670, ...}) = 0
2874 old_mmap(NULL, 1362312, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb76ab000
2874 old_mmap(0xb77f2000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x147000) = 0xb77f2000
2874 old_mmap(0xb77f5000, 10632, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb77f5000
2874 close(3) = 0
2874 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb76aa000
2874 set_thread_area({entry_number:-1 -> 6, base_addr:0xb76aa6c0, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}) = 0
2874 mprotect(0xb77f2000, 8192, PROT_READ) = 0
2874 mprotect(0xb7818000, 4096, PROT_READ) = 0
2874 brk(0) = 0x9a88000
2874 brk(0x9aa9000) = 0x9aa9000
2874 fstat64(1, {st_mode=S_IFIFO|0600, st_size=0, ...}) = 0
2874 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb76a9000
2874 write(1, "/usr/bin\n", 9) = 9
2873 <... read resumed> "/usr/bin\n", 128) = 9
2874 close(1 <unfinished ...>
2873 read(3, <unfinished ...>
2874 <... close resumed> ) = 0
2873 <... read resumed> "", 128) = 0
2874 munmap(0xb76a9000, 4096 <unfinished ...>
2873 close(3 <unfinished ...>
2874 <... munmap resumed> ) = 0
2874 exit_group(0) = ?
2873 <... close resumed> ) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0
2873 rt_sigaction(SIGINT, {0x807b370, [], 0}, {SIG_DFL}, 8) = 0
2873 waitpid(-1, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0) = 2874
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 --- SIGCHLD (Child exited) @ 0 (0) ---
2873 waitpid(-1, 0xbfca956c, WNOHANG) = -1 ECHILD (No child processes)
2873 sigreturn() = ? (mask now [])
2873 rt_sigaction(SIGINT, {SIG_DFL}, {0x807b370, [], 0}, 8) = 0
2873 read(255, "if [ -x \"${USTTRACE_DIR}/ustd/us"..., 4496) = 4073
2873 stat64("/usr/bin/ustd/ustd", 0xbfca9bac) = -1 ENOTDIR (Not a directory)
2873 pipe([3, 4]) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, [INT CHLD], [], 8) = 0
2873 _llseek(255, -3380, [1116], SEEK_CUR) = 0
2873 clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0xb7700b98) = 2875
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigaction(SIGCHLD, {0x807d490, [], 0}, <unfinished ...>
2875 close(255 <unfinished ...>
2873 <... rt_sigaction resumed> {0x807d490, [], 0}, 8) = 0
2875 <... close resumed> ) = 0
2873 close(4 <unfinished ...>
2875 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2873 <... close resumed> ) = 0
2875 <... rt_sigprocmask resumed> NULL, 8) = 0
2873 read(3, <unfinished ...>
2875 rt_sigaction(SIGTSTP, {SIG_DFL}, {SIG_DFL}, 8) = 0
2875 rt_sigaction(SIGTTIN, {SIG_DFL}, {SIG_DFL}, 8) = 0
2875 rt_sigaction(SIGTTOU, {SIG_DFL}, {SIG_DFL}, 8) = 0
2875 rt_sigaction(SIGINT, {SIG_DFL}, {SIG_DFL}, 8) = 0
2875 rt_sigaction(SIGQUIT, {SIG_DFL}, {SIG_IGN}, 8) = 0
2875 rt_sigaction(SIGCHLD, {SIG_DFL}, {0x807d490, [], 0}, 8) = 0
2875 rt_sigaction(SIGCHLD, {0x807d490, [], 0}, {SIG_DFL}, 8) = 0
2875 rt_sigaction(SIGINT, {0x80900e0, [], 0}, {SIG_DFL}, 8) = 0
2875 dup2(4, 1) = 1
2875 close(4) = 0
2875 close(3) = 0
2875 stat64(".", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
2875 stat64("/usr/local/bin/which", 0xbfca9490) = -1 ENOENT (No such file or directory)
2875 stat64("/usr/bin/which", {st_mode=S_IFREG|0755, st_size=946, ...}) = 0
2875 stat64("/usr/bin/which", {st_mode=S_IFREG|0755, st_size=946, ...}) = 0
2875 rt_sigprocmask(SIG_BLOCK, [INT CHLD], [], 8) = 0
2875 clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0xb7700b98) = 2876
2876 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2875 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2876 <... rt_sigprocmask resumed> NULL, 8) = 0
2875 <... rt_sigprocmask resumed> NULL, 8) = 0
2876 rt_sigaction(SIGTSTP, {SIG_DFL}, <unfinished ...>
2875 rt_sigprocmask(SIG_BLOCK, [CHLD], <unfinished ...>
2876 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2875 <... rt_sigprocmask resumed> [], 8) = 0
2876 rt_sigaction(SIGTTIN, {SIG_DFL}, {SIG_DFL}, 8) = 0
2875 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2876 rt_sigaction(SIGTTOU, {SIG_DFL}, <unfinished ...>
2875 <... rt_sigprocmask resumed> NULL, 8) = 0
2876 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2876 rt_sigaction(SIGINT, {SIG_DFL}, <unfinished ...>
2875 rt_sigprocmask(SIG_BLOCK, [CHLD], <unfinished ...>
2876 <... rt_sigaction resumed> {0x80900e0, [], 0}, 8) = 0
2875 <... rt_sigprocmask resumed> [], 8) = 0
2876 rt_sigaction(SIGQUIT, {SIG_DFL}, <unfinished ...>
2875 rt_sigaction(SIGINT, {0x807b370, [], 0}, <unfinished ...>
2876 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2876 rt_sigaction(SIGCHLD, {SIG_DFL}, <unfinished ...>
2875 <... rt_sigaction resumed> {0x80900e0, [], 0}, 8) = 0
2876 <... rt_sigaction resumed> {0x807d490, [], 0}, 8) = 0
2875 waitpid(-1, <unfinished ...>
2876 open("/dev/null", O_WRONLY|O_CREAT|O_TRUNC|O_LARGEFILE, 0666) = 3
2876 dup2(3, 2) = 2
2876 close(3) = 0
2876 execve("/usr/bin/which", ["which", "ustd"], [/* 16 vars */]) = 0
2876 brk(0) = 0x9f85000
2876 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb776d000
2876 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
2876 open("/etc/ld.so.cache", O_RDONLY) = -1 ENOENT (No such file or directory)
2876 open("/lib/tls/i686/sse2/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2876 stat64("/lib/tls/i686/sse2", 0xbfe54858) = -1 ENOENT (No such file or directory)
2876 open("/lib/tls/i686/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2876 stat64("/lib/tls/i686", 0xbfe54858) = -1 ENOENT (No such file or directory)
2876 open("/lib/tls/sse2/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2876 stat64("/lib/tls/sse2", 0xbfe54858) = -1 ENOENT (No such file or directory)
2876 open("/lib/tls/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2876 stat64("/lib/tls", 0xbfe54858) = -1 ENOENT (No such file or directory)
2876 open("/lib/i686/sse2/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2876 stat64("/lib/i686/sse2", 0xbfe54858) = -1 ENOENT (No such file or directory)
2876 open("/lib/i686/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2876 stat64("/lib/i686", 0xbfe54858) = -1 ENOENT (No such file or directory)
2876 open("/lib/sse2/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2876 stat64("/lib/sse2", 0xbfe54858) = -1 ENOENT (No such file or directory)
2876 open("/lib/libm.so.6", O_RDONLY) = 3
2876 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0 at 4\0\000"..., 512) = 512
2876 fstat64(3, {st_mode=S_IFREG|0755, st_size=610631, ...}) = 0
2876 old_mmap(NULL, 155716, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7746000
2876 old_mmap(0xb776b000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x24000) = 0xb776b000
2876 close(3) = 0
2876 open("/lib/libc.so.6", O_RDONLY) = 3
2876 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\360m\1"..., 512) = 512
2876 fstat64(3, {st_mode=S_IFREG|0755, st_size=7907670, ...}) = 0
2876 old_mmap(NULL, 1362312, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb75f9000
2876 old_mmap(0xb7740000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x147000) = 0xb7740000
2876 old_mmap(0xb7743000, 10632, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb7743000
2876 close(3) = 0
2876 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb75f8000
2876 set_thread_area({entry_number:-1 -> 6, base_addr:0xb75f86c0, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}) = 0
2876 mprotect(0xb7740000, 8192, PROT_READ) = 0
2876 mprotect(0xb776b000, 4096, PROT_READ) = 0
2876 mprotect(0xb778d000, 4096, PROT_READ) = 0
2876 getpid() = 2876
2876 getuid32() = 0
2876 brk(0) = 0x9f85000
2876 brk(0x9fa6000) = 0x9fa6000
2876 rt_sigaction(SIGCHLD, {SIG_DFL}, {SIG_DFL}, 8) = 0
2876 getppid() = 2875
2876 stat64("/home/root/ust-test", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
2876 stat64(".", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
2876 open("/usr/bin/which", O_RDONLY|O_LARGEFILE) = 3
2876 fcntl64(3, F_DUPFD, 10) = 10
2876 close(3) = 0
2876 fcntl64(10, F_SETFD, FD_CLOEXEC) = 0
2876 rt_sigaction(SIGINT, NULL, {SIG_DFL}, 8) = 0
2876 rt_sigaction(SIGINT, {0x80ae853, ~[RTMIN RT_1], 0}, NULL, 8) = 0
2876 rt_sigaction(SIGQUIT, NULL, {SIG_DFL}, 8) = 0
2876 rt_sigaction(SIGQUIT, {SIG_DFL}, NULL, 8) = 0
2876 rt_sigaction(SIGTERM, NULL, {SIG_DFL}, 8) = 0
2876 rt_sigaction(SIGTERM, {SIG_DFL}, NULL, 8) = 0
2876 read(10, "#! /bin/sh\nset -ef\n\nif test -n \""..., 8191) = 946
2876 waitpid(-1, 0xbfe54a0c, WNOHANG) = -1 ECHILD (No child processes)
2876 waitpid(-1, 0xbfe5492c, WNOHANG) = -1 ECHILD (No child processes)
2876 waitpid(-1, 0xbfe54a0c, WNOHANG) = -1 ECHILD (No child processes)
2876 waitpid(-1, 0xbfe548fc, WNOHANG) = -1 ECHILD (No child processes)
2876 waitpid(-1, 0xbfe54a0c, WNOHANG) = -1 ECHILD (No child processes)
2876 waitpid(-1, 0xbfe5492c, WNOHANG) = -1 ECHILD (No child processes)
2876 waitpid(-1, 0xbfe5492c, WNOHANG) = -1 ECHILD (No child processes)
2876 waitpid(-1, 0xbfe5447c, WNOHANG) = -1 ECHILD (No child processes)
2876 waitpid(-1, 0xbfe5447c, WNOHANG) = -1 ECHILD (No child processes)
2876 waitpid(-1, 0xbfe5455c, WNOHANG) = -1 ECHILD (No child processes)
2876 waitpid(-1, 0xbfe541fc, WNOHANG) = -1 ECHILD (No child processes)
2876 waitpid(-1, 0xbfe5411c, WNOHANG) = -1 ECHILD (No child processes)
2876 stat64("/usr/local/bin/ustd", 0xbfe53f90) = -1 ENOENT (No such file or directory)
2876 waitpid(-1, 0xbfe541fc, WNOHANG) = -1 ECHILD (No child processes)
2876 waitpid(-1, 0xbfe5411c, WNOHANG) = -1 ECHILD (No child processes)
2876 stat64("/usr/bin/ustd", {st_mode=S_IFREG|0755, st_size=181265, ...}) = 0
2876 waitpid(-1, 0xbfe5411c, WNOHANG) = -1 ECHILD (No child processes)
2876 stat64("/usr/bin/ustd", {st_mode=S_IFREG|0755, st_size=181265, ...}) = 0
2876 geteuid32() = 0
2876 stat64("/usr/bin/ustd", {st_mode=S_IFREG|0755, st_size=181265, ...}) = 0
2876 waitpid(-1, 0xbfe5403c, WNOHANG) = -1 ECHILD (No child processes)
2876 waitpid(-1, 0xbfe53dcc, WNOHANG) = -1 ECHILD (No child processes)
2876 fcntl64(1, F_GETFL) = 0x1 (flags O_WRONLY)
2876 fstat64(1, {st_mode=S_IFIFO|0600, st_size=0, ...}) = 0
2876 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb75f7000
2876 write(1, "/usr/bin/ustd\n", 14) = 14
2873 <... read resumed> "/usr/bin/ustd\n", 128) = 14
2876 waitpid(-1, <unfinished ...>
2873 read(3, <unfinished ...>
2876 <... waitpid resumed> 0xbfe5403c, WNOHANG) = -1 ECHILD (No child processes)
2876 waitpid(-1, 0xbfe5403c, WNOHANG) = -1 ECHILD (No child processes)
2876 waitpid(-1, 0xbfe5403c, WNOHANG) = -1 ECHILD (No child processes)
2876 waitpid(-1, 0xbfe5471c, WNOHANG) = -1 ECHILD (No child processes)
2876 waitpid(-1, 0xbfe5471c, WNOHANG) = -1 ECHILD (No child processes)
2876 waitpid(-1, 0xbfe54a0c, WNOHANG) = -1 ECHILD (No child processes)
2876 exit_group(0) = ?
2875 <... waitpid resumed> [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0) = 2876
2875 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2875 --- SIGCHLD (Child exited) @ 0 (0) ---
2875 waitpid(-1, 0xbfca919c, WNOHANG) = -1 ECHILD (No child processes)
2875 sigreturn() = ? (mask now [])
2875 rt_sigaction(SIGINT, {0x80900e0, [], 0}, {0x807b370, [], 0}, 8) = 0
2875 exit_group(0) = ?
2873 <... read resumed> "", 128) = 0
2873 --- SIGCHLD (Child exited) @ 0 (0) ---
2873 waitpid(-1, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG) = 2875
2873 waitpid(-1, 0xbfca941c, WNOHANG) = -1 ECHILD (No child processes)
2873 sigreturn() = ? (mask now [])
2873 close(3) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0
2873 rt_sigaction(SIGINT, {0x807b370, [], 0}, {SIG_DFL}, 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigaction(SIGINT, {SIG_DFL}, {0x807b370, [], 0}, 8) = 0
2873 stat64("/usr/bin/ustd", {st_mode=S_IFREG|0755, st_size=181265, ...}) = 0
2873 geteuid32() = 0
2873 getegid32() = 0
2873 getuid32() = 0
2873 getgid32() = 0
2873 access("/usr/bin/ustd", X_OK) = 0
2873 read(255, "\nBASE_TRACE_DIR=\"${HOME}/.usttra"..., 4496) = 3380
2873 brk(0x9170000) = 0x9170000
2873 brk(0x9171000) = 0x9171000
2873 pipe([3, 4]) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, [INT CHLD], [], 8) = 0
2873 _llseek(255, -1894, [2602], SEEK_CUR) = 0
2873 clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0xb7700b98) = 2877
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2877 close(255 <unfinished ...>
2873 rt_sigaction(SIGCHLD, {0x807d490, [], 0}, <unfinished ...>
2877 <... close resumed> ) = 0
2873 <... rt_sigaction resumed> {0x807d490, [], 0}, 8) = 0
2877 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2873 close(4 <unfinished ...>
2877 <... rt_sigprocmask resumed> NULL, 8) = 0
2873 <... close resumed> ) = 0
2877 rt_sigaction(SIGTSTP, {SIG_DFL}, <unfinished ...>
2873 read(3, <unfinished ...>
2877 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2877 rt_sigaction(SIGTTIN, {SIG_DFL}, {SIG_DFL}, 8) = 0
2877 rt_sigaction(SIGTTOU, {SIG_DFL}, {SIG_DFL}, 8) = 0
2877 rt_sigaction(SIGINT, {SIG_DFL}, {SIG_DFL}, 8) = 0
2877 rt_sigaction(SIGQUIT, {SIG_DFL}, {SIG_IGN}, 8) = 0
2877 rt_sigaction(SIGCHLD, {SIG_DFL}, {0x807d490, [], 0}, 8) = 0
2877 rt_sigaction(SIGCHLD, {0x807d490, [], 0}, {SIG_DFL}, 8) = 0
2877 rt_sigaction(SIGINT, {0x80900e0, [], 0}, {SIG_DFL}, 8) = 0
2877 dup2(4, 1) = 1
2877 close(4) = 0
2877 close(3) = 0
2877 stat64(".", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
2877 stat64("/usr/local/bin/hostname", 0xbfca9780) = -1 ENOENT (No such file or directory)
2877 stat64("/usr/bin/hostname", 0xbfca9780) = -1 ENOENT (No such file or directory)
2877 stat64("/bin/hostname", {st_mode=S_IFREG|0755, st_size=9128, ...}) = 0
2877 stat64("/bin/hostname", {st_mode=S_IFREG|0755, st_size=9128, ...}) = 0
2877 rt_sigaction(SIGINT, {SIG_DFL}, {0x80900e0, [], 0}, 8) = 0
2877 rt_sigaction(SIGQUIT, {SIG_DFL}, {SIG_DFL}, 8) = 0
2877 rt_sigaction(SIGCHLD, {SIG_DFL}, {0x807d490, [], 0}, 8) = 0
2877 execve("/bin/hostname", ["hostname"], [/* 16 vars */]) = 0
2877 brk(0) = 0x9bbb000
2877 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7729000
2877 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
2877 open("/etc/ld.so.cache", O_RDONLY) = -1 ENOENT (No such file or directory)
2877 open("/lib/tls/i686/sse2/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2877 stat64("/lib/tls/i686/sse2", 0xbfe78a88) = -1 ENOENT (No such file or directory)
2877 open("/lib/tls/i686/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2877 stat64("/lib/tls/i686", 0xbfe78a88) = -1 ENOENT (No such file or directory)
2877 open("/lib/tls/sse2/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2877 stat64("/lib/tls/sse2", 0xbfe78a88) = -1 ENOENT (No such file or directory)
2877 open("/lib/tls/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2877 stat64("/lib/tls", 0xbfe78a88) = -1 ENOENT (No such file or directory)
2877 open("/lib/i686/sse2/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2877 stat64("/lib/i686/sse2", 0xbfe78a88) = -1 ENOENT (No such file or directory)
2877 open("/lib/i686/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2877 stat64("/lib/i686", 0xbfe78a88) = -1 ENOENT (No such file or directory)
2877 open("/lib/sse2/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2877 stat64("/lib/sse2", 0xbfe78a88) = -1 ENOENT (No such file or directory)
2877 open("/lib/libc.so.6", O_RDONLY) = 3
2877 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\360m\1"..., 512) = 512
2877 fstat64(3, {st_mode=S_IFREG|0755, st_size=7907670, ...}) = 0
2877 old_mmap(NULL, 1362312, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb75dc000
2877 old_mmap(0xb7723000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x147000) = 0xb7723000
2877 old_mmap(0xb7726000, 10632, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb7726000
2877 close(3) = 0
2877 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb75db000
2877 set_thread_area({entry_number:-1 -> 6, base_addr:0xb75db6c0, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}) = 0
2877 mprotect(0xb7723000, 8192, PROT_READ) = 0
2877 mprotect(0xb7749000, 4096, PROT_READ) = 0
2877 uname({sys="Linux", node="x86-generic", ...}) = 0
2877 fstat64(1, {st_mode=S_IFIFO|0600, st_size=0, ...}) = 0
2877 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb75da000
2877 write(1, "x86-generic\n", 12) = 12
2877 exit_group(0) = ?
2873 <... read resumed> "x86-generic\n", 128) = 12
2873 --- SIGCHLD (Child exited) @ 0 (0) ---
2873 waitpid(-1, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG) = 2877
2873 waitpid(-1, 0xbfca970c, WNOHANG) = -1 ECHILD (No child processes)
2873 sigreturn() = ? (mask now [])
2873 read(3, "", 128) = 0
2873 close(3) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0
2873 rt_sigaction(SIGINT, {0x807b370, [], 0}, {SIG_DFL}, 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigaction(SIGINT, {SIG_DFL}, {0x807b370, [], 0}, 8) = 0
2873 pipe([3, 4]) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, [INT CHLD], [], 8) = 0
2873 clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0xb7700b98) = 2878
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigaction(SIGCHLD, {0x807d490, [], 0}, <unfinished ...>
2878 close(255 <unfinished ...>
2873 <... rt_sigaction resumed> {0x807d490, [], 0}, 8) = 0
2878 <... close resumed> ) = 0
2873 close(4 <unfinished ...>
2878 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2873 <... close resumed> ) = 0
2878 <... rt_sigprocmask resumed> NULL, 8) = 0
2873 read(3, <unfinished ...>
2878 rt_sigaction(SIGTSTP, {SIG_DFL}, {SIG_DFL}, 8) = 0
2878 rt_sigaction(SIGTTIN, {SIG_DFL}, {SIG_DFL}, 8) = 0
2878 rt_sigaction(SIGTTOU, {SIG_DFL}, {SIG_DFL}, 8) = 0
2878 rt_sigaction(SIGINT, {SIG_DFL}, {SIG_DFL}, 8) = 0
2878 rt_sigaction(SIGQUIT, {SIG_DFL}, {SIG_IGN}, 8) = 0
2878 rt_sigaction(SIGCHLD, {SIG_DFL}, {0x807d490, [], 0}, 8) = 0
2878 rt_sigaction(SIGCHLD, {0x807d490, [], 0}, {SIG_DFL}, 8) = 0
2878 rt_sigaction(SIGINT, {0x80900e0, [], 0}, {SIG_DFL}, 8) = 0
2878 dup2(4, 1) = 1
2878 close(4) = 0
2878 close(3) = 0
2878 stat64(".", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
2878 stat64("/usr/local/bin/date", 0xbfca9780) = -1 ENOENT (No such file or directory)
2878 stat64("/usr/bin/date", 0xbfca9780) = -1 ENOENT (No such file or directory)
2878 stat64("/bin/date", {st_mode=S_IFREG|0755, st_size=48812, ...}) = 0
2878 stat64("/bin/date", {st_mode=S_IFREG|0755, st_size=48812, ...}) = 0
2878 rt_sigaction(SIGINT, {SIG_DFL}, {0x80900e0, [], 0}, 8) = 0
2878 rt_sigaction(SIGQUIT, {SIG_DFL}, {SIG_DFL}, 8) = 0
2878 rt_sigaction(SIGCHLD, {SIG_DFL}, {0x807d490, [], 0}, 8) = 0
2878 execve("/bin/date", ["date", "+%Y%m%d%H%M%S%N"], [/* 16 vars */]) = 0
2878 brk(0) = 0x828f000
2878 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb77e1000
2878 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
2878 open("/etc/ld.so.cache", O_RDONLY) = -1 ENOENT (No such file or directory)
2878 open("/lib/tls/i686/sse2/librt.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
2878 stat64("/lib/tls/i686/sse2", 0xbf9145e8) = -1 ENOENT (No such file or directory)
2878 open("/lib/tls/i686/librt.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
2878 stat64("/lib/tls/i686", 0xbf9145e8) = -1 ENOENT (No such file or directory)
2878 open("/lib/tls/sse2/librt.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
2878 stat64("/lib/tls/sse2", 0xbf9145e8) = -1 ENOENT (No such file or directory)
2878 open("/lib/tls/librt.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
2878 stat64("/lib/tls", 0xbf9145e8) = -1 ENOENT (No such file or directory)
2878 open("/lib/i686/sse2/librt.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
2878 stat64("/lib/i686/sse2", 0xbf9145e8) = -1 ENOENT (No such file or directory)
2878 open("/lib/i686/librt.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
2878 stat64("/lib/i686", 0xbf9145e8) = -1 ENOENT (No such file or directory)
2878 open("/lib/sse2/librt.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
2878 stat64("/lib/sse2", 0xbf9145e8) = -1 ENOENT (No such file or directory)
2878 open("/lib/librt.so.1", O_RDONLY) = 3
2878 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\260\34"..., 512) = 512
2878 fstat64(3, {st_mode=S_IFREG|0755, st_size=217239, ...}) = 0
2878 old_mmap(NULL, 85784, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb77cc000
2878 old_mmap(0xb77d5000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x8000) = 0xb77d5000
2878 old_mmap(0xb77d7000, 40728, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb77d7000
2878 close(3) = 0
2878 open("/lib/libc.so.6", O_RDONLY) = 3
2878 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\360m\1"..., 512) = 512
2878 fstat64(3, {st_mode=S_IFREG|0755, st_size=7907670, ...}) = 0
2878 old_mmap(NULL, 1362312, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb767f000
2878 old_mmap(0xb77c6000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x147000) = 0xb77c6000
2878 old_mmap(0xb77c9000, 10632, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb77c9000
2878 close(3) = 0
2878 open("/lib/libpthread.so.0", O_RDONLY) = 3
2878 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\0J\0\000"..., 512) = 512
2878 fstat64(3, {st_mode=S_IFREG|0755, st_size=679667, ...}) = 0
2878 old_mmap(NULL, 98816, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7666000
2878 old_mmap(0xb767b000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x15000) = 0xb767b000
2878 old_mmap(0xb767d000, 4608, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb767d000
2878 close(3) = 0
2878 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7665000
2878 set_thread_area({entry_number:-1 -> 6, base_addr:0xb7665ad0, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}) = 0
2878 mprotect(0xb767b000, 4096, PROT_READ) = 0
2878 mprotect(0xb77c6000, 8192, PROT_READ) = 0
2878 mprotect(0xb77d5000, 4096, PROT_READ) = 0
2878 mprotect(0xb7801000, 4096, PROT_READ) = 0
2878 set_tid_address(0xb7665b38) = 2878
2878 sendto(-1218028736, 0xc, 3077029876, MSG_DONTROUTE|MSG_CTRUNC|MSG_PROXY|MSG_WAITALL|MSG_DONTWAIT|MSG_CONFIRM|MSG_FIN|MSG_RST|MSG_NOSIGNAL|0xb7660000, {sa_family=0x2938 /* AF_??? */, sa_data="\200\267\6\0\0\0\224*\200\267\0\0\0\0"}, 3076938448) = 0
2878 futex(0xbf914bc0, 0x81 /* FUTEX_??? */, 1) = 0
2878 futex(0xbf914bc0, 0x189 /* FUTEX_??? */, 1) = -1 EAGAIN (Resource temporarily unavailable)
2878 rt_sigaction(SIGRTMIN, {0xb766a400, [], SA_SIGINFO}, NULL, 8) = 0
2878 rt_sigaction(SIGRT_1, {0xb766a8f0, [], SA_RESTART|SA_SIGINFO}, NULL, 8) = 0
2878 rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0
2878 getrlimit(RLIMIT_STACK, {rlim_cur=8192*1024, rlim_max=RLIM_INFINITY}) = 0
2878 uname({sys="Linux", node="x86-generic", ...}) = 0
2878 brk(0) = 0x828f000
2878 brk(0x82b0000) = 0x82b0000
2878 clock_gettime(CLOCK_REALTIME, {1280502269, 647915588}) = 0
2878 open("/etc/localtime", O_RDONLY) = 3
2878 fstat64(3, {st_mode=S_IFREG|0644, st_size=255, ...}) = 0
2878 fstat64(3, {st_mode=S_IFREG|0644, st_size=255, ...}) = 0
2878 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7664000
2878 read(3, "TZif2\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\1\0\0\0\1\0\0"..., 4096) = 255
2878 _llseek(3, -53, [202], SEEK_CUR) = 0
2878 read(3, "\n<Local time zone must be set--s"..., 4096) = 53
2878 close(3) = 0
2878 munmap(0xb7664000, 4096) = 0
2878 fstat64(1, {st_mode=S_IFIFO|0600, st_size=0, ...}) = 0
2878 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7664000
2878 write(1, "20100730150429647915588\n", 24) = 24
2878 close(1) = 0
2878 munmap(0xb7664000, 4096) = 0
2878 exit_group(0) = ?
2873 <... read resumed> "20100730150429647915588\n", 128) = 24
2873 --- SIGCHLD (Child exited) @ 0 (0) ---
2873 waitpid(-1, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG) = 2878
2873 waitpid(-1, 0xbfca970c, WNOHANG) = -1 ECHILD (No child processes)
2873 sigreturn() = ? (mask now [])
2873 read(3, "", 128) = 0
2873 close(3) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0
2873 rt_sigaction(SIGINT, {0x807b370, [], 0}, {SIG_DFL}, 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigaction(SIGINT, {SIG_DFL}, {0x807b370, [], 0}, 8) = 0
2873 read(255, "OUTDIR=\"$BASE_TRACE_DIR/$DATESTR"..., 4496) = 1894
2873 stat64(".", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
2873 stat64("/usr/local/bin/mkdir", 0xbfca9d00) = -1 ENOENT (No such file or directory)
2873 stat64("/usr/bin/mkdir", 0xbfca9d00) = -1 ENOENT (No such file or directory)
2873 stat64("/bin/mkdir", {st_mode=S_IFREG|0755, st_size=18912, ...}) = 0
2873 stat64("/bin/mkdir", {st_mode=S_IFREG|0755, st_size=18912, ...}) = 0
2873 brk(0x9172000) = 0x9172000
2873 rt_sigprocmask(SIG_BLOCK, [INT CHLD], [], 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], [INT CHLD], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [INT CHLD], NULL, 8) = 0
2873 _llseek(255, -1838, [2658], SEEK_CUR) = 0
2873 clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0xb7700b98) = 2879
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2879 close(255 <unfinished ...>
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], <unfinished ...>
2879 <... close resumed> ) = 0
2873 <... rt_sigprocmask resumed> [], 8) = 0
2879 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2873 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2879 <... rt_sigprocmask resumed> NULL, 8) = 0
2873 <... rt_sigprocmask resumed> NULL, 8) = 0
2879 rt_sigaction(SIGTSTP, {SIG_DFL}, <unfinished ...>
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], <unfinished ...>
2879 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2873 <... rt_sigprocmask resumed> [], 8) = 0
2879 rt_sigaction(SIGTTIN, {SIG_DFL}, <unfinished ...>
2873 rt_sigaction(SIGINT, {0x807b370, [], 0}, <unfinished ...>
2879 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2873 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2879 rt_sigaction(SIGTTOU, {SIG_DFL}, <unfinished ...>
2873 waitpid(-1, <unfinished ...>
2879 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2879 rt_sigaction(SIGINT, {SIG_DFL}, {SIG_DFL}, 8) = 0
2879 rt_sigaction(SIGQUIT, {SIG_DFL}, {SIG_IGN}, 8) = 0
2879 rt_sigaction(SIGCHLD, {SIG_DFL}, {0x807d490, [], 0}, 8) = 0
2879 execve("/bin/mkdir", ["mkdir", "-p", "/home/root/.usttraces/x86-generi"...], [/* 16 vars */]) = 0
2879 brk(0) = 0x84a6000
2879 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7890000
2879 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
2879 open("/etc/ld.so.cache", O_RDONLY) = -1 ENOENT (No such file or directory)
2879 open("/lib/tls/i686/sse2/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2879 stat64("/lib/tls/i686/sse2", 0xbf9a9af8) = -1 ENOENT (No such file or directory)
2879 open("/lib/tls/i686/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2879 stat64("/lib/tls/i686", 0xbf9a9af8) = -1 ENOENT (No such file or directory)
2879 open("/lib/tls/sse2/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2879 stat64("/lib/tls/sse2", 0xbf9a9af8) = -1 ENOENT (No such file or directory)
2879 open("/lib/tls/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2879 stat64("/lib/tls", 0xbf9a9af8) = -1 ENOENT (No such file or directory)
2879 open("/lib/i686/sse2/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2879 stat64("/lib/i686/sse2", 0xbf9a9af8) = -1 ENOENT (No such file or directory)
2879 open("/lib/i686/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2879 stat64("/lib/i686", 0xbf9a9af8) = -1 ENOENT (No such file or directory)
2879 open("/lib/sse2/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2879 stat64("/lib/sse2", 0xbf9a9af8) = -1 ENOENT (No such file or directory)
2879 open("/lib/libc.so.6", O_RDONLY) = 3
2879 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\360m\1"..., 512) = 512
2879 fstat64(3, {st_mode=S_IFREG|0755, st_size=7907670, ...}) = 0
2879 old_mmap(NULL, 1362312, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7743000
2879 old_mmap(0xb788a000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x147000) = 0xb788a000
2879 old_mmap(0xb788d000, 10632, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb788d000
2879 close(3) = 0
2879 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7742000
2879 set_thread_area({entry_number:-1 -> 6, base_addr:0xb77426c0, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}) = 0
2879 mprotect(0xb788a000, 8192, PROT_READ) = 0
2879 mprotect(0xb78b0000, 4096, PROT_READ) = 0
2879 brk(0) = 0x84a6000
2879 brk(0x84c7000) = 0x84c7000
2879 umask(0) = 022
2879 mkdir("/home/root/.usttraces/x86-generic-20100730150429647915588", 0755) = 0
2879 close(1) = 0
2879 exit_group(0) = ?
2873 <... waitpid resumed> [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0) = 2879
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 --- SIGCHLD (Child exited) @ 0 (0) ---
2873 waitpid(-1, 0xbfca9a0c, WNOHANG) = -1 ECHILD (No child processes)
2873 sigreturn() = ? (mask now [])
2873 rt_sigaction(SIGINT, {SIG_DFL}, {0x807b370, [], 0}, 8) = 0
2873 read(255, "\n# Choose ustd socket path\nUSTDS"..., 4496) = 1838
2873 pipe([3, 4]) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, [INT CHLD], [], 8) = 0
2873 _llseek(255, -1194, [3302], SEEK_CUR) = 0
2873 clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0xb7700b98) = 2880
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigaction(SIGCHLD, {0x807d490, [], 0}, <unfinished ...>
2880 close(255 <unfinished ...>
2873 <... rt_sigaction resumed> {0x807d490, [], 0}, 8) = 0
2880 <... close resumed> ) = 0
2873 close(4 <unfinished ...>
2880 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2873 <... close resumed> ) = 0
2880 <... rt_sigprocmask resumed> NULL, 8) = 0
2873 read(3, <unfinished ...>
2880 rt_sigaction(SIGTSTP, {SIG_DFL}, {SIG_DFL}, 8) = 0
2880 rt_sigaction(SIGTTIN, {SIG_DFL}, {SIG_DFL}, 8) = 0
2880 rt_sigaction(SIGTTOU, {SIG_DFL}, {SIG_DFL}, 8) = 0
2880 rt_sigaction(SIGINT, {SIG_DFL}, {SIG_DFL}, 8) = 0
2880 rt_sigaction(SIGQUIT, {SIG_DFL}, {SIG_IGN}, 8) = 0
2880 rt_sigaction(SIGCHLD, {SIG_DFL}, {0x807d490, [], 0}, 8) = 0
2880 rt_sigaction(SIGCHLD, {0x807d490, [], 0}, {SIG_DFL}, 8) = 0
2880 rt_sigaction(SIGINT, {0x80900e0, [], 0}, {SIG_DFL}, 8) = 0
2880 dup2(4, 1) = 1
2880 close(4) = 0
2880 close(3) = 0
2880 stat64(".", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
2880 stat64("/usr/local/bin/date", 0xbfca9410) = -1 ENOENT (No such file or directory)
2880 stat64("/usr/bin/date", 0xbfca9410) = -1 ENOENT (No such file or directory)
2880 stat64("/bin/date", {st_mode=S_IFREG|0755, st_size=48812, ...}) = 0
2880 stat64("/bin/date", {st_mode=S_IFREG|0755, st_size=48812, ...}) = 0
2880 rt_sigaction(SIGINT, {SIG_DFL}, {0x80900e0, [], 0}, 8) = 0
2880 rt_sigaction(SIGQUIT, {SIG_DFL}, {SIG_DFL}, 8) = 0
2880 rt_sigaction(SIGCHLD, {SIG_DFL}, {0x807d490, [], 0}, 8) = 0
2880 execve("/bin/date", ["date", "+%Y%m%d%H%M%S%N"], [/* 16 vars */]) = 0
2880 brk(0) = 0x845f000
2880 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb782a000
2880 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
2880 open("/etc/ld.so.cache", O_RDONLY) = -1 ENOENT (No such file or directory)
2880 open("/lib/tls/i686/sse2/librt.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
2880 stat64("/lib/tls/i686/sse2", 0xbfafb7b8) = -1 ENOENT (No such file or directory)
2880 open("/lib/tls/i686/librt.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
2880 stat64("/lib/tls/i686", 0xbfafb7b8) = -1 ENOENT (No such file or directory)
2880 open("/lib/tls/sse2/librt.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
2880 stat64("/lib/tls/sse2", 0xbfafb7b8) = -1 ENOENT (No such file or directory)
2880 open("/lib/tls/librt.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
2880 stat64("/lib/tls", 0xbfafb7b8) = -1 ENOENT (No such file or directory)
2880 open("/lib/i686/sse2/librt.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
2880 stat64("/lib/i686/sse2", 0xbfafb7b8) = -1 ENOENT (No such file or directory)
2880 open("/lib/i686/librt.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
2880 stat64("/lib/i686", 0xbfafb7b8) = -1 ENOENT (No such file or directory)
2880 open("/lib/sse2/librt.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
2880 stat64("/lib/sse2", 0xbfafb7b8) = -1 ENOENT (No such file or directory)
2880 open("/lib/librt.so.1", O_RDONLY) = 3
2880 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\260\34"..., 512) = 512
2880 fstat64(3, {st_mode=S_IFREG|0755, st_size=217239, ...}) = 0
2880 old_mmap(NULL, 85784, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7815000
2880 old_mmap(0xb781e000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x8000) = 0xb781e000
2880 old_mmap(0xb7820000, 40728, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb7820000
2880 close(3) = 0
2880 open("/lib/libc.so.6", O_RDONLY) = 3
2880 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\360m\1"..., 512) = 512
2880 fstat64(3, {st_mode=S_IFREG|0755, st_size=7907670, ...}) = 0
2880 old_mmap(NULL, 1362312, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb76c8000
2880 old_mmap(0xb780f000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x147000) = 0xb780f000
2880 old_mmap(0xb7812000, 10632, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb7812000
2880 close(3) = 0
2880 open("/lib/libpthread.so.0", O_RDONLY) = 3
2880 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\0J\0\000"..., 512) = 512
2880 fstat64(3, {st_mode=S_IFREG|0755, st_size=679667, ...}) = 0
2880 old_mmap(NULL, 98816, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb76af000
2880 old_mmap(0xb76c4000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x15000) = 0xb76c4000
2880 old_mmap(0xb76c6000, 4608, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb76c6000
2880 close(3) = 0
2880 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb76ae000
2880 set_thread_area({entry_number:-1 -> 6, base_addr:0xb76aead0, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}) = 0
2880 mprotect(0xb76c4000, 4096, PROT_READ) = 0
2880 mprotect(0xb780f000, 8192, PROT_READ) = 0
2880 mprotect(0xb781e000, 4096, PROT_READ) = 0
2880 mprotect(0xb784a000, 4096, PROT_READ) = 0
2880 set_tid_address(0xb76aeb38) = 2880
2880 sendto(-1217729728, 0xc, 3077328884, MSG_DONTROUTE|MSG_CTRUNC|MSG_PROXY|MSG_WAITALL|MSG_ERRQUEUE|MSG_DONTWAIT|MSG_CONFIRM|MSG_FIN|MSG_NOSIGNAL|MSG_MORE|0xb76a0000, {sa_family=0xb938 /* AF_??? */, sa_data="\204\267\6\0\0\0\224\272\204\267\0\0\0\0"}, 3077237456) = 0
2880 futex(0xbfafbd90, 0x81 /* FUTEX_??? */, 1) = 0
2880 futex(0xbfafbd90, 0x189 /* FUTEX_??? */, 1) = -1 EAGAIN (Resource temporarily unavailable)
2880 rt_sigaction(SIGRTMIN, {0xb76b3400, [], SA_SIGINFO}, NULL, 8) = 0
2880 rt_sigaction(SIGRT_1, {0xb76b38f0, [], SA_RESTART|SA_SIGINFO}, NULL, 8) = 0
2880 rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0
2880 getrlimit(RLIMIT_STACK, {rlim_cur=8192*1024, rlim_max=RLIM_INFINITY}) = 0
2880 uname({sys="Linux", node="x86-generic", ...}) = 0
2880 brk(0) = 0x845f000
2880 brk(0x8480000) = 0x8480000
2880 clock_gettime(CLOCK_REALTIME, {1280502269, 721391273}) = 0
2880 open("/etc/localtime", O_RDONLY) = 3
2880 fstat64(3, {st_mode=S_IFREG|0644, st_size=255, ...}) = 0
2880 fstat64(3, {st_mode=S_IFREG|0644, st_size=255, ...}) = 0
2880 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb76ad000
2880 read(3, "TZif2\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\1\0\0\0\1\0\0"..., 4096) = 255
2880 _llseek(3, -53, [202], SEEK_CUR) = 0
2880 read(3, "\n<Local time zone must be set--s"..., 4096) = 53
2880 close(3) = 0
2880 munmap(0xb76ad000, 4096) = 0
2880 fstat64(1, {st_mode=S_IFIFO|0600, st_size=0, ...}) = 0
2880 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb76ad000
2880 write(1, "20100730150429721391273\n", 24) = 24
2880 close(1) = 0
2880 munmap(0xb76ad000, 4096) = 0
2880 exit_group(0) = ?
2873 <... read resumed> "20100730150429721391273\n", 128) = 24
2873 --- SIGCHLD (Child exited) @ 0 (0) ---
2873 waitpid(-1, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG) = 2880
2873 waitpid(-1, 0xbfca939c, WNOHANG) = -1 ECHILD (No child processes)
2873 sigreturn() = ? (mask now [])
2873 read(3, "", 128) = 0
2873 close(3) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0
2873 rt_sigaction(SIGINT, {0x807b370, [], 0}, {SIG_DFL}, 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigaction(SIGINT, {SIG_DFL}, {0x807b370, [], 0}, 8) = 0
2873 rt_sigaction(SIGINT, {SIG_IGN}, {SIG_DFL}, 8) = 0
2873 rt_sigaction(SIGINT, {0x808e370, [], 0}, {SIG_IGN}, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 stat64(".", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
2873 stat64("/usr/local/bin/mkfifo", 0xbfca9a70) = -1 ENOENT (No such file or directory)
2873 stat64("/usr/bin/mkfifo", {st_mode=S_IFREG|0755, st_size=15264, ...}) = 0
2873 stat64("/usr/bin/mkfifo", {st_mode=S_IFREG|0755, st_size=15264, ...}) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, [INT CHLD], [], 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], [INT CHLD], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [INT CHLD], NULL, 8) = 0
2873 clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0xb7700b98) = 2881
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2881 close(255) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], <unfinished ...>
2881 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2873 <... rt_sigprocmask resumed> [], 8) = 0
2881 <... rt_sigprocmask resumed> NULL, 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2881 rt_sigaction(SIGTSTP, {SIG_DFL}, <unfinished ...>
2873 <... rt_sigprocmask resumed> NULL, 8) = 0
2881 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], <unfinished ...>
2881 rt_sigaction(SIGTTIN, {SIG_DFL}, <unfinished ...>
2873 <... rt_sigprocmask resumed> [], 8) = 0
2881 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2873 rt_sigaction(SIGINT, {0x807b370, [], 0}, <unfinished ...>
2881 rt_sigaction(SIGTTOU, {SIG_DFL}, <unfinished ...>
2873 <... rt_sigaction resumed> {0x808e370, [], 0}, 8) = 0
2881 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2873 waitpid(-1, <unfinished ...>
2881 rt_sigaction(SIGINT, {SIG_DFL}, {0x808e370, [], 0}, 8) = 0
2881 rt_sigaction(SIGQUIT, {SIG_DFL}, {SIG_IGN}, 8) = 0
2881 rt_sigaction(SIGCHLD, {SIG_DFL}, {0x807d490, [], 0}, 8) = 0
2881 execve("/usr/bin/mkfifo", ["mkfifo", "-m", "0600", "/tmp/usttrace-root-2010073015042"...], [/* 16 vars */]) = 0
2881 brk(0) = 0x9c9b000
2881 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb785b000
2881 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
2881 open("/etc/ld.so.cache", O_RDONLY) = -1 ENOENT (No such file or directory)
2881 open("/lib/tls/i686/sse2/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2881 stat64("/lib/tls/i686/sse2", 0xbfd05a08) = -1 ENOENT (No such file or directory)
2881 open("/lib/tls/i686/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2881 stat64("/lib/tls/i686", 0xbfd05a08) = -1 ENOENT (No such file or directory)
2881 open("/lib/tls/sse2/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2881 stat64("/lib/tls/sse2", 0xbfd05a08) = -1 ENOENT (No such file or directory)
2881 open("/lib/tls/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2881 stat64("/lib/tls", 0xbfd05a08) = -1 ENOENT (No such file or directory)
2881 open("/lib/i686/sse2/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2881 stat64("/lib/i686/sse2", 0xbfd05a08) = -1 ENOENT (No such file or directory)
2881 open("/lib/i686/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2881 stat64("/lib/i686", 0xbfd05a08) = -1 ENOENT (No such file or directory)
2881 open("/lib/sse2/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2881 stat64("/lib/sse2", 0xbfd05a08) = -1 ENOENT (No such file or directory)
2881 open("/lib/libc.so.6", O_RDONLY) = 3
2881 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\360m\1"..., 512) = 512
2881 fstat64(3, {st_mode=S_IFREG|0755, st_size=7907670, ...}) = 0
2881 old_mmap(NULL, 1362312, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb770e000
2881 old_mmap(0xb7855000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x147000) = 0xb7855000
2881 old_mmap(0xb7858000, 10632, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb7858000
2881 close(3) = 0
2881 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb770d000
2881 set_thread_area({entry_number:-1 -> 6, base_addr:0xb770d6c0, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}) = 0
2881 mprotect(0xb7855000, 8192, PROT_READ) = 0
2881 mprotect(0xb787b000, 4096, PROT_READ) = 0
2881 brk(0) = 0x9c9b000
2881 brk(0x9cbc000) = 0x9cbc000
2881 umask(0) = 022
2881 mknod("/tmp/usttrace-root-20100730150429721391273-ustd-pid", S_IFIFO|0600) = 0
2881 close(1) = 0
2881 exit_group(0) = ?
2873 <... waitpid resumed> [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0) = 2881
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 --- SIGCHLD (Child exited) @ 0 (0) ---
2873 waitpid(-1, 0xbfca977c, WNOHANG) = -1 ECHILD (No child processes)
2873 sigreturn() = ? (mask now [])
2873 rt_sigaction(SIGINT, {0x808e370, [], 0}, {0x807b370, [], 0}, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, [INT CHLD], [], 8) = 0
2873 clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0xb7700b98) = 2882
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2882 close(255 <unfinished ...>
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], <unfinished ...>
2882 <... close resumed> ) = 0
2873 <... rt_sigprocmask resumed> [], 8) = 0
2882 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], <unfinished ...>
2882 <... rt_sigprocmask resumed> NULL, 8) = 0
2873 <... rt_sigprocmask resumed> [CHLD], 8) = 0
2882 rt_sigaction(SIGTSTP, {SIG_DFL}, <unfinished ...>
2873 rt_sigprocmask(SIG_SETMASK, [CHLD], <unfinished ...>
2882 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2873 <... rt_sigprocmask resumed> NULL, 8) = 0
2882 rt_sigaction(SIGTTIN, {SIG_DFL}, <unfinished ...>
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], <unfinished ...>
2882 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2873 <... rt_sigprocmask resumed> [CHLD], 8) = 0
2882 rt_sigaction(SIGTTOU, {SIG_DFL}, <unfinished ...>
2873 rt_sigprocmask(SIG_SETMASK, [CHLD], <unfinished ...>
2882 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2873 <... rt_sigprocmask resumed> NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], [CHLD], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [CHLD], NULL, 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2882 stat64(".", <unfinished ...>
2873 <... rt_sigprocmask resumed> NULL, 8) = 0
2882 <... stat64 resumed> {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
2873 pipe( <unfinished ...>
2882 stat64("/usr/local/bin/ustd", <unfinished ...>
2873 <... pipe resumed> [3, 4]) = 0
2882 <... stat64 resumed> 0xbfca9aa0) = -1 ENOENT (No such file or directory)
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], <unfinished ...>
2882 stat64("/usr/bin/ustd", <unfinished ...>
2873 <... rt_sigprocmask resumed> [], 8) = 0
2882 <... stat64 resumed> {st_mode=S_IFREG|0755, st_size=181265, ...}) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2882 stat64("/usr/bin/ustd", <unfinished ...>
2873 <... rt_sigprocmask resumed> NULL, 8) = 0
2882 <... stat64 resumed> {st_mode=S_IFREG|0755, st_size=181265, ...}) = 0
2873 rt_sigprocmask(SIG_BLOCK, [INT CHLD], <unfinished ...>
2882 rt_sigaction(SIGINT, {SIG_DFL}, <unfinished ...>
2873 <... rt_sigprocmask resumed> [], 8) = 0
2882 <... rt_sigaction resumed> {0x808e370, [], 0}, 8) = 0
2873 clone( <unfinished ...>
2882 rt_sigaction(SIGQUIT, {SIG_DFL}, <unfinished ...>
2873 <... clone resumed> child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0xb7700b98) = 2883
2882 <... rt_sigaction resumed> {SIG_IGN}, 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2883 close(255 <unfinished ...>
2882 rt_sigaction(SIGCHLD, {SIG_DFL}, <unfinished ...>
2883 <... close resumed> ) = 0
2882 <... rt_sigaction resumed> {0x807d490, [], 0}, 8) = 0
2883 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2882 open("/dev/null", O_RDONLY|O_LARGEFILE <unfinished ...>
2883 <... rt_sigprocmask resumed> NULL, 8) = 0
2873 <... rt_sigprocmask resumed> NULL, 8) = 0
2883 rt_sigaction(SIGTSTP, {SIG_DFL}, <unfinished ...>
2882 <... open resumed> ) = 3
2883 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2882 dup2(3, 0 <unfinished ...>
2883 rt_sigaction(SIGTTIN, {SIG_DFL}, <unfinished ...>
2882 <... dup2 resumed> ) = 0
2883 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2882 close(3 <unfinished ...>
2883 rt_sigaction(SIGTTOU, {SIG_DFL}, <unfinished ...>
2882 <... close resumed> ) = 0
2883 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2882 rt_sigaction(SIGINT, {SIG_IGN}, <unfinished ...>
2883 rt_sigaction(SIGINT, {SIG_DFL}, <unfinished ...>
2882 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2883 <... rt_sigaction resumed> {0x808e370, [], 0}, 8) = 0
2882 rt_sigaction(SIGQUIT, {SIG_IGN}, <unfinished ...>
2883 rt_sigaction(SIGQUIT, {SIG_DFL}, <unfinished ...>
2882 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2883 <... rt_sigaction resumed> {SIG_IGN}, 8) = 0
2873 rt_sigaction(SIGCHLD, {0x807d490, [], 0}, <unfinished ...>
2883 rt_sigaction(SIGCHLD, {SIG_DFL}, <unfinished ...>
2882 open("/home/root/.usttraces/x86-generic-20100730150429647915588/ustd.log", O_WRONLY|O_CREAT|O_TRUNC|O_LARGEFILE, 0666 <unfinished ...>
2883 <... rt_sigaction resumed> {0x807d490, [], 0}, 8) = 0
2873 <... rt_sigaction resumed> {0x807d490, [], 0}, 8) = 0
2873 close(4 <unfinished ...>
2883 rt_sigaction(SIGCHLD, {0x807d490, [], 0}, <unfinished ...>
2873 <... close resumed> ) = 0
2883 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2873 read(3, <unfinished ...>
2883 rt_sigaction(SIGINT, {0x80900e0, [], 0}, {SIG_DFL}, 8) = 0
2883 dup2(4, 1) = 1
2883 close(4) = 0
2883 close(3) = 0
2883 open("/tmp/usttrace-root-20100730150429721391273-ustd-pid", O_RDONLY|O_LARGEFILE <unfinished ...>
2882 <... open resumed> ) = 3
2882 dup2(3, 1) = 1
2882 close(3) = 0
2882 dup2(1, 2) = 2
2882 fcntl64(1, F_GETFD) = 0
2882 execve("/usr/bin/ustd", ["ustd", "--pidfile", "/tmp/usttrace-root-2010073015042"..., "-s", "/tmp/ustd-sock-2873", "-o", "/home/root/.usttraces/x86-generi"...], [/* 16 vars */]) = 0
2882 brk(0) = 0x9fe9000
2882 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb77ad000
2882 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
2882 open("/etc/ld.so.cache", O_RDONLY) = -1 ENOENT (No such file or directory)
2882 open("/lib/tls/i686/sse2/liburcu-bp.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
2882 stat64("/lib/tls/i686/sse2", 0xbff6aef8) = -1 ENOENT (No such file or directory)
2882 open("/lib/tls/i686/liburcu-bp.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
2882 stat64("/lib/tls/i686", 0xbff6aef8) = -1 ENOENT (No such file or directory)
2882 open("/lib/tls/sse2/liburcu-bp.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
2882 stat64("/lib/tls/sse2", 0xbff6aef8) = -1 ENOENT (No such file or directory)
2882 open("/lib/tls/liburcu-bp.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
2882 stat64("/lib/tls", 0xbff6aef8) = -1 ENOENT (No such file or directory)
2882 open("/lib/i686/sse2/liburcu-bp.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
2882 stat64("/lib/i686/sse2", 0xbff6aef8) = -1 ENOENT (No such file or directory)
2882 open("/lib/i686/liburcu-bp.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
2882 stat64("/lib/i686", 0xbff6aef8) = -1 ENOENT (No such file or directory)
2882 open("/lib/sse2/liburcu-bp.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
2882 stat64("/lib/sse2", 0xbff6aef8) = -1 ENOENT (No such file or directory)
2882 open("/lib/liburcu-bp.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
2882 stat64("/lib", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
2882 open("/usr/lib/tls/i686/sse2/liburcu-bp.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
2882 stat64("/usr/lib/tls/i686/sse2", 0xbff6aef8) = -1 ENOENT (No such file or directory)
2882 open("/usr/lib/tls/i686/liburcu-bp.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
2882 stat64("/usr/lib/tls/i686", 0xbff6aef8) = -1 ENOENT (No such file or directory)
2882 open("/usr/lib/tls/sse2/liburcu-bp.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
2882 stat64("/usr/lib/tls/sse2", 0xbff6aef8) = -1 ENOENT (No such file or directory)
2882 open("/usr/lib/tls/liburcu-bp.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
2882 stat64("/usr/lib/tls", 0xbff6aef8) = -1 ENOENT (No such file or directory)
2882 open("/usr/lib/i686/sse2/liburcu-bp.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
2882 stat64("/usr/lib/i686/sse2", 0xbff6aef8) = -1 ENOENT (No such file or directory)
2882 open("/usr/lib/i686/liburcu-bp.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
2882 stat64("/usr/lib/i686", 0xbff6aef8) = -1 ENOENT (No such file or directory)
2882 open("/usr/lib/sse2/liburcu-bp.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
2882 stat64("/usr/lib/sse2", 0xbff6aef8) = -1 ENOENT (No such file or directory)
2882 open("/usr/lib/liburcu-bp.so.0", O_RDONLY) = 3
2882 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0000\n\0"..., 512) = 512
2882 fstat64(3, {st_mode=S_IFREG|0755, st_size=19033, ...}) = 0
2882 old_mmap(NULL, 9512, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb77aa000
2882 old_mmap(0xb77ac000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1000) = 0xb77ac000
2882 close(3) = 0
2882 open("/lib/libpthread.so.0", O_RDONLY) = 3
2882 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\0J\0\000"..., 512) = 512
2882 fstat64(3, {st_mode=S_IFREG|0755, st_size=679667, ...}) = 0
2882 old_mmap(NULL, 98816, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7791000
2882 old_mmap(0xb77a6000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x15000) = 0xb77a6000
2882 old_mmap(0xb77a8000, 4608, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb77a8000
2882 close(3) = 0
2882 open("/lib/libc.so.6", O_RDONLY) = 3
2882 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\360m\1"..., 512) = 512
2882 fstat64(3, {st_mode=S_IFREG|0755, st_size=7907670, ...}) = 0
2882 old_mmap(NULL, 1362312, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7644000
2882 old_mmap(0xb778b000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x147000) = 0xb778b000
2882 old_mmap(0xb778e000, 10632, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb778e000
2882 close(3) = 0
2882 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7643000
2882 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7642000
2882 set_thread_area({entry_number:-1 -> 6, base_addr:0xb7643b40, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}) = 0
2882 mprotect(0xb778b000, 8192, PROT_READ) = 0
2882 mprotect(0xb77a6000, 4096, PROT_READ) = 0
2882 mprotect(0xb77cd000, 4096, PROT_READ) = 0
2882 set_tid_address(0xb7643ba8) = 2882
2882 sendto(-1218167888, 0xc, 3078254580, MSG_DONTROUTE|MSG_CTRUNC|MSG_EOR|MSG_WAITALL|MSG_ERRQUEUE|MSG_DONTWAIT|MSG_CONFIRM|MSG_FIN|MSG_RST|0xb7640000, {sa_family=0xe938 /* AF_??? */, sa_data="|\267\6\0\0\0\224\352|\267\0\0\0\0"}, 3076799296) = 0
2882 futex(0xbff6b4d0, 0x81 /* FUTEX_??? */, 1) = 0
2882 futex(0xbff6b4d0, 0x189 /* FUTEX_??? */, 1) = -1 EAGAIN (Resource temporarily unavailable)
2882 rt_sigaction(SIGRTMIN, {0xb7795400, [], SA_SIGINFO}, NULL, 8) = 0
2882 rt_sigaction(SIGRT_1, {0xb77958f0, [], SA_RESTART|SA_SIGINFO}, NULL, 8) = 0
2882 rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0
2882 getrlimit(RLIMIT_STACK, {rlim_cur=8192*1024, rlim_max=RLIM_INFINITY}) = 0
2882 uname({sys="Linux", node="x86-generic", ...}) = 0
2882 brk(0) = 0x9fe9000
2882 brk(0xa00a000) = 0xa00a000
2882 stat64("/home/root/.usttraces/x86-generic-20100730150429647915588", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
2882 rt_sigaction(SIGTERM, {0x8049c20, [], 0}, NULL, 8) = 0
2882 rt_sigaction(SIGINT, {0x8049c20, [], 0}, NULL, 8) = 0
2882 socket(PF_FILE, SOCK_STREAM, 0) = 3
2882 access("/tmp/ustd-sock-2873", F_OK) = -1 ENOENT (No such file or directory)
2882 bind(3, {sa_family=AF_FILE, path="/tmp/ustd-sock-2873"}, 110) = 0
2882 listen(3, 1) = 0
2882 rt_sigprocmask(SIG_BLOCK, [PIPE], NULL, 8) = 0
2882 open("/tmp/usttrace-root-20100730150429721391273-ustd-pid", O_WRONLY|O_CREAT|O_TRUNC, 0666 <unfinished ...>
2883 <... open resumed> ) = 3
2883 read(3, <unfinished ...>
2882 <... open resumed> ) = 4
2882 fstat64(4, {st_mode=S_IFIFO|0600, st_size=0, ...}) = 0
2882 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7641000
2882 write(4, "2882\n", 5 <unfinished ...>
2883 <... read resumed> "2882\n", 128) = 5
2883 write(1, "2882\n", 5) = 5
2883 read(3, <unfinished ...>
2882 <... write resumed> ) = 5
2882 close(4 <unfinished ...>
2883 <... read resumed> "", 128) = 0
2883 close(3) = 0
2883 exit_group(0) = ?
2882 <... close resumed> ) = 0
2882 munmap(0xb7641000, 4096) = 0
2882 poll( <unfinished ...>
2873 <... read resumed> "2882\n", 128) = 5
2873 --- SIGCHLD (Child exited) @ 0 (0) ---
2873 waitpid(-1, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG) = 2883
2873 waitpid(-1, 0xbfca94ac, WNOHANG) = 0
2873 sigreturn() = ? (mask now [])
2873 read(3, "", 128) = 0
2873 close(3) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0
2873 rt_sigaction(SIGINT, {0x807b370, [], 0}, {0x808e370, [], 0}, 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigaction(SIGINT, {0x808e370, [], 0}, {0x807b370, [], 0}, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 read(255, "\n# Establish the environment for"..., 4496) = 1194
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0
2873 pipe([3, 4]) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [CHLD], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [CHLD], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [CHLD], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [CHLD], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [CHLD], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [CHLD], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [CHLD], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [CHLD], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [CHLD], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [CHLD], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [CHLD], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [CHLD], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [CHLD], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [CHLD], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, [INT CHLD], [CHLD], 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], [INT CHLD], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [INT CHLD], NULL, 8) = 0
2873 _llseek(255, -418, [4078], SEEK_CUR) = 0
2873 clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0xb7700b98) = 2884
2873 rt_sigprocmask(SIG_SETMASK, [CHLD], NULL, 8) = 0
2873 close(4 <unfinished ...>
2884 close(255 <unfinished ...>
2873 <... close resumed> ) = 0
2884 <... close resumed> ) = 0
2873 close(4 <unfinished ...>
2884 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2873 <... close resumed> ) = -1 EBADF (Bad file descriptor)
2884 <... rt_sigprocmask resumed> NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], <unfinished ...>
2884 rt_sigaction(SIGTSTP, {SIG_DFL}, <unfinished ...>
2873 <... rt_sigprocmask resumed> [CHLD], 8) = 0
2884 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [CHLD], <unfinished ...>
2884 rt_sigaction(SIGTTIN, {SIG_DFL}, <unfinished ...>
2873 <... rt_sigprocmask resumed> NULL, 8) = 0
2884 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], <unfinished ...>
2884 rt_sigaction(SIGTTOU, {SIG_DFL}, <unfinished ...>
2873 <... rt_sigprocmask resumed> [CHLD], 8) = 0
2884 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [CHLD], <unfinished ...>
2884 rt_sigaction(SIGINT, {SIG_DFL}, <unfinished ...>
2873 <... rt_sigprocmask resumed> NULL, 8) = 0
2884 <... rt_sigaction resumed> {0x808e370, [], 0}, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, [INT CHLD], <unfinished ...>
2884 rt_sigaction(SIGQUIT, {SIG_DFL}, <unfinished ...>
2873 <... rt_sigprocmask resumed> [CHLD], 8) = 0
2884 <... rt_sigaction resumed> {SIG_IGN}, 8) = 0
2873 clone( <unfinished ...>
2884 rt_sigaction(SIGCHLD, {SIG_DFL}, <unfinished ...>
2873 <... clone resumed> child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0xb7700b98) = 2885
2885 close(255 <unfinished ...>
2884 <... rt_sigaction resumed> {0x807d490, [], 0}, 8) = 0
2885 <... close resumed> ) = 0
2884 rt_sigaction(SIGCHLD, {0x807d490, [], 0}, <unfinished ...>
2885 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2884 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2885 <... rt_sigprocmask resumed> NULL, 8) = 0
2884 rt_sigaction(SIGINT, {0x80900e0, [], 0}, <unfinished ...>
2885 rt_sigaction(SIGTSTP, {SIG_DFL}, <unfinished ...>
2884 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2885 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2884 rt_sigprocmask(SIG_BLOCK, [CHLD], <unfinished ...>
2885 rt_sigaction(SIGTTIN, {SIG_DFL}, <unfinished ...>
2884 <... rt_sigprocmask resumed> [], 8) = 0
2885 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2884 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2885 rt_sigaction(SIGTTOU, {SIG_DFL}, <unfinished ...>
2884 <... rt_sigprocmask resumed> NULL, 8) = 0
2885 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2884 close(3 <unfinished ...>
2885 dup2(3, 0 <unfinished ...>
2884 <... close resumed> ) = 0
2885 <... dup2 resumed> ) = 0
2884 dup2(4, 1 <unfinished ...>
2885 close(3 <unfinished ...>
2884 <... dup2 resumed> ) = 1
2885 <... close resumed> ) = 0
2884 close(4 <unfinished ...>
2873 rt_sigprocmask(SIG_SETMASK, [CHLD], <unfinished ...>
2884 <... close resumed> ) = 0
2885 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], <unfinished ...>
2873 <... rt_sigprocmask resumed> NULL, 8) = 0
2873 close(3) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], [CHLD], 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [CHLD], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [CHLD], <unfinished ...>
2885 <... rt_sigprocmask resumed> [], 8) = 0
2884 rt_sigprocmask(SIG_BLOCK, [INT CHLD], <unfinished ...>
2885 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2884 <... rt_sigprocmask resumed> [], 8) = 0
2885 <... rt_sigprocmask resumed> NULL, 8) = 0
2884 clone( <unfinished ...>
2873 <... rt_sigprocmask resumed> NULL, 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [CHLD], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], <unfinished ...>
2884 <... clone resumed> child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0xb7700b98) = 2886
2885 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], <unfinished ...>
2884 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2885 <... rt_sigprocmask resumed> [], 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2885 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2886 <... rt_sigprocmask resumed> NULL, 8) = 0
2885 <... rt_sigprocmask resumed> NULL, 8) = 0
2886 rt_sigaction(SIGTSTP, {SIG_DFL}, <unfinished ...>
2885 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], <unfinished ...>
2886 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2885 <... rt_sigprocmask resumed> [], 8) = 0
2886 rt_sigaction(SIGTTIN, {SIG_DFL}, <unfinished ...>
2885 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2886 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2885 <... rt_sigprocmask resumed> NULL, 8) = 0
2886 rt_sigaction(SIGTTOU, {SIG_DFL}, <unfinished ...>
2885 stat64(".", <unfinished ...>
2886 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2885 <... stat64 resumed> {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
2886 rt_sigaction(SIGINT, {SIG_DFL}, <unfinished ...>
2885 stat64("/usr/local/bin/tee", <unfinished ...>
2886 <... rt_sigaction resumed> {0x80900e0, [], 0}, 8) = 0
2885 <... stat64 resumed> 0xbfca9b20) = -1 ENOENT (No such file or directory)
2886 rt_sigaction(SIGQUIT, {SIG_DFL}, <unfinished ...>
2885 stat64("/usr/bin/tee", <unfinished ...>
2886 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2884 <... rt_sigprocmask resumed> NULL, 8) = 0
2886 rt_sigaction(SIGCHLD, {SIG_DFL}, <unfinished ...>
2884 rt_sigprocmask(SIG_BLOCK, [CHLD], <unfinished ...>
2886 <... rt_sigaction resumed> {0x807d490, [], 0}, 8) = 0
2884 <... rt_sigprocmask resumed> [], 8) = 0
2886 dup2(1, 2 <unfinished ...>
2884 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2886 <... dup2 resumed> ) = 2
2884 <... rt_sigprocmask resumed> NULL, 8) = 0
2884 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0
2886 fcntl64(1, F_GETFD <unfinished ...>
2884 rt_sigaction(SIGINT, {0x807b370, [], 0}, <unfinished ...>
2886 <... fcntl64 resumed> ) = 0
2884 <... rt_sigaction resumed> {0x80900e0, [], 0}, 8) = 0
2884 waitpid(-1, <unfinished ...>
2873 <... rt_sigprocmask resumed> [CHLD], 8) = 0
2886 execve("./usttest", ["./usttest"], [/* 18 vars */] <unfinished ...>
2885 <... stat64 resumed> {st_mode=S_IFREG|0755, st_size=15060, ...}) = 0
2873 rt_sigaction(SIGINT, {0x807b370, [], 0}, <unfinished ...>
2885 stat64("/usr/bin/tee", <unfinished ...>
2873 <... rt_sigaction resumed> {0x808e370, [], 0}, 8) = 0
2885 <... stat64 resumed> {st_mode=S_IFREG|0755, st_size=15060, ...}) = 0
2873 waitpid(-1, <unfinished ...>
2885 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], [], 8) = 0
2885 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2885 rt_sigaction(SIGINT, {SIG_DFL}, {0x808e370, [], 0}, 8) = 0
2885 rt_sigaction(SIGQUIT, {SIG_DFL}, {SIG_IGN}, 8) = 0
2885 rt_sigaction(SIGCHLD, {SIG_DFL}, {0x807d490, [], 0}, 8) = 0
2886 <... execve resumed> ) = 0
2885 execve("/usr/bin/tee", ["tee", "/home/root/.usttraces/x86-generi"...], [/* 17 vars */] <unfinished ...>
2886 brk(0) = 0xa034000
2886 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb78c2000
2886 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
2886 open("/etc/ld.so.cache", O_RDONLY) = -1 ENOENT (No such file or directory)
2886 open("/lib/tls/i686/sse2/libust.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
2886 stat64("/lib/tls/i686/sse2", 0xbff80ca8) = -1 ENOENT (No such file or directory)
2886 open("/lib/tls/i686/libust.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
2886 stat64("/lib/tls/i686", 0xbff80ca8) = -1 ENOENT (No such file or directory)
2885 <... execve resumed> ) = 0
2886 open("/lib/tls/sse2/libust.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
2885 brk(0 <unfinished ...>
2886 stat64("/lib/tls/sse2", <unfinished ...>
2885 <... brk resumed> ) = 0x99e1000
2886 <... stat64 resumed> 0xbff80ca8) = -1 ENOENT (No such file or directory)
2885 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0 <unfinished ...>
2886 open("/lib/tls/libust.so.0", O_RDONLY <unfinished ...>
2885 <... old_mmap resumed> ) = 0xb77f3000
2886 <... open resumed> ) = -1 ENOENT (No such file or directory)
2885 access("/etc/ld.so.preload", R_OK <unfinished ...>
2886 stat64("/lib/tls", <unfinished ...>
2885 <... access resumed> ) = -1 ENOENT (No such file or directory)
2886 <... stat64 resumed> 0xbff80ca8) = -1 ENOENT (No such file or directory)
2885 open("/etc/ld.so.cache", O_RDONLY <unfinished ...>
2886 open("/lib/i686/sse2/libust.so.0", O_RDONLY <unfinished ...>
2885 <... open resumed> ) = -1 ENOENT (No such file or directory)
2886 <... open resumed> ) = -1 ENOENT (No such file or directory)
2885 open("/lib/tls/i686/sse2/libc.so.6", O_RDONLY <unfinished ...>
2886 stat64("/lib/i686/sse2", <unfinished ...>
2885 <... open resumed> ) = -1 ENOENT (No such file or directory)
2886 <... stat64 resumed> 0xbff80ca8) = -1 ENOENT (No such file or directory)
2885 stat64("/lib/tls/i686/sse2", <unfinished ...>
2886 open("/lib/i686/libust.so.0", O_RDONLY <unfinished ...>
2885 <... stat64 resumed> 0xbfaafe28) = -1 ENOENT (No such file or directory)
2886 <... open resumed> ) = -1 ENOENT (No such file or directory)
2885 open("/lib/tls/i686/libc.so.6", O_RDONLY <unfinished ...>
2886 stat64("/lib/i686", <unfinished ...>
2885 <... open resumed> ) = -1 ENOENT (No such file or directory)
2886 <... stat64 resumed> 0xbff80ca8) = -1 ENOENT (No such file or directory)
2885 stat64("/lib/tls/i686", <unfinished ...>
2886 open("/lib/sse2/libust.so.0", O_RDONLY <unfinished ...>
2885 <... stat64 resumed> 0xbfaafe28) = -1 ENOENT (No such file or directory)
2886 <... open resumed> ) = -1 ENOENT (No such file or directory)
2885 open("/lib/tls/sse2/libc.so.6", O_RDONLY <unfinished ...>
2886 stat64("/lib/sse2", <unfinished ...>
2885 <... open resumed> ) = -1 ENOENT (No such file or directory)
2886 <... stat64 resumed> 0xbff80ca8) = -1 ENOENT (No such file or directory)
2885 stat64("/lib/tls/sse2", <unfinished ...>
2886 open("/lib/libust.so.0", O_RDONLY <unfinished ...>
2885 <... stat64 resumed> 0xbfaafe28) = -1 ENOENT (No such file or directory)
2886 <... open resumed> ) = -1 ENOENT (No such file or directory)
2885 open("/lib/tls/libc.so.6", O_RDONLY <unfinished ...>
2886 stat64("/lib", <unfinished ...>
2885 <... open resumed> ) = -1 ENOENT (No such file or directory)
2886 <... stat64 resumed> {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
2885 stat64("/lib/tls", <unfinished ...>
2886 open("/usr/lib/tls/i686/sse2/libust.so.0", O_RDONLY <unfinished ...>
2885 <... stat64 resumed> 0xbfaafe28) = -1 ENOENT (No such file or directory)
2886 <... open resumed> ) = -1 ENOENT (No such file or directory)
2885 open("/lib/i686/sse2/libc.so.6", O_RDONLY <unfinished ...>
2886 stat64("/usr/lib/tls/i686/sse2", <unfinished ...>
2885 <... open resumed> ) = -1 ENOENT (No such file or directory)
2886 <... stat64 resumed> 0xbff80ca8) = -1 ENOENT (No such file or directory)
2885 stat64("/lib/i686/sse2", <unfinished ...>
2886 open("/usr/lib/tls/i686/libust.so.0", O_RDONLY <unfinished ...>
2885 <... stat64 resumed> 0xbfaafe28) = -1 ENOENT (No such file or directory)
2886 <... open resumed> ) = -1 ENOENT (No such file or directory)
2885 open("/lib/i686/libc.so.6", O_RDONLY <unfinished ...>
2886 stat64("/usr/lib/tls/i686", <unfinished ...>
2885 <... open resumed> ) = -1 ENOENT (No such file or directory)
2886 <... stat64 resumed> 0xbff80ca8) = -1 ENOENT (No such file or directory)
2885 stat64("/lib/i686", <unfinished ...>
2886 open("/usr/lib/tls/sse2/libust.so.0", O_RDONLY <unfinished ...>
2885 <... stat64 resumed> 0xbfaafe28) = -1 ENOENT (No such file or directory)
2885 open("/lib/sse2/libc.so.6", O_RDONLY <unfinished ...>
2886 <... open resumed> ) = -1 ENOENT (No such file or directory)
2885 <... open resumed> ) = -1 ENOENT (No such file or directory)
2886 stat64("/usr/lib/tls/sse2", 0xbff80ca8) = -1 ENOENT (No such file or directory)
2885 stat64("/lib/sse2", <unfinished ...>
2886 open("/usr/lib/tls/libust.so.0", O_RDONLY <unfinished ...>
2885 <... stat64 resumed> 0xbfaafe28) = -1 ENOENT (No such file or directory)
2886 <... open resumed> ) = -1 ENOENT (No such file or directory)
2885 open("/lib/libc.so.6", O_RDONLY <unfinished ...>
2886 stat64("/usr/lib/tls", 0xbff80ca8) = -1 ENOENT (No such file or directory)
2886 open("/usr/lib/i686/sse2/libust.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
2886 stat64("/usr/lib/i686/sse2", 0xbff80ca8) = -1 ENOENT (No such file or directory)
2886 open("/usr/lib/i686/libust.so.0", O_RDONLY <unfinished ...>
2885 <... open resumed> ) = 3
2886 <... open resumed> ) = -1 ENOENT (No such file or directory)
2885 read(3, <unfinished ...>
2886 stat64("/usr/lib/i686", <unfinished ...>
2885 <... read resumed> "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\360m\1"..., 512) = 512
2886 <... stat64 resumed> 0xbff80ca8) = -1 ENOENT (No such file or directory)
2885 fstat64(3, <unfinished ...>
2886 open("/usr/lib/sse2/libust.so.0", O_RDONLY <unfinished ...>
2885 <... fstat64 resumed> {st_mode=S_IFREG|0755, st_size=7907670, ...}) = 0
2886 <... open resumed> ) = -1 ENOENT (No such file or directory)
2885 old_mmap(NULL, 1362312, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0 <unfinished ...>
2886 stat64("/usr/lib/sse2", <unfinished ...>
2885 <... old_mmap resumed> ) = 0xb76a6000
2886 <... stat64 resumed> 0xbff80ca8) = -1 ENOENT (No such file or directory)
2885 old_mmap(0xb77ed000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x147000 <unfinished ...>
2886 open("/usr/lib/libust.so.0", O_RDONLY <unfinished ...>
2885 <... old_mmap resumed> ) = 0xb77ed000
2885 old_mmap(0xb77f0000, 10632, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb77f0000
2885 close(3) = 0
2885 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb76a5000
2885 set_thread_area({entry_number:-1 -> 6, base_addr:0xb76a56c0, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}) = 0
2885 mprotect(0xb77ed000, 8192, PROT_READ) = 0
2885 mprotect(0xb7813000, 4096, PROT_READ) = 0
2885 brk(0) = 0x99e1000
2885 brk(0x9a02000) = 0x9a02000
2885 open("/home/root/.usttraces/x86-generic-20100730150429647915588/app.log", O_WRONLY|O_CREAT|O_TRUNC|O_LARGEFILE, 0666 <unfinished ...>
2886 <... open resumed> ) = 3
2886 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\300D\0"..., 512) = 512
2886 fstat64(3, {st_mode=S_IFREG|0755, st_size=462279, ...}) = 0
2886 old_mmap(NULL, 146576, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb789e000
2886 old_mmap(0xb78c1000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x23000) = 0xb78c1000
2886 close(3) = 0
2886 open("/lib/libc.so.6", O_RDONLY) = 3
2886 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\360m\1"..., 512) = 512
2886 fstat64(3, {st_mode=S_IFREG|0755, st_size=7907670, ...}) = 0
2886 old_mmap(NULL, 1362312, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7751000
2886 old_mmap(0xb7898000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x147000) = 0xb7898000
2886 old_mmap(0xb789b000, 10632, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb789b000
2886 close(3) = 0
2886 open("/lib/liburcu-bp.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
2886 open("/usr/lib/liburcu-bp.so.0", O_RDONLY) = 3
2886 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0000\n\0"..., 512) = 512
2886 fstat64(3, {st_mode=S_IFREG|0755, st_size=19033, ...}) = 0
2886 old_mmap(NULL, 9512, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb774e000
2886 old_mmap(0xb7750000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1000) = 0xb7750000
2886 close(3) = 0
2886 open("/lib/librt.so.1", O_RDONLY) = 3
2886 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\260\34"..., 512) = 512
2886 fstat64(3, {st_mode=S_IFREG|0755, st_size=217239, ...}) = 0
2886 old_mmap(NULL, 85784, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7739000
2886 old_mmap(0xb7742000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x8000) = 0xb7742000
2886 old_mmap(0xb7744000, 40728, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb7744000
2886 close(3) = 0
2886 open("/lib/libpthread.so.0", O_RDONLY) = 3
2886 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\0J\0\000"..., 512) = 512
2886 fstat64(3, {st_mode=S_IFREG|0755, st_size=679667, ...}) = 0
2886 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7738000
2886 old_mmap(NULL, 98816, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb771f000
2886 old_mmap(0xb7734000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x15000) = 0xb7734000
2886 old_mmap(0xb7736000, 4608, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb7736000
2886 close(3) = 0
2886 old_mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb771d000
2886 set_thread_area({entry_number:-1 -> 6, base_addr:0xb771dea0, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}) = 0
2886 mprotect(0xb7734000, 4096, PROT_READ) = 0
2886 mprotect(0xb7742000, 4096, PROT_READ) = 0
2886 mprotect(0xb7898000, 8192, PROT_READ) = 0
2886 mprotect(0xb789e000, 143360, PROT_READ|PROT_WRITE) = 0
2886 mprotect(0xb789e000, 143360, PROT_READ|PROT_EXEC) = 0
2886 mprotect(0xb78e2000, 4096, PROT_READ) = 0
2886 set_tid_address(0xb771df08) = 2886
2886 sendto(-1217274096, 0xc, 3077787636, MSG_DONTROUTE|MSG_CTRUNC|MSG_WAITALL|MSG_TRUNC|MSG_CONFIRM|MSG_FIN|MSG_SYN|MSG_RST|MSG_NOSIGNAL|MSG_MORE|0xb7710000, {sa_family=0x3938 /* AF_??? */, sa_data="\216\267\10\0\0\0\224:\216\267\0\0\0\0"}, 3077693088) = 0
2886 futex(0xbff81280, 0x81 /* FUTEX_??? */, 1) = 0
2886 futex(0xbff81280, 0x189 /* FUTEX_??? */, 1) = -1 EAGAIN (Resource temporarily unavailable)
2886 rt_sigaction(SIGRTMIN, {0xb7723400, [], SA_SIGINFO}, NULL, 8) = 0
2886 rt_sigaction(SIGRT_1, {0xb77238f0, [], SA_RESTART|SA_SIGINFO}, NULL, 8) = 0
2886 rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0
2886 getrlimit(RLIMIT_STACK, {rlim_cur=8192*1024, rlim_max=RLIM_INFINITY}) = 0
2886 uname({sys="Linux", node="x86-generic", ...}) = 0
2886 gettimeofday({1280502269, 829088}, NULL) = 0
2886 brk(0) = 0xa034000
2886 brk(0xa055000) = 0xa055000
2886 stat64("/tmp/ust-app-socks", {st_mode=S_IFDIR|0755, st_size=60, ...}) = 0
2886 socket(PF_FILE, SOCK_STREAM, 0) = 3
2886 access("/tmp/ust-app-socks/2886", F_OK) = -1 ENOENT (No such file or directory)
2886 bind(3, {sa_family=AF_FILE, path="/tmp/ust-app-socks/2886"}, 110) = 0
2886 listen(3, 1) = 0
2886 rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 8) = 0
2886 old_mmap(NULL, 8392704, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0) = 0xb6f1c000
2886 mprotect(0xb6f1c000, 4096, PROT_NONE) = 0
2886 clone(child_stack=0xb771bcb4, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID|CLONE_DETACHED, parent_tidptr=0xb771cbd8, {entry_number:6, base_addr:0xb771cb70, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}, child_tidptr=0xb771cbd8) = 2887
2886 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2887 sendto(-1217279008, 0xc, 3077787636, 0, {...}, 3077684408 <unfinished ...>
2886 <... rt_sigprocmask resumed> NULL, 8) = 0
2887 <... sendto resumed> ) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2887 poll( <unfinished ...>
2886 <... rt_sigprocmask resumed> [], 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], [], 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], [], 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 752064404}) = 0
2886 gettimeofday({1280502269, 832627}, NULL) = 0
2886 open("/dev/null", O_RDONLY|O_NONBLOCK|O_DIRECTORY) = -1 ENOTDIR (Not a directory)
2886 open("/sys/devices/system/cpu", O_RDONLY|O_NONBLOCK|O_LARGEFILE|O_DIRECTORY|0x80000) = 4
2886 fcntl64(4, F_GETFD) = 0x1 (flags FD_CLOEXEC)
2886 getdents64(4, /* 13 entries */, 32768) = 400
2886 getdents64(4, /* 0 entries */, 32768) = 0
2886 close(4) = 0
2886 shmget(2886, 4096, IPC_CREAT|IPC_EXCL|0700) = 786432
2886 shmat(786432, 0, 0) = 0xb6f1b000
2886 shmctl(786432, IPC_RMID, 0) = 0
2886 shmget(2886, 4096, IPC_CREAT|IPC_EXCL|0700) = 819201
2886 shmat(819201, 0, 0) = 0xb6f1a000
2886 shmctl(819201, IPC_RMID, 0) = 0
2886 shmget(2886, 2097152, IPC_CREAT|IPC_EXCL|0700) = 851970
2886 shmat(851970, 0, 0) = 0xb6d1a000
2886 shmctl(851970, IPC_RMID, 0) = 0
2886 pipe([4, 5]) = 0
2886 shmget(2886, 2097152, IPC_CREAT|IPC_EXCL|0700) = 884739
2886 shmat(884739, 0, 0) = 0xb6b1a000
2886 shmctl(884739, IPC_RMID, 0) = 0
2886 pipe([6, 7]) = 0
2886 shmget(2886, 4096, IPC_CREAT|IPC_EXCL|0700) = 917508
2886 shmat(917508, 0, 0) = 0xb6b19000
2886 shmctl(917508, IPC_RMID, 0) = 0
2886 shmget(2886, 4096, IPC_CREAT|IPC_EXCL|0700) = 950277
2886 shmat(950277, 0, 0) = 0xb6b18000
2886 shmctl(950277, IPC_RMID, 0) = 0
2886 shmget(2886, 2097152, IPC_CREAT|IPC_EXCL|0700) = 983046
2886 shmat(983046, 0, 0) = 0xb6918000
2886 shmctl(983046, IPC_RMID, 0) = 0
2886 pipe([8, 9]) = 0
2886 shmget(2886, 2097152, IPC_CREAT|IPC_EXCL|0700) = 1015815
2886 shmat(1015815, 0, 0) = 0xb6718000
2886 shmctl(1015815, IPC_RMID, 0) = 0
2886 pipe([10, 11]) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], [], 8) = 0
2886 old_mmap(NULL, 16, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb6717000
2886 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2886 ipc_subcall(0xbff80c10, 0, 0, 0xb78c1800) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 756726730}) = 0
2886 ipc_subcall(0xbff80c10, 0, 0, 0xb78c1830) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 756899378}) = 0
2886 ipc_subcall(0xbff80c10, 0, 0, 0xb78c1800) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 757087740}) = 0
2886 ipc_subcall(0xbff80c10, 0, 0, 0xb78c1830) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 757244394}) = 0
2886 ipc_subcall(0xbff80c10, 0, 0, 0xb78c1800) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 757429963}) = 0
2886 ipc_subcall(0xbff80c10, 0, 0, 0xb78c1830) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 757587175}) = 0
2886 socket(PF_FILE, SOCK_STREAM, 0) = 12
2886 connect(12, {sa_family=AF_FILE, path="/tmp/ustd-sock-2873"}, 110) = 0
2886 send(12, "collect 2886 metadata_0\0", 24, MSG_NOSIGNAL) = 24
2886 close(12) = 0
2886 socket(PF_FILE, SOCK_STREAM, 0) = 12
2886 connect(12, {sa_family=AF_FILE, path="/tmp/ustd-sock-2873"}, 110) = 0
2886 send(12, "collect 2886 metadata_1\0", 24, MSG_NOSIGNAL) = 24
2886 close(12) = 0
2886 socket(PF_FILE, SOCK_STREAM, 0) = 12
2886 connect(12, {sa_family=AF_FILE, path="/tmp/ustd-sock-2873"}, 110 <unfinished ...>
2885 <... open resumed> ) = 3
2882 <... poll resumed> [{fd=3, events=POLLIN, revents=POLLIN}], 1, -1) = 1
2885 read(0, <unfinished ...>
2882 accept(3, <unfinished ...>
2886 <... connect resumed> ) = 0
2886 send(12, "collect 2886 ust_0\0", 19, MSG_NOSIGNAL) = 19
2886 close(12) = 0
2886 socket(PF_FILE, SOCK_STREAM, 0) = 12
2886 connect(12, {sa_family=AF_FILE, path="/tmp/ustd-sock-2873"}, 110 <unfinished ...>
2882 <... accept resumed> 0, NULL) = 4
2882 poll([{fd=3, events=POLLIN, revents=POLLIN}, {fd=4, events=POLLIN, revents=POLLIN|POLLHUP}], 2, -1) = 2
2882 accept(3, <unfinished ...>
2886 <... connect resumed> ) = 0
2886 send(12, "collect 2886 ust_1\0", 19, MSG_NOSIGNAL) = 19
2886 close(12) = 0
2886 ipc_subcall(0xbff80b60, 0, 0, 0xb78c18f0) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 760757970}) = 0
2886 ipc_subcall(0xbff808f0, 0, 0, 0xb78c1860) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 760920351}) = 0
2886 ipc_subcall(0xbff80ab0, 0, 0, 0xb78c1890) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 761111438}) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], [], 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2886 ipc_subcall(0xbff80b60, 0, 0, 0xb78c18f0) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 761512536}) = 0
2886 ipc_subcall(0xbff80ab0, 0, 0, 0xb78c1890) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 761672892}) = 0
2886 ipc_subcall(0xbff808f0, 0, 0, 0xb78c1860) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 761831292}) = 0
2886 ipc_subcall(0xbff80ab0, 0, 0, 0xb78c1890) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 761986619}) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], [], 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2886 ipc_subcall(0xbff80b60, 0, 0, 0xb78c18f0) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 762396168}) = 0
2886 ipc_subcall(0xbff80ab0, 0, 0, 0xb78c1890) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 762557083}) = 0
2886 ipc_subcall(0xbff80ab0, 0, 0, 0xb78c1890) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 762715203}) = 0
2886 ipc_subcall(0xbff808f0, 0, 0, 0xb78c1860) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 762873324}) = 0
2886 ipc_subcall(0xbff80ab0, 0, 0, 0xb78c1890) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 763059103}) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], [], 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2886 ipc_subcall(0xbff80e10, 0, 0, 0xb78c1890) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 763450004}) = 0
2886 ipc_subcall(0xbff80e10, 0, 0, 0xb78c1890) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 763608614}) = 0
2886 ipc_subcall(0xbff80e10, 0, 0, 0xb78c1890) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 763766804}) = 0
2886 ipc_subcall(0xbff80ee0, 0, 0, 0x8049c38) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 763935192}) = 0
2886 ipc_subcall(0xbff80ee0, 0, 0, 0x8049c68) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 764122995}) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], [], 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], [], 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], [], 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], [], 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], [], 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], [], 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2886 nanosleep({0, 200000000}, <unfinished ...>
2882 <... accept resumed> 0, NULL) = 5
2882 recv(4, "collect 2886 metadata_0\0", 1000, 0) = 24
2882 old_mmap(NULL, 8392704, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0) = 0xb6e41000
2882 mprotect(0xb6e41000, 4096, PROT_NONE) = 0
2882 clone(child_stack=0xb7641484, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID|CLONE_DETACHED, parent_tidptr=0xb7641bd8, {entry_number:6, base_addr:0xb7641b70, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}, child_tidptr=0xb7641bd8) = 2888
2882 poll( <unfinished ...>
2888 sendto(-1218176032, 0xc, 3078254580, 0, {...}, 3076789384 <unfinished ...>
2882 <... poll resumed> [{fd=3, events=POLLIN, revents=POLLIN}, {fd=5, events=POLLIN, revents=POLLIN|POLLHUP}, {fd=4, events=POLLIN, revents=POLLIN|POLLHUP}], 3, -1) = 3
2888 <... sendto resumed> ) = 0
2882 accept(3, <unfinished ...>
2888 rt_sigprocmask(SIG_BLOCK, [INT TERM], <unfinished ...>
2882 <... accept resumed> 0, NULL) = 6
2888 <... rt_sigprocmask resumed> NULL, 8) = 0
2882 recv(5, <unfinished ...>
2888 socket(PF_FILE, SOCK_STREAM, 0 <unfinished ...>
2882 <... recv resumed> "collect 2886 metadata_1\0", 1000, 0) = 24
2888 <... socket resumed> ) = 7
2882 old_mmap(NULL, 8392704, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0 <unfinished ...>
2888 connect(7, {sa_family=AF_FILE, path="/tmp/ust-app-socks/2886"}, 110 <unfinished ...>
2882 <... old_mmap resumed> ) = 0xb6640000
2888 <... connect resumed> ) = 0
2887 <... poll resumed> [{fd=3, events=POLLIN, revents=POLLIN}], 1, -1) = 1
2888 send(7, "get_pidunique\0", 14, MSG_NOSIGNAL <unfinished ...>
2887 accept(3, <unfinished ...>
2888 <... send resumed> ) = 14
2887 <... accept resumed> 0, NULL) = 12
2888 recv(7, <unfinished ...>
2887 poll( <unfinished ...>
2882 mprotect(0xb6640000, 4096, PROT_NONE <unfinished ...>
2887 <... poll resumed> [{fd=3, events=POLLIN}, {fd=12, events=POLLIN, revents=POLLIN}], 2, -1) = 1
2882 <... mprotect resumed> ) = 0
2887 recv(12, <unfinished ...>
2882 clone( <unfinished ...>
2887 <... recv resumed> "get_pidunique\0", 1000, 0) = 14
2882 <... clone resumed> child_stack=0xb6e40484, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID|CLONE_DETACHED, parent_tidptr=0xb6e40bd8, {entry_number:6, base_addr:0xb6e40b70, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}, child_tidptr=0xb6e40bd8) = 2889
2889 sendto(-1226568736, 0xc, 3078254580, 0, {...}, 3068396680 <unfinished ...>
2887 send(12, "5499715367809623712\0", 20, MSG_NOSIGNAL <unfinished ...>
2889 <... sendto resumed> ) = 0
2888 <... recv resumed> "5499715367809623712\0", 1000, 0) = 20
2888 send(7, "get_shmid metadata_0\0", 21, MSG_NOSIGNAL <unfinished ...>
2889 rt_sigprocmask(SIG_BLOCK, [INT TERM], <unfinished ...>
2888 <... send resumed> ) = 21
2889 <... rt_sigprocmask resumed> NULL, 8) = 0
2888 recv(7, <unfinished ...>
2889 socket(PF_FILE, SOCK_STREAM, 0 <unfinished ...>
2887 <... send resumed> ) = 20
2889 <... socket resumed> ) = 8
2887 poll( <unfinished ...>
2889 connect(8, {sa_family=AF_FILE, path="/tmp/ust-app-socks/2886"}, 110 <unfinished ...>
2887 <... poll resumed> [{fd=3, events=POLLIN}, {fd=12, events=POLLIN, revents=POLLIN}], 2, -1) = 1
2889 <... connect resumed> ) = 0
2887 recv(12, <unfinished ...>
2889 send(8, "get_pidunique\0", 14, MSG_NOSIGNAL <unfinished ...>
2887 <... recv resumed> "get_shmid metadata_0\0", 1000, 0) = 21
2889 <... send resumed> ) = 14
2882 poll( <unfinished ...>
2889 recv(8, <unfinished ...>
2887 send(12, "851970 786432\0", 14, MSG_NOSIGNAL <unfinished ...>
2882 <... poll resumed> [{fd=3, events=POLLIN, revents=POLLIN}, {fd=6, events=POLLIN, revents=POLLIN|POLLHUP}, {fd=5, events=POLLIN, revents=POLLIN|POLLHUP}, {fd=4, events=POLLIN, revents=POLLIN|POLLHUP}], 4, -1) = 4
2888 <... recv resumed> "851970 786432\0", 1000, 0) = 14
2887 <... send resumed> ) = 14
2888 send(7, "get_n_subbufs metadata_0\0", 25, MSG_NOSIGNAL <unfinished ...>
2887 poll( <unfinished ...>
2888 <... send resumed> ) = 25
2887 <... poll resumed> [{fd=3, events=POLLIN, revents=POLLIN}, {fd=12, events=POLLIN, revents=POLLIN}], 2, -1) = 2
2888 recv(7, <unfinished ...>
2887 accept(3, <unfinished ...>
2882 accept(3, <unfinished ...>
2887 <... accept resumed> 0, NULL) = 13
2882 <... accept resumed> 0, NULL) = 9
2887 recv(12, <unfinished ...>
2882 recv(6, <unfinished ...>
2887 <... recv resumed> "get_n_subbufs metadata_0\0", 1000, 0) = 25
2882 <... recv resumed> "collect 2886 ust_0\0", 1000, 0) = 19
2887 send(12, "2\0", 2, MSG_NOSIGNAL <unfinished ...>
2882 old_mmap(NULL, 8392704, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0 <unfinished ...>
2888 <... recv resumed> "2\0", 1000, 0) = 2
2887 <... send resumed> ) = 2
2888 send(7, "get_subbuf_size metadata_0\0", 27, MSG_NOSIGNAL <unfinished ...>
2887 poll( <unfinished ...>
2888 <... send resumed> ) = 27
2887 <... poll resumed> [{fd=3, events=POLLIN}, {fd=13, events=POLLIN, revents=POLLIN}, {fd=12, events=POLLIN, revents=POLLIN}], 3, -1) = 2
2888 recv(7, <unfinished ...>
2887 recv(13, <unfinished ...>
2882 <... old_mmap resumed> ) = 0xb5e3f000
2887 <... recv resumed> "get_pidunique\0", 1000, 0) = 14
2882 mprotect(0xb5e3f000, 4096, PROT_NONE <unfinished ...>
2887 send(13, "5499715367809623712\0", 20, MSG_NOSIGNAL <unfinished ...>
2882 <... mprotect resumed> ) = 0
2889 <... recv resumed> "5499715367809623712\0", 1000, 0) = 20
2887 <... send resumed> ) = 20
2889 send(8, "get_shmid metadata_1\0", 21, MSG_NOSIGNAL <unfinished ...>
2887 recv(12, <unfinished ...>
2889 <... send resumed> ) = 21
2887 <... recv resumed> "get_subbuf_size metadata_0\0", 1000, 0) = 27
2889 recv(8, <unfinished ...>
2887 send(12, "1048576\0", 8, MSG_NOSIGNAL <unfinished ...>
2888 <... recv resumed> "1048576\0", 1000, 0) = 8
2888 shmat(851970, 0, 0) = 0xb5c3f000
2888 shmat(786432, 0, 0) = 0xb5c3e000
2888 getuid32() = 0
2888 shmctl(851970, IPC_64|IPC_STAT, 0xb7641070) = 0
2888 mkdir("/home/root/.usttraces/x86-generic-20100730150429647915588/2886_5499715367809623712", 0777 <unfinished ...>
2887 <... send resumed> ) = 8
2887 poll([{fd=3, events=POLLIN}, {fd=13, events=POLLIN, revents=POLLIN}, {fd=12, events=POLLIN}], 3, -1) = 1
2887 recv(13, "get_shmid metadata_1\0", 1000, 0) = 21
2887 send(13, "884739 819201\0", 14, MSG_NOSIGNAL <unfinished ...>
2889 <... recv resumed> "884739 819201\0", 1000, 0) = 14
2887 <... send resumed> ) = 14
2889 send(8, "get_n_subbufs metadata_1\0", 25, MSG_NOSIGNAL <unfinished ...>
2887 poll( <unfinished ...>
2889 <... send resumed> ) = 25
2887 <... poll resumed> [{fd=3, events=POLLIN}, {fd=13, events=POLLIN, revents=POLLIN}, {fd=12, events=POLLIN}], 3, -1) = 1
2889 recv(8, <unfinished ...>
2887 recv(13, "get_n_subbufs metadata_1\0", 1000, 0) = 25
2887 send(13, "2\0", 2, MSG_NOSIGNAL <unfinished ...>
2889 <... recv resumed> "2\0", 1000, 0) = 2
2887 <... send resumed> ) = 2
2889 send(8, "get_subbuf_size metadata_1\0", 27, MSG_NOSIGNAL <unfinished ...>
2887 poll( <unfinished ...>
2889 <... send resumed> ) = 27
2887 <... poll resumed> [{fd=3, events=POLLIN}, {fd=13, events=POLLIN, revents=POLLIN}, {fd=12, events=POLLIN}], 3, -1) = 1
2889 recv(8, <unfinished ...>
2887 recv(13, "get_subbuf_size metadata_1\0", 1000, 0) = 27
2887 send(13, "1048576\0", 8, MSG_NOSIGNAL <unfinished ...>
2889 <... recv resumed> "1048576\0", 1000, 0) = 8
2889 shmat(884739, 0, 0) = 0xb5a3e000
2889 shmat(819201, 0, 0) = 0xb5a3d000
2889 shmctl(884739, IPC_64|IPC_STAT, 0xb6e40070) = 0
2889 mkdir("/home/root/.usttraces/x86-generic-20100730150429647915588/2886_5499715367809623712", 0777 <unfinished ...>
2887 <... send resumed> ) = 8
2887 poll( <unfinished ...>
2882 clone(child_stack=0xb663f484, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID|CLONE_DETACHED, parent_tidptr=0xb663fbd8, {entry_number:6, base_addr:0xb663fb70, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}, child_tidptr=0xb663fbd8) = 2890
2882 poll( <unfinished ...>
2890 sendto(-1234961440, 0xc, 3078254580, 0, {...}, 3060003976 <unfinished ...>
2882 <... poll resumed> [{fd=3, events=POLLIN}, {fd=9, events=POLLIN, revents=POLLIN|POLLHUP}, {fd=6, events=POLLIN, revents=POLLIN|POLLHUP}, {fd=5, events=POLLIN, revents=POLLIN|POLLHUP}, {fd=4, events=POLLIN, revents=POLLIN|POLLHUP}], 5, -1) = 4
2890 <... sendto resumed> ) = 0
2890 rt_sigprocmask(SIG_BLOCK, [INT TERM], <unfinished ...>
2882 recv(9, <unfinished ...>
2890 <... rt_sigprocmask resumed> NULL, 8) = 0
2882 <... recv resumed> "collect 2886 ust_1\0", 1000, 0) = 19
2890 socket(PF_FILE, SOCK_STREAM, 0 <unfinished ...>
2882 old_mmap(NULL, 2097152, PROT_NONE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_NORESERVE, -1, 0 <unfinished ...>
2890 <... socket resumed> ) = 10
2882 <... old_mmap resumed> ) = 0xb583d000
2890 connect(10, {sa_family=AF_FILE, path="/tmp/ust-app-socks/2886"}, 110 <unfinished ...>
2882 munmap(0xb583d000, 798720) = 0
2882 munmap(0xb5a00000, 249856 <unfinished ...>
2890 <... connect resumed> ) = 0
2887 <... poll resumed> [{fd=3, events=POLLIN, revents=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN}], 3, -1) = 1
2890 send(10, "get_pidunique\0", 14, MSG_NOSIGNAL <unfinished ...>
2887 accept(3, <unfinished ...>
2890 <... send resumed> ) = 14
2887 <... accept resumed> 0, NULL) = 14
2890 recv(10, <unfinished ...>
2887 poll( <unfinished ...>
2882 <... munmap resumed> ) = 0
2887 <... poll resumed> [{fd=3, events=POLLIN}, {fd=14, events=POLLIN, revents=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN}], 4, -1) = 1
2882 mprotect(0xb5900000, 135168, PROT_READ|PROT_WRITE <unfinished ...>
2887 recv(14, <unfinished ...>
2882 <... mprotect resumed> ) = 0
2887 <... recv resumed> "get_pidunique\0", 1000, 0) = 14
2887 send(14, "5499715367809623712\0", 20, MSG_NOSIGNAL <unfinished ...>
2890 <... recv resumed> "5499715367809623712\0", 1000, 0) = 20
2887 <... send resumed> ) = 20
2890 send(10, "get_shmid ust_0\0", 16, MSG_NOSIGNAL <unfinished ...>
2887 poll( <unfinished ...>
2890 <... send resumed> ) = 16
2887 <... poll resumed> [{fd=3, events=POLLIN}, {fd=14, events=POLLIN, revents=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN}], 4, -1) = 1
2890 recv(10, <unfinished ...>
2887 recv(14, "get_shmid ust_0\0", 1000, 0) = 16
2887 send(14, "983046 917508\0", 14, MSG_NOSIGNAL <unfinished ...>
2890 <... recv resumed> "983046 917508\0", 1000, 0) = 14
2887 <... send resumed> ) = 14
2890 send(10, "get_n_subbufs ust_0\0", 20, MSG_NOSIGNAL <unfinished ...>
2887 poll( <unfinished ...>
2890 <... send resumed> ) = 20
2887 <... poll resumed> [{fd=3, events=POLLIN}, {fd=14, events=POLLIN, revents=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN}], 4, -1) = 1
2890 recv(10, <unfinished ...>
2887 recv(14, "get_n_subbufs ust_0\0", 1000, 0) = 20
2887 send(14, "2\0", 2, MSG_NOSIGNAL <unfinished ...>
2890 <... recv resumed> "2\0", 1000, 0) = 2
2887 <... send resumed> ) = 2
2890 send(10, "get_subbuf_size ust_0\0", 22, MSG_NOSIGNAL <unfinished ...>
2887 poll( <unfinished ...>
2890 <... send resumed> ) = 22
2887 <... poll resumed> [{fd=3, events=POLLIN}, {fd=14, events=POLLIN, revents=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN}], 4, -1) = 1
2890 recv(10, <unfinished ...>
2887 recv(14, "get_subbuf_size ust_0\0", 1000, 0) = 22
2887 send(14, "1048576\0", 8, MSG_NOSIGNAL <unfinished ...>
2890 <... recv resumed> "1048576\0", 1000, 0) = 8
2887 <... send resumed> ) = 8
2890 shmat( <unfinished ...>
2887 poll( <unfinished ...>
2890 <... shmat resumed> 983046, 0, 0) = 0xb5700000
2882 old_mmap(NULL, 8392704, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0 <unfinished ...>
2890 shmat( <unfinished ...>
2882 <... old_mmap resumed> ) = 0xb4eff000
2882 mprotect(0xb4eff000, 4096, PROT_NONE <unfinished ...>
2890 <... shmat resumed> 917508, 0, 0) = 0xb5a3c000
2882 <... mprotect resumed> ) = 0
2890 shmctl(983046, IPC_64|IPC_STAT, 0xb663f070 <unfinished ...>
2882 clone( <unfinished ...>
2890 <... shmctl resumed> ) = 0
2882 <... clone resumed> child_stack=0xb56ff484, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID|CLONE_DETACHED, parent_tidptr=0xb56ffbd8, {entry_number:6, base_addr:0xb56ffb70, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}, child_tidptr=0xb56ffbd8) = 2891
2891 sendto(-1250952224, 0xc, 3078254580, 0, {...}, 3044013192 <unfinished ...>
2890 mkdir("/home/root/.usttraces/x86-generic-20100730150429647915588/2886_5499715367809623712", 0777 <unfinished ...>
2891 <... sendto resumed> ) = 0
2882 poll( <unfinished ...>
2891 rt_sigprocmask(SIG_BLOCK, [INT TERM], <unfinished ...>
2882 <... poll resumed> [{fd=3, events=POLLIN}, {fd=9, events=POLLIN, revents=POLLIN|POLLHUP}, {fd=6, events=POLLIN, revents=POLLIN|POLLHUP}, {fd=5, events=POLLIN, revents=POLLIN|POLLHUP}, {fd=4, events=POLLIN, revents=POLLIN|POLLHUP}], 5, -1) = 4
2891 <... rt_sigprocmask resumed> NULL, 8) = 0
2882 recv(9, <unfinished ...>
2891 socket(PF_FILE, SOCK_STREAM, 0 <unfinished ...>
2882 <... recv resumed> "", 1000, 0) = 0
2891 <... socket resumed> ) = 11
2882 close(9 <unfinished ...>
2891 connect(11, {sa_family=AF_FILE, path="/tmp/ust-app-socks/2886"}, 110 <unfinished ...>
2882 <... close resumed> ) = 0
2882 recv(6, <unfinished ...>
2891 <... connect resumed> ) = 0
2887 <... poll resumed> [{fd=3, events=POLLIN, revents=POLLIN}, {fd=14, events=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN}], 4, -1) = 1
2891 send(11, "get_pidunique\0", 14, MSG_NOSIGNAL <unfinished ...>
2887 accept(3, <unfinished ...>
2891 <... send resumed> ) = 14
2887 <... accept resumed> 0, NULL) = 15
2891 recv(11, <unfinished ...>
2887 poll( <unfinished ...>
2882 <... recv resumed> "", 1000, 0) = 0
2887 <... poll resumed> [{fd=3, events=POLLIN}, {fd=15, events=POLLIN, revents=POLLIN}, {fd=14, events=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN}], 5, -1) = 1
2882 close(6 <unfinished ...>
2887 recv(15, <unfinished ...>
2882 <... close resumed> ) = 0
2887 <... recv resumed> "get_pidunique\0", 1000, 0) = 14
2882 recv(5, <unfinished ...>
2887 send(15, "5499715367809623712\0", 20, MSG_NOSIGNAL <unfinished ...>
2882 <... recv resumed> "", 1000, 0) = 0
2891 <... recv resumed> "5499715367809623712\0", 1000, 0) = 20
2887 <... send resumed> ) = 20
2891 send(11, "get_shmid ust_1\0", 16, MSG_NOSIGNAL <unfinished ...>
2887 poll( <unfinished ...>
2891 <... send resumed> ) = 16
2887 <... poll resumed> [{fd=3, events=POLLIN}, {fd=15, events=POLLIN, revents=POLLIN}, {fd=14, events=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN}], 5, -1) = 1
2891 recv(11, <unfinished ...>
2887 recv(15, <unfinished ...>
2882 close(5 <unfinished ...>
2887 <... recv resumed> "get_shmid ust_1\0", 1000, 0) = 16
2882 <... close resumed> ) = 0
2887 send(15, "1015815 950277\0", 15, MSG_NOSIGNAL <unfinished ...>
2882 recv(4, <unfinished ...>
2891 <... recv resumed> "1015815 950277\0", 1000, 0) = 15
2887 <... send resumed> ) = 15
2891 send(11, "get_n_subbufs ust_1\0", 20, MSG_NOSIGNAL <unfinished ...>
2887 poll( <unfinished ...>
2891 <... send resumed> ) = 20
2887 <... poll resumed> [{fd=3, events=POLLIN}, {fd=15, events=POLLIN, revents=POLLIN}, {fd=14, events=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN}], 5, -1) = 1
2891 recv(11, <unfinished ...>
2887 recv(15, <unfinished ...>
2882 <... recv resumed> "", 1000, 0) = 0
2887 <... recv resumed> "get_n_subbufs ust_1\0", 1000, 0) = 20
2882 close(4 <unfinished ...>
2887 send(15, "2\0", 2, MSG_NOSIGNAL <unfinished ...>
2882 <... close resumed> ) = 0
2891 <... recv resumed> "2\0", 1000, 0) = 2
2887 <... send resumed> ) = 2
2891 send(11, "get_subbuf_size ust_1\0", 22, MSG_NOSIGNAL <unfinished ...>
2887 poll( <unfinished ...>
2891 <... send resumed> ) = 22
2887 <... poll resumed> [{fd=3, events=POLLIN}, {fd=15, events=POLLIN, revents=POLLIN}, {fd=14, events=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN}], 5, -1) = 1
2891 recv(11, <unfinished ...>
2887 recv(15, <unfinished ...>
2882 poll( <unfinished ...>
2887 <... recv resumed> "get_subbuf_size ust_1\0", 1000, 0) = 22
2887 send(15, "1048576\0", 8, MSG_NOSIGNAL <unfinished ...>
2891 <... recv resumed> "1048576\0", 1000, 0) = 8
2887 <... send resumed> ) = 8
2891 shmat( <unfinished ...>
2887 poll( <unfinished ...>
2891 <... shmat resumed> 1015815, 0, 0) = 0xb4cff000
2891 shmat(950277, 0, 0) = 0xb5a3b000
2891 shmctl(1015815, IPC_64|IPC_STAT, 0xb56ff070) = 0
2891 mkdir("/home/root/.usttraces/x86-generic-20100730150429647915588/2886_5499715367809623712", 0777 <unfinished ...>
2888 <... mkdir resumed> ) = 0
2888 open("/home/root/.usttraces/x86-generic-20100730150429647915588/2886_5499715367809623712/metadata_0", O_WRONLY|O_CREAT|O_TRUNC|O_EXCL, 0600 <unfinished ...>
2889 <... mkdir resumed> ) = -1 EEXIST (File exists)
2891 <... mkdir resumed> ) = -1 EEXIST (File exists)
2890 <... mkdir resumed> ) = -1 EEXIST (File exists)
2891 open("/home/root/.usttraces/x86-generic-20100730150429647915588/2886_5499715367809623712/ust_1", O_WRONLY|O_CREAT|O_TRUNC|O_EXCL, 0600 <unfinished ...>
2890 open("/home/root/.usttraces/x86-generic-20100730150429647915588/2886_5499715367809623712/ust_0", O_WRONLY|O_CREAT|O_TRUNC|O_EXCL, 0600 <unfinished ...>
2889 open("/home/root/.usttraces/x86-generic-20100730150429647915588/2886_5499715367809623712/metadata_1", O_WRONLY|O_CREAT|O_TRUNC|O_EXCL, 0600 <unfinished ...>
2891 <... open resumed> ) = 5
2891 send(11, "get_subbuffer ust_1\0", 20, MSG_NOSIGNAL) = 20
2887 <... poll resumed> [{fd=3, events=POLLIN}, {fd=15, events=POLLIN, revents=POLLIN}, {fd=14, events=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN}], 5, -1) = 1
2891 recv(11, <unfinished ...>
2887 recv(15, "get_subbuffer ust_1\0", 1000, 0) = 20
2887 poll( <unfinished ...>
2888 <... open resumed> ) = 4
2888 send(7, "get_subbuffer metadata_0\0", 25, MSG_NOSIGNAL) = 25
2888 recv(7, <unfinished ...>
2887 <... poll resumed> [{fd=10, events=POLLIN}, {fd=3, events=POLLIN}, {fd=15, events=POLLIN}, {fd=14, events=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN, revents=POLLIN}], 6, -1) = 1
2887 recv(12, "get_subbuffer metadata_0\0", 1000, 0) = 25
2887 poll( <unfinished ...>
2890 <... open resumed> ) = 6
2890 send(10, "get_subbuffer ust_0\0", 20, MSG_NOSIGNAL) = 20
2890 recv(10, <unfinished ...>
2887 <... poll resumed> [{fd=4, events=POLLIN}, {fd=10, events=POLLIN}, {fd=3, events=POLLIN}, {fd=15, events=POLLIN}, {fd=14, events=POLLIN, revents=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN}], 7, -1) = 1
2887 recv(14, "get_subbuffer ust_0\0", 1000, 0) = 20
2887 poll( <unfinished ...>
2889 <... open resumed> ) = 9
2889 send(8, "get_subbuffer metadata_1\0", 25, MSG_NOSIGNAL) = 25
2889 recv(8, <unfinished ...>
2887 <... poll resumed> [{fd=8, events=POLLIN}, {fd=4, events=POLLIN}, {fd=10, events=POLLIN}, {fd=3, events=POLLIN}, {fd=15, events=POLLIN}, {fd=14, events=POLLIN}, {fd=13, events=POLLIN, revents=POLLIN}, {fd=12, events=POLLIN}], 8, -1) = 1
2887 recv(13, "get_subbuffer metadata_1\0", 1000, 0) = 25
2887 poll( <unfinished ...>
2886 <... nanosleep resumed> {0, 200000000}) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], [], 8) = 0
2886 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2886 clock_gettime(CLOCK_MONOTONIC, {5692, 966063971}) = 0
2886 write(5, "1", 1) = 1
2887 <... poll resumed> [{fd=6, events=POLLIN}, {fd=8, events=POLLIN}, {fd=4, events=POLLIN, revents=POLLIN}, {fd=10, events=POLLIN}, {fd=3, events=POLLIN}, {fd=15, events=POLLIN}, {fd=14, events=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN}], 9, -1) = 1
2886 write(5, "1", 1 <unfinished ...>
2887 read(4, <unfinished ...>
2886 <... write resumed> ) = 1
2887 <... read resumed> "1", 1) = 1
2886 close(5 <unfinished ...>
2887 send(12, "OK 0\0", 5, MSG_NOSIGNAL <unfinished ...>
2886 <... close resumed> ) = 0
2888 <... recv resumed> "OK 0\0", 1000, 0) = 5
2887 <... send resumed> ) = 5
2886 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
2888 lseek(4, 0, SEEK_CUR <unfinished ...>
2887 poll( <unfinished ...>
2888 <... lseek resumed> ) = 0
2888 write(4, "\224\357vr-\5\0\0cO8\177-\5\0\0\355\267\326\0\2\6\4\0\375"..., 4096 <unfinished ...>
2886 <... clock_gettime resumed> {5692, 966881393}) = 0
2888 <... write resumed> ) = 4096
2886 write(7, "1", 1 <unfinished ...>
2888 send(7, "put_subbuffer metadata_0 0\0", 27, MSG_NOSIGNAL <unfinished ...>
2887 <... poll resumed> [{fd=6, events=POLLIN, revents=POLLIN}, {fd=8, events=POLLIN}, {fd=10, events=POLLIN}, {fd=3, events=POLLIN}, {fd=15, events=POLLIN}, {fd=14, events=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN}], 8, -1) = 1
2888 <... send resumed> ) = 27
2887 read(6, <unfinished ...>
2888 recv(7, <unfinished ...>
2887 <... read resumed> "1", 1) = 1
2886 <... write resumed> ) = 1
2887 send(13, "OK 0\0", 5, MSG_NOSIGNAL <unfinished ...>
2886 write(7, "1", 1 <unfinished ...>
2889 <... recv resumed> "OK 0\0", 1000, 0) = 5
2887 <... send resumed> ) = 5
2889 lseek(9, 0, SEEK_CUR) = 0
2887 poll( <unfinished ...>
2889 write(9, "\224\357vr-\5\0\0q\310D\177-\5\0\0\355\267\326\0\2\6\4"..., 4096 <unfinished ...>
2887 <... poll resumed> [{fd=8, events=POLLIN}, {fd=10, events=POLLIN}, {fd=3, events=POLLIN}, {fd=15, events=POLLIN}, {fd=14, events=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN, revents=POLLIN}], 7, -1) = 1
2889 <... write resumed> ) = 4096
2887 recv(12, <unfinished ...>
2889 send(8, "put_subbuffer metadata_1 0\0", 27, MSG_NOSIGNAL <unfinished ...>
2887 <... recv resumed> "put_subbuffer metadata_0 0\0", 1000, 0) = 27
2889 <... send resumed> ) = 27
2887 send(12, "NOTFOUND\0", 9, MSG_NOSIGNAL <unfinished ...>
2889 recv(8, <unfinished ...>
2888 <... recv resumed> "NOTFOUND\0", 1000, 0) = 9
2887 <... send resumed> ) = 9
2888 ftruncate(4, 0 <unfinished ...>
2887 poll( <unfinished ...>
2886 <... write resumed> ) = 1
2887 <... poll resumed> [{fd=8, events=POLLIN}, {fd=10, events=POLLIN}, {fd=3, events=POLLIN}, {fd=15, events=POLLIN}, {fd=14, events=POLLIN}, {fd=13, events=POLLIN, revents=POLLIN}, {fd=12, events=POLLIN}], 7, -1) = 1
2886 close(7 <unfinished ...>
2887 recv(13, <unfinished ...>
2886 <... close resumed> ) = 0
2887 <... recv resumed> "put_subbuffer metadata_1 0\0", 1000, 0) = 27
2886 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
2887 send(13, "NOTFOUND\0", 9, MSG_NOSIGNAL <unfinished ...>
2886 <... clock_gettime resumed> {5692, 969519160}) = 0
2889 <... recv resumed> "NOTFOUND\0", 1000, 0) = 9
2887 <... send resumed> ) = 9
2889 ftruncate(9, 0 <unfinished ...>
2887 poll( <unfinished ...>
2886 write(9, "1", 1 <unfinished ...>
2887 <... poll resumed> [{fd=8, events=POLLIN, revents=POLLIN}, {fd=10, events=POLLIN}, {fd=3, events=POLLIN}, {fd=15, events=POLLIN}, {fd=14, events=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN}], 7, -1) = 1
2886 <... write resumed> ) = 1
2887 read(8, <unfinished ...>
2886 write(9, "1", 1 <unfinished ...>
2887 <... read resumed> "1", 1) = 1
2886 <... write resumed> ) = 1
2887 send(14, "OK 0\0", 5, MSG_NOSIGNAL <unfinished ...>
2886 close(9 <unfinished ...>
2890 <... recv resumed> "OK 0\0", 1000, 0) = 5
2887 <... send resumed> ) = 5
2886 <... close resumed> ) = 0
2890 lseek(6, 0, SEEK_CUR <unfinished ...>
2887 poll( <unfinished ...>
2890 <... lseek resumed> ) = 0
2886 clock_gettime(CLOCK_MONOTONIC, <unfinished ...>
2890 write(6, "\224\357vr-\5\0\0008\10m\177-\5\0\0\355\267\326\0\2\6\4"..., 4096 <unfinished ...>
2886 <... clock_gettime resumed> {5692, 970710933}) = 0
2890 <... write resumed> ) = 4096
2886 write(11, "1", 1 <unfinished ...>
2890 send(10, "put_subbuffer ust_0 0\0", 22, MSG_NOSIGNAL <unfinished ...>
2887 <... poll resumed> [{fd=10, events=POLLIN, revents=POLLIN}, {fd=3, events=POLLIN}, {fd=15, events=POLLIN}, {fd=14, events=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN}], 6, -1) = 1
2890 <... send resumed> ) = 22
2887 read(10, <unfinished ...>
2890 recv(10, <unfinished ...>
2887 <... read resumed> "1", 1) = 1
2886 <... write resumed> ) = 1
2887 send(15, "OK 0\0", 5, MSG_NOSIGNAL <unfinished ...>
2886 write(11, "1", 1 <unfinished ...>
2891 <... recv resumed> "OK 0\0", 1000, 0) = 5
2887 <... send resumed> ) = 5
2886 <... write resumed> ) = 1
2891 lseek(5, 0, SEEK_CUR <unfinished ...>
2887 poll( <unfinished ...>
2891 <... lseek resumed> ) = 0
2887 <... poll resumed> [{fd=3, events=POLLIN}, {fd=15, events=POLLIN}, {fd=14, events=POLLIN, revents=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN}], 5, -1) = 1
2891 write(5, "\224\357vr-\5\0\0\2257\177\177-\5\0\0\355\267\326\0\2\6"..., 4096 <unfinished ...>
2887 recv(14, <unfinished ...>
2891 <... write resumed> ) = 4096
2887 <... recv resumed> "put_subbuffer ust_0 0\0", 1000, 0) = 22
2891 send(11, "put_subbuffer ust_1 0\0", 22, MSG_NOSIGNAL <unfinished ...>
2887 send(14, "NOTFOUND\0", 9, MSG_NOSIGNAL <unfinished ...>
2891 <... send resumed> ) = 22
2890 <... recv resumed> "NOTFOUND\0", 1000, 0) = 9
2891 recv(11, <unfinished ...>
2890 ftruncate(6, 0 <unfinished ...>
2887 <... send resumed> ) = 9
2886 close(11) = 0
2887 poll( <unfinished ...>
2886 open("/lib/libgcc_s.so.1", O_RDONLY <unfinished ...>
2887 <... poll resumed> [{fd=3, events=POLLIN}, {fd=15, events=POLLIN, revents=POLLIN}, {fd=14, events=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN}], 5, -1) = 1
2887 recv(15, "put_subbuffer ust_1 0\0", 1000, 0) = 22
2887 send(15, "NOTFOUND\0", 9, MSG_NOSIGNAL <unfinished ...>
2891 <... recv resumed> "NOTFOUND\0", 1000, 0) = 9
2891 ftruncate(5, 0 <unfinished ...>
2887 <... send resumed> ) = 9
2887 poll( <unfinished ...>
2886 <... open resumed> ) = 5
2886 read(5, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0p\36\0\000"..., 512) = 512
2886 fstat64(5, {st_mode=S_IFREG|0755, st_size=112344, ...}) = 0
2886 old_mmap(NULL, 111400, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 5, 0) = 0xb66fb000
2886 old_mmap(0xb6716000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 5, 0x1b000) = 0xb6716000
2886 close(5) = 0
2886 tgkill(2886, 2887, SIGRTMIN) = 0
2887 <... poll resumed> [{fd=3, events=POLLIN}, {fd=15, events=POLLIN}, {fd=14, events=POLLIN}, {fd=13, events=POLLIN}, {fd=12, events=POLLIN}], 5, -1) = ? ERESTART_RESTARTBLOCK (To be restarted)
2886 futex(0xb771cbd8, FUTEX_WAIT, 2887, NULL <unfinished ...>
2887 --- SIGRTMIN (Unknown signal 32) @ 0 (0) ---
2887 futex(0xb67161e8, 0x81 /* FUTEX_??? */, 2147483647) = 0
2887 stat64("/tmp/ust-app-socks/2886", {st_mode=S_IFSOCK|0755, st_size=0, ...}) = 0
2887 unlink("/tmp/ust-app-socks/2886") = 0
2887 close(3) = 0
2887 madvise(0xb6f1c000, 8368128, 0x4 /* MADV_??? */) = 0
2887 _exit(0) = ?
2886 <... futex resumed> ) = 0
2886 munmap(0xb6717000, 0) = -1 EINVAL (Invalid argument)
2886 exit_group(0) = ?
2884 <... waitpid resumed> [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0) = 2886
2884 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2884 --- SIGCHLD (Child exited) @ 0 (0) ---
2884 waitpid(-1, 0xbfca96dc, WNOHANG) = -1 ECHILD (No child processes)
2884 sigreturn() = ? (mask now [])
2884 rt_sigaction(SIGINT, {0x80900e0, [], 0}, {0x807b370, [], 0}, 8) = 0
2884 exit_group(0) = ?
2885 <... read resumed> "", 8192) = 0
2885 close(3) = 0
2885 close(0) = 0
2885 close(1) = 0
2885 exit_group(0) = ?
2873 <... waitpid resumed> [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0) = 2884
2873 waitpid(-1, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0) = 2885
2873 rt_sigprocmask(SIG_SETMASK, [CHLD], NULL, 8) = 0
2873 rt_sigaction(SIGINT, {0x808e370, [], 0}, {0x807b370, [], 0}, 8) = 0
2873 close(3) = -1 EBADF (Bad file descriptor)
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 --- SIGCHLD (Child exited) @ 0 (0) ---
2873 waitpid(-1, 0xbfca99ec, WNOHANG) = 0
2873 sigreturn() = ? (mask now [])
2873 read(255, "\n## Because of the keepalive mec"..., 4496) = 418
2873 kill(2882, SIGTERM) = 0
2882 <... poll resumed> [{fd=3, events=POLLIN}], 1, -1) = ? ERESTART_RESTARTBLOCK (To be restarted)
2873 rt_sigprocmask(SIG_BLOCK, ~[RTMIN RT_1], <unfinished ...>
2882 --- SIGTERM (Terminated) @ 0 (0) ---
2873 <... rt_sigprocmask resumed> [], 8) = 0
2882 sigreturn( <unfinished ...>
2873 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2882 <... sigreturn resumed> ) = ? (mask now [PIPE])
2873 <... rt_sigprocmask resumed> NULL, 8) = 0
2882 poll( <unfinished ...>
2873 write(1, "Waiting for ustd to shutdown...\n", 32) = 32
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0
2873 rt_sigaction(SIGINT, {0x807b370, [], 0}, {0x808e370, [], 0}, 8) = 0
2873 waitpid(-1, <unfinished ...>
2891 <... ftruncate resumed> ) = 0
2890 <... ftruncate resumed> ) = 0
2891 lseek(5, 0, SEEK_SET <unfinished ...>
2890 lseek(6, 0, SEEK_SET <unfinished ...>
2891 <... lseek resumed> ) = 0
2890 <... lseek resumed> ) = 0
2891 write(5, "\224\357vr-\5\0\0\2257\177\177-\5\0\0\355\267\326\0\2\6"..., 68 <unfinished ...>
2890 write(6, "\224\357vr-\5\0\0008\10m\177-\5\0\0\355\267\326\0\2\6\4"..., 102 <unfinished ...>
2891 <... write resumed> ) = 68
2890 <... write resumed> ) = 102
2891 write(5, "\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"..., 4028 <unfinished ...>
2890 write(6, "\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"..., 3994 <unfinished ...>
2891 <... write resumed> ) = 4028
2890 <... write resumed> ) = 3994
2891 close(11 <unfinished ...>
2890 close(10 <unfinished ...>
2888 <... ftruncate resumed> ) = 0
2891 <... close resumed> ) = 0
2890 <... close resumed> ) = 0
2891 shmdt(0xb4cff000 <unfinished ...>
2890 shmdt(0xb5700000 <unfinished ...>
2888 lseek(4, 0, SEEK_SET <unfinished ...>
2891 <... shmdt resumed> ) = 0
2890 <... shmdt resumed> ) = 0
2891 shmdt(0xb5a3b000 <unfinished ...>
2890 shmdt(0xb5a3c000 <unfinished ...>
2891 <... shmdt resumed> ) = 0
2890 <... shmdt resumed> ) = 0
2891 close(5 <unfinished ...>
2890 close(6 <unfinished ...>
2888 <... lseek resumed> ) = 0
2888 write(4, "\224\357vr-\5\0\0cO8\177-\5\0\0\355\267\326\0\2\6\4\0\375"..., 783) = 783
2888 write(4, "\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"..., 3313) = 3313
2888 close(7) = 0
2888 shmdt(0xb5c3f000) = 0
2888 shmdt(0xb5c3e000) = 0
2888 close(4 <unfinished ...>
2882 <... poll resumed> [{fd=3, events=POLLIN}], 1, 100) = 0
2882 poll( <unfinished ...>
2889 <... ftruncate resumed> ) = 0
2889 lseek(9, 0, SEEK_SET) = 0
2889 write(9, "\224\357vr-\5\0\0q\310D\177-\5\0\0\355\267\326\0\2\6\4"..., 68) = 68
2889 write(9, "\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"..., 4028) = 4028
2889 close(8) = 0
2889 shmdt(0xb5a3e000) = 0
2889 shmdt(0xb5a3d000) = 0
2889 close(9 <unfinished ...>
2891 <... close resumed> ) = 0
2891 madvise(0xb4eff000, 8372224, 0x4 /* MADV_??? */) = 0
2891 _exit(0) = ?
2888 <... close resumed> ) = 0
2888 madvise(0xb6e41000, 8372224, 0x4 /* MADV_??? */) = 0
2888 _exit(0) = ?
2890 <... close resumed> ) = 0
2890 madvise(0xb5e3f000, 8372224, 0x4 /* MADV_??? */) = 0
2890 _exit(0) = ?
2889 <... close resumed> ) = 0
2889 madvise(0xb6640000, 8372224, 0x4 /* MADV_??? */) = 0
2889 _exit(0) = ?
2882 <... poll resumed> [{fd=3, events=POLLIN}], 1, 100) = 0
2882 stat64("/tmp/ustd-sock-2873", {st_mode=S_IFSOCK|0755, st_size=0, ...}) = 0
2882 unlink("/tmp/ustd-sock-2873") = 0
2882 close(3) = 0
2882 munmap(0, 0) = -1 EINVAL (Invalid argument)
2882 exit_group(0) = ?
2873 <... waitpid resumed> [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0) = 2882
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 --- SIGCHLD (Child exited) @ 0 (0) ---
2873 waitpid(-1, 0xbfca94fc, WNOHANG) = -1 ECHILD (No child processes)
2873 sigreturn() = ? (mask now [])
2873 rt_sigaction(SIGINT, {0x808e370, [], 0}, {0x807b370, [], 0}, 8) = 0
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 stat64(".", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
2873 stat64("/usr/local/bin/rm", 0xbfca9bd0) = -1 ENOENT (No such file or directory)
2873 stat64("/usr/bin/rm", 0xbfca9bd0) = -1 ENOENT (No such file or directory)
2873 stat64("/bin/rm", {st_mode=S_IFREG|0755, st_size=35020, ...}) = 0
2873 stat64("/bin/rm", {st_mode=S_IFREG|0755, st_size=35020, ...}) = 0
2873 rt_sigprocmask(SIG_BLOCK, [INT CHLD], [], 8) = 0
2873 _llseek(255, -38, [4458], SEEK_CUR) = 0
2873 clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0xb7700b98) = 2892
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2892 close(255 <unfinished ...>
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], <unfinished ...>
2892 <... close resumed> ) = 0
2873 <... rt_sigprocmask resumed> [], 8) = 0
2892 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], <unfinished ...>
2892 <... rt_sigprocmask resumed> NULL, 8) = 0
2873 <... rt_sigprocmask resumed> [CHLD], 8) = 0
2892 rt_sigaction(SIGTSTP, {SIG_DFL}, <unfinished ...>
2873 rt_sigprocmask(SIG_SETMASK, [CHLD], <unfinished ...>
2892 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2873 <... rt_sigprocmask resumed> NULL, 8) = 0
2892 rt_sigaction(SIGTTIN, {SIG_DFL}, <unfinished ...>
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], <unfinished ...>
2892 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2873 <... rt_sigprocmask resumed> [CHLD], 8) = 0
2892 rt_sigaction(SIGTTOU, {SIG_DFL}, <unfinished ...>
2873 rt_sigprocmask(SIG_SETMASK, [CHLD], <unfinished ...>
2892 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0
2873 <... rt_sigprocmask resumed> NULL, 8) = 0
2892 rt_sigaction(SIGINT, {SIG_DFL}, <unfinished ...>
2873 rt_sigprocmask(SIG_SETMASK, [], <unfinished ...>
2892 <... rt_sigaction resumed> {0x808e370, [], 0}, 8) = 0
2873 <... rt_sigprocmask resumed> NULL, 8) = 0
2892 rt_sigaction(SIGQUIT, {SIG_DFL}, <unfinished ...>
2873 rt_sigprocmask(SIG_BLOCK, [CHLD], <unfinished ...>
2892 <... rt_sigaction resumed> {SIG_IGN}, 8) = 0
2873 <... rt_sigprocmask resumed> [], 8) = 0
2892 rt_sigaction(SIGCHLD, {SIG_DFL}, <unfinished ...>
2873 rt_sigaction(SIGINT, {0x807b370, [], 0}, <unfinished ...>
2892 <... rt_sigaction resumed> {0x807d490, [], 0}, 8) = 0
2873 <... rt_sigaction resumed> {0x808e370, [], 0}, 8) = 0
2873 waitpid(-1, <unfinished ...>
2892 execve("/bin/rm", ["rm", "/tmp/usttrace-root-2010073015042"...], [/* 17 vars */]) = 0
2892 brk(0) = 0x991a000
2892 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb77ca000
2892 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
2892 open("/etc/ld.so.cache", O_RDONLY) = -1 ENOENT (No such file or directory)
2892 open("/lib/tls/i686/sse2/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2892 stat64("/lib/tls/i686/sse2", 0xbfd384e8) = -1 ENOENT (No such file or directory)
2892 open("/lib/tls/i686/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2892 stat64("/lib/tls/i686", 0xbfd384e8) = -1 ENOENT (No such file or directory)
2892 open("/lib/tls/sse2/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2892 stat64("/lib/tls/sse2", 0xbfd384e8) = -1 ENOENT (No such file or directory)
2892 open("/lib/tls/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2892 stat64("/lib/tls", 0xbfd384e8) = -1 ENOENT (No such file or directory)
2892 open("/lib/i686/sse2/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2892 stat64("/lib/i686/sse2", 0xbfd384e8) = -1 ENOENT (No such file or directory)
2892 open("/lib/i686/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2892 stat64("/lib/i686", 0xbfd384e8) = -1 ENOENT (No such file or directory)
2892 open("/lib/sse2/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
2892 stat64("/lib/sse2", 0xbfd384e8) = -1 ENOENT (No such file or directory)
2892 open("/lib/libc.so.6", O_RDONLY) = 3
2892 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\360m\1"..., 512) = 512
2892 fstat64(3, {st_mode=S_IFREG|0755, st_size=7907670, ...}) = 0
2892 old_mmap(NULL, 1362312, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb767d000
2892 old_mmap(0xb77c4000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x147000) = 0xb77c4000
2892 old_mmap(0xb77c7000, 10632, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb77c7000
2892 close(3) = 0
2892 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb767c000
2892 set_thread_area({entry_number:-1 -> 6, base_addr:0xb767c6c0, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}) = 0
2892 mprotect(0xb77c4000, 8192, PROT_READ) = 0
2892 mprotect(0xb77ea000, 4096, PROT_READ) = 0
2892 brk(0) = 0x991a000
2892 brk(0x993b000) = 0x993b000
2892 ioctl(0, SNDCTL_TMR_TIMEBASE or TCGETS, {B38400 opost isig icanon echo ...}) = 0
2892 socket_subcall(0xffffff9c, 0xbfd38e2e, 0xbfd38840, 0x100, 0xb77c5ff4, 0xbfd38808, 0xffffffda, 0x7b) = 0
2892 stat64("/tmp/usttrace-root-20100730150429721391273-ustd-pid", {st_mode=S_IFIFO|0600, st_size=0, ...}) = 0
2892 geteuid32() = 0
2892 getegid32() = 0
2892 getuid32() = 0
2892 getgid32() = 0
2892 access("/tmp/usttrace-root-20100730150429721391273-ustd-pid", W_OK) = 0
2892 socket(0xffffff9c /* PF_??? */, 0xbfd38e2e /* SOCK_??? */, 0) = 0
2892 close(1) = 0
2892 exit_group(0) = ?
2873 <... waitpid resumed> [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0) = 2892
2873 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
2873 --- SIGCHLD (Child exited) @ 0 (0) ---
2873 waitpid(-1, 0xbfca98dc, WNOHANG) = -1 ECHILD (No child processes)
2873 sigreturn() = ? (mask now [])
2873 rt_sigaction(SIGINT, {0x808e370, [], 0}, {0x807b370, [], 0}, 8) = 0
2873 read(255, "\necho \"Trace was output in: \" $O"..., 4496) = 38
2873 write(1, "Trace was output in: /home/root"..., 80) = 80
2873 read(255, "", 4496) = 0
2873 exit_group(0) = ?
More information about the lttng-dev
mailing list