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/upgrade/easy/rand736.cudf.log.runsolver /home/competition/p2cudf-full-1.15/p2cudf-full-1.15 /home/competition/data/upgrade/easy/rand736.cudf /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/slowlink/upgrade/easy/rand736.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

[startup+0 s]
/proc/loadavg: 1.07 1.33 1.19 2/64 22448
/proc/meminfo: memFree=501484/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=3152 CPUtime=0
/proc/22448/stat : 22448 (runsolver) R 22447 1745 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 1 0 120878529 3227648 32 18446744073709551615 134512640 134586868 4292946576 4292944624 4151374896 0 0 16781316 24578 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 788 32 0 19 0 73 0
Current StackSize limit: 8192 KiB


[startup+0.145562 s]
/proc/loadavg: 1.07 1.33 1.19 2/64 22448
/proc/meminfo: memFree=501484/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.200374 s]
/proc/loadavg: 1.07 1.33 1.19 2/64 22448
/proc/meminfo: memFree=501484/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.30032 s]
/proc/loadavg: 1.07 1.33 1.19 2/64 22448
/proc/meminfo: memFree=501484/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.700202 s]
/proc/loadavg: 1.07 1.33 1.19 2/64 22448
/proc/meminfo: memFree=501484/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+1.50075 s]
/proc/loadavg: 1.23 1.36 1.20 4/75 22460
/proc/meminfo: memFree=422152/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
[pid=22451] ppid=22448 vsize=1056372 CPUtime=1.35
/proc/22451/stat : 22451 (java) S 22448 22448 1745 0 -1 4202496 36771 0 0 0 118 17 0 0 20 0 10 0 120878531 1081724928 33049 18446744073709551615 4194304 4228012 140734819570176 140734819561200 140004174052981 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22451/statm: 264093 33049 2348 9 0 251443 0
[pid=22451/tid=22452] ppid=22448 vsize=1056372 CPUtime=0.62
/proc/22451/task/22452/stat : 22452 (java) R 22448 22448 1745 0 -1 4202560 16952 0 0 0 53 9 0 0 20 0 10 0 120878536 1081724928 33049 18446744073709551615 4194304 4228012 140734819570176 140004180578976 140004078051369 0 4 0 16800975 0 0 0 -1 0 0 0 8 0 0
[pid=22451/tid=22453] ppid=22448 vsize=1056372 CPUtime=0
/proc/22451/task/22453/stat : 22453 (java) S 22448 22448 1745 0 -1 4202560 4 0 0 0 0 0 0 0 20 0 10 0 120878540 1081724928 33049 18446744073709551615 4194304 4228012 140734819570176 140004073225344 140004174067275 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22454] ppid=22448 vsize=1056372 CPUtime=0
/proc/22451/task/22454/stat : 22454 (java) S 22448 22448 1745 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 120878541 1081724928 33049 18446744073709551615 4194304 4228012 140734819570176 140004072171536 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22455] ppid=22448 vsize=1056372 CPUtime=0
/proc/22451/task/22455/stat : 22455 (java) S 22448 22448 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 120878541 1081724928 33049 18446744073709551615 4194304 4228012 140734819570176 140004071118752 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22456] ppid=22448 vsize=1056372 CPUtime=0
/proc/22451/task/22456/stat : 22456 (java) S 22448 22448 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120878546 1081724928 33049 18446744073709551615 4194304 4228012 140734819570176 140004070067184 140004174074912 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22457] ppid=22448 vsize=1056372 CPUtime=0.36
/proc/22451/task/22457/stat : 22457 (java) R 22448 22448 1745 0 -1 4202560 10543 0 0 0 32 4 0 0 20 0 10 0 120878546 1081724928 33049 18446744073709551615 4194304 4228012 140734819570176 140004069002344 140004157195522 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22458] ppid=22448 vsize=1056372 CPUtime=0.35
/proc/22451/task/22458/stat : 22458 (java) R 22448 22448 1745 0 -1 4202560 7983 0 0 0 31 4 0 0 20 0 10 0 120878546 1081724928 33049 18446744073709551615 4194304 4228012 140734819570176 140004067949448 140004158331935 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22459] ppid=22448 vsize=1056372 CPUtime=0
/proc/22451/task/22459/stat : 22459 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120878546 1081724928 33049 18446744073709551615 4194304 4228012 140734819570176 140004066909392 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22460] ppid=22448 vsize=1056372 CPUtime=0
/proc/22451/task/22460/stat : 22460 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120878546 1081724928 33049 18446744073709551615 4194304 4228012 140734819570176 140004065856896 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.35
Current children cumulated vsize (KiB) 1065540

[startup+3.10074 s]
/proc/loadavg: 1.23 1.36 1.20 2/75 22460
/proc/meminfo: memFree=321952/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
[pid=22451] ppid=22448 vsize=1052012 CPUtime=2.93
/proc/22451/stat : 22451 (java) S 22448 22448 1745 0 -1 4202496 82743 0 0 0 261 32 0 0 20 0 10 0 120878531 1077260288 77624 18446744073709551615 4194304 4228012 140734819570176 140734819561200 140004174052981 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22451/statm: 263003 77624 2369 9 0 250353 0
[pid=22451/tid=22452] ppid=22448 vsize=1052012 CPUtime=1.47
/proc/22451/task/22452/stat : 22452 (java) S 22448 22448 1745 0 -1 4202560 47410 0 0 0 129 18 0 0 20 0 10 0 120878536 1077260288 77624 18446744073709551615 4194304 4228012 140734819570176 140004180587088 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 8 0 0
[pid=22451/tid=22453] ppid=22448 vsize=1052012 CPUtime=0.3
/proc/22451/task/22453/stat : 22453 (java) R 22448 22448 1745 0 -1 4202560 14762 0 0 0 24 6 0 0 20 0 10 0 120878540 1077260288 77624 18446744073709551615 4194304 4228012 140734819570176 140004073224720 140004167305139 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22454] ppid=22448 vsize=1052012 CPUtime=0
/proc/22451/task/22454/stat : 22454 (java) S 22448 22448 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 120878541 1077260288 77624 18446744073709551615 4194304 4228012 140734819570176 140004072171536 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22455] ppid=22448 vsize=1052012 CPUtime=0
/proc/22451/task/22455/stat : 22455 (java) S 22448 22448 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 120878541 1077260288 77624 18446744073709551615 4194304 4228012 140734819570176 140004071118752 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22456] ppid=22448 vsize=1052012 CPUtime=0
/proc/22451/task/22456/stat : 22456 (java) S 22448 22448 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120878546 1077260288 77624 18446744073709551615 4194304 4228012 140734819570176 140004070067184 140004174074912 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22457] ppid=22448 vsize=1052012 CPUtime=0.58
/proc/22451/task/22457/stat : 22457 (java) S 22448 22448 1745 0 -1 4202560 10847 0 0 0 54 4 0 0 20 0 10 0 120878546 1077260288 77624 18446744073709551615 4194304 4228012 140734819570176 140004069014544 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22458] ppid=22448 vsize=1052012 CPUtime=0.57
/proc/22451/task/22458/stat : 22458 (java) S 22448 22448 1745 0 -1 4202560 8431 0 0 0 53 4 0 0 20 0 10 0 120878546 1077260288 77624 18446744073709551615 4194304 4228012 140734819570176 140004067961872 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22459] ppid=22448 vsize=1052012 CPUtime=0
/proc/22451/task/22459/stat : 22459 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120878546 1077260288 77624 18446744073709551615 4194304 4228012 140734819570176 140004066909392 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22460] ppid=22448 vsize=1052012 CPUtime=0
/proc/22451/task/22460/stat : 22460 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120878546 1077260288 77624 18446744073709551615 4194304 4228012 140734819570176 140004065856896 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 2.93
Current children cumulated vsize (KiB) 1061180

