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/201012061533/p2cudf-paranoid-1.9/cb0e73b0-0ffd-11df-9e59-00163e2c8d72.cudf.debian-dudf.log.runsolver ./p2cudf-paranoid-1.9 /home/misc2010/data/2010/debian-dudf//cb0e73b0-0ffd-11df-9e59-00163e2c8d72.cudf /home/misc2010/tmp/201012061533/p2cudf-paranoid-1.9/cb0e73b0-0ffd-11df-9e59-00163e2c8d72.cudf.debian-dudf.result Enforcing CPUTime limit (soft limit, will send signal-name then SIGKILL): 290 seconds Enforcing CPUTime limit (hard limit, will send SIGXCPU): 320 seconds Enforcing VSIZE limit (soft limit, will send signal-name then SIGKILL): 1150976 KiB Enforcing VSIZE limit (hard limit, stack expansion will fail with SIGSEGV, brk() and mmap() will return ENOMEM): 1202176 KiB Current StackSize limit: 8192 KiB [startup+0 s] /proc/loadavg: 1.26 1.26 1.24 1/45 31525 /proc/meminfo: memFree=891632/1048576 swapFree=2078672/2097144 [pid=31525] ppid=31524 vsize=3028 CPUtime=0 /proc/31525/stat : 31525 (runsolver) D 31524 31525 2059 34821 2220 4202560 73 0 0 0 0 0 0 0 25 0 1 0 876633886 3100672 94 1283457024 134512640 134586868 4293142336 18446744073709551615 4294960130 0 0 0 24578 18446744071564332000 0 0 17 0 0 0 0 /proc/31525/statm: 757 94 62 19 0 55 0 [startup+0.140182 s] /proc/loadavg: 1.26 1.26 1.24 1/45 31525 /proc/meminfo: memFree=891632/1048576 swapFree=2078672/2097144 [pid=31525] ppid=31524 vsize=2572 CPUtime=0 /proc/31525/stat : 31525 (p2cudf-paranoid) S 31524 31525 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876633886 2633728 274 1283457024 134512640 135304128 4292753360 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/31525/statm: 643 274 233 194 0 29 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 2572 [startup+0.204225 s] /proc/loadavg: 1.26 1.26 1.24 1/45 31525 /proc/meminfo: memFree=891632/1048576 swapFree=2078672/2097144 [pid=31525] ppid=31524 vsize=2572 CPUtime=0 /proc/31525/stat : 31525 (p2cudf-paranoid) S 31524 31525 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876633886 2633728 274 1283457024 134512640 135304128 4292753360 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/31525/statm: 643 274 233 194 0 29 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 2572 [startup+0.30419 s] /proc/loadavg: 1.26 1.26 1.24 1/45 31525 /proc/meminfo: memFree=891632/1048576 swapFree=2078672/2097144 [pid=31525] ppid=31524 vsize=2572 CPUtime=0 /proc/31525/stat : 31525 (p2cudf-paranoid) S 31524 31525 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876633886 2633728 274 1283457024 134512640 135304128 4292753360 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/31525/statm: 643 274 233 194 0 29 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 2572 [startup+0.704201 s] /proc/loadavg: 1.26 1.26 1.24 1/45 31525 /proc/meminfo: memFree=891632/1048576 swapFree=2078672/2097144 [pid=31525] ppid=31524 vsize=2572 CPUtime=0 /proc/31525/stat : 31525 (p2cudf-paranoid) S 31524 31525 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876633886 2633728 274 1283457024 134512640 135304128 4292753360 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/31525/statm: 643 274 233 194 0 29 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 2572 [startup+1.50554 s] /proc/loadavg: 1.26 1.26 1.24 2/57 31548 /proc/meminfo: memFree=867244/1048576 swapFree=2078672/2097144 [pid=31525] ppid=31524 vsize=2572 CPUtime=0 /proc/31525/stat : 31525 (p2cudf-paranoid) S 31524 31525 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876633886 2633728 274 1283457024 134512640 135304128 4292753360 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/31525/statm: 643 274 233 194 0 29 0 [pid=31527] ppid=31525 vsize=2576 CPUtime=0.02 /proc/31527/stat : 31527 (p2cudf) S 31525 31525 2059 34821 2220 4202496 389 2996 0 1 0 0 2 0 25 0 1 0 876633887 2637824 281 1283457024 134512640 135304128 4290596304 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/31527/statm: 644 281 241 194 0 30 0 [pid=31539] ppid=31527 vsize=870476 CPUtime=0.61 /proc/31539/stat : 31539 (java) S 31527 31525 2059 34821 2220 4202496 9973 0 108 0 60 1 0 0 23 0 10 0 876633890 891367424 8810 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/31539/statm: 217619 8810 1735 10 0 214709 0 [pid=31539/tid=31540] ppid=31527 vsize=870476 CPUtime=0.23 /proc/31539/task/31540/stat : 31540 (java) R 31527 31525 2059 34821 2220 4202560 4596 0 15 0 23 0 0 0 24 0 10 0 876633916 891367424 8810 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=31539/tid=31541] ppid=31527 vsize=870476 CPUtime=0 /proc/31539/task/31541/stat : 31541 (java) S 31527 31525 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 18 0 10 0 876633925 891367424 8810 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 0 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31542] ppid=31527 vsize=870476 CPUtime=0 /proc/31539/task/31542/stat : 31542 (java) S 31527 31525 2059 34821 2220 4202560 5 0 1 0 0 0 0 0 25 0 10 0 876633925 891367424 8810 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31543] ppid=31527 vsize=870476 CPUtime=0 /proc/31539/task/31543/stat : 31543 (java) S 31527 31525 2059 34821 2220 4202560 7 0 0 0 0 0 0 0 25 0 10 0 876633925 891367424 8810 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31544] ppid=31527 vsize=870476 CPUtime=0 /proc/31539/task/31544/stat : 31544 (java) S 31527 31525 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876633937 891367424 8810 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31545] ppid=31527 vsize=870476 CPUtime=0.14 /proc/31539/task/31545/stat : 31545 (java) R 31527 31525 2059 34821 2220 4202560 476 0 2 0 13 1 0 0 18 0 10 0 876633937 891367424 8810 1283457024 134512640 134550932 4287224112 18446744073709551615 4151691603 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=31539/tid=31546] ppid=31527 vsize=870476 CPUtime=0.24 /proc/31539/task/31546/stat : 31546 (java) R 31527 31525 2059 34821 2220 4202560 3871 0 6 0 24 0 0 0 20 0 10 0 876633937 891367424 8810 1283457024 134512640 134550932 4287224112 18446744073709551615 4152119650 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=31539/tid=31547] ppid=31527 vsize=870476 CPUtime=0 /proc/31539/task/31547/stat : 31547 (java) S 31527 31525 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876633937 891367424 8810 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31548] ppid=31527 vsize=870476 CPUtime=0 /proc/31539/task/31548/stat : 31548 (java) S 31527 31525 2059 34821 2220 4202560 3 0 0 0 0 0 0 0 16 0 10 0 876633937 891367424 8810 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 0.63 Current children cumulated vsize (KiB) 875624 [startup+3.11464 s] /proc/loadavg: 1.26 1.26 1.24 3/57 31548 /proc/meminfo: memFree=821680/1048576 swapFree=2078672/2097144 [pid=31525] ppid=31524 vsize=2572 CPUtime=0 /proc/31525/stat : 31525 (p2cudf-paranoid) S 31524 31525 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876633886 2633728 274 1283457024 134512640 135304128 4292753360 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/31525/statm: 643 274 233 194 0 29 0 [pid=31527] ppid=31525 vsize=2576 CPUtime=0.02 /proc/31527/stat : 31527 (p2cudf) S 31525 31525 2059 34821 2220 4202496 389 2996 0 1 0 0 2 0 25 0 1 0 876633887 2637824 281 1283457024 134512640 135304128 4290596304 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/31527/statm: 644 281 241 194 0 30 0 [pid=31539] ppid=31527 vsize=879548 CPUtime=2.09 /proc/31539/stat : 31539 (java) S 31527 31525 2059 34821 2220 4202496 42548 0 108 0 194 15 0 0 23 0 10 0 876633890 900657152 37789 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/31539/statm: 219887 37789 1745 10 0 216977 0 [pid=31539/tid=31540] ppid=31527 vsize=879548 CPUtime=0.9 /proc/31539/task/31540/stat : 31540 (java) R 31527 31525 2059 34821 2220 4202560 30867 0 15 0 78 12 0 0 25 0 10 0 876633916 900657152 37789 1283457024 134512640 134550932 4287224112 18446744073709551615 4095304631 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=31539/tid=31541] ppid=31527 vsize=879548 CPUtime=0 /proc/31539/task/31541/stat : 31541 (java) S 31527 31525 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 16 0 10 0 876633925 900657152 37789 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 0 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31542] ppid=31527 vsize=879548 CPUtime=0 /proc/31539/task/31542/stat : 31542 (java) S 31527 31525 2059 34821 2220 4202560 5 0 1 0 0 0 0 0 25 0 10 0 876633925 900657152 37789 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31543] ppid=31527 vsize=879548 CPUtime=0 /proc/31539/task/31543/stat : 31543 (java) S 31527 31525 2059 34821 2220 4202560 7 0 0 0 0 0 0 0 25 0 10 0 876633925 900657152 37789 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31544] ppid=31527 vsize=879548 CPUtime=0 /proc/31539/task/31544/stat : 31544 (java) S 31527 31525 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876633937 900657152 37789 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31545] ppid=31527 vsize=879548 CPUtime=0.58 /proc/31539/task/31545/stat : 31545 (java) S 31527 31525 2059 34821 2220 4202560 5221 0 2 0 56 2 0 0 17 0 10 0 876633937 900657152 37789 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31546] ppid=31527 vsize=879548 CPUtime=0.59 /proc/31539/task/31546/stat : 31546 (java) S 31527 31525 2059 34821 2220 4202560 5430 0 6 0 59 0 0 0 16 0 10 0 876633937 900657152 37789 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31547] ppid=31527 vsize=879548 CPUtime=0 /proc/31539/task/31547/stat : 31547 (java) S 31527 31525 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876633937 900657152 37789 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31548] ppid=31527 vsize=879548 CPUtime=0 /proc/31539/task/31548/stat : 31548 (java) S 31527 31525 2059 34821 2220 4202560 3 0 0 0 0 0 0 0 15 0 10 0 876633937 900657152 37789 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 2.11 Current children cumulated vsize (KiB) 884696 heavy processes: [startup+6.30867 s] /proc/loadavg: 1.32 1.27 1.24 5/59 31551 /proc/meminfo: memFree=647208/1048576 swapFree=2078672/2097144 [pid=31525] ppid=31524 vsize=2572 CPUtime=0 /proc/31525/stat : 31525 (p2cudf-paranoid) S 31524 31525 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876633886 2633728 274 1283457024 134512640 135304128 4292753360 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/31525/statm: 643 274 233 194 0 29 0 [pid=31527] ppid=31525 vsize=2576 CPUtime=0.02 /proc/31527/stat : 31527 (p2cudf) S 31525 31525 2059 34821 2220 4202496 389 2996 0 1 0 0 2 0 25 0 1 0 876633887 2637824 281 1283457024 134512640 135304128 4290596304 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/31527/statm: 644 281 241 194 0 30 0 [pid=31539] ppid=31527 vsize=880844 CPUtime=5.13 /proc/31539/stat : 31539 (java) S 31527 31525 2059 34821 2220 4202496 76155 0 109 0 487 26 0 0 23 0 12 0 876633890 901984256 67293 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/31539/statm: 220211 67293 1793 10 0 217301 0 [pid=31539/tid=31540] ppid=31527 vsize=880844 CPUtime=1.9 /proc/31539/task/31540/stat : 31540 (java) R 31527 31525 2059 34821 2220 4202560 50440 0 15 0 170 20 0 0 25 0 12 0 876633916 901984256 67293 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=31539/tid=31541] ppid=31527 vsize=880844 CPUtime=0.23 /proc/31539/task/31541/stat : 31541 (java) S 31527 31525 2059 34821 2220 4202560 9647 0 0 0 22 1 0 0 15 0 12 0 876633925 901984256 67293 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 0 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31542] ppid=31527 vsize=880844 CPUtime=0 /proc/31539/task/31542/stat : 31542 (java) S 31527 31525 2059 34821 2220 4202560 6 0 1 0 0 0 0 0 18 0 12 0 876633925 901984256 67293 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31543] ppid=31527 vsize=880844 CPUtime=0 /proc/31539/task/31543/stat : 31543 (java) S 31527 31525 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 18 0 12 0 876633925 901984256 67293 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31544] ppid=31527 vsize=880844 CPUtime=0 /proc/31539/task/31544/stat : 31544 (java) S 31527 31525 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 12 0 876633937 901984256 67293 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31545] ppid=31527 vsize=880844 CPUtime=0.6 /proc/31539/task/31545/stat : 31545 (java) S 31527 31525 2059 34821 2220 4202560 5221 0 2 0 58 2 0 0 15 0 12 0 876633937 901984256 67293 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31546] ppid=31527 vsize=880844 CPUtime=2.37 /proc/31539/task/31546/stat : 31546 (java) S 31527 31525 2059 34821 2220 4202560 9802 0 7 0 236 1 0 0 15 0 12 0 876633937 901984256 67293 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31547] ppid=31527 vsize=880844 CPUtime=0 /proc/31539/task/31547/stat : 31547 (java) S 31527 31525 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 12 0 876633937 901984256 67293 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31548] ppid=31527 vsize=880844 CPUtime=0 /proc/31539/task/31548/stat : 31548 (java) S 31527 31525 2059 34821 2220 4202560 3 0 0 0 0 0 0 0 15 0 12 0 876633937 901984256 67293 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.15 Current children cumulated vsize (KiB) 885992 Solver just ended. Dumping a history of the last processes samples [startup+6.40056 s] /proc/loadavg: 1.32 1.27 1.24 5/59 31551 /proc/meminfo: memFree=647208/1048576 swapFree=2078672/2097144 [pid=31525] ppid=31524 vsize=2572 CPUtime=0 /proc/31525/stat : 31525 (p2cudf-paranoid) S 31524 31525 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876633886 2633728 274 1283457024 134512640 135304128 4292753360 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/31525/statm: 643 274 233 194 0 29 0 [pid=31527] ppid=31525 vsize=2576 CPUtime=0.02 /proc/31527/stat : 31527 (p2cudf) S 31525 31525 2059 34821 2220 4202496 389 2996 0 1 0 0 2 0 25 0 1 0 876633887 2637824 281 1283457024 134512640 135304128 4290596304 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/31527/statm: 644 281 241 194 0 30 0 [pid=31539] ppid=31527 vsize=880844 CPUtime=5.19 /proc/31539/stat : 31539 (java) S 31527 31525 2059 34821 2220 4202496 76160 0 109 0 493 26 0 0 23 0 10 0 876633890 901984256 67298 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/31539/statm: 220211 67298 1793 10 0 217301 0 [pid=31539/tid=31540] ppid=31527 vsize=880844 CPUtime=1.94 /proc/31539/task/31540/stat : 31540 (java) R 31527 31525 2059 34821 2220 4202560 50445 0 15 0 174 20 0 0 25 0 10 0 876633916 901984256 67298 1283457024 134512640 134550932 4287224112 18446744073709551615 4148473511 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=31539/tid=31541] ppid=31527 vsize=880844 CPUtime=0.23 /proc/31539/task/31541/stat : 31541 (java) S 31527 31525 2059 34821 2220 4202560 9647 0 0 0 22 1 0 0 16 0 10 0 876633925 901984256 67298 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 0 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31542] ppid=31527 vsize=880844 CPUtime=0 /proc/31539/task/31542/stat : 31542 (java) S 31527 31525 2059 34821 2220 4202560 6 0 1 0 0 0 0 0 18 0 10 0 876633925 901984256 67298 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31543] ppid=31527 vsize=880844 CPUtime=0 /proc/31539/task/31543/stat : 31543 (java) S 31527 31525 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 18 0 10 0 876633925 901984256 67298 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31544] ppid=31527 vsize=880844 CPUtime=0 /proc/31539/task/31544/stat : 31544 (java) S 31527 31525 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876633937 901984256 67298 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31545] ppid=31527 vsize=880844 CPUtime=0.6 /proc/31539/task/31545/stat : 31545 (java) S 31527 31525 2059 34821 2220 4202560 5221 0 2 0 58 2 0 0 15 0 10 0 876633937 901984256 67298 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31546] ppid=31527 vsize=880844 CPUtime=2.4 /proc/31539/task/31546/stat : 31546 (java) S 31527 31525 2059 34821 2220 4202560 9802 0 7 0 239 1 0 0 15 0 10 0 876633937 901984256 67298 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31547] ppid=31527 vsize=880844 CPUtime=0 /proc/31539/task/31547/stat : 31547 (java) S 31527 31525 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876633937 901984256 67298 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31548] ppid=31527 vsize=880844 CPUtime=0 /proc/31539/task/31548/stat : 31548 (java) S 31527 31525 2059 34821 2220 4202560 3 0 0 0 0 0 0 0 15 0 10 0 876633937 901984256 67298 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.21 Current children cumulated vsize (KiB) 885992 [startup+6.80464 s] /proc/loadavg: 1.32 1.27 1.24 1/58 31562 /proc/meminfo: memFree=612684/1048576 swapFree=2078672/2097144 [pid=31525] ppid=31524 vsize=2572 CPUtime=0 /proc/31525/stat : 31525 (p2cudf-paranoid) S 31524 31525 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876633886 2633728 274 1283457024 134512640 135304128 4292753360 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/31525/statm: 643 274 233 194 0 29 0 [pid=31527] ppid=31525 vsize=2576 CPUtime=0.02 /proc/31527/stat : 31527 (p2cudf) S 31525 31525 2059 34821 2220 4202496 389 2996 0 1 0 0 2 0 25 0 1 0 876633887 2637824 281 1283457024 134512640 135304128 4290596304 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/31527/statm: 644 281 241 194 0 30 0 [pid=31539] ppid=31527 vsize=875028 CPUtime=5.19 /proc/31539/stat : 31539 (java) S 31527 31525 2059 34821 2220 4202496 76162 0 109 0 493 26 0 0 23 0 11 0 876633890 896028672 66009 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/31539/statm: 218757 66009 1793 10 0 215847 0 [pid=31539/tid=31540] ppid=31527 vsize=875028 CPUtime=1.94 /proc/31539/task/31540/stat : 31540 (java) S 31527 31525 2059 34821 2220 4202560 50447 0 15 0 174 20 0 0 25 0 11 0 876633916 896028672 66009 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31541] ppid=31527 vsize=875028 CPUtime=0.23 /proc/31539/task/31541/stat : 31541 (java) S 31527 31525 2059 34821 2220 4202560 9647 0 0 0 22 1 0 0 16 0 11 0 876633925 896028672 66009 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 0 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31542] ppid=31527 vsize=875028 CPUtime=0 /proc/31539/task/31542/stat : 31542 (java) S 31527 31525 2059 34821 2220 4202560 6 0 1 0 0 0 0 0 18 0 11 0 876633925 896028672 66009 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31543] ppid=31527 vsize=875028 CPUtime=0 /proc/31539/task/31543/stat : 31543 (java) S 31527 31525 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 18 0 11 0 876633925 896028672 66009 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31544] ppid=31527 vsize=875028 CPUtime=0 /proc/31539/task/31544/stat : 31544 (java) S 31527 31525 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876633937 896028672 66009 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31545] ppid=31527 vsize=875028 CPUtime=0.6 /proc/31539/task/31545/stat : 31545 (java) S 31527 31525 2059 34821 2220 4202560 5221 0 2 0 58 2 0 0 15 0 11 0 876633937 896028672 66009 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31546] ppid=31527 vsize=875028 CPUtime=2.4 /proc/31539/task/31546/stat : 31546 (java) S 31527 31525 2059 34821 2220 4202560 9802 0 7 0 239 1 0 0 15 0 11 0 876633937 896028672 66009 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31547] ppid=31527 vsize=875028 CPUtime=0 /proc/31539/task/31547/stat : 31547 (java) S 31527 31525 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876633937 896028672 66009 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31548] ppid=31527 vsize=875028 CPUtime=0 /proc/31539/task/31548/stat : 31548 (java) S 31527 31525 2059 34821 2220 4202560 3 0 0 0 0 0 0 0 15 0 11 0 876633937 896028672 66009 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31562] ppid=31527 vsize=875028 CPUtime=0 /proc/31539/task/31562/stat : 31562 (java) S 31527 31525 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 11 0 876634526 896028672 66009 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.21 Current children cumulated vsize (KiB) 880176 [startup+7.00695 s] /proc/loadavg: 1.32 1.27 1.24 1/58 31562 /proc/meminfo: memFree=612684/1048576 swapFree=2078672/2097144 [pid=31525] ppid=31524 vsize=2572 CPUtime=0 /proc/31525/stat : 31525 (p2cudf-paranoid) S 31524 31525 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876633886 2633728 274 1283457024 134512640 135304128 4292753360 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/31525/statm: 643 274 233 194 0 29 0 [pid=31527] ppid=31525 vsize=2576 CPUtime=0.02 /proc/31527/stat : 31527 (p2cudf) S 31525 31525 2059 34821 2220 4202496 389 2996 0 1 0 0 2 0 25 0 1 0 876633887 2637824 281 1283457024 134512640 135304128 4290596304 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/31527/statm: 644 281 241 194 0 30 0 [pid=31539] ppid=31527 vsize=875028 CPUtime=5.29 /proc/31539/stat : 31539 (java) S 31527 31525 2059 34821 2220 4202496 76163 0 109 0 502 27 0 0 23 0 11 0 876633890 896028672 66010 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/31539/statm: 218757 66010 1793 10 0 215847 0 [pid=31539/tid=31540] ppid=31527 vsize=875028 CPUtime=1.94 /proc/31539/task/31540/stat : 31540 (java) S 31527 31525 2059 34821 2220 4202560 50447 0 15 0 174 20 0 0 25 0 11 0 876633916 896028672 66010 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31541] ppid=31527 vsize=875028 CPUtime=0.23 /proc/31539/task/31541/stat : 31541 (java) S 31527 31525 2059 34821 2220 4202560 9647 0 0 0 22 1 0 0 16 0 11 0 876633925 896028672 66010 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 0 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31542] ppid=31527 vsize=875028 CPUtime=0 /proc/31539/task/31542/stat : 31542 (java) S 31527 31525 2059 34821 2220 4202560 6 0 1 0 0 0 0 0 18 0 11 0 876633925 896028672 66010 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31543] ppid=31527 vsize=875028 CPUtime=0 /proc/31539/task/31543/stat : 31543 (java) S 31527 31525 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 18 0 11 0 876633925 896028672 66010 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31544] ppid=31527 vsize=875028 CPUtime=0 /proc/31539/task/31544/stat : 31544 (java) S 31527 31525 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876633937 896028672 66010 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31545] ppid=31527 vsize=875028 CPUtime=0.6 /proc/31539/task/31545/stat : 31545 (java) S 31527 31525 2059 34821 2220 4202560 5221 0 2 0 58 2 0 0 15 0 11 0 876633937 896028672 66010 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31546] ppid=31527 vsize=875028 CPUtime=2.43 /proc/31539/task/31546/stat : 31546 (java) S 31527 31525 2059 34821 2220 4202560 9802 0 7 0 242 1 0 0 15 0 11 0 876633937 896028672 66010 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31547] ppid=31527 vsize=875028 CPUtime=0 /proc/31539/task/31547/stat : 31547 (java) S 31527 31525 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876633937 896028672 66010 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31548] ppid=31527 vsize=875028 CPUtime=0 /proc/31539/task/31548/stat : 31548 (java) S 31527 31525 2059 34821 2220 4202560 3 0 0 0 0 0 0 0 15 0 11 0 876633937 896028672 66010 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=31539/tid=31562] ppid=31527 vsize=875028 CPUtime=0.07 /proc/31539/task/31562/stat : 31562 (java) R 31527 31525 2059 34821 2220 4202560 1 0 0 0 6 1 0 0 20 0 11 0 876634526 896028672 66010 1283457024 134512640 134550932 4287224112 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.31 Current children cumulated vsize (KiB) 880176 Child status: 0 Real time (s): 7.09295 CPU time (s): 5.40834 CPU user time (s): 5.11232 CPU system time (s): 0.296018 CPU usage (%): 76.2494 Max. virtual memory (cumulated for all children) (KiB): 889180 getrusage(RUSAGE_CHILDREN,...) data: user time used= 5.11232 system time used= 0.296018 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 79953 page faults= 110 swaps= 0 block input operations= 0 block output operations= 0 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 1666 involuntary context switches= 1044 runsolver used 0 second user time and 0 second system time The end