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 150 -d 10 -w /tmp/misc2012/2012-09-02-17:21/basic/p2cudf-basic-1.15/embedded/install/rand179.cudf.log.runsolver /home/competition/p2cudf-basic-1.15/p2cudf-basic-1.15 /home/competition/data/install/rand179.cudf /tmp/misc2012/2012-09-02-17:21/basic/p2cudf-basic-1.15/embedded/install/rand179.cudf.result -sum(solution,installedsize),-count(removed) Enforcing CPUTime limit (soft limit, will send signal-name then SIGKILL): 150 seconds Enforcing CPUTime limit (hard limit, will send SIGXCPU): 180 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.31 1.05 0.94 2/59 24042 /proc/meminfo: memFree=575936/1022884 swapFree=0/0 [pid=24042] ppid=24041 vsize=3152 CPUtime=0 /proc/24042/stat : 24042 (runsolver) R 24041 1733 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 1 0 115976117 3227648 32 18446744073709551615 134512640 134586868 4287662672 4287660720 4151985200 0 0 16781316 24578 0 0 0 17 0 0 0 0 0 0 /proc/24042/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.136762 s] /proc/loadavg: 1.31 1.05 0.94 2/59 24042 /proc/meminfo: memFree=575936/1022884 swapFree=0/0 [pid=24042] ppid=24041 vsize=9168 CPUtime=0 /proc/24042/stat : 24042 (p2cudf-basic-1.) S 24041 24042 1733 0 -1 4202496 456 291 0 0 0 0 0 0 20 0 1 0 115976117 9388032 331 18446744073709551615 4194304 5129932 140737405483712 140737405481856 139875292832862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24042/statm: 2292 331 280 229 0 52 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9168 [startup+0.200364 s] /proc/loadavg: 1.31 1.05 0.94 2/59 24042 /proc/meminfo: memFree=575936/1022884 swapFree=0/0 [pid=24042] ppid=24041 vsize=9168 CPUtime=0 /proc/24042/stat : 24042 (p2cudf-basic-1.) S 24041 24042 1733 0 -1 4202496 456 291 0 0 0 0 0 0 20 0 1 0 115976117 9388032 331 18446744073709551615 4194304 5129932 140737405483712 140737405481856 139875292832862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24042/statm: 2292 331 280 229 0 52 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9168 [startup+0.30036 s] /proc/loadavg: 1.31 1.05 0.94 2/59 24042 /proc/meminfo: memFree=575936/1022884 swapFree=0/0 [pid=24042] ppid=24041 vsize=9168 CPUtime=0 /proc/24042/stat : 24042 (p2cudf-basic-1.) S 24041 24042 1733 0 -1 4202496 456 291 0 0 0 0 0 0 20 0 1 0 115976117 9388032 331 18446744073709551615 4194304 5129932 140737405483712 140737405481856 139875292832862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24042/statm: 2292 331 280 229 0 52 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9168 [startup+0.700234 s] /proc/loadavg: 1.31 1.05 0.94 2/59 24042 /proc/meminfo: memFree=575936/1022884 swapFree=0/0 [pid=24042] ppid=24041 vsize=9168 CPUtime=0 /proc/24042/stat : 24042 (p2cudf-basic-1.) S 24041 24042 1733 0 -1 4202496 456 291 0 0 0 0 0 0 20 0 1 0 115976117 9388032 331 18446744073709551615 4194304 5129932 140737405483712 140737405481856 139875292832862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24042/statm: 2292 331 280 229 0 52 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9168 [startup+1.50058 s] /proc/loadavg: 1.45 1.08 0.95 4/70 24054 /proc/meminfo: memFree=524132/1022884 swapFree=0/0 [pid=24042] ppid=24041 vsize=9168 CPUtime=0 /proc/24042/stat : 24042 (p2cudf-basic-1.) S 24041 24042 1733 0 -1 4202496 456 291 0 0 0 0 0 0 20 0 1 0 115976117 9388032 331 18446744073709551615 4194304 5129932 140737405483712 140737405481856 139875292832862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24042/statm: 2292 331 280 229 0 52 0 [pid=24045] ppid=24042 vsize=1060468 CPUtime=1.44 /proc/24045/stat : 24045 (java) S 24042 24042 1733 0 -1 4202496 37095 0 0 0 130 14 0 0 20 0 10 0 115976118 1085919232 33225 18446744073709551615 4194304 4228012 140736168595168 140736168586192 140335147691637 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24045/statm: 265117 33225 2346 9 0 252467 0 [pid=24045/tid=24046] ppid=24042 vsize=1060468 CPUtime=0.66 /proc/24045/task/24046/stat : 24046 (java) R 24042 24042 1733 0 -1 4202560 16895 0 0 0 61 5 0 0 20 0 10 0 115976118 1085919232 33225 18446744073709551615 4194304 4228012 140736168595168 140335154217632 140335052105874 0 4 0 16800975 0 0 0 -1 0 0 0 4 0 0 [pid=24045/tid=24047] ppid=24042 vsize=1060468 CPUtime=0 /proc/24045/task/24047/stat : 24047 (java) S 24042 24042 1733 0 -1 4202560 3 0 0 0 0 0 0 0 20 0 10 0 115976120 1085919232 33225 18446744073709551615 4194304 4228012 140736168595168 140335046864000 140335147705931 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24048] ppid=24042 vsize=1060468 CPUtime=0 /proc/24045/task/24048/stat : 24048 (java) S 24042 24042 1733 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 115976121 1085919232 33225 18446744073709551615 4194304 4228012 140736168595168 140335045810192 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24049] ppid=24042 vsize=1060468 CPUtime=0 /proc/24045/task/24049/stat : 24049 (java) S 24042 24042 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 115976121 1085919232 33225 18446744073709551615 4194304 4228012 140736168595168 140335044757408 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24050] ppid=24042 vsize=1060468 CPUtime=0 /proc/24045/task/24050/stat : 24050 (java) S 24042 24042 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 115976123 1085919232 33225 18446744073709551615 4194304 4228012 140736168595168 140335043705840 140335147713568 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24051] ppid=24042 vsize=1060468 CPUtime=0.39 /proc/24045/task/24051/stat : 24051 (java) R 24042 24042 1733 0 -1 4202560 9677 0 0 0 36 3 0 0 20 0 10 0 115976123 1085919232 33225 18446744073709551615 4194304 4228012 140736168595168 140335042640776 140335131970136 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24052] ppid=24042 vsize=1060468 CPUtime=0.37 /proc/24045/task/24052/stat : 24052 (java) R 24042 24042 1733 0 -1 4202560 9234 0 0 0 33 4 0 0 20 0 10 0 115976123 1085919232 33225 18446744073709551615 4194304 4228012 140736168595168 140335041588056 140335129510992 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24053] ppid=24042 vsize=1060468 CPUtime=0 /proc/24045/task/24053/stat : 24053 (java) S 24042 24042 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115976123 1085919232 33225 18446744073709551615 4194304 4228012 140736168595168 140335040548048 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24054] ppid=24042 vsize=1060468 CPUtime=0 /proc/24045/task/24054/stat : 24054 (java) S 24042 24042 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115976123 1085919232 33225 18446744073709551615 4194304 4228012 140736168595168 140335039495552 140335147705931 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.44 Current children cumulated vsize (KiB) 1069636 [startup+3.10058 s] /proc/loadavg: 1.45 1.08 0.95 2/70 24054 /proc/meminfo: memFree=303288/1022884 swapFree=0/0 [pid=24042] ppid=24041 vsize=9168 CPUtime=0 /proc/24042/stat : 24042 (p2cudf-basic-1.) S 24041 24042 1733 0 -1 4202496 456 291 0 0 0 0 0 0 20 0 1 0 115976117 9388032 331 18446744073709551615 4194304 5129932 140737405483712 140737405481856 139875292832862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24042/statm: 2292 331 280 229 0 52 0 [pid=24045] ppid=24042 vsize=1056324 CPUtime=3.02 /proc/24045/stat : 24045 (java) S 24042 24042 1733 0 -1 4202496 97652 0 0 0 269 33 0 0 20 0 10 0 115976118 1081675776 92417 18446744073709551615 4194304 4228012 140736168595168 140736168586192 140335147691637 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24045/statm: 264081 92417 2367 9 0 251431 0 [pid=24045/tid=24046] ppid=24042 vsize=1056324 CPUtime=1.48 /proc/24045/task/24046/stat : 24046 (java) R 24042 24042 1733 0 -1 4202560 47410 0 0 0 132 16 0 0 20 0 10 0 115976118 1081675776 92417 18446744073709551615 4194304 4228012 140736168595168 140335154217664 140335052092721 0 4 0 16800975 0 0 0 -1 0 0 0 5 0 0 [pid=24045/tid=24047] ppid=24042 vsize=1056324 CPUtime=0.47 /proc/24045/task/24047/stat : 24047 (java) S 24042 24042 1733 0 -1 4202560 29293 0 0 0 38 9 0 0 20 0 10 0 115976120 1081675776 92417 18446744073709551615 4194304 4228012 140736168595168 140335046864000 140335147705931 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24048] ppid=24042 vsize=1056324 CPUtime=0 /proc/24045/task/24048/stat : 24048 (java) S 24042 24042 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 115976121 1081675776 92417 18446744073709551615 4194304 4228012 140736168595168 140335045810192 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24049] ppid=24042 vsize=1056324 CPUtime=0 /proc/24045/task/24049/stat : 24049 (java) S 24042 24042 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 115976121 1081675776 92417 18446744073709551615 4194304 4228012 140736168595168 140335044757408 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24050] ppid=24042 vsize=1056324 CPUtime=0 /proc/24045/task/24050/stat : 24050 (java) S 24042 24042 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 115976123 1081675776 92417 18446744073709551615 4194304 4228012 140736168595168 140335043705840 140335147713568 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24051] ppid=24042 vsize=1056324 CPUtime=0.53 /proc/24045/task/24051/stat : 24051 (java) S 24042 24042 1733 0 -1 4202560 10131 0 0 0 50 3 0 0 20 0 10 0 115976123 1081675776 92417 18446744073709551615 4194304 4228012 140736168595168 140335042653200 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24052] ppid=24042 vsize=1056324 CPUtime=0.51 /proc/24045/task/24052/stat : 24052 (java) S 24042 24042 1733 0 -1 4202560 9528 0 0 0 47 4 0 0 20 0 10 0 115976123 1081675776 92417 18446744073709551615 4194304 4228012 140736168595168 140335041600528 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24053] ppid=24042 vsize=1056324 CPUtime=0 /proc/24045/task/24053/stat : 24053 (java) S 24042 24042 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115976123 1081675776 92417 18446744073709551615 4194304 4228012 140736168595168 140335040548048 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24054] ppid=24042 vsize=1056324 CPUtime=0 /proc/24045/task/24054/stat : 24054 (java) S 24042 24042 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115976123 1081675776 92417 18446744073709551615 4194304 4228012 140736168595168 140335039495552 140335147705931 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.02 Current children cumulated vsize (KiB) 1065492 [startup+6.30059 s] /proc/loadavg: 1.45 1.08 0.95 2/70 24054 /proc/meminfo: memFree=19452/1022884 swapFree=0/0 [pid=24042] ppid=24041 vsize=9168 CPUtime=0 /proc/24042/stat : 24042 (p2cudf-basic-1.) S 24041 24042 1733 0 -1 4202496 456 291 0 0 0 0 0 0 20 0 1 0 115976117 9388032 331 18446744073709551615 4194304 5129932 140737405483712 140737405481856 139875292832862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24042/statm: 2292 331 280 229 0 52 0 [pid=24045] ppid=24042 vsize=1056324 CPUtime=6.06 /proc/24045/stat : 24045 (java) S 24042 24042 1733 0 -1 4202496 145787 0 0 0 557 49 0 0 20 0 10 0 115976118 1081675776 140499 18446744073709551615 4194304 4228012 140736168595168 140736168586192 140335147691637 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24045/statm: 264081 140499 2370 9 0 251431 0 [pid=24045/tid=24046] ppid=24042 vsize=1056324 CPUtime=3.15 /proc/24045/task/24046/stat : 24046 (java) R 24042 24042 1733 0 -1 4202560 47480 0 0 0 294 21 0 0 20 0 10 0 115976118 1081675776 140499 18446744073709551615 4194304 4228012 140736168595168 140335154225184 140335134544244 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0 [pid=24045/tid=24047] ppid=24042 vsize=1056324 CPUtime=1.51 /proc/24045/task/24047/stat : 24047 (java) S 24042 24042 1733 0 -1 4202560 77222 0 0 0 132 19 0 0 20 0 10 0 115976120 1081675776 140499 18446744073709551615 4194304 4228012 140736168595168 140335046864000 140335147705931 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24048] ppid=24042 vsize=1056324 CPUtime=0 /proc/24045/task/24048/stat : 24048 (java) S 24042 24042 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 115976121 1081675776 140499 18446744073709551615 4194304 4228012 140736168595168 140335045810192 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24049] ppid=24042 vsize=1056324 CPUtime=0 /proc/24045/task/24049/stat : 24049 (java) S 24042 24042 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 115976121 1081675776 140499 18446744073709551615 4194304 4228012 140736168595168 140335044757408 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24050] ppid=24042 vsize=1056324 CPUtime=0 /proc/24045/task/24050/stat : 24050 (java) S 24042 24042 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 115976123 1081675776 140499 18446744073709551615 4194304 4228012 140736168595168 140335043705840 140335147713568 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24051] ppid=24042 vsize=1056324 CPUtime=0.69 /proc/24045/task/24051/stat : 24051 (java) R 24042 24042 1733 0 -1 4202560 10232 0 0 0 66 3 0 0 20 0 10 0 115976123 1081675776 140499 18446744073709551615 4194304 4228012 140736168595168 140335042652944 140335133286360 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24052] ppid=24042 vsize=1056324 CPUtime=0.68 /proc/24045/task/24052/stat : 24052 (java) R 24042 24042 1733 0 -1 4202560 9562 0 0 0 64 4 0 0 20 0 10 0 115976123 1081675776 140499 18446744073709551615 4194304 4228012 140736168595168 140335041586280 140335130537224 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24053] ppid=24042 vsize=1056324 CPUtime=0 /proc/24045/task/24053/stat : 24053 (java) S 24042 24042 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115976123 1081675776 140499 18446744073709551615 4194304 4228012 140736168595168 140335040548048 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24054] ppid=24042 vsize=1056324 CPUtime=0 /proc/24045/task/24054/stat : 24054 (java) S 24042 24042 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 115976123 1081675776 140499 18446744073709551615 4194304 4228012 140736168595168 140335039495552 140335147705931 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 6.06 Current children cumulated vsize (KiB) 1065492 Solver just ended. Dumping a history of the last processes samples [startup+6.40075 s] /proc/loadavg: 1.45 1.08 0.95 2/70 24054 /proc/meminfo: memFree=19452/1022884 swapFree=0/0 [pid=24042] ppid=24041 vsize=9168 CPUtime=0 /proc/24042/stat : 24042 (p2cudf-basic-1.) S 24041 24042 1733 0 -1 4202496 456 291 0 0 0 0 0 0 20 0 1 0 115976117 9388032 331 18446744073709551615 4194304 5129932 140737405483712 140737405481856 139875292832862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24042/statm: 2292 331 280 229 0 52 0 [pid=24045] ppid=24042 vsize=1056324 CPUtime=6.16 /proc/24045/stat : 24045 (java) S 24042 24042 1733 0 -1 4202496 145815 0 0 0 567 49 0 0 20 0 10 0 115976118 1081675776 140527 18446744073709551615 4194304 4228012 140736168595168 140736168586192 140335147691637 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24045/statm: 264081 140527 2370 9 0 251431 0 [pid=24045/tid=24046] ppid=24042 vsize=1056324 CPUtime=3.19 /proc/24045/task/24046/stat : 24046 (java) R 24042 24042 1733 0 -1 4202560 47480 0 0 0 298 21 0 0 20 0 10 0 115976118 1081675776 140527 18446744073709551615 4194304 4228012 140736168595168 140335154226392 140335052135213 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0 [pid=24045/tid=24047] ppid=24042 vsize=1056324 CPUtime=1.51 /proc/24045/task/24047/stat : 24047 (java) S 24042 24042 1733 0 -1 4202560 77222 0 0 0 132 19 0 0 20 0 10 0 115976120 1081675776 140527 18446744073709551615 4194304 4228012 140736168595168 140335046864000 140335147705931 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24048] ppid=24042 vsize=1056324 CPUtime=0 /proc/24045/task/24048/stat : 24048 (java) S 24042 24042 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 115976121 1081675776 140527 18446744073709551615 4194304 4228012 140736168595168 140335045810192 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24049] ppid=24042 vsize=1056324 CPUtime=0 /proc/24045/task/24049/stat : 24049 (java) S 24042 24042 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 115976121 1081675776 140527 18446744073709551615 4194304 4228012 140736168595168 140335044757408 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24050] ppid=24042 vsize=1056324 CPUtime=0 /proc/24045/task/24050/stat : 24050 (java) S 24042 24042 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 115976123 1081675776 140527 18446744073709551615 4194304 4228012 140736168595168 140335043705840 140335147713568 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24051] ppid=24042 vsize=1056324 CPUtime=0.73 /proc/24045/task/24051/stat : 24051 (java) R 24042 24042 1733 0 -1 4202560 10244 0 0 0 70 3 0 0 20 0 10 0 115976123 1081675776 140527 18446744073709551615 4194304 4228012 140736168595168 140335042652944 140335133264287 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24052] ppid=24042 vsize=1056324 CPUtime=0.7 /proc/24045/task/24052/stat : 24052 (java) S 24042 24042 1733 0 -1 4202560 9578 0 0 0 66 4 0 0 20 0 10 0 115976123 1081675776 140527 18446744073709551615 4194304 4228012 140736168595168 140335041600528 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24053] ppid=24042 vsize=1056324 CPUtime=0 /proc/24045/task/24053/stat : 24053 (java) S 24042 24042 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115976123 1081675776 140527 18446744073709551615 4194304 4228012 140736168595168 140335040548048 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24054] ppid=24042 vsize=1056324 CPUtime=0 /proc/24045/task/24054/stat : 24054 (java) S 24042 24042 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 115976123 1081675776 140527 18446744073709551615 4194304 4228012 140736168595168 140335039495552 140335147705931 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 6.16 Current children cumulated vsize (KiB) 1065492 [startup+9.60059 s] /proc/loadavg: 1.41 1.08 0.95 2/70 24054 /proc/meminfo: memFree=9484/1022884 swapFree=0/0 [pid=24042] ppid=24041 vsize=9168 CPUtime=0 /proc/24042/stat : 24042 (p2cudf-basic-1.) S 24041 24042 1733 0 -1 4202496 456 291 0 0 0 0 0 0 20 0 1 0 115976117 9388032 331 18446744073709551615 4194304 5129932 140737405483712 140737405481856 139875292832862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24042/statm: 2292 331 280 229 0 52 0 [pid=24045] ppid=24042 vsize=1057352 CPUtime=9.28 /proc/24045/stat : 24045 (java) S 24042 24042 1733 0 -1 4202496 153034 0 0 0 876 52 0 0 20 0 11 0 115976118 1082728448 147642 18446744073709551615 4194304 4228012 140736168595168 140736168586192 140335147691637 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24045/statm: 264338 147642 2380 9 0 251688 0 [pid=24045/tid=24046] ppid=24042 vsize=1057352 CPUtime=4.47 /proc/24045/task/24046/stat : 24046 (java) R 24042 24042 1733 0 -1 4202560 47790 0 0 0 426 21 0 0 20 0 11 0 115976118 1082728448 147642 18446744073709551615 4194304 4228012 140736168595168 140335154224904 140335052230870 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0 [pid=24045/tid=24047] ppid=24042 vsize=1057352 CPUtime=1.68 /proc/24045/task/24047/stat : 24047 (java) S 24042 24042 1733 0 -1 4202560 83754 0 0 0 146 22 0 0 20 0 11 0 115976120 1082728448 147642 18446744073709551615 4194304 4228012 140736168595168 140335046864000 140335147705931 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24048] ppid=24042 vsize=1057352 CPUtime=0 /proc/24045/task/24048/stat : 24048 (java) S 24042 24042 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115976121 1082728448 147642 18446744073709551615 4194304 4228012 140736168595168 140335045810192 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24049] ppid=24042 vsize=1057352 CPUtime=0 /proc/24045/task/24049/stat : 24049 (java) S 24042 24042 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115976121 1082728448 147642 18446744073709551615 4194304 4228012 140736168595168 140335044757408 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24050] ppid=24042 vsize=1057352 CPUtime=0 /proc/24045/task/24050/stat : 24050 (java) S 24042 24042 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115976123 1082728448 147642 18446744073709551615 4194304 4228012 140736168595168 140335043705840 140335147713568 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24051] ppid=24042 vsize=1057352 CPUtime=1.56 /proc/24045/task/24051/stat : 24051 (java) R 24042 24042 1733 0 -1 4202560 10541 0 0 0 152 4 0 0 20 0 11 0 115976123 1082728448 147642 18446744073709551615 4194304 4228012 140736168595168 140335042652944 140335131996152 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24052] ppid=24042 vsize=1057352 CPUtime=1.54 /proc/24045/task/24052/stat : 24052 (java) R 24042 24042 1733 0 -1 4202560 9652 0 0 0 150 4 0 0 20 0 11 0 115976123 1082728448 147642 18446744073709551615 4194304 4228012 140736168595168 140335041600272 140335134217883 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24053] ppid=24042 vsize=1057352 CPUtime=0 /proc/24045/task/24053/stat : 24053 (java) S 24042 24042 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115976123 1082728448 147642 18446744073709551615 4194304 4228012 140736168595168 140335040548048 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24054] ppid=24042 vsize=1057352 CPUtime=0 /proc/24045/task/24054/stat : 24054 (java) S 24042 24042 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115976123 1082728448 147642 18446744073709551615 4194304 4228012 140736168595168 140335039495552 140335147705931 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 9.28 Current children cumulated vsize (KiB) 1066520 [startup+10.4006 s] /proc/loadavg: 1.41 1.08 0.95 2/71 24055 /proc/meminfo: memFree=9104/1022884 swapFree=0/0 [pid=24042] ppid=24041 vsize=9168 CPUtime=0 /proc/24042/stat : 24042 (p2cudf-basic-1.) S 24041 24042 1733 0 -1 4202496 456 291 0 0 0 0 0 0 20 0 1 0 115976117 9388032 331 18446744073709551615 4194304 5129932 140737405483712 140737405481856 139875292832862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24042/statm: 2292 331 280 229 0 52 0 [pid=24045] ppid=24042 vsize=1057352 CPUtime=10.07 /proc/24045/stat : 24045 (java) S 24042 24042 1733 0 -1 4202496 153051 0 1 0 955 52 0 0 20 0 11 0 115976118 1082728448 147660 18446744073709551615 4194304 4228012 140736168595168 140736168586192 140335147691637 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24045/statm: 264338 147660 2381 9 0 251688 0 [pid=24045/tid=24046] ppid=24042 vsize=1057352 CPUtime=5.14 /proc/24045/task/24046/stat : 24046 (java) R 24042 24042 1733 0 -1 4202560 47796 0 0 0 493 21 0 0 20 0 11 0 115976118 1082728448 147660 18446744073709551615 4194304 4228012 140736168595168 140335154224584 140335147716186 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0 [pid=24045/tid=24047] ppid=24042 vsize=1057352 CPUtime=1.68 /proc/24045/task/24047/stat : 24047 (java) S 24042 24042 1733 0 -1 4202560 83754 0 0 0 146 22 0 0 20 0 11 0 115976120 1082728448 147660 18446744073709551615 4194304 4228012 140736168595168 140335046864000 140335147705931 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24048] ppid=24042 vsize=1057352 CPUtime=0 /proc/24045/task/24048/stat : 24048 (java) S 24042 24042 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115976121 1082728448 147660 18446744073709551615 4194304 4228012 140736168595168 140335045810192 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24049] ppid=24042 vsize=1057352 CPUtime=0 /proc/24045/task/24049/stat : 24049 (java) S 24042 24042 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115976121 1082728448 147660 18446744073709551615 4194304 4228012 140736168595168 140335044757408 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24050] ppid=24042 vsize=1057352 CPUtime=0 /proc/24045/task/24050/stat : 24050 (java) S 24042 24042 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115976123 1082728448 147660 18446744073709551615 4194304 4228012 140736168595168 140335043705840 140335147713568 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24051] ppid=24042 vsize=1057352 CPUtime=1.63 /proc/24045/task/24051/stat : 24051 (java) R 24042 24042 1733 0 -1 4202560 10550 0 0 0 159 4 0 0 20 0 11 0 115976123 1082728448 147660 18446744073709551615 4194304 4228012 140736168595168 140335042652944 140335133540720 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24052] ppid=24042 vsize=1057352 CPUtime=1.59 /proc/24045/task/24052/stat : 24052 (java) R 24042 24042 1733 0 -1 4202560 9654 0 1 0 155 4 0 0 20 0 11 0 115976123 1082728448 147660 18446744073709551615 4194304 4228012 140736168595168 140335041600272 140335131974228 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0 [pid=24045/tid=24053] ppid=24042 vsize=1057352 CPUtime=0 /proc/24045/task/24053/stat : 24053 (java) S 24042 24042 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115976123 1082728448 147660 18446744073709551615 4194304 4228012 140736168595168 140335040548048 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24054] ppid=24042 vsize=1057352 CPUtime=0 /proc/24045/task/24054/stat : 24054 (java) S 24042 24042 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115976123 1082728448 147660 18446744073709551615 4194304 4228012 140736168595168 140335039495552 140335147705931 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24055] ppid=24042 vsize=1057352 CPUtime=0 /proc/24045/task/24055/stat : 24055 (java) S 24042 24042 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 115977004 1082728448 147660 18446744073709551615 4194304 4228012 140736168595168 140335038289760 140335147705931 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 10.07 Current children cumulated vsize (KiB) 1066520 [startup+10.8008 s] /proc/loadavg: 1.41 1.08 0.95 2/71 24055 /proc/meminfo: memFree=9104/1022884 swapFree=0/0 [pid=24042] ppid=24041 vsize=9168 CPUtime=0 /proc/24042/stat : 24042 (p2cudf-basic-1.) S 24041 24042 1733 0 -1 4202496 456 291 0 0 0 0 0 0 20 0 1 0 115976117 9388032 331 18446744073709551615 4194304 5129932 140737405483712 140737405481856 139875292832862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24042/statm: 2292 331 280 229 0 52 0 [pid=24045] ppid=24042 vsize=1058380 CPUtime=10.08 /proc/24045/stat : 24045 (java) S 24042 24042 1733 0 -1 4202496 153062 0 1 0 956 52 0 0 20 0 11 0 115976118 1083781120 147669 18446744073709551615 4194304 4228012 140736168595168 140736168586192 140335147691637 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24045/statm: 264595 147669 2381 9 0 251945 0 [pid=24045/tid=24046] ppid=24042 vsize=1058380 CPUtime=5.14 /proc/24045/task/24046/stat : 24046 (java) S 24042 24042 1733 0 -1 4202560 47801 0 0 0 493 21 0 0 20 0 11 0 115976118 1083781120 147669 18446744073709551615 4194304 4228012 140736168595168 140335154226096 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0 [pid=24045/tid=24047] ppid=24042 vsize=1058380 CPUtime=1.68 /proc/24045/task/24047/stat : 24047 (java) S 24042 24042 1733 0 -1 4202560 83754 0 0 0 146 22 0 0 20 0 11 0 115976120 1083781120 147669 18446744073709551615 4194304 4228012 140736168595168 140335046864000 140335147705931 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24048] ppid=24042 vsize=1058380 CPUtime=0 /proc/24045/task/24048/stat : 24048 (java) S 24042 24042 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115976121 1083781120 147669 18446744073709551615 4194304 4228012 140736168595168 140335045810192 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24049] ppid=24042 vsize=1058380 CPUtime=0 /proc/24045/task/24049/stat : 24049 (java) S 24042 24042 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115976121 1083781120 147669 18446744073709551615 4194304 4228012 140736168595168 140335044757408 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24050] ppid=24042 vsize=1058380 CPUtime=0 /proc/24045/task/24050/stat : 24050 (java) S 24042 24042 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115976123 1083781120 147669 18446744073709551615 4194304 4228012 140736168595168 140335043705840 140335147713568 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24051] ppid=24042 vsize=1058380 CPUtime=1.64 /proc/24045/task/24051/stat : 24051 (java) S 24042 24042 1733 0 -1 4202560 10550 0 0 0 160 4 0 0 20 0 11 0 115976123 1083781120 147669 18446744073709551615 4194304 4228012 140736168595168 140335042653200 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24052] ppid=24042 vsize=1058380 CPUtime=1.59 /proc/24045/task/24052/stat : 24052 (java) S 24042 24042 1733 0 -1 4202560 9654 0 1 0 155 4 0 0 20 0 11 0 115976123 1083781120 147669 18446744073709551615 4194304 4228012 140736168595168 140335041600528 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0 [pid=24045/tid=24053] ppid=24042 vsize=1058380 CPUtime=0 /proc/24045/task/24053/stat : 24053 (java) S 24042 24042 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115976123 1083781120 147669 18446744073709551615 4194304 4228012 140736168595168 140335040548048 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24054] ppid=24042 vsize=1058380 CPUtime=0 /proc/24045/task/24054/stat : 24054 (java) S 24042 24042 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115976123 1083781120 147669 18446744073709551615 4194304 4228012 140736168595168 140335039495552 140335147705931 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 10.08 Current children cumulated vsize (KiB) 1067548 [startup+11.0007 s] /proc/loadavg: 1.46 1.10 0.96 2/71 24056 /proc/meminfo: memFree=10220/1022884 swapFree=0/0 [pid=24042] ppid=24041 vsize=9168 CPUtime=0 /proc/24042/stat : 24042 (p2cudf-basic-1.) S 24041 24042 1733 0 -1 4202496 456 291 0 0 0 0 0 0 20 0 1 0 115976117 9388032 331 18446744073709551615 4194304 5129932 140737405483712 140737405481856 139875292832862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24042/statm: 2292 331 280 229 0 52 0 [pid=24045] ppid=24042 vsize=1058380 CPUtime=10.18 /proc/24045/stat : 24045 (java) S 24042 24042 1733 0 -1 4202496 153064 0 1 0 962 56 0 0 20 0 11 0 115976118 1083781120 147671 18446744073709551615 4194304 4228012 140736168595168 140736168586192 140335147691637 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24045/statm: 264595 147671 2381 9 0 251945 0 [pid=24045/tid=24046] ppid=24042 vsize=1058380 CPUtime=5.14 /proc/24045/task/24046/stat : 24046 (java) S 24042 24042 1733 0 -1 4202560 47801 0 0 0 493 21 0 0 20 0 11 0 115976118 1083781120 147671 18446744073709551615 4194304 4228012 140736168595168 140335154226096 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0 [pid=24045/tid=24047] ppid=24042 vsize=1058380 CPUtime=1.68 /proc/24045/task/24047/stat : 24047 (java) S 24042 24042 1733 0 -1 4202560 83754 0 0 0 146 22 0 0 20 0 11 0 115976120 1083781120 147671 18446744073709551615 4194304 4228012 140736168595168 140335046864000 140335147705931 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24048] ppid=24042 vsize=1058380 CPUtime=0 /proc/24045/task/24048/stat : 24048 (java) S 24042 24042 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115976121 1083781120 147671 18446744073709551615 4194304 4228012 140736168595168 140335045810192 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24049] ppid=24042 vsize=1058380 CPUtime=0 /proc/24045/task/24049/stat : 24049 (java) S 24042 24042 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115976121 1083781120 147671 18446744073709551615 4194304 4228012 140736168595168 140335044757408 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24050] ppid=24042 vsize=1058380 CPUtime=0 /proc/24045/task/24050/stat : 24050 (java) S 24042 24042 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115976123 1083781120 147671 18446744073709551615 4194304 4228012 140736168595168 140335043705840 140335147713568 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24051] ppid=24042 vsize=1058380 CPUtime=1.65 /proc/24045/task/24051/stat : 24051 (java) S 24042 24042 1733 0 -1 4202560 10550 0 0 0 161 4 0 0 20 0 11 0 115976123 1083781120 147671 18446744073709551615 4194304 4228012 140736168595168 140335042653200 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24052] ppid=24042 vsize=1058380 CPUtime=1.59 /proc/24045/task/24052/stat : 24052 (java) S 24042 24042 1733 0 -1 4202560 9654 0 1 0 155 4 0 0 20 0 11 0 115976123 1083781120 147671 18446744073709551615 4194304 4228012 140736168595168 140335041600360 140335147715820 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0 [pid=24045/tid=24053] ppid=24042 vsize=1058380 CPUtime=0 /proc/24045/task/24053/stat : 24053 (java) S 24042 24042 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115976123 1083781120 147671 18446744073709551615 4194304 4228012 140736168595168 140335040548048 140335147705044 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24054] ppid=24042 vsize=1058380 CPUtime=0 /proc/24045/task/24054/stat : 24054 (java) S 24042 24042 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115976123 1083781120 147671 18446744073709551615 4194304 4228012 140736168595168 140335039495552 140335147705931 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24045/tid=24056] ppid=24042 vsize=1058380 CPUtime=0.08 /proc/24045/task/24056/stat : 24056 (java) R 24042 24042 1733 0 -1 4202560 8 0 0 0 4 4 0 0 20 0 11 0 115977158 1083781120 147671 18446744073709551615 4194304 4228012 140736168595168 140334969381976 140335147707061 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 10.18 Current children cumulated vsize (KiB) 1067548 [startup+11.1083 s] /proc/loadavg: 1.46 1.10 0.96 2/71 24056 /proc/meminfo: memFree=10220/1022884 swapFree=0/0 [pid=24042] ppid=24041 vsize=9168 CPUtime=0 /proc/24042/stat : 24042 (p2cudf-basic-1.) S 24041 24042 1733 0 -1 4202496 456 291 0 0 0 0 0 0 20 0 1 0 115976117 9388032 331 18446744073709551615 4194304 5129932 140737405483712 140737405481856 139875292832862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24042/statm: 2292 331 280 229 0 52 0 [pid=24045] ppid=24042 vsize=0 CPUtime=10.28 /proc/24045/stat : 24045 (java) Z 24042 24042 1733 0 -1 4203532 153075 0 1 0 968 60 0 0 20 0 2 0 115976118 0 0 18446744073709551615 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24045/statm: 0 0 0 0 0 0 0 [pid=24045/tid=24056] ppid=24042 vsize=0 CPUtime=0.16 /proc/24045/task/24056/stat : 24056 (java) R 24042 24042 1733 0 -1 4203588 9 0 0 0 8 8 0 0 20 0 2 0 115977158 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) 10.28 Current children cumulated vsize (KiB) 9168 Child status: 0 Real time (s): 11.1655 CPU time (s): 10.3486 CPU user time (s): 9.68861 CPU system time (s): 0.660041 CPU usage (%): 92.6842 Max. virtual memory (cumulated for all children) (KiB): 1070488 getrusage(RUSAGE_CHILDREN,...) data: user time used= 9.68861 system time used= 0.660041 maximum resident set size= 590720 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 153835 page faults= 1 swaps= 0 block input operations= 68472 block output operations= 224 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 1104 involuntary context switches= 2850 runsolver used 0.012 second user time and 0.088005 second system time The end