[startup+6.30075 s]
/proc/loadavg: 1.23 1.36 1.20 4/75 22460
/proc/meminfo: memFree=55848/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
[pid=22451] ppid=22448 vsize=1052012 CPUtime=6.08
/proc/22451/stat : 22451 (java) S 22448 22448 1745 0 -1 4202496 113820 0 0 0 564 44 0 0 20 0 10 0 120878531 1077260288 108647 18446744073709551615 4194304 4228012 140734819570176 140734819561200 140004174052981 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22451/statm: 263003 108647 2371 9 0 250353 0
[pid=22451/tid=22452] ppid=22448 vsize=1052012 CPUtime=3.23
/proc/22451/task/22452/stat : 22452 (java) S 22448 22448 1745 0 -1 4202560 47627 0 0 0 304 19 0 0 20 0 10 0 120878536 1077260288 108647 18446744073709551615 4194304 4228012 140734819570176 140004180581920 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 11 0 0
[pid=22451/tid=22453] ppid=22448 vsize=1052012 CPUtime=1.12
/proc/22451/task/22453/stat : 22453 (java) R 22448 22448 1745 0 -1 4202560 45460 0 0 0 96 16 0 0 20 0 10 0 120878540 1077260288 108647 18446744073709551615 4194304 4228012 140734819570176 140004073224720 140004158363769 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22454] ppid=22448 vsize=1052012 CPUtime=0
/proc/22451/task/22454/stat : 22454 (java) S 22448 22448 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 120878541 1077260288 108647 18446744073709551615 4194304 4228012 140734819570176 140004072171536 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22455] ppid=22448 vsize=1052012 CPUtime=0
/proc/22451/task/22455/stat : 22455 (java) S 22448 22448 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 120878541 1077260288 108647 18446744073709551615 4194304 4228012 140734819570176 140004071118752 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22456] ppid=22448 vsize=1052012 CPUtime=0
/proc/22451/task/22456/stat : 22456 (java) S 22448 22448 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120878546 1077260288 108647 18446744073709551615 4194304 4228012 140734819570176 140004070067184 140004174074912 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22457] ppid=22448 vsize=1052012 CPUtime=0.86
/proc/22451/task/22457/stat : 22457 (java) S 22448 22448 1745 0 -1 4202560 10867 0 0 0 82 4 0 0 20 0 10 0 120878546 1077260288 108647 18446744073709551615 4194304 4228012 140734819570176 140004069014544 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22458] ppid=22448 vsize=1052012 CPUtime=0.84
/proc/22451/task/22458/stat : 22458 (java) S 22448 22448 1745 0 -1 4202560 8573 0 0 0 80 4 0 0 20 0 10 0 120878546 1077260288 108647 18446744073709551615 4194304 4228012 140734819570176 140004067961872 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22459] ppid=22448 vsize=1052012 CPUtime=0
/proc/22451/task/22459/stat : 22459 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120878546 1077260288 108647 18446744073709551615 4194304 4228012 140734819570176 140004066909392 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22460] ppid=22448 vsize=1052012 CPUtime=0
/proc/22451/task/22460/stat : 22460 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120878546 1077260288 108647 18446744073709551615 4194304 4228012 140734819570176 140004065856896 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 6.08
Current children cumulated vsize (KiB) 1061180

[startup+12.7006 s]
/proc/loadavg: 1.50 1.41 1.22 2/76 22461
/proc/meminfo: memFree=22112/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
[pid=22451] ppid=22448 vsize=1053040 CPUtime=12.4
/proc/22451/stat : 22451 (java) S 22448 22448 1745 0 -1 4202496 121659 0 0 0 1190 50 0 0 20 0 11 0 120878531 1078312960 116486 18446744073709551615 4194304 4228012 140734819570176 140734819561200 140004174052981 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22451/statm: 263260 116486 2389 9 0 250610 0
[pid=22451/tid=22452] ppid=22448 vsize=1053040 CPUtime=7.02
/proc/22451/task/22452/stat : 22452 (java) S 22448 22448 1745 0 -1 4202560 47795 0 0 0 682 20 0 0 20 0 11 0 120878536 1078312960 116486 18446744073709551615 4194304 4228012 140734819570176 140004180586272 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 11 0 0
[pid=22451/tid=22453] ppid=22448 vsize=1053040 CPUtime=1.42
/proc/22451/task/22453/stat : 22453 (java) R 22448 22448 1745 0 -1 4202560 52871 0 0 0 122 20 0 0 20 0 11 0 120878540 1078312960 116486 18446744073709551615 4194304 4228012 140734819570176 140004073224752 140004158415269 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22454] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22454/stat : 22454 (java) S 22448 22448 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 116486 18446744073709551615 4194304 4228012 140734819570176 140004072171536 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22455] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22455/stat : 22455 (java) S 22448 22448 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 116486 18446744073709551615 4194304 4228012 140734819570176 140004071118752 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22456] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22456/stat : 22456 (java) S 22448 22448 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 116486 18446744073709551615 4194304 4228012 140734819570176 140004070067184 140004174074912 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22457] ppid=22448 vsize=1053040 CPUtime=1.94
/proc/22451/task/22457/stat : 22457 (java) S 22448 22448 1745 0 -1 4202560 10957 0 0 0 190 4 0 0 20 0 11 0 120878546 1078312960 116486 18446744073709551615 4194304 4228012 140734819570176 140004069014544 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22458] ppid=22448 vsize=1053040 CPUtime=1.96
/proc/22451/task/22458/stat : 22458 (java) S 22448 22448 1745 0 -1 4202560 8737 0 0 0 192 4 0 0 20 0 11 0 120878546 1078312960 116486 18446744073709551615 4194304 4228012 140734819570176 140004067952272 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22459] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22459/stat : 22459 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 116486 18446744073709551615 4194304 4228012 140734819570176 140004066909392 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22460] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22460/stat : 22460 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 116486 18446744073709551615 4194304 4228012 140734819570176 140004065856896 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22461] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22461/stat : 22461 (java) S 22448 22448 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120879388 1078312960 116486 18446744073709551615 4194304 4228012 140734819570176 140004064663392 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 12.4
Current children cumulated vsize (KiB) 1062208

