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/201103071905/p2cudf-user-1.12/0207e19a-9b1c-11df-af69-00163e46d37a.cudf.upgrades.log.runsolver ./p2cudf-user-1.12 /home/misc2010/data/misc4/upgrades//0207e19a-9b1c-11df-af69-00163e46d37a.cudf /home/misc2010/tmp/201103071905/p2cudf-user-1.12/0207e19a-9b1c-11df-af69-00163e46d37a.cudf.upgrades.result -notuptodate,-removed,-changed 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: 0.25 0.06 0.02 5/41 1343 /proc/meminfo: memFree=277952/1048576 swapFree=0/0 [pid=1342] ppid=1341 vsize=2568 CPUtime=0.01 /proc/1342/stat : 1342 (p2cudf-user-1.1) R 1341 1342 1268 34818 1268 4202496 356 0 0 0 1 0 0 0 25 0 1 0 112386826 2629632 270 1283457024 134512640 135304128 4288682960 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/1342/statm: 642 270 230 194 0 29 0 [pid=1343] ppid=1342 vsize=2568 CPUtime=0 /proc/1343/stat : 1343 (p2cudf-user-1.1) R 1342 1342 1268 34818 1268 4202560 0 0 0 0 0 0 0 0 25 0 1 0 112386826 2629632 40 1283457024 134512640 135304128 4288682960 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/1343/statm: 642 40 0 194 0 29 0 [startup+0.121524 s] /proc/loadavg: 0.25 0.06 0.02 5/41 1343 /proc/meminfo: memFree=277952/1048576 swapFree=0/0 [pid=1342] ppid=1341 vsize=2568 CPUtime=0.01 /proc/1342/stat : 1342 (p2cudf-user-1.1) S 1341 1342 1268 34818 1268 4202496 373 0 0 0 1 0 0 0 25 0 1 0 112386826 2629632 270 1283457024 134512640 135304128 4288682960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1342/statm: 642 270 230 194 0 29 0 [pid=1343] ppid=1342 vsize=2572 CPUtime=0.02 /proc/1343/stat : 1343 (p2cudf) S 1342 1342 1268 34818 1268 4202496 392 3016 0 1 0 0 2 0 25 0 1 0 112386826 2633728 281 1283457024 134512640 135304128 4292634560 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1343/statm: 643 281 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5140 [startup+0.211545 s] /proc/loadavg: 0.25 0.06 0.02 5/41 1343 /proc/meminfo: memFree=277952/1048576 swapFree=0/0 [pid=1342] ppid=1341 vsize=2568 CPUtime=0.01 /proc/1342/stat : 1342 (p2cudf-user-1.1) S 1341 1342 1268 34818 1268 4202496 373 0 0 0 1 0 0 0 25 0 1 0 112386826 2629632 270 1283457024 134512640 135304128 4288682960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1342/statm: 642 270 230 194 0 29 0 [pid=1343] ppid=1342 vsize=2572 CPUtime=0.02 /proc/1343/stat : 1343 (p2cudf) S 1342 1342 1268 34818 1268 4202496 392 3016 0 1 0 0 2 0 25 0 1 0 112386826 2633728 281 1283457024 134512640 135304128 4292634560 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1343/statm: 643 281 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5140 [startup+0.313868 s] /proc/loadavg: 0.25 0.06 0.02 5/41 1343 /proc/meminfo: memFree=277952/1048576 swapFree=0/0 [pid=1342] ppid=1341 vsize=2568 CPUtime=0.01 /proc/1342/stat : 1342 (p2cudf-user-1.1) S 1341 1342 1268 34818 1268 4202496 373 0 0 0 1 0 0 0 25 0 1 0 112386826 2629632 270 1283457024 134512640 135304128 4288682960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1342/statm: 642 270 230 194 0 29 0 [pid=1343] ppid=1342 vsize=2572 CPUtime=0.02 /proc/1343/stat : 1343 (p2cudf) S 1342 1342 1268 34818 1268 4202496 392 3016 0 1 0 0 2 0 25 0 1 0 112386826 2633728 281 1283457024 134512640 135304128 4292634560 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1343/statm: 643 281 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5140 [startup+0.711664 s] /proc/loadavg: 0.25 0.06 0.02 5/41 1343 /proc/meminfo: memFree=277952/1048576 swapFree=0/0 [pid=1342] ppid=1341 vsize=2568 CPUtime=0.01 /proc/1342/stat : 1342 (p2cudf-user-1.1) S 1341 1342 1268 34818 1268 4202496 373 0 0 0 1 0 0 0 25 0 1 0 112386826 2629632 270 1283457024 134512640 135304128 4288682960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1342/statm: 642 270 230 194 0 29 0 [pid=1343] ppid=1342 vsize=2572 CPUtime=0.02 /proc/1343/stat : 1343 (p2cudf) S 1342 1342 1268 34818 1268 4202496 392 3016 0 1 0 0 2 0 25 0 1 0 112386826 2633728 281 1283457024 134512640 135304128 4292634560 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1343/statm: 643 281 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5140 [startup+1.51184 s] /proc/loadavg: 0.25 0.06 0.02 4/52 1365 /proc/meminfo: memFree=230912/1048576 swapFree=0/0 [pid=1342] ppid=1341 vsize=2568 CPUtime=0.01 /proc/1342/stat : 1342 (p2cudf-user-1.1) S 1341 1342 1268 34818 1268 4202496 373 0 0 0 1 0 0 0 25 0 1 0 112386826 2629632 270 1283457024 134512640 135304128 4288682960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1342/statm: 642 270 230 194 0 29 0 [pid=1343] ppid=1342 vsize=2572 CPUtime=0.02 /proc/1343/stat : 1343 (p2cudf) S 1342 1342 1268 34818 1268 4202496 392 3016 0 1 0 0 2 0 25 0 1 0 112386826 2633728 281 1283457024 134512640 135304128 4292634560 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1343/statm: 643 281 239 194 0 30 0 [pid=1356] ppid=1343 vsize=882704 CPUtime=1.48 /proc/1356/stat : 1356 (java) S 1343 1342 1268 34818 1268 4202496 33095 0 1 0 132 16 0 0 25 0 10 0 112386830 903888896 29784 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/1356/statm: 220676 29784 1723 10 0 217767 0 [pid=1356/tid=1357] ppid=1343 vsize=882704 CPUtime=0.76 /proc/1356/task/1357/stat : 1357 (java) R 1343 1342 1268 34818 1268 4202560 21835 0 1 0 67 9 0 0 25 0 10 0 112386830 903888896 29784 1283457024 134512640 134550932 4290278432 18446744073709551615 4095801043 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=1356/tid=1358] ppid=1343 vsize=882704 CPUtime=0 /proc/1356/task/1358/stat : 1358 (java) S 1343 1342 1268 34818 1268 4202560 7 0 0 0 0 0 0 0 17 0 10 0 112386832 903888896 29784 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 0 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1359] ppid=1343 vsize=882704 CPUtime=0 /proc/1356/task/1359/stat : 1359 (java) S 1343 1342 1268 34818 1268 4202560 8 0 0 0 0 0 0 0 25 0 10 0 112386832 903888896 29784 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1360] ppid=1343 vsize=882704 CPUtime=0 /proc/1356/task/1360/stat : 1360 (java) S 1343 1342 1268 34818 1268 4202560 4 0 0 0 0 0 0 0 25 0 10 0 112386832 903888896 29784 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1361] ppid=1343 vsize=882704 CPUtime=0 /proc/1356/task/1361/stat : 1361 (java) S 1343 1342 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 25 0 10 0 112386835 903888896 29784 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1362] ppid=1343 vsize=882704 CPUtime=0.36 /proc/1356/task/1362/stat : 1362 (java) S 1343 1342 1268 34818 1268 4202560 7518 0 0 0 32 4 0 0 24 0 10 0 112386835 903888896 29784 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1363] ppid=1343 vsize=882704 CPUtime=0.31 /proc/1356/task/1363/stat : 1363 (java) S 1343 1342 1268 34818 1268 4202560 2662 0 0 0 31 0 0 0 21 0 10 0 112386835 903888896 29784 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1364] ppid=1343 vsize=882704 CPUtime=0 /proc/1356/task/1364/stat : 1364 (java) S 1343 1342 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 25 0 10 0 112386835 903888896 29784 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1365] ppid=1343 vsize=882704 CPUtime=0 /proc/1356/task/1365/stat : 1365 (java) S 1343 1342 1268 34818 1268 4202560 2 0 0 0 0 0 0 0 15 0 10 0 112386835 903888896 29784 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.51 Current children cumulated vsize (KiB) 887844 [startup+3.10223 s] /proc/loadavg: 0.31 0.08 0.02 3/52 1365 /proc/meminfo: memFree=67976/1048576 swapFree=0/0 [pid=1342] ppid=1341 vsize=2568 CPUtime=0.01 /proc/1342/stat : 1342 (p2cudf-user-1.1) S 1341 1342 1268 34818 1268 4202496 373 0 0 0 1 0 0 0 25 0 1 0 112386826 2629632 270 1283457024 134512640 135304128 4288682960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1342/statm: 642 270 230 194 0 29 0 [pid=1343] ppid=1342 vsize=2572 CPUtime=0.02 /proc/1343/stat : 1343 (p2cudf) S 1342 1342 1268 34818 1268 4202496 392 3016 0 1 0 0 2 0 25 0 1 0 112386826 2633728 281 1283457024 134512640 135304128 4292634560 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1343/statm: 643 281 239 194 0 30 0 [pid=1356] ppid=1343 vsize=884456 CPUtime=3.06 /proc/1356/stat : 1356 (java) S 1343 1342 1268 34818 1268 4202496 74786 0 1 0 281 25 0 0 25 0 10 0 112386830 905682944 70654 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/1356/statm: 221114 70654 1746 10 0 218205 0 [pid=1356/tid=1357] ppid=1343 vsize=884456 CPUtime=1.42 /proc/1356/task/1357/stat : 1357 (java) R 1343 1342 1268 34818 1268 4202560 50178 0 1 0 125 17 0 0 25 0 10 0 112386830 905682944 70654 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=1356/tid=1358] ppid=1343 vsize=884456 CPUtime=0.27 /proc/1356/task/1358/stat : 1358 (java) S 1343 1342 1268 34818 1268 4202560 12089 0 0 0 26 1 0 0 16 0 10 0 112386832 905682944 70654 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 0 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1359] ppid=1343 vsize=884456 CPUtime=0 /proc/1356/task/1359/stat : 1359 (java) S 1343 1342 1268 34818 1268 4202560 8 0 0 0 0 0 0 0 18 0 10 0 112386832 905682944 70654 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1360] ppid=1343 vsize=884456 CPUtime=0 /proc/1356/task/1360/stat : 1360 (java) S 1343 1342 1268 34818 1268 4202560 6 0 0 0 0 0 0 0 18 0 10 0 112386832 905682944 70654 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1361] ppid=1343 vsize=884456 CPUtime=0 /proc/1356/task/1361/stat : 1361 (java) S 1343 1342 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 25 0 10 0 112386835 905682944 70654 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1362] ppid=1343 vsize=884456 CPUtime=0.74 /proc/1356/task/1362/stat : 1362 (java) R 1343 1342 1268 34818 1268 4202560 8729 0 0 0 70 4 0 0 19 0 10 0 112386835 905682944 70654 1283457024 134512640 134550932 4290278432 18446744073709551615 4149306339 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=1356/tid=1363] ppid=1343 vsize=884456 CPUtime=0.59 /proc/1356/task/1363/stat : 1363 (java) S 1343 1342 1268 34818 1268 4202560 2715 0 0 0 59 0 0 0 15 0 10 0 112386835 905682944 70654 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1364] ppid=1343 vsize=884456 CPUtime=0 /proc/1356/task/1364/stat : 1364 (java) S 1343 1342 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 25 0 10 0 112386835 905682944 70654 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1365] ppid=1343 vsize=884456 CPUtime=0 /proc/1356/task/1365/stat : 1365 (java) S 1343 1342 1268 34818 1268 4202560 2 0 0 0 0 0 0 0 15 0 10 0 112386835 905682944 70654 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.09 Current children cumulated vsize (KiB) 889596 Solver just ended. Dumping a history of the last processes samples [startup+3.21465 s] /proc/loadavg: 0.31 0.08 0.02 3/52 1365 /proc/meminfo: memFree=67976/1048576 swapFree=0/0 [pid=1342] ppid=1341 vsize=2568 CPUtime=0.01 /proc/1342/stat : 1342 (p2cudf-user-1.1) S 1341 1342 1268 34818 1268 4202496 373 0 0 0 1 0 0 0 25 0 1 0 112386826 2629632 270 1283457024 134512640 135304128 4288682960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1342/statm: 642 270 230 194 0 29 0 [pid=1343] ppid=1342 vsize=2572 CPUtime=0.02 /proc/1343/stat : 1343 (p2cudf) S 1342 1342 1268 34818 1268 4202496 392 3016 0 1 0 0 2 0 25 0 1 0 112386826 2633728 281 1283457024 134512640 135304128 4292634560 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1343/statm: 643 281 239 194 0 30 0 [pid=1356] ppid=1343 vsize=884476 CPUtime=3.17 /proc/1356/stat : 1356 (java) S 1343 1342 1268 34818 1268 4202496 74983 0 1 0 291 26 0 0 25 0 10 0 112386830 905703424 70674 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/1356/statm: 221119 70674 1746 10 0 218210 0 [pid=1356/tid=1357] ppid=1343 vsize=884476 CPUtime=1.47 /proc/1356/task/1357/stat : 1357 (java) R 1343 1342 1268 34818 1268 4202560 50191 0 1 0 130 17 0 0 25 0 10 0 112386830 905703424 70674 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=1356/tid=1358] ppid=1343 vsize=884476 CPUtime=0.27 /proc/1356/task/1358/stat : 1358 (java) S 1343 1342 1268 34818 1268 4202560 12089 0 0 0 26 1 0 0 16 0 10 0 112386832 905703424 70674 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 0 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1359] ppid=1343 vsize=884476 CPUtime=0 /proc/1356/task/1359/stat : 1359 (java) S 1343 1342 1268 34818 1268 4202560 8 0 0 0 0 0 0 0 18 0 10 0 112386832 905703424 70674 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1360] ppid=1343 vsize=884476 CPUtime=0 /proc/1356/task/1360/stat : 1360 (java) S 1343 1342 1268 34818 1268 4202560 6 0 0 0 0 0 0 0 18 0 10 0 112386832 905703424 70674 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1361] ppid=1343 vsize=884476 CPUtime=0 /proc/1356/task/1361/stat : 1361 (java) S 1343 1342 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 25 0 10 0 112386835 905703424 70674 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1362] ppid=1343 vsize=884476 CPUtime=0.77 /proc/1356/task/1362/stat : 1362 (java) R 1343 1342 1268 34818 1268 4202560 8911 0 0 0 72 5 0 0 19 0 10 0 112386835 905703424 70674 1283457024 134512640 134550932 4290278432 18446744073709551615 4150413127 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=1356/tid=1363] ppid=1343 vsize=884476 CPUtime=0.62 /proc/1356/task/1363/stat : 1363 (java) S 1343 1342 1268 34818 1268 4202560 2717 0 0 0 62 0 0 0 15 0 10 0 112386835 905703424 70674 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1364] ppid=1343 vsize=884476 CPUtime=0 /proc/1356/task/1364/stat : 1364 (java) S 1343 1342 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 25 0 10 0 112386835 905703424 70674 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1365] ppid=1343 vsize=884476 CPUtime=0 /proc/1356/task/1365/stat : 1365 (java) S 1343 1342 1268 34818 1268 4202560 2 0 0 0 0 0 0 0 15 0 10 0 112386835 905703424 70674 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.2 Current children cumulated vsize (KiB) 889616 [startup+4.81263 s] /proc/loadavg: 0.31 0.08 0.02 2/53 1366 /proc/meminfo: memFree=6424/1048576 swapFree=0/0 [pid=1342] ppid=1341 vsize=2568 CPUtime=0.01 /proc/1342/stat : 1342 (p2cudf-user-1.1) S 1341 1342 1268 34818 1268 4202496 373 0 0 0 1 0 0 0 25 0 1 0 112386826 2629632 270 1283457024 134512640 135304128 4288682960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1342/statm: 642 270 230 194 0 29 0 [pid=1343] ppid=1342 vsize=2572 CPUtime=0.02 /proc/1343/stat : 1343 (p2cudf) S 1342 1342 1268 34818 1268 4202496 392 3016 0 1 0 0 2 0 25 0 1 0 112386826 2633728 281 1283457024 134512640 135304128 4292634560 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1343/statm: 643 281 239 194 0 30 0 [pid=1356] ppid=1343 vsize=883524 CPUtime=4.77 /proc/1356/stat : 1356 (java) S 1343 1342 1268 34818 1268 4202496 75348 0 1 0 451 26 0 0 25 0 11 0 112386830 904728576 70671 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/1356/statm: 220881 70671 1772 10 0 217972 0 [pid=1356/tid=1357] ppid=1343 vsize=883524 CPUtime=2.34 /proc/1356/task/1357/stat : 1357 (java) R 1343 1342 1268 34818 1268 4202560 50258 0 1 0 217 17 0 0 25 0 11 0 112386830 904728576 70671 1283457024 134512640 134550932 4290278432 18446744073709551615 4096389211 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=1356/tid=1358] ppid=1343 vsize=883524 CPUtime=0.27 /proc/1356/task/1358/stat : 1358 (java) S 1343 1342 1268 34818 1268 4202560 12089 0 0 0 26 1 0 0 16 0 11 0 112386832 904728576 70671 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 0 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1359] ppid=1343 vsize=883524 CPUtime=0 /proc/1356/task/1359/stat : 1359 (java) S 1343 1342 1268 34818 1268 4202560 8 0 0 0 0 0 0 0 18 0 11 0 112386832 904728576 70671 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1360] ppid=1343 vsize=883524 CPUtime=0 /proc/1356/task/1360/stat : 1360 (java) S 1343 1342 1268 34818 1268 4202560 6 0 0 0 0 0 0 0 18 0 11 0 112386832 904728576 70671 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1361] ppid=1343 vsize=883524 CPUtime=0 /proc/1356/task/1361/stat : 1361 (java) S 1343 1342 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 25 0 11 0 112386835 904728576 70671 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1362] ppid=1343 vsize=883524 CPUtime=1.33 /proc/1356/task/1362/stat : 1362 (java) S 1343 1342 1268 34818 1268 4202560 9181 0 0 0 128 5 0 0 16 0 11 0 112386835 904728576 70671 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1363] ppid=1343 vsize=883524 CPUtime=0.79 /proc/1356/task/1363/stat : 1363 (java) S 1343 1342 1268 34818 1268 4202560 2741 0 0 0 79 0 0 0 15 0 11 0 112386835 904728576 70671 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1364] ppid=1343 vsize=883524 CPUtime=0 /proc/1356/task/1364/stat : 1364 (java) S 1343 1342 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 25 0 11 0 112386835 904728576 70671 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1365] ppid=1343 vsize=883524 CPUtime=0 /proc/1356/task/1365/stat : 1365 (java) S 1343 1342 1268 34818 1268 4202560 2 0 0 0 0 0 0 0 15 0 11 0 112386835 904728576 70671 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1366] ppid=1343 vsize=883524 CPUtime=0 /proc/1356/task/1366/stat : 1366 (java) S 1343 1342 1268 34818 1268 4202560 4 0 0 0 0 0 0 0 25 0 11 0 112387222 904728576 70671 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.8 Current children cumulated vsize (KiB) 888664 [startup+5.20545 s] /proc/loadavg: 0.31 0.08 0.02 2/53 1366 /proc/meminfo: memFree=6424/1048576 swapFree=0/0 [pid=1342] ppid=1341 vsize=2568 CPUtime=0.01 /proc/1342/stat : 1342 (p2cudf-user-1.1) S 1341 1342 1268 34818 1268 4202496 373 0 0 0 1 0 0 0 25 0 1 0 112386826 2629632 270 1283457024 134512640 135304128 4288682960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1342/statm: 642 270 230 194 0 29 0 [pid=1343] ppid=1342 vsize=2572 CPUtime=0.02 /proc/1343/stat : 1343 (p2cudf) S 1342 1342 1268 34818 1268 4202496 392 3016 0 1 0 0 2 0 25 0 1 0 112386826 2633728 281 1283457024 134512640 135304128 4292634560 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1343/statm: 643 281 239 194 0 30 0 [pid=1356] ppid=1343 vsize=883848 CPUtime=4.88 /proc/1356/stat : 1356 (java) S 1343 1342 1268 34818 1268 4202496 75371 0 1 0 462 26 0 0 25 0 12 0 112386830 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/1356/statm: 220962 70694 1773 10 0 218053 0 [pid=1356/tid=1357] ppid=1343 vsize=883848 CPUtime=2.34 /proc/1356/task/1357/stat : 1357 (java) S 1343 1342 1268 34818 1268 4202560 50266 0 1 0 217 17 0 0 25 0 12 0 112386830 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1358] ppid=1343 vsize=883848 CPUtime=0.27 /proc/1356/task/1358/stat : 1358 (java) S 1343 1342 1268 34818 1268 4202560 12089 0 0 0 26 1 0 0 16 0 12 0 112386832 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 0 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1359] ppid=1343 vsize=883848 CPUtime=0 /proc/1356/task/1359/stat : 1359 (java) S 1343 1342 1268 34818 1268 4202560 8 0 0 0 0 0 0 0 18 0 12 0 112386832 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1360] ppid=1343 vsize=883848 CPUtime=0 /proc/1356/task/1360/stat : 1360 (java) S 1343 1342 1268 34818 1268 4202560 6 0 0 0 0 0 0 0 18 0 12 0 112386832 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1361] ppid=1343 vsize=883848 CPUtime=0 /proc/1356/task/1361/stat : 1361 (java) S 1343 1342 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 25 0 12 0 112386835 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1362] ppid=1343 vsize=883848 CPUtime=1.44 /proc/1356/task/1362/stat : 1362 (java) S 1343 1342 1268 34818 1268 4202560 9192 0 0 0 139 5 0 0 16 0 12 0 112386835 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1363] ppid=1343 vsize=883848 CPUtime=0.79 /proc/1356/task/1363/stat : 1363 (java) S 1343 1342 1268 34818 1268 4202560 2741 0 0 0 79 0 0 0 15 0 12 0 112386835 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1364] ppid=1343 vsize=883848 CPUtime=0 /proc/1356/task/1364/stat : 1364 (java) S 1343 1342 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 25 0 12 0 112386835 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1365] ppid=1343 vsize=883848 CPUtime=0 /proc/1356/task/1365/stat : 1365 (java) S 1343 1342 1268 34818 1268 4202560 2 0 0 0 0 0 0 0 15 0 12 0 112386835 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1366] ppid=1343 vsize=883848 CPUtime=0 /proc/1356/task/1366/stat : 1366 (java) S 1343 1342 1268 34818 1268 4202560 4 0 0 0 0 0 0 0 25 0 12 0 112387222 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.91 Current children cumulated vsize (KiB) 888988 [startup+5.40544 s] /proc/loadavg: 0.31 0.08 0.02 2/53 1366 /proc/meminfo: memFree=6424/1048576 swapFree=0/0 [pid=1342] ppid=1341 vsize=2568 CPUtime=0.01 /proc/1342/stat : 1342 (p2cudf-user-1.1) S 1341 1342 1268 34818 1268 4202496 373 0 0 0 1 0 0 0 25 0 1 0 112386826 2629632 270 1283457024 134512640 135304128 4288682960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1342/statm: 642 270 230 194 0 29 0 [pid=1343] ppid=1342 vsize=2572 CPUtime=0.02 /proc/1343/stat : 1343 (p2cudf) S 1342 1342 1268 34818 1268 4202496 392 3016 0 1 0 0 2 0 25 0 1 0 112386826 2633728 281 1283457024 134512640 135304128 4292634560 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1343/statm: 643 281 239 194 0 30 0 [pid=1356] ppid=1343 vsize=883848 CPUtime=4.88 /proc/1356/stat : 1356 (java) S 1343 1342 1268 34818 1268 4202496 75371 0 1 0 462 26 0 0 25 0 12 0 112386830 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/1356/statm: 220962 70694 1773 10 0 218053 0 [pid=1356/tid=1357] ppid=1343 vsize=883848 CPUtime=2.34 /proc/1356/task/1357/stat : 1357 (java) S 1343 1342 1268 34818 1268 4202560 50266 0 1 0 217 17 0 0 25 0 12 0 112386830 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1358] ppid=1343 vsize=883848 CPUtime=0.27 /proc/1356/task/1358/stat : 1358 (java) S 1343 1342 1268 34818 1268 4202560 12089 0 0 0 26 1 0 0 16 0 12 0 112386832 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 0 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1359] ppid=1343 vsize=883848 CPUtime=0 /proc/1356/task/1359/stat : 1359 (java) S 1343 1342 1268 34818 1268 4202560 8 0 0 0 0 0 0 0 18 0 12 0 112386832 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1360] ppid=1343 vsize=883848 CPUtime=0 /proc/1356/task/1360/stat : 1360 (java) S 1343 1342 1268 34818 1268 4202560 6 0 0 0 0 0 0 0 18 0 12 0 112386832 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1361] ppid=1343 vsize=883848 CPUtime=0 /proc/1356/task/1361/stat : 1361 (java) S 1343 1342 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 25 0 12 0 112386835 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1362] ppid=1343 vsize=883848 CPUtime=1.44 /proc/1356/task/1362/stat : 1362 (java) S 1343 1342 1268 34818 1268 4202560 9192 0 0 0 139 5 0 0 16 0 12 0 112386835 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1363] ppid=1343 vsize=883848 CPUtime=0.79 /proc/1356/task/1363/stat : 1363 (java) S 1343 1342 1268 34818 1268 4202560 2741 0 0 0 79 0 0 0 15 0 12 0 112386835 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1364] ppid=1343 vsize=883848 CPUtime=0 /proc/1356/task/1364/stat : 1364 (java) S 1343 1342 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 25 0 12 0 112386835 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1365] ppid=1343 vsize=883848 CPUtime=0 /proc/1356/task/1365/stat : 1365 (java) S 1343 1342 1268 34818 1268 4202560 2 0 0 0 0 0 0 0 15 0 12 0 112386835 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1366] ppid=1343 vsize=883848 CPUtime=0 /proc/1356/task/1366/stat : 1366 (java) S 1343 1342 1268 34818 1268 4202560 4 0 0 0 0 0 0 0 25 0 12 0 112387222 905060352 70694 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.91 Current children cumulated vsize (KiB) 888988 [startup+5.5075 s] /proc/loadavg: 0.31 0.08 0.02 2/54 1367 /proc/meminfo: memFree=6292/1048576 swapFree=0/0 [pid=1342] ppid=1341 vsize=2568 CPUtime=0.01 /proc/1342/stat : 1342 (p2cudf-user-1.1) S 1341 1342 1268 34818 1268 4202496 373 0 0 0 1 0 0 0 25 0 1 0 112386826 2629632 270 1283457024 134512640 135304128 4288682960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1342/statm: 642 270 230 194 0 29 0 [pid=1343] ppid=1342 vsize=2572 CPUtime=0.02 /proc/1343/stat : 1343 (p2cudf) S 1342 1342 1268 34818 1268 4202496 392 3016 0 1 0 0 2 0 25 0 1 0 112386826 2633728 281 1283457024 134512640 135304128 4292634560 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1343/statm: 643 281 239 194 0 30 0 [pid=1356] ppid=1343 vsize=883848 CPUtime=4.95 /proc/1356/stat : 1356 (java) S 1343 1342 1268 34818 1268 4202496 75373 0 1 0 466 29 0 0 25 0 12 0 112386830 905060352 70696 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/1356/statm: 220962 70696 1773 10 0 218053 0 [pid=1356/tid=1357] ppid=1343 vsize=883848 CPUtime=2.34 /proc/1356/task/1357/stat : 1357 (java) S 1343 1342 1268 34818 1268 4202560 50266 0 1 0 217 17 0 0 25 0 12 0 112386830 905060352 70696 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1358] ppid=1343 vsize=883848 CPUtime=0.27 /proc/1356/task/1358/stat : 1358 (java) S 1343 1342 1268 34818 1268 4202560 12089 0 0 0 26 1 0 0 16 0 12 0 112386832 905060352 70696 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 0 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1359] ppid=1343 vsize=883848 CPUtime=0 /proc/1356/task/1359/stat : 1359 (java) S 1343 1342 1268 34818 1268 4202560 8 0 0 0 0 0 0 0 18 0 12 0 112386832 905060352 70696 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1360] ppid=1343 vsize=883848 CPUtime=0 /proc/1356/task/1360/stat : 1360 (java) S 1343 1342 1268 34818 1268 4202560 6 0 0 0 0 0 0 0 18 0 12 0 112386832 905060352 70696 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1361] ppid=1343 vsize=883848 CPUtime=0 /proc/1356/task/1361/stat : 1361 (java) S 1343 1342 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 25 0 12 0 112386835 905060352 70696 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1362] ppid=1343 vsize=883848 CPUtime=1.45 /proc/1356/task/1362/stat : 1362 (java) S 1343 1342 1268 34818 1268 4202560 9192 0 0 0 140 5 0 0 16 0 12 0 112386835 905060352 70696 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1363] ppid=1343 vsize=883848 CPUtime=0.79 /proc/1356/task/1363/stat : 1363 (java) S 1343 1342 1268 34818 1268 4202560 2741 0 0 0 79 0 0 0 15 0 12 0 112386835 905060352 70696 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1364] ppid=1343 vsize=883848 CPUtime=0 /proc/1356/task/1364/stat : 1364 (java) S 1343 1342 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 25 0 12 0 112386835 905060352 70696 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1365] ppid=1343 vsize=883848 CPUtime=0 /proc/1356/task/1365/stat : 1365 (java) S 1343 1342 1268 34818 1268 4202560 2 0 0 0 0 0 0 0 15 0 12 0 112386835 905060352 70696 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1366] ppid=1343 vsize=883848 CPUtime=0 /proc/1356/task/1366/stat : 1366 (java) S 1343 1342 1268 34818 1268 4202560 4 0 0 0 0 0 0 0 25 0 12 0 112387222 905060352 70696 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 18446612132804081728 0 0 -1 0 0 0 0 [pid=1356/tid=1367] ppid=1343 vsize=883848 CPUtime=0.05 /proc/1356/task/1367/stat : 1367 (java) R 1343 1342 1268 34818 1268 4202560 6 0 0 0 2 3 0 0 20 0 12 0 112387319 905060352 70696 1283457024 134512640 134550932 4290278432 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.98 Current children cumulated vsize (KiB) 888988 Child status: 0 Real time (s): 5.60801 CPU time (s): 5.04831 CPU user time (s): 4.71629 CPU system time (s): 0.33202 CPU usage (%): 90.0197 Max. virtual memory (cumulated for all children) (KiB): 889872 getrusage(RUSAGE_CHILDREN,...) data: user time used= 4.71629 system time used= 0.33202 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 79183 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= 722 involuntary context switches= 707 runsolver used 0 second user time and 0 second system time The end