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/29180036-5408-11df-9f57-00163e7a6f5e.cudf.upgrades.log.runsolver ./p2cudf-user-1.12 /home/misc2010/data/misc4/upgrades//29180036-5408-11df-9f57-00163e7a6f5e.cudf /home/misc2010/tmp/201103071905/p2cudf-user-1.12/29180036-5408-11df-9f57-00163e7a6f5e.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.79 0.21 0.07 5/42 1922 /proc/meminfo: memFree=299232/1048576 swapFree=0/0 [pid=1920] ppid=1919 vsize=2564 CPUtime=0 /proc/1920/stat : 1920 (p2cudf-user-1.1) S 1919 1920 1268 34818 1268 4202496 374 0 0 0 0 0 0 0 18 0 1 0 112389803 2625536 270 1283457024 134512640 135304128 4288035632 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1920/statm: 641 270 230 194 0 28 0 [pid=1921] ppid=1920 vsize=2568 CPUtime=0 /proc/1921/stat : 1921 (p2cudf) R 1920 1920 1268 34818 1268 4202496 345 0 0 0 0 0 0 0 25 0 1 0 112389803 2629632 271 1283457024 134512640 135304128 4294650288 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/1921/statm: 642 271 231 194 0 29 0 [pid=1922] ppid=1921 vsize=2568 CPUtime=0 /proc/1922/stat : 1922 (p2cudf) R 1921 1920 1268 34818 1268 4202560 0 0 0 0 0 0 0 0 25 0 1 0 112389803 2629632 40 1283457024 134512640 135304128 4294650288 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/1922/statm: 642 40 0 194 0 29 0 [startup+0.145165 s] /proc/loadavg: 0.79 0.21 0.07 5/42 1922 /proc/meminfo: memFree=299232/1048576 swapFree=0/0 [pid=1920] ppid=1919 vsize=2564 CPUtime=0 /proc/1920/stat : 1920 (p2cudf-user-1.1) S 1919 1920 1268 34818 1268 4202496 374 0 0 0 0 0 0 0 18 0 1 0 112389803 2625536 270 1283457024 134512640 135304128 4288035632 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1920/statm: 641 270 230 194 0 28 0 [pid=1921] ppid=1920 vsize=2572 CPUtime=0.02 /proc/1921/stat : 1921 (p2cudf) S 1920 1920 1268 34818 1268 4202496 391 3018 0 1 0 0 2 0 25 0 1 0 112389803 2633728 280 1283457024 134512640 135304128 4294650288 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1921/statm: 643 280 239 194 0 30 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5136 [startup+0.205195 s] /proc/loadavg: 0.79 0.21 0.07 5/42 1922 /proc/meminfo: memFree=299232/1048576 swapFree=0/0 [pid=1920] ppid=1919 vsize=2564 CPUtime=0 /proc/1920/stat : 1920 (p2cudf-user-1.1) S 1919 1920 1268 34818 1268 4202496 374 0 0 0 0 0 0 0 18 0 1 0 112389803 2625536 270 1283457024 134512640 135304128 4288035632 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1920/statm: 641 270 230 194 0 28 0 [pid=1921] ppid=1920 vsize=2572 CPUtime=0.02 /proc/1921/stat : 1921 (p2cudf) S 1920 1920 1268 34818 1268 4202496 391 3018 0 1 0 0 2 0 25 0 1 0 112389803 2633728 280 1283457024 134512640 135304128 4294650288 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1921/statm: 643 280 239 194 0 30 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5136 [startup+0.305211 s] /proc/loadavg: 0.79 0.21 0.07 5/42 1922 /proc/meminfo: memFree=299232/1048576 swapFree=0/0 [pid=1920] ppid=1919 vsize=2564 CPUtime=0 /proc/1920/stat : 1920 (p2cudf-user-1.1) S 1919 1920 1268 34818 1268 4202496 374 0 0 0 0 0 0 0 18 0 1 0 112389803 2625536 270 1283457024 134512640 135304128 4288035632 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1920/statm: 641 270 230 194 0 28 0 [pid=1921] ppid=1920 vsize=2572 CPUtime=0.02 /proc/1921/stat : 1921 (p2cudf) S 1920 1920 1268 34818 1268 4202496 391 3018 0 1 0 0 2 0 25 0 1 0 112389803 2633728 280 1283457024 134512640 135304128 4294650288 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1921/statm: 643 280 239 194 0 30 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5136 [startup+0.705345 s] /proc/loadavg: 0.79 0.21 0.07 5/42 1922 /proc/meminfo: memFree=299232/1048576 swapFree=0/0 [pid=1920] ppid=1919 vsize=2564 CPUtime=0 /proc/1920/stat : 1920 (p2cudf-user-1.1) S 1919 1920 1268 34818 1268 4202496 374 0 0 0 0 0 0 0 18 0 1 0 112389803 2625536 270 1283457024 134512640 135304128 4288035632 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1920/statm: 641 270 230 194 0 28 0 [pid=1921] ppid=1920 vsize=2572 CPUtime=0.02 /proc/1921/stat : 1921 (p2cudf) S 1920 1920 1268 34818 1268 4202496 391 3018 0 1 0 0 2 0 25 0 1 0 112389803 2633728 280 1283457024 134512640 135304128 4294650288 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1921/statm: 643 280 239 194 0 30 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5136 [startup+1.50561 s] /proc/loadavg: 0.79 0.21 0.07 3/52 1943 /proc/meminfo: memFree=244208/1048576 swapFree=0/0 [pid=1920] ppid=1919 vsize=2564 CPUtime=0 /proc/1920/stat : 1920 (p2cudf-user-1.1) S 1919 1920 1268 34818 1268 4202496 374 0 0 0 0 0 0 0 18 0 1 0 112389803 2625536 270 1283457024 134512640 135304128 4288035632 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1920/statm: 641 270 230 194 0 28 0 [pid=1921] ppid=1920 vsize=2572 CPUtime=0.02 /proc/1921/stat : 1921 (p2cudf) S 1920 1920 1268 34818 1268 4202496 391 3018 0 1 0 0 2 0 25 0 1 0 112389803 2633728 280 1283457024 134512640 135304128 4294650288 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1921/statm: 643 280 239 194 0 30 0 [pid=1934] ppid=1921 vsize=878912 CPUtime=1.46 /proc/1934/stat : 1934 (java) S 1921 1920 1268 34818 1268 4202496 22241 0 1 0 138 8 0 0 25 0 10 0 112389807 900005888 19513 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/1934/statm: 219728 19513 1723 10 0 216819 0 [pid=1934/tid=1935] ppid=1921 vsize=878912 CPUtime=0.79 /proc/1934/task/1935/stat : 1935 (java) R 1921 1920 1268 34818 1268 4202560 12448 0 1 0 73 6 0 0 25 0 10 0 112389807 900005888 19513 1283457024 134512640 134550932 4293978016 18446744073709551615 4094812894 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=1934/tid=1936] ppid=1921 vsize=878912 CPUtime=0 /proc/1934/task/1936/stat : 1936 (java) S 1921 1920 1268 34818 1268 4202560 6 0 0 0 0 0 0 0 18 0 10 0 112389809 900005888 19513 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 0 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1937] ppid=1921 vsize=878912 CPUtime=0 /proc/1934/task/1937/stat : 1937 (java) S 1921 1920 1268 34818 1268 4202560 8 0 0 0 0 0 0 0 25 0 10 0 112389809 900005888 19513 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1938] ppid=1921 vsize=878912 CPUtime=0 /proc/1934/task/1938/stat : 1938 (java) S 1921 1920 1268 34818 1268 4202560 4 0 0 0 0 0 0 0 25 0 10 0 112389809 900005888 19513 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1939] ppid=1921 vsize=878912 CPUtime=0 /proc/1934/task/1939/stat : 1939 (java) S 1921 1920 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 25 0 10 0 112389812 900005888 19513 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1940] ppid=1921 vsize=878912 CPUtime=0.54 /proc/1934/task/1940/stat : 1940 (java) R 1921 1920 1268 34818 1268 4202560 7597 0 0 0 53 1 0 0 24 0 10 0 112389812 900005888 19513 1283457024 134512640 134550932 4293978016 18446744073709551615 4148296771 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=1934/tid=1941] ppid=1921 vsize=878912 CPUtime=0.11 /proc/1934/task/1941/stat : 1941 (java) S 1921 1920 1268 34818 1268 4202560 1119 0 0 0 10 1 0 0 19 0 10 0 112389812 900005888 19513 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1942] ppid=1921 vsize=878912 CPUtime=0 /proc/1934/task/1942/stat : 1942 (java) S 1921 1920 1268 34818 1268 4202560 0 0 0 0 0 0 0 0 25 0 10 0 112389812 900005888 19513 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1943] ppid=1921 vsize=878912 CPUtime=0 /proc/1934/task/1943/stat : 1943 (java) S 1921 1920 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 15 0 10 0 112389812 900005888 19513 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.48 Current children cumulated vsize (KiB) 884048 [startup+3.10608 s] /proc/loadavg: 0.79 0.21 0.07 3/52 1943 /proc/meminfo: memFree=157656/1048576 swapFree=0/0 [pid=1920] ppid=1919 vsize=2564 CPUtime=0 /proc/1920/stat : 1920 (p2cudf-user-1.1) S 1919 1920 1268 34818 1268 4202496 374 0 0 0 0 0 0 0 18 0 1 0 112389803 2625536 270 1283457024 134512640 135304128 4288035632 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1920/statm: 641 270 230 194 0 28 0 [pid=1921] ppid=1920 vsize=2572 CPUtime=0.02 /proc/1921/stat : 1921 (p2cudf) S 1920 1920 1268 34818 1268 4202496 391 3018 0 1 0 0 2 0 25 0 1 0 112389803 2633728 280 1283457024 134512640 135304128 4294650288 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1921/statm: 643 280 239 194 0 30 0 [pid=1934] ppid=1921 vsize=878912 CPUtime=3.06 /proc/1934/stat : 1934 (java) S 1921 1920 1268 34818 1268 4202496 46057 0 1 0 281 25 0 0 25 0 10 0 112389807 900005888 43329 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/1934/statm: 219728 43329 1744 10 0 216819 0 [pid=1934/tid=1935] ppid=1921 vsize=878912 CPUtime=1.78 /proc/1934/task/1935/stat : 1935 (java) R 1921 1920 1268 34818 1268 4202560 36174 0 1 0 156 22 0 0 25 0 10 0 112389807 900005888 43329 1283457024 134512640 134550932 4293978016 18446744073709551615 4152358431 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=1934/tid=1936] ppid=1921 vsize=878912 CPUtime=0 /proc/1934/task/1936/stat : 1936 (java) R 1921 1920 1268 34818 1268 4202560 6 0 0 0 0 0 0 0 18 0 10 0 112389809 900005888 43329 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 0 0 16800975 0 0 0 -1 0 0 0 0 [pid=1934/tid=1937] ppid=1921 vsize=878912 CPUtime=0 /proc/1934/task/1937/stat : 1937 (java) S 1921 1920 1268 34818 1268 4202560 8 0 0 0 0 0 0 0 25 0 10 0 112389809 900005888 43329 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1938] ppid=1921 vsize=878912 CPUtime=0 /proc/1934/task/1938/stat : 1938 (java) S 1921 1920 1268 34818 1268 4202560 4 0 0 0 0 0 0 0 25 0 10 0 112389809 900005888 43329 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1939] ppid=1921 vsize=878912 CPUtime=0 /proc/1934/task/1939/stat : 1939 (java) S 1921 1920 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 25 0 10 0 112389812 900005888 43329 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1940] ppid=1921 vsize=878912 CPUtime=0.71 /proc/1934/task/1940/stat : 1940 (java) R 1921 1920 1268 34818 1268 4202560 7634 0 0 0 70 1 0 0 24 0 10 0 112389812 900005888 43329 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=1934/tid=1941] ppid=1921 vsize=878912 CPUtime=0.55 /proc/1934/task/1941/stat : 1941 (java) R 1921 1920 1268 34818 1268 4202560 1172 0 0 0 54 1 0 0 16 0 10 0 112389812 900005888 43329 1283457024 134512640 134550932 4293978016 18446744073709551615 4151747818 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=1934/tid=1942] ppid=1921 vsize=878912 CPUtime=0 /proc/1934/task/1942/stat : 1942 (java) S 1921 1920 1268 34818 1268 4202560 0 0 0 0 0 0 0 0 25 0 10 0 112389812 900005888 43329 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1943] ppid=1921 vsize=878912 CPUtime=0 /proc/1934/task/1943/stat : 1943 (java) S 1921 1920 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 15 0 10 0 112389812 900005888 43329 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.08 Current children cumulated vsize (KiB) 884048 Solver just ended. Dumping a history of the last processes samples [startup+3.20608 s] /proc/loadavg: 0.79 0.21 0.07 3/52 1943 /proc/meminfo: memFree=157656/1048576 swapFree=0/0 [pid=1920] ppid=1919 vsize=2564 CPUtime=0 /proc/1920/stat : 1920 (p2cudf-user-1.1) S 1919 1920 1268 34818 1268 4202496 374 0 0 0 0 0 0 0 18 0 1 0 112389803 2625536 270 1283457024 134512640 135304128 4288035632 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1920/statm: 641 270 230 194 0 28 0 [pid=1921] ppid=1920 vsize=2572 CPUtime=0.02 /proc/1921/stat : 1921 (p2cudf) S 1920 1920 1268 34818 1268 4202496 391 3018 0 1 0 0 2 0 25 0 1 0 112389803 2633728 280 1283457024 134512640 135304128 4294650288 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1921/statm: 643 280 239 194 0 30 0 [pid=1934] ppid=1921 vsize=887952 CPUtime=3.16 /proc/1934/stat : 1934 (java) S 1921 1920 1268 34818 1268 4202496 48455 0 1 0 291 25 0 0 25 0 10 0 112389807 909262848 45210 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/1934/statm: 221988 45210 1746 10 0 219079 0 [pid=1934/tid=1935] ppid=1921 vsize=887952 CPUtime=1.78 /proc/1934/task/1935/stat : 1935 (java) R 1921 1920 1268 34818 1268 4202560 36174 0 1 0 156 22 0 0 25 0 10 0 112389807 909262848 45210 1283457024 134512640 134550932 4293978016 18446744073709551615 4152358431 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=1934/tid=1936] ppid=1921 vsize=887952 CPUtime=0 /proc/1934/task/1936/stat : 1936 (java) R 1921 1920 1268 34818 1268 4202560 6 0 0 0 0 0 0 0 18 0 10 0 112389809 909262848 45210 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 0 0 16800975 0 0 0 -1 0 0 0 0 [pid=1934/tid=1937] ppid=1921 vsize=887952 CPUtime=0 /proc/1934/task/1937/stat : 1937 (java) S 1921 1920 1268 34818 1268 4202560 8 0 0 0 0 0 0 0 25 0 10 0 112389809 909262848 45210 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1938] ppid=1921 vsize=887952 CPUtime=0 /proc/1934/task/1938/stat : 1938 (java) S 1921 1920 1268 34818 1268 4202560 4 0 0 0 0 0 0 0 25 0 10 0 112389809 909262848 45210 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1939] ppid=1921 vsize=887952 CPUtime=0 /proc/1934/task/1939/stat : 1939 (java) S 1921 1920 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 25 0 10 0 112389812 909262848 45210 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1940] ppid=1921 vsize=887952 CPUtime=0.71 /proc/1934/task/1940/stat : 1940 (java) R 1921 1920 1268 34818 1268 4202560 7634 0 0 0 70 1 0 0 24 0 10 0 112389812 909262848 45210 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=1934/tid=1941] ppid=1921 vsize=887952 CPUtime=0.65 /proc/1934/task/1941/stat : 1941 (java) R 1921 1920 1268 34818 1268 4202560 3570 0 0 0 64 1 0 0 16 0 10 0 112389812 909262848 45210 1283457024 134512640 134550932 4293978016 18446744073709551615 4152038432 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=1934/tid=1942] ppid=1921 vsize=887952 CPUtime=0 /proc/1934/task/1942/stat : 1942 (java) S 1921 1920 1268 34818 1268 4202560 0 0 0 0 0 0 0 0 25 0 10 0 112389812 909262848 45210 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1943] ppid=1921 vsize=887952 CPUtime=0 /proc/1934/task/1943/stat : 1943 (java) S 1921 1920 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 15 0 10 0 112389812 909262848 45210 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.18 Current children cumulated vsize (KiB) 893088 [startup+4.00628 s] /proc/loadavg: 0.88 0.24 0.08 5/52 1943 /proc/meminfo: memFree=118720/1048576 swapFree=0/0 [pid=1920] ppid=1919 vsize=2564 CPUtime=0 /proc/1920/stat : 1920 (p2cudf-user-1.1) S 1919 1920 1268 34818 1268 4202496 374 0 0 0 0 0 0 0 18 0 1 0 112389803 2625536 270 1283457024 134512640 135304128 4288035632 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1920/statm: 641 270 230 194 0 28 0 [pid=1921] ppid=1920 vsize=2572 CPUtime=0.02 /proc/1921/stat : 1921 (p2cudf) S 1920 1920 1268 34818 1268 4202496 391 3018 0 1 0 0 2 0 25 0 1 0 112389803 2633728 280 1283457024 134512640 135304128 4294650288 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1921/statm: 643 280 239 194 0 30 0 [pid=1934] ppid=1921 vsize=903380 CPUtime=3.96 /proc/1934/stat : 1934 (java) S 1921 1920 1268 34818 1268 4202496 56333 0 1 0 369 27 0 0 25 0 10 0 112389807 925061120 48913 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/1934/statm: 225845 48913 1748 10 0 222936 0 [pid=1934/tid=1935] ppid=1921 vsize=903380 CPUtime=1.78 /proc/1934/task/1935/stat : 1935 (java) R 1921 1920 1268 34818 1268 4202560 36174 0 1 0 156 22 0 0 25 0 10 0 112389807 925061120 48913 1283457024 134512640 134550932 4293978016 18446744073709551615 4152358431 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=1934/tid=1936] ppid=1921 vsize=903380 CPUtime=0 /proc/1934/task/1936/stat : 1936 (java) R 1921 1920 1268 34818 1268 4202560 6 0 0 0 0 0 0 0 18 0 10 0 112389809 925061120 48913 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 0 0 16800975 0 0 0 -1 0 0 0 0 [pid=1934/tid=1937] ppid=1921 vsize=903380 CPUtime=0 /proc/1934/task/1937/stat : 1937 (java) S 1921 1920 1268 34818 1268 4202560 8 0 0 0 0 0 0 0 25 0 10 0 112389809 925061120 48913 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1938] ppid=1921 vsize=903380 CPUtime=0 /proc/1934/task/1938/stat : 1938 (java) S 1921 1920 1268 34818 1268 4202560 4 0 0 0 0 0 0 0 25 0 10 0 112389809 925061120 48913 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1939] ppid=1921 vsize=903380 CPUtime=0 /proc/1934/task/1939/stat : 1939 (java) S 1921 1920 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 25 0 10 0 112389812 925061120 48913 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1940] ppid=1921 vsize=903380 CPUtime=0.71 /proc/1934/task/1940/stat : 1940 (java) R 1921 1920 1268 34818 1268 4202560 7634 0 0 0 70 1 0 0 24 0 10 0 112389812 925061120 48913 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=1934/tid=1941] ppid=1921 vsize=903380 CPUtime=1.45 /proc/1934/task/1941/stat : 1941 (java) R 1921 1920 1268 34818 1268 4202560 11448 0 0 0 142 3 0 0 16 0 10 0 112389812 925061120 48913 1283457024 134512640 134550932 4293978016 18446744073709551615 4149688113 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=1934/tid=1942] ppid=1921 vsize=903380 CPUtime=0 /proc/1934/task/1942/stat : 1942 (java) S 1921 1920 1268 34818 1268 4202560 0 0 0 0 0 0 0 0 25 0 10 0 112389812 925061120 48913 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1943] ppid=1921 vsize=903380 CPUtime=0 /proc/1934/task/1943/stat : 1943 (java) S 1921 1920 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 15 0 10 0 112389812 925061120 48913 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.98 Current children cumulated vsize (KiB) 908516 [startup+4.80543 s] /proc/loadavg: 0.88 0.24 0.08 3/52 1943 /proc/meminfo: memFree=98260/1048576 swapFree=0/0 [pid=1920] ppid=1919 vsize=2564 CPUtime=0 /proc/1920/stat : 1920 (p2cudf-user-1.1) S 1919 1920 1268 34818 1268 4202496 374 0 0 0 0 0 0 0 18 0 1 0 112389803 2625536 270 1283457024 134512640 135304128 4288035632 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1920/statm: 641 270 230 194 0 28 0 [pid=1921] ppid=1920 vsize=2572 CPUtime=0.02 /proc/1921/stat : 1921 (p2cudf) S 1920 1920 1268 34818 1268 4202496 391 3018 0 1 0 0 2 0 25 0 1 0 112389803 2633728 280 1283457024 134512640 135304128 4294650288 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1921/statm: 643 280 239 194 0 30 0 [pid=1934] ppid=1921 vsize=903704 CPUtime=4.63 /proc/1934/stat : 1934 (java) S 1921 1920 1268 34818 1268 4202496 65250 0 1 0 434 29 0 0 25 0 11 0 112389807 925392896 57830 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/1934/statm: 225926 57830 1753 10 0 223017 0 [pid=1934/tid=1935] ppid=1921 vsize=903704 CPUtime=2.06 /proc/1934/task/1935/stat : 1935 (java) S 1921 1920 1268 34818 1268 4202560 45036 0 1 0 182 24 0 0 25 0 11 0 112389807 925392896 57830 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1936] ppid=1921 vsize=903704 CPUtime=0 /proc/1934/task/1936/stat : 1936 (java) S 1921 1920 1268 34818 1268 4202560 6 0 0 0 0 0 0 0 18 0 11 0 112389809 925392896 57830 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 0 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1937] ppid=1921 vsize=903704 CPUtime=0 /proc/1934/task/1937/stat : 1937 (java) S 1921 1920 1268 34818 1268 4202560 8 0 0 0 0 0 0 0 25 0 11 0 112389809 925392896 57830 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1938] ppid=1921 vsize=903704 CPUtime=0 /proc/1934/task/1938/stat : 1938 (java) S 1921 1920 1268 34818 1268 4202560 4 0 0 0 0 0 0 0 25 0 11 0 112389809 925392896 57830 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1939] ppid=1921 vsize=903704 CPUtime=0 /proc/1934/task/1939/stat : 1939 (java) S 1921 1920 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 25 0 11 0 112389812 925392896 57830 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1940] ppid=1921 vsize=903704 CPUtime=0.83 /proc/1934/task/1940/stat : 1940 (java) S 1921 1920 1268 34818 1268 4202560 7645 0 0 0 82 1 0 0 25 0 11 0 112389812 925392896 57830 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1941] ppid=1921 vsize=903704 CPUtime=1.72 /proc/1934/task/1941/stat : 1941 (java) S 1921 1920 1268 34818 1268 4202560 11488 0 0 0 169 3 0 0 15 0 11 0 112389812 925392896 57830 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1942] ppid=1921 vsize=903704 CPUtime=0 /proc/1934/task/1942/stat : 1942 (java) S 1921 1920 1268 34818 1268 4202560 0 0 0 0 0 0 0 0 25 0 11 0 112389812 925392896 57830 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1943] ppid=1921 vsize=903704 CPUtime=0 /proc/1934/task/1943/stat : 1943 (java) S 1921 1920 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 15 0 11 0 112389812 925392896 57830 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.65 Current children cumulated vsize (KiB) 908840 [startup+5.20546 s] /proc/loadavg: 0.88 0.24 0.08 3/52 1943 /proc/meminfo: memFree=98260/1048576 swapFree=0/0 [pid=1920] ppid=1919 vsize=2564 CPUtime=0 /proc/1920/stat : 1920 (p2cudf-user-1.1) S 1919 1920 1268 34818 1268 4202496 374 0 0 0 0 0 0 0 18 0 1 0 112389803 2625536 270 1283457024 134512640 135304128 4288035632 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1920/statm: 641 270 230 194 0 28 0 [pid=1921] ppid=1920 vsize=2572 CPUtime=0.02 /proc/1921/stat : 1921 (p2cudf) S 1920 1920 1268 34818 1268 4202496 391 3018 0 1 0 0 2 0 25 0 1 0 112389803 2633728 280 1283457024 134512640 135304128 4294650288 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/1921/statm: 643 280 239 194 0 30 0 [pid=1934] ppid=1921 vsize=903704 CPUtime=4.63 /proc/1934/stat : 1934 (java) S 1921 1920 1268 34818 1268 4202496 65253 0 1 0 434 29 0 0 25 0 10 0 112389807 925392896 57833 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/1934/statm: 225926 57833 1755 10 0 223017 0 [pid=1934/tid=1935] ppid=1921 vsize=903704 CPUtime=2.06 /proc/1934/task/1935/stat : 1935 (java) S 1921 1920 1268 34818 1268 4202560 45036 0 1 0 182 24 0 0 20 0 10 0 112389807 925392896 57833 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1936] ppid=1921 vsize=903704 CPUtime=0 /proc/1934/task/1936/stat : 1936 (java) S 1921 1920 1268 34818 1268 4202560 7 0 0 0 0 0 0 0 16 0 10 0 112389809 925392896 57833 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 0 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1937] ppid=1921 vsize=903704 CPUtime=0 /proc/1934/task/1937/stat : 1937 (java) S 1921 1920 1268 34818 1268 4202560 8 0 0 0 0 0 0 0 25 0 10 0 112389809 925392896 57833 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1938] ppid=1921 vsize=903704 CPUtime=0 /proc/1934/task/1938/stat : 1938 (java) S 1921 1920 1268 34818 1268 4202560 4 0 0 0 0 0 0 0 25 0 10 0 112389809 925392896 57833 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1939] ppid=1921 vsize=903704 CPUtime=0 /proc/1934/task/1939/stat : 1939 (java) S 1921 1920 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 25 0 10 0 112389812 925392896 57833 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1940] ppid=1921 vsize=903704 CPUtime=0.83 /proc/1934/task/1940/stat : 1940 (java) S 1921 1920 1268 34818 1268 4202560 7645 0 0 0 82 1 0 0 25 0 10 0 112389812 925392896 57833 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1941] ppid=1921 vsize=903704 CPUtime=1.72 /proc/1934/task/1941/stat : 1941 (java) S 1921 1920 1268 34818 1268 4202560 11488 0 0 0 169 3 0 0 15 0 10 0 112389812 925392896 57833 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1942] ppid=1921 vsize=903704 CPUtime=0 /proc/1934/task/1942/stat : 1942 (java) S 1921 1920 1268 34818 1268 4202560 0 0 0 0 0 0 0 0 25 0 10 0 112389812 925392896 57833 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 [pid=1934/tid=1943] ppid=1921 vsize=903704 CPUtime=0 /proc/1934/task/1943/stat : 1943 (java) S 1921 1920 1268 34818 1268 4202560 1 0 0 0 0 0 0 0 15 0 10 0 112389812 925392896 57833 1283457024 134512640 134550932 4293978016 18446744073709551615 4294960130 0 4 0 16800975 18446612132804082624 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.65 Current children cumulated vsize (KiB) 908840 Child status: 0 Real time (s): 5.25525 CPU time (s): 4.68429 CPU user time (s): 4.36027 CPU system time (s): 0.32402 CPU usage (%): 89.1354 Max. virtual memory (cumulated for all children) (KiB): 910056 getrusage(RUSAGE_CHILDREN,...) data: user time used= 4.36027 system time used= 0.32402 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 69062 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= 330 involuntary context switches= 315 runsolver used 0 second user time and 0.012 second system time The end