[startup+25.5006 s]
/proc/loadavg: 1.42 1.40 1.22 2/76 22461
/proc/meminfo: memFree=10136/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
[pid=22451] ppid=22448 vsize=1053040 CPUtime=25
/proc/22451/stat : 22451 (java) S 22448 22448 1745 0 -1 4202496 136107 0 0 0 2446 54 0 0 20 0 11 0 120878531 1078312960 130390 18446744073709551615 4194304 4228012 140734819570176 140734819561200 140004174052981 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22451/statm: 263260 130390 2392 9 0 250610 0
[pid=22451/tid=22452] ppid=22448 vsize=1053040 CPUtime=16.65
/proc/22451/task/22452/stat : 22452 (java) R 22448 22448 1745 0 -1 4202560 47796 0 0 0 1645 20 0 0 20 0 11 0 120878536 1078312960 130390 18446744073709551615 4194304 4228012 140734819570176 140004180586472 140004078968221 0 4 0 16800975 0 0 0 -1 0 0 0 11 0 0
[pid=22451/tid=22453] ppid=22448 vsize=1053040 CPUtime=4.12
/proc/22451/task/22453/stat : 22453 (java) S 22448 22448 1745 0 -1 4202560 66740 0 0 0 388 24 0 0 20 0 11 0 120878540 1078312960 130390 18446744073709551615 4194304 4228012 140734819570176 140004073225344 140004174067275 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22454] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22454/stat : 22454 (java) S 22448 22448 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 130390 18446744073709551615 4194304 4228012 140734819570176 140004072171536 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22455] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22455/stat : 22455 (java) S 22448 22448 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 130390 18446744073709551615 4194304 4228012 140734819570176 140004071118752 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22456] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22456/stat : 22456 (java) S 22448 22448 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 130390 18446744073709551615 4194304 4228012 140734819570176 140004070067184 140004174074912 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22457] ppid=22448 vsize=1053040 CPUtime=1.98
/proc/22451/task/22457/stat : 22457 (java) S 22448 22448 1745 0 -1 4202560 10957 0 0 0 194 4 0 0 20 0 11 0 120878546 1078312960 130390 18446744073709551615 4194304 4228012 140734819570176 140004069014544 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22458] ppid=22448 vsize=1053040 CPUtime=2.2
/proc/22451/task/22458/stat : 22458 (java) S 22448 22448 1745 0 -1 4202560 9315 0 0 0 216 4 0 0 20 0 11 0 120878546 1078312960 130390 18446744073709551615 4194304 4228012 140734819570176 140004067961872 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22459] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22459/stat : 22459 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 130390 18446744073709551615 4194304 4228012 140734819570176 140004066909392 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22460] ppid=22448 vsize=1053040 CPUtime=0.01
/proc/22451/task/22460/stat : 22460 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 1 0 0 0 20 0 11 0 120878546 1078312960 130390 18446744073709551615 4194304 4228012 140734819570176 140004065856896 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22461] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22461/stat : 22461 (java) S 22448 22448 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120879388 1078312960 130390 18446744073709551615 4194304 4228012 140734819570176 140004064663392 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 25
Current children cumulated vsize (KiB) 1062208

[startup+51.1008 s]
/proc/loadavg: 1.28 1.37 1.21 2/76 22461
/proc/meminfo: memFree=10248/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
[pid=22451] ppid=22448 vsize=1053040 CPUtime=50.25
/proc/22451/stat : 22451 (java) S 22448 22448 1745 0 -1 4202496 138588 0 0 0 4970 55 0 0 20 0 11 0 120878531 1078312960 132867 18446744073709551615 4194304 4228012 140734819570176 140734819561200 140004174052981 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22451/statm: 263260 132867 2392 9 0 250610 0
[pid=22451/tid=22452] ppid=22448 vsize=1053040 CPUtime=39.25
/proc/22451/task/22452/stat : 22452 (java) R 22448 22448 1745 0 -1 4202560 47796 0 0 0 3905 20 0 0 20 0 11 0 120878536 1078312960 132867 18446744073709551615 4194304 4228012 140734819570176 140004180586472 140004079291451 0 4 0 16800975 0 0 0 -1 0 0 0 12 0 0
[pid=22451/tid=22453] ppid=22448 vsize=1053040 CPUtime=6.75
/proc/22451/task/22453/stat : 22453 (java) S 22448 22448 1745 0 -1 4202560 69218 0 0 0 650 25 0 0 20 0 11 0 120878540 1078312960 132867 18446744073709551615 4194304 4228012 140734819570176 140004073225344 140004174067275 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22454] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22454/stat : 22454 (java) S 22448 22448 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 132867 18446744073709551615 4194304 4228012 140734819570176 140004072171536 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22455] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22455/stat : 22455 (java) S 22448 22448 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 132867 18446744073709551615 4194304 4228012 140734819570176 140004071118752 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22456] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22456/stat : 22456 (java) S 22448 22448 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 132867 18446744073709551615 4194304 4228012 140734819570176 140004070067184 140004174074912 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22457] ppid=22448 vsize=1053040 CPUtime=1.99
/proc/22451/task/22457/stat : 22457 (java) S 22448 22448 1745 0 -1 4202560 10957 0 0 0 195 4 0 0 20 0 11 0 120878546 1078312960 132867 18446744073709551615 4194304 4228012 140734819570176 140004069014544 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22458] ppid=22448 vsize=1053040 CPUtime=2.2
/proc/22451/task/22458/stat : 22458 (java) S 22448 22448 1745 0 -1 4202560 9315 0 0 0 216 4 0 0 20 0 11 0 120878546 1078312960 132867 18446744073709551615 4194304 4228012 140734819570176 140004067961872 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22459] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22459/stat : 22459 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 132867 18446744073709551615 4194304 4228012 140734819570176 140004066909392 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22460] ppid=22448 vsize=1053040 CPUtime=0.02
/proc/22451/task/22460/stat : 22460 (java) S 22448 22448 1745 0 -1 4202560 3 0 0 0 2 0 0 0 20 0 11 0 120878546 1078312960 132867 18446744073709551615 4194304 4228012 140734819570176 140004065856896 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22461] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22461/stat : 22461 (java) S 22448 22448 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120879388 1078312960 132867 18446744073709551615 4194304 4228012 140734819570176 140004064663392 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 50.25
Current children cumulated vsize (KiB) 1062208

