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/ec32fc68-7254-11e0-8436-00163e1e087d.cudf.dudf-real.log.runsolver ./p2cudf-paranoid-1.13 /home/misc2010/data/2011/dudf-real//ec32fc68-7254-11e0-8436-00163e1e087d.cudf /home/misc2010/tmp/201108251442/p2cudf-paranoid-1.13/ec32fc68-7254-11e0-8436-00163e1e087d.cudf.dudf-real.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.47 1.44 1.36 5/35 30530 /proc/meminfo: memFree=275116/1048576 swapFree=0/0 [pid=30528] ppid=30527 vsize=2568 CPUtime=0 /proc/30528/stat : 30528 (p2cudf-paranoid) S 30527 30528 4778 34817 4778 4202496 372 0 0 0 0 0 0 0 18 0 1 0 11264555 2629632 269 1283457024 134512640 135304128 4290682320 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30528/statm: 642 269 230 194 0 29 0 [pid=30529] ppid=30528 vsize=2568 CPUtime=0 /proc/30529/stat : 30529 (p2cudf) R 30528 30528 4778 34817 4778 4202496 345 0 0 0 0 0 0 0 25 0 1 0 11264555 2629632 271 1283457024 134512640 135304128 4287097472 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/30529/statm: 642 271 231 194 0 29 0 [pid=30530] ppid=30529 vsize=2568 CPUtime=0 /proc/30530/stat : 30530 (p2cudf) R 30529 30528 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 1 0 11264555 2629632 40 1283457024 134512640 135304128 4287097472 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/30530/statm: 642 40 0 194 0 29 0 [startup+0.115952 s] /proc/loadavg: 1.47 1.44 1.36 5/35 30530 /proc/meminfo: memFree=275116/1048576 swapFree=0/0 [pid=30528] ppid=30527 vsize=2568 CPUtime=0 /proc/30528/stat : 30528 (p2cudf-paranoid) S 30527 30528 4778 34817 4778 4202496 372 0 0 0 0 0 0 0 18 0 1 0 11264555 2629632 269 1283457024 134512640 135304128 4290682320 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30528/statm: 642 269 230 194 0 29 0 [pid=30529] ppid=30528 vsize=2572 CPUtime=0.02 /proc/30529/stat : 30529 (p2cudf) S 30528 30528 4778 34817 4778 4202496 389 3018 0 1 0 0 2 0 25 0 1 0 11264555 2633728 280 1283457024 134512640 135304128 4287097472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30529/statm: 643 280 239 194 0 30 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5140 [startup+0.20596 s] /proc/loadavg: 1.47 1.44 1.36 5/35 30530 /proc/meminfo: memFree=275116/1048576 swapFree=0/0 [pid=30528] ppid=30527 vsize=2568 CPUtime=0 /proc/30528/stat : 30528 (p2cudf-paranoid) S 30527 30528 4778 34817 4778 4202496 372 0 0 0 0 0 0 0 18 0 1 0 11264555 2629632 269 1283457024 134512640 135304128 4290682320 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30528/statm: 642 269 230 194 0 29 0 [pid=30529] ppid=30528 vsize=2572 CPUtime=0.02 /proc/30529/stat : 30529 (p2cudf) S 30528 30528 4778 34817 4778 4202496 389 3018 0 1 0 0 2 0 25 0 1 0 11264555 2633728 280 1283457024 134512640 135304128 4287097472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30529/statm: 643 280 239 194 0 30 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5140 [startup+0.305976 s] /proc/loadavg: 1.47 1.44 1.36 5/35 30530 /proc/meminfo: memFree=275116/1048576 swapFree=0/0 [pid=30528] ppid=30527 vsize=2568 CPUtime=0 /proc/30528/stat : 30528 (p2cudf-paranoid) S 30527 30528 4778 34817 4778 4202496 372 0 0 0 0 0 0 0 18 0 1 0 11264555 2629632 269 1283457024 134512640 135304128 4290682320 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30528/statm: 642 269 230 194 0 29 0 [pid=30529] ppid=30528 vsize=2572 CPUtime=0.02 /proc/30529/stat : 30529 (p2cudf) S 30528 30528 4778 34817 4778 4202496 389 3018 0 1 0 0 2 0 25 0 1 0 11264555 2633728 280 1283457024 134512640 135304128 4287097472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30529/statm: 643 280 239 194 0 30 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5140 [startup+0.706087 s] /proc/loadavg: 1.47 1.44 1.36 5/35 30530 /proc/meminfo: memFree=275116/1048576 swapFree=0/0 [pid=30528] ppid=30527 vsize=2568 CPUtime=0 /proc/30528/stat : 30528 (p2cudf-paranoid) S 30527 30528 4778 34817 4778 4202496 372 0 0 0 0 0 0 0 18 0 1 0 11264555 2629632 269 1283457024 134512640 135304128 4290682320 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30528/statm: 642 269 230 194 0 29 0 [pid=30529] ppid=30528 vsize=2572 CPUtime=0.02 /proc/30529/stat : 30529 (p2cudf) S 30528 30528 4778 34817 4778 4202496 389 3018 0 1 0 0 2 0 25 0 1 0 11264555 2633728 280 1283457024 134512640 135304128 4287097472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30529/statm: 643 280 239 194 0 30 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5140 [startup+1.50628 s] /proc/loadavg: 1.47 1.44 1.36 3/45 30551 /proc/meminfo: memFree=222140/1048576 swapFree=0/0 [pid=30528] ppid=30527 vsize=2568 CPUtime=0 /proc/30528/stat : 30528 (p2cudf-paranoid) S 30527 30528 4778 34817 4778 4202496 372 0 0 0 0 0 0 0 18 0 1 0 11264555 2629632 269 1283457024 134512640 135304128 4290682320 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30528/statm: 642 269 230 194 0 29 0 [pid=30529] ppid=30528 vsize=2572 CPUtime=0.02 /proc/30529/stat : 30529 (p2cudf) S 30528 30528 4778 34817 4778 4202496 389 3018 0 1 0 0 2 0 25 0 1 0 11264555 2633728 280 1283457024 134512640 135304128 4287097472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30529/statm: 643 280 239 194 0 30 0 [pid=30542] ppid=30529 vsize=884604 CPUtime=1.47 /proc/30542/stat : 30542 (java) S 30529 30528 4778 34817 4778 4202496 22206 0 1 0 134 13 0 0 25 0 10 0 11264558 905834496 18785 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/30542/statm: 221151 18785 1721 10 0 218242 0 [pid=30542/tid=30543] ppid=30529 vsize=884604 CPUtime=0.8 /proc/30542/task/30543/stat : 30543 (java) R 30529 30528 4778 34817 4778 4202560 10789 0 1 0 73 7 0 0 25 0 10 0 11264559 905834496 18785 1283457024 134512640 134550932 4292226560 18446744073709551615 4148861123 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30542/tid=30544] ppid=30529 vsize=884604 CPUtime=0 /proc/30542/task/30544/stat : 30544 (java) S 30529 30528 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 10 0 11264560 905834496 18785 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 0 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30545] ppid=30529 vsize=884604 CPUtime=0 /proc/30542/task/30545/stat : 30545 (java) S 30529 30528 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 25 0 10 0 11264561 905834496 18785 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30546] ppid=30529 vsize=884604 CPUtime=0 /proc/30542/task/30546/stat : 30546 (java) S 30529 30528 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 11264561 905834496 18785 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30547] ppid=30529 vsize=884604 CPUtime=0 /proc/30542/task/30547/stat : 30547 (java) S 30529 30528 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11264563 905834496 18785 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30548] ppid=30529 vsize=884604 CPUtime=0.44 /proc/30542/task/30548/stat : 30548 (java) R 30529 30528 4778 34817 4778 4202560 8468 0 0 0 40 4 0 0 25 0 10 0 11264563 905834496 18785 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30542/tid=30549] ppid=30529 vsize=884604 CPUtime=0.2 /proc/30542/task/30549/stat : 30549 (java) R 30529 30528 4778 34817 4778 4202560 1872 0 0 0 20 0 0 0 18 0 10 0 11264563 905834496 18785 1283457024 134512640 134550932 4292226560 18446744073709551615 4151455812 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30542/tid=30550] ppid=30529 vsize=884604 CPUtime=0 /proc/30542/task/30550/stat : 30550 (java) S 30529 30528 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 11264563 905834496 18785 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30551] ppid=30529 vsize=884604 CPUtime=0 /proc/30542/task/30551/stat : 30551 (java) S 30529 30528 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11264563 905834496 18785 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.49 Current children cumulated vsize (KiB) 889744 [startup+3.10668 s] /proc/loadavg: 1.59 1.47 1.37 3/45 30551 /proc/meminfo: memFree=129636/1048576 swapFree=0/0 [pid=30528] ppid=30527 vsize=2568 CPUtime=0 /proc/30528/stat : 30528 (p2cudf-paranoid) S 30527 30528 4778 34817 4778 4202496 372 0 0 0 0 0 0 0 18 0 1 0 11264555 2629632 269 1283457024 134512640 135304128 4290682320 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30528/statm: 642 269 230 194 0 29 0 [pid=30529] ppid=30528 vsize=2572 CPUtime=0.02 /proc/30529/stat : 30529 (p2cudf) S 30528 30528 4778 34817 4778 4202496 389 3018 0 1 0 0 2 0 25 0 1 0 11264555 2633728 280 1283457024 134512640 135304128 4287097472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30529/statm: 643 280 239 194 0 30 0 [pid=30542] ppid=30529 vsize=884504 CPUtime=3.07 /proc/30542/stat : 30542 (java) S 30529 30528 4778 34817 4778 4202496 58859 0 1 0 279 28 0 0 25 0 10 0 11264558 905732096 53712 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/30542/statm: 221126 53712 1744 10 0 218217 0 [pid=30542/tid=30543] ppid=30529 vsize=884504 CPUtime=1.53 /proc/30542/task/30543/stat : 30543 (java) R 30529 30528 4778 34817 4778 4202560 45383 0 1 0 131 22 0 0 25 0 10 0 11264559 905732096 53712 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30542/tid=30544] ppid=30529 vsize=884504 CPUtime=0 /proc/30542/task/30544/stat : 30544 (java) S 30529 30528 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 10 0 11264560 905732096 53712 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 0 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30545] ppid=30529 vsize=884504 CPUtime=0 /proc/30542/task/30545/stat : 30545 (java) S 30529 30528 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 25 0 10 0 11264561 905732096 53712 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30546] ppid=30529 vsize=884504 CPUtime=0 /proc/30542/task/30546/stat : 30546 (java) S 30529 30528 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 11264561 905732096 53712 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30547] ppid=30529 vsize=884504 CPUtime=0 /proc/30542/task/30547/stat : 30547 (java) S 30529 30528 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11264563 905732096 53712 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30548] ppid=30529 vsize=884504 CPUtime=0.79 /proc/30542/task/30548/stat : 30548 (java) R 30529 30528 4778 34817 4778 4202560 8541 0 0 0 75 4 0 0 24 0 10 0 11264563 905732096 53712 1283457024 134512640 134550932 4292226560 18446744073709551615 4152074068 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30542/tid=30549] ppid=30529 vsize=884504 CPUtime=0.72 /proc/30542/task/30549/stat : 30549 (java) R 30529 30528 4778 34817 4778 4202560 3858 0 0 0 72 0 0 0 16 0 10 0 11264563 905732096 53712 1283457024 134512640 134550932 4292226560 18446744073709551615 4149843612 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30542/tid=30550] ppid=30529 vsize=884504 CPUtime=0 /proc/30542/task/30550/stat : 30550 (java) S 30529 30528 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 11264563 905732096 53712 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30551] ppid=30529 vsize=884504 CPUtime=0 /proc/30542/task/30551/stat : 30551 (java) S 30529 30528 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11264563 905732096 53712 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.09 Current children cumulated vsize (KiB) 889644 Solver just ended. Dumping a history of the last processes samples [startup+3.2067 s] /proc/loadavg: 1.59 1.47 1.37 3/45 30551 /proc/meminfo: memFree=129636/1048576 swapFree=0/0 [pid=30528] ppid=30527 vsize=2568 CPUtime=0 /proc/30528/stat : 30528 (p2cudf-paranoid) S 30527 30528 4778 34817 4778 4202496 372 0 0 0 0 0 0 0 18 0 1 0 11264555 2629632 269 1283457024 134512640 135304128 4290682320 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30528/statm: 642 269 230 194 0 29 0 [pid=30529] ppid=30528 vsize=2572 CPUtime=0.02 /proc/30529/stat : 30529 (p2cudf) S 30528 30528 4778 34817 4778 4202496 389 3018 0 1 0 0 2 0 25 0 1 0 11264555 2633728 280 1283457024 134512640 135304128 4287097472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30529/statm: 643 280 239 194 0 30 0 [pid=30542] ppid=30529 vsize=881904 CPUtime=3.17 /proc/30542/stat : 30542 (java) S 30529 30528 4778 34817 4778 4202496 59478 0 1 0 288 29 0 0 25 0 10 0 11264558 903069696 53740 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/30542/statm: 220476 53740 1744 10 0 217567 0 [pid=30542/tid=30543] ppid=30529 vsize=881904 CPUtime=1.55 /proc/30542/task/30543/stat : 30543 (java) R 30529 30528 4778 34817 4778 4202560 45797 0 1 0 133 22 0 0 25 0 10 0 11264559 903069696 53740 1283457024 134512640 134550932 4292226560 18446744073709551615 4095608971 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30542/tid=30544] ppid=30529 vsize=881904 CPUtime=0 /proc/30542/task/30544/stat : 30544 (java) S 30529 30528 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 15 0 10 0 11264560 903069696 53740 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 0 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30545] ppid=30529 vsize=881904 CPUtime=0 /proc/30542/task/30545/stat : 30545 (java) S 30529 30528 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 25 0 10 0 11264561 903069696 53740 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30546] ppid=30529 vsize=881904 CPUtime=0 /proc/30542/task/30546/stat : 30546 (java) S 30529 30528 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 11264561 903069696 53740 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30547] ppid=30529 vsize=881904 CPUtime=0 /proc/30542/task/30547/stat : 30547 (java) S 30529 30528 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11264563 903069696 53740 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30548] ppid=30529 vsize=881904 CPUtime=0.79 /proc/30542/task/30548/stat : 30548 (java) R 30529 30528 4778 34817 4778 4202560 8541 0 0 0 75 4 0 0 24 0 10 0 11264563 903069696 53740 1283457024 134512640 134550932 4292226560 18446744073709551615 4152074068 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30542/tid=30549] ppid=30529 vsize=881904 CPUtime=0.81 /proc/30542/task/30549/stat : 30549 (java) S 30529 30528 4778 34817 4778 4202560 4063 0 0 0 80 1 0 0 16 0 10 0 11264563 903069696 53740 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30550] ppid=30529 vsize=881904 CPUtime=0 /proc/30542/task/30550/stat : 30550 (java) S 30529 30528 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 11264563 903069696 53740 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30551] ppid=30529 vsize=881904 CPUtime=0 /proc/30542/task/30551/stat : 30551 (java) S 30529 30528 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11264563 903069696 53740 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.19 Current children cumulated vsize (KiB) 887044 [startup+4.00691 s] /proc/loadavg: 1.59 1.47 1.37 2/45 30551 /proc/meminfo: memFree=62428/1048576 swapFree=0/0 [pid=30528] ppid=30527 vsize=2568 CPUtime=0 /proc/30528/stat : 30528 (p2cudf-paranoid) S 30527 30528 4778 34817 4778 4202496 372 0 0 0 0 0 0 0 18 0 1 0 11264555 2629632 269 1283457024 134512640 135304128 4290682320 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30528/statm: 642 269 230 194 0 29 0 [pid=30529] ppid=30528 vsize=2572 CPUtime=0.02 /proc/30529/stat : 30529 (p2cudf) S 30528 30528 4778 34817 4778 4202496 389 3018 0 1 0 0 2 0 25 0 1 0 11264555 2633728 280 1283457024 134512640 135304128 4287097472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30529/statm: 643 280 239 194 0 30 0 [pid=30542] ppid=30529 vsize=881904 CPUtime=3.98 /proc/30542/stat : 30542 (java) S 30529 30528 4778 34817 4778 4202496 75206 0 1 0 362 36 0 0 25 0 10 0 11264558 903069696 69468 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/30542/statm: 220476 69468 1761 10 0 217567 0 [pid=30542/tid=30543] ppid=30529 vsize=881904 CPUtime=1.87 /proc/30542/task/30543/stat : 30543 (java) R 30529 30528 4778 34817 4778 4202560 50254 0 1 0 163 24 0 0 25 0 10 0 11264559 903069696 69468 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30542/tid=30544] ppid=30529 vsize=881904 CPUtime=0.26 /proc/30542/task/30544/stat : 30544 (java) S 30529 30528 4778 34817 4778 4202560 11240 0 0 0 22 4 0 0 16 0 10 0 11264560 903069696 69468 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 0 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30545] ppid=30529 vsize=881904 CPUtime=0 /proc/30542/task/30545/stat : 30545 (java) S 30529 30528 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 18 0 10 0 11264561 903069696 69468 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30546] ppid=30529 vsize=881904 CPUtime=0 /proc/30542/task/30546/stat : 30546 (java) S 30529 30528 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 10 0 11264561 903069696 69468 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30547] ppid=30529 vsize=881904 CPUtime=0 /proc/30542/task/30547/stat : 30547 (java) S 30529 30528 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11264563 903069696 69468 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30548] ppid=30529 vsize=881904 CPUtime=1 /proc/30542/task/30548/stat : 30548 (java) R 30529 30528 4778 34817 4778 4202560 8576 0 0 0 96 4 0 0 20 0 10 0 11264563 903069696 69468 1283457024 134512640 134550932 4292226560 18446744073709551615 4149612580 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30542/tid=30549] ppid=30529 vsize=881904 CPUtime=0.81 /proc/30542/task/30549/stat : 30549 (java) S 30529 30528 4778 34817 4778 4202560 4063 0 0 0 80 1 0 0 16 0 10 0 11264563 903069696 69468 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30550] ppid=30529 vsize=881904 CPUtime=0 /proc/30542/task/30550/stat : 30550 (java) S 30529 30528 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 11264563 903069696 69468 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30551] ppid=30529 vsize=881904 CPUtime=0 /proc/30542/task/30551/stat : 30551 (java) S 30529 30528 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11264563 903069696 69468 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4 Current children cumulated vsize (KiB) 887044 [startup+4.80621 s] /proc/loadavg: 1.59 1.47 1.37 3/46 30552 /proc/meminfo: memFree=5668/1048576 swapFree=0/0 [pid=30528] ppid=30527 vsize=2568 CPUtime=0 /proc/30528/stat : 30528 (p2cudf-paranoid) S 30527 30528 4778 34817 4778 4202496 372 0 0 0 0 0 0 0 18 0 1 0 11264555 2629632 269 1283457024 134512640 135304128 4290682320 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30528/statm: 642 269 230 194 0 29 0 [pid=30529] ppid=30528 vsize=2572 CPUtime=0.02 /proc/30529/stat : 30529 (p2cudf) S 30528 30528 4778 34817 4778 4202496 389 3018 0 1 0 0 2 0 25 0 1 0 11264555 2633728 280 1283457024 134512640 135304128 4287097472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30529/statm: 643 280 239 194 0 30 0 [pid=30542] ppid=30529 vsize=882552 CPUtime=4.44 /proc/30542/stat : 30542 (java) S 30529 30528 4778 34817 4778 4202496 75290 0 1 0 408 36 0 0 25 0 11 0 11264558 903733248 69552 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/30542/statm: 220638 69552 1774 10 0 217729 0 [pid=30542/tid=30543] ppid=30529 vsize=882552 CPUtime=2.01 /proc/30542/task/30543/stat : 30543 (java) S 30529 30528 4778 34817 4778 4202560 50287 0 1 0 177 24 0 0 25 0 11 0 11264559 903733248 69552 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30544] ppid=30529 vsize=882552 CPUtime=0.26 /proc/30542/task/30544/stat : 30544 (java) S 30529 30528 4778 34817 4778 4202560 11240 0 0 0 22 4 0 0 15 0 11 0 11264560 903733248 69552 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 0 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30545] ppid=30529 vsize=882552 CPUtime=0 /proc/30542/task/30545/stat : 30545 (java) S 30529 30528 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 18 0 11 0 11264561 903733248 69552 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30546] ppid=30529 vsize=882552 CPUtime=0 /proc/30542/task/30546/stat : 30546 (java) S 30529 30528 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 11 0 11264561 903733248 69552 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30547] ppid=30529 vsize=882552 CPUtime=0 /proc/30542/task/30547/stat : 30547 (java) S 30529 30528 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 11264563 903733248 69552 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30548] ppid=30529 vsize=882552 CPUtime=1.1 /proc/30542/task/30548/stat : 30548 (java) S 30529 30528 4778 34817 4778 4202560 8593 0 0 0 106 4 0 0 20 0 11 0 11264563 903733248 69552 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30549] ppid=30529 vsize=882552 CPUtime=1.02 /proc/30542/task/30549/stat : 30549 (java) S 30529 30528 4778 34817 4778 4202560 4087 0 0 0 101 1 0 0 16 0 11 0 11264563 903733248 69552 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30550] ppid=30529 vsize=882552 CPUtime=0 /proc/30542/task/30550/stat : 30550 (java) S 30529 30528 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 11 0 11264563 903733248 69552 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30551] ppid=30529 vsize=882552 CPUtime=0 /proc/30542/task/30551/stat : 30551 (java) S 30529 30528 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 11 0 11264563 903733248 69552 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.46 Current children cumulated vsize (KiB) 887692 [startup+5.0102 s] /proc/loadavg: 1.59 1.47 1.37 3/46 30552 /proc/meminfo: memFree=5668/1048576 swapFree=0/0 [pid=30528] ppid=30527 vsize=2568 CPUtime=0 /proc/30528/stat : 30528 (p2cudf-paranoid) S 30527 30528 4778 34817 4778 4202496 372 0 0 0 0 0 0 0 18 0 1 0 11264555 2629632 269 1283457024 134512640 135304128 4290682320 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30528/statm: 642 269 230 194 0 29 0 [pid=30529] ppid=30528 vsize=2572 CPUtime=0.02 /proc/30529/stat : 30529 (p2cudf) S 30528 30528 4778 34817 4778 4202496 389 3018 0 1 0 0 2 0 25 0 1 0 11264555 2633728 280 1283457024 134512640 135304128 4287097472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30529/statm: 643 280 239 194 0 30 0 [pid=30542] ppid=30529 vsize=882552 CPUtime=4.48 /proc/30542/stat : 30542 (java) S 30529 30528 4778 34817 4778 4202496 75292 0 1 0 412 36 0 0 25 0 11 0 11264558 903733248 69554 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/30542/statm: 220638 69554 1774 10 0 217729 0 [pid=30542/tid=30543] ppid=30529 vsize=882552 CPUtime=2.01 /proc/30542/task/30543/stat : 30543 (java) S 30529 30528 4778 34817 4778 4202560 50287 0 1 0 177 24 0 0 25 0 11 0 11264559 903733248 69554 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30544] ppid=30529 vsize=882552 CPUtime=0.26 /proc/30542/task/30544/stat : 30544 (java) S 30529 30528 4778 34817 4778 4202560 11240 0 0 0 22 4 0 0 16 0 11 0 11264560 903733248 69554 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 0 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30545] ppid=30529 vsize=882552 CPUtime=0 /proc/30542/task/30545/stat : 30545 (java) S 30529 30528 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 18 0 11 0 11264561 903733248 69554 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30546] ppid=30529 vsize=882552 CPUtime=0 /proc/30542/task/30546/stat : 30546 (java) S 30529 30528 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 11 0 11264561 903733248 69554 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30547] ppid=30529 vsize=882552 CPUtime=0 /proc/30542/task/30547/stat : 30547 (java) S 30529 30528 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 11264563 903733248 69554 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30548] ppid=30529 vsize=882552 CPUtime=1.12 /proc/30542/task/30548/stat : 30548 (java) S 30529 30528 4778 34817 4778 4202560 8595 0 0 0 108 4 0 0 15 0 11 0 11264563 903733248 69554 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30549] ppid=30529 vsize=882552 CPUtime=1.02 /proc/30542/task/30549/stat : 30549 (java) S 30529 30528 4778 34817 4778 4202560 4087 0 0 0 101 1 0 0 16 0 11 0 11264563 903733248 69554 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30550] ppid=30529 vsize=882552 CPUtime=0 /proc/30542/task/30550/stat : 30550 (java) S 30529 30528 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 11 0 11264563 903733248 69554 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30551] ppid=30529 vsize=882552 CPUtime=0 /proc/30542/task/30551/stat : 30551 (java) S 30529 30528 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 11 0 11264563 903733248 69554 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.5 Current children cumulated vsize (KiB) 887692 [startup+5.11023 s] /proc/loadavg: 1.59 1.47 1.37 3/46 30552 /proc/meminfo: memFree=5668/1048576 swapFree=0/0 [pid=30528] ppid=30527 vsize=2568 CPUtime=0 /proc/30528/stat : 30528 (p2cudf-paranoid) S 30527 30528 4778 34817 4778 4202496 372 0 0 0 0 0 0 0 18 0 1 0 11264555 2629632 269 1283457024 134512640 135304128 4290682320 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30528/statm: 642 269 230 194 0 29 0 [pid=30529] ppid=30528 vsize=2572 CPUtime=0.02 /proc/30529/stat : 30529 (p2cudf) S 30528 30528 4778 34817 4778 4202496 389 3018 0 1 0 0 2 0 25 0 1 0 11264555 2633728 280 1283457024 134512640 135304128 4287097472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30529/statm: 643 280 239 194 0 30 0 [pid=30542] ppid=30529 vsize=882552 CPUtime=4.58 /proc/30542/stat : 30542 (java) S 30529 30528 4778 34817 4778 4202496 75295 0 1 0 421 37 0 0 25 0 11 0 11264558 903733248 69557 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/30542/statm: 220638 69557 1774 10 0 217729 0 [pid=30542/tid=30543] ppid=30529 vsize=882552 CPUtime=2.01 /proc/30542/task/30543/stat : 30543 (java) S 30529 30528 4778 34817 4778 4202560 50287 0 1 0 177 24 0 0 25 0 11 0 11264559 903733248 69557 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30544] ppid=30529 vsize=882552 CPUtime=0.26 /proc/30542/task/30544/stat : 30544 (java) S 30529 30528 4778 34817 4778 4202560 11240 0 0 0 22 4 0 0 16 0 11 0 11264560 903733248 69557 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 0 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30545] ppid=30529 vsize=882552 CPUtime=0 /proc/30542/task/30545/stat : 30545 (java) S 30529 30528 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 18 0 11 0 11264561 903733248 69557 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30546] ppid=30529 vsize=882552 CPUtime=0 /proc/30542/task/30546/stat : 30546 (java) S 30529 30528 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 11 0 11264561 903733248 69557 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30547] ppid=30529 vsize=882552 CPUtime=0 /proc/30542/task/30547/stat : 30547 (java) S 30529 30528 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 11264563 903733248 69557 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30548] ppid=30529 vsize=882552 CPUtime=1.17 /proc/30542/task/30548/stat : 30548 (java) R 30529 30528 4778 34817 4778 4202560 8597 0 0 0 113 4 0 0 16 0 11 0 11264563 903733248 69557 1283457024 134512640 134550932 4292226560 18446744073709551615 4149615366 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30542/tid=30549] ppid=30529 vsize=882552 CPUtime=1.02 /proc/30542/task/30549/stat : 30549 (java) S 30529 30528 4778 34817 4778 4202560 4087 0 0 0 101 1 0 0 16 0 11 0 11264563 903733248 69557 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30550] ppid=30529 vsize=882552 CPUtime=0 /proc/30542/task/30550/stat : 30550 (java) S 30529 30528 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 11 0 11264563 903733248 69557 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30542/tid=30551] ppid=30529 vsize=882552 CPUtime=0 /proc/30542/task/30551/stat : 30551 (java) S 30529 30528 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 11 0 11264563 903733248 69557 1283457024 134512640 134550932 4292226560 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.6 Current children cumulated vsize (KiB) 887692 Child status: 0 Real time (s): 5.19687 CPU time (s): 4.65229 CPU user time (s): 4.24027 CPU system time (s): 0.412025 CPU usage (%): 89.521 Max. virtual memory (cumulated for all children) (KiB): 891144 getrusage(RUSAGE_CHILDREN,...) data: user time used= 4.24026 system time used= 0.412025 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 79103 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= 668 involuntary context switches= 660 runsolver used 0 second user time and 0 second system time The end