roxen.lists.pike.general

Subject Author Date
Startup times Pike vs. Perl Stephen R. van den Berg <srb[at]cuci[dot]nl> 17-08-2009
I noticed some (currently) sad truths (this is on an AMD Geode LX800,
using a recent 7.8 Pike, freshly compiled optimised for the Geodo, perl
is the standard 32-bit Debian binary from testing):

# time pike -e ''
real	0m0.685s
user	0m0.660s
sys	0m0.020s
# time perl -e ''
real	0m0.022s
user	0m0.020s
sys	0m0.010s
# strace  -tt -r -f pike -e '' 2>&1 | wc
    342    2351   24873
# strace  -tt -r -f perl -e '' 2>&1 | wc                       
     98     711    7495

Pike and Perl straces included below (I tried creating ltraces, but that
is a bit timeconsuming (at least in the Pike case it is)):

Pike trace:
     0.000000 execve("/usr/bin/pike", ["pike", "-e", ""], [/* 15 vars */]) = 0
     0.002118 brk(0)                    = 0x9364000
     0.000154 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or
directory)
     0.000188 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS,
-1, 0) = 0xb8088000
     0.000173 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or
directory)
     0.000161 open("/usr/lib/gcc/i486-linux-gnu/4.4/tls/i586/cmov/libgmp.so.3",
O_RDONLY) = -1 ENOENT (No such file or directory)
     0.001536 stat64("/usr/lib/gcc/i486-linux-gnu/4.4/tls/i586/cmov",
0xbfb9b640) = -1 ENOENT (No such file or directory)
     0.000182 open("/usr/lib/gcc/i486-linux-gnu/4.4/tls/i586/libgmp.so.3",
O_RDONLY) = -1 ENOENT (No such file or directory)
     0.000173 stat64("/usr/lib/gcc/i486-linux-gnu/4.4/tls/i586", 0xbfb9b640) =
-1 ENOENT (No such file or directory)
     0.000157 open("/usr/lib/gcc/i486-linux-gnu/4.4/tls/cmov/libgmp.so.3",
O_RDONLY) = -1 ENOENT (No such file or directory)
     0.000168 stat64("/usr/lib/gcc/i486-linux-gnu/4.4/tls/cmov", 0xbfb9b640) =
-1 ENOENT (No such file or directory)
     0.000151 open("/usr/lib/gcc/i486-linux-gnu/4.4/tls/libgmp.so.3", O_RDONLY)
= -1 ENOENT (No such file or directory)
     0.000158 stat64("/usr/lib/gcc/i486-linux-gnu/4.4/tls", 0xbfb9b640) = -1
ENOENT (No such file or directory)
     0.000148 open("/usr/lib/gcc/i486-linux-gnu/4.4/i586/cmov/libgmp.so.3",
O_RDONLY) = -1 ENOENT (No such file or directory)
     0.000169 stat64("/usr/lib/gcc/i486-linux-gnu/4.4/i586/cmov", 0xbfb9b640) =
-1 ENOENT (No such file or directory)
     0.000151 open("/usr/lib/gcc/i486-linux-gnu/4.4/i586/libgmp.so.3", O_RDONLY)
= -1 ENOENT (No such file or directory)
     0.000162 stat64("/usr/lib/gcc/i486-linux-gnu/4.4/i586", 0xbfb9b640) = -1
ENOENT (No such file or directory)
     0.000148 open("/usr/lib/gcc/i486-linux-gnu/4.4/cmov/libgmp.so.3", O_RDONLY)
= -1 ENOENT (No such file or directory)
     0.000161 stat64("/usr/lib/gcc/i486-linux-gnu/4.4/cmov", 0xbfb9b640) = -1
ENOENT (No such file or directory)
     0.000148 open("/usr/lib/gcc/i486-linux-gnu/4.4/libgmp.so.3", O_RDONLY) = -1
ENOENT (No such file or directory)
     0.000156 stat64("/usr/lib/gcc/i486-linux-gnu/4.4", {st_mode=S_IFDIR|0755,
st_size=1024, ...}) = 0
     0.000254 open("/etc/ld.so.cache", O_RDONLY) = 3
     0.000657 fstat64(3, {st_mode=S_IFREG|0644, st_size=14714, ...}) = 0
     0.000191 mmap2(NULL, 14714, PROT_READ, MAP_PRIVATE, 3, 0) = 0xb8084000
     0.000108 close(3)                  = 0
     0.000090 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or
directory)
     0.000149 open("/usr/lib/libgmp.so.3", O_RDONLY) = 3
     0.001297 read(3,
"7ELF05040"...,
512) = 512
     0.000218 fstat64(3, {st_mode=S_IFREG|0644, st_size=384512, ...}) = 0
     0.000183 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS,