[startup+102.301 s]
/proc/loadavg: 1.15 1.32 1.20 2/76 22461
/proc/meminfo: memFree=10124/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
[pid=22451] ppid=22448 vsize=1053040 CPUtime=100.75
/proc/22451/stat : 22451 (java) S 22448 22448 1745 0 -1 4202496 139020 0 0 0 10018 57 0 0 20 0 11 0 120878531 1078312960 133292 18446744073709551615 4194304 4228012 140734819570176 140734819561200 140004174052981 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22451/statm: 263260 133292 2392 9 0 250610 0
[pid=22451/tid=22452] ppid=22448 vsize=1053040 CPUtime=85.57
/proc/22451/task/22452/stat : 22452 (java) R 22448 22448 1745 0 -1 4202560 47796 0 0 0 8536 21 0 0 20 0 11 0 120878536 1078312960 133292 18446744073709551615 4194304 4228012 140734819570176 140004180586552 140004079291990 0 4 0 16800975 0 0 0 -1 0 0 0 12 0 0
[pid=22451/tid=22453] ppid=22448 vsize=1053040 CPUtime=10.85
/proc/22451/task/22453/stat : 22453 (java) S 22448 22448 1745 0 -1 4202560 69644 0 0 0 1059 26 0 0 20 0 11 0 120878540 1078312960 133292 18446744073709551615 4194304 4228012 140734819570176 140004073225344 140004174067275 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22454] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22454/stat : 22454 (java) S 22448 22448 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 133292 18446744073709551615 4194304 4228012 140734819570176 140004072171536 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22455] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22455/stat : 22455 (java) S 22448 22448 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 133292 18446744073709551615 4194304 4228012 140734819570176 140004071118752 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22456] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22456/stat : 22456 (java) S 22448 22448 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 133292 18446744073709551615 4194304 4228012 140734819570176 140004070067184 140004174074912 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22457] ppid=22448 vsize=1053040 CPUtime=2.01
/proc/22451/task/22457/stat : 22457 (java) S 22448 22448 1745 0 -1 4202560 10957 0 0 0 197 4 0 0 20 0 11 0 120878546 1078312960 133292 18446744073709551615 4194304 4228012 140734819570176 140004069014544 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22458] ppid=22448 vsize=1053040 CPUtime=2.22
/proc/22451/task/22458/stat : 22458 (java) S 22448 22448 1745 0 -1 4202560 9315 0 0 0 218 4 0 0 20 0 11 0 120878546 1078312960 133292 18446744073709551615 4194304 4228012 140734819570176 140004067961872 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22459] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22459/stat : 22459 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 133292 18446744073709551615 4194304 4228012 140734819570176 140004066909392 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22460] ppid=22448 vsize=1053040 CPUtime=0.05
/proc/22451/task/22460/stat : 22460 (java) S 22448 22448 1745 0 -1 4202560 9 0 0 0 5 0 0 0 20 0 11 0 120878546 1078312960 133292 18446744073709551615 4194304 4228012 140734819570176 140004065856896 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22461] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22461/stat : 22461 (java) S 22448 22448 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120879388 1078312960 133292 18446744073709551615 4194304 4228012 140734819570176 140004064663392 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 100.75
Current children cumulated vsize (KiB) 1062208

[startup+162.301 s]
/proc/loadavg: 1.05 1.26 1.18 2/76 22461
/proc/meminfo: memFree=9880/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
[pid=22451] ppid=22448 vsize=1053040 CPUtime=160.03
/proc/22451/stat : 22451 (java) S 22448 22448 1745 0 -1 4202496 144311 0 0 0 15944 59 0 0 20 0 11 0 120878531 1078312960 138578 18446744073709551615 4194304 4228012 140734819570176 140734819561200 140004174052981 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22451/statm: 263260 138578 2392 9 0 250610 0
[pid=22451/tid=22452] ppid=22448 vsize=1053040 CPUtime=141.79
/proc/22451/task/22452/stat : 22452 (java) R 22448 22448 1745 0 -1 4202560 47796 0 0 0 14158 21 0 0 20 0 11 0 120878536 1078312960 138578 18446744073709551615 4194304 4228012 140734819570176 140004180586472 140004079248193 0 4 0 16800975 0 0 0 -1 0 0 0 12 0 0
[pid=22451/tid=22453] ppid=22448 vsize=1053040 CPUtime=13.89
/proc/22451/task/22453/stat : 22453 (java) S 22448 22448 1745 0 -1 4202560 74932 0 0 0 1361 28 0 0 20 0 11 0 120878540 1078312960 138578 18446744073709551615 4194304 4228012 140734819570176 140004073225344 140004174067275 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22454] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22454/stat : 22454 (java) S 22448 22448 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 138578 18446744073709551615 4194304 4228012 140734819570176 140004072171536 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22455] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22455/stat : 22455 (java) S 22448 22448 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 138578 18446744073709551615 4194304 4228012 140734819570176 140004071118752 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22456] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22456/stat : 22456 (java) S 22448 22448 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 138578 18446744073709551615 4194304 4228012 140734819570176 140004070067184 140004174074912 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22457] ppid=22448 vsize=1053040 CPUtime=2.01
/proc/22451/task/22457/stat : 22457 (java) S 22448 22448 1745 0 -1 4202560 10957 0 0 0 197 4 0 0 20 0 11 0 120878546 1078312960 138578 18446744073709551615 4194304 4228012 140734819570176 140004069014544 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22458] ppid=22448 vsize=1053040 CPUtime=2.23
/proc/22451/task/22458/stat : 22458 (java) S 22448 22448 1745 0 -1 4202560 9315 0 0 0 219 4 0 0 20 0 11 0 120878546 1078312960 138578 18446744073709551615 4194304 4228012 140734819570176 140004067961872 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22459] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22459/stat : 22459 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 138578 18446744073709551615 4194304 4228012 140734819570176 140004066909392 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22460] ppid=22448 vsize=1053040 CPUtime=0.08
/proc/22451/task/22460/stat : 22460 (java) S 22448 22448 1745 0 -1 4202560 12 0 0 0 8 0 0 0 20 0 11 0 120878546 1078312960 138578 18446744073709551615 4194304 4228012 140734819570176 140004065856896 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22461] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22461/stat : 22461 (java) S 22448 22448 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120879388 1078312960 138578 18446744073709551615 4194304 4228012 140734819570176 140004064663392 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 160.03
Current children cumulated vsize (KiB) 1062208

