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/rand414.cudf.log.runsolver /home/competition/p2cudf-full-1.15/p2cudf-full-1.15 /home/competition/data/upgrade/easy/rand414.cudf /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/slowlink/upgrade/easy/rand414.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.00 1.05 1.10 2/64 22505
/proc/meminfo: memFree=533688/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 426 0 0 0 0 0 0 0 20 0 1 0 120939617 9388032 326 18446744073709551615 4194304 5129932 140735144706368 140735144704040 140331759580960 0 0 16781316 65536 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 326 275 229 0 52 0

[startup+0.188684 s]
/proc/loadavg: 1.00 1.05 1.10 2/64 22505
/proc/meminfo: memFree=533688/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.20031 s]
/proc/loadavg: 1.00 1.05 1.10 2/64 22505
/proc/meminfo: memFree=533688/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.300276 s]
/proc/loadavg: 1.00 1.05 1.10 2/64 22505
/proc/meminfo: memFree=533688/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.700213 s]
/proc/loadavg: 1.00 1.05 1.10 2/64 22505
/proc/meminfo: memFree=533688/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+1.5008 s]
/proc/loadavg: 1.16 1.08 1.11 4/75 22517
/proc/meminfo: memFree=484364/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
[pid=22508] ppid=22505 vsize=1052756 CPUtime=1.45
/proc/22508/stat : 22508 (java) S 22505 22505 1745 0 -1 4202496 33460 0 0 0 129 16 0 0 20 0 10 0 120939618 1078022144 29626 18446744073709551615 4194304 4228012 140734509397584 140734509388608 139804244119157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/22508/statm: 263189 29626 2346 9 0 250539 0
[pid=22508/tid=22509] ppid=22505 vsize=1052756 CPUtime=0.69
/proc/22508/task/22509/stat : 22509 (java) R 22505 22505 1745 0 -1 4202560 14273 0 0 0 60 9 0 0 20 0 10 0 120939619 1078022144 29626 18446744073709551615 4194304 4228012 140734509397584 139804250645152 139804148075142 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=22508/tid=22510] ppid=22505 vsize=1052756 CPUtime=0
/proc/22508/task/22510/stat : 22510 (java) S 22505 22505 1745 0 -1 4202560 3 0 0 0 0 0 0 0 20 0 10 0 120939622 1078022144 29626 18446744073709551615 4194304 4228012 140734509397584 139804143291520 139804244133451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22511] ppid=22505 vsize=1052756 CPUtime=0
/proc/22508/task/22511/stat : 22511 (java) S 22505 22505 1745 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 120939622 1078022144 29626 18446744073709551615 4194304 4228012 140734509397584 139804142237712 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22512] ppid=22505 vsize=1052756 CPUtime=0
/proc/22508/task/22512/stat : 22512 (java) S 22505 22505 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 120939623 1078022144 29626 18446744073709551615 4194304 4228012 140734509397584 139804141184928 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22513] ppid=22505 vsize=1052756 CPUtime=0
/proc/22508/task/22513/stat : 22513 (java) S 22505 22505 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120939626 1078022144 29626 18446744073709551615 4194304 4228012 140734509397584 139804140133360 139804244141088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22514] ppid=22505 vsize=1052756 CPUtime=0.37
/proc/22508/task/22514/stat : 22514 (java) R 22505 22505 1745 0 -1 4202560 9955 0 0 0 35 2 0 0 20 0 10 0 120939626 1078022144 29626 18446744073709551615 4194304 4228012 140734509397584 139804139066184 139804229755616 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22515] ppid=22505 vsize=1052756 CPUtime=0.38
/proc/22508/task/22515/stat : 22515 (java) R 22505 22505 1745 0 -1 4202560 7941 0 0 0 34 4 0 0 20 0 10 0 120939626 1078022144 29626 18446744073709551615 4194304 4228012 140734509397584 139804138015896 139804228180806 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22516] ppid=22505 vsize=1052756 CPUtime=0
/proc/22508/task/22516/stat : 22516 (java) S 22505 22505 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120939626 1078022144 29626 18446744073709551615 4194304 4228012 140734509397584 139804136975568 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22517] ppid=22505 vsize=1052756 CPUtime=0
/proc/22508/task/22517/stat : 22517 (java) S 22505 22505 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120939626 1078022144 29626 18446744073709551615 4194304 4228012 140734509397584 139804135923072 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.45
Current children cumulated vsize (KiB) 1061924

[startup+3.10069 s]
/proc/loadavg: 1.16 1.08 1.11 4/75 22517
/proc/meminfo: memFree=388388/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
[pid=22508] ppid=22505 vsize=1049768 CPUtime=3.03
/proc/22508/stat : 22508 (java) S 22505 22505 1745 0 -1 4202496 75988 0 0 0 273 30 0 0 20 0 10 0 120939618 1074962432 70618 18446744073709551615 4194304 4228012 140734509397584 140734509388608 139804244119157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/22508/statm: 262442 70618 2366 9 0 249792 0
[pid=22508/tid=22509] ppid=22505 vsize=1049768 CPUtime=1.55
/proc/22508/task/22509/stat : 22509 (java) R 22505 22505 1745 0 -1 4202560 47418 0 0 0 136 19 0 0 20 0 10 0 120939619 1074962432 70618 18446744073709551615 4194304 4228012 140734509397584 139804250645184 139804148539130 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0
[pid=22508/tid=22510] ppid=22505 vsize=1049768 CPUtime=0.15
/proc/22508/task/22510/stat : 22510 (java) S 22505 22505 1745 0 -1 4202560 7936 0 0 0 12 3 0 0 20 0 10 0 120939622 1074962432 70618 18446744073709551615 4194304 4228012 140734509397584 139804143291520 139804244133451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22511] ppid=22505 vsize=1049768 CPUtime=0
/proc/22508/task/22511/stat : 22511 (java) S 22505 22505 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 120939622 1074962432 70618 18446744073709551615 4194304 4228012 140734509397584 139804142237712 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22512] ppid=22505 vsize=1049768 CPUtime=0
/proc/22508/task/22512/stat : 22512 (java) S 22505 22505 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 120939623 1074962432 70618 18446744073709551615 4194304 4228012 140734509397584 139804141184928 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22513] ppid=22505 vsize=1049768 CPUtime=0
/proc/22508/task/22513/stat : 22513 (java) S 22505 22505 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120939626 1074962432 70618 18446744073709551615 4194304 4228012 140734509397584 139804140133360 139804244141088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22514] ppid=22505 vsize=1049768 CPUtime=0.62
/proc/22508/task/22514/stat : 22514 (java) S 22505 22505 1745 0 -1 4202560 10629 0 0 0 60 2 0 0 20 0 10 0 120939626 1074962432 70618 18446744073709551615 4194304 4228012 140734509397584 139804139080720 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22515] ppid=22505 vsize=1049768 CPUtime=0.66
/proc/22508/task/22515/stat : 22515 (java) S 22505 22505 1745 0 -1 4202560 8713 0 0 0 62 4 0 0 20 0 10 0 120939626 1074962432 70618 18446744073709551615 4194304 4228012 140734509397584 139804138028048 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22516] ppid=22505 vsize=1049768 CPUtime=0
/proc/22508/task/22516/stat : 22516 (java) S 22505 22505 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120939626 1074962432 70618 18446744073709551615 4194304 4228012 140734509397584 139804136975568 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22517] ppid=22505 vsize=1049768 CPUtime=0
/proc/22508/task/22517/stat : 22517 (java) S 22505 22505 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120939626 1074962432 70618 18446744073709551615 4194304 4228012 140734509397584 139804135923072 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 3.03
Current children cumulated vsize (KiB) 1058936

