runsolver Copyright (C) 2010 Olivier ROUSSEL

This is runsolver version 3.2.9a (svn: 651)

This program is distributed in the hope that it will be useful,
but WITHOUT ANY WARRANTY; without even the implied warranty of
MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the
GNU General Public License for more details.

command line: runsolver -s SIGUSR1 -M 1124 -C 300 -d 10 -w /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/slowlink/install/rand717.cudf.log.runsolver /home/competition/p2cudf-full-1.15/p2cudf-full-1.15 /home/competition/data/install/rand717.cudf /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/slowlink/install/rand717.cudf.result -sum(changed,installedsize),-count(removed),-notuptodate(solution),-count(changed) 

Enforcing CPUTime limit (soft limit, will send signal-name then SIGKILL): 300 seconds
Enforcing CPUTime limit (hard limit, will send SIGXCPU): 330 seconds
Enforcing VSIZE limit (soft limit, will send signal-name then SIGKILL): 1150976 KiB
Enforcing VSIZE limit (hard limit, stack expansion will fail with SIGSEGV, brk() and mmap() will return ENOMEM): 1202176 KiB
Current StackSize limit: 8192 KiB


[startup+0 s]
/proc/loadavg: 1.07 1.05 1.00 2/59 22254
/proc/meminfo: memFree=648804/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=1068 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) D 22253 22254 1750 0 -1 4194304 75 0 0 0 0 0 0 0 20 0 1 0 120729575 1093632 1 18446744073709551615 0 0 140735207324618 4287680320 4151620656 0 0 16781316 0 0 0 0 17 0 0 0 0 0 0
/proc/22254/statm: 267 1 0 0 0 28 0

[startup+0.164014 s]
/proc/loadavg: 1.07 1.05 1.00 2/59 22254
/proc/meminfo: memFree=648804/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.200352 s]
/proc/loadavg: 1.07 1.05 1.00 2/59 22254
/proc/meminfo: memFree=648804/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.300294 s]
/proc/loadavg: 1.07 1.05 1.00 2/59 22254
/proc/meminfo: memFree=648804/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.700238 s]
/proc/loadavg: 1.07 1.05 1.00 2/59 22254
/proc/meminfo: memFree=648804/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+1.50074 s]
/proc/loadavg: 1.07 1.05 1.00 2/70 22266
/proc/meminfo: memFree=615228/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
[pid=22257] ppid=22254 vsize=1047228 CPUtime=1.24
/proc/22257/stat : 22257 (java) S 22254 22254 1750 0 -1 4202496 22748 0 20 0 111 13 0 0 20 0 10 0 120729579 1072361472 20813 18446744073709551615 4194304 4228012 140736790069104 140736790060128 140274583342709 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 8 0 0
/proc/22257/statm: 261807 20813 2346 9 0 249157 0
[pid=22257/tid=22258] ppid=22254 vsize=1047228 CPUtime=0.62
/proc/22257/task/22258/stat : 22258 (java) R 22254 22254 1750 0 -1 4202560 11521 0 8 0 54 8 0 0 20 0 10 0 120729588 1072361472 20813 18446744073709551615 4194304 4228012 140736790069104 140274589868704 140274487676307 0 4 0 16800975 0 0 0 -1 0 0 0 12 0 0
[pid=22257/tid=22259] ppid=22254 vsize=1047228 CPUtime=0
/proc/22257/task/22259/stat : 22259 (java) S 22254 22254 1750 0 -1 4202560 3 0 0 0 0 0 0 0 20 0 10 0 120729595 1072361472 20813 18446744073709551615 4194304 4228012 140736790069104 140274482515072 140274583357003 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22260] ppid=22254 vsize=1047228 CPUtime=0
/proc/22257/task/22260/stat : 22260 (java) S 22254 22254 1750 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 120729596 1072361472 20813 18446744073709551615 4194304 4228012 140736790069104 140274481461264 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22261] ppid=22254 vsize=1047228 CPUtime=0
/proc/22257/task/22261/stat : 22261 (java) S 22254 22254 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 120729596 1072361472 20813 18446744073709551615 4194304 4228012 140736790069104 140274480408480 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22262] ppid=22254 vsize=1047228 CPUtime=0
/proc/22257/task/22262/stat : 22262 (java) S 22254 22254 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120729603 1072361472 20813 18446744073709551615 4194304 4228012 140736790069104 140274479356912 140274583364640 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22263] ppid=22254 vsize=1047228 CPUtime=0.29
/proc/22257/task/22263/stat : 22263 (java) R 22254 22254 1750 0 -1 4202560 4548 0 0 0 27 2 0 0 20 0 10 0 120729603 1072361472 20813 18446744073709551615 4194304 4228012 140736790069104 140274478289560 140274567140576 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22264] ppid=22254 vsize=1047228 CPUtime=0.31
/proc/22257/task/22264/stat : 22264 (java) R 22254 22254 1750 0 -1 4202560 5398 0 0 0 29 2 0 0 20 0 10 0 120729603 1072361472 20813 18446744073709551615 4194304 4228012 140736790069104 140274477237064 140274569009386 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22265] ppid=22254 vsize=1047228 CPUtime=0
/proc/22257/task/22265/stat : 22265 (java) S 22254 22254 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120729603 1072361472 20813 18446744073709551615 4194304 4228012 140736790069104 140274476199120 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22266] ppid=22254 vsize=1047228 CPUtime=0
/proc/22257/task/22266/stat : 22266 (java) S 22254 22254 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120729603 1072361472 20813 18446744073709551615 4194304 4228012 140736790069104 140274475146624 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.24
Current children cumulated vsize (KiB) 1056396