-1, 0) = 0xb8083000
     0.000134 mmap2(NULL, 387404, PROT_READ|PROT_EXEC,
MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb8024000
     0.000110 mmap2(0xb8082000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x5d) = 0xb8082000
     0.000156 close(3)                  = 0
     0.000102 open("/usr/lib/gcc/i486-linux-gnu/4.4/libm.so.6", O_RDONLY) = -1
ENOENT (No such file or directory)
     0.000171 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or
directory)
     0.000137 open("/lib/libm.so.6", O_RDONLY) = 3
     0.001284 read(3,
"7ELF`404d"..., 512) = 512
     0.000202 fstat64(3, {st_mode=S_IFREG|0644, st_size=149388, ...}) = 0
     0.000186 mmap2(NULL, 151680, PROT_READ|PROT_EXEC,
MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7ffe000
     0.000119 mmap2(0xb8022000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x23) = 0xb8022000
     0.000174 close(3)                  = 0
     0.000105 open("/usr/lib/gcc/i486-linux-gnu/4.4/libnsl.so.1", O_RDONLY) = -1
ENOENT (No such file or directory)
     0.000174 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or
directory)
     0.000248 open("/lib/libnsl.so.1", O_RDONLY) = 3
     0.001282 read(3,
"7ELF01040"..., 512)
= 512
     0.000212 fstat64(3, {st_mode=S_IFREG|0644, st_size=79672, ...}) = 0
     0.000185 mmap2(NULL, 92136, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE,
3, 0) = 0xb7fe7000
     0.000121 mmap2(0xb7ffa000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x12) = 0xb7ffa000
     0.000166 mmap2(0xb7ffc000, 6120, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb7ffc000
     0.000140 close(3)                  = 0
     0.000103 open("/usr/lib/gcc/i486-linux-gnu/4.4/libdl.so.2", O_RDONLY) = -1
ENOENT (No such file or directory)
     0.000175 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or
directory)
     0.000135 open("/lib/libdl.so.2", O_RDONLY) = 3
     0.001429 read(3,
"7ELF@\n04X"..., 512) = 512
     0.000199 fstat64(3, {st_mode=S_IFREG|0644, st_size=9736, ...}) = 0
     0.000187 mmap2(NULL, 12408, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE,
3, 0) = 0xb7fe3000
     0.000120 mmap2(0xb7fe5000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1) = 0xb7fe5000
     0.000179 close(3)                  = 0
     0.000098 open("/usr/lib/gcc/i486-linux-gnu/4.4/librt.so.1", O_RDONLY) = -1
ENOENT (No such file or directory)
     0.000178 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or
directory)
     0.000135 open("/lib/librt.so.1", O_RDONLY) = 3
     0.000565 read(3,
"7ELF0044"...,
512) = 512
     0.000210 fstat64(3, {st_mode=S_IFREG|0644, st_size=30684, ...}) = 0
     0.000184 mmap2(NULL, 33364, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE,
3, 0) = 0xb7fda000
     0.000119 mmap2(0xb7fe1000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x6) = 0xb7fe1000
     0.000169 close(3)                  = 0
     0.000100 open("/usr/lib/gcc/i486-linux-gnu/4.4/libpthread.so.0", O_RDONLY)
= -1 ENOENT (No such file or directory)
     0.000177 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or
directory)
     0.000137 open("/lib/libpthread.so.0", O_RDONLY) = 3
     0.001482 read(3,
"7ELF0G044"..., 512)
= 512
     0.000219 fstat64(3, {st_mode=S_IFREG|0755, st_size=111967, ...}) = 0
     0.000187 mmap2(NULL, 94676, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE,
3, 0) = 0xb7fc2000
     0.000123 mmap2(0xb7fd6000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x13) = 0xb7fd6000
     0.000168 mmap2(0xb7fd8000, 4564, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb7fd8000
     0.000142 close(3)                  = 0
     0.000104 open("/usr/lib/gcc/i486-linux-gnu/4.4/libcrypt.so.1", O_RDONLY) =
-1 ENOENT (No such file or directory)
     0.000181 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or
directory)
     0.000138 open("/lib/libcrypt.so.1", O_RDONLY) = 3
     0.001294 read(3,
"7ELFP04L"..., 512) = 512
     0.000205 fstat64(3, {st_mode=S_IFREG|0644, st_size=38356, ...}) = 0
     0.000184 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS,