[startup+6.30079 s]
/proc/loadavg: 1.16 1.08 1.11 2/75 22517
/proc/meminfo: memFree=90788/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
[pid=22508] ppid=22505 vsize=1049768 CPUtime=6.17
/proc/22508/stat : 22508 (java) S 22505 22505 1745 0 -1 4202496 113412 0 0 0 576 41 0 0 20 0 10 0 120939618 1074962432 107682 18446744073709551615 4194304 4228012 140734509397584 140734509388608 139804244119157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/22508/statm: 262442 107682 2371 9 0 249792 0
[pid=22508/tid=22509] ppid=22505 vsize=1049768 CPUtime=3.3
/proc/22508/task/22509/stat : 22509 (java) R 22505 22505 1745 0 -1 4202560 47487 0 0 0 310 20 0 0 20 0 10 0 120939619 1074962432 107682 18446744073709551615 4194304 4228012 140734509397584 139804250653656 139804148152930 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=22508/tid=22510] ppid=22505 vsize=1049768 CPUtime=1.06
/proc/22508/task/22510/stat : 22510 (java) S 22505 22505 1745 0 -1 4202560 45148 0 0 0 93 13 0 0 20 0 10 0 120939622 1074962432 107682 18446744073709551615 4194304 4228012 140734509397584 139804143291520 139804244133451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22511] ppid=22505 vsize=1049768 CPUtime=0
/proc/22508/task/22511/stat : 22511 (java) S 22505 22505 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 120939622 1074962432 107682 18446744073709551615 4194304 4228012 140734509397584 139804142237712 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22512] ppid=22505 vsize=1049768 CPUtime=0
/proc/22508/task/22512/stat : 22512 (java) S 22505 22505 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 120939623 1074962432 107682 18446744073709551615 4194304 4228012 140734509397584 139804141184928 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22513] ppid=22505 vsize=1049768 CPUtime=0
/proc/22508/task/22513/stat : 22513 (java) S 22505 22505 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120939626 1074962432 107682 18446744073709551615 4194304 4228012 140734509397584 139804140133360 139804244141088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22514] ppid=22505 vsize=1049768 CPUtime=0.85
/proc/22508/task/22514/stat : 22514 (java) R 22505 22505 1745 0 -1 4202560 10706 0 0 0 83 2 0 0 20 0 10 0 120939626 1074962432 107682 18446744073709551615 4194304 4228012 140734509397584 139804139080464 139804230633637 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22515] ppid=22505 vsize=1049768 CPUtime=0.93
/proc/22508/task/22515/stat : 22515 (java) R 22505 22505 1745 0 -1 4202560 8778 0 0 0 89 4 0 0 20 0 10 0 120939626 1074962432 107682 18446744073709551615 4194304 4228012 140734509397584 139804138027792 139804230277120 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22516] ppid=22505 vsize=1049768 CPUtime=0
/proc/22508/task/22516/stat : 22516 (java) S 22505 22505 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120939626 1074962432 107682 18446744073709551615 4194304 4228012 140734509397584 139804136975568 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22517] ppid=22505 vsize=1049768 CPUtime=0
/proc/22508/task/22517/stat : 22517 (java) S 22505 22505 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120939626 1074962432 107682 18446744073709551615 4194304 4228012 140734509397584 139804135923072 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 6.17
Current children cumulated vsize (KiB) 1058936

