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: /home/misc2010/bin/runsolver -s SIGUSR1 -M 1124 -C 290 -d 10 -w /home/misc2010/tmp/201108251442/p2cudf-paranoid-1.13/rand763.cudf.user-upgrades.log.runsolver ./p2cudf-paranoid-1.13 /home/misc2010/data/2011/user-upgrades/rand763.cudf /home/misc2010/tmp/201108251442/p2cudf-paranoid-1.13/rand763.cudf.user-upgrades.result Enforcing CPUTime limit (soft limit, will send signal-name then SIGKILL): 290 seconds Enforcing CPUTime limit (hard limit, will send SIGXCPU): 320 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.25 1.39 1.25 5/37 22905 /proc/meminfo: memFree=285980/1048576 swapFree=0/0 [pid=22903] ppid=22902 vsize=2568 CPUtime=0 /proc/22903/stat : 22903 (p2cudf-paranoid) S 22902 22903 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 11162918 2629632 270 1283457024 134512640 135304128 4293054528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22903/statm: 642 270 230 194 0 29 0 [pid=22904] ppid=22903 vsize=2564 CPUtime=0 /proc/22904/stat : 22904 (p2cudf) R 22903 22903 4778 34817 4778 4202496 344 0 0 0 0 0 0 0 25 0 1 0 11162918 2625536 270 1283457024 134512640 135304128 4294234496 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/22904/statm: 641 270 231 194 0 28 0 [pid=22905] ppid=22904 vsize=2564 CPUtime=0 /proc/22905/stat : 22905 (p2cudf) R 22904 22903 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 1 0 11162918 2625536 39 1283457024 134512640 135304128 4294234496 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/22905/statm: 641 39 0 194 0 28 0 [startup+0.10573 s] /proc/loadavg: 1.25 1.39 1.25 5/37 22905 /proc/meminfo: memFree=285980/1048576 swapFree=0/0 [pid=22903] ppid=22902 vsize=2568 CPUtime=0 /proc/22903/stat : 22903 (p2cudf-paranoid) S 22902 22903 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 11162918 2629632 270 1283457024 134512640 135304128 4293054528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22903/statm: 642 270 230 194 0 29 0 [pid=22904] ppid=22903 vsize=2568 CPUtime=0.02 /proc/22904/stat : 22904 (p2cudf) S 22903 22903 4778 34817 4778 4202496 389 3015 0 1 0 0 1 1 25 0 1 0 11162918 2629632 279 1283457024 134512640 135304128 4294234496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22904/statm: 642 279 239 194 0 29 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5136 [startup+0.205732 s] /proc/loadavg: 1.25 1.39 1.25 5/37 22905 /proc/meminfo: memFree=285980/1048576 swapFree=0/0 [pid=22903] ppid=22902 vsize=2568 CPUtime=0 /proc/22903/stat : 22903 (p2cudf-paranoid) S 22902 22903 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 11162918 2629632 270 1283457024 134512640 135304128 4293054528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22903/statm: 642 270 230 194 0 29 0 [pid=22904] ppid=22903 vsize=2568 CPUtime=0.02 /proc/22904/stat : 22904 (p2cudf) S 22903 22903 4778 34817 4778 4202496 389 3015 0 1 0 0 1 1 25 0 1 0 11162918 2629632 279 1283457024 134512640 135304128 4294234496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22904/statm: 642 279 239 194 0 29 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5136 [startup+0.305741 s] /proc/loadavg: 1.25 1.39 1.25 5/37 22905 /proc/meminfo: memFree=285980/1048576 swapFree=0/0 [pid=22903] ppid=22902 vsize=2568 CPUtime=0 /proc/22903/stat : 22903 (p2cudf-paranoid) S 22902 22903 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 11162918 2629632 270 1283457024 134512640 135304128 4293054528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22903/statm: 642 270 230 194 0 29 0 [pid=22904] ppid=22903 vsize=2568 CPUtime=0.02 /proc/22904/stat : 22904 (p2cudf) S 22903 22903 4778 34817 4778 4202496 389 3015 0 1 0 0 1 1 25 0 1 0 11162918 2629632 279 1283457024 134512640 135304128 4294234496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22904/statm: 642 279 239 194 0 29 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5136 [startup+0.705808 s] /proc/loadavg: 1.25 1.39 1.25 5/37 22905 /proc/meminfo: memFree=285980/1048576 swapFree=0/0 [pid=22903] ppid=22902 vsize=2568 CPUtime=0 /proc/22903/stat : 22903 (p2cudf-paranoid) S 22902 22903 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 11162918 2629632 270 1283457024 134512640 135304128 4293054528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22903/statm: 642 270 230 194 0 29 0 [pid=22904] ppid=22903 vsize=2568 CPUtime=0.02 /proc/22904/stat : 22904 (p2cudf) S 22903 22903 4778 34817 4778 4202496 389 3015 0 1 0 0 1 1 25 0 1 0 11162918 2629632 279 1283457024 134512640 135304128 4294234496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22904/statm: 642 279 239 194 0 29 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5136 [startup+1.50594 s] /proc/loadavg: 1.25 1.39 1.25 5/47 22926 /proc/meminfo: memFree=236972/1048576 swapFree=0/0 [pid=22903] ppid=22902 vsize=2568 CPUtime=0 /proc/22903/stat : 22903 (p2cudf-paranoid) S 22902 22903 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 11162918 2629632 270 1283457024 134512640 135304128 4293054528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22903/statm: 642 270 230 194 0 29 0 [pid=22904] ppid=22903 vsize=2568 CPUtime=0.02 /proc/22904/stat : 22904 (p2cudf) S 22903 22903 4778 34817 4778 4202496 389 3015 0 1 0 0 1 1 25 0 1 0 11162918 2629632 279 1283457024 134512640 135304128 4294234496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22904/statm: 642 279 239 194 0 29 0 [pid=22917] ppid=22904 vsize=893772 CPUtime=1.48 /proc/22917/stat : 22917 (java) S 22904 22903 4778 34817 4778 4202496 21730 0 1 0 140 8 0 0 25 0 10 0 11162922 915222528 18297 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22917/statm: 223443 18297 1721 10 0 220534 0 [pid=22917/tid=22918] ppid=22904 vsize=893772 CPUtime=0.79 /proc/22917/task/22918/stat : 22918 (java) R 22904 22903 4778 34817 4778 4202560 8243 0 1 0 75 4 0 0 25 0 10 0 11162922 915222528 18297 1283457024 134512640 134550932 4288053808 18446744073709551615 4095087545 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=22917/tid=22919] ppid=22904 vsize=893772 CPUtime=0 /proc/22917/task/22919/stat : 22919 (java) S 22904 22903 4778 34817 4778 4202560 7 0 0 0 0 0 0 0 16 0 10 0 11162924 915222528 18297 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 0 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22920] ppid=22904 vsize=893772 CPUtime=0 /proc/22917/task/22920/stat : 22920 (java) S 22904 22903 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 25 0 10 0 11162924 915222528 18297 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22921] ppid=22904 vsize=893772 CPUtime=0 /proc/22917/task/22921/stat : 22921 (java) S 22904 22903 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 11162924 915222528 18297 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22922] ppid=22904 vsize=893772 CPUtime=0 /proc/22917/task/22922/stat : 22922 (java) S 22904 22903 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11162927 915222528 18297 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22923] ppid=22904 vsize=893772 CPUtime=0.31 /proc/22917/task/22923/stat : 22923 (java) R 22904 22903 4778 34817 4778 4202560 5656 0 0 0 29 2 0 0 18 0 10 0 11162927 915222528 18297 1283457024 134512640 134550932 4288053808 18446744073709551615 4152481776 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=22917/tid=22924] ppid=22904 vsize=893772 CPUtime=0.35 /proc/22917/task/22924/stat : 22924 (java) R 22904 22903 4778 34817 4778 4202560 6750 0 0 0 35 0 0 0 25 0 10 0 11162927 915222528 18297 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=22917/tid=22925] ppid=22904 vsize=893772 CPUtime=0 /proc/22917/task/22925/stat : 22925 (java) S 22904 22903 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11162927 915222528 18297 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22926] ppid=22904 vsize=893772 CPUtime=0 /proc/22917/task/22926/stat : 22926 (java) S 22904 22903 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11162927 915222528 18297 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.5 Current children cumulated vsize (KiB) 898908 [startup+3.1062 s] /proc/loadavg: 1.25 1.39 1.25 2/47 22926 /proc/meminfo: memFree=138144/1048576 swapFree=0/0 [pid=22903] ppid=22902 vsize=2568 CPUtime=0 /proc/22903/stat : 22903 (p2cudf-paranoid) S 22902 22903 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 11162918 2629632 270 1283457024 134512640 135304128 4293054528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22903/statm: 642 270 230 194 0 29 0 [pid=22904] ppid=22903 vsize=2568 CPUtime=0.02 /proc/22904/stat : 22904 (p2cudf) S 22903 22903 4778 34817 4778 4202496 389 3015 0 1 0 0 1 1 25 0 1 0 11162918 2629632 279 1283457024 134512640 135304128 4294234496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22904/statm: 642 279 239 194 0 29 0 [pid=22917] ppid=22904 vsize=891672 CPUtime=3.07 /proc/22917/stat : 22917 (java) S 22904 22903 4778 34817 4778 4202496 74569 0 1 0 279 28 0 0 25 0 10 0 11162922 913072128 71024 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22917/statm: 222918 71024 1732 10 0 220009 0 [pid=22917/tid=22918] ppid=22904 vsize=891672 CPUtime=1.81 /proc/22917/task/22918/stat : 22918 (java) R 22904 22903 4778 34817 4778 4202560 50038 0 1 0 158 23 0 0 25 0 10 0 11162922 913072128 71024 1283457024 134512640 134550932 4288053808 18446744073709551615 4095326118 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=22917/tid=22919] ppid=22904 vsize=891672 CPUtime=0.21 /proc/22917/task/22919/stat : 22919 (java) S 22904 22903 4778 34817 4778 4202560 10772 0 0 0 21 0 0 0 16 0 10 0 11162924 913072128 71024 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 0 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22920] ppid=22904 vsize=891672 CPUtime=0 /proc/22917/task/22920/stat : 22920 (java) S 22904 22903 4778 34817 4778 4202560 9 0 0 0 0 0 0 0 18 0 10 0 11162924 913072128 71024 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22921] ppid=22904 vsize=891672 CPUtime=0 /proc/22917/task/22921/stat : 22921 (java) S 22904 22903 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 10 0 11162924 913072128 71024 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22922] ppid=22904 vsize=891672 CPUtime=0 /proc/22917/task/22922/stat : 22922 (java) S 22904 22903 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11162927 913072128 71024 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22923] ppid=22904 vsize=891672 CPUtime=0.65 /proc/22917/task/22923/stat : 22923 (java) S 22904 22903 4778 34817 4778 4202560 5905 0 0 0 62 3 0 0 15 0 10 0 11162927 913072128 71024 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22924] ppid=22904 vsize=891672 CPUtime=0.36 /proc/22917/task/22924/stat : 22924 (java) S 22904 22903 4778 34817 4778 4202560 6777 0 0 0 36 0 0 0 25 0 10 0 11162927 913072128 71024 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22925] ppid=22904 vsize=891672 CPUtime=0 /proc/22917/task/22925/stat : 22925 (java) S 22904 22903 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11162927 913072128 71024 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22926] ppid=22904 vsize=891672 CPUtime=0 /proc/22917/task/22926/stat : 22926 (java) S 22904 22903 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11162927 913072128 71024 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.09 Current children cumulated vsize (KiB) 896808 Solver just ended. Dumping a history of the last processes samples [startup+3.22624 s] /proc/loadavg: 1.25 1.39 1.25 2/47 22926 /proc/meminfo: memFree=138144/1048576 swapFree=0/0 [pid=22903] ppid=22902 vsize=2568 CPUtime=0 /proc/22903/stat : 22903 (p2cudf-paranoid) S 22902 22903 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 11162918 2629632 270 1283457024 134512640 135304128 4293054528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22903/statm: 642 270 230 194 0 29 0 [pid=22904] ppid=22903 vsize=2568 CPUtime=0.02 /proc/22904/stat : 22904 (p2cudf) S 22903 22903 4778 34817 4778 4202496 389 3015 0 1 0 0 1 1 25 0 1 0 11162918 2629632 279 1283457024 134512640 135304128 4294234496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22904/statm: 642 279 239 194 0 29 0 [pid=22917] ppid=22904 vsize=891672 CPUtime=3.19 /proc/22917/stat : 22917 (java) S 22904 22903 4778 34817 4778 4202496 74588 0 1 0 291 28 0 0 25 0 10 0 11162922 913072128 71043 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22917/statm: 222918 71043 1740 10 0 220009 0 [pid=22917/tid=22918] ppid=22904 vsize=891672 CPUtime=1.87 /proc/22917/task/22918/stat : 22918 (java) R 22904 22903 4778 34817 4778 4202560 50052 0 1 0 164 23 0 0 25 0 10 0 11162922 913072128 71043 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=22917/tid=22919] ppid=22904 vsize=891672 CPUtime=0.21 /proc/22917/task/22919/stat : 22919 (java) S 22904 22903 4778 34817 4778 4202560 10772 0 0 0 21 0 0 0 16 0 10 0 11162924 913072128 71043 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 0 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22920] ppid=22904 vsize=891672 CPUtime=0 /proc/22917/task/22920/stat : 22920 (java) S 22904 22903 4778 34817 4778 4202560 9 0 0 0 0 0 0 0 18 0 10 0 11162924 913072128 71043 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22921] ppid=22904 vsize=891672 CPUtime=0 /proc/22917/task/22921/stat : 22921 (java) S 22904 22903 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 10 0 11162924 913072128 71043 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22922] ppid=22904 vsize=891672 CPUtime=0 /proc/22917/task/22922/stat : 22922 (java) S 22904 22903 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11162927 913072128 71043 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22923] ppid=22904 vsize=891672 CPUtime=0.72 /proc/22917/task/22923/stat : 22923 (java) R 22904 22903 4778 34817 4778 4202560 5910 0 0 0 69 3 0 0 15 0 10 0 11162927 913072128 71043 1283457024 134512640 134550932 4288053808 18446744073709551615 4149959512 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=22917/tid=22924] ppid=22904 vsize=891672 CPUtime=0.36 /proc/22917/task/22924/stat : 22924 (java) S 22904 22903 4778 34817 4778 4202560 6777 0 0 0 36 0 0 0 25 0 10 0 11162927 913072128 71043 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22925] ppid=22904 vsize=891672 CPUtime=0 /proc/22917/task/22925/stat : 22925 (java) S 22904 22903 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11162927 913072128 71043 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22926] ppid=22904 vsize=891672 CPUtime=0 /proc/22917/task/22926/stat : 22926 (java) S 22904 22903 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11162927 913072128 71043 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.21 Current children cumulated vsize (KiB) 896808 [startup+4.00638 s] /proc/loadavg: 1.31 1.40 1.25 3/47 22926 /proc/meminfo: memFree=7696/1048576 swapFree=0/0 [pid=22903] ppid=22902 vsize=2568 CPUtime=0 /proc/22903/stat : 22903 (p2cudf-paranoid) S 22902 22903 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 11162918 2629632 270 1283457024 134512640 135304128 4293054528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22903/statm: 642 270 230 194 0 29 0 [pid=22904] ppid=22903 vsize=2568 CPUtime=0.02 /proc/22904/stat : 22904 (p2cudf) S 22903 22903 4778 34817 4778 4202496 389 3015 0 1 0 0 1 1 25 0 1 0 11162918 2629632 279 1283457024 134512640 135304128 4294234496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22904/statm: 642 279 239 194 0 29 0 [pid=22917] ppid=22904 vsize=893608 CPUtime=3.97 /proc/22917/stat : 22917 (java) S 22904 22903 4778 34817 4778 4202496 76335 0 1 0 366 31 0 0 25 0 10 0 11162922 915054592 71739 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22917/statm: 223402 71739 1746 10 0 220493 0 [pid=22917/tid=22918] ppid=22904 vsize=893608 CPUtime=1.95 /proc/22917/task/22918/stat : 22918 (java) R 22904 22903 4778 34817 4778 4202560 50212 0 1 0 172 23 0 0 25 0 10 0 11162922 915054592 71739 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=22917/tid=22919] ppid=22904 vsize=893608 CPUtime=0.21 /proc/22917/task/22919/stat : 22919 (java) S 22904 22903 4778 34817 4778 4202560 10772 0 0 0 21 0 0 0 15 0 10 0 11162924 915054592 71739 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 0 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22920] ppid=22904 vsize=893608 CPUtime=0 /proc/22917/task/22920/stat : 22920 (java) S 22904 22903 4778 34817 4778 4202560 9 0 0 0 0 0 0 0 18 0 10 0 11162924 915054592 71739 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22921] ppid=22904 vsize=893608 CPUtime=0 /proc/22917/task/22921/stat : 22921 (java) S 22904 22903 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 10 0 11162924 915054592 71739 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22922] ppid=22904 vsize=893608 CPUtime=0 /proc/22917/task/22922/stat : 22922 (java) S 22904 22903 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11162927 915054592 71739 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22923] ppid=22904 vsize=893608 CPUtime=1.42 /proc/22917/task/22923/stat : 22923 (java) R 22904 22903 4778 34817 4778 4202560 7497 0 0 0 136 6 0 0 16 0 10 0 11162927 915054592 71739 1283457024 134512640 134550932 4288053808 18446744073709551615 4149962637 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=22917/tid=22924] ppid=22904 vsize=893608 CPUtime=0.36 /proc/22917/task/22924/stat : 22924 (java) S 22904 22903 4778 34817 4778 4202560 6777 0 0 0 36 0 0 0 25 0 10 0 11162927 915054592 71739 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22925] ppid=22904 vsize=893608 CPUtime=0 /proc/22917/task/22925/stat : 22925 (java) S 22904 22903 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11162927 915054592 71739 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22926] ppid=22904 vsize=893608 CPUtime=0 /proc/22917/task/22926/stat : 22926 (java) S 22904 22903 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11162927 915054592 71739 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.99 Current children cumulated vsize (KiB) 898744 [startup+4.80597 s] /proc/loadavg: 1.31 1.40 1.25 2/47 22926 /proc/meminfo: memFree=7340/1048576 swapFree=0/0 [pid=22903] ppid=22902 vsize=2568 CPUtime=0 /proc/22903/stat : 22903 (p2cudf-paranoid) S 22902 22903 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 11162918 2629632 270 1283457024 134512640 135304128 4293054528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22903/statm: 642 270 230 194 0 29 0 [pid=22904] ppid=22903 vsize=2568 CPUtime=0.02 /proc/22904/stat : 22904 (p2cudf) S 22903 22903 4778 34817 4778 4202496 389 3015 0 1 0 0 1 1 25 0 1 0 11162918 2629632 279 1283457024 134512640 135304128 4294234496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22904/statm: 642 279 239 194 0 29 0 [pid=22917] ppid=22904 vsize=892716 CPUtime=4.64 /proc/22917/stat : 22917 (java) S 22904 22903 4778 34817 4778 4202496 76484 0 1 0 431 33 0 0 25 0 11 0 11162922 914141184 71503 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22917/statm: 223179 71503 1761 10 0 220270 0 [pid=22917/tid=22918] ppid=22904 vsize=892716 CPUtime=2.21 /proc/22917/task/22918/stat : 22918 (java) S 22904 22903 4778 34817 4778 4202560 50282 0 1 0 197 24 0 0 25 0 11 0 11162922 914141184 71503 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22919] ppid=22904 vsize=892716 CPUtime=0.21 /proc/22917/task/22919/stat : 22919 (java) S 22904 22903 4778 34817 4778 4202560 10772 0 0 0 21 0 0 0 16 0 11 0 11162924 914141184 71503 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 0 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22920] ppid=22904 vsize=892716 CPUtime=0 /proc/22917/task/22920/stat : 22920 (java) S 22904 22903 4778 34817 4778 4202560 9 0 0 0 0 0 0 0 18 0 11 0 11162924 914141184 71503 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22921] ppid=22904 vsize=892716 CPUtime=0 /proc/22917/task/22921/stat : 22921 (java) S 22904 22903 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 11 0 11162924 914141184 71503 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22922] ppid=22904 vsize=892716 CPUtime=0 /proc/22917/task/22922/stat : 22922 (java) S 22904 22903 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 11162927 914141184 71503 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22923] ppid=22904 vsize=892716 CPUtime=1.83 /proc/22917/task/22923/stat : 22923 (java) S 22904 22903 4778 34817 4778 4202560 7567 0 0 0 176 7 0 0 15 0 11 0 11162927 914141184 71503 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22924] ppid=22904 vsize=892716 CPUtime=0.36 /proc/22917/task/22924/stat : 22924 (java) S 22904 22903 4778 34817 4778 4202560 6777 0 0 0 36 0 0 0 25 0 11 0 11162927 914141184 71503 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22925] ppid=22904 vsize=892716 CPUtime=0 /proc/22917/task/22925/stat : 22925 (java) S 22904 22903 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 11162927 914141184 71503 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22926] ppid=22904 vsize=892716 CPUtime=0 /proc/22917/task/22926/stat : 22926 (java) S 22904 22903 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 11 0 11162927 914141184 71503 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.66 Current children cumulated vsize (KiB) 897852 [startup+5.2149 s] /proc/loadavg: 1.31 1.40 1.25 2/47 22926 /proc/meminfo: memFree=7340/1048576 swapFree=0/0 [pid=22903] ppid=22902 vsize=2568 CPUtime=0 /proc/22903/stat : 22903 (p2cudf-paranoid) S 22902 22903 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 11162918 2629632 270 1283457024 134512640 135304128 4293054528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22903/statm: 642 270 230 194 0 29 0 [pid=22904] ppid=22903 vsize=2568 CPUtime=0.02 /proc/22904/stat : 22904 (p2cudf) S 22903 22903 4778 34817 4778 4202496 389 3015 0 1 0 0 1 1 25 0 1 0 11162918 2629632 279 1283457024 134512640 135304128 4294234496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22904/statm: 642 279 239 194 0 29 0 [pid=22917] ppid=22904 vsize=892716 CPUtime=4.66 /proc/22917/stat : 22917 (java) S 22904 22903 4778 34817 4778 4202496 76487 0 1 0 433 33 0 0 25 0 11 0 11162922 914141184 71506 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22917/statm: 223179 71506 1762 10 0 220270 0 [pid=22917/tid=22918] ppid=22904 vsize=892716 CPUtime=2.21 /proc/22917/task/22918/stat : 22918 (java) S 22904 22903 4778 34817 4778 4202560 50282 0 1 0 197 24 0 0 25 0 11 0 11162922 914141184 71506 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22919] ppid=22904 vsize=892716 CPUtime=0.21 /proc/22917/task/22919/stat : 22919 (java) S 22904 22903 4778 34817 4778 4202560 10772 0 0 0 21 0 0 0 15 0 11 0 11162924 914141184 71506 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 0 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22920] ppid=22904 vsize=892716 CPUtime=0 /proc/22917/task/22920/stat : 22920 (java) S 22904 22903 4778 34817 4778 4202560 9 0 0 0 0 0 0 0 18 0 11 0 11162924 914141184 71506 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22921] ppid=22904 vsize=892716 CPUtime=0 /proc/22917/task/22921/stat : 22921 (java) S 22904 22903 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 11 0 11162924 914141184 71506 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22922] ppid=22904 vsize=892716 CPUtime=0 /proc/22917/task/22922/stat : 22922 (java) S 22904 22903 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 11162927 914141184 71506 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22923] ppid=22904 vsize=892716 CPUtime=1.85 /proc/22917/task/22923/stat : 22923 (java) S 22904 22903 4778 34817 4778 4202560 7570 0 0 0 178 7 0 0 15 0 11 0 11162927 914141184 71506 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22924] ppid=22904 vsize=892716 CPUtime=0.36 /proc/22917/task/22924/stat : 22924 (java) S 22904 22903 4778 34817 4778 4202560 6777 0 0 0 36 0 0 0 25 0 11 0 11162927 914141184 71506 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22925] ppid=22904 vsize=892716 CPUtime=0 /proc/22917/task/22925/stat : 22925 (java) S 22904 22903 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 11162927 914141184 71506 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 [pid=22917/tid=22926] ppid=22904 vsize=892716 CPUtime=0 /proc/22917/task/22926/stat : 22926 (java) S 22904 22903 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 11 0 11162927 914141184 71506 1283457024 134512640 134550932 4288053808 18446744073709551615 4294960130 0 4 0 16800975 18446612133155120448 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.68 Current children cumulated vsize (KiB) 897852 [startup+5.30419 s] /proc/loadavg: 1.31 1.40 1.25 2/47 22926 /proc/meminfo: memFree=7340/1048576 swapFree=0/0 [pid=22903] ppid=22902 vsize=2568 CPUtime=0 /proc/22903/stat : 22903 (p2cudf-paranoid) S 22902 22903 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 11162918 2629632 270 1283457024 134512640 135304128 4293054528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22903/statm: 642 270 230 194 0 29 0 [pid=22904] ppid=22903 vsize=2568 CPUtime=0.02 /proc/22904/stat : 22904 (p2cudf) S 22903 22903 4778 34817 4778 4202496 389 3015 0 1 0 0 1 1 25 0 1 0 11162918 2629632 279 1283457024 134512640 135304128 4294234496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22904/statm: 642 279 239 194 0 29 0 [pid=22917] ppid=22904 vsize=0 CPUtime=4.72 /proc/22917/stat : 22917 (java) Z 22904 22903 4778 34817 4778 4203532 76493 0 1 0 437 35 0 0 18 0 2 0 11162922 0 0 1283457024 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22917/statm: 0 0 0 0 0 0 0 [pid=22917/tid=22918] ppid=22904 vsize=0 CPUtime=2.22 /proc/22917/task/22918/stat : 22918 (java) R 22904 22903 4778 34817 4778 4203588 50282 0 1 0 197 25 0 0 19 0 2 0 11162922 0 0 1283457024 0 0 0 0 0 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.74 Current children cumulated vsize (KiB) 5136 Child status: 0 Real time (s): 5.31739 CPU time (s): 4.7683 CPU user time (s): 4.38827 CPU system time (s): 0.380023 CPU usage (%): 89.6736 Max. virtual memory (cumulated for all children) (KiB): 899752 getrusage(RUSAGE_CHILDREN,...) data: user time used= 4.38827 system time used= 0.380023 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 80291 page faults= 2 swaps= 0 block input operations= 0 block output operations= 0 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 535 involuntary context switches= 505 runsolver used 0 second user time and 0 second system time The end