[startup+3.10076 s]
/proc/loadavg: 1.07 1.05 1.00 4/70 22266
/proc/meminfo: memFree=510200/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
[pid=22257] ppid=22254 vsize=1061472 CPUtime=2.82
/proc/22257/stat : 22257 (java) S 22254 22254 1750 0 -1 4202496 76228 0 20 0 250 32 0 0 20 0 10 0 120729579 1086947328 71047 18446744073709551615 4194304 4228012 140736790069104 140736790060128 140274583342709 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 8 0 0
/proc/22257/statm: 265368 71047 2366 9 0 252718 0
[pid=22257/tid=22258] ppid=22254 vsize=1061472 CPUtime=1.31
/proc/22257/task/22258/stat : 22258 (java) R 22254 22254 1750 0 -1 4202560 47396 0 8 0 110 21 0 0 20 0 10 0 120729588 1086947328 71047 18446744073709551615 4194304 4228012 140736790069104 140274589868736 140274487668327 0 4 0 16800975 0 0 0 -1 0 0 0 12 0 0
[pid=22257/tid=22259] ppid=22254 vsize=1061472 CPUtime=0.16
/proc/22257/task/22259/stat : 22259 (java) S 22254 22254 1750 0 -1 4202560 7950 0 0 0 14 2 0 0 20 0 10 0 120729595 1086947328 71047 18446744073709551615 4194304 4228012 140736790069104 140274482515072 140274583357003 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22260] ppid=22254 vsize=1061472 CPUtime=0
/proc/22257/task/22260/stat : 22260 (java) S 22254 22254 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 120729596 1086947328 71047 18446744073709551615 4194304 4228012 140736790069104 140274481461264 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22261] ppid=22254 vsize=1061472 CPUtime=0
/proc/22257/task/22261/stat : 22261 (java) S 22254 22254 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 120729596 1086947328 71047 18446744073709551615 4194304 4228012 140736790069104 140274480408480 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22262] ppid=22254 vsize=1061472 CPUtime=0
/proc/22257/task/22262/stat : 22262 (java) S 22254 22254 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120729603 1086947328 71047 18446744073709551615 4194304 4228012 140736790069104 140274479356912 140274583364640 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22263] ppid=22254 vsize=1061472 CPUtime=0.65
/proc/22257/task/22263/stat : 22263 (java) S 22254 22254 1750 0 -1 4202560 10330 0 0 0 59 6 0 0 20 0 10 0 120729603 1086947328 71047 18446744073709551615 4194304 4228012 140736790069104 140274478304272 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22264] ppid=22254 vsize=1061472 CPUtime=0.68
/proc/22257/task/22264/stat : 22264 (java) S 22254 22254 1750 0 -1 4202560 9270 0 0 0 65 3 0 0 20 0 10 0 120729603 1086947328 71047 18446744073709551615 4194304 4228012 140736790069104 140274477251600 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22265] ppid=22254 vsize=1061472 CPUtime=0
/proc/22257/task/22265/stat : 22265 (java) S 22254 22254 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120729603 1086947328 71047 18446744073709551615 4194304 4228012 140736790069104 140274476199120 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22266] ppid=22254 vsize=1061472 CPUtime=0
/proc/22257/task/22266/stat : 22266 (java) S 22254 22254 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120729603 1086947328 71047 18446744073709551615 4194304 4228012 140736790069104 140274475146624 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 2.82
Current children cumulated vsize (KiB) 1070640

[startup+6.30076 s]
/proc/loadavg: 1.06 1.05 1.00 2/70 22266
/proc/meminfo: memFree=217312/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
[pid=22257] ppid=22254 vsize=1043564 CPUtime=5.89
/proc/22257/stat : 22257 (java) S 22254 22254 1750 0 -1 4202496 121839 0 20 0 540 49 0 0 20 0 10 0 120729579 1068609536 112484 18446744073709551615 4194304 4228012 140736790069104 140736790060128 140274583342709 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 8 0 0
/proc/22257/statm: 260891 112484 2368 9 0 248241 0
[pid=22257/tid=22258] ppid=22254 vsize=1043564 CPUtime=3.2
/proc/22257/task/22258/stat : 22258 (java) S 22254 22254 1750 0 -1 4202560 47399 0 8 0 296 24 0 0 20 0 10 0 120729588 1068609536 112484 18446744073709551615 4194304 4228012 140736790069104 140274589876688 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 22 0 0
[pid=22257/tid=22259] ppid=22254 vsize=1043564 CPUtime=1.25
/proc/22257/task/22259/stat : 22259 (java) R 22254 22254 1750 0 -1 4202560 53554 0 0 0 110 15 0 0 20 0 10 0 120729595 1068609536 112484 18446744073709551615 4194304 4228012 140736790069104 140274482514448 140274567653337 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22260] ppid=22254 vsize=1043564 CPUtime=0
/proc/22257/task/22260/stat : 22260 (java) S 22254 22254 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 120729596 1068609536 112484 18446744073709551615 4194304 4228012 140736790069104 140274481461264 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22261] ppid=22254 vsize=1043564 CPUtime=0
/proc/22257/task/22261/stat : 22261 (java) S 22254 22254 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 120729596 1068609536 112484 18446744073709551615 4194304 4228012 140736790069104 140274480408480 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22262] ppid=22254 vsize=1043564 CPUtime=0
/proc/22257/task/22262/stat : 22262 (java) S 22254 22254 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120729603 1068609536 112484 18446744073709551615 4194304 4228012 140736790069104 140274479356912 140274583364640 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22263] ppid=22254 vsize=1043564 CPUtime=0.69
/proc/22257/task/22263/stat : 22263 (java) S 22254 22254 1750 0 -1 4202560 10333 0 0 0 63 6 0 0 20 0 10 0 120729603 1068609536 112484 18446744073709551615 4194304 4228012 140736790069104 140274478304272 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22264] ppid=22254 vsize=1043564 CPUtime=0.71
/proc/22257/task/22264/stat : 22264 (java) S 22254 22254 1750 0 -1 4202560 9270 0 0 0 68 3 0 0 20 0 10 0 120729603 1068609536 112484 18446744073709551615 4194304 4228012 140736790069104 140274477251600 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22265] ppid=22254 vsize=1043564 CPUtime=0
/proc/22257/task/22265/stat : 22265 (java) S 22254 22254 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120729603 1068609536 112484 18446744073709551615 4194304 4228012 140736790069104 140274476199120 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22266] ppid=22254 vsize=1043564 CPUtime=0
/proc/22257/task/22266/stat : 22266 (java) S 22254 22254 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120729603 1068609536 112484 18446744073709551615 4194304 4228012 140736790069104 140274475146624 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 5.89
Current children cumulated vsize (KiB) 1052732

[startup+12.7008 s]
/proc/loadavg: 1.22 1.08 1.01 3/70 22266
/proc/meminfo: memFree=41852/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
[pid=22257] ppid=22254 vsize=1044592 CPUtime=12.19
/proc/22257/stat : 22257 (java) S 22254 22254 1750 0 -1 4202496 155891 0 20 0 1158 61 0 0 20 0 11 0 120729579 1069662208 144743 18446744073709551615 4194304 4228012 140736790069104 140736790060128 140274583342709 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 8 0 0
/proc/22257/statm: 261148 144743 2378 9 0 248498 0
[pid=22257/tid=22258] ppid=22254 vsize=1044592 CPUtime=5.91
/proc/22257/task/22258/stat : 22258 (java) R 22254 22254 1750 0 -1 4202560 47762 0 8 0 565 26 0 0 20 0 11 0 120729588 1069662208 144743 18446744073709551615 4194304 4228012 140736790069104 140274589876392 140274488299863 0 4 0 16800975 0 0 0 -1 0 0 0 24 0 0
[pid=22257/tid=22259] ppid=22254 vsize=1044592 CPUtime=2.07
/proc/22257/task/22259/stat : 22259 (java) S 22254 22254 1750 0 -1 4202560 83798 0 0 0 184 23 0 0 20 0 11 0 120729595 1069662208 144743 18446744073709551615 4194304 4228012 140736790069104 140274482515072 140274583357003 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22260] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22260/stat : 22260 (java) S 22254 22254 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 144743 18446744073709551615 4194304 4228012 140736790069104 140274481461264 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22261] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22261/stat : 22261 (java) S 22254 22254 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 144743 18446744073709551615 4194304 4228012 140736790069104 140274480408480 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22262] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22262/stat : 22262 (java) S 22254 22254 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 144743 18446744073709551615 4194304 4228012 140736790069104 140274479356912 140274583364640 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22263] ppid=22254 vsize=1044592 CPUtime=2.11
/proc/22257/task/22263/stat : 22263 (java) R 22254 22254 1750 0 -1 4202560 13632 0 0 0 204 7 0 0 20 0 11 0 120729603 1069662208 144743 18446744073709551615 4194304 4228012 140736790069104 140274478304016 140274570033396 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22264] ppid=22254 vsize=1044592 CPUtime=2.05
/proc/22257/task/22264/stat : 22264 (java) R 22254 22254 1750 0 -1 4202560 9410 0 0 0 202 3 0 0 20 0 11 0 120729603 1069662208 144743 18446744073709551615 4194304 4228012 140736790069104 140274477251344 140274567618200 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22265] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22265/stat : 22265 (java) S 22254 22254 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 144743 18446744073709551615 4194304 4228012 140736790069104 140274476199120 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22266] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22266/stat : 22266 (java) S 22254 22254 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 144743 18446744073709551615 4194304 4228012 140736790069104 140274475146624 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 12.19
Current children cumulated vsize (KiB) 1053760