[startup+12.7009 s]
/proc/loadavg: 1.13 1.08 1.11 2/76 22518
/proc/meminfo: memFree=56060/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
[pid=22508] ppid=22505 vsize=1050796 CPUtime=12.49
/proc/22508/stat : 22508 (java) S 22505 22505 1745 0 -1 4202496 121786 0 0 0 1204 45 0 0 20 0 11 0 120939618 1076015104 116056 18446744073709551615 4194304 4228012 140734509397584 140734509388608 139804244119157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/22508/statm: 262699 116056 2380 9 0 250049 0
[pid=22508/tid=22509] ppid=22505 vsize=1050796 CPUtime=7.4
/proc/22508/task/22509/stat : 22509 (java) R 22505 22505 1745 0 -1 4202560 47805 0 0 0 718 22 0 0 20 0 11 0 120939619 1076015104 116056 18446744073709551615 4194304 4228012 140734509397584 139804250652472 139804149249886 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=22508/tid=22510] ppid=22505 vsize=1050796 CPUtime=1.3
/proc/22508/task/22510/stat : 22510 (java) S 22505 22505 1745 0 -1 4202560 52861 0 0 0 115 15 0 0 20 0 11 0 120939622 1076015104 116056 18446744073709551615 4194304 4228012 140734509397584 139804143291520 139804244133451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22511] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22511/stat : 22511 (java) S 22505 22505 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120939622 1076015104 116056 18446744073709551615 4194304 4228012 140734509397584 139804142237712 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22512] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22512/stat : 22512 (java) S 22505 22505 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120939623 1076015104 116056 18446744073709551615 4194304 4228012 140734509397584 139804141184928 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22513] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22513/stat : 22513 (java) S 22505 22505 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 116056 18446744073709551615 4194304 4228012 140734509397584 139804140133360 139804244141088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22514] ppid=22505 vsize=1050796 CPUtime=1.9
/proc/22508/task/22514/stat : 22514 (java) S 22505 22505 1745 0 -1 4202560 10787 0 0 0 188 2 0 0 20 0 11 0 120939626 1076015104 116056 18446744073709551615 4194304 4228012 140734509397584 139804139080720 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22515] ppid=22505 vsize=1050796 CPUtime=1.85
/proc/22508/task/22515/stat : 22515 (java) S 22505 22505 1745 0 -1 4202560 9034 0 0 0 181 4 0 0 20 0 11 0 120939626 1076015104 116056 18446744073709551615 4194304 4228012 140734509397584 139804138028048 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22516] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22516/stat : 22516 (java) S 22505 22505 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 116056 18446744073709551615 4194304 4228012 140734509397584 139804136975568 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22517] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22517/stat : 22517 (java) S 22505 22505 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 116056 18446744073709551615 4194304 4228012 140734509397584 139804135923072 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22518] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22518/stat : 22518 (java) S 22505 22505 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120940497 1076015104 116056 18446744073709551615 4194304 4228012 140734509397584 139804134729568 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 12.49
Current children cumulated vsize (KiB) 1059964

[startup+25.5009 s]
/proc/loadavg: 1.11 1.08 1.10 2/76 22518
/proc/meminfo: memFree=14768/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
[pid=22508] ppid=22505 vsize=1050796 CPUtime=25.1
/proc/22508/stat : 22508 (java) S 22505 22505 1745 0 -1 4202496 133349 0 0 0 2461 49 0 0 20 0 11 0 120939618 1076015104 127619 18446744073709551615 4194304 4228012 140734509397584 140734509388608 139804244119157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/22508/statm: 262699 127619 2392 9 0 250049 0
[pid=22508/tid=22509] ppid=22505 vsize=1050796 CPUtime=17.32
/proc/22508/task/22509/stat : 22509 (java) R 22505 22505 1745 0 -1 4202560 47806 0 0 0 1710 22 0 0 20 0 11 0 120939619 1076015104 127619 18446744073709551615 4194304 4228012 140734509397584 139804250652648 139804149270384 0 4 0 16800975 0 0 0 -1 0 0 0 4 0 0
[pid=22508/tid=22510] ppid=22505 vsize=1050796 CPUtime=3.68
/proc/22508/task/22510/stat : 22510 (java) S 22505 22505 1745 0 -1 4202560 64392 0 0 0 348 20 0 0 20 0 11 0 120939622 1076015104 127619 18446744073709551615 4194304 4228012 140734509397584 139804143291520 139804244133451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22511] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22511/stat : 22511 (java) S 22505 22505 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120939622 1076015104 127619 18446744073709551615 4194304 4228012 140734509397584 139804142237712 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22512] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22512/stat : 22512 (java) S 22505 22505 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120939623 1076015104 127619 18446744073709551615 4194304 4228012 140734509397584 139804141184928 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22513] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22513/stat : 22513 (java) S 22505 22505 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 127619 18446744073709551615 4194304 4228012 140734509397584 139804140133360 139804244141088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22514] ppid=22505 vsize=1050796 CPUtime=2.17
/proc/22508/task/22514/stat : 22514 (java) S 22505 22505 1745 0 -1 4202560 10818 0 0 0 215 2 0 0 20 0 11 0 120939626 1076015104 127619 18446744073709551615 4194304 4228012 140734509397584 139804139080720 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22515] ppid=22505 vsize=1050796 CPUtime=1.88
/proc/22508/task/22515/stat : 22515 (java) S 22505 22505 1745 0 -1 4202560 9034 0 0 0 184 4 0 0 20 0 11 0 120939626 1076015104 127619 18446744073709551615 4194304 4228012 140734509397584 139804138028048 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22516] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22516/stat : 22516 (java) S 22505 22505 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 127619 18446744073709551615 4194304 4228012 140734509397584 139804136975568 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22517] ppid=22505 vsize=1050796 CPUtime=0.01
/proc/22508/task/22517/stat : 22517 (java) S 22505 22505 1745 0 -1 4202560 1 0 0 0 1 0 0 0 20 0 11 0 120939626 1076015104 127619 18446744073709551615 4194304 4228012 140734509397584 139804135923072 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22518] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22518/stat : 22518 (java) S 22505 22505 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120940497 1076015104 127619 18446744073709551615 4194304 4228012 140734509397584 139804134729568 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 25.1
Current children cumulated vsize (KiB) 1059964