[startup+222.304 s]
/proc/loadavg: 1.02 1.21 1.17 2/76 22461
/proc/meminfo: memFree=10268/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
[pid=22451] ppid=22448 vsize=1053040 CPUtime=219.4
/proc/22451/stat : 22451 (java) S 22448 22448 1745 0 -1 4202496 149465 0 0 0 21880 60 0 0 20 0 11 0 120878531 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140734819561200 140004174052981 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22451/statm: 263260 143724 2392 9 0 250610 0
[pid=22451/tid=22452] ppid=22448 vsize=1053040 CPUtime=198.85
/proc/22451/task/22452/stat : 22452 (java) R 22448 22448 1745 0 -1 4202560 47796 0 0 0 19864 21 0 0 20 0 11 0 120878536 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004180586472 140004079211524 0 4 0 16800975 0 0 0 -1 0 0 0 12 0 0
[pid=22451/tid=22453] ppid=22448 vsize=1053040 CPUtime=16.16
/proc/22451/task/22453/stat : 22453 (java) S 22448 22448 1745 0 -1 4202560 80080 0 0 0 1586 30 0 0 20 0 11 0 120878540 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004073225344 140004174067275 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22454] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22454/stat : 22454 (java) S 22448 22448 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004072171536 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22455] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22455/stat : 22455 (java) S 22448 22448 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004071118752 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22456] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22456/stat : 22456 (java) S 22448 22448 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004070067184 140004174074912 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22457] ppid=22448 vsize=1053040 CPUtime=2.04
/proc/22451/task/22457/stat : 22457 (java) S 22448 22448 1745 0 -1 4202560 10957 0 0 0 200 4 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004069014544 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22458] ppid=22448 vsize=1053040 CPUtime=2.23
/proc/22451/task/22458/stat : 22458 (java) S 22448 22448 1745 0 -1 4202560 9315 0 0 0 219 4 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004067961872 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22459] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22459/stat : 22459 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004066909392 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22460] ppid=22448 vsize=1053040 CPUtime=0.1
/proc/22451/task/22460/stat : 22460 (java) S 22448 22448 1745 0 -1 4202560 18 0 0 0 10 0 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004065856896 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22461] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22461/stat : 22461 (java) S 22448 22448 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120879388 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004064663392 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 219.4
Current children cumulated vsize (KiB) 1062208

[startup+282.301 s]
/proc/loadavg: 1.00 1.17 1.16 2/76 22461
/proc/meminfo: memFree=10144/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
[pid=22451] ppid=22448 vsize=1053040 CPUtime=278.79
/proc/22451/stat : 22451 (java) S 22448 22448 1745 0 -1 4202496 149473 0 0 0 27819 60 0 0 20 0 11 0 120878531 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140734819561200 140004174052981 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22451/statm: 263260 143724 2392 9 0 250610 0
[pid=22451/tid=22452] ppid=22448 vsize=1053040 CPUtime=255.54
/proc/22451/task/22452/stat : 22452 (java) R 22448 22448 1745 0 -1 4202560 47796 0 0 0 25533 21 0 0 20 0 11 0 120878536 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004180586472 140004079245302 0 4 0 16800975 0 0 0 -1 0 0 0 12 0 0
[pid=22451/tid=22453] ppid=22448 vsize=1053040 CPUtime=18.83
/proc/22451/task/22453/stat : 22453 (java) S 22448 22448 1745 0 -1 4202560 80082 0 0 0 1853 30 0 0 20 0 11 0 120878540 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004073225344 140004174067275 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22454] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22454/stat : 22454 (java) S 22448 22448 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004072171536 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22455] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22455/stat : 22455 (java) S 22448 22448 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004071118752 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22456] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22456/stat : 22456 (java) S 22448 22448 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004070067184 140004174074912 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22457] ppid=22448 vsize=1053040 CPUtime=2.04
/proc/22451/task/22457/stat : 22457 (java) S 22448 22448 1745 0 -1 4202560 10957 0 0 0 200 4 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004069014544 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22458] ppid=22448 vsize=1053040 CPUtime=2.23
/proc/22451/task/22458/stat : 22458 (java) S 22448 22448 1745 0 -1 4202560 9315 0 0 0 219 4 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004067961872 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22459] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22459/stat : 22459 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004066909392 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22460] ppid=22448 vsize=1053040 CPUtime=0.12
/proc/22451/task/22460/stat : 22460 (java) S 22448 22448 1745 0 -1 4202560 24 0 0 0 12 0 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004065856896 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22461] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22461/stat : 22461 (java) S 22448 22448 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120879388 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004064663392 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 278.79
Current children cumulated vsize (KiB) 1062208

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

