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/rand105.cudf.user-upgrades.log.runsolver ./p2cudf-paranoid-1.13 /home/misc2010/data/2011/user-upgrades/rand105.cudf /home/misc2010/tmp/201108251442/p2cudf-paranoid-1.13/rand105.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.45 1.11 0.74 6/35 6110 /proc/meminfo: memFree=311576/1048576 swapFree=0/0 [pid=6108] ppid=6107 vsize=2564 CPUtime=0 /proc/6108/stat : 6108 (p2cudf-paranoid) S 6107 6108 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 10054514 2625536 270 1283457024 134512640 135304128 4288875600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6108/statm: 641 270 230 194 0 28 0 [pid=6109] ppid=6108 vsize=2568 CPUtime=0 /proc/6109/stat : 6109 (p2cudf) R 6108 6108 4778 34817 4778 4202496 346 0 0 0 0 0 0 0 25 0 1 0 10054515 2629632 272 1283457024 134512640 135304128 4291413712 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/6109/statm: 642 272 231 194 0 29 0 [pid=6110] ppid=6109 vsize=2568 CPUtime=0 /proc/6110/stat : 6110 (p2cudf) R 6109 6108 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 1 0 10054515 2629632 41 1283457024 134512640 135304128 4291413712 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/6110/statm: 642 41 0 194 0 29 0 [startup+0.130028 s] /proc/loadavg: 1.45 1.11 0.74 6/35 6110 /proc/meminfo: memFree=311576/1048576 swapFree=0/0 [pid=6108] ppid=6107 vsize=2564 CPUtime=0 /proc/6108/stat : 6108 (p2cudf-paranoid) S 6107 6108 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 10054514 2625536 270 1283457024 134512640 135304128 4288875600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6108/statm: 641 270 230 194 0 28 0 [pid=6109] ppid=6108 vsize=2572 CPUtime=0.03 /proc/6109/stat : 6109 (p2cudf) S 6108 6108 4778 34817 4778 4202496 391 3014 0 1 0 0 1 2 25 0 1 0 10054515 2633728 281 1283457024 134512640 135304128 4291413712 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6109/statm: 643 281 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5136 [startup+0.210045 s] /proc/loadavg: 1.45 1.11 0.74 6/35 6110 /proc/meminfo: memFree=311576/1048576 swapFree=0/0 [pid=6108] ppid=6107 vsize=2564 CPUtime=0 /proc/6108/stat : 6108 (p2cudf-paranoid) S 6107 6108 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 10054514 2625536 270 1283457024 134512640 135304128 4288875600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6108/statm: 641 270 230 194 0 28 0 [pid=6109] ppid=6108 vsize=2572 CPUtime=0.03 /proc/6109/stat : 6109 (p2cudf) S 6108 6108 4778 34817 4778 4202496 391 3014 0 1 0 0 1 2 25 0 1 0 10054515 2633728 281 1283457024 134512640 135304128 4291413712 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6109/statm: 643 281 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5136 [startup+0.310063 s] /proc/loadavg: 1.45 1.11 0.74 6/35 6110 /proc/meminfo: memFree=311576/1048576 swapFree=0/0 [pid=6108] ppid=6107 vsize=2564 CPUtime=0 /proc/6108/stat : 6108 (p2cudf-paranoid) S 6107 6108 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 10054514 2625536 270 1283457024 134512640 135304128 4288875600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6108/statm: 641 270 230 194 0 28 0 [pid=6109] ppid=6108 vsize=2572 CPUtime=0.03 /proc/6109/stat : 6109 (p2cudf) S 6108 6108 4778 34817 4778 4202496 391 3014 0 1 0 0 1 2 25 0 1 0 10054515 2633728 281 1283457024 134512640 135304128 4291413712 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6109/statm: 643 281 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5136 [startup+0.710168 s] /proc/loadavg: 1.45 1.11 0.74 6/35 6110 /proc/meminfo: memFree=311576/1048576 swapFree=0/0 [pid=6108] ppid=6107 vsize=2564 CPUtime=0 /proc/6108/stat : 6108 (p2cudf-paranoid) S 6107 6108 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 10054514 2625536 270 1283457024 134512640 135304128 4288875600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6108/statm: 641 270 230 194 0 28 0 [pid=6109] ppid=6108 vsize=2572 CPUtime=0.03 /proc/6109/stat : 6109 (p2cudf) S 6108 6108 4778 34817 4778 4202496 391 3014 0 1 0 0 1 2 25 0 1 0 10054515 2633728 281 1283457024 134512640 135304128 4291413712 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6109/statm: 643 281 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5136 [startup+1.51038 s] /proc/loadavg: 1.45 1.11 0.74 5/45 6131 /proc/meminfo: memFree=256568/1048576 swapFree=0/0 [pid=6108] ppid=6107 vsize=2564 CPUtime=0 /proc/6108/stat : 6108 (p2cudf-paranoid) S 6107 6108 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 10054514 2625536 270 1283457024 134512640 135304128 4288875600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6108/statm: 641 270 230 194 0 28 0 [pid=6109] ppid=6108 vsize=2572 CPUtime=0.03 /proc/6109/stat : 6109 (p2cudf) S 6108 6108 4778 34817 4778 4202496 391 3014 0 1 0 0 1 2 25 0 1 0 10054515 2633728 281 1283457024 134512640 135304128 4291413712 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6109/statm: 643 281 239 194 0 30 0 [pid=6122] ppid=6109 vsize=892208 CPUtime=1.48 /proc/6122/stat : 6122 (java) S 6109 6108 4778 34817 4778 4202496 23400 0 1 0 142 6 0 0 25 0 10 0 10054518 913620992 20743 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/6122/statm: 223052 20743 1724 10 0 220143 0 [pid=6122/tid=6123] ppid=6109 vsize=892208 CPUtime=0.79 /proc/6122/task/6123/stat : 6123 (java) R 6109 6108 4778 34817 4778 4202560 10968 0 1 0 76 3 0 0 25 0 10 0 10054519 913620992 20743 1283457024 134512640 134550932 4291562896 18446744073709551615 4095708891 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=6122/tid=6124] ppid=6109 vsize=892208 CPUtime=0 /proc/6122/task/6124/stat : 6124 (java) S 6109 6108 4778 34817 4778 4202560 7 0 0 0 0 0 0 0 15 0 10 0 10054520 913620992 20743 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 0 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6125] ppid=6109 vsize=892208 CPUtime=0 /proc/6122/task/6125/stat : 6125 (java) S 6109 6108 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 25 0 10 0 10054521 913620992 20743 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6126] ppid=6109 vsize=892208 CPUtime=0 /proc/6122/task/6126/stat : 6126 (java) S 6109 6108 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 10054521 913620992 20743 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6127] ppid=6109 vsize=892208 CPUtime=0 /proc/6122/task/6127/stat : 6127 (java) S 6109 6108 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10054523 913620992 20743 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6128] ppid=6109 vsize=892208 CPUtime=0.35 /proc/6122/task/6128/stat : 6128 (java) R 6109 6108 4778 34817 4778 4202560 6719 0 0 0 35 0 0 0 25 0 10 0 10054523 913620992 20743 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=6122/tid=6129] ppid=6109 vsize=892208 CPUtime=0.3 /proc/6122/task/6129/stat : 6129 (java) S 6109 6108 4778 34817 4778 4202560 4633 0 0 0 30 0 0 0 25 0 10 0 10054523 913620992 20743 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6130] ppid=6109 vsize=892208 CPUtime=0 /proc/6122/task/6130/stat : 6130 (java) S 6109 6108 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10054523 913620992 20743 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6131] ppid=6109 vsize=892208 CPUtime=0 /proc/6122/task/6131/stat : 6131 (java) S 6109 6108 4778 34817 4778 4202560 2 0 0 0 0 0 0 0 15 0 10 0 10054523 913620992 20743 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.51 Current children cumulated vsize (KiB) 897344 [startup+3.11092 s] /proc/loadavg: 1.57 1.14 0.75 2/45 6131 /proc/meminfo: memFree=168032/1048576 swapFree=0/0 [pid=6108] ppid=6107 vsize=2564 CPUtime=0 /proc/6108/stat : 6108 (p2cudf-paranoid) S 6107 6108 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 10054514 2625536 270 1283457024 134512640 135304128 4288875600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6108/statm: 641 270 230 194 0 28 0 [pid=6109] ppid=6108 vsize=2572 CPUtime=0.03 /proc/6109/stat : 6109 (p2cudf) S 6108 6108 4778 34817 4778 4202496 391 3014 0 1 0 0 1 2 25 0 1 0 10054515 2633728 281 1283457024 134512640 135304128 4291413712 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6109/statm: 643 281 239 194 0 30 0 [pid=6122] ppid=6109 vsize=890112 CPUtime=3.07 /proc/6122/stat : 6122 (java) S 6109 6108 4778 34817 4778 4202496 73423 0 1 0 285 22 0 0 25 0 10 0 10054518 911474688 70631 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/6122/statm: 222528 70631 1732 10 0 219619 0 [pid=6122/tid=6123] ppid=6109 vsize=890112 CPUtime=1.79 /proc/6122/task/6123/stat : 6123 (java) R 6109 6108 4778 34817 4778 4202560 50039 0 1 0 164 15 0 0 25 0 10 0 10054519 911474688 70631 1283457024 134512640 134550932 4291562896 18446744073709551615 4095918562 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=6122/tid=6124] ppid=6109 vsize=890112 CPUtime=0.21 /proc/6122/task/6124/stat : 6124 (java) S 6109 6108 4778 34817 4778 4202560 10772 0 0 0 17 4 0 0 16 0 10 0 10054520 911474688 70631 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 0 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6125] ppid=6109 vsize=890112 CPUtime=0 /proc/6122/task/6125/stat : 6125 (java) S 6109 6108 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 18 0 10 0 10054521 911474688 70631 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6126] ppid=6109 vsize=890112 CPUtime=0 /proc/6122/task/6126/stat : 6126 (java) S 6109 6108 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 10 0 10054521 911474688 70631 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6127] ppid=6109 vsize=890112 CPUtime=0 /proc/6122/task/6127/stat : 6127 (java) S 6109 6108 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10054523 911474688 70631 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6128] ppid=6109 vsize=890112 CPUtime=0.36 /proc/6122/task/6128/stat : 6128 (java) S 6109 6108 4778 34817 4778 4202560 6743 0 0 0 36 0 0 0 25 0 10 0 10054523 911474688 70631 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6129] ppid=6109 vsize=890112 CPUtime=0.66 /proc/6122/task/6129/stat : 6129 (java) S 6109 6108 4778 34817 4778 4202560 4794 0 0 0 66 0 0 0 15 0 10 0 10054523 911474688 70631 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6130] ppid=6109 vsize=890112 CPUtime=0 /proc/6122/task/6130/stat : 6130 (java) S 6109 6108 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10054523 911474688 70631 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6131] ppid=6109 vsize=890112 CPUtime=0 /proc/6122/task/6131/stat : 6131 (java) S 6109 6108 4778 34817 4778 4202560 2 0 0 0 0 0 0 0 15 0 10 0 10054523 911474688 70631 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.1 Current children cumulated vsize (KiB) 895248 Solver just ended. Dumping a history of the last processes samples [startup+3.21097 s] /proc/loadavg: 1.57 1.14 0.75 2/45 6131 /proc/meminfo: memFree=168032/1048576 swapFree=0/0 [pid=6108] ppid=6107 vsize=2564 CPUtime=0 /proc/6108/stat : 6108 (p2cudf-paranoid) S 6107 6108 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 10054514 2625536 270 1283457024 134512640 135304128 4288875600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6108/statm: 641 270 230 194 0 28 0 [pid=6109] ppid=6108 vsize=2572 CPUtime=0.03 /proc/6109/stat : 6109 (p2cudf) S 6108 6108 4778 34817 4778 4202496 391 3014 0 1 0 0 1 2 25 0 1 0 10054515 2633728 281 1283457024 134512640 135304128 4291413712 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6109/statm: 643 281 239 194 0 30 0 [pid=6122] ppid=6109 vsize=890112 CPUtime=3.17 /proc/6122/stat : 6122 (java) S 6109 6108 4778 34817 4778 4202496 73441 0 1 0 295 22 0 0 25 0 10 0 10054518 911474688 70649 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/6122/statm: 222528 70649 1740 10 0 219619 0 [pid=6122/tid=6123] ppid=6109 vsize=890112 CPUtime=1.85 /proc/6122/task/6123/stat : 6123 (java) R 6109 6108 4778 34817 4778 4202560 50052 0 1 0 170 15 0 0 25 0 10 0 10054519 911474688 70649 1283457024 134512640 134550932 4291562896 18446744073709551615 4096015705 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=6122/tid=6124] ppid=6109 vsize=890112 CPUtime=0.21 /proc/6122/task/6124/stat : 6124 (java) S 6109 6108 4778 34817 4778 4202560 10772 0 0 0 17 4 0 0 16 0 10 0 10054520 911474688 70649 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 0 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6125] ppid=6109 vsize=890112 CPUtime=0 /proc/6122/task/6125/stat : 6125 (java) S 6109 6108 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 18 0 10 0 10054521 911474688 70649 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6126] ppid=6109 vsize=890112 CPUtime=0 /proc/6122/task/6126/stat : 6126 (java) S 6109 6108 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 10 0 10054521 911474688 70649 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6127] ppid=6109 vsize=890112 CPUtime=0 /proc/6122/task/6127/stat : 6127 (java) S 6109 6108 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10054523 911474688 70649 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6128] ppid=6109 vsize=890112 CPUtime=0.36 /proc/6122/task/6128/stat : 6128 (java) S 6109 6108 4778 34817 4778 4202560 6743 0 0 0 36 0 0 0 25 0 10 0 10054523 911474688 70649 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6129] ppid=6109 vsize=890112 CPUtime=0.7 /proc/6122/task/6129/stat : 6129 (java) S 6109 6108 4778 34817 4778 4202560 4799 0 0 0 70 0 0 0 15 0 10 0 10054523 911474688 70649 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6130] ppid=6109 vsize=890112 CPUtime=0 /proc/6122/task/6130/stat : 6130 (java) S 6109 6108 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10054523 911474688 70649 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6131] ppid=6109 vsize=890112 CPUtime=0 /proc/6122/task/6131/stat : 6131 (java) S 6109 6108 4778 34817 4778 4202560 2 0 0 0 0 0 0 0 15 0 10 0 10054523 911474688 70649 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.2 Current children cumulated vsize (KiB) 895248 [startup+4.01126 s] /proc/loadavg: 1.57 1.14 0.75 2/45 6131 /proc/meminfo: memFree=34732/1048576 swapFree=0/0 [pid=6108] ppid=6107 vsize=2564 CPUtime=0 /proc/6108/stat : 6108 (p2cudf-paranoid) S 6107 6108 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 10054514 2625536 270 1283457024 134512640 135304128 4288875600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6108/statm: 641 270 230 194 0 28 0 [pid=6109] ppid=6108 vsize=2572 CPUtime=0.03 /proc/6109/stat : 6109 (p2cudf) S 6108 6108 4778 34817 4778 4202496 391 3014 0 1 0 0 1 2 25 0 1 0 10054515 2633728 281 1283457024 134512640 135304128 4291413712 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6109/statm: 643 281 239 194 0 30 0 [pid=6122] ppid=6109 vsize=890864 CPUtime=3.97 /proc/6122/stat : 6122 (java) S 6109 6108 4778 34817 4778 4202496 74033 0 1 0 375 22 0 0 25 0 10 0 10054518 912244736 71054 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/6122/statm: 222716 71054 1746 10 0 219807 0 [pid=6122/tid=6123] ppid=6109 vsize=890864 CPUtime=1.96 /proc/6122/task/6123/stat : 6123 (java) R 6109 6108 4778 34817 4778 4202560 50213 0 1 0 181 15 0 0 25 0 10 0 10054519 912244736 71054 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=6122/tid=6124] ppid=6109 vsize=890864 CPUtime=0.21 /proc/6122/task/6124/stat : 6124 (java) S 6109 6108 4778 34817 4778 4202560 10772 0 0 0 17 4 0 0 15 0 10 0 10054520 912244736 71054 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 0 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6125] ppid=6109 vsize=890864 CPUtime=0 /proc/6122/task/6125/stat : 6125 (java) S 6109 6108 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 18 0 10 0 10054521 912244736 71054 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6126] ppid=6109 vsize=890864 CPUtime=0 /proc/6122/task/6126/stat : 6126 (java) S 6109 6108 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 10 0 10054521 912244736 71054 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6127] ppid=6109 vsize=890864 CPUtime=0 /proc/6122/task/6127/stat : 6127 (java) S 6109 6108 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10054523 912244736 71054 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6128] ppid=6109 vsize=890864 CPUtime=0.36 /proc/6122/task/6128/stat : 6128 (java) S 6109 6108 4778 34817 4778 4202560 6743 0 0 0 36 0 0 0 25 0 10 0 10054523 912244736 71054 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6129] ppid=6109 vsize=890864 CPUtime=1.39 /proc/6122/task/6129/stat : 6129 (java) R 6109 6108 4778 34817 4778 4202560 5230 0 0 0 139 0 0 0 16 0 10 0 10054523 912244736 71054 1283457024 134512640 134550932 4291562896 18446744073709551615 4150322090 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=6122/tid=6130] ppid=6109 vsize=890864 CPUtime=0 /proc/6122/task/6130/stat : 6130 (java) S 6109 6108 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10054523 912244736 71054 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6131] ppid=6109 vsize=890864 CPUtime=0 /proc/6122/task/6131/stat : 6131 (java) S 6109 6108 4778 34817 4778 4202560 2 0 0 0 0 0 0 0 15 0 10 0 10054523 912244736 71054 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4 Current children cumulated vsize (KiB) 896000 [startup+4.80606 s] /proc/loadavg: 1.57 1.14 0.75 4/45 6131 /proc/meminfo: memFree=33376/1048576 swapFree=0/0 [pid=6108] ppid=6107 vsize=2564 CPUtime=0 /proc/6108/stat : 6108 (p2cudf-paranoid) S 6107 6108 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 10054514 2625536 270 1283457024 134512640 135304128 4288875600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6108/statm: 641 270 230 194 0 28 0 [pid=6109] ppid=6108 vsize=2572 CPUtime=0.03 /proc/6109/stat : 6109 (p2cudf) S 6108 6108 4778 34817 4778 4202496 391 3014 0 1 0 0 1 2 25 0 1 0 10054515 2633728 281 1283457024 134512640 135304128 4291413712 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6109/statm: 643 281 239 194 0 30 0 [pid=6122] ppid=6109 vsize=890760 CPUtime=4.66 /proc/6122/stat : 6122 (java) S 6109 6108 4778 34817 4778 4202496 74175 0 1 0 442 24 0 0 25 0 11 0 10054518 912138240 71008 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/6122/statm: 222690 71008 1761 10 0 219781 0 [pid=6122/tid=6123] ppid=6109 vsize=890760 CPUtime=2.3 /proc/6122/task/6123/stat : 6123 (java) S 6109 6108 4778 34817 4778 4202560 50283 0 1 0 214 16 0 0 25 0 11 0 10054519 912138240 71008 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6124] ppid=6109 vsize=890760 CPUtime=0.22 /proc/6122/task/6124/stat : 6124 (java) S 6109 6108 4778 34817 4778 4202560 10772 0 0 0 17 5 0 0 16 0 11 0 10054520 912138240 71008 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 0 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6125] ppid=6109 vsize=890760 CPUtime=0 /proc/6122/task/6125/stat : 6125 (java) S 6109 6108 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 18 0 11 0 10054521 912138240 71008 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6126] ppid=6109 vsize=890760 CPUtime=0 /proc/6122/task/6126/stat : 6126 (java) S 6109 6108 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 11 0 10054521 912138240 71008 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6127] ppid=6109 vsize=890760 CPUtime=0 /proc/6122/task/6127/stat : 6127 (java) S 6109 6108 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 10054523 912138240 71008 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6128] ppid=6109 vsize=890760 CPUtime=0.36 /proc/6122/task/6128/stat : 6128 (java) S 6109 6108 4778 34817 4778 4202560 6743 0 0 0 36 0 0 0 25 0 11 0 10054523 912138240 71008 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6129] ppid=6109 vsize=890760 CPUtime=1.72 /proc/6122/task/6129/stat : 6129 (java) S 6109 6108 4778 34817 4778 4202560 5293 0 0 0 172 0 0 0 15 0 11 0 10054523 912138240 71008 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6130] ppid=6109 vsize=890760 CPUtime=0 /proc/6122/task/6130/stat : 6130 (java) S 6109 6108 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 10054523 912138240 71008 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6131] ppid=6109 vsize=890760 CPUtime=0 /proc/6122/task/6131/stat : 6131 (java) S 6109 6108 4778 34817 4778 4202560 2 0 0 0 0 0 0 0 15 0 11 0 10054523 912138240 71008 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.69 Current children cumulated vsize (KiB) 895896 [startup+5.20611 s] /proc/loadavg: 1.57 1.14 0.75 4/45 6131 /proc/meminfo: memFree=33376/1048576 swapFree=0/0 [pid=6108] ppid=6107 vsize=2564 CPUtime=0 /proc/6108/stat : 6108 (p2cudf-paranoid) S 6107 6108 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 10054514 2625536 270 1283457024 134512640 135304128 4288875600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6108/statm: 641 270 230 194 0 28 0 [pid=6109] ppid=6108 vsize=2572 CPUtime=0.03 /proc/6109/stat : 6109 (p2cudf) S 6108 6108 4778 34817 4778 4202496 391 3014 0 1 0 0 1 2 25 0 1 0 10054515 2633728 281 1283457024 134512640 135304128 4291413712 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6109/statm: 643 281 239 194 0 30 0 [pid=6122] ppid=6109 vsize=890760 CPUtime=4.66 /proc/6122/stat : 6122 (java) S 6109 6108 4778 34817 4778 4202496 74175 0 1 0 442 24 0 0 25 0 11 0 10054518 912138240 71008 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/6122/statm: 222690 71008 1761 10 0 219781 0 [pid=6122/tid=6123] ppid=6109 vsize=890760 CPUtime=2.3 /proc/6122/task/6123/stat : 6123 (java) S 6109 6108 4778 34817 4778 4202560 50283 0 1 0 214 16 0 0 25 0 11 0 10054519 912138240 71008 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6124] ppid=6109 vsize=890760 CPUtime=0.22 /proc/6122/task/6124/stat : 6124 (java) S 6109 6108 4778 34817 4778 4202560 10772 0 0 0 17 5 0 0 16 0 11 0 10054520 912138240 71008 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 0 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6125] ppid=6109 vsize=890760 CPUtime=0 /proc/6122/task/6125/stat : 6125 (java) S 6109 6108 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 18 0 11 0 10054521 912138240 71008 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6126] ppid=6109 vsize=890760 CPUtime=0 /proc/6122/task/6126/stat : 6126 (java) S 6109 6108 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 11 0 10054521 912138240 71008 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6127] ppid=6109 vsize=890760 CPUtime=0 /proc/6122/task/6127/stat : 6127 (java) S 6109 6108 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 10054523 912138240 71008 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6128] ppid=6109 vsize=890760 CPUtime=0.36 /proc/6122/task/6128/stat : 6128 (java) S 6109 6108 4778 34817 4778 4202560 6743 0 0 0 36 0 0 0 25 0 11 0 10054523 912138240 71008 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6129] ppid=6109 vsize=890760 CPUtime=1.72 /proc/6122/task/6129/stat : 6129 (java) S 6109 6108 4778 34817 4778 4202560 5293 0 0 0 172 0 0 0 15 0 11 0 10054523 912138240 71008 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6130] ppid=6109 vsize=890760 CPUtime=0 /proc/6122/task/6130/stat : 6130 (java) S 6109 6108 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 10054523 912138240 71008 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6131] ppid=6109 vsize=890760 CPUtime=0 /proc/6122/task/6131/stat : 6131 (java) S 6109 6108 4778 34817 4778 4202560 2 0 0 0 0 0 0 0 15 0 11 0 10054523 912138240 71008 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.69 Current children cumulated vsize (KiB) 895896 [startup+5.30608 s] /proc/loadavg: 1.57 1.14 0.75 4/45 6131 /proc/meminfo: memFree=33376/1048576 swapFree=0/0 [pid=6108] ppid=6107 vsize=2564 CPUtime=0 /proc/6108/stat : 6108 (p2cudf-paranoid) S 6107 6108 4778 34817 4778 4202496 374 0 0 0 0 0 0 0 18 0 1 0 10054514 2625536 270 1283457024 134512640 135304128 4288875600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6108/statm: 641 270 230 194 0 28 0 [pid=6109] ppid=6108 vsize=2572 CPUtime=0.03 /proc/6109/stat : 6109 (p2cudf) S 6108 6108 4778 34817 4778 4202496 391 3014 0 1 0 0 1 2 25 0 1 0 10054515 2633728 281 1283457024 134512640 135304128 4291413712 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6109/statm: 643 281 239 194 0 30 0 [pid=6122] ppid=6109 vsize=890760 CPUtime=4.72 /proc/6122/stat : 6122 (java) S 6109 6108 4778 34817 4778 4202496 74180 0 1 0 446 26 0 0 25 0 10 0 10054518 912138240 71013 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/6122/statm: 222690 71013 1762 10 0 219781 0 [pid=6122/tid=6123] ppid=6109 vsize=890760 CPUtime=2.3 /proc/6122/task/6123/stat : 6123 (java) S 6109 6108 4778 34817 4778 4202560 50283 0 1 0 214 16 0 0 20 0 10 0 10054519 912138240 71013 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6124] ppid=6109 vsize=890760 CPUtime=0.22 /proc/6122/task/6124/stat : 6124 (java) S 6109 6108 4778 34817 4778 4202560 10772 0 0 0 17 5 0 0 15 0 10 0 10054520 912138240 71013 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 0 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6125] ppid=6109 vsize=890760 CPUtime=0 /proc/6122/task/6125/stat : 6125 (java) S 6109 6108 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 18 0 10 0 10054521 912138240 71013 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6126] ppid=6109 vsize=890760 CPUtime=0 /proc/6122/task/6126/stat : 6126 (java) S 6109 6108 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 10 0 10054521 912138240 71013 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6127] ppid=6109 vsize=890760 CPUtime=0 /proc/6122/task/6127/stat : 6127 (java) S 6109 6108 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10054523 912138240 71013 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6128] ppid=6109 vsize=890760 CPUtime=0.36 /proc/6122/task/6128/stat : 6128 (java) S 6109 6108 4778 34817 4778 4202560 6743 0 0 0 36 0 0 0 25 0 10 0 10054523 912138240 71013 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6129] ppid=6109 vsize=890760 CPUtime=1.74 /proc/6122/task/6129/stat : 6129 (java) S 6109 6108 4778 34817 4778 4202560 5297 0 0 0 174 0 0 0 16 0 10 0 10054523 912138240 71013 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6130] ppid=6109 vsize=890760 CPUtime=0 /proc/6122/task/6130/stat : 6130 (java) S 6109 6108 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10054523 912138240 71013 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 [pid=6122/tid=6131] ppid=6109 vsize=890760 CPUtime=0 /proc/6122/task/6131/stat : 6131 (java) S 6109 6108 4778 34817 4778 4202560 2 0 0 0 0 0 0 0 15 0 10 0 10054523 912138240 71013 1283457024 134512640 134550932 4291562896 18446744073709551615 4294960130 0 4 0 16800975 18446612133155122240 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.75 Current children cumulated vsize (KiB) 895896 Child status: 0 Real time (s): 5.33893 CPU time (s): 4.7843 CPU user time (s): 4.47628 CPU system time (s): 0.308019 CPU usage (%): 89.6116 Max. virtual memory (cumulated for all children) (KiB): 897700 getrusage(RUSAGE_CHILDREN,...) data: user time used= 4.47628 system time used= 0.308019 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 77985 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= 516 involuntary context switches= 496 runsolver used 0 second user time and 0.008 second system time The end