[startup+51.1009 s]
/proc/loadavg: 1.07 1.07 1.10 2/76 22518
/proc/meminfo: memFree=10284/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
[pid=22508] ppid=22505 vsize=1050796 CPUtime=50.32
/proc/22508/stat : 22508 (java) S 22505 22505 1745 0 -1 4202496 134539 0 0 0 4983 49 0 0 20 0 11 0 120939618 1076015104 128805 18446744073709551615 4194304 4228012 140734509397584 140734509388608 139804244119157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/22508/statm: 262699 128805 2392 9 0 250049 0
[pid=22508/tid=22509] ppid=22505 vsize=1050796 CPUtime=40.03
/proc/22508/task/22509/stat : 22509 (java) R 22505 22505 1745 0 -1 4202560 47806 0 0 0 3981 22 0 0 20 0 11 0 120939619 1076015104 128805 18446744073709551615 4194304 4228012 140734509397584 139804250652648 139804149328224 0 4 0 16800975 0 0 0 -1 0 0 0 4 0 0
[pid=22508/tid=22510] ppid=22505 vsize=1050796 CPUtime=6.14
/proc/22508/task/22510/stat : 22510 (java) S 22505 22505 1745 0 -1 4202560 65579 0 0 0 594 20 0 0 20 0 11 0 120939622 1076015104 128805 18446744073709551615 4194304 4228012 140734509397584 139804143291520 139804244133451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22511] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22511/stat : 22511 (java) S 22505 22505 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120939622 1076015104 128805 18446744073709551615 4194304 4228012 140734509397584 139804142237712 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22512] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22512/stat : 22512 (java) S 22505 22505 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120939623 1076015104 128805 18446744073709551615 4194304 4228012 140734509397584 139804141184928 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22513] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22513/stat : 22513 (java) S 22505 22505 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 128805 18446744073709551615 4194304 4228012 140734509397584 139804140133360 139804244141088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22514] ppid=22505 vsize=1050796 CPUtime=2.19
/proc/22508/task/22514/stat : 22514 (java) S 22505 22505 1745 0 -1 4202560 10818 0 0 0 217 2 0 0 20 0 11 0 120939626 1076015104 128805 18446744073709551615 4194304 4228012 140734509397584 139804139080720 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22515] ppid=22505 vsize=1050796 CPUtime=1.91
/proc/22508/task/22515/stat : 22515 (java) S 22505 22505 1745 0 -1 4202560 9034 0 0 0 187 4 0 0 20 0 11 0 120939626 1076015104 128805 18446744073709551615 4194304 4228012 140734509397584 139804138028048 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22516] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22516/stat : 22516 (java) S 22505 22505 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 128805 18446744073709551615 4194304 4228012 140734509397584 139804136975568 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22517] ppid=22505 vsize=1050796 CPUtime=0.02
/proc/22508/task/22517/stat : 22517 (java) S 22505 22505 1745 0 -1 4202560 4 0 0 0 2 0 0 0 20 0 11 0 120939626 1076015104 128805 18446744073709551615 4194304 4228012 140734509397584 139804135923072 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22518] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22518/stat : 22518 (java) S 22505 22505 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120940497 1076015104 128805 18446744073709551615 4194304 4228012 140734509397584 139804134729568 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 50.32
Current children cumulated vsize (KiB) 1059964

[startup+102.301 s]
/proc/loadavg: 1.06 1.07 1.09 2/76 22518
/proc/meminfo: memFree=10264/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
[pid=22508] ppid=22505 vsize=1050796 CPUtime=100.79
/proc/22508/stat : 22508 (java) S 22505 22505 1745 0 -1 4202496 142603 0 0 0 10026 53 0 0 20 0 11 0 120939618 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 140734509388608 139804244119157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/22508/statm: 262699 136865 2392 9 0 250049 0
[pid=22508/tid=22509] ppid=22505 vsize=1050796 CPUtime=86.42
/proc/22508/task/22509/stat : 22509 (java) R 22505 22505 1745 0 -1 4202560 47806 0 0 0 8620 22 0 0 20 0 11 0 120939619 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 139804250652648 139804148446402 0 4 0 16800975 0 0 0 -1 0 0 0 4 0 0
[pid=22508/tid=22510] ppid=22505 vsize=1050796 CPUtime=10.19
/proc/22508/task/22510/stat : 22510 (java) S 22505 22505 1745 0 -1 4202560 73640 0 0 0 995 24 0 0 20 0 11 0 120939622 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 139804143291520 139804244133451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22511] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22511/stat : 22511 (java) S 22505 22505 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120939622 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 139804142237712 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22512] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22512/stat : 22512 (java) S 22505 22505 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120939623 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 139804141184928 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22513] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22513/stat : 22513 (java) S 22505 22505 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 139804140133360 139804244141088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22514] ppid=22505 vsize=1050796 CPUtime=2.19
/proc/22508/task/22514/stat : 22514 (java) S 22505 22505 1745 0 -1 4202560 10818 0 0 0 217 2 0 0 20 0 11 0 120939626 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 139804139080720 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22515] ppid=22505 vsize=1050796 CPUtime=1.91
/proc/22508/task/22515/stat : 22515 (java) S 22505 22505 1745 0 -1 4202560 9034 0 0 0 187 4 0 0 20 0 11 0 120939626 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 139804138028048 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22516] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22516/stat : 22516 (java) S 22505 22505 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 139804136975568 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22517] ppid=22505 vsize=1050796 CPUtime=0.04
/proc/22508/task/22517/stat : 22517 (java) S 22505 22505 1745 0 -1 4202560 7 0 0 0 4 0 0 0 20 0 11 0 120939626 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 139804135923072 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22518] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22518/stat : 22518 (java) S 22505 22505 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120940497 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 139804134729568 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 100.79
Current children cumulated vsize (KiB) 1059964

[startup+162.301 s]
/proc/loadavg: 1.02 1.06 1.09 2/76 22518
/proc/meminfo: memFree=10160/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
[pid=22508] ppid=22505 vsize=1050796 CPUtime=160
/proc/22508/stat : 22508 (java) S 22505 22505 1745 0 -1 4202496 142611 0 0 0 15947 53 0 0 20 0 11 0 120939618 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 140734509388608 139804244119157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/22508/statm: 262699 136865 2392 9 0 250049 0
[pid=22508/tid=22509] ppid=22505 vsize=1050796 CPUtime=142.79
/proc/22508/task/22509/stat : 22509 (java) R 22505 22505 1745 0 -1 4202560 47806 0 0 0 14257 22 0 0 20 0 11 0 120939619 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 139804250652840 139804148653888 0 4 0 16800975 0 0 0 -1 0 0 0 4 0 0
[pid=22508/tid=22510] ppid=22505 vsize=1050796 CPUtime=12.99
/proc/22508/task/22510/stat : 22510 (java) S 22505 22505 1745 0 -1 4202560 73642 0 0 0 1275 24 0 0 20 0 11 0 120939622 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 139804143291520 139804244133451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22511] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22511/stat : 22511 (java) S 22505 22505 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120939622 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 139804142237712 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22512] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22512/stat : 22512 (java) S 22505 22505 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120939623 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 139804141184928 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22513] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22513/stat : 22513 (java) S 22505 22505 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 139804140133360 139804244141088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22514] ppid=22505 vsize=1050796 CPUtime=2.2
/proc/22508/task/22514/stat : 22514 (java) S 22505 22505 1745 0 -1 4202560 10818 0 0 0 218 2 0 0 20 0 11 0 120939626 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 139804139080720 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22515] ppid=22505 vsize=1050796 CPUtime=1.91
/proc/22508/task/22515/stat : 22515 (java) S 22505 22505 1745 0 -1 4202560 9034 0 0 0 187 4 0 0 20 0 11 0 120939626 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 139804138028048 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22516] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22516/stat : 22516 (java) S 22505 22505 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 139804136975568 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22517] ppid=22505 vsize=1050796 CPUtime=0.07
/proc/22508/task/22517/stat : 22517 (java) S 22505 22505 1745 0 -1 4202560 13 0 0 0 7 0 0 0 20 0 11 0 120939626 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 139804135923072 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22518] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22518/stat : 22518 (java) S 22505 22505 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120940497 1076015104 136865 18446744073709551615 4194304 4228012 140734509397584 139804134729568 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 160
Current children cumulated vsize (KiB) 1059964