[startup+288.001 s]
/proc/loadavg: 1.00 1.16 1.16 2/76 22461
/proc/meminfo: memFree=10144/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
[pid=22451] ppid=22448 vsize=1053040 CPUtime=284.44
/proc/22451/stat : 22451 (java) S 22448 22448 1745 0 -1 4202496 149473 0 0 0 28384 60 0 0 20 0 11 0 120878531 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140734819561200 140004174052981 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22451/statm: 263260 143724 2392 9 0 250610 0
[pid=22451/tid=22452] ppid=22448 vsize=1053040 CPUtime=261.19
/proc/22451/task/22452/stat : 22452 (java) R 22448 22448 1745 0 -1 4202560 47796 0 0 0 26098 21 0 0 20 0 11 0 120878536 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004180586472 140004078838752 0 4 0 16800975 0 0 0 -1 0 0 0 12 0 0
[pid=22451/tid=22453] ppid=22448 vsize=1053040 CPUtime=18.83
/proc/22451/task/22453/stat : 22453 (java) S 22448 22448 1745 0 -1 4202560 80082 0 0 0 1853 30 0 0 20 0 11 0 120878540 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004073225344 140004174067275 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22454] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22454/stat : 22454 (java) S 22448 22448 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004072171536 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22455] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22455/stat : 22455 (java) S 22448 22448 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004071118752 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22456] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22456/stat : 22456 (java) S 22448 22448 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004070067184 140004174074912 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22457] ppid=22448 vsize=1053040 CPUtime=2.04
/proc/22451/task/22457/stat : 22457 (java) S 22448 22448 1745 0 -1 4202560 10957 0 0 0 200 4 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004069014544 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22458] ppid=22448 vsize=1053040 CPUtime=2.23
/proc/22451/task/22458/stat : 22458 (java) S 22448 22448 1745 0 -1 4202560 9315 0 0 0 219 4 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004067961872 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22459] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22459/stat : 22459 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004066909392 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22460] ppid=22448 vsize=1053040 CPUtime=0.12
/proc/22451/task/22460/stat : 22460 (java) S 22448 22448 1745 0 -1 4202560 24 0 0 0 12 0 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004065856896 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22461] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22461/stat : 22461 (java) S 22448 22448 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120879388 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004064663392 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 284.44
Current children cumulated vsize (KiB) 1062208

[startup+291.201 s]
/proc/loadavg: 1.00 1.16 1.16 2/76 22461
/proc/meminfo: memFree=10144/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
[pid=22451] ppid=22448 vsize=1053040 CPUtime=287.6
/proc/22451/stat : 22451 (java) S 22448 22448 1745 0 -1 4202496 149473 0 0 0 28700 60 0 0 20 0 11 0 120878531 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140734819561200 140004174052981 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22451/statm: 263260 143724 2392 9 0 250610 0
[pid=22451/tid=22452] ppid=22448 vsize=1053040 CPUtime=263.84
/proc/22451/task/22452/stat : 22452 (java) R 22448 22448 1745 0 -1 4202560 47796 0 0 0 26363 21 0 0 20 0 11 0 120878536 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004180586472 140004078605505 0 4 0 16800975 0 0 0 -1 0 0 0 12 0 0
[pid=22451/tid=22453] ppid=22448 vsize=1053040 CPUtime=19.34
/proc/22451/task/22453/stat : 22453 (java) S 22448 22448 1745 0 -1 4202560 80082 0 0 0 1904 30 0 0 20 0 11 0 120878540 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004073225344 140004174067275 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22454] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22454/stat : 22454 (java) S 22448 22448 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004072171536 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22455] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22455/stat : 22455 (java) S 22448 22448 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004071118752 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22456] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22456/stat : 22456 (java) S 22448 22448 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004070067184 140004174074912 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22457] ppid=22448 vsize=1053040 CPUtime=2.04
/proc/22451/task/22457/stat : 22457 (java) S 22448 22448 1745 0 -1 4202560 10957 0 0 0 200 4 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004069014544 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22458] ppid=22448 vsize=1053040 CPUtime=2.23
/proc/22451/task/22458/stat : 22458 (java) S 22448 22448 1745 0 -1 4202560 9315 0 0 0 219 4 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004067961872 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22459] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22459/stat : 22459 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004066909392 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22460] ppid=22448 vsize=1053040 CPUtime=0.13
/proc/22451/task/22460/stat : 22460 (java) S 22448 22448 1745 0 -1 4202560 24 0 0 0 13 0 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004065856896 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22461] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22461/stat : 22461 (java) S 22448 22448 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120879388 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004064663392 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 287.6
Current children cumulated vsize (KiB) 1062208

[startup+292.801 s]
/proc/loadavg: 1.00 1.16 1.15 2/76 22461
/proc/meminfo: memFree=10144/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
[pid=22451] ppid=22448 vsize=1053040 CPUtime=289.19
/proc/22451/stat : 22451 (java) S 22448 22448 1745 0 -1 4202496 149473 0 0 0 28859 60 0 0 20 0 11 0 120878531 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140734819561200 140004174052981 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22451/statm: 263260 143724 2392 9 0 250610 0
[pid=22451/tid=22452] ppid=22448 vsize=1053040 CPUtime=265.42
/proc/22451/task/22452/stat : 22452 (java) R 22448 22448 1745 0 -1 4202560 47796 0 0 0 26521 21 0 0 20 0 11 0 120878536 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004180586472 140004079248057 0 4 0 16800975 0 0 0 -1 0 0 0 12 0 0
[pid=22451/tid=22453] ppid=22448 vsize=1053040 CPUtime=19.34
/proc/22451/task/22453/stat : 22453 (java) S 22448 22448 1745 0 -1 4202560 80082 0 0 0 1904 30 0 0 20 0 11 0 120878540 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004073225344 140004174067275 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22454] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22454/stat : 22454 (java) S 22448 22448 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004072171536 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22455] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22455/stat : 22455 (java) S 22448 22448 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004071118752 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22456] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22456/stat : 22456 (java) S 22448 22448 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004070067184 140004174074912 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22457] ppid=22448 vsize=1053040 CPUtime=2.04
/proc/22451/task/22457/stat : 22457 (java) S 22448 22448 1745 0 -1 4202560 10957 0 0 0 200 4 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004069014544 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22458] ppid=22448 vsize=1053040 CPUtime=2.23
/proc/22451/task/22458/stat : 22458 (java) S 22448 22448 1745 0 -1 4202560 9315 0 0 0 219 4 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004067961872 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22459] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22459/stat : 22459 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004066909392 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22460] ppid=22448 vsize=1053040 CPUtime=0.13
/proc/22451/task/22460/stat : 22460 (java) S 22448 22448 1745 0 -1 4202560 24 0 0 0 13 0 0 0 20 0 11 0 120878546 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004065856896 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22461] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22461/stat : 22461 (java) S 22448 22448 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120879388 1078312960 143724 18446744073709551615 4194304 4228012 140734819570176 140004064663392 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 289.19
Current children cumulated vsize (KiB) 1062208