[startup+25.5009 s]
/proc/loadavg: 1.17 1.08 1.01 2/71 22267
/proc/meminfo: memFree=18656/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
[pid=22257] ppid=22254 vsize=1044592 CPUtime=24.8
/proc/22257/stat : 22257 (java) S 22254 22254 1750 0 -1 4202496 162918 0 20 0 2416 64 0 0 20 0 11 0 120729579 1069662208 150444 18446744073709551615 4194304 4228012 140736790069104 140736790060128 140274583342709 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 8 0 0
/proc/22257/statm: 261148 150444 2393 9 0 248498 0
[pid=22257/tid=22258] ppid=22254 vsize=1044592 CPUtime=16.7
/proc/22257/task/22258/stat : 22258 (java) R 22254 22254 1750 0 -1 4202560 47785 0 8 0 1644 26 0 0 20 0 11 0 120729588 1069662208 150444 18446744073709551615 4194304 4228012 140736790069104 140274589876328 140274488127927 0 4 0 16800975 0 0 0 -1 0 0 0 24 0 0
[pid=22257/tid=22259] ppid=22254 vsize=1044592 CPUtime=3.29
/proc/22257/task/22259/stat : 22259 (java) S 22254 22254 1750 0 -1 4202560 90769 0 0 0 303 26 0 0 20 0 11 0 120729595 1069662208 150444 18446744073709551615 4194304 4228012 140736790069104 140274482515072 140274583357003 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22260] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22260/stat : 22260 (java) S 22254 22254 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 150444 18446744073709551615 4194304 4228012 140736790069104 140274481461264 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22261] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22261/stat : 22261 (java) S 22254 22254 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 150444 18446744073709551615 4194304 4228012 140736790069104 140274480408480 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22262] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22262/stat : 22262 (java) S 22254 22254 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 150444 18446744073709551615 4194304 4228012 140736790069104 140274479356912 140274583364640 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22263] ppid=22254 vsize=1044592 CPUtime=2.41
/proc/22257/task/22263/stat : 22263 (java) S 22254 22254 1750 0 -1 4202560 13652 0 0 0 234 7 0 0 20 0 11 0 120729603 1069662208 150444 18446744073709551615 4194304 4228012 140736790069104 140274478304272 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22264] ppid=22254 vsize=1044592 CPUtime=2.35
/proc/22257/task/22264/stat : 22264 (java) S 22254 22254 1750 0 -1 4202560 9423 0 0 0 232 3 0 0 20 0 11 0 120729603 1069662208 150444 18446744073709551615 4194304 4228012 140736790069104 140274477251600 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22265] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22265/stat : 22265 (java) S 22254 22254 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 150444 18446744073709551615 4194304 4228012 140736790069104 140274476199120 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22266] ppid=22254 vsize=1044592 CPUtime=0.01
/proc/22257/task/22266/stat : 22266 (java) S 22254 22254 1750 0 -1 4202560 1 0 0 0 0 1 0 0 20 0 11 0 120729603 1069662208 150444 18446744073709551615 4194304 4228012 140736790069104 140274475146624 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22267] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22267/stat : 22267 (java) S 22254 22254 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120730800 1069662208 150444 18446744073709551615 4194304 4228012 140736790069104 140274473949024 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 24.8
Current children cumulated vsize (KiB) 1053760

[startup+51.1009 s]
/proc/loadavg: 1.11 1.07 1.01 2/71 22267
/proc/meminfo: memFree=11216/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
[pid=22257] ppid=22254 vsize=1044592 CPUtime=50.04
/proc/22257/stat : 22257 (java) S 22254 22254 1750 0 -1 4202496 164758 0 20 0 4939 65 0 0 20 0 11 0 120729579 1069662208 152280 18446744073709551615 4194304 4228012 140736790069104 140736790060128 140274583342709 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 8 0 0
/proc/22257/statm: 261148 152280 2393 9 0 248498 0
[pid=22257/tid=22258] ppid=22254 vsize=1044592 CPUtime=39.41
/proc/22257/task/22258/stat : 22258 (java) R 22254 22254 1750 0 -1 4202560 47786 0 8 0 3915 26 0 0 20 0 11 0 120729588 1069662208 152280 18446744073709551615 4194304 4228012 140736790069104 140274589876200 140274488535936 0 4 0 16800975 0 0 0 -1 0 0 0 24 0 0
[pid=22257/tid=22259] ppid=22254 vsize=1044592 CPUtime=5.74
/proc/22257/task/22259/stat : 22259 (java) S 22254 22254 1750 0 -1 4202560 92605 0 0 0 547 27 0 0 20 0 11 0 120729595 1069662208 152280 18446744073709551615 4194304 4228012 140736790069104 140274482515072 140274583357003 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22260] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22260/stat : 22260 (java) S 22254 22254 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 152280 18446744073709551615 4194304 4228012 140736790069104 140274481461264 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22261] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22261/stat : 22261 (java) S 22254 22254 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 152280 18446744073709551615 4194304 4228012 140736790069104 140274480408480 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22262] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22262/stat : 22262 (java) S 22254 22254 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 152280 18446744073709551615 4194304 4228012 140736790069104 140274479356912 140274583364640 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22263] ppid=22254 vsize=1044592 CPUtime=2.43
/proc/22257/task/22263/stat : 22263 (java) S 22254 22254 1750 0 -1 4202560 13652 0 0 0 236 7 0 0 20 0 11 0 120729603 1069662208 152280 18446744073709551615 4194304 4228012 140736790069104 140274478304272 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22264] ppid=22254 vsize=1044592 CPUtime=2.39
/proc/22257/task/22264/stat : 22264 (java) S 22254 22254 1750 0 -1 4202560 9423 0 0 0 236 3 0 0 20 0 11 0 120729603 1069662208 152280 18446744073709551615 4194304 4228012 140736790069104 140274477251600 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22265] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22265/stat : 22265 (java) S 22254 22254 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 152280 18446744073709551615 4194304 4228012 140736790069104 140274476199120 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22266] ppid=22254 vsize=1044592 CPUtime=0.03
/proc/22257/task/22266/stat : 22266 (java) S 22254 22254 1750 0 -1 4202560 4 0 0 0 0 3 0 0 20 0 11 0 120729603 1069662208 152280 18446744073709551615 4194304 4228012 140736790069104 140274475146624 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22267] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22267/stat : 22267 (java) S 22254 22254 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120730800 1069662208 152280 18446744073709551615 4194304 4228012 140736790069104 140274473949024 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 50.04
Current children cumulated vsize (KiB) 1053760