[startup+222.304 s]
/proc/loadavg: 1.04 1.06 1.09 2/76 22518
/proc/meminfo: memFree=10024/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
[pid=22508] ppid=22505 vsize=1050796 CPUtime=219.38
/proc/22508/stat : 22508 (java) S 22505 22505 1745 0 -1 4202496 143450 0 0 0 21884 54 0 0 20 0 11 0 120939618 1076015104 137696 18446744073709551615 4194304 4228012 140734509397584 140734509388608 139804244119157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/22508/statm: 262699 137696 2392 9 0 250049 0
[pid=22508/tid=22509] ppid=22505 vsize=1050796 CPUtime=199.54
/proc/22508/task/22509/stat : 22509 (java) R 22505 22505 1745 0 -1 4202560 47806 0 0 0 19932 22 0 0 20 0 11 0 120939619 1076015104 137696 18446744073709551615 4194304 4228012 140734509397584 139804250652648 139804148445996 0 4 0 16800975 0 0 0 -1 0 0 0 4 0 0
[pid=22508/tid=22510] ppid=22505 vsize=1050796 CPUtime=15.58
/proc/22508/task/22510/stat : 22510 (java) S 22505 22505 1745 0 -1 4202560 74475 0 0 0 1534 24 0 0 20 0 11 0 120939622 1076015104 137696 18446744073709551615 4194304 4228012 140734509397584 139804143291520 139804244133451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22511] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22511/stat : 22511 (java) S 22505 22505 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120939622 1076015104 137696 18446744073709551615 4194304 4228012 140734509397584 139804142237712 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22512] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22512/stat : 22512 (java) S 22505 22505 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120939623 1076015104 137696 18446744073709551615 4194304 4228012 140734509397584 139804141184928 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22513] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22513/stat : 22513 (java) S 22505 22505 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 137696 18446744073709551615 4194304 4228012 140734509397584 139804140133360 139804244141088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22514] ppid=22505 vsize=1050796 CPUtime=2.21
/proc/22508/task/22514/stat : 22514 (java) S 22505 22505 1745 0 -1 4202560 10818 0 0 0 219 2 0 0 20 0 11 0 120939626 1076015104 137696 18446744073709551615 4194304 4228012 140734509397584 139804139080720 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22515] ppid=22505 vsize=1050796 CPUtime=1.91
/proc/22508/task/22515/stat : 22515 (java) S 22505 22505 1745 0 -1 4202560 9034 0 0 0 187 4 0 0 20 0 11 0 120939626 1076015104 137696 18446744073709551615 4194304 4228012 140734509397584 139804138028048 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22516] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22516/stat : 22516 (java) S 22505 22505 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 137696 18446744073709551615 4194304 4228012 140734509397584 139804136975568 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22517] ppid=22505 vsize=1050796 CPUtime=0.1
/proc/22508/task/22517/stat : 22517 (java) S 22505 22505 1745 0 -1 4202560 19 0 0 0 10 0 0 0 20 0 11 0 120939626 1076015104 137696 18446744073709551615 4194304 4228012 140734509397584 139804135923072 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22518] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22518/stat : 22518 (java) S 22505 22505 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120940497 1076015104 137696 18446744073709551615 4194304 4228012 140734509397584 139804134729568 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 219.38
Current children cumulated vsize (KiB) 1059964

[startup+282.301 s]
/proc/loadavg: 1.05 1.06 1.09 2/76 22526
/proc/meminfo: memFree=9940/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
[pid=22508] ppid=22505 vsize=1050796 CPUtime=278.7
/proc/22508/stat : 22508 (java) S 22505 22505 1745 0 -1 4202496 150382 0 0 0 27813 57 0 0 20 0 11 0 120939618 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 140734509388608 139804244119157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/22508/statm: 262699 144621 2392 9 0 250049 0
[pid=22508/tid=22509] ppid=22505 vsize=1050796 CPUtime=256.69
/proc/22508/task/22509/stat : 22509 (java) R 22505 22505 1745 0 -1 4202560 47806 0 0 0 25647 22 0 0 20 0 11 0 120939619 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804250652648 139804149328080 0 4 0 16800975 0 0 0 -1 0 0 0 4 0 0
[pid=22508/tid=22510] ppid=22505 vsize=1050796 CPUtime=17.69
/proc/22508/task/22510/stat : 22510 (java) S 22505 22505 1745 0 -1 4202560 81401 0 0 0 1743 26 0 0 20 0 11 0 120939622 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804143291520 139804244133451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22511] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22511/stat : 22511 (java) S 22505 22505 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120939622 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804142237712 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22512] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22512/stat : 22512 (java) S 22505 22505 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120939623 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804141184928 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22513] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22513/stat : 22513 (java) S 22505 22505 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804140133360 139804244141088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22514] ppid=22505 vsize=1050796 CPUtime=2.23
/proc/22508/task/22514/stat : 22514 (java) S 22505 22505 1745 0 -1 4202560 10818 0 0 0 221 2 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804139080720 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22515] ppid=22505 vsize=1050796 CPUtime=1.91
/proc/22508/task/22515/stat : 22515 (java) S 22505 22505 1745 0 -1 4202560 9034 0 0 0 187 4 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804138028048 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22516] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22516/stat : 22516 (java) S 22505 22505 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804136975568 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22517] ppid=22505 vsize=1050796 CPUtime=0.12
/proc/22508/task/22517/stat : 22517 (java) S 22505 22505 1745 0 -1 4202560 25 0 0 0 12 0 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804135923072 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22518] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22518/stat : 22518 (java) S 22505 22505 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120940497 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804134729568 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 278.7
Current children cumulated vsize (KiB) 1059964

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