[startup+293.601 s]
/proc/loadavg: 1.00 1.16 1.15 2/76 22461
/proc/meminfo: memFree=10144/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
[pid=22451] ppid=22448 vsize=1053040 CPUtime=289.98
/proc/22451/stat : 22451 (java) S 22448 22448 1745 0 -1 4202496 149475 0 0 0 28938 60 0 0 20 0 11 0 120878531 1078312960 143726 18446744073709551615 4194304 4228012 140734819570176 140734819561200 140004174052981 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22451/statm: 263260 143726 2392 9 0 250610 0
[pid=22451/tid=22452] ppid=22448 vsize=1053040 CPUtime=266.21
/proc/22451/task/22452/stat : 22452 (java) R 22448 22448 1745 0 -1 4202560 47798 0 0 0 26600 21 0 0 20 0 11 0 120878536 1078312960 143726 18446744073709551615 4194304 4228012 140734819570176 140004180578656 140004078077857 0 4 0 16800975 0 0 0 -1 0 0 0 12 0 0
[pid=22451/tid=22453] ppid=22448 vsize=1053040 CPUtime=19.34
/proc/22451/task/22453/stat : 22453 (java) S 22448 22448 1745 0 -1 4202560 80082 0 0 0 1904 30 0 0 20 0 11 0 120878540 1078312960 143726 18446744073709551615 4194304 4228012 140734819570176 140004073225344 140004174067275 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22454] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22454/stat : 22454 (java) S 22448 22448 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 143726 18446744073709551615 4194304 4228012 140734819570176 140004072171536 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22455] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22455/stat : 22455 (java) S 22448 22448 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120878541 1078312960 143726 18446744073709551615 4194304 4228012 140734819570176 140004071118752 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22456] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22456/stat : 22456 (java) S 22448 22448 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 143726 18446744073709551615 4194304 4228012 140734819570176 140004070067184 140004174074912 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22457] ppid=22448 vsize=1053040 CPUtime=2.04
/proc/22451/task/22457/stat : 22457 (java) S 22448 22448 1745 0 -1 4202560 10957 0 0 0 200 4 0 0 20 0 11 0 120878546 1078312960 143726 18446744073709551615 4194304 4228012 140734819570176 140004069014544 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22458] ppid=22448 vsize=1053040 CPUtime=2.23
/proc/22451/task/22458/stat : 22458 (java) S 22448 22448 1745 0 -1 4202560 9315 0 0 0 219 4 0 0 20 0 11 0 120878546 1078312960 143726 18446744073709551615 4194304 4228012 140734819570176 140004067961872 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22459] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22459/stat : 22459 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120878546 1078312960 143726 18446744073709551615 4194304 4228012 140734819570176 140004066909392 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22460] ppid=22448 vsize=1053040 CPUtime=0.13
/proc/22451/task/22460/stat : 22460 (java) S 22448 22448 1745 0 -1 4202560 24 0 0 0 13 0 0 0 20 0 11 0 120878546 1078312960 143726 18446744073709551615 4194304 4228012 140734819570176 140004065856896 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22461] ppid=22448 vsize=1053040 CPUtime=0
/proc/22451/task/22461/stat : 22461 (java) S 22448 22448 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120879388 1078312960 143726 18446744073709551615 4194304 4228012 140734819570176 140004064663376 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 289.98
Current children cumulated vsize (KiB) 1062208

[startup+294.001 s]
/proc/loadavg: 1.00 1.16 1.15 1/76 22462
/proc/meminfo: memFree=10020/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
[pid=22451] ppid=22448 vsize=1054068 CPUtime=290.02
/proc/22451/stat : 22451 (java) S 22448 22448 1745 0 -1 4202496 149487 0 0 0 28942 60 0 0 20 0 11 0 120878531 1079365632 143736 18446744073709551615 4194304 4228012 140734819570176 140734819561200 140004174052981 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22451/statm: 263517 143736 2392 9 0 250867 0
[pid=22451/tid=22452] ppid=22448 vsize=1054068 CPUtime=266.23
/proc/22451/task/22452/stat : 22452 (java) S 22448 22448 1745 0 -1 4202560 47804 0 0 0 26602 21 0 0 20 0 11 0 120878536 1079365632 143736 18446744073709551615 4194304 4228012 140734819570176 140004180587440 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 12 0 0
[pid=22451/tid=22453] ppid=22448 vsize=1054068 CPUtime=19.34
/proc/22451/task/22453/stat : 22453 (java) S 22448 22448 1745 0 -1 4202560 80082 0 0 0 1904 30 0 0 20 0 11 0 120878540 1079365632 143736 18446744073709551615 4194304 4228012 140734819570176 140004073225344 140004174067275 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22454] ppid=22448 vsize=1054068 CPUtime=0
/proc/22451/task/22454/stat : 22454 (java) S 22448 22448 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120878541 1079365632 143736 18446744073709551615 4194304 4228012 140734819570176 140004072171536 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22455] ppid=22448 vsize=1054068 CPUtime=0
/proc/22451/task/22455/stat : 22455 (java) S 22448 22448 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120878541 1079365632 143736 18446744073709551615 4194304 4228012 140734819570176 140004071118752 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22456] ppid=22448 vsize=1054068 CPUtime=0
/proc/22451/task/22456/stat : 22456 (java) S 22448 22448 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120878546 1079365632 143736 18446744073709551615 4194304 4228012 140734819570176 140004070067184 140004174074912 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22457] ppid=22448 vsize=1054068 CPUtime=2.04
/proc/22451/task/22457/stat : 22457 (java) S 22448 22448 1745 0 -1 4202560 10957 0 0 0 200 4 0 0 20 0 11 0 120878546 1079365632 143736 18446744073709551615 4194304 4228012 140734819570176 140004069014544 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22458] ppid=22448 vsize=1054068 CPUtime=2.25
/proc/22451/task/22458/stat : 22458 (java) S 22448 22448 1745 0 -1 4202560 9315 0 0 0 221 4 0 0 20 0 11 0 120878546 1079365632 143736 18446744073709551615 4194304 4228012 140734819570176 140004067961872 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22459] ppid=22448 vsize=1054068 CPUtime=0
/proc/22451/task/22459/stat : 22459 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120878546 1079365632 143736 18446744073709551615 4194304 4228012 140734819570176 140004066909392 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22460] ppid=22448 vsize=1054068 CPUtime=0.13
/proc/22451/task/22460/stat : 22460 (java) S 22448 22448 1745 0 -1 4202560 24 0 0 0 13 0 0 0 20 0 11 0 120878546 1079365632 143736 18446744073709551615 4194304 4228012 140734819570176 140004065856896 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22462] ppid=22448 vsize=1054068 CPUtime=0
/proc/22451/task/22462/stat : 22462 (java) S 22448 22448 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120907893 1079365632 143736 18446744073709551615 4194304 4228012 140734819570176 140004063611056 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.02
Current children cumulated vsize (KiB) 1063236