[startup+102.301 s]
/proc/loadavg: 1.05 1.06 1.00 2/71 22267
/proc/meminfo: memFree=9604/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
[pid=22257] ppid=22254 vsize=1044592 CPUtime=100.55
/proc/22257/stat : 22257 (java) S 22254 22254 1750 0 -1 4202496 165117 0 20 0 9989 66 0 0 20 0 11 0 120729579 1069662208 152635 18446744073709551615 4194304 4228012 140736790069104 140736790060128 140274583342709 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 8 0 0
/proc/22257/statm: 261148 152635 2393 9 0 248498 0
[pid=22257/tid=22258] ppid=22254 vsize=1044592 CPUtime=86.28
/proc/22257/task/22258/stat : 22258 (java) R 22254 22254 1750 0 -1 4202560 47786 0 8 0 8601 27 0 0 20 0 11 0 120729588 1069662208 152635 18446744073709551615 4194304 4228012 140736790069104 140274589876200 140274488648301 0 4 0 16800975 0 0 0 -1 0 0 0 24 0 0
[pid=22257/tid=22259] ppid=22254 vsize=1044592 CPUtime=9.32
/proc/22257/task/22259/stat : 22259 (java) S 22254 22254 1750 0 -1 4202560 92961 0 0 0 904 28 0 0 20 0 11 0 120729595 1069662208 152635 18446744073709551615 4194304 4228012 140736790069104 140274482515072 140274583357003 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22260] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22260/stat : 22260 (java) S 22254 22254 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 152635 18446744073709551615 4194304 4228012 140736790069104 140274481461264 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22261] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22261/stat : 22261 (java) S 22254 22254 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 152635 18446744073709551615 4194304 4228012 140736790069104 140274480408480 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22262] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22262/stat : 22262 (java) S 22254 22254 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 152635 18446744073709551615 4194304 4228012 140736790069104 140274479356912 140274583364640 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22263] ppid=22254 vsize=1044592 CPUtime=2.45
/proc/22257/task/22263/stat : 22263 (java) S 22254 22254 1750 0 -1 4202560 13652 0 0 0 238 7 0 0 20 0 11 0 120729603 1069662208 152635 18446744073709551615 4194304 4228012 140736790069104 140274478304272 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22264] ppid=22254 vsize=1044592 CPUtime=2.41
/proc/22257/task/22264/stat : 22264 (java) S 22254 22254 1750 0 -1 4202560 9423 0 0 0 238 3 0 0 20 0 11 0 120729603 1069662208 152635 18446744073709551615 4194304 4228012 140736790069104 140274477251600 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22265] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22265/stat : 22265 (java) S 22254 22254 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 152635 18446744073709551615 4194304 4228012 140736790069104 140274476199120 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22266] ppid=22254 vsize=1044592 CPUtime=0.05
/proc/22257/task/22266/stat : 22266 (java) S 22254 22254 1750 0 -1 4202560 7 0 0 0 0 5 0 0 20 0 11 0 120729603 1069662208 152635 18446744073709551615 4194304 4228012 140736790069104 140274475146624 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22267] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22267/stat : 22267 (java) S 22254 22254 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120730800 1069662208 152635 18446744073709551615 4194304 4228012 140736790069104 140274473949024 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 100.55
Current children cumulated vsize (KiB) 1053760

[startup+162.301 s]
/proc/loadavg: 1.05 1.06 1.00 2/71 22267
/proc/meminfo: memFree=9980/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
[pid=22257] ppid=22254 vsize=1044592 CPUtime=159.8
/proc/22257/stat : 22257 (java) S 22254 22254 1750 0 -1 4202496 168295 0 20 0 15913 67 0 0 20 0 11 0 120729579 1069662208 155805 18446744073709551615 4194304 4228012 140736790069104 140736790060128 140274583342709 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 8 0 0
/proc/22257/statm: 261148 155805 2393 9 0 248498 0
[pid=22257/tid=22258] ppid=22254 vsize=1044592 CPUtime=138.09
/proc/22257/task/22258/stat : 22258 (java) R 22254 22254 1750 0 -1 4202560 47786 0 8 0 13782 27 0 0 20 0 11 0 120729588 1069662208 155805 18446744073709551615 4194304 4228012 140736790069104 140274589876296 140274488127762 0 4 0 16800975 0 0 0 -1 0 0 0 25 0 0
[pid=22257/tid=22259] ppid=22254 vsize=1044592 CPUtime=16.68
/proc/22257/task/22259/stat : 22259 (java) S 22254 22254 1750 0 -1 4202560 96133 0 0 0 1640 28 0 0 20 0 11 0 120729595 1069662208 155805 18446744073709551615 4194304 4228012 140736790069104 140274482515072 140274583357003 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22260] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22260/stat : 22260 (java) S 22254 22254 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 155805 18446744073709551615 4194304 4228012 140736790069104 140274481461264 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22261] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22261/stat : 22261 (java) S 22254 22254 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 155805 18446744073709551615 4194304 4228012 140736790069104 140274480408480 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22262] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22262/stat : 22262 (java) S 22254 22254 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 155805 18446744073709551615 4194304 4228012 140736790069104 140274479356912 140274583364640 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22263] ppid=22254 vsize=1044592 CPUtime=2.48
/proc/22257/task/22263/stat : 22263 (java) S 22254 22254 1750 0 -1 4202560 13652 0 0 0 241 7 0 0 20 0 11 0 120729603 1069662208 155805 18446744073709551615 4194304 4228012 140736790069104 140274478304272 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22264] ppid=22254 vsize=1044592 CPUtime=2.44
/proc/22257/task/22264/stat : 22264 (java) S 22254 22254 1750 0 -1 4202560 9423 0 0 0 241 3 0 0 20 0 11 0 120729603 1069662208 155805 18446744073709551615 4194304 4228012 140736790069104 140274477251600 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22265] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22265/stat : 22265 (java) S 22254 22254 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 155805 18446744073709551615 4194304 4228012 140736790069104 140274476199120 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22266] ppid=22254 vsize=1044592 CPUtime=0.08
/proc/22257/task/22266/stat : 22266 (java) S 22254 22254 1750 0 -1 4202560 13 0 0 0 0 8 0 0 20 0 11 0 120729603 1069662208 155805 18446744073709551615 4194304 4228012 140736790069104 140274475146624 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22267] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22267/stat : 22267 (java) S 22254 22254 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120730800 1069662208 155805 18446744073709551615 4194304 4228012 140736790069104 140274473949024 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 159.8
Current children cumulated vsize (KiB) 1053760