[startup+288.001 s]
/proc/loadavg: 1.05 1.06 1.08 2/76 22526
/proc/meminfo: memFree=9940/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
[pid=22508] ppid=22505 vsize=1050796 CPUtime=284.34
/proc/22508/stat : 22508 (java) S 22505 22505 1745 0 -1 4202496 150382 0 0 0 28377 57 0 0 20 0 11 0 120939618 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 140734509388608 139804244119157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/22508/statm: 262699 144621 2392 9 0 250049 0
[pid=22508/tid=22509] ppid=22505 vsize=1050796 CPUtime=262.33
/proc/22508/task/22509/stat : 22509 (java) R 22505 22505 1745 0 -1 4202560 47806 0 0 0 26211 22 0 0 20 0 11 0 120939619 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804250652648 139804149249621 0 4 0 16800975 0 0 0 -1 0 0 0 4 0 0
[pid=22508/tid=22510] ppid=22505 vsize=1050796 CPUtime=17.69
/proc/22508/task/22510/stat : 22510 (java) S 22505 22505 1745 0 -1 4202560 81401 0 0 0 1743 26 0 0 20 0 11 0 120939622 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804143291520 139804244133451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22511] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22511/stat : 22511 (java) S 22505 22505 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120939622 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804142237712 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22512] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22512/stat : 22512 (java) S 22505 22505 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120939623 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804141184928 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22513] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22513/stat : 22513 (java) S 22505 22505 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804140133360 139804244141088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22514] ppid=22505 vsize=1050796 CPUtime=2.23
/proc/22508/task/22514/stat : 22514 (java) S 22505 22505 1745 0 -1 4202560 10818 0 0 0 221 2 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804139080720 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22515] ppid=22505 vsize=1050796 CPUtime=1.91
/proc/22508/task/22515/stat : 22515 (java) S 22505 22505 1745 0 -1 4202560 9034 0 0 0 187 4 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804138028048 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22516] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22516/stat : 22516 (java) S 22505 22505 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804136975568 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22517] ppid=22505 vsize=1050796 CPUtime=0.13
/proc/22508/task/22517/stat : 22517 (java) S 22505 22505 1745 0 -1 4202560 25 0 0 0 13 0 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804135923072 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22518] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22518/stat : 22518 (java) S 22505 22505 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120940497 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804134729568 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 284.34
Current children cumulated vsize (KiB) 1059964

[startup+291.201 s]
/proc/loadavg: 1.05 1.06 1.08 2/76 22526
/proc/meminfo: memFree=9940/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
[pid=22508] ppid=22505 vsize=1050796 CPUtime=287.51
/proc/22508/stat : 22508 (java) S 22505 22505 1745 0 -1 4202496 150383 0 0 0 28694 57 0 0 20 0 11 0 120939618 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 140734509388608 139804244119157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/22508/statm: 262699 144621 2392 9 0 250049 0
[pid=22508/tid=22509] ppid=22505 vsize=1050796 CPUtime=265.49
/proc/22508/task/22509/stat : 22509 (java) S 22505 22505 1745 0 -1 4202560 47806 0 0 0 26527 22 0 0 20 0 11 0 120939619 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804250652448 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 4 0 0
[pid=22508/tid=22510] ppid=22505 vsize=1050796 CPUtime=17.7
/proc/22508/task/22510/stat : 22510 (java) R 22505 22505 1745 0 -1 4202560 81402 0 0 0 1744 26 0 0 20 0 11 0 120939622 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804143290928 139804228481445 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22511] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22511/stat : 22511 (java) S 22505 22505 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120939622 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804142237712 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22512] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22512/stat : 22512 (java) S 22505 22505 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120939623 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804141184928 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22513] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22513/stat : 22513 (java) S 22505 22505 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804140133360 139804244141088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22514] ppid=22505 vsize=1050796 CPUtime=2.23
/proc/22508/task/22514/stat : 22514 (java) S 22505 22505 1745 0 -1 4202560 10818 0 0 0 221 2 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804139080720 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22515] ppid=22505 vsize=1050796 CPUtime=1.91
/proc/22508/task/22515/stat : 22515 (java) S 22505 22505 1745 0 -1 4202560 9034 0 0 0 187 4 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804138028048 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22516] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22516/stat : 22516 (java) S 22505 22505 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804136975568 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22517] ppid=22505 vsize=1050796 CPUtime=0.13
/proc/22508/task/22517/stat : 22517 (java) S 22505 22505 1745 0 -1 4202560 25 0 0 0 13 0 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804135923072 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22518] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22518/stat : 22518 (java) S 22505 22505 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120940497 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804134729568 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 287.51
Current children cumulated vsize (KiB) 1059964

