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/201012070034/p2cudf-trendy-1.9/29180036-5408-11df-9f57-00163e7a6f5e.cudf.debian-dudf.log.runsolver ./p2cudf-trendy-1.9 /home/misc2010/data/2010/debian-dudf//29180036-5408-11df-9f57-00163e7a6f5e.cudf /home/misc2010/tmp/201012070034/p2cudf-trendy-1.9/29180036-5408-11df-9f57-00163e7a6f5e.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: 0.99 0.97 0.99 4/42 13540 /proc/meminfo: memFree=741660/1048576 swapFree=2076380/2097144 [pid=13538] ppid=13537 vsize=2568 CPUtime=0 /proc/13538/stat : 13538 (p2cudf-trendy-1) S 13537 13538 4159 34819 4229 4202496 377 0 0 0 0 0 0 0 18 0 1 0 879804805 2629632 273 1283457024 134512640 135304128 4289621200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13538/statm: 642 273 233 194 0 28 0 [pid=13539] ppid=13538 vsize=2572 CPUtime=0 /proc/13539/stat : 13539 (p2cudf) S 13538 13538 4159 34819 4229 4202496 355 0 0 0 0 0 0 0 18 0 1 0 879804805 2633728 274 1283457024 134512640 135304128 4287951664 18446744073709551615 4294960130 0 0 4 65536 18446744071564457842 0 0 17 0 0 0 0 /proc/13539/statm: 643 274 234 194 0 29 0 [pid=13540] ppid=13539 vsize=2572 CPUtime=0.01 /proc/13540/stat : 13540 (p2cudf) R 13539 13538 4159 34819 4229 4202560 45 0 0 0 0 1 0 0 25 0 1 0 879804805 2633728 71 1283457024 134512640 135304128 4287951664 18446744073709551615 135193088 0 0 0 65538 0 0 0 17 0 0 0 0 /proc/13540/statm: 643 71 30 194 0 29 0 [startup+0.143354 s] /proc/loadavg: 0.99 0.97 0.99 4/42 13540 /proc/meminfo: memFree=741660/1048576 swapFree=2076380/2097144 [pid=13538] ppid=13537 vsize=2568 CPUtime=0 /proc/13538/stat : 13538 (p2cudf-trendy-1) S 13537 13538 4159 34819 4229 4202496 377 0 0 0 0 0 0 0 18 0 1 0 879804805 2629632 273 1283457024 134512640 135304128 4289621200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13538/statm: 642 273 233 194 0 28 0 [pid=13539] ppid=13538 vsize=2576 CPUtime=0.04 /proc/13539/stat : 13539 (p2cudf) S 13538 13538 4159 34819 4229 4202496 394 2993 0 1 0 0 2 2 25 0 1 0 879804805 2637824 282 1283457024 134512640 135304128 4287951664 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13539/statm: 644 282 241 194 0 30 0 Current children cumulated CPU time (s) 0.04 Current children cumulated vsize (KiB) 5144 [startup+0.213382 s] /proc/loadavg: 0.99 0.97 0.99 4/42 13540 /proc/meminfo: memFree=741660/1048576 swapFree=2076380/2097144 [pid=13538] ppid=13537 vsize=2568 CPUtime=0 /proc/13538/stat : 13538 (p2cudf-trendy-1) S 13537 13538 4159 34819 4229 4202496 377 0 0 0 0 0 0 0 18 0 1 0 879804805 2629632 273 1283457024 134512640 135304128 4289621200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13538/statm: 642 273 233 194 0 28 0 [pid=13539] ppid=13538 vsize=2576 CPUtime=0.04 /proc/13539/stat : 13539 (p2cudf) S 13538 13538 4159 34819 4229 4202496 394 2993 0 1 0 0 2 2 25 0 1 0 879804805 2637824 282 1283457024 134512640 135304128 4287951664 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13539/statm: 644 282 241 194 0 30 0 Current children cumulated CPU time (s) 0.04 Current children cumulated vsize (KiB) 5144 [startup+0.30341 s] /proc/loadavg: 0.99 0.97 0.99 4/42 13540 /proc/meminfo: memFree=741660/1048576 swapFree=2076380/2097144 [pid=13538] ppid=13537 vsize=2568 CPUtime=0 /proc/13538/stat : 13538 (p2cudf-trendy-1) S 13537 13538 4159 34819 4229 4202496 377 0 0 0 0 0 0 0 18 0 1 0 879804805 2629632 273 1283457024 134512640 135304128 4289621200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13538/statm: 642 273 233 194 0 28 0 [pid=13539] ppid=13538 vsize=2576 CPUtime=0.04 /proc/13539/stat : 13539 (p2cudf) S 13538 13538 4159 34819 4229 4202496 394 2993 0 1 0 0 2 2 25 0 1 0 879804805 2637824 282 1283457024 134512640 135304128 4287951664 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13539/statm: 644 282 241 194 0 30 0 Current children cumulated CPU time (s) 0.04 Current children cumulated vsize (KiB) 5144 [startup+0.703526 s] /proc/loadavg: 0.99 0.97 0.99 4/42 13540 /proc/meminfo: memFree=741660/1048576 swapFree=2076380/2097144 [pid=13538] ppid=13537 vsize=2568 CPUtime=0 /proc/13538/stat : 13538 (p2cudf-trendy-1) S 13537 13538 4159 34819 4229 4202496 377 0 0 0 0 0 0 0 18 0 1 0 879804805 2629632 273 1283457024 134512640 135304128 4289621200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13538/statm: 642 273 233 194 0 28 0 [pid=13539] ppid=13538 vsize=2576 CPUtime=0.04 /proc/13539/stat : 13539 (p2cudf) S 13538 13538 4159 34819 4229 4202496 394 2993 0 1 0 0 2 2 25 0 1 0 879804805 2637824 282 1283457024 134512640 135304128 4287951664 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13539/statm: 644 282 241 194 0 30 0 Current children cumulated CPU time (s) 0.04 Current children cumulated vsize (KiB) 5144 [startup+1.5038 s] /proc/loadavg: 0.99 0.97 0.99 4/52 13561 /proc/meminfo: memFree=700116/1048576 swapFree=2076380/2097144 [pid=13538] ppid=13537 vsize=2568 CPUtime=0 /proc/13538/stat : 13538 (p2cudf-trendy-1) S 13537 13538 4159 34819 4229 4202496 377 0 0 0 0 0 0 0 18 0 1 0 879804805 2629632 273 1283457024 134512640 135304128 4289621200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13538/statm: 642 273 233 194 0 28 0 [pid=13539] ppid=13538 vsize=2576 CPUtime=0.04 /proc/13539/stat : 13539 (p2cudf) S 13538 13538 4159 34819 4229 4202496 394 2993 0 1 0 0 2 2 25 0 1 0 879804805 2637824 282 1283457024 134512640 135304128 4287951664 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13539/statm: 644 282 241 194 0 30 0 [pid=13552] ppid=13539 vsize=876996 CPUtime=1.46 /proc/13552/stat : 13552 (java) S 13539 13538 4159 34819 4229 4202496 19632 0 1 0 138 8 0 0 25 0 10 0 879804809 898043904 16230 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/13552/statm: 219249 16230 1729 10 0 216339 0 [pid=13552/tid=13553] ppid=13539 vsize=876996 CPUtime=0.78 /proc/13552/task/13553/stat : 13553 (java) R 13539 13538 4159 34819 4229 4202560 10122 0 1 0 74 4 0 0 25 0 10 0 879804810 898043904 16230 1283457024 134512640 134550932 4293871472 18446744073709551615 4095619462 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=13552/tid=13554] ppid=13539 vsize=876996 CPUtime=0 /proc/13552/task/13554/stat : 13554 (java) S 13539 13538 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 18 0 10 0 879804811 898043904 16230 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 0 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13555] ppid=13539 vsize=876996 CPUtime=0 /proc/13552/task/13555/stat : 13555 (java) S 13539 13538 4159 34819 4229 4202560 9 0 0 0 0 0 0 0 25 0 10 0 879804812 898043904 16230 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13556] ppid=13539 vsize=876996 CPUtime=0 /proc/13552/task/13556/stat : 13556 (java) S 13539 13538 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 25 0 10 0 879804812 898043904 16230 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13557] ppid=13539 vsize=876996 CPUtime=0 /proc/13552/task/13557/stat : 13557 (java) S 13539 13538 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 879804814 898043904 16230 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13558] ppid=13539 vsize=876996 CPUtime=0.52 /proc/13552/task/13558/stat : 13558 (java) R 13539 13538 4159 34819 4229 4202560 7038 0 0 0 51 1 0 0 25 0 10 0 879804814 898043904 16230 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=13552/tid=13559] ppid=13539 vsize=876996 CPUtime=0.13 /proc/13552/task/13559/stat : 13559 (java) R 13539 13538 4159 34819 4229 4202560 1357 0 0 0 12 1 0 0 20 0 10 0 879804814 898043904 16230 1283457024 134512640 134550932 4293871472 18446744073709551615 4152922003 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=13552/tid=13560] ppid=13539 vsize=876996 CPUtime=0 /proc/13552/task/13560/stat : 13560 (java) S 13539 13538 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 25 0 10 0 879804814 898043904 16230 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13561] ppid=13539 vsize=876996 CPUtime=0 /proc/13552/task/13561/stat : 13561 (java) S 13539 13538 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 10 0 879804814 898043904 16230 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.5 Current children cumulated vsize (KiB) 882140 [startup+3.11656 s] /proc/loadavg: 1.15 1.00 1.00 3/52 13561 /proc/meminfo: memFree=644936/1048576 swapFree=2076380/2097144 [pid=13538] ppid=13537 vsize=2568 CPUtime=0 /proc/13538/stat : 13538 (p2cudf-trendy-1) S 13537 13538 4159 34819 4229 4202496 377 0 0 0 0 0 0 0 18 0 1 0 879804805 2629632 273 1283457024 134512640 135304128 4289621200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13538/statm: 642 273 233 194 0 28 0 [pid=13539] ppid=13538 vsize=2576 CPUtime=0.04 /proc/13539/stat : 13539 (p2cudf) S 13538 13538 4159 34819 4229 4202496 394 2993 0 1 0 0 2 2 25 0 1 0 879804805 2637824 282 1283457024 134512640 135304128 4287951664 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13539/statm: 644 282 241 194 0 30 0 [pid=13552] ppid=13539 vsize=879980 CPUtime=3.07 /proc/13552/stat : 13552 (java) S 13539 13538 4159 34819 4229 4202496 44397 0 1 0 286 21 0 0 25 0 10 0 879804809 901099520 39547 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/13552/statm: 219995 39547 1748 10 0 217085 0 [pid=13552/tid=13553] ppid=13539 vsize=879980 CPUtime=1.51 /proc/13552/task/13553/stat : 13553 (java) R 13539 13538 4159 34819 4229 4202560 32339 0 1 0 133 18 0 0 25 0 10 0 879804810 901099520 39547 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=13552/tid=13554] ppid=13539 vsize=879980 CPUtime=0 /proc/13552/task/13554/stat : 13554 (java) S 13539 13538 4159 34819 4229 4202560 5 0 0 0 0 0 0 0 18 0 10 0 879804811 901099520 39547 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 0 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13555] ppid=13539 vsize=879980 CPUtime=0 /proc/13552/task/13555/stat : 13555 (java) S 13539 13538 4159 34819 4229 4202560 9 0 0 0 0 0 0 0 25 0 10 0 879804812 901099520 39547 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13556] ppid=13539 vsize=879980 CPUtime=0 /proc/13552/task/13556/stat : 13556 (java) S 13539 13538 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 25 0 10 0 879804812 901099520 39547 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13557] ppid=13539 vsize=879980 CPUtime=0 /proc/13552/task/13557/stat : 13557 (java) S 13539 13538 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 879804814 901099520 39547 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13558] ppid=13539 vsize=879980 CPUtime=0.8 /proc/13552/task/13558/stat : 13558 (java) R 13539 13538 4159 34819 4229 4202560 7958 0 0 0 79 1 0 0 25 0 10 0 879804814 901099520 39547 1283457024 134512640 134550932 4293871472 18446744073709551615 4150429713 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=13552/tid=13559] ppid=13539 vsize=879980 CPUtime=0.74 /proc/13552/task/13559/stat : 13559 (java) S 13539 13538 4159 34819 4229 4202560 2984 0 0 0 73 1 0 0 15 0 10 0 879804814 901099520 39547 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13560] ppid=13539 vsize=879980 CPUtime=0 /proc/13552/task/13560/stat : 13560 (java) S 13539 13538 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 25 0 10 0 879804814 901099520 39547 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13561] ppid=13539 vsize=879980 CPUtime=0 /proc/13552/task/13561/stat : 13561 (java) S 13539 13538 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 10 0 879804814 901099520 39547 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.11 Current children cumulated vsize (KiB) 885124 [startup+6.30529 s] /proc/loadavg: 1.15 1.00 1.00 4/53 13564 /proc/meminfo: memFree=517588/1048576 swapFree=2076380/2097144 [pid=13538] ppid=13537 vsize=2568 CPUtime=0 /proc/13538/stat : 13538 (p2cudf-trendy-1) S 13537 13538 4159 34819 4229 4202496 377 0 0 0 0 0 0 0 18 0 1 0 879804805 2629632 273 1283457024 134512640 135304128 4289621200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13538/statm: 642 273 233 194 0 28 0 [pid=13539] ppid=13538 vsize=2576 CPUtime=0.04 /proc/13539/stat : 13539 (p2cudf) S 13538 13538 4159 34819 4229 4202496 394 2993 0 1 0 0 2 2 25 0 1 0 879804805 2637824 282 1283457024 134512640 135304128 4287951664 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13539/statm: 644 282 241 194 0 30 0 [pid=13552] ppid=13539 vsize=884780 CPUtime=6.26 /proc/13552/stat : 13552 (java) S 13539 13538 4159 34819 4229 4202496 76524 0 1 0 594 32 0 0 25 0 13 0 879804809 906014720 68521 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/13552/statm: 221195 68521 1792 10 0 218285 0 [pid=13552/tid=13553] ppid=13539 vsize=884780 CPUtime=2.76 /proc/13552/task/13553/stat : 13553 (java) R 13539 13538 4159 34819 4229 4202560 50467 0 1 0 252 24 0 0 25 0 13 0 879804810 906014720 68521 1283457024 134512640 134550932 4293871472 18446744073709551615 4096846478 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=13552/tid=13554] ppid=13539 vsize=884780 CPUtime=0.24 /proc/13552/task/13554/stat : 13554 (java) S 13539 13538 4159 34819 4229 4202560 9493 0 0 0 21 3 0 0 15 0 13 0 879804811 906014720 68521 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 0 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13555] ppid=13539 vsize=884780 CPUtime=0 /proc/13552/task/13555/stat : 13555 (java) S 13539 13538 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 18 0 13 0 879804812 906014720 68521 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13556] ppid=13539 vsize=884780 CPUtime=0 /proc/13552/task/13556/stat : 13556 (java) S 13539 13538 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 13 0 879804812 906014720 68521 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13557] ppid=13539 vsize=884780 CPUtime=0 /proc/13552/task/13557/stat : 13557 (java) S 13539 13538 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 13 0 879804814 906014720 68521 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13558] ppid=13539 vsize=884780 CPUtime=1.64 /proc/13552/task/13558/stat : 13558 (java) S 13539 13538 4159 34819 4229 4202560 9036 0 0 0 163 1 0 0 16 0 13 0 879804814 906014720 68521 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13559] ppid=13539 vsize=884780 CPUtime=1.59 /proc/13552/task/13559/stat : 13559 (java) S 13539 13538 4159 34819 4229 4202560 6402 0 0 0 157 2 0 0 16 0 13 0 879804814 906014720 68521 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13560] ppid=13539 vsize=884780 CPUtime=0 /proc/13552/task/13560/stat : 13560 (java) S 13539 13538 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 25 0 13 0 879804814 906014720 68521 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13561] ppid=13539 vsize=884780 CPUtime=0 /proc/13552/task/13561/stat : 13561 (java) S 13539 13538 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 13 0 879804814 906014720 68521 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.3 Current children cumulated vsize (KiB) 889924 Solver just ended. Dumping a history of the last processes samples [startup+6.4053 s] /proc/loadavg: 1.15 1.00 1.00 4/53 13564 /proc/meminfo: memFree=517588/1048576 swapFree=2076380/2097144 [pid=13538] ppid=13537 vsize=2568 CPUtime=0 /proc/13538/stat : 13538 (p2cudf-trendy-1) S 13537 13538 4159 34819 4229 4202496 377 0 0 0 0 0 0 0 18 0 1 0 879804805 2629632 273 1283457024 134512640 135304128 4289621200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13538/statm: 642 273 233 194 0 28 0 [pid=13539] ppid=13538 vsize=2576 CPUtime=0.04 /proc/13539/stat : 13539 (p2cudf) S 13538 13538 4159 34819 4229 4202496 394 2993 0 1 0 0 2 2 25 0 1 0 879804805 2637824 282 1283457024 134512640 135304128 4287951664 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13539/statm: 644 282 241 194 0 30 0 [pid=13552] ppid=13539 vsize=884780 CPUtime=6.36 /proc/13552/stat : 13552 (java) S 13539 13538 4159 34819 4229 4202496 76524 0 1 0 604 32 0 0 25 0 11 0 879804809 906014720 68521 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/13552/statm: 221195 68521 1792 10 0 218285 0 [pid=13552/tid=13553] ppid=13539 vsize=884780 CPUtime=2.86 /proc/13552/task/13553/stat : 13553 (java) R 13539 13538 4159 34819 4229 4202560 50467 0 1 0 262 24 0 0 25 0 11 0 879804810 906014720 68521 1283457024 134512640 134550932 4293871472 18446744073709551615 4096684411 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=13552/tid=13554] ppid=13539 vsize=884780 CPUtime=0.24 /proc/13552/task/13554/stat : 13554 (java) S 13539 13538 4159 34819 4229 4202560 9493 0 0 0 21 3 0 0 16 0 11 0 879804811 906014720 68521 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 0 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13555] ppid=13539 vsize=884780 CPUtime=0 /proc/13552/task/13555/stat : 13555 (java) S 13539 13538 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 18 0 11 0 879804812 906014720 68521 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13556] ppid=13539 vsize=884780 CPUtime=0 /proc/13552/task/13556/stat : 13556 (java) S 13539 13538 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 11 0 879804812 906014720 68521 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13557] ppid=13539 vsize=884780 CPUtime=0 /proc/13552/task/13557/stat : 13557 (java) S 13539 13538 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 879804814 906014720 68521 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13558] ppid=13539 vsize=884780 CPUtime=1.64 /proc/13552/task/13558/stat : 13558 (java) S 13539 13538 4159 34819 4229 4202560 9036 0 0 0 163 1 0 0 16 0 11 0 879804814 906014720 68521 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13559] ppid=13539 vsize=884780 CPUtime=1.59 /proc/13552/task/13559/stat : 13559 (java) S 13539 13538 4159 34819 4229 4202560 6402 0 0 0 157 2 0 0 16 0 11 0 879804814 906014720 68521 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13560] ppid=13539 vsize=884780 CPUtime=0 /proc/13552/task/13560/stat : 13560 (java) S 13539 13538 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 25 0 11 0 879804814 906014720 68521 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13561] ppid=13539 vsize=884780 CPUtime=0 /proc/13552/task/13561/stat : 13561 (java) S 13539 13538 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 879804814 906014720 68521 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.4 Current children cumulated vsize (KiB) 889924 [startup+6.8078 s] /proc/loadavg: 1.15 1.00 1.00 4/55 13574 /proc/meminfo: memFree=473800/1048576 swapFree=2076380/2097144 [pid=13538] ppid=13537 vsize=2568 CPUtime=0 /proc/13538/stat : 13538 (p2cudf-trendy-1) S 13537 13538 4159 34819 4229 4202496 377 0 0 0 0 0 0 0 18 0 1 0 879804805 2629632 273 1283457024 134512640 135304128 4289621200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13538/statm: 642 273 233 194 0 28 0 [pid=13539] ppid=13538 vsize=2576 CPUtime=0.04 /proc/13539/stat : 13539 (p2cudf) S 13538 13538 4159 34819 4229 4202496 394 2993 0 1 0 0 2 2 25 0 1 0 879804805 2637824 282 1283457024 134512640 135304128 4287951664 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13539/statm: 644 282 241 194 0 30 0 [pid=13552] ppid=13539 vsize=885104 CPUtime=6.65 /proc/13552/stat : 13552 (java) S 13539 13538 4159 34819 4229 4202496 76536 0 1 0 633 32 0 0 25 0 11 0 879804809 906346496 68533 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/13552/statm: 221276 68533 1792 10 0 218366 0 [pid=13552/tid=13553] ppid=13539 vsize=885104 CPUtime=3.1 /proc/13552/task/13553/stat : 13553 (java) S 13539 13538 4159 34819 4229 4202560 50475 0 1 0 286 24 0 0 25 0 11 0 879804810 906346496 68533 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13554] ppid=13539 vsize=885104 CPUtime=0.24 /proc/13552/task/13554/stat : 13554 (java) S 13539 13538 4159 34819 4229 4202560 9493 0 0 0 21 3 0 0 15 0 11 0 879804811 906346496 68533 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 0 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13555] ppid=13539 vsize=885104 CPUtime=0 /proc/13552/task/13555/stat : 13555 (java) S 13539 13538 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 18 0 11 0 879804812 906346496 68533 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13556] ppid=13539 vsize=885104 CPUtime=0 /proc/13552/task/13556/stat : 13556 (java) S 13539 13538 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 11 0 879804812 906346496 68533 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13557] ppid=13539 vsize=885104 CPUtime=0 /proc/13552/task/13557/stat : 13557 (java) S 13539 13538 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 879804814 906346496 68533 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13558] ppid=13539 vsize=885104 CPUtime=1.69 /proc/13552/task/13558/stat : 13558 (java) S 13539 13538 4159 34819 4229 4202560 9036 0 0 0 168 1 0 0 15 0 11 0 879804814 906346496 68533 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13559] ppid=13539 vsize=885104 CPUtime=1.59 /proc/13552/task/13559/stat : 13559 (java) S 13539 13538 4159 34819 4229 4202560 6402 0 0 0 157 2 0 0 16 0 11 0 879804814 906346496 68533 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13560] ppid=13539 vsize=885104 CPUtime=0 /proc/13552/task/13560/stat : 13560 (java) S 13539 13538 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 25 0 11 0 879804814 906346496 68533 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13561] ppid=13539 vsize=885104 CPUtime=0 /proc/13552/task/13561/stat : 13561 (java) S 13539 13538 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 879804814 906346496 68533 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.69 Current children cumulated vsize (KiB) 890248 [startup+7.22135 s] /proc/loadavg: 1.15 1.00 1.00 4/55 13574 /proc/meminfo: memFree=473800/1048576 swapFree=2076380/2097144 [pid=13538] ppid=13537 vsize=2568 CPUtime=0 /proc/13538/stat : 13538 (p2cudf-trendy-1) S 13537 13538 4159 34819 4229 4202496 377 0 0 0 0 0 0 0 18 0 1 0 879804805 2629632 273 1283457024 134512640 135304128 4289621200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13538/statm: 642 273 233 194 0 28 0 [pid=13539] ppid=13538 vsize=2576 CPUtime=0.04 /proc/13539/stat : 13539 (p2cudf) S 13538 13538 4159 34819 4229 4202496 394 2993 0 1 0 0 2 2 25 0 1 0 879804805 2637824 282 1283457024 134512640 135304128 4287951664 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13539/statm: 644 282 241 194 0 30 0 [pid=13552] ppid=13539 vsize=885104 CPUtime=6.66 /proc/13552/stat : 13552 (java) S 13539 13538 4159 34819 4229 4202496 76538 0 1 0 634 32 0 0 25 0 11 0 879804809 906346496 68535 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/13552/statm: 221276 68535 1792 10 0 218366 0 [pid=13552/tid=13553] ppid=13539 vsize=885104 CPUtime=3.1 /proc/13552/task/13553/stat : 13553 (java) S 13539 13538 4159 34819 4229 4202560 50475 0 1 0 286 24 0 0 25 0 11 0 879804810 906346496 68535 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13554] ppid=13539 vsize=885104 CPUtime=0.24 /proc/13552/task/13554/stat : 13554 (java) S 13539 13538 4159 34819 4229 4202560 9493 0 0 0 21 3 0 0 15 0 11 0 879804811 906346496 68535 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 0 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13555] ppid=13539 vsize=885104 CPUtime=0 /proc/13552/task/13555/stat : 13555 (java) S 13539 13538 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 18 0 11 0 879804812 906346496 68535 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13556] ppid=13539 vsize=885104 CPUtime=0 /proc/13552/task/13556/stat : 13556 (java) S 13539 13538 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 11 0 879804812 906346496 68535 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13557] ppid=13539 vsize=885104 CPUtime=0 /proc/13552/task/13557/stat : 13557 (java) S 13539 13538 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 879804814 906346496 68535 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13558] ppid=13539 vsize=885104 CPUtime=1.7 /proc/13552/task/13558/stat : 13558 (java) S 13539 13538 4159 34819 4229 4202560 9038 0 0 0 169 1 0 0 15 0 11 0 879804814 906346496 68535 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13559] ppid=13539 vsize=885104 CPUtime=1.59 /proc/13552/task/13559/stat : 13559 (java) S 13539 13538 4159 34819 4229 4202560 6402 0 0 0 157 2 0 0 16 0 11 0 879804814 906346496 68535 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13560] ppid=13539 vsize=885104 CPUtime=0 /proc/13552/task/13560/stat : 13560 (java) S 13539 13538 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 25 0 11 0 879804814 906346496 68535 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13561] ppid=13539 vsize=885104 CPUtime=0 /proc/13552/task/13561/stat : 13561 (java) S 13539 13538 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 879804814 906346496 68535 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.7 Current children cumulated vsize (KiB) 890248 [startup+7.30396 s] /proc/loadavg: 1.15 1.00 1.00 4/55 13574 /proc/meminfo: memFree=473800/1048576 swapFree=2076380/2097144 [pid=13538] ppid=13537 vsize=2568 CPUtime=0 /proc/13538/stat : 13538 (p2cudf-trendy-1) S 13537 13538 4159 34819 4229 4202496 377 0 0 0 0 0 0 0 18 0 1 0 879804805 2629632 273 1283457024 134512640 135304128 4289621200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13538/statm: 642 273 233 194 0 28 0 [pid=13539] ppid=13538 vsize=2576 CPUtime=0.04 /proc/13539/stat : 13539 (p2cudf) S 13538 13538 4159 34819 4229 4202496 394 2993 0 1 0 0 2 2 25 0 1 0 879804805 2637824 282 1283457024 134512640 135304128 4287951664 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/13539/statm: 644 282 241 194 0 30 0 [pid=13552] ppid=13539 vsize=885104 CPUtime=6.75 /proc/13552/stat : 13552 (java) S 13539 13538 4159 34819 4229 4202496 76539 0 1 0 642 33 0 0 25 0 11 0 879804809 906346496 68536 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/13552/statm: 221276 68536 1792 10 0 218366 0 [pid=13552/tid=13553] ppid=13539 vsize=885104 CPUtime=3.1 /proc/13552/task/13553/stat : 13553 (java) S 13539 13538 4159 34819 4229 4202560 50475 0 1 0 286 24 0 0 25 0 11 0 879804810 906346496 68536 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13554] ppid=13539 vsize=885104 CPUtime=0.24 /proc/13552/task/13554/stat : 13554 (java) S 13539 13538 4159 34819 4229 4202560 9493 0 0 0 21 3 0 0 15 0 11 0 879804811 906346496 68536 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 0 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13555] ppid=13539 vsize=885104 CPUtime=0 /proc/13552/task/13555/stat : 13555 (java) S 13539 13538 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 18 0 11 0 879804812 906346496 68536 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13556] ppid=13539 vsize=885104 CPUtime=0 /proc/13552/task/13556/stat : 13556 (java) S 13539 13538 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 11 0 879804812 906346496 68536 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13557] ppid=13539 vsize=885104 CPUtime=0 /proc/13552/task/13557/stat : 13557 (java) S 13539 13538 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 879804814 906346496 68536 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13558] ppid=13539 vsize=885104 CPUtime=1.71 /proc/13552/task/13558/stat : 13558 (java) S 13539 13538 4159 34819 4229 4202560 9038 0 0 0 170 1 0 0 15 0 11 0 879804814 906346496 68536 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13559] ppid=13539 vsize=885104 CPUtime=1.59 /proc/13552/task/13559/stat : 13559 (java) S 13539 13538 4159 34819 4229 4202560 6402 0 0 0 157 2 0 0 16 0 11 0 879804814 906346496 68536 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13560] ppid=13539 vsize=885104 CPUtime=0 /proc/13552/task/13560/stat : 13560 (java) S 13539 13538 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 25 0 11 0 879804814 906346496 68536 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0 [pid=13552/tid=13561] ppid=13539 vsize=885104 CPUtime=0 /proc/13552/task/13561/stat : 13561 (java) R 13539 13538 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 879804814 906346496 68536 1283457024 134512640 134550932 4293871472 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.79 Current children cumulated vsize (KiB) 890248 Child status: 0 Real time (s): 7.37987 CPU time (s): 6.85243 CPU user time (s): 6.4844 CPU system time (s): 0.368023 CPU usage (%): 92.8529 Max. virtual memory (cumulated for all children) (KiB): 892132 getrusage(RUSAGE_CHILDREN,...) data: user time used= 6.4844 system time used= 0.368023 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 80329 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= 1074 involuntary context switches= 1014 runsolver used 0 second user time and 0 second system time The end