[startup+222.304 s]
/proc/loadavg: 1.02 1.05 1.00 2/71 22267
/proc/meminfo: memFree=9112/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
[pid=22257] ppid=22254 vsize=1044592 CPUtime=219.18
/proc/22257/stat : 22257 (java) S 22254 22254 1750 0 -1 4202496 170190 0 20 0 21850 68 0 0 20 0 11 0 120729579 1069662208 157692 18446744073709551615 4194304 4228012 140736790069104 140736790060128 140274583342709 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 8 0 0
/proc/22257/statm: 261148 157692 2393 9 0 248498 0
[pid=22257/tid=22258] ppid=22254 vsize=1044592 CPUtime=194.46
/proc/22257/task/22258/stat : 22258 (java) R 22254 22254 1750 0 -1 4202560 47786 0 8 0 19419 27 0 0 20 0 11 0 120729588 1069662208 157692 18446744073709551615 4194304 4228012 140736790069104 140274589876296 140274488306207 0 4 0 16800975 0 0 0 -1 0 0 0 25 0 0
[pid=22257/tid=22259] ppid=22254 vsize=1044592 CPUtime=19.64
/proc/22257/task/22259/stat : 22259 (java) S 22254 22254 1750 0 -1 4202560 98022 0 0 0 1934 30 0 0 20 0 11 0 120729595 1069662208 157692 18446744073709551615 4194304 4228012 140736790069104 140274482515072 140274583357003 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22260] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22260/stat : 22260 (java) S 22254 22254 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 157692 18446744073709551615 4194304 4228012 140736790069104 140274481461264 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22261] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22261/stat : 22261 (java) S 22254 22254 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 157692 18446744073709551615 4194304 4228012 140736790069104 140274480408480 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22262] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22262/stat : 22262 (java) S 22254 22254 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 157692 18446744073709551615 4194304 4228012 140736790069104 140274479356912 140274583364640 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22263] ppid=22254 vsize=1044592 CPUtime=2.48
/proc/22257/task/22263/stat : 22263 (java) S 22254 22254 1750 0 -1 4202560 13652 0 0 0 241 7 0 0 20 0 11 0 120729603 1069662208 157692 18446744073709551615 4194304 4228012 140736790069104 140274478304272 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22264] ppid=22254 vsize=1044592 CPUtime=2.46
/proc/22257/task/22264/stat : 22264 (java) S 22254 22254 1750 0 -1 4202560 9423 0 0 0 243 3 0 0 20 0 11 0 120729603 1069662208 157692 18446744073709551615 4194304 4228012 140736790069104 140274477251600 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22265] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22265/stat : 22265 (java) S 22254 22254 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 157692 18446744073709551615 4194304 4228012 140736790069104 140274476199120 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22266] ppid=22254 vsize=1044592 CPUtime=0.11
/proc/22257/task/22266/stat : 22266 (java) S 22254 22254 1750 0 -1 4202560 19 0 0 0 0 11 0 0 20 0 11 0 120729603 1069662208 157692 18446744073709551615 4194304 4228012 140736790069104 140274475146624 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22267] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22267/stat : 22267 (java) S 22254 22254 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120730800 1069662208 157692 18446744073709551615 4194304 4228012 140736790069104 140274473949024 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 219.18
Current children cumulated vsize (KiB) 1053760

[startup+282.301 s]
/proc/loadavg: 1.00 1.03 1.00 2/71 22267
/proc/meminfo: memFree=10228/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
[pid=22257] ppid=22254 vsize=1044592 CPUtime=278.54
/proc/22257/stat : 22257 (java) S 22254 22254 1750 0 -1 4202496 172581 0 20 0 27785 69 0 0 20 0 11 0 120729579 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140736790060128 140274583342709 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 8 0 0
/proc/22257/statm: 261148 160076 2393 9 0 248498 0
[pid=22257/tid=22258] ppid=22254 vsize=1044592 CPUtime=250.79
/proc/22257/task/22258/stat : 22258 (java) R 22254 22254 1750 0 -1 4202560 47786 0 8 0 25052 27 0 0 20 0 11 0 120729588 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140274589876296 140274488648599 0 4 0 16800975 0 0 0 -1 0 0 0 25 0 0
[pid=22257/tid=22259] ppid=22254 vsize=1044592 CPUtime=22.64
/proc/22257/task/22259/stat : 22259 (java) S 22254 22254 1750 0 -1 4202560 100407 0 0 0 2234 30 0 0 20 0 11 0 120729595 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140274482515072 140274583357003 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22260] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22260/stat : 22260 (java) S 22254 22254 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140274481461264 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22261] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22261/stat : 22261 (java) S 22254 22254 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140274480408480 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22262] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22262/stat : 22262 (java) S 22254 22254 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140274479356912 140274583364640 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22263] ppid=22254 vsize=1044592 CPUtime=2.48
/proc/22257/task/22263/stat : 22263 (java) S 22254 22254 1750 0 -1 4202560 13652 0 0 0 241 7 0 0 20 0 11 0 120729603 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140274478304272 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22264] ppid=22254 vsize=1044592 CPUtime=2.46
/proc/22257/task/22264/stat : 22264 (java) S 22254 22254 1750 0 -1 4202560 9423 0 0 0 243 3 0 0 20 0 11 0 120729603 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140274477251600 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22265] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22265/stat : 22265 (java) S 22254 22254 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140274476199120 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22266] ppid=22254 vsize=1044592 CPUtime=0.14
/proc/22257/task/22266/stat : 22266 (java) S 22254 22254 1750 0 -1 4202560 25 0 0 0 0 14 0 0 20 0 11 0 120729603 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140274475146624 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22267] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22267/stat : 22267 (java) S 22254 22254 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120730800 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140274473949024 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 278.54
Current children cumulated vsize (KiB) 1053760

Solver just ended. Dumping a history of the last processes samples