[startup+292.801 s]
/proc/loadavg: 1.04 1.06 1.08 2/76 22526
/proc/meminfo: memFree=9940/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
[pid=22508] ppid=22505 vsize=1050796 CPUtime=289.09
/proc/22508/stat : 22508 (java) S 22505 22505 1745 0 -1 4202496 150383 0 0 0 28852 57 0 0 20 0 11 0 120939618 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 140734509388608 139804244119157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/22508/statm: 262699 144621 2392 9 0 250049 0
[pid=22508/tid=22509] ppid=22505 vsize=1050796 CPUtime=266.56
/proc/22508/task/22509/stat : 22509 (java) R 22505 22505 1745 0 -1 4202560 47806 0 0 0 26634 22 0 0 20 0 11 0 120939619 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804250652648 139804149330327 0 4 0 16800975 0 0 0 -1 0 0 0 4 0 0
[pid=22508/tid=22510] ppid=22505 vsize=1050796 CPUtime=18.21
/proc/22508/task/22510/stat : 22510 (java) S 22505 22505 1745 0 -1 4202560 81402 0 0 0 1795 26 0 0 20 0 11 0 120939622 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804143291520 139804244133451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22511] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22511/stat : 22511 (java) S 22505 22505 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120939622 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804142237712 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22512] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22512/stat : 22512 (java) S 22505 22505 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120939623 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804141184928 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22513] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22513/stat : 22513 (java) S 22505 22505 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804140133360 139804244141088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22514] ppid=22505 vsize=1050796 CPUtime=2.23
/proc/22508/task/22514/stat : 22514 (java) S 22505 22505 1745 0 -1 4202560 10818 0 0 0 221 2 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804139080720 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22515] ppid=22505 vsize=1050796 CPUtime=1.91
/proc/22508/task/22515/stat : 22515 (java) S 22505 22505 1745 0 -1 4202560 9034 0 0 0 187 4 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804138028048 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22516] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22516/stat : 22516 (java) S 22505 22505 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804136975568 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22517] ppid=22505 vsize=1050796 CPUtime=0.13
/proc/22508/task/22517/stat : 22517 (java) S 22505 22505 1745 0 -1 4202560 25 0 0 0 13 0 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804135923072 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22518] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22518/stat : 22518 (java) S 22505 22505 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120940497 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804134729568 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 289.09
Current children cumulated vsize (KiB) 1059964

[startup+293.601 s]
/proc/loadavg: 1.04 1.06 1.08 2/76 22526
/proc/meminfo: memFree=9940/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
[pid=22508] ppid=22505 vsize=1050796 CPUtime=289.89
/proc/22508/stat : 22508 (java) S 22505 22505 1745 0 -1 4202496 150383 0 0 0 28932 57 0 0 20 0 11 0 120939618 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 140734509388608 139804244119157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/22508/statm: 262699 144621 2392 9 0 250049 0
[pid=22508/tid=22509] ppid=22505 vsize=1050796 CPUtime=267.35
/proc/22508/task/22509/stat : 22509 (java) R 22505 22505 1745 0 -1 4202560 47806 0 0 0 26713 22 0 0 20 0 11 0 120939619 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804250652648 139804148653597 0 4 0 16800975 0 0 0 -1 0 0 0 4 0 0
[pid=22508/tid=22510] ppid=22505 vsize=1050796 CPUtime=18.21
/proc/22508/task/22510/stat : 22510 (java) S 22505 22505 1745 0 -1 4202560 81402 0 0 0 1795 26 0 0 20 0 11 0 120939622 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804143291520 139804244133451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22511] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22511/stat : 22511 (java) S 22505 22505 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120939622 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804142237712 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22512] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22512/stat : 22512 (java) S 22505 22505 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120939623 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804141184928 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22513] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22513/stat : 22513 (java) S 22505 22505 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804140133360 139804244141088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22514] ppid=22505 vsize=1050796 CPUtime=2.23
/proc/22508/task/22514/stat : 22514 (java) S 22505 22505 1745 0 -1 4202560 10818 0 0 0 221 2 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804139080720 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22515] ppid=22505 vsize=1050796 CPUtime=1.91
/proc/22508/task/22515/stat : 22515 (java) S 22505 22505 1745 0 -1 4202560 9034 0 0 0 187 4 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804138028048 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22516] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22516/stat : 22516 (java) S 22505 22505 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804136975568 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22517] ppid=22505 vsize=1050796 CPUtime=0.13
/proc/22508/task/22517/stat : 22517 (java) S 22505 22505 1745 0 -1 4202560 25 0 0 0 13 0 0 0 20 0 11 0 120939626 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804135923072 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22518] ppid=22505 vsize=1050796 CPUtime=0
/proc/22508/task/22518/stat : 22518 (java) S 22505 22505 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120940497 1076015104 144621 18446744073709551615 4194304 4228012 140734509397584 139804134729568 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 289.89
Current children cumulated vsize (KiB) 1059964

[startup+294.001 s]
/proc/loadavg: 1.04 1.06 1.08 2/76 22526
/proc/meminfo: memFree=9940/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
[pid=22508] ppid=22505 vsize=1051824 CPUtime=290.12
/proc/22508/stat : 22508 (java) S 22505 22505 1745 0 -1 4202496 150398 0 0 0 28955 57 0 0 20 0 11 0 120939618 1077067776 144634 18446744073709551615 4194304 4228012 140734509397584 140734509388608 139804244119157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/22508/statm: 262956 144634 2392 9 0 250306 0
[pid=22508/tid=22509] ppid=22505 vsize=1051824 CPUtime=267.57
/proc/22508/task/22509/stat : 22509 (java) S 22505 22505 1745 0 -1 4202560 47815 0 0 0 26735 22 0 0 20 0 11 0 120939619 1077067776 144634 18446744073709551615 4194304 4228012 140734509397584 139804250653616 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 4 0 0
[pid=22508/tid=22510] ppid=22505 vsize=1051824 CPUtime=18.21
/proc/22508/task/22510/stat : 22510 (java) S 22505 22505 1745 0 -1 4202560 81402 0 0 0 1795 26 0 0 20 0 11 0 120939622 1077067776 144634 18446744073709551615 4194304 4228012 140734509397584 139804143291520 139804244133451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22511] ppid=22505 vsize=1051824 CPUtime=0
/proc/22508/task/22511/stat : 22511 (java) S 22505 22505 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120939622 1077067776 144634 18446744073709551615 4194304 4228012 140734509397584 139804142237712 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22512] ppid=22505 vsize=1051824 CPUtime=0
/proc/22508/task/22512/stat : 22512 (java) S 22505 22505 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120939623 1077067776 144634 18446744073709551615 4194304 4228012 140734509397584 139804141184928 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22513] ppid=22505 vsize=1051824 CPUtime=0
/proc/22508/task/22513/stat : 22513 (java) S 22505 22505 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120939626 1077067776 144634 18446744073709551615 4194304 4228012 140734509397584 139804140133360 139804244141088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22514] ppid=22505 vsize=1051824 CPUtime=2.24
/proc/22508/task/22514/stat : 22514 (java) S 22505 22505 1745 0 -1 4202560 10818 0 0 0 222 2 0 0 20 0 11 0 120939626 1077067776 144634 18446744073709551615 4194304 4228012 140734509397584 139804139080720 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22515] ppid=22505 vsize=1051824 CPUtime=1.92
/proc/22508/task/22515/stat : 22515 (java) S 22505 22505 1745 0 -1 4202560 9034 0 0 0 188 4 0 0 20 0 11 0 120939626 1077067776 144634 18446744073709551615 4194304 4228012 140734509397584 139804138028048 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22516] ppid=22505 vsize=1051824 CPUtime=0
/proc/22508/task/22516/stat : 22516 (java) S 22505 22505 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120939626 1077067776 144634 18446744073709551615 4194304 4228012 140734509397584 139804136975568 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22517] ppid=22505 vsize=1051824 CPUtime=0.13
/proc/22508/task/22517/stat : 22517 (java) S 22505 22505 1745 0 -1 4202560 25 0 0 0 13 0 0 0 20 0 11 0 120939626 1077067776 144634 18446744073709551615 4194304 4228012 140734509397584 139804135923072 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.12
Current children cumulated vsize (KiB) 1060992

