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/201108291842/p2cudf-user-1.13/7f80e4f0-4fe9-11e0-acd7-00163e1e087d.cudf.dudf-real.log.runsolver ./p2cudf-user-1.13 /home/misc2010/data/2011/dudf-real//7f80e4f0-4fe9-11e0-acd7-00163e1e087d.cudf /home/misc2010/tmp/201108291842/p2cudf-user-1.13/7f80e4f0-4fe9-11e0-acd7-00163e1e087d.cudf.dudf-real.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: 1.45 1.27 1.18 5/34 27075 /proc/meminfo: memFree=695044/1048576 swapFree=0/0 [pid=27074] ppid=27073 vsize=2572 CPUtime=0 /proc/27074/stat : 27074 (p2cudf-user-1.1) R 27073 27074 17511 34816 17511 4202496 357 0 0 0 0 0 0 0 25 0 1 0 46460310 2633728 271 1283457024 134512640 135304128 4292400976 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/27074/statm: 643 271 230 194 0 30 0 [pid=27075] ppid=27074 vsize=2572 CPUtime=0 /proc/27075/stat : 27075 (p2cudf-user-1.1) R 27074 27074 17511 34816 17511 4202560 0 0 0 0 0 0 0 0 25 0 1 0 46460310 2633728 41 1283457024 134512640 135304128 4292400976 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/27075/statm: 643 41 0 194 0 30 0 [startup+0.14548 s] /proc/loadavg: 1.45 1.27 1.18 5/34 27075 /proc/meminfo: memFree=695044/1048576 swapFree=0/0 [pid=27074] ppid=27073 vsize=2572 CPUtime=0 /proc/27074/stat : 27074 (p2cudf-user-1.1) S 27073 27074 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 25 0 1 0 46460310 2633728 271 1283457024 134512640 135304128 4292400976 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27074/statm: 643 271 230 194 0 30 0 [pid=27075] ppid=27074 vsize=2568 CPUtime=0.03 /proc/27075/stat : 27075 (p2cudf) S 27074 27074 17511 34816 17511 4202496 388 3014 0 1 0 0 3 0 25 0 1 0 46460310 2629632 279 1283457024 134512640 135304128 4292530080 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27075/statm: 642 279 239 194 0 29 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5140 [startup+0.205468 s] /proc/loadavg: 1.45 1.27 1.18 5/34 27075 /proc/meminfo: memFree=695044/1048576 swapFree=0/0 [pid=27074] ppid=27073 vsize=2572 CPUtime=0 /proc/27074/stat : 27074 (p2cudf-user-1.1) S 27073 27074 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 25 0 1 0 46460310 2633728 271 1283457024 134512640 135304128 4292400976 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27074/statm: 643 271 230 194 0 30 0 [pid=27075] ppid=27074 vsize=2568 CPUtime=0.03 /proc/27075/stat : 27075 (p2cudf) S 27074 27074 17511 34816 17511 4202496 388 3014 0 1 0 0 3 0 25 0 1 0 46460310 2629632 279 1283457024 134512640 135304128 4292530080 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27075/statm: 642 279 239 194 0 29 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5140 [startup+0.305491 s] /proc/loadavg: 1.45 1.27 1.18 5/34 27075 /proc/meminfo: memFree=695044/1048576 swapFree=0/0 [pid=27074] ppid=27073 vsize=2572 CPUtime=0 /proc/27074/stat : 27074 (p2cudf-user-1.1) S 27073 27074 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 25 0 1 0 46460310 2633728 271 1283457024 134512640 135304128 4292400976 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27074/statm: 643 271 230 194 0 30 0 [pid=27075] ppid=27074 vsize=2568 CPUtime=0.03 /proc/27075/stat : 27075 (p2cudf) S 27074 27074 17511 34816 17511 4202496 388 3014 0 1 0 0 3 0 25 0 1 0 46460310 2629632 279 1283457024 134512640 135304128 4292530080 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27075/statm: 642 279 239 194 0 29 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5140 [startup+0.705591 s] /proc/loadavg: 1.45 1.27 1.18 5/34 27075 /proc/meminfo: memFree=695044/1048576 swapFree=0/0 [pid=27074] ppid=27073 vsize=2572 CPUtime=0 /proc/27074/stat : 27074 (p2cudf-user-1.1) S 27073 27074 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 25 0 1 0 46460310 2633728 271 1283457024 134512640 135304128 4292400976 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27074/statm: 643 271 230 194 0 30 0 [pid=27075] ppid=27074 vsize=2568 CPUtime=0.03 /proc/27075/stat : 27075 (p2cudf) S 27074 27074 17511 34816 17511 4202496 388 3014 0 1 0 0 3 0 25 0 1 0 46460310 2629632 279 1283457024 134512640 135304128 4292530080 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27075/statm: 642 279 239 194 0 29 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5140 [startup+1.50585 s] /proc/loadavg: 1.57 1.30 1.19 3/45 27097 /proc/meminfo: memFree=642052/1048576 swapFree=0/0 [pid=27074] ppid=27073 vsize=2572 CPUtime=0 /proc/27074/stat : 27074 (p2cudf-user-1.1) S 27073 27074 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 25 0 1 0 46460310 2633728 271 1283457024 134512640 135304128 4292400976 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27074/statm: 643 271 230 194 0 30 0 [pid=27075] ppid=27074 vsize=2568 CPUtime=0.03 /proc/27075/stat : 27075 (p2cudf) S 27074 27074 17511 34816 17511 4202496 388 3014 0 1 0 0 3 0 25 0 1 0 46460310 2629632 279 1283457024 134512640 135304128 4292530080 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27075/statm: 642 279 239 194 0 29 0 [pid=27088] ppid=27075 vsize=885144 CPUtime=1.47 /proc/27088/stat : 27088 (java) S 27075 27074 17511 34816 17511 4202496 22314 0 1 0 141 6 0 0 25 0 10 0 46460313 906387456 18808 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/27088/statm: 221286 18808 1721 10 0 218377 0 [pid=27088/tid=27089] ppid=27075 vsize=885144 CPUtime=0.8 /proc/27088/task/27089/stat : 27089 (java) R 27075 27074 17511 34816 17511 4202560 10686 0 1 0 78 2 0 0 25 0 10 0 46460314 906387456 18808 1283457024 134512640 134550932 4290161664 18446744073709551615 4095575230 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27088/tid=27090] ppid=27075 vsize=885144 CPUtime=0 /proc/27088/task/27090/stat : 27090 (java) S 27075 27074 17511 34816 17511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 46460315 906387456 18808 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27091] ppid=27075 vsize=885144 CPUtime=0 /proc/27088/task/27091/stat : 27091 (java) S 27075 27074 17511 34816 17511 4202560 9 0 0 0 0 0 0 0 25 0 10 0 46460316 906387456 18808 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27092] ppid=27075 vsize=885144 CPUtime=0 /proc/27088/task/27092/stat : 27092 (java) S 27075 27074 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 46460316 906387456 18808 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27093] ppid=27075 vsize=885144 CPUtime=0 /proc/27088/task/27093/stat : 27093 (java) S 27075 27074 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46460318 906387456 18808 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27094] ppid=27075 vsize=885144 CPUtime=0.45 /proc/27088/task/27094/stat : 27094 (java) R 27075 27074 17511 34816 17511 4202560 8397 0 0 0 42 3 0 0 25 0 10 0 46460318 906387456 18808 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27088/tid=27095] ppid=27075 vsize=885144 CPUtime=0.2 /proc/27088/task/27095/stat : 27095 (java) R 27075 27074 17511 34816 17511 4202560 2152 0 0 0 20 0 0 0 18 0 10 0 46460318 906387456 18808 1283457024 134512640 134550932 4290161664 18446744073709551615 4153650623 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27088/tid=27096] ppid=27075 vsize=885144 CPUtime=0 /proc/27088/task/27096/stat : 27096 (java) S 27075 27074 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46460319 906387456 18808 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27097] ppid=27075 vsize=885144 CPUtime=0 /proc/27088/task/27097/stat : 27097 (java) S 27075 27074 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 46460319 906387456 18808 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.5 Current children cumulated vsize (KiB) 890284 [startup+3.10741 s] /proc/loadavg: 1.57 1.30 1.19 3/45 27097 /proc/meminfo: memFree=549052/1048576 swapFree=0/0 [pid=27074] ppid=27073 vsize=2572 CPUtime=0 /proc/27074/stat : 27074 (p2cudf-user-1.1) S 27073 27074 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 25 0 1 0 46460310 2633728 271 1283457024 134512640 135304128 4292400976 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27074/statm: 643 271 230 194 0 30 0 [pid=27075] ppid=27074 vsize=2568 CPUtime=0.03 /proc/27075/stat : 27075 (p2cudf) S 27074 27074 17511 34816 17511 4202496 388 3014 0 1 0 0 3 0 25 0 1 0 46460310 2629632 279 1283457024 134512640 135304128 4292530080 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27075/statm: 642 279 239 194 0 29 0 [pid=27088] ppid=27075 vsize=884196 CPUtime=3.05 /proc/27088/stat : 27088 (java) S 27075 27074 17511 34816 17511 4202496 62977 0 1 0 286 19 0 0 25 0 10 0 46460313 905416704 58188 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/27088/statm: 221049 58188 1744 10 0 218140 0 [pid=27088/tid=27089] ppid=27075 vsize=884196 CPUtime=1.64 /proc/27088/task/27089/stat : 27089 (java) R 27075 27074 17511 34816 17511 4202560 50019 0 1 0 150 14 0 0 25 0 10 0 46460314 905416704 58188 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27088/tid=27090] ppid=27075 vsize=884196 CPUtime=0 /proc/27088/task/27090/stat : 27090 (java) R 27075 27074 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 10 0 46460315 905416704 58188 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 0 0 16800975 0 0 0 -1 0 0 0 0 [pid=27088/tid=27091] ppid=27075 vsize=884196 CPUtime=0 /proc/27088/task/27091/stat : 27091 (java) S 27075 27074 17511 34816 17511 4202560 9 0 0 0 0 0 0 0 25 0 10 0 46460316 905416704 58188 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27092] ppid=27075 vsize=884196 CPUtime=0 /proc/27088/task/27092/stat : 27092 (java) S 27075 27074 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 46460316 905416704 58188 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27093] ppid=27075 vsize=884196 CPUtime=0 /proc/27088/task/27093/stat : 27093 (java) S 27075 27074 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46460318 905416704 58188 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27094] ppid=27075 vsize=884196 CPUtime=0.8 /proc/27088/task/27094/stat : 27094 (java) R 27075 27074 17511 34816 17511 4202560 8470 0 0 0 77 3 0 0 21 0 10 0 46460318 905416704 58188 1283457024 134512640 134550932 4290161664 18446744073709551615 4150215480 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27088/tid=27095] ppid=27075 vsize=884196 CPUtime=0.61 /proc/27088/task/27095/stat : 27095 (java) R 27075 27074 17511 34816 17511 4202560 3408 0 0 0 59 2 0 0 16 0 10 0 46460318 905416704 58188 1283457024 134512640 134550932 4290161664 18446744073709551615 4150423602 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27088/tid=27096] ppid=27075 vsize=884196 CPUtime=0 /proc/27088/task/27096/stat : 27096 (java) S 27075 27074 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46460319 905416704 58188 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27097] ppid=27075 vsize=884196 CPUtime=0 /proc/27088/task/27097/stat : 27097 (java) S 27075 27074 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 46460319 905416704 58188 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.08 Current children cumulated vsize (KiB) 889336 [startup+6.30824 s] /proc/loadavg: 1.57 1.30 1.19 2/46 27098 /proc/meminfo: memFree=422564/1048576 swapFree=0/0 [pid=27074] ppid=27073 vsize=2572 CPUtime=0 /proc/27074/stat : 27074 (p2cudf-user-1.1) S 27073 27074 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 25 0 1 0 46460310 2633728 271 1283457024 134512640 135304128 4292400976 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27074/statm: 643 271 230 194 0 30 0 [pid=27075] ppid=27074 vsize=2568 CPUtime=0.03 /proc/27075/stat : 27075 (p2cudf) S 27074 27074 17511 34816 17511 4202496 388 3014 0 1 0 0 3 0 25 0 1 0 46460310 2629632 279 1283457024 134512640 135304128 4292530080 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27075/statm: 642 279 239 194 0 29 0 [pid=27088] ppid=27075 vsize=882036 CPUtime=6.25 /proc/27088/stat : 27088 (java) S 27075 27074 17511 34816 17511 4202496 75361 0 1 0 601 24 0 0 25 0 11 0 46460313 903204864 69636 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/27088/statm: 220509 69636 1780 10 0 217600 0 [pid=27088/tid=27089] ppid=27075 vsize=882036 CPUtime=3.41 /proc/27088/task/27089/stat : 27089 (java) R 27075 27074 17511 34816 17511 4202560 50268 0 1 0 326 15 0 0 25 0 11 0 46460314 903204864 69636 1283457024 134512640 134550932 4290161664 18446744073709551615 4096421462 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27088/tid=27090] ppid=27075 vsize=882036 CPUtime=0.25 /proc/27088/task/27090/stat : 27090 (java) S 27075 27074 17511 34816 17511 4202560 11351 0 0 0 22 3 0 0 15 0 11 0 46460315 903204864 69636 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27091] ppid=27075 vsize=882036 CPUtime=0 /proc/27088/task/27091/stat : 27091 (java) S 27075 27074 17511 34816 17511 4202560 10 0 0 0 0 0 0 0 18 0 11 0 46460316 903204864 69636 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27092] ppid=27075 vsize=882036 CPUtime=0 /proc/27088/task/27092/stat : 27092 (java) S 27075 27074 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46460316 903204864 69636 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27093] ppid=27075 vsize=882036 CPUtime=0 /proc/27088/task/27093/stat : 27093 (java) S 27075 27074 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46460318 903204864 69636 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27094] ppid=27075 vsize=882036 CPUtime=1.61 /proc/27088/task/27094/stat : 27094 (java) S 27075 27074 17511 34816 17511 4202560 8576 0 0 0 158 3 0 0 16 0 11 0 46460318 903204864 69636 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27095] ppid=27075 vsize=882036 CPUtime=0.95 /proc/27088/task/27095/stat : 27095 (java) S 27075 27074 17511 34816 17511 4202560 4085 0 0 0 93 2 0 0 15 0 11 0 46460318 903204864 69636 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27096] ppid=27075 vsize=882036 CPUtime=0 /proc/27088/task/27096/stat : 27096 (java) S 27075 27074 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46460319 903204864 69636 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27097] ppid=27075 vsize=882036 CPUtime=0 /proc/27088/task/27097/stat : 27097 (java) S 27075 27074 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46460319 903204864 69636 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27098] ppid=27075 vsize=882036 CPUtime=0 /proc/27088/task/27098/stat : 27098 (java) S 27075 27074 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 24 0 11 0 46460749 903204864 69636 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.28 Current children cumulated vsize (KiB) 887176 Solver just ended. Dumping a history of the last processes samples [startup+6.40829 s] /proc/loadavg: 1.57 1.30 1.19 2/46 27098 /proc/meminfo: memFree=422564/1048576 swapFree=0/0 [pid=27074] ppid=27073 vsize=2572 CPUtime=0 /proc/27074/stat : 27074 (p2cudf-user-1.1) S 27073 27074 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 25 0 1 0 46460310 2633728 271 1283457024 134512640 135304128 4292400976 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27074/statm: 643 271 230 194 0 30 0 [pid=27075] ppid=27074 vsize=2568 CPUtime=0.03 /proc/27075/stat : 27075 (p2cudf) S 27074 27074 17511 34816 17511 4202496 388 3014 0 1 0 0 3 0 25 0 1 0 46460310 2629632 279 1283457024 134512640 135304128 4292530080 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27075/statm: 642 279 239 194 0 29 0 [pid=27088] ppid=27075 vsize=880564 CPUtime=6.34 /proc/27088/stat : 27088 (java) S 27075 27074 17511 34816 17511 4202496 75365 0 1 0 610 24 0 0 25 0 11 0 46460313 901697536 69272 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/27088/statm: 220141 69272 1780 10 0 217232 0 [pid=27088/tid=27089] ppid=27075 vsize=880564 CPUtime=3.49 /proc/27088/task/27089/stat : 27089 (java) R 27075 27074 17511 34816 17511 4202560 50268 0 1 0 334 15 0 0 25 0 11 0 46460314 901697536 69272 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27088/tid=27090] ppid=27075 vsize=880564 CPUtime=0.25 /proc/27088/task/27090/stat : 27090 (java) S 27075 27074 17511 34816 17511 4202560 11351 0 0 0 22 3 0 0 15 0 11 0 46460315 901697536 69272 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27091] ppid=27075 vsize=880564 CPUtime=0 /proc/27088/task/27091/stat : 27091 (java) S 27075 27074 17511 34816 17511 4202560 10 0 0 0 0 0 0 0 18 0 11 0 46460316 901697536 69272 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27092] ppid=27075 vsize=880564 CPUtime=0 /proc/27088/task/27092/stat : 27092 (java) S 27075 27074 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46460316 901697536 69272 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27093] ppid=27075 vsize=880564 CPUtime=0 /proc/27088/task/27093/stat : 27093 (java) S 27075 27074 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46460318 901697536 69272 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27094] ppid=27075 vsize=880564 CPUtime=1.62 /proc/27088/task/27094/stat : 27094 (java) S 27075 27074 17511 34816 17511 4202560 8580 0 0 0 159 3 0 0 15 0 11 0 46460318 901697536 69272 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27095] ppid=27075 vsize=880564 CPUtime=0.95 /proc/27088/task/27095/stat : 27095 (java) S 27075 27074 17511 34816 17511 4202560 4085 0 0 0 93 2 0 0 15 0 11 0 46460318 901697536 69272 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27096] ppid=27075 vsize=880564 CPUtime=0 /proc/27088/task/27096/stat : 27096 (java) S 27075 27074 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46460319 901697536 69272 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27097] ppid=27075 vsize=880564 CPUtime=0 /proc/27088/task/27097/stat : 27097 (java) S 27075 27074 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46460319 901697536 69272 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27098] ppid=27075 vsize=880564 CPUtime=0 /proc/27088/task/27098/stat : 27098 (java) S 27075 27074 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 24 0 11 0 46460749 901697536 69272 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.37 Current children cumulated vsize (KiB) 885704 [startup+7.2085 s] /proc/loadavg: 1.60 1.31 1.20 2/46 27098 /proc/meminfo: memFree=423432/1048576 swapFree=0/0 [pid=27074] ppid=27073 vsize=2572 CPUtime=0 /proc/27074/stat : 27074 (p2cudf-user-1.1) S 27073 27074 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 25 0 1 0 46460310 2633728 271 1283457024 134512640 135304128 4292400976 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27074/statm: 643 271 230 194 0 30 0 [pid=27075] ppid=27074 vsize=2568 CPUtime=0.03 /proc/27075/stat : 27075 (p2cudf) S 27074 27074 17511 34816 17511 4202496 388 3014 0 1 0 0 3 0 25 0 1 0 46460310 2629632 279 1283457024 134512640 135304128 4292530080 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27075/statm: 642 279 239 194 0 29 0 [pid=27088] ppid=27075 vsize=880564 CPUtime=7.14 /proc/27088/stat : 27088 (java) S 27075 27074 17511 34816 17511 4202496 75384 0 1 0 690 24 0 0 25 0 11 0 46460313 901697536 69291 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/27088/statm: 220141 69291 1782 10 0 217232 0 [pid=27088/tid=27089] ppid=27075 vsize=880564 CPUtime=4.13 /proc/27088/task/27089/stat : 27089 (java) R 27075 27074 17511 34816 17511 4202560 50275 0 1 0 398 15 0 0 25 0 11 0 46460314 901697536 69291 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27088/tid=27090] ppid=27075 vsize=880564 CPUtime=0.25 /proc/27088/task/27090/stat : 27090 (java) S 27075 27074 17511 34816 17511 4202560 11351 0 0 0 22 3 0 0 16 0 11 0 46460315 901697536 69291 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27091] ppid=27075 vsize=880564 CPUtime=0 /proc/27088/task/27091/stat : 27091 (java) S 27075 27074 17511 34816 17511 4202560 10 0 0 0 0 0 0 0 18 0 11 0 46460316 901697536 69291 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27092] ppid=27075 vsize=880564 CPUtime=0 /proc/27088/task/27092/stat : 27092 (java) S 27075 27074 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46460316 901697536 69291 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27093] ppid=27075 vsize=880564 CPUtime=0 /proc/27088/task/27093/stat : 27093 (java) S 27075 27074 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46460318 901697536 69291 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27094] ppid=27075 vsize=880564 CPUtime=1.79 /proc/27088/task/27094/stat : 27094 (java) R 27075 27074 17511 34816 17511 4202560 8592 0 0 0 176 3 0 0 16 0 11 0 46460318 901697536 69291 1283457024 134512640 134550932 4290161664 18446744073709551615 4152949271 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27088/tid=27095] ppid=27075 vsize=880564 CPUtime=0.95 /proc/27088/task/27095/stat : 27095 (java) S 27075 27074 17511 34816 17511 4202560 4085 0 0 0 93 2 0 0 15 0 11 0 46460318 901697536 69291 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27096] ppid=27075 vsize=880564 CPUtime=0 /proc/27088/task/27096/stat : 27096 (java) S 27075 27074 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46460319 901697536 69291 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27097] ppid=27075 vsize=880564 CPUtime=0 /proc/27088/task/27097/stat : 27097 (java) S 27075 27074 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46460319 901697536 69291 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27098] ppid=27075 vsize=880564 CPUtime=0 /proc/27088/task/27098/stat : 27098 (java) S 27075 27074 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 24 0 11 0 46460749 901697536 69291 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 7.17 Current children cumulated vsize (KiB) 885704 [startup+8.00586 s] /proc/loadavg: 1.60 1.31 1.20 2/46 27098 /proc/meminfo: memFree=423440/1048576 swapFree=0/0 [pid=27074] ppid=27073 vsize=2572 CPUtime=0 /proc/27074/stat : 27074 (p2cudf-user-1.1) S 27073 27074 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 25 0 1 0 46460310 2633728 271 1283457024 134512640 135304128 4292400976 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27074/statm: 643 271 230 194 0 30 0 [pid=27075] ppid=27074 vsize=2568 CPUtime=0.03 /proc/27075/stat : 27075 (p2cudf) S 27074 27074 17511 34816 17511 4202496 388 3014 0 1 0 0 3 0 25 0 1 0 46460310 2629632 279 1283457024 134512640 135304128 4292530080 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27075/statm: 642 279 239 194 0 29 0 [pid=27088] ppid=27075 vsize=880888 CPUtime=7.7 /proc/27088/stat : 27088 (java) S 27075 27074 17511 34816 17511 4202496 75410 0 1 0 746 24 0 0 25 0 11 0 46460313 902029312 69317 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/27088/statm: 220222 69317 1784 10 0 217313 0 [pid=27088/tid=27089] ppid=27075 vsize=880888 CPUtime=4.61 /proc/27088/task/27089/stat : 27089 (java) S 27075 27074 17511 34816 17511 4202560 50283 0 1 0 446 15 0 0 25 0 11 0 46460314 902029312 69317 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27090] ppid=27075 vsize=880888 CPUtime=0.25 /proc/27088/task/27090/stat : 27090 (java) S 27075 27074 17511 34816 17511 4202560 11351 0 0 0 22 3 0 0 16 0 11 0 46460315 902029312 69317 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27091] ppid=27075 vsize=880888 CPUtime=0 /proc/27088/task/27091/stat : 27091 (java) S 27075 27074 17511 34816 17511 4202560 10 0 0 0 0 0 0 0 18 0 11 0 46460316 902029312 69317 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27092] ppid=27075 vsize=880888 CPUtime=0 /proc/27088/task/27092/stat : 27092 (java) S 27075 27074 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46460316 902029312 69317 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27093] ppid=27075 vsize=880888 CPUtime=0 /proc/27088/task/27093/stat : 27093 (java) S 27075 27074 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46460318 902029312 69317 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27094] ppid=27075 vsize=880888 CPUtime=1.87 /proc/27088/task/27094/stat : 27094 (java) S 27075 27074 17511 34816 17511 4202560 8605 0 0 0 184 3 0 0 16 0 11 0 46460318 902029312 69317 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27095] ppid=27075 vsize=880888 CPUtime=0.95 /proc/27088/task/27095/stat : 27095 (java) S 27075 27074 17511 34816 17511 4202560 4085 0 0 0 93 2 0 0 15 0 11 0 46460318 902029312 69317 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27096] ppid=27075 vsize=880888 CPUtime=0 /proc/27088/task/27096/stat : 27096 (java) S 27075 27074 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46460319 902029312 69317 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27097] ppid=27075 vsize=880888 CPUtime=0 /proc/27088/task/27097/stat : 27097 (java) S 27075 27074 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46460319 902029312 69317 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 7.73 Current children cumulated vsize (KiB) 886028 [startup+8.4139 s] /proc/loadavg: 1.60 1.31 1.20 2/46 27098 /proc/meminfo: memFree=423440/1048576 swapFree=0/0 [pid=27074] ppid=27073 vsize=2572 CPUtime=0 /proc/27074/stat : 27074 (p2cudf-user-1.1) S 27073 27074 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 25 0 1 0 46460310 2633728 271 1283457024 134512640 135304128 4292400976 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27074/statm: 643 271 230 194 0 30 0 [pid=27075] ppid=27074 vsize=2568 CPUtime=0.03 /proc/27075/stat : 27075 (p2cudf) S 27074 27074 17511 34816 17511 4202496 388 3014 0 1 0 0 3 0 25 0 1 0 46460310 2629632 279 1283457024 134512640 135304128 4292530080 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27075/statm: 642 279 239 194 0 29 0 [pid=27088] ppid=27075 vsize=880888 CPUtime=7.83 /proc/27088/stat : 27088 (java) S 27075 27074 17511 34816 17511 4202496 75415 0 1 0 757 26 0 0 25 0 11 0 46460313 902029312 69322 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/27088/statm: 220222 69322 1784 10 0 217313 0 [pid=27088/tid=27089] ppid=27075 vsize=880888 CPUtime=4.61 /proc/27088/task/27089/stat : 27089 (java) S 27075 27074 17511 34816 17511 4202560 50283 0 1 0 446 15 0 0 25 0 11 0 46460314 902029312 69322 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27090] ppid=27075 vsize=880888 CPUtime=0.25 /proc/27088/task/27090/stat : 27090 (java) S 27075 27074 17511 34816 17511 4202560 11351 0 0 0 22 3 0 0 16 0 11 0 46460315 902029312 69322 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27091] ppid=27075 vsize=880888 CPUtime=0 /proc/27088/task/27091/stat : 27091 (java) S 27075 27074 17511 34816 17511 4202560 10 0 0 0 0 0 0 0 18 0 11 0 46460316 902029312 69322 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27092] ppid=27075 vsize=880888 CPUtime=0 /proc/27088/task/27092/stat : 27092 (java) S 27075 27074 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46460316 902029312 69322 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27093] ppid=27075 vsize=880888 CPUtime=0 /proc/27088/task/27093/stat : 27093 (java) S 27075 27074 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46460318 902029312 69322 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27094] ppid=27075 vsize=880888 CPUtime=1.91 /proc/27088/task/27094/stat : 27094 (java) R 27075 27074 17511 34816 17511 4202560 8608 0 0 0 188 3 0 0 16 0 11 0 46460318 902029312 69322 1283457024 134512640 134550932 4290161664 18446744073709551615 4152951313 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27088/tid=27095] ppid=27075 vsize=880888 CPUtime=0.95 /proc/27088/task/27095/stat : 27095 (java) S 27075 27074 17511 34816 17511 4202560 4085 0 0 0 93 2 0 0 15 0 11 0 46460318 902029312 69322 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27096] ppid=27075 vsize=880888 CPUtime=0 /proc/27088/task/27096/stat : 27096 (java) S 27075 27074 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46460319 902029312 69322 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=27088/tid=27097] ppid=27075 vsize=880888 CPUtime=0 /proc/27088/task/27097/stat : 27097 (java) S 27075 27074 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46460319 902029312 69322 1283457024 134512640 134550932 4290161664 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 7.86 Current children cumulated vsize (KiB) 886028 Child status: 0 Real time (s): 8.49477 CPU time (s): 7.92849 CPU user time (s): 7.62848 CPU system time (s): 0.300018 CPU usage (%): 93.3338 Max. virtual memory (cumulated for all children) (KiB): 891136 getrusage(RUSAGE_CHILDREN,...) data: user time used= 7.62848 system time used= 0.300018 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 79220 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= 946 involuntary context switches= 968 runsolver used 0 second user time and 0 second system time The end