[startup+288.001 s]
/proc/loadavg: 1.00 1.03 1.00 2/71 22267
/proc/meminfo: memFree=10104/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
[pid=22257] ppid=22254 vsize=1044592 CPUtime=284.18
/proc/22257/stat : 22257 (java) S 22254 22254 1750 0 -1 4202496 172581 0 20 0 28349 69 0 0 20 0 11 0 120729579 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140736790060128 140274583342709 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 8 0 0
/proc/22257/statm: 261148 160076 2393 9 0 248498 0
[pid=22257/tid=22258] ppid=22254 vsize=1044592 CPUtime=256.43
/proc/22257/task/22258/stat : 22258 (java) R 22254 22254 1750 0 -1 4202560 47786 0 8 0 25616 27 0 0 20 0 11 0 120729588 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140274589876296 140274488562526 0 4 0 16800975 0 0 0 -1 0 0 0 25 0 0
[pid=22257/tid=22259] ppid=22254 vsize=1044592 CPUtime=22.64
/proc/22257/task/22259/stat : 22259 (java) S 22254 22254 1750 0 -1 4202560 100407 0 0 0 2234 30 0 0 20 0 11 0 120729595 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140274482515072 140274583357003 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22260] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22260/stat : 22260 (java) S 22254 22254 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140274481461264 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22261] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22261/stat : 22261 (java) S 22254 22254 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140274480408480 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22262] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22262/stat : 22262 (java) S 22254 22254 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140274479356912 140274583364640 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22263] ppid=22254 vsize=1044592 CPUtime=2.48
/proc/22257/task/22263/stat : 22263 (java) S 22254 22254 1750 0 -1 4202560 13652 0 0 0 241 7 0 0 20 0 11 0 120729603 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140274478304272 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22264] ppid=22254 vsize=1044592 CPUtime=2.46
/proc/22257/task/22264/stat : 22264 (java) S 22254 22254 1750 0 -1 4202560 9423 0 0 0 243 3 0 0 20 0 11 0 120729603 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140274477251600 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22265] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22265/stat : 22265 (java) S 22254 22254 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140274476199120 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22266] ppid=22254 vsize=1044592 CPUtime=0.14
/proc/22257/task/22266/stat : 22266 (java) S 22254 22254 1750 0 -1 4202560 25 0 0 0 0 14 0 0 20 0 11 0 120729603 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140274475146624 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22267] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22267/stat : 22267 (java) S 22254 22254 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120730800 1069662208 160076 18446744073709551615 4194304 4228012 140736790069104 140274473949024 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 284.18
Current children cumulated vsize (KiB) 1053760

[startup+294.401 s]
/proc/loadavg: 1.00 1.03 1.00 2/71 22267
/proc/meminfo: memFree=10228/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
[pid=22257] ppid=22254 vsize=1044592 CPUtime=290.51
/proc/22257/stat : 22257 (java) S 22254 22254 1750 0 -1 4202496 173155 0 20 0 28982 69 0 0 20 0 11 0 120729579 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140736790060128 140274583342709 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 8 0 0
/proc/22257/statm: 261148 160649 2393 9 0 248498 0
[pid=22257/tid=22258] ppid=22254 vsize=1044592 CPUtime=262
/proc/22257/task/22258/stat : 22258 (java) R 22254 22254 1750 0 -1 4202560 47786 0 8 0 26173 27 0 0 20 0 11 0 120729588 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274589876296 140274488666873 0 4 0 16800975 0 0 0 -1 0 0 0 25 0 0
[pid=22257/tid=22259] ppid=22254 vsize=1044592 CPUtime=23.39
/proc/22257/task/22259/stat : 22259 (java) S 22254 22254 1750 0 -1 4202560 100981 0 0 0 2309 30 0 0 20 0 11 0 120729595 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274482515072 140274583357003 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22260] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22260/stat : 22260 (java) S 22254 22254 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274481461264 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22261] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22261/stat : 22261 (java) S 22254 22254 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274480408480 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22262] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22262/stat : 22262 (java) S 22254 22254 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274479356912 140274583364640 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22263] ppid=22254 vsize=1044592 CPUtime=2.48
/proc/22257/task/22263/stat : 22263 (java) S 22254 22254 1750 0 -1 4202560 13652 0 0 0 241 7 0 0 20 0 11 0 120729603 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274478304272 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22264] ppid=22254 vsize=1044592 CPUtime=2.46
/proc/22257/task/22264/stat : 22264 (java) S 22254 22254 1750 0 -1 4202560 9423 0 0 0 243 3 0 0 20 0 11 0 120729603 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274477251600 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22265] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22265/stat : 22265 (java) S 22254 22254 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274476199120 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22266] ppid=22254 vsize=1044592 CPUtime=0.14
/proc/22257/task/22266/stat : 22266 (java) S 22254 22254 1750 0 -1 4202560 25 0 0 0 0 14 0 0 20 0 11 0 120729603 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274475146624 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22267] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22267/stat : 22267 (java) S 22254 22254 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120730800 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274473949024 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.51
Current children cumulated vsize (KiB) 1053760

[startup+296.01 s]
/proc/loadavg: 1.00 1.03 1.00 2/71 22267
/proc/meminfo: memFree=10228/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
[pid=22257] ppid=22254 vsize=1044592 CPUtime=292.1
/proc/22257/stat : 22257 (java) S 22254 22254 1750 0 -1 4202496 173155 0 20 0 29141 69 0 0 20 0 11 0 120729579 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140736790060128 140274583342709 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 8 0 0
/proc/22257/statm: 261148 160649 2393 9 0 248498 0
[pid=22257/tid=22258] ppid=22254 vsize=1044592 CPUtime=263.59
/proc/22257/task/22258/stat : 22258 (java) R 22254 22254 1750 0 -1 4202560 47786 0 8 0 26332 27 0 0 20 0 11 0 120729588 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274589876296 140274488657142 0 4 0 16800975 0 0 0 -1 0 0 0 25 0 0
[pid=22257/tid=22259] ppid=22254 vsize=1044592 CPUtime=23.39
/proc/22257/task/22259/stat : 22259 (java) S 22254 22254 1750 0 -1 4202560 100981 0 0 0 2309 30 0 0 20 0 11 0 120729595 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274482515072 140274583357003 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22260] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22260/stat : 22260 (java) S 22254 22254 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274481461264 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22261] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22261/stat : 22261 (java) S 22254 22254 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274480408480 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22262] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22262/stat : 22262 (java) S 22254 22254 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274479356912 140274583364640 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22263] ppid=22254 vsize=1044592 CPUtime=2.48
/proc/22257/task/22263/stat : 22263 (java) S 22254 22254 1750 0 -1 4202560 13652 0 0 0 241 7 0 0 20 0 11 0 120729603 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274478304272 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22264] ppid=22254 vsize=1044592 CPUtime=2.46
/proc/22257/task/22264/stat : 22264 (java) S 22254 22254 1750 0 -1 4202560 9423 0 0 0 243 3 0 0 20 0 11 0 120729603 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274477251600 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22265] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22265/stat : 22265 (java) S 22254 22254 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274476199120 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22266] ppid=22254 vsize=1044592 CPUtime=0.14
/proc/22257/task/22266/stat : 22266 (java) S 22254 22254 1750 0 -1 4202560 25 0 0 0 0 14 0 0 20 0 11 0 120729603 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274475146624 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22267] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22267/stat : 22267 (java) S 22254 22254 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120730800 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274473949024 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 292.1
Current children cumulated vsize (KiB) 1053760

