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/201108241238/gj-trendy-solver-1.0/d1583bd8-d489-11df-9a24-00163e3d3b7c.cudf.dudf-real.log.runsolver ./gj-trendy-solver-1.0 /home/misc2010/data/2011/dudf-real//d1583bd8-d489-11df-9a24-00163e3d3b7c.cudf /home/misc2010/tmp/201108241238/gj-trendy-solver-1.0/d1583bd8-d489-11df-9a24-00163e3d3b7c.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.12 1.10 1.09 3/35 32111 /proc/meminfo: memFree=342792/1048576 swapFree=0/0 [pid=32110] ppid=32109 vsize=2564 CPUtime=0 /proc/32110/stat : 32110 (gj-trendy-solve) R 32109 32110 1511 34817 1511 4202496 354 0 0 0 0 0 0 0 25 0 1 0 5115611 2625536 268 1283457024 134512640 135304128 4293441648 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/32110/statm: 641 268 228 194 0 28 0 [pid=32111] ppid=32110 vsize=2564 CPUtime=0 /proc/32111/stat : 32111 (gj-trendy-solve) R 32110 32110 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 1 0 5115612 2625536 40 1283457024 134512640 135304128 4293441648 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/32111/statm: 641 40 0 194 0 28 0 [startup+0.145275 s] /proc/loadavg: 1.12 1.10 1.09 3/35 32111 /proc/meminfo: memFree=342792/1048576 swapFree=0/0 [pid=32110] ppid=32109 vsize=2564 CPUtime=0 /proc/32110/stat : 32110 (gj-trendy-solve) S 32109 32110 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 5115611 2625536 269 1283457024 134512640 135304128 4293441648 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32110/statm: 641 269 229 194 0 28 0 [pid=32111] ppid=32110 vsize=409800 CPUtime=0.14 /proc/32111/stat : 32111 (java) S 32110 32110 1511 34817 1511 4202496 3945 0 1 0 12 2 0 0 25 0 9 0 5115612 419635200 3372 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/32111/statm: 102450 3372 1922 10 0 96598 0 Current children cumulated CPU time (s) 0.14 Current children cumulated vsize (KiB) 412364 [startup+0.205296 s] /proc/loadavg: 1.12 1.10 1.09 3/35 32111 /proc/meminfo: memFree=342792/1048576 swapFree=0/0 [pid=32110] ppid=32109 vsize=2564 CPUtime=0 /proc/32110/stat : 32110 (gj-trendy-solve) S 32109 32110 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 5115611 2625536 269 1283457024 134512640 135304128 4293441648 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32110/statm: 641 269 229 194 0 28 0 [pid=32111] ppid=32110 vsize=409800 CPUtime=0.2 /proc/32111/stat : 32111 (java) S 32110 32110 1511 34817 1511 4202496 4081 0 1 0 18 2 0 0 25 0 9 0 5115612 419635200 3508 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/32111/statm: 102450 3508 1922 10 0 96598 0 Current children cumulated CPU time (s) 0.2 Current children cumulated vsize (KiB) 412364 [startup+0.305327 s] /proc/loadavg: 1.12 1.10 1.09 3/35 32111 /proc/meminfo: memFree=342792/1048576 swapFree=0/0 [pid=32110] ppid=32109 vsize=2564 CPUtime=0 /proc/32110/stat : 32110 (gj-trendy-solve) S 32109 32110 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 5115611 2625536 269 1283457024 134512640 135304128 4293441648 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32110/statm: 641 269 229 194 0 28 0 [pid=32111] ppid=32110 vsize=410384 CPUtime=0.3 /proc/32111/stat : 32111 (java) S 32110 32110 1511 34817 1511 4202496 4426 0 1 0 28 2 0 0 25 0 9 0 5115612 420233216 3853 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/32111/statm: 102596 3853 1944 10 0 96744 0 Current children cumulated CPU time (s) 0.3 Current children cumulated vsize (KiB) 412948 [startup+0.705477 s] /proc/loadavg: 1.12 1.10 1.09 3/35 32111 /proc/meminfo: memFree=342792/1048576 swapFree=0/0 [pid=32110] ppid=32109 vsize=2564 CPUtime=0 /proc/32110/stat : 32110 (gj-trendy-solve) S 32109 32110 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 5115611 2625536 269 1283457024 134512640 135304128 4293441648 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32110/statm: 641 269 229 194 0 28 0 [pid=32111] ppid=32110 vsize=410652 CPUtime=0.7 /proc/32111/stat : 32111 (java) S 32110 32110 1511 34817 1511 4202496 11786 0 1 0 64 6 0 0 25 0 9 0 5115612 420507648 11026 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/32111/statm: 102663 11026 2901 10 0 96811 0 Current children cumulated CPU time (s) 0.7 Current children cumulated vsize (KiB) 413216 [startup+1.50572 s] /proc/loadavg: 1.12 1.10 1.09 2/44 32120 /proc/meminfo: memFree=283252/1048576 swapFree=0/0 [pid=32110] ppid=32109 vsize=2564 CPUtime=0 /proc/32110/stat : 32110 (gj-trendy-solve) S 32109 32110 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 5115611 2625536 269 1283457024 134512640 135304128 4293441648 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32110/statm: 641 269 229 194 0 28 0 [pid=32111] ppid=32110 vsize=410784 CPUtime=1.49 /proc/32111/stat : 32111 (java) S 32110 32110 1511 34817 1511 4202496 25766 0 1 0 137 12 0 0 25 0 9 0 5115612 420642816 25006 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/32111/statm: 102696 25006 2901 10 0 96844 0 [pid=32111/tid=32113] ppid=32110 vsize=410784 CPUtime=0.85 /proc/32111/task/32113/stat : 32113 (java) R 32110 32110 1511 34817 1511 4202560 6818 0 1 0 81 4 0 0 25 0 9 0 5115612 420642816 25006 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=32111/tid=32114] ppid=32110 vsize=410784 CPUtime=0.6 /proc/32111/task/32114/stat : 32114 (java) R 32110 32110 1511 34817 1511 4202560 17560 0 0 0 52 8 0 0 18 0 9 0 5115613 420642816 25006 1283457024 134512640 134550932 4292783872 18446744073709551615 4151493536 0 0 0 16800975 0 0 0 -1 0 0 0 0 [pid=32111/tid=32115] ppid=32110 vsize=410784 CPUtime=0 /proc/32111/task/32115/stat : 32115 (java) S 32110 32110 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 20 0 9 0 5115613 420642816 25006 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32116] ppid=32110 vsize=410784 CPUtime=0 /proc/32111/task/32116/stat : 32116 (java) S 32110 32110 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 20 0 9 0 5115613 420642816 25006 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32117] ppid=32110 vsize=410784 CPUtime=0 /proc/32111/task/32117/stat : 32117 (java) S 32110 32110 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 5115614 420642816 25006 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32118] ppid=32110 vsize=410784 CPUtime=0.03 /proc/32111/task/32118/stat : 32118 (java) S 32110 32110 1511 34817 1511 4202560 447 0 0 0 3 0 0 0 15 0 9 0 5115614 420642816 25006 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32119] ppid=32110 vsize=410784 CPUtime=0 /proc/32111/task/32119/stat : 32119 (java) S 32110 32110 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 5115614 420642816 25006 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32120] ppid=32110 vsize=410784 CPUtime=0 /proc/32111/task/32120/stat : 32120 (java) S 32110 32110 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 9 0 5115614 420642816 25006 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.49 Current children cumulated vsize (KiB) 413348 [startup+3.10619 s] /proc/loadavg: 1.19 1.12 1.09 2/44 32120 /proc/meminfo: memFree=208604/1048576 swapFree=0/0 [pid=32110] ppid=32109 vsize=2564 CPUtime=0 /proc/32110/stat : 32110 (gj-trendy-solve) S 32109 32110 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 5115611 2625536 269 1283457024 134512640 135304128 4293441648 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32110/statm: 641 269 229 194 0 28 0 [pid=32111] ppid=32110 vsize=410916 CPUtime=3.1 /proc/32111/stat : 32111 (java) S 32110 32110 1511 34817 1511 4202496 48305 0 1 0 290 20 0 0 25 0 9 0 5115612 420777984 47539 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/32111/statm: 102729 47539 2903 10 0 96877 0 [pid=32111/tid=32113] ppid=32110 vsize=410916 CPUtime=1.51 /proc/32111/task/32113/stat : 32113 (java) R 32110 32110 1511 34817 1511 4202560 13511 0 1 0 145 6 0 0 25 0 9 0 5115612 420777984 47539 1283457024 134512640 134550932 4292783872 18446744073709551615 4115522877 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=32111/tid=32114] ppid=32110 vsize=410916 CPUtime=1.54 /proc/32111/task/32114/stat : 32114 (java) S 32110 32110 1511 34817 1511 4202560 33399 0 0 0 140 14 0 0 16 0 9 0 5115613 420777984 47539 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 0 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32115] ppid=32110 vsize=410916 CPUtime=0 /proc/32111/task/32115/stat : 32115 (java) S 32110 32110 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 20 0 9 0 5115613 420777984 47539 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32116] ppid=32110 vsize=410916 CPUtime=0 /proc/32111/task/32116/stat : 32116 (java) S 32110 32110 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 20 0 9 0 5115613 420777984 47539 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32117] ppid=32110 vsize=410916 CPUtime=0 /proc/32111/task/32117/stat : 32117 (java) S 32110 32110 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 5115614 420777984 47539 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32118] ppid=32110 vsize=410916 CPUtime=0.04 /proc/32111/task/32118/stat : 32118 (java) S 32110 32110 1511 34817 1511 4202560 454 0 0 0 4 0 0 0 16 0 9 0 5115614 420777984 47539 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32119] ppid=32110 vsize=410916 CPUtime=0 /proc/32111/task/32119/stat : 32119 (java) S 32110 32110 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 5115614 420777984 47539 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32120] ppid=32110 vsize=410916 CPUtime=0 /proc/32111/task/32120/stat : 32120 (java) S 32110 32110 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 9 0 5115614 420777984 47539 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.1 Current children cumulated vsize (KiB) 413480 [startup+6.30733 s] /proc/loadavg: 1.19 1.12 1.09 2/44 32121 /proc/meminfo: memFree=96260/1048576 swapFree=0/0 [pid=32110] ppid=32109 vsize=2564 CPUtime=0 /proc/32110/stat : 32110 (gj-trendy-solve) S 32109 32110 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 5115611 2625536 269 1283457024 134512640 135304128 4293441648 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32110/statm: 641 269 229 194 0 28 0 [pid=32111] ppid=32110 vsize=411516 CPUtime=6.3 /proc/32111/stat : 32111 (java) S 32110 32110 1511 34817 1511 4202496 66495 0 1 0 607 23 0 0 25 0 10 0 5115612 421392384 65641 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/32111/statm: 102879 65641 2920 10 0 97024 0 [pid=32111/tid=32113] ppid=32110 vsize=411516 CPUtime=3.76 /proc/32111/task/32113/stat : 32113 (java) R 32110 32110 1511 34817 1511 4202560 14547 0 1 0 370 6 0 0 25 0 10 0 5115612 421392384 65641 1283457024 134512640 134550932 4292783872 18446744073709551615 4115931845 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=32111/tid=32114] ppid=32110 vsize=411516 CPUtime=2.36 /proc/32111/task/32114/stat : 32114 (java) S 32110 32110 1511 34817 1511 4202560 50451 0 0 0 220 16 0 0 16 0 10 0 5115613 421392384 65641 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 0 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32115] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32115/stat : 32115 (java) S 32110 32110 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 5115613 421392384 65641 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32116] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32116/stat : 32116 (java) S 32110 32110 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 5115613 421392384 65641 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32117] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32117/stat : 32117 (java) S 32110 32110 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 5115614 421392384 65641 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32118] ppid=32110 vsize=411516 CPUtime=0.15 /proc/32111/task/32118/stat : 32118 (java) S 32110 32110 1511 34817 1511 4202560 551 0 0 0 15 0 0 0 15 0 10 0 5115614 421392384 65641 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32119] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32119/stat : 32119 (java) S 32110 32110 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 5115614 421392384 65641 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32120] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32120/stat : 32120 (java) S 32110 32110 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 5115614 421392384 65641 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32121] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32121/stat : 32121 (java) S 32110 32110 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 5116035 421392384 65641 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.3 Current children cumulated vsize (KiB) 414080 [startup+12.7088 s] /proc/loadavg: 1.18 1.12 1.09 3/44 32121 /proc/meminfo: memFree=90936/1048576 swapFree=0/0 [pid=32110] ppid=32109 vsize=2564 CPUtime=0 /proc/32110/stat : 32110 (gj-trendy-solve) S 32109 32110 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 5115611 2625536 269 1283457024 134512640 135304128 4293441648 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32110/statm: 641 269 229 194 0 28 0 [pid=32111] ppid=32110 vsize=411516 CPUtime=12.69 /proc/32111/stat : 32111 (java) S 32110 32110 1511 34817 1511 4202496 66505 0 1 0 1246 23 0 0 25 0 10 0 5115612 421392384 65651 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/32111/statm: 102879 65651 2920 10 0 97024 0 [pid=32111/tid=32113] ppid=32110 vsize=411516 CPUtime=10.04 /proc/32111/task/32113/stat : 32113 (java) R 32110 32110 1511 34817 1511 4202560 14548 0 1 0 998 6 0 0 25 0 10 0 5115612 421392384 65651 1283457024 134512640 134550932 4292783872 18446744073709551615 4115803498 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=32111/tid=32114] ppid=32110 vsize=411516 CPUtime=2.45 /proc/32111/task/32114/stat : 32114 (java) S 32110 32110 1511 34817 1511 4202560 50451 0 0 0 229 16 0 0 15 0 10 0 5115613 421392384 65651 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 0 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32115] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32115/stat : 32115 (java) S 32110 32110 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 5115613 421392384 65651 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32116] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32116/stat : 32116 (java) S 32110 32110 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 5115613 421392384 65651 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32117] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32117/stat : 32117 (java) S 32110 32110 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 5115614 421392384 65651 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32118] ppid=32110 vsize=411516 CPUtime=0.18 /proc/32111/task/32118/stat : 32118 (java) S 32110 32110 1511 34817 1511 4202560 560 0 0 0 18 0 0 0 15 0 10 0 5115614 421392384 65651 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32119] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32119/stat : 32119 (java) S 32110 32110 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 5115614 421392384 65651 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32120] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32120/stat : 32120 (java) S 32110 32110 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 5115614 421392384 65651 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32121] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32121/stat : 32121 (java) S 32110 32110 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 5116035 421392384 65651 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 12.69 Current children cumulated vsize (KiB) 414080 Solver just ended. Dumping a history of the last processes samples [startup+13.5089 s] /proc/loadavg: 1.16 1.12 1.09 2/44 32121 /proc/meminfo: memFree=90936/1048576 swapFree=0/0 [pid=32110] ppid=32109 vsize=2564 CPUtime=0 /proc/32110/stat : 32110 (gj-trendy-solve) S 32109 32110 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 5115611 2625536 269 1283457024 134512640 135304128 4293441648 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32110/statm: 641 269 229 194 0 28 0 [pid=32111] ppid=32110 vsize=411516 CPUtime=13.49 /proc/32111/stat : 32111 (java) S 32110 32110 1511 34817 1511 4202496 66505 0 1 0 1326 23 0 0 25 0 10 0 5115612 421392384 65651 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/32111/statm: 102879 65651 2920 10 0 97024 0 [pid=32111/tid=32113] ppid=32110 vsize=411516 CPUtime=10.84 /proc/32111/task/32113/stat : 32113 (java) R 32110 32110 1511 34817 1511 4202560 14548 0 1 0 1078 6 0 0 25 0 10 0 5115612 421392384 65651 1283457024 134512640 134550932 4292783872 18446744073709551615 4115726438 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=32111/tid=32114] ppid=32110 vsize=411516 CPUtime=2.45 /proc/32111/task/32114/stat : 32114 (java) S 32110 32110 1511 34817 1511 4202560 50451 0 0 0 229 16 0 0 16 0 10 0 5115613 421392384 65651 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 0 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32115] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32115/stat : 32115 (java) S 32110 32110 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 5115613 421392384 65651 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32116] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32116/stat : 32116 (java) S 32110 32110 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 5115613 421392384 65651 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32117] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32117/stat : 32117 (java) S 32110 32110 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 5115614 421392384 65651 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32118] ppid=32110 vsize=411516 CPUtime=0.18 /proc/32111/task/32118/stat : 32118 (java) S 32110 32110 1511 34817 1511 4202560 560 0 0 0 18 0 0 0 15 0 10 0 5115614 421392384 65651 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32119] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32119/stat : 32119 (java) S 32110 32110 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 5115614 421392384 65651 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32120] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32120/stat : 32120 (java) R 32110 32110 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 5115614 421392384 65651 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=32111/tid=32121] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32121/stat : 32121 (java) S 32110 32110 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 5116035 421392384 65651 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 13.49 Current children cumulated vsize (KiB) 414080 [startup+13.909 s] /proc/loadavg: 1.16 1.12 1.09 2/44 32121 /proc/meminfo: memFree=90936/1048576 swapFree=0/0 [pid=32110] ppid=32109 vsize=2564 CPUtime=0 /proc/32110/stat : 32110 (gj-trendy-solve) S 32109 32110 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 5115611 2625536 269 1283457024 134512640 135304128 4293441648 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32110/statm: 641 269 229 194 0 28 0 [pid=32111] ppid=32110 vsize=411516 CPUtime=13.89 /proc/32111/stat : 32111 (java) S 32110 32110 1511 34817 1511 4202496 66506 0 1 0 1366 23 0 0 25 0 10 0 5115612 421392384 65652 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/32111/statm: 102879 65652 2920 10 0 97024 0 [pid=32111/tid=32113] ppid=32110 vsize=411516 CPUtime=11.24 /proc/32111/task/32113/stat : 32113 (java) R 32110 32110 1511 34817 1511 4202560 14548 0 1 0 1118 6 0 0 25 0 10 0 5115612 421392384 65652 1283457024 134512640 134550932 4292783872 18446744073709551615 4115804378 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=32111/tid=32114] ppid=32110 vsize=411516 CPUtime=2.45 /proc/32111/task/32114/stat : 32114 (java) S 32110 32110 1511 34817 1511 4202560 50451 0 0 0 229 16 0 0 16 0 10 0 5115613 421392384 65652 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 0 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32115] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32115/stat : 32115 (java) S 32110 32110 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 5115613 421392384 65652 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32116] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32116/stat : 32116 (java) S 32110 32110 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 5115613 421392384 65652 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32117] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32117/stat : 32117 (java) S 32110 32110 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 5115614 421392384 65652 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32118] ppid=32110 vsize=411516 CPUtime=0.18 /proc/32111/task/32118/stat : 32118 (java) S 32110 32110 1511 34817 1511 4202560 561 0 0 0 18 0 0 0 15 0 10 0 5115614 421392384 65652 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32119] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32119/stat : 32119 (java) S 32110 32110 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 5115614 421392384 65652 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32120] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32120/stat : 32120 (java) S 32110 32110 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 5115614 421392384 65652 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32121] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32121/stat : 32121 (java) S 32110 32110 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 5116035 421392384 65652 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 13.89 Current children cumulated vsize (KiB) 414080 [startup+14.3091 s] /proc/loadavg: 1.16 1.12 1.09 2/44 32121 /proc/meminfo: memFree=90936/1048576 swapFree=0/0 [pid=32110] ppid=32109 vsize=2564 CPUtime=0 /proc/32110/stat : 32110 (gj-trendy-solve) S 32109 32110 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 5115611 2625536 269 1283457024 134512640 135304128 4293441648 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32110/statm: 641 269 229 194 0 28 0 [pid=32111] ppid=32110 vsize=411516 CPUtime=14.29 /proc/32111/stat : 32111 (java) S 32110 32110 1511 34817 1511 4202496 66515 0 1 0 1406 23 0 0 25 0 9 0 5115612 421392384 65661 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/32111/statm: 102879 65661 2921 10 0 97024 0 [pid=32111/tid=32113] ppid=32110 vsize=411516 CPUtime=11.64 /proc/32111/task/32113/stat : 32113 (java) R 32110 32110 1511 34817 1511 4202560 14548 0 1 0 1158 6 0 0 25 0 9 0 5115612 421392384 65661 1283457024 134512640 134550932 4292783872 18446744073709551615 4150611224 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=32111/tid=32114] ppid=32110 vsize=411516 CPUtime=2.45 /proc/32111/task/32114/stat : 32114 (java) S 32110 32110 1511 34817 1511 4202560 50451 0 0 0 229 16 0 0 16 0 9 0 5115613 421392384 65661 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 0 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32115] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32115/stat : 32115 (java) S 32110 32110 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 9 0 5115613 421392384 65661 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32116] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32116/stat : 32116 (java) S 32110 32110 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 9 0 5115613 421392384 65661 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32117] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32117/stat : 32117 (java) S 32110 32110 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 5115614 421392384 65661 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32118] ppid=32110 vsize=411516 CPUtime=0.18 /proc/32111/task/32118/stat : 32118 (java) S 32110 32110 1511 34817 1511 4202560 569 0 0 0 18 0 0 0 16 0 9 0 5115614 421392384 65661 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32119] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32119/stat : 32119 (java) S 32110 32110 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 5115614 421392384 65661 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 [pid=32111/tid=32120] ppid=32110 vsize=411516 CPUtime=0 /proc/32111/task/32120/stat : 32120 (java) S 32110 32110 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 9 0 5115614 421392384 65661 1283457024 134512640 134550932 4292783872 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 14.29 Current children cumulated vsize (KiB) 414080 Child status: 0 Real time (s): 14.3683 CPU time (s): 14.3409 CPU user time (s): 14.0889 CPU system time (s): 0.252015 CPU usage (%): 99.8095 Max. virtual memory (cumulated for all children) (KiB): 414080 getrusage(RUSAGE_CHILDREN,...) data: user time used= 14.0889 system time used= 0.252015 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 66902 page faults= 1 swaps= 0 block input operations= 0 block output operations= 0 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 1055 involuntary context switches= 1270 runsolver used 0 second user time and 0 second system time The end