[startup+294.201 s]
/proc/loadavg: 1.00 1.16 1.15 1/76 22462
/proc/meminfo: memFree=10020/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
[pid=22451] ppid=22448 vsize=1054068 CPUtime=290.08
/proc/22451/stat : 22451 (java) S 22448 22448 1745 0 -1 4202496 149490 0 0 0 28946 62 0 0 20 0 11 0 120878531 1079365632 143739 18446744073709551615 4194304 4228012 140734819570176 140734819561200 140004174052981 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22451/statm: 263517 143739 2392 9 0 250867 0
[pid=22451/tid=22452] ppid=22448 vsize=1054068 CPUtime=266.23
/proc/22451/task/22452/stat : 22452 (java) S 22448 22448 1745 0 -1 4202560 47804 0 0 0 26602 21 0 0 20 0 11 0 120878536 1079365632 143739 18446744073709551615 4194304 4228012 140734819570176 140004180587440 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 12 0 0
[pid=22451/tid=22453] ppid=22448 vsize=1054068 CPUtime=19.34
/proc/22451/task/22453/stat : 22453 (java) S 22448 22448 1745 0 -1 4202560 80082 0 0 0 1904 30 0 0 20 0 11 0 120878540 1079365632 143739 18446744073709551615 4194304 4228012 140734819570176 140004073225344 140004174067275 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22454] ppid=22448 vsize=1054068 CPUtime=0
/proc/22451/task/22454/stat : 22454 (java) S 22448 22448 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120878541 1079365632 143739 18446744073709551615 4194304 4228012 140734819570176 140004072171536 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22455] ppid=22448 vsize=1054068 CPUtime=0
/proc/22451/task/22455/stat : 22455 (java) S 22448 22448 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120878541 1079365632 143739 18446744073709551615 4194304 4228012 140734819570176 140004071118752 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22456] ppid=22448 vsize=1054068 CPUtime=0
/proc/22451/task/22456/stat : 22456 (java) S 22448 22448 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120878546 1079365632 143739 18446744073709551615 4194304 4228012 140734819570176 140004070067184 140004174074912 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22457] ppid=22448 vsize=1054068 CPUtime=2.04
/proc/22451/task/22457/stat : 22457 (java) S 22448 22448 1745 0 -1 4202560 10957 0 0 0 200 4 0 0 20 0 11 0 120878546 1079365632 143739 18446744073709551615 4194304 4228012 140734819570176 140004069014544 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22458] ppid=22448 vsize=1054068 CPUtime=2.25
/proc/22451/task/22458/stat : 22458 (java) S 22448 22448 1745 0 -1 4202560 9315 0 0 0 221 4 0 0 20 0 11 0 120878546 1079365632 143739 18446744073709551615 4194304 4228012 140734819570176 140004067961872 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22459] ppid=22448 vsize=1054068 CPUtime=0
/proc/22451/task/22459/stat : 22459 (java) S 22448 22448 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120878546 1079365632 143739 18446744073709551615 4194304 4228012 140734819570176 140004066909392 140004174066388 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22460] ppid=22448 vsize=1054068 CPUtime=0.13
/proc/22451/task/22460/stat : 22460 (java) S 22448 22448 1745 0 -1 4202560 24 0 0 0 13 0 0 0 20 0 11 0 120878546 1079365632 143739 18446744073709551615 4194304 4228012 140734819570176 140004065856896 140004174067275 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22451/tid=22462] ppid=22448 vsize=1054068 CPUtime=0.06
/proc/22451/task/22462/stat : 22462 (java) R 22448 22448 1745 0 -1 4202560 9 0 0 0 4 2 0 0 20 0 11 0 120907893 1079365632 143739 18446744073709551615 4194304 4228012 140734819570176 140004063602000 140004078216684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.08
Current children cumulated vsize (KiB) 1063236

[startup+294.305 s]
/proc/loadavg: 1.00 1.16 1.15 1/76 22462
/proc/meminfo: memFree=10020/1022884 swapFree=0/0
[pid=22448] ppid=22447 vsize=9168 CPUtime=0
/proc/22448/stat : 22448 (p2cudf-full-1.1) S 22447 22448 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120878529 9388032 331 18446744073709551615 4194304 5129932 140736915727744 140736915725888 140241054872670 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22448/statm: 2292 331 280 229 0 52 0
[pid=22451] ppid=22448 vsize=0 CPUtime=290.19
/proc/22451/stat : 22451 (java) Z 22448 22448 1745 0 -1 4203532 149501 0 0 0 28953 66 0 0 20 0 2 0 120878531 0 0 18446744073709551615 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22451/statm: 0 0 0 0 0 0 0
[pid=22451/tid=22462] ppid=22448 vsize=0 CPUtime=0.14
/proc/22451/task/22462/stat : 22462 (java) R 22448 22448 1745 0 -1 4203588 9 0 0 0 7 7 0 0 20 0 2 0 120907893 0 0 18446744073709551615 0 0 0 0 0 0 4 0 16800975 18446744073709551615 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.19
Current children cumulated vsize (KiB) 9168

Child status: 0
Real time (s): 294.329
CPU time (s): 290.226
CPU user time (s): 289.538
CPU system time (s): 0.688043
CPU usage (%): 98.6059
Max. virtual memory (cumulated for all children) (KiB): 1066404

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 289.538
system time used= 0.688043
maximum resident set size= 574992
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 150261
page faults= 0
swaps= 0
block input operations= 42512
block output operations= 568
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 7231
involuntary context switches= 41261

runsolver used 0.920057 second user time and 1.86812 second system time

The end