[startup+296.801 s]
/proc/loadavg: 1.00 1.03 1.00 2/71 22267
/proc/meminfo: memFree=10228/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
[pid=22257] ppid=22254 vsize=1044592 CPUtime=292.89
/proc/22257/stat : 22257 (java) S 22254 22254 1750 0 -1 4202496 173155 0 20 0 29220 69 0 0 20 0 11 0 120729579 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140736790060128 140274583342709 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 8 0 0
/proc/22257/statm: 261148 160649 2393 9 0 248498 0
[pid=22257/tid=22258] ppid=22254 vsize=1044592 CPUtime=264.37
/proc/22257/task/22258/stat : 22258 (java) R 22254 22254 1750 0 -1 4202560 47786 0 8 0 26410 27 0 0 20 0 11 0 120729588 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274589876296 140274488137901 0 4 0 16800975 0 0 0 -1 0 0 0 25 0 0
[pid=22257/tid=22259] ppid=22254 vsize=1044592 CPUtime=23.39
/proc/22257/task/22259/stat : 22259 (java) S 22254 22254 1750 0 -1 4202560 100981 0 0 0 2309 30 0 0 20 0 11 0 120729595 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274482515072 140274583357003 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22260] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22260/stat : 22260 (java) S 22254 22254 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274481461264 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22261] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22261/stat : 22261 (java) S 22254 22254 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120729596 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274480408480 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22262] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22262/stat : 22262 (java) S 22254 22254 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274479356912 140274583364640 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22263] ppid=22254 vsize=1044592 CPUtime=2.48
/proc/22257/task/22263/stat : 22263 (java) S 22254 22254 1750 0 -1 4202560 13652 0 0 0 241 7 0 0 20 0 11 0 120729603 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274478304272 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22264] ppid=22254 vsize=1044592 CPUtime=2.46
/proc/22257/task/22264/stat : 22264 (java) S 22254 22254 1750 0 -1 4202560 9423 0 0 0 243 3 0 0 20 0 11 0 120729603 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274477251600 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22265] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22265/stat : 22265 (java) S 22254 22254 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120729603 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274476199120 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22266] ppid=22254 vsize=1044592 CPUtime=0.14
/proc/22257/task/22266/stat : 22266 (java) S 22254 22254 1750 0 -1 4202560 25 0 0 0 0 14 0 0 20 0 11 0 120729603 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274475146624 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22267] ppid=22254 vsize=1044592 CPUtime=0
/proc/22257/task/22267/stat : 22267 (java) S 22254 22254 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120730800 1069662208 160649 18446744073709551615 4194304 4228012 140736790069104 140274473949024 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 292.89
Current children cumulated vsize (KiB) 1053760

[startup+297.601 s]
/proc/loadavg: 1.00 1.03 1.00 2/71 22267
/proc/meminfo: memFree=10228/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
[pid=22257] ppid=22254 vsize=1045620 CPUtime=293.39
/proc/22257/stat : 22257 (java) S 22254 22254 1750 0 -1 4202496 173170 0 20 0 29270 69 0 0 20 0 11 0 120729579 1070714880 160662 18446744073709551615 4194304 4228012 140736790069104 140736790060128 140274583342709 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 8 0 0
/proc/22257/statm: 261405 160662 2393 9 0 248755 0
[pid=22257/tid=22258] ppid=22254 vsize=1045620 CPUtime=264.84
/proc/22257/task/22258/stat : 22258 (java) S 22254 22254 1750 0 -1 4202560 47795 0 8 0 26457 27 0 0 20 0 11 0 120729588 1070714880 160662 18446744073709551615 4194304 4228012 140736790069104 140274589877168 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 25 0 0
[pid=22257/tid=22259] ppid=22254 vsize=1045620 CPUtime=23.39
/proc/22257/task/22259/stat : 22259 (java) S 22254 22254 1750 0 -1 4202560 100981 0 0 0 2309 30 0 0 20 0 11 0 120729595 1070714880 160662 18446744073709551615 4194304 4228012 140736790069104 140274482515072 140274583357003 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22260] ppid=22254 vsize=1045620 CPUtime=0
/proc/22257/task/22260/stat : 22260 (java) S 22254 22254 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120729596 1070714880 160662 18446744073709551615 4194304 4228012 140736790069104 140274481461264 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22261] ppid=22254 vsize=1045620 CPUtime=0
/proc/22257/task/22261/stat : 22261 (java) S 22254 22254 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120729596 1070714880 160662 18446744073709551615 4194304 4228012 140736790069104 140274480408480 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22262] ppid=22254 vsize=1045620 CPUtime=0
/proc/22257/task/22262/stat : 22262 (java) S 22254 22254 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120729603 1070714880 160662 18446744073709551615 4194304 4228012 140736790069104 140274479356912 140274583364640 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22263] ppid=22254 vsize=1045620 CPUtime=2.5
/proc/22257/task/22263/stat : 22263 (java) S 22254 22254 1750 0 -1 4202560 13652 0 0 0 243 7 0 0 20 0 11 0 120729603 1070714880 160662 18446744073709551615 4194304 4228012 140736790069104 140274478304272 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22264] ppid=22254 vsize=1045620 CPUtime=2.47
/proc/22257/task/22264/stat : 22264 (java) S 22254 22254 1750 0 -1 4202560 9423 0 0 0 244 3 0 0 20 0 11 0 120729603 1070714880 160662 18446744073709551615 4194304 4228012 140736790069104 140274477251600 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22265] ppid=22254 vsize=1045620 CPUtime=0
/proc/22257/task/22265/stat : 22265 (java) S 22254 22254 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120729603 1070714880 160662 18446744073709551615 4194304 4228012 140736790069104 140274476199120 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22266] ppid=22254 vsize=1045620 CPUtime=0.15
/proc/22257/task/22266/stat : 22266 (java) S 22254 22254 1750 0 -1 4202560 25 0 0 0 0 15 0 0 20 0 11 0 120729603 1070714880 160662 18446744073709551615 4194304 4228012 140736790069104 140274475146624 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 293.39
Current children cumulated vsize (KiB) 1054788