[startup+294.401 s]
/proc/loadavg: 1.04 1.06 1.08 2/76 22526
/proc/meminfo: memFree=9940/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
[pid=22508] ppid=22505 vsize=1051824 CPUtime=290.18
/proc/22508/stat : 22508 (java) S 22505 22505 1745 0 -1 4202496 150400 0 0 0 28959 59 0 0 20 0 11 0 120939618 1077067776 144636 18446744073709551615 4194304 4228012 140734509397584 140734509388608 139804244119157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/22508/statm: 262956 144636 2392 9 0 250306 0
[pid=22508/tid=22509] ppid=22505 vsize=1051824 CPUtime=267.57
/proc/22508/task/22509/stat : 22509 (java) S 22505 22505 1745 0 -1 4202560 47815 0 0 0 26735 22 0 0 20 0 11 0 120939619 1077067776 144636 18446744073709551615 4194304 4228012 140734509397584 139804250653616 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 4 0 0
[pid=22508/tid=22510] ppid=22505 vsize=1051824 CPUtime=18.21
/proc/22508/task/22510/stat : 22510 (java) S 22505 22505 1745 0 -1 4202560 81402 0 0 0 1795 26 0 0 20 0 11 0 120939622 1077067776 144636 18446744073709551615 4194304 4228012 140734509397584 139804143291520 139804244133451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22511] ppid=22505 vsize=1051824 CPUtime=0
/proc/22508/task/22511/stat : 22511 (java) S 22505 22505 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120939622 1077067776 144636 18446744073709551615 4194304 4228012 140734509397584 139804142237712 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22512] ppid=22505 vsize=1051824 CPUtime=0
/proc/22508/task/22512/stat : 22512 (java) S 22505 22505 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120939623 1077067776 144636 18446744073709551615 4194304 4228012 140734509397584 139804141184928 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22513] ppid=22505 vsize=1051824 CPUtime=0
/proc/22508/task/22513/stat : 22513 (java) S 22505 22505 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120939626 1077067776 144636 18446744073709551615 4194304 4228012 140734509397584 139804140133360 139804244141088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22514] ppid=22505 vsize=1051824 CPUtime=2.24
/proc/22508/task/22514/stat : 22514 (java) S 22505 22505 1745 0 -1 4202560 10818 0 0 0 222 2 0 0 20 0 11 0 120939626 1077067776 144636 18446744073709551615 4194304 4228012 140734509397584 139804139080720 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22515] ppid=22505 vsize=1051824 CPUtime=1.92
/proc/22508/task/22515/stat : 22515 (java) S 22505 22505 1745 0 -1 4202560 9034 0 0 0 188 4 0 0 20 0 11 0 120939626 1077067776 144636 18446744073709551615 4194304 4228012 140734509397584 139804138028048 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22516] ppid=22505 vsize=1051824 CPUtime=0
/proc/22508/task/22516/stat : 22516 (java) S 22505 22505 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120939626 1077067776 144636 18446744073709551615 4194304 4228012 140734509397584 139804136975568 139804244132564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22508/tid=22517] ppid=22505 vsize=1051824 CPUtime=0.13
/proc/22508/task/22517/stat : 22517 (java) S 22505 22505 1745 0 -1 4202560 25 0 0 0 13 0 0 0 20 0 11 0 120939626 1077067776 144636 18446744073709551615 4194304 4228012 140734509397584 139804135923072 139804244133451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.18
Current children cumulated vsize (KiB) 1060992

[startup+294.501 s]
/proc/loadavg: 1.04 1.06 1.08 2/76 22526
/proc/meminfo: memFree=9940/1022884 swapFree=0/0
[pid=22505] ppid=22504 vsize=9168 CPUtime=0
/proc/22505/stat : 22505 (p2cudf-full-1.1) S 22504 22505 1745 0 -1 4202496 456 292 0 0 0 0 0 0 20 0 1 0 120939617 9388032 331 18446744073709551615 4194304 5129932 140735144706368 140735144704512 140331759436894 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22505/statm: 2292 331 280 229 0 52 0
[pid=22508] ppid=22505 vsize=0 CPUtime=290.28
/proc/22508/stat : 22508 (java) Z 22505 22505 1745 0 -1 4203532 150411 0 0 0 28965 63 0 0 20 0 2 0 120939618 0 0 18446744073709551615 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/22508/statm: 0 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.28
Current children cumulated vsize (KiB) 9168

Child status: 0
Real time (s): 294.534
CPU time (s): 290.326
CPU user time (s): 289.662
CPU system time (s): 0.664041
CPU usage (%): 98.5712
Max. virtual memory (cumulated for all children) (KiB): 1064640

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 289.662
system time used= 0.664041
maximum resident set size= 578580
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 151172
page faults= 0
swaps= 0
block input operations= 42368
block output operations= 584
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 7221
involuntary context switches= 41358

runsolver used 0.460028 second user time and 2.52416 second system time

The end