-1, 0) = 0xb7fc1000
     0.000140 mmap2(NULL, 201052, PROT_READ|PROT_EXEC,
MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7f8f000
     0.000111 mmap2(0xb7f98000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x8) = 0xb7f98000
     0.000161 mmap2(0xb7f9a000, 155996, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb7f9a000
     0.000135 close(3)                  = 0
     0.000102 open("/usr/lib/gcc/i486-linux-gnu/4.4/libc.so.6", O_RDONLY) = -1
ENOENT (No such file or directory)
     0.000175 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or
directory)
     0.000136 open("/lib/libc.so.6", O_RDONLY) = 3
     0.000552 read(3,
"7ELFi04d"..., 512) = 512
     0.000220 fstat64(3, {st_mode=S_IFREG|0755, st_size=1302788, ...}) = 0
     0.000196 mmap2(NULL, 1312336, PROT_READ|PROT_EXEC,
MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7e4e000
     0.000118 mmap2(0xb7f89000, 12288, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x13a) = 0xb7f89000
     0.000157 mmap2(0xb7f8c000, 9808, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb7f8c000
     0.000137 close(3)                  = 0
     0.000510 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS,
-1, 0) = 0xb7e4d000
     0.000138 set_thread_area({entry_number:-1 -> 6, base_addr:0xb7e4d8d0,
limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1,
seg_not_present:0, useable:1}) = 0
     0.000135 open("/dev/urandom", O_RDONLY) = 3
     0.000130 read(3, "1r15"..., 4) = 4
     0.000131 close(3)                  = 0
     0.000625 mprotect(0xb7f89000, 8192, PROT_READ) = 0
     0.000136 mprotect(0xb7f98000, 4096, PROT_READ) = 0
     0.000172 mprotect(0xb7fd6000, 4096, PROT_READ) = 0
     0.000122 mprotect(0xb7fe1000, 4096, PROT_READ) = 0
     0.000149 mprotect(0xb7fe5000, 4096, PROT_READ) = 0
     0.000202 mprotect(0xb7ffa000, 4096, PROT_READ) = 0
     0.000124 mprotect(0xb8022000, 4096, PROT_READ) = 0
     0.000339 mprotect(0xb80a6000, 4096, PROT_READ) = 0
     0.000094 munmap(0xb8084000, 14714) = 0
     0.000104 set_tid_address(0xb7e4d918) = 14133
     0.000079 set_robust_list(0xb7e4d920, 0xc) = 0
     0.000085 futex(0xbfb9bcf0, FUTEX_WAKE_PRIVATE, 1) = 0
     0.000102 rt_sigaction(SIGRTMIN, {0xb7fc62a0, [], SA_SIGINFO}, NULL, 8) = 0
     0.000152 rt_sigaction(SIGRT_1, {0xb7fc6680, [], SA_RESTART|SA_SIGINFO},
NULL, 8) = 0
     0.000133 rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0
     0.000143 getrlimit(RLIMIT_STACK, {rlim_cur=8192*1024,
rlim_max=RLIM_INFINITY}) = 0
     0.000168 uname({sys="Linux", node="IceCube", ...}) = 0
     0.000509 clock_getres(0x3 /* CLOCK_??? */, {0, 1}) = 0
     0.000115 clock_getres(0xfffffffa /* CLOCK_??? */, NULL) = 0
     0.000088 clock_getres(CLOCK_MONOTONIC, {0, 1}) = 0
     0.000534 brk(0)                    = 0x9364000
     0.000083 brk(0x9387000)            = 0x9387000
     0.000213 open("/etc/localtime", O_RDONLY) = 3
     0.000649 fstat64(3, {st_mode=S_IFREG|0644, st_size=2917, ...}) = 0
     0.000200 fstat64(3, {st_mode=S_IFREG|0644, st_size=2917, ...}) = 0
     0.000179 mmap2(NULL, 32768, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7e45000
     0.000121 read(3,
"TZif2\r\r"..., 32768) = 2917
     0.000430 _llseek(3, -28, [2889], SEEK_CUR) = 0
     0.000113 read(3, "\nCET-1CEST,M3.5.0,M10.5.0/3\n"..., 32768) = 28
     0.000126 close(3)                  = 0
     0.000091 munmap(0xb7e45000, 32768) = 0
     0.000227 getrlimit(RLIMIT_STACK, {rlim_cur=8192*1024,
rlim_max=RLIM_INFINITY}) = 0
     0.000144 futex(0xb7fe606c, FUTEX_WAKE_PRIVATE, 2147483647) = 0
     0.000192 gettimeofday({1250496222, 742592}, NULL) = 0
     0.000233 mmap2(NULL, 800000, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_ANONYMOUS|MAP_NORESERVE, -1, 0) = 0xb7d89000
     0.000118 mmap2(NULL, 400000, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_ANONYMOUS|MAP_NORESERVE, -1, 0) = 0xb7d27000
     0.000145 mmap2(NULL, 135168, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7d06000
     0.000923 gettimeofday({1250496222, 744012}, NULL) = 0
     0.000097 gettimeofday({1250496222, 744106}, NULL) = 0
     0.000214 mmap2(NULL, 4096, PROT_READ|PROT_WRITE|PROT_EXEC,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb8087000
     0.000455 gettimeofday({1250496222, 744778}, NULL) = 0
     0.000668 gettimeofday({1250496222, 745449}, NULL) = 0
     0.000211 gettimeofday({1250496222, 745656}, NULL) = 0
     0.000151 gettimeofday({1250496222, 745806}, NULL) = 0
     0.000218 gettimeofday({1250496222, 746026}, NULL) = 0
     0.000187 gettimeofday({1250496222, 746213}, NULL) = 0
     0.000181 gettimeofday({1250496222, 746392}, NULL) = 0
     0.000188 gettimeofday({1250496222, 746580}, NULL) = 0
     0.000203 gettimeofday({1250496222, 746785}, NULL) = 0
     0.000121 gettimeofday({1250496222, 746903}, NULL) = 0
     0.000238 gettimeofday({1250496222, 747145}, NULL) = 0
     0.000388 gettimeofday({1250496222, 747535}, NULL) = 0
     0.000289 gettimeofday({1250496222, 747824}, NULL) = 0
     0.000275 gettimeofday({1250496222, 748095}, NULL) = 0
     0.000267 gettimeofday({1250496222, 748364}, NULL) = 0
     0.000125 epoll_create(32)          = 3
     0.000116 fcntl64(3, F_GETFD)       = 0
     0.000089 fcntl64(3, F_SETFD, FD_CLOEXEC) = 0
     0.000122 socketpair(PF_FILE, SOCK_STREAM, 0, [4, 5]) = 0
     0.000130 fcntl64(4, F_GETFL)       = 0x2 (flags O_RDWR)
     0.000099 fcntl64(4, F_SETFL, O_RDWR|O_NONBLOCK) = 0
     0.000087 fcntl64(5, F_GETFL)       = 0x2 (flags O_RDWR)
     0.000084 fcntl64(5, F_SETFL, O_RDWR|O_NONBLOCK) = 0
     0.000110 epoll_ctl(3, EPOLL_CTL_MOD, 4, {EPOLLIN|EPOLLRDNORM, {u32=4,
u64=13256558121226076164}}) = -1 ENOENT (No such file or directory)
     0.000139 epoll_ctl(3, EPOLL_CTL_ADD, 4, {EPOLLIN|EPOLLRDNORM, {u32=4,
u64=13256558121226076164}}) = 0
     0.000112 fcntl64(4, F_GETFD)       = 0
     0.000080 fcntl64(4, F_SETFD, FD_CLOEXEC) = 0
     0.000081 fcntl64(5, F_GETFD)       = 0
     0.000079 fcntl64(5, F_SETFD, FD_CLOEXEC) = 0
     0.000118 gettimeofday({1250496222, 749934}, NULL) = 0
     0.000335 gettimeofday({1250496222, 750269}, NULL) = 0
     0.000281 gettimeofday({1250496222, 750551}, NULL) = 0
     0.000118 brk(0x93a8000)            = 0x93a8000
     0.000184 brk(0x93a6000)            = 0x93a6000
     0.000210 gettimeofday({1250496222, 751060}, NULL) = 0
     0.000420 gettimeofday({1250496222, 751481}, NULL) = 0
     0.000332 gettimeofday({1250496222, 751814}, NULL) = 0
     0.000251 gettimeofday({1250496222, 752064}, NULL) = 0
     0.000325 gettimeofday({1250496222, 752389}, NULL) = 0
     0.000173 gettimeofday({1250496222, 752563}, NULL) = 0
     0.000359 gettimeofday({1250496222, 752923}, NULL) = 0
     0.000313 gettimeofday({1250496222, 753234}, NULL) = 0
     0.000325 gettimeofday({1250496222, 753561}, NULL) = 0
     0.000285 gettimeofday({1250496222, 753845}, NULL) = 0
     0.000308 gettimeofday({1250496222, 754152}, NULL) = 0
     0.000288 gettimeofday({1250496222, 754440}, NULL) = 0
     0.000294 gettimeofday({1250496222, 754732}, NULL) = 0
     0.000295 gettimeofday({1250496222, 755028}, NULL) = 0
     0.000277 gettimeofday({1250496222, 755304}, NULL) = 0
     0.000317 gettimeofday({1250496222, 755622}, NULL) = 0
     0.000175 gettimeofday({1250496222, 755796}, NULL) = 0
     0.000213 gettimeofday({1250496222, 756012}, NULL) = 0
     0.000223 gettimeofday({1250496222, 756236}, NULL) = 0
     0.000215 gettimeofday({1250496222, 756449}, NULL) = 0
     0.000165 gettimeofday({1250496222, 756614}, NULL) = 0
     0.000206 gettimeofday({1250496222, 756821}, NULL) = 0
     0.002404 gettimeofday({1250496222, 759234}, NULL) = 0
     0.000222 gettimeofday({1250496222, 759448}, NULL) = 0
     0.000113 mmap2(NULL, 4096, PROT_READ|PROT_WRITE|PROT_EXEC,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb8086000
     0.000368 gettimeofday({1250496222, 759932}, NULL) = 0
     0.000329 gettimeofday({1250496222, 760257}, NULL) = 0
     0.000274 gettimeofday({1250496222, 760532}, NULL) = 0
     0.000262 gettimeofday({1250496222, 760794}, NULL) = 0
     0.000237 gettimeofday({1250496222, 761030}, NULL) = 0
     0.000212 gettimeofday({1250496222, 761242}, NULL) = 0
     0.000206 gettimeofday({1250496222, 761447}, NULL) = 0
     0.000223 gettimeofday({1250496222, 761671}, NULL) = 0
     0.000124 gettimeofday({1250496222, 761793}, NULL) = 0
     0.001024 gettimeofday({1250496222, 762820}, NULL) = 0
     0.003681 socketpair(PF_FILE, SOCK_STREAM, 0, [6, 7]) = 0
     0.000155 fcntl64(6, F_GETFL)       = 0x2 (flags O_RDWR)
     0.000095 fcntl64(6, F_SETFL, O_RDWR|O_NONBLOCK) = 0
     0.000090 fcntl64(7, F_GETFL)       = 0x2 (flags O_RDWR)
     0.000084 fcntl64(7, F_SETFL, O_RDWR|O_NONBLOCK) = 0
     0.000086 fcntl64(6, F_GETFD)       = 0
     0.000082 fcntl64(6, F_SETFD, FD_CLOEXEC) = 0
     0.000101 fcntl64(7, F_GETFD)       = 0
     0.000080 fcntl64(7, F_SETFD, FD_CLOEXEC) = 0
     0.000095 socketpair(PF_FILE, SOCK_STREAM, 0, [8, 9]) = 0
     0.000121 fcntl64(8, F_GETFL)       = 0x2 (flags O_RDWR)
     0.000084 fcntl64(8, F_SETFL, O_RDWR|O_NONBLOCK) = 0
     0.000084 fcntl64(9, F_GETFL)       = 0x2 (flags O_RDWR)
     0.000086 fcntl64(9, F_SETFL, O_RDWR|O_NONBLOCK) = 0
     0.000084 fcntl64(8, F_GETFD)       = 0
     0.000078 fcntl64(8, F_SETFD, FD_CLOEXEC) = 0
     0.000081 fcntl64(9, F_GETFD)       = 0
     0.000079 fcntl64(9, F_SETFD, FD_CLOEXEC) = 0
     0.000121 rt_sigaction(SIGCHLD, {0x80cc609, ~[RTMIN RT_1], SA_INTERRUPT},
NULL, 8) = 0
     0.000179 rt_sigaction(SIGPIPE, , NULL, 8) = 0
     0.000117 gettimeofday({1250496222, 768480}, NULL) = 0
     0.000313 gettimeofday({1250496222, 768795}, NULL) = 0
     0.001811 gettimeofday({1250496222, 770608}, NULL) = 0
     0.000140 gettimeofday({1250496222, 770745}, NULL) = 0
     0.001707 gettimeofday({1250496222, 772463}, NULL) = 0
     0.001504 gettimeofday({1250496222, 773963}, NULL) = 0
     0.000769 gettimeofday({1250496222, 774731}, NULL) = 0
     0.000765 gettimeofday({1250496222, 775495}, NULL) = 0
     0.000447 gettimeofday({1250496222, 775939}, NULL) = 0
     0.000128 fcntl64(0, F_GETFD)       = 0
     0.000092 fcntl64(0, F_SETFD, FD_CLOEXEC) = 0
     0.000090 fcntl64(1, F_GETFD)       = 0
     0.000086 fcntl64(1, F_SETFD, FD_CLOEXEC) = 0
     0.000086 fcntl64(2, F_GETFD)       = 0
     0.000084 fcntl64(2, F_SETFD, FD_CLOEXEC) = 0
     0.000295 gettimeofday({1250496222, 776799}, NULL) = 0
     0.000410 gettimeofday({1250496222, 777216}, NULL) = 0
     0.000932 gettimeofday({1250496222, 778146}, NULL) = 0
     0.001050 gettimeofday({1250496222, 779195}, NULL) = 0
     0.001481 gettimeofday({1250496222, 780677}, NULL) = 0
     0.000527 gettimeofday({1250496222, 781203}, NULL) = 0
     0.000236 gettimeofday({1250496222, 781438}, NULL) = 0
     0.000572 gettimeofday({1250496222, 782009}, NULL) = 0
     0.000233 gettimeofday({1250496222, 782242}, NULL) = 0
     0.001098 gettimeofday({1250496222, 783341}, NULL) = 0
     0.000223 gettimeofday({1250496222, 783562}, NULL) = 0
     0.000935 clock_getres(0x3 /* CLOCK_??? */, {0, 1}) = 0
     0.000147 clock_getres(CLOCK_MONOTONIC, {0, 1}) = 0
     0.000297 gettimeofday({1250496222, 784940}, NULL) = 0
     0.008625 stat64("/usr/pike/lib/pike/master.pike.o", {st_mode=S_IFREG|0644,
st_size=265726, ...}) = 0
     0.001168 stat64("/usr/pike/lib/pike/master.pike", {st_mode=S_IFREG|0644,
st_size=157590, ...}) = 0
     0.000392 open("/usr/pike/lib/pike/master.pike.o", O_RDONLY|O_LARGEFILE) = 10
     0.001025 _llseek(10, 0, [265726], SEEK_END) = 0
     0.000111 _llseek(10, 0, [0], SEEK_SET) = 0
     0.000116 mmap2(NULL, 266240, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7cc5000
     0.000153 read(10,
"6ke0I/6119(Ef0K5\r\v\f"...,
265726) = 265726
     0.001740 close(10)                 = 0
     0.000190 gettimeofday({1250496222, 798462}, NULL) = 0
     0.000140 brk(0x93c8000)            = 0x93c8000
     0.000321 mmap2(0xb8087000, 4096, PROT_READ|PROT_WRITE|PROT_EXEC,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb8085000
     0.000543 mmap2(0xb8086000, 8192, PROT_READ|PROT_WRITE|PROT_EXEC,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7cc3000
     0.000690 mmap2(0xb7cc5000, 12288, PROT_READ|PROT_WRITE|PROT_EXEC,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7cc0000
     0.001246 mmap2(0xb7cc3000, 20480, PROT_READ|PROT_WRITE|PROT_EXEC,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7cbb000
     0.002518 mmap2(0xb7cc0000, 36864, PROT_READ|PROT_WRITE|PROT_EXEC,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7cb2000
     0.004578 mmap2(0xb7cbb000, 69632, PROT_READ|PROT_WRITE|PROT_EXEC,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7ca1000
     0.009151 mmap2(0xb7cb2000, 135168, PROT_READ|PROT_WRITE|PROT_EXEC,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7c80000
     0.018687 gettimeofday({1250496222, 836354}, NULL) = 0
     0.000141 gettimeofday({1250496222, 836477}, NULL) = 0
     0.000158 gettimeofday({1250496222, 836635}, NULL) = 0
     0.000113 gettimeofday({1250496222, 836747}, NULL) = 0
     0.000110 gettimeofday({1250496222, 836855}, NULL) = 0
     0.000105 gettimeofday({1250496222, 836961}, NULL) = 0
     0.004703 brk(0x93e9000)            = 0x93e9000
     0.002062 gettimeofday({1250496222, 843731}, NULL) = 0
     0.001043 munmap(0xb7cc5000, 266240) = 0
     0.000365 open("/usr/pike/lib/pike/master.pike", O_RDONLY|O_LARGEFILE) = 10
     0.001073 _llseek(10, 0, [157590], SEEK_END) = 0
     0.000114 _llseek(10, 0, [0], SEEK_SET) = 0
     0.000137 brk(0x9413000)            = 0x9413000
     0.000112 read(10, "// -*- Pike -*-\n//\n// Master Cont"..., 157590) = 157590
     0.001047 close(10)                 = 0
     0.062669 gettimeofday({1250496222, 910320}, NULL) = 0
     0.001163 gettimeofday({1250496222, 911460}, NULL) = 0
     0.002414 gettimeofday({1250496222, 913872}, NULL) = 0
     0.001881 gettimeofday({1250496222, 915757}, NULL) = 0
     0.000992 gettimeofday({1250496222, 916746}, NULL) = 0
     0.000825 gettimeofday({1250496222, 917567}, NULL) = 0
     0.000538 gettimeofday({1250496222, 918104}, NULL) = 0
     0.006707 gettimeofday({1250496222, 924833}, NULL) = 0
     0.001267 gettimeofday({1250496222, 926080}, NULL) = 0
     0.000259 gettimeofday({1250496222, 926337}, NULL) = 0
     0.000240 gettimeofday({1250496222, 926576}, NULL) = 0
     0.000858 gettimeofday({1250496222, 927436}, NULL) = 0
     0.001963 gettimeofday({1250496222, 929402}, NULL) = 0
     0.000184 gettimeofday({1250496222, 929582}, NULL) = 0
     0.001971 gettimeofday({1250496222, 931556}, NULL) = 0
     0.010774 gettimeofday({1250496222, 942347}, NULL) = 0
     0.019095 gettimeofday({1250496222, 961447}, NULL) = 0
     0.000272 gettimeofday({1250496222, 961694}, NULL) = 0
     0.002919 gettimeofday({1250496222, 964622}, NULL) = 0
     0.005649 gettimeofday({1250496222, 970277}, NULL) = 0
     0.007199 gettimeofday({1250496222, 977474}, NULL) = 0
     0.008799 gettimeofday({1250496222, 986280}, NULL) = 0
     0.023845 gettimeofday({1250496223, 10135}, NULL) = 0
     0.026571 gettimeofday({1250496223, 36703}, NULL) = 0
     0.010011 gettimeofday({1250496223, 46707}, NULL) = 0
     0.005880 gettimeofday({1250496223, 52580}, NULL) = 0
     0.000479 gettimeofday({1250496223, 53047}, NULL) = 0
     0.207454 brk(0x9438000)            = 0x9438000
     0.005695 mmap2(0xb7cb2000, 135168, PROT_READ|PROT_WRITE|PROT_EXEC,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7ce5000
     0.144397 getcwd("/run/rw/root"..., 1000) = 13
     0.000231 open("/usr/pike/lib/pike/modules", O_RDONLY|O_LARGEFILE) = 10
     0.001177 fstatfs(10, {f_type="NFS_SUPER_MAGIC", f_bsize=32768,
f_blocks=492053, f_bfree=111017, f_bavail=106017, f_files=2000896,
f_ffree=1723762, f_fsid={0, 0}, f_namelen=255, f_frsize=32768}) = 0
     0.000645 fstat64(10, {st_mode=S_IFDIR|0755, st_size=6144, ...}) = 0
     0.000186 fcntl64(10, F_GETFL)      = 0x8000 (flags O_RDONLY|O_LARGEFILE)
     0.000107 fcntl64(10, F_SETFD, FD_CLOEXEC) = 0
     0.000115 getdents64(10, /* 151 entries */, 32768) = 5216
     0.004966 getdents64(10, /* 0 entries */, 32768) = 0
     0.000455 close(10)                 = 0
     0.006290 stat64("/usr/pike/lib/pike/modules/Getopt.pmod",
{st_mode=S_IFREG|0644, st_size=0, ...}) = 0
     0.001030 stat64("/usr/pike/lib/pike/modules/Getopt.pmod",
{st_mode=S_IFREG|0644, st_size=0, ...}) = 0
     0.000317 stat64("/usr/pike/lib/pike/modules/Getopt.pmod.o",
{st_mode=S_IFREG|0644, st_size=18333, ...}) = 0
     0.000316 open("/usr/pike/lib/pike/modules/Getopt.pmod.o",
O_RDONLY|O_LARGEFILE) = 10
     0.001003 fcntl64(10, F_GETFD)      = 0
     0.000093 fcntl64(10, F_SETFD, FD_CLOEXEC) = 0
     0.000142 read(10, "6ke0I/6119(B3&
/d'!'#"..., 8192) = 8192
     0.000314 read(10, "33144
3H$31@711K7C7"..., 8192) = 8192
     0.000244 read(10,
"1A2M2I3J6F7K7I0L1A3N4J"...,
8192) = 1949
     0.000204 read(10, ""..., 8192)     = 0
     0.000200 close(10)                 = 0
     0.000190 gettimeofday({1250496223, 428816}, NULL) = 0
     0.006628 gettimeofday({1250496223, 435468}, NULL) = 0
     0.000442 gettimeofday({1250496223, 435890}, NULL) = 0
     0.000795 exit_group(0)             = ?

Perl trace:
     0.000000 execve("/usr/bin/perl", ["perl", "-e", ""], [/* 15 vars */]) = 0
     0.001767 brk(0)                    = 0x90d7000
     0.000157 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or
directory)
     0.000189 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS,
-1, 0) = 0xb8078000
     0.000160 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or
directory)
     0.000158 open("/etc/ld.so.cache", O_RDONLY) = 3
     0.000572 fstat64(3, {st_mode=S_IFREG|0644, st_size=14714, ...}) = 0
     0.000217 mmap2(NULL, 14714, PROT_READ, MAP_PRIVATE, 3, 0) = 0xb8074000
     0.000107 close(3)                  = 0
     0.000088 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or
directory)
     0.000155 open("/lib/libdl.so.2", O_RDONLY) = 3
     0.000549 read(3,
"7ELF@\n04X"..., 512) = 512
     0.000216 fstat64(3, {st_mode=S_IFREG|0644, st_size=9736, ...}) = 0
     0.000183 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS,
-1, 0) = 0xb8073000
     0.000134 mmap2(NULL, 12408, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE,
3, 0) = 0xb806f000
     0.000114 mmap2(0xb8071000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1) = 0xb8071000
     0.000177 close(3)                  = 0
     0.000095 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or
directory)
     0.000145 open("/lib/libm.so.6", O_RDONLY) = 3
     0.000608 read(3,
"7ELF`404d"..., 512) = 512
     0.000207 fstat64(3, {st_mode=S_IFREG|0644, st_size=149388, ...}) = 0
     0.000186 mmap2(NULL, 151680, PROT_READ|PROT_EXEC,
MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb8049000
     0.000121 mmap2(0xb806d000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x23) = 0xb806d000
     0.000168 close(3)                  = 0
     0.000099 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or
directory)
     0.000141 open("/lib/libpthread.so.0", O_RDONLY) = 3
     0.000557 read(3,
"7ELF0G044"..., 512)
= 512
     0.000211 fstat64(3, {st_mode=S_IFREG|0755, st_size=111967, ...}) = 0
     0.000185 mmap2(NULL, 94676, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE,
3, 0) = 0xb8031000
     0.000118 mmap2(0xb8045000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x13) = 0xb8045000
     0.000163 mmap2(0xb8047000, 4564, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb8047000
     0.000137 close(3)                  = 0
     0.000097 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or
directory)
     0.000143 open("/lib/libc.so.6", O_RDONLY) = 3
     0.000552 read(3,
"7ELFi04d"..., 512) = 512
     0.000205 fstat64(3, {st_mode=S_IFREG|0755, st_size=1302788, ...}) = 0
     0.000192 mmap2(NULL, 1312336, PROT_READ|PROT_EXEC,
MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7ef0000
     0.000121 mmap2(0xb802b000, 12288, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x13a) = 0xb802b000
     0.000162 mmap2(0xb802e000, 9808, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb802e000
     0.000137 close(3)                  = 0
     0.000103 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or
directory)
     0.000244 open("/lib/libcrypt.so.1", O_RDONLY) = 3
     0.000560 read(3,
"7ELFP04L"..., 512) = 512
     0.000204 fstat64(3, {st_mode=S_IFREG|0644, st_size=38356, ...}) = 0
     0.000184 mmap2(NULL, 201052, PROT_READ|PROT_EXEC,
MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7ebe000
     0.000120 mmap2(0xb7ec7000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x8) = 0xb7ec7000
     0.000165 mmap2(0xb7ec9000, 155996, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb7ec9000
     0.000139 close(3)                  = 0
     0.000208 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS,
-1, 0) = 0xb7ebd000
     0.000177 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS,
-1, 0) = 0xb7ebc000
     0.000112 set_thread_area({entry_number:-1 -> 6, base_addr:0xb7ebc6c0,
limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1,
seg_not_present:0, useable:1}) = 0
     0.000131 open("/dev/urandom", O_RDONLY) = 3
     0.000129 read(3, "s16n"..., 4) = 4
     0.000126 close(3)                  = 0
     0.000135 mprotect(0xb7ec7000, 4096, PROT_READ) = 0
     0.000638 mprotect(0xb802b000, 8192, PROT_READ) = 0
     0.000169 mprotect(0xb8045000, 4096, PROT_READ) = 0
     0.000123 mprotect(0xb806d000, 4096, PROT_READ) = 0
     0.000137 mprotect(0xb8071000, 4096, PROT_READ) = 0
     0.000171 mprotect(0xb8096000, 4096, PROT_READ) = 0
     0.000092 munmap(0xb8074000, 14714) = 0
     0.000105 set_tid_address(0xb7ebc708) = 14131
     0.000079 set_robust_list(0xb7ebc710, 0xc) = 0
     0.000086 futex(0xbfe1d280, FUTEX_WAKE_PRIVATE, 1) = 0
     0.000102 rt_sigaction(SIGRTMIN, {0xb80352a0, [], SA_SIGINFO}, NULL, 8) = 0
     0.000152 rt_sigaction(SIGRT_1, {0xb8035680, [], SA_RESTART|SA_SIGINFO},
NULL, 8) = 0
     0.000136 rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0
     0.000141 getrlimit(RLIMIT_STACK, {rlim_cur=8192*1024,
rlim_max=RLIM_INFINITY}) = 0
     0.000154 uname({sys="Linux", node="IceCube", ...}) = 0
     0.000448 rt_sigaction(SIGFPE, , , 8) = 0
     0.000526 brk(0)                    = 0x90d7000
     0.000082 brk(0x90f8000)            = 0x90f8000
     0.000367 getuid32()                = 0
     0.000084 geteuid32()               = 0
     0.000077 getgid32()                = 0
     0.000079 getegid32()               = 0
     0.000686 open("/dev/urandom", O_RDONLY|O_LARGEFILE) = 3
     0.000170 read(3, "0iz"..., 4) = 4
     0.000139 close(3)                  = 0
     0.000164 time(NULL)                = 1250496215
     0.000375 readlink("/proc/self/exe", "/usr/bin/perl"..., 4095) = 13
     0.000294
stat64("/usr/local/lib/site_perl/5.10.0/i486-linux-gnu-thread-multi",
0xbfe1d010) = -1 ENOENT (No such file or directory)
     0.000195 stat64("/usr/local/lib/site_perl/5.10.0", 0xbfe1d010) = -1 ENOENT
(No such file or directory)
     0.000144 stat64("/usr/local/lib/site_perl/i486-linux-gnu-thread-multi",
0xbfe1d010) = -1 ENOENT (No such file or directory)
     0.000256 ioctl(0, SNDCTL_TMR_TIMEBASE or TCGETS, {B38400 opost isig icanon
echo ...}) = 0
     0.000175 _llseek(0, 0, 0xbfe1ce80, SEEK_CUR) = -1 ESPIPE (Illegal seek)
     0.000111 ioctl(1, SNDCTL_TMR_TIMEBASE or TCGETS, 0xbfe1ce38) = -1 ENOTTY
(Inappropriate ioctl for device)
     0.000102 _llseek(1, 0, [6789], SEEK_CUR) = 0
     0.000105 ioctl(2, SNDCTL_TMR_TIMEBASE or TCGETS, 0xbfe1ce38) = -1 ENOTTY
(Inappropriate ioctl for device)
     0.000096 _llseek(2, 0, [6950], SEEK_CUR) = 0
     0.000095 open("/dev/null", O_RDONLY|O_LARGEFILE) = 3
     0.000132 ioctl(3, SNDCTL_TMR_TIMEBASE or TCGETS, 0xbfe1cf08) = -1 ENOTTY
(Inappropriate ioctl for device)
     0.000099 _llseek(3, 0, [0], SEEK_CUR) = 0
     0.000105 fcntl64(3, F_SETFD, FD_CLOEXEC) = 0
     0.000100 fstat64(3, {st_mode=S_IFCHR|0666, st_rdev=makedev(1, 3), ...}) = 0
     0.000185 rt_sigaction(SIGCHLD, NULL, , 8) = 0
     0.001793 close(3)                  = 0
     0.000306 exit_group(0)             = ?
-- 
Sincerely,
           Stephen R. van den Berg.

"God... root...  what's the difference?..."