[startup+297.801 s]
/proc/loadavg: 1.00 1.03 1.00 2/71 22267
/proc/meminfo: memFree=10228/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
[pid=22257] ppid=22254 vsize=1045620 CPUtime=293.39
/proc/22257/stat : 22257 (java) S 22254 22254 1750 0 -1 4202496 173170 0 20 0 29270 69 0 0 20 0 11 0 120729579 1070714880 160662 18446744073709551615 4194304 4228012 140736790069104 140736790060128 140274583342709 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 8 0 0
/proc/22257/statm: 261405 160662 2393 9 0 248755 0
[pid=22257/tid=22258] ppid=22254 vsize=1045620 CPUtime=264.84
/proc/22257/task/22258/stat : 22258 (java) S 22254 22254 1750 0 -1 4202560 47795 0 8 0 26457 27 0 0 20 0 11 0 120729588 1070714880 160662 18446744073709551615 4194304 4228012 140736790069104 140274589877168 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 25 0 0
[pid=22257/tid=22259] ppid=22254 vsize=1045620 CPUtime=23.39
/proc/22257/task/22259/stat : 22259 (java) S 22254 22254 1750 0 -1 4202560 100981 0 0 0 2309 30 0 0 20 0 11 0 120729595 1070714880 160662 18446744073709551615 4194304 4228012 140736790069104 140274482515072 140274583357003 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22260] ppid=22254 vsize=1045620 CPUtime=0
/proc/22257/task/22260/stat : 22260 (java) S 22254 22254 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120729596 1070714880 160662 18446744073709551615 4194304 4228012 140736790069104 140274481461264 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22261] ppid=22254 vsize=1045620 CPUtime=0
/proc/22257/task/22261/stat : 22261 (java) S 22254 22254 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120729596 1070714880 160662 18446744073709551615 4194304 4228012 140736790069104 140274480408480 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22262] ppid=22254 vsize=1045620 CPUtime=0
/proc/22257/task/22262/stat : 22262 (java) S 22254 22254 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120729603 1070714880 160662 18446744073709551615 4194304 4228012 140736790069104 140274479356912 140274583364640 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22263] ppid=22254 vsize=1045620 CPUtime=2.5
/proc/22257/task/22263/stat : 22263 (java) S 22254 22254 1750 0 -1 4202560 13652 0 0 0 243 7 0 0 20 0 11 0 120729603 1070714880 160662 18446744073709551615 4194304 4228012 140736790069104 140274478304272 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22264] ppid=22254 vsize=1045620 CPUtime=2.47
/proc/22257/task/22264/stat : 22264 (java) S 22254 22254 1750 0 -1 4202560 9423 0 0 0 244 3 0 0 20 0 11 0 120729603 1070714880 160662 18446744073709551615 4194304 4228012 140736790069104 140274477251600 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22265] ppid=22254 vsize=1045620 CPUtime=0
/proc/22257/task/22265/stat : 22265 (java) S 22254 22254 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120729603 1070714880 160662 18446744073709551615 4194304 4228012 140736790069104 140274476199120 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22266] ppid=22254 vsize=1045620 CPUtime=0.15
/proc/22257/task/22266/stat : 22266 (java) S 22254 22254 1750 0 -1 4202560 25 0 0 0 0 15 0 0 20 0 11 0 120729603 1070714880 160662 18446744073709551615 4194304 4228012 140736790069104 140274475146624 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 293.39
Current children cumulated vsize (KiB) 1054788

[startup+297.901 s]
/proc/loadavg: 1.00 1.03 1.00 2/71 22267
/proc/meminfo: memFree=10228/1022884 swapFree=0/0
[pid=22254] ppid=22253 vsize=9168 CPUtime=0
/proc/22254/stat : 22254 (p2cudf-full-1.1) S 22253 22254 1750 0 -1 4202496 455 290 2 1 0 0 0 0 20 0 1 0 120729575 9388032 331 18446744073709551615 4194304 5129932 140735207319728 140735207317872 140383236281438 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/22254/statm: 2292 331 280 229 0 52 0
[pid=22257] ppid=22254 vsize=1045620 CPUtime=293.48
/proc/22257/stat : 22257 (java) S 22254 22254 1750 0 -1 4202496 173172 0 20 0 29276 72 0 0 20 0 11 0 120729579 1070714880 160664 18446744073709551615 4194304 4228012 140736790069104 140736790060128 140274583342709 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 8 0 0
/proc/22257/statm: 261405 160664 2393 9 0 248755 0
[pid=22257/tid=22258] ppid=22254 vsize=1045620 CPUtime=264.84
/proc/22257/task/22258/stat : 22258 (java) S 22254 22254 1750 0 -1 4202560 47795 0 8 0 26457 27 0 0 20 0 11 0 120729588 1070714880 160664 18446744073709551615 4194304 4228012 140736790069104 140274589877168 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 25 0 0
[pid=22257/tid=22259] ppid=22254 vsize=1045620 CPUtime=23.39
/proc/22257/task/22259/stat : 22259 (java) S 22254 22254 1750 0 -1 4202560 100981 0 0 0 2309 30 0 0 20 0 11 0 120729595 1070714880 160664 18446744073709551615 4194304 4228012 140736790069104 140274482515072 140274583357003 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22260] ppid=22254 vsize=1045620 CPUtime=0
/proc/22257/task/22260/stat : 22260 (java) S 22254 22254 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120729596 1070714880 160664 18446744073709551615 4194304 4228012 140736790069104 140274481461264 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22261] ppid=22254 vsize=1045620 CPUtime=0
/proc/22257/task/22261/stat : 22261 (java) S 22254 22254 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120729596 1070714880 160664 18446744073709551615 4194304 4228012 140736790069104 140274480408480 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22262] ppid=22254 vsize=1045620 CPUtime=0
/proc/22257/task/22262/stat : 22262 (java) S 22254 22254 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120729603 1070714880 160664 18446744073709551615 4194304 4228012 140736790069104 140274479356912 140274583364640 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22263] ppid=22254 vsize=1045620 CPUtime=2.5
/proc/22257/task/22263/stat : 22263 (java) S 22254 22254 1750 0 -1 4202560 13652 0 0 0 243 7 0 0 20 0 11 0 120729603 1070714880 160664 18446744073709551615 4194304 4228012 140736790069104 140274478304272 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22264] ppid=22254 vsize=1045620 CPUtime=2.48
/proc/22257/task/22264/stat : 22264 (java) S 22254 22254 1750 0 -1 4202560 9423 0 0 0 245 3 0 0 20 0 11 0 120729603 1070714880 160664 18446744073709551615 4194304 4228012 140736790069104 140274477251600 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22265] ppid=22254 vsize=1045620 CPUtime=0
/proc/22257/task/22265/stat : 22265 (java) S 22254 22254 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120729603 1070714880 160664 18446744073709551615 4194304 4228012 140736790069104 140274476199120 140274583356116 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22257/tid=22266] ppid=22254 vsize=1045620 CPUtime=0.15
/proc/22257/task/22266/stat : 22266 (java) S 22254 22254 1750 0 -1 4202560 25 0 0 0 0 15 0 0 20 0 11 0 120729603 1070714880 160664 18446744073709551615 4194304 4228012 140736790069104 140274475146624 140274583357003 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 293.48
Current children cumulated vsize (KiB) 1054788

Child status: 0
Real time (s): 297.997
CPU time (s): 293.59
CPU user time (s): 292.822
CPU system time (s): 0.768048
CPU usage (%): 98.5211
Max. virtual memory (cumulated for all children) (KiB): 1071112

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 292.822
system time used= 0.768048
maximum resident set size= 642692
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 173940
page faults= 23
swaps= 0
block input operations= 70976
block output operations= 584
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 7340
involuntary context switches= 41847

runsolver used 0.884055 second user time and 1.97212 second system time

The end