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/201103031013/p2cudf-trendy-1.12/3e4f8550-0b33-11df-942d-00163e1d94dc.cudf.debian.log.runsolver ./p2cudf-trendy-1.12 /home/misc2010/data/misc4/debian//3e4f8550-0b33-11df-942d-00163e1d94dc.cudf /home/misc2010/tmp/201103031013/p2cudf-trendy-1.12/3e4f8550-0b33-11df-942d-00163e1d94dc.cudf.debian.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.27 1.13 1.02 5/40 18674 /proc/meminfo: memFree=683584/1048576 swapFree=0/0 [pid=18672] ppid=18671 vsize=2564 CPUtime=0 /proc/18672/stat : 18672 (p2cudf-trendy-1) S 18671 18672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74883884 2625536 270 1283457024 134512640 135304128 4288179072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18672/statm: 641 270 230 194 0 28 0 [pid=18673] ppid=18672 vsize=2564 CPUtime=0 /proc/18673/stat : 18673 (p2cudf) R 18672 18672 29493 34817 29493 4202496 344 0 0 0 0 0 0 0 25 0 1 0 74883884 2625536 270 1283457024 134512640 135304128 4292337504 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/18673/statm: 641 270 231 194 0 28 0 [pid=18674] ppid=18673 vsize=2564 CPUtime=0 /proc/18674/stat : 18674 (p2cudf) R 18673 18672 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 1 0 74883884 2625536 39 1283457024 134512640 135304128 4292337504 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/18674/statm: 641 39 0 194 0 28 0 [startup+0.155817 s] /proc/loadavg: 1.27 1.13 1.02 5/40 18674 /proc/meminfo: memFree=683584/1048576 swapFree=0/0 [pid=18672] ppid=18671 vsize=2564 CPUtime=0 /proc/18672/stat : 18672 (p2cudf-trendy-1) S 18671 18672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74883884 2625536 270 1283457024 134512640 135304128 4288179072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18672/statm: 641 270 230 194 0 28 0 [pid=18673] ppid=18672 vsize=2568 CPUtime=0.03 /proc/18673/stat : 18673 (p2cudf) S 18672 18672 29493 34817 29493 4202496 388 3010 0 1 1 0 2 0 25 0 1 0 74883884 2629632 279 1283457024 134512640 135304128 4292337504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18673/statm: 642 279 239 194 0 29 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5132 [startup+0.205814 s] /proc/loadavg: 1.27 1.13 1.02 5/40 18674 /proc/meminfo: memFree=683584/1048576 swapFree=0/0 [pid=18672] ppid=18671 vsize=2564 CPUtime=0 /proc/18672/stat : 18672 (p2cudf-trendy-1) S 18671 18672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74883884 2625536 270 1283457024 134512640 135304128 4288179072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18672/statm: 641 270 230 194 0 28 0 [pid=18673] ppid=18672 vsize=2568 CPUtime=0.03 /proc/18673/stat : 18673 (p2cudf) S 18672 18672 29493 34817 29493 4202496 388 3010 0 1 1 0 2 0 25 0 1 0 74883884 2629632 279 1283457024 134512640 135304128 4292337504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18673/statm: 642 279 239 194 0 29 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5132 [startup+0.305836 s] /proc/loadavg: 1.27 1.13 1.02 5/40 18674 /proc/meminfo: memFree=683584/1048576 swapFree=0/0 [pid=18672] ppid=18671 vsize=2564 CPUtime=0 /proc/18672/stat : 18672 (p2cudf-trendy-1) S 18671 18672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74883884 2625536 270 1283457024 134512640 135304128 4288179072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18672/statm: 641 270 230 194 0 28 0 [pid=18673] ppid=18672 vsize=2568 CPUtime=0.03 /proc/18673/stat : 18673 (p2cudf) S 18672 18672 29493 34817 29493 4202496 388 3010 0 1 1 0 2 0 25 0 1 0 74883884 2629632 279 1283457024 134512640 135304128 4292337504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18673/statm: 642 279 239 194 0 29 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5132 [startup+0.705927 s] /proc/loadavg: 1.27 1.13 1.02 5/40 18674 /proc/meminfo: memFree=683584/1048576 swapFree=0/0 [pid=18672] ppid=18671 vsize=2564 CPUtime=0 /proc/18672/stat : 18672 (p2cudf-trendy-1) S 18671 18672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74883884 2625536 270 1283457024 134512640 135304128 4288179072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18672/statm: 641 270 230 194 0 28 0 [pid=18673] ppid=18672 vsize=2568 CPUtime=0.03 /proc/18673/stat : 18673 (p2cudf) S 18672 18672 29493 34817 29493 4202496 388 3010 0 1 1 0 2 0 25 0 1 0 74883884 2629632 279 1283457024 134512640 135304128 4292337504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18673/statm: 642 279 239 194 0 29 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5132 [startup+1.50609 s] /proc/loadavg: 1.27 1.13 1.02 3/50 18695 /proc/meminfo: memFree=630732/1048576 swapFree=0/0 [pid=18672] ppid=18671 vsize=2564 CPUtime=0 /proc/18672/stat : 18672 (p2cudf-trendy-1) S 18671 18672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74883884 2625536 270 1283457024 134512640 135304128 4288179072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18672/statm: 641 270 230 194 0 28 0 [pid=18673] ppid=18672 vsize=2568 CPUtime=0.03 /proc/18673/stat : 18673 (p2cudf) S 18672 18672 29493 34817 29493 4202496 388 3010 0 1 1 0 2 0 25 0 1 0 74883884 2629632 279 1283457024 134512640 135304128 4292337504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18673/statm: 642 279 239 194 0 29 0 [pid=18686] ppid=18673 vsize=885776 CPUtime=1.46 /proc/18686/stat : 18686 (java) S 18673 18672 29493 34817 29493 4202496 22243 0 1 0 142 4 0 0 25 0 10 0 74883887 907034624 18848 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/18686/statm: 221444 18848 1724 10 0 218535 0 [pid=18686/tid=18687] ppid=18673 vsize=885776 CPUtime=0.79 /proc/18686/task/18687/stat : 18687 (java) R 18673 18672 29493 34817 29493 4202560 10593 0 1 0 75 4 0 0 25 0 10 0 74883888 907034624 18848 1283457024 134512640 134550932 4290659424 18446744073709551615 4153301284 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=18686/tid=18688] ppid=18673 vsize=885776 CPUtime=0 /proc/18686/task/18688/stat : 18688 (java) S 18673 18672 29493 34817 29493 4202560 7 0 0 0 0 0 0 0 16 0 10 0 74883889 907034624 18848 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 0 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18689] ppid=18673 vsize=885776 CPUtime=0 /proc/18686/task/18689/stat : 18689 (java) S 18673 18672 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 74883890 907034624 18848 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18690] ppid=18673 vsize=885776 CPUtime=0 /proc/18686/task/18690/stat : 18690 (java) S 18673 18672 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 74883890 907034624 18848 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18691] ppid=18673 vsize=885776 CPUtime=0 /proc/18686/task/18691/stat : 18691 (java) S 18673 18672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 74883892 907034624 18848 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18692] ppid=18673 vsize=885776 CPUtime=0.43 /proc/18686/task/18692/stat : 18692 (java) R 18673 18672 29493 34817 29493 4202560 8469 0 0 0 43 0 0 0 25 0 10 0 74883892 907034624 18848 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=18686/tid=18693] ppid=18673 vsize=885776 CPUtime=0.23 /proc/18686/task/18693/stat : 18693 (java) R 18673 18672 29493 34817 29493 4202560 2103 0 0 0 23 0 0 0 18 0 10 0 74883892 907034624 18848 1283457024 134512640 134550932 4290659424 18446744073709551615 4149874768 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=18686/tid=18694] ppid=18673 vsize=885776 CPUtime=0 /proc/18686/task/18694/stat : 18694 (java) S 18673 18672 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 10 0 74883892 907034624 18848 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18695] ppid=18673 vsize=885776 CPUtime=0 /proc/18686/task/18695/stat : 18695 (java) S 18673 18672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 74883892 907034624 18848 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.49 Current children cumulated vsize (KiB) 890908 [startup+3.10644 s] /proc/loadavg: 1.27 1.13 1.02 2/50 18695 /proc/meminfo: memFree=536492/1048576 swapFree=0/0 [pid=18672] ppid=18671 vsize=2564 CPUtime=0 /proc/18672/stat : 18672 (p2cudf-trendy-1) S 18671 18672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74883884 2625536 270 1283457024 134512640 135304128 4288179072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18672/statm: 641 270 230 194 0 28 0 [pid=18673] ppid=18672 vsize=2568 CPUtime=0.03 /proc/18673/stat : 18673 (p2cudf) S 18672 18672 29493 34817 29493 4202496 388 3010 0 1 1 0 2 0 25 0 1 0 74883884 2629632 279 1283457024 134512640 135304128 4292337504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18673/statm: 642 279 239 194 0 29 0 [pid=18686] ppid=18673 vsize=882136 CPUtime=3.06 /proc/18686/stat : 18686 (java) S 18673 18672 29493 34817 29493 4202496 74855 0 1 0 285 21 0 0 25 0 10 0 74883887 903307264 70854 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/18686/statm: 220534 70854 1743 10 0 217625 0 [pid=18686/tid=18687] ppid=18673 vsize=882136 CPUtime=1.83 /proc/18686/task/18687/stat : 18687 (java) R 18673 18672 29493 34817 29493 4202560 50067 0 1 0 165 18 0 0 25 0 10 0 74883888 903307264 70854 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=18686/tid=18688] ppid=18673 vsize=882136 CPUtime=0.27 /proc/18686/task/18688/stat : 18688 (java) S 18673 18672 29493 34817 29493 4202560 12953 0 0 0 24 3 0 0 16 0 10 0 74883889 903307264 70854 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 0 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18689] ppid=18673 vsize=882136 CPUtime=0 /proc/18686/task/18689/stat : 18689 (java) S 18673 18672 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 10 0 74883890 903307264 70854 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18690] ppid=18673 vsize=882136 CPUtime=0 /proc/18686/task/18690/stat : 18690 (java) S 18673 18672 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 10 0 74883890 903307264 70854 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18691] ppid=18673 vsize=882136 CPUtime=0 /proc/18686/task/18691/stat : 18691 (java) S 18673 18672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 74883892 903307264 70854 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18692] ppid=18673 vsize=882136 CPUtime=0.53 /proc/18686/task/18692/stat : 18692 (java) R 18673 18672 29493 34817 29493 4202560 8534 0 0 0 53 0 0 0 16 0 10 0 74883892 903307264 70854 1283457024 134512640 134550932 4290659424 18446744073709551615 4150410680 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=18686/tid=18693] ppid=18673 vsize=882136 CPUtime=0.42 /proc/18686/task/18693/stat : 18693 (java) S 18673 18672 29493 34817 29493 4202560 2228 0 0 0 42 0 0 0 16 0 10 0 74883892 903307264 70854 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18694] ppid=18673 vsize=882136 CPUtime=0 /proc/18686/task/18694/stat : 18694 (java) S 18673 18672 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 10 0 74883892 903307264 70854 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18695] ppid=18673 vsize=882136 CPUtime=0 /proc/18686/task/18695/stat : 18695 (java) S 18673 18672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 74883892 903307264 70854 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.09 Current children cumulated vsize (KiB) 887268 [startup+6.30739 s] /proc/loadavg: 1.25 1.12 1.02 4/51 18696 /proc/meminfo: memFree=348880/1048576 swapFree=0/0 [pid=18672] ppid=18671 vsize=2564 CPUtime=0 /proc/18672/stat : 18672 (p2cudf-trendy-1) S 18671 18672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74883884 2625536 270 1283457024 134512640 135304128 4288179072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18672/statm: 641 270 230 194 0 28 0 [pid=18673] ppid=18672 vsize=2568 CPUtime=0.03 /proc/18673/stat : 18673 (p2cudf) S 18672 18672 29493 34817 29493 4202496 388 3010 0 1 1 0 2 0 25 0 1 0 74883884 2629632 279 1283457024 134512640 135304128 4292337504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18673/statm: 642 279 239 194 0 29 0 [pid=18686] ppid=18673 vsize=882628 CPUtime=6.26 /proc/18686/stat : 18686 (java) S 18673 18672 29493 34817 29493 4202496 90809 0 1 0 594 32 0 0 25 0 11 0 74883887 903811072 85219 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/18686/statm: 220657 85219 1778 10 0 217748 0 [pid=18686/tid=18687] ppid=18673 vsize=882628 CPUtime=2.97 /proc/18686/task/18687/stat : 18687 (java) R 18673 18672 29493 34817 29493 4202560 50260 0 1 0 279 18 0 0 25 0 11 0 74883888 903811072 85219 1283457024 134512640 134550932 4290659424 18446744073709551615 4096527718 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=18686/tid=18688] ppid=18673 vsize=882628 CPUtime=0.6 /proc/18686/task/18688/stat : 18688 (java) S 18673 18672 29493 34817 29493 4202560 26829 0 0 0 49 11 0 0 16 0 11 0 74883889 903811072 85219 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 0 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18689] ppid=18673 vsize=882628 CPUtime=0 /proc/18686/task/18689/stat : 18689 (java) S 18673 18672 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 74883890 903811072 85219 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18690] ppid=18673 vsize=882628 CPUtime=0 /proc/18686/task/18690/stat : 18690 (java) S 18673 18672 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 74883890 903811072 85219 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18691] ppid=18673 vsize=882628 CPUtime=0 /proc/18686/task/18691/stat : 18691 (java) S 18673 18672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74883892 903811072 85219 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18692] ppid=18673 vsize=882628 CPUtime=2.24 /proc/18686/task/18692/stat : 18692 (java) S 18673 18672 29493 34817 29493 4202560 10415 0 0 0 222 2 0 0 15 0 11 0 74883892 903811072 85219 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18693] ppid=18673 vsize=882628 CPUtime=0.42 /proc/18686/task/18693/stat : 18693 (java) S 18673 18672 29493 34817 29493 4202560 2228 0 0 0 42 0 0 0 16 0 11 0 74883892 903811072 85219 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18694] ppid=18673 vsize=882628 CPUtime=0 /proc/18686/task/18694/stat : 18694 (java) S 18673 18672 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 11 0 74883892 903811072 85219 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18695] ppid=18673 vsize=882628 CPUtime=0 /proc/18686/task/18695/stat : 18695 (java) S 18673 18672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 74883892 903811072 85219 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18696] ppid=18673 vsize=882628 CPUtime=0 /proc/18686/task/18696/stat : 18696 (java) S 18673 18672 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 24 0 11 0 74884422 903811072 85219 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.29 Current children cumulated vsize (KiB) 887760 Solver just ended. Dumping a history of the last processes samples [startup+6.4074 s] /proc/loadavg: 1.25 1.12 1.02 4/51 18696 /proc/meminfo: memFree=348880/1048576 swapFree=0/0 [pid=18672] ppid=18671 vsize=2564 CPUtime=0 /proc/18672/stat : 18672 (p2cudf-trendy-1) S 18671 18672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74883884 2625536 270 1283457024 134512640 135304128 4288179072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18672/statm: 641 270 230 194 0 28 0 [pid=18673] ppid=18672 vsize=2568 CPUtime=0.03 /proc/18673/stat : 18673 (p2cudf) S 18672 18672 29493 34817 29493 4202496 388 3010 0 1 1 0 2 0 25 0 1 0 74883884 2629632 279 1283457024 134512640 135304128 4292337504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18673/statm: 642 279 239 194 0 29 0 [pid=18686] ppid=18673 vsize=882628 CPUtime=6.36 /proc/18686/stat : 18686 (java) S 18673 18672 29493 34817 29493 4202496 90810 0 1 0 604 32 0 0 25 0 11 0 74883887 903811072 85220 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/18686/statm: 220657 85220 1778 10 0 217748 0 [pid=18686/tid=18687] ppid=18673 vsize=882628 CPUtime=3.06 /proc/18686/task/18687/stat : 18687 (java) R 18673 18672 29493 34817 29493 4202560 50260 0 1 0 288 18 0 0 25 0 11 0 74883888 903811072 85220 1283457024 134512640 134550932 4290659424 18446744073709551615 4096639980 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=18686/tid=18688] ppid=18673 vsize=882628 CPUtime=0.6 /proc/18686/task/18688/stat : 18688 (java) S 18673 18672 29493 34817 29493 4202560 26829 0 0 0 49 11 0 0 16 0 11 0 74883889 903811072 85220 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 0 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18689] ppid=18673 vsize=882628 CPUtime=0 /proc/18686/task/18689/stat : 18689 (java) S 18673 18672 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 74883890 903811072 85220 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18690] ppid=18673 vsize=882628 CPUtime=0 /proc/18686/task/18690/stat : 18690 (java) S 18673 18672 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 74883890 903811072 85220 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18691] ppid=18673 vsize=882628 CPUtime=0 /proc/18686/task/18691/stat : 18691 (java) S 18673 18672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74883892 903811072 85220 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18692] ppid=18673 vsize=882628 CPUtime=2.26 /proc/18686/task/18692/stat : 18692 (java) S 18673 18672 29493 34817 29493 4202560 10416 0 0 0 224 2 0 0 15 0 11 0 74883892 903811072 85220 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18693] ppid=18673 vsize=882628 CPUtime=0.42 /proc/18686/task/18693/stat : 18693 (java) S 18673 18672 29493 34817 29493 4202560 2228 0 0 0 42 0 0 0 16 0 11 0 74883892 903811072 85220 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18694] ppid=18673 vsize=882628 CPUtime=0 /proc/18686/task/18694/stat : 18694 (java) S 18673 18672 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 11 0 74883892 903811072 85220 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18695] ppid=18673 vsize=882628 CPUtime=0 /proc/18686/task/18695/stat : 18695 (java) S 18673 18672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 74883892 903811072 85220 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18696] ppid=18673 vsize=882628 CPUtime=0 /proc/18686/task/18696/stat : 18696 (java) S 18673 18672 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 24 0 11 0 74884422 903811072 85220 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.39 Current children cumulated vsize (KiB) 887760 [startup+7.20768 s] /proc/loadavg: 1.25 1.12 1.02 2/51 18697 /proc/meminfo: memFree=349748/1048576 swapFree=0/0 [pid=18672] ppid=18671 vsize=2564 CPUtime=0 /proc/18672/stat : 18672 (p2cudf-trendy-1) S 18671 18672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74883884 2625536 270 1283457024 134512640 135304128 4288179072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18672/statm: 641 270 230 194 0 28 0 [pid=18673] ppid=18672 vsize=2568 CPUtime=0.03 /proc/18673/stat : 18673 (p2cudf) S 18672 18672 29493 34817 29493 4202496 388 3010 0 1 1 0 2 0 25 0 1 0 74883884 2629632 279 1283457024 134512640 135304128 4292337504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18673/statm: 642 279 239 194 0 29 0 [pid=18686] ppid=18673 vsize=880948 CPUtime=7.16 /proc/18686/stat : 18686 (java) S 18673 18672 29493 34817 29493 4202496 90827 0 1 0 684 32 0 0 25 0 11 0 74883887 902090752 84840 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/18686/statm: 220237 84840 1779 10 0 217328 0 [pid=18686/tid=18687] ppid=18673 vsize=880948 CPUtime=3.71 /proc/18686/task/18687/stat : 18687 (java) R 18673 18672 29493 34817 29493 4202560 50260 0 1 0 353 18 0 0 25 0 11 0 74883888 902090752 84840 1283457024 134512640 134550932 4290659424 18446744073709551615 4096727666 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=18686/tid=18688] ppid=18673 vsize=880948 CPUtime=0.6 /proc/18686/task/18688/stat : 18688 (java) S 18673 18672 29493 34817 29493 4202560 26829 0 0 0 49 11 0 0 16 0 11 0 74883889 902090752 84840 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 0 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18689] ppid=18673 vsize=880948 CPUtime=0 /proc/18686/task/18689/stat : 18689 (java) S 18673 18672 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 74883890 902090752 84840 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18690] ppid=18673 vsize=880948 CPUtime=0 /proc/18686/task/18690/stat : 18690 (java) S 18673 18672 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 74883890 902090752 84840 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18691] ppid=18673 vsize=880948 CPUtime=0 /proc/18686/task/18691/stat : 18691 (java) S 18673 18672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74883892 902090752 84840 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18692] ppid=18673 vsize=880948 CPUtime=2.4 /proc/18686/task/18692/stat : 18692 (java) S 18673 18672 29493 34817 29493 4202560 10433 0 0 0 238 2 0 0 16 0 11 0 74883892 902090752 84840 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18693] ppid=18673 vsize=880948 CPUtime=0.42 /proc/18686/task/18693/stat : 18693 (java) S 18673 18672 29493 34817 29493 4202560 2228 0 0 0 42 0 0 0 16 0 11 0 74883892 902090752 84840 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18694] ppid=18673 vsize=880948 CPUtime=0 /proc/18686/task/18694/stat : 18694 (java) S 18673 18672 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 11 0 74883892 902090752 84840 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18695] ppid=18673 vsize=880948 CPUtime=0 /proc/18686/task/18695/stat : 18695 (java) S 18673 18672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 74883892 902090752 84840 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18696] ppid=18673 vsize=880948 CPUtime=0 /proc/18686/task/18696/stat : 18696 (java) S 18673 18672 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 24 0 11 0 74884422 902090752 84840 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 7.19 Current children cumulated vsize (KiB) 886080 [startup+7.60623 s] /proc/loadavg: 1.25 1.12 1.02 2/51 18697 /proc/meminfo: memFree=349748/1048576 swapFree=0/0 [pid=18672] ppid=18671 vsize=2564 CPUtime=0 /proc/18672/stat : 18672 (p2cudf-trendy-1) S 18671 18672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74883884 2625536 270 1283457024 134512640 135304128 4288179072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18672/statm: 641 270 230 194 0 28 0 [pid=18673] ppid=18672 vsize=2568 CPUtime=0.03 /proc/18673/stat : 18673 (p2cudf) S 18672 18672 29493 34817 29493 4202496 388 3010 0 1 1 0 2 0 25 0 1 0 74883884 2629632 279 1283457024 134512640 135304128 4292337504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18673/statm: 642 279 239 194 0 29 0 [pid=18686] ppid=18673 vsize=881272 CPUtime=7.4 /proc/18686/stat : 18686 (java) S 18673 18672 29493 34817 29493 4202496 90847 0 1 0 708 32 0 0 25 0 12 0 74883887 902422528 84860 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/18686/statm: 220318 84860 1779 10 0 217409 0 [pid=18686/tid=18687] ppid=18673 vsize=881272 CPUtime=3.9 /proc/18686/task/18687/stat : 18687 (java) S 18673 18672 29493 34817 29493 4202560 50269 0 1 0 372 18 0 0 25 0 12 0 74883888 902422528 84860 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18688] ppid=18673 vsize=881272 CPUtime=0.6 /proc/18686/task/18688/stat : 18688 (java) S 18673 18672 29493 34817 29493 4202560 26829 0 0 0 49 11 0 0 16 0 12 0 74883889 902422528 84860 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 0 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18689] ppid=18673 vsize=881272 CPUtime=0 /proc/18686/task/18689/stat : 18689 (java) S 18673 18672 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 12 0 74883890 902422528 84860 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18690] ppid=18673 vsize=881272 CPUtime=0 /proc/18686/task/18690/stat : 18690 (java) S 18673 18672 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 12 0 74883890 902422528 84860 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18691] ppid=18673 vsize=881272 CPUtime=0 /proc/18686/task/18691/stat : 18691 (java) S 18673 18672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 74883892 902422528 84860 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18692] ppid=18673 vsize=881272 CPUtime=2.46 /proc/18686/task/18692/stat : 18692 (java) S 18673 18672 29493 34817 29493 4202560 10440 0 0 0 244 2 0 0 15 0 12 0 74883892 902422528 84860 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18693] ppid=18673 vsize=881272 CPUtime=0.42 /proc/18686/task/18693/stat : 18693 (java) S 18673 18672 29493 34817 29493 4202560 2228 0 0 0 42 0 0 0 16 0 12 0 74883892 902422528 84860 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18694] ppid=18673 vsize=881272 CPUtime=0 /proc/18686/task/18694/stat : 18694 (java) S 18673 18672 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 12 0 74883892 902422528 84860 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18695] ppid=18673 vsize=881272 CPUtime=0 /proc/18686/task/18695/stat : 18695 (java) S 18673 18672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 12 0 74883892 902422528 84860 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18696] ppid=18673 vsize=881272 CPUtime=0 /proc/18686/task/18696/stat : 18696 (java) S 18673 18672 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 24 0 12 0 74884422 902422528 84860 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 7.43 Current children cumulated vsize (KiB) 886404 [startup+8.00627 s] /proc/loadavg: 1.25 1.12 1.02 1/52 18698 /proc/meminfo: memFree=349740/1048576 swapFree=0/0 [pid=18672] ppid=18671 vsize=2564 CPUtime=0 /proc/18672/stat : 18672 (p2cudf-trendy-1) S 18671 18672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74883884 2625536 270 1283457024 134512640 135304128 4288179072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18672/statm: 641 270 230 194 0 28 0 [pid=18673] ppid=18672 vsize=2568 CPUtime=0.03 /proc/18673/stat : 18673 (p2cudf) S 18672 18672 29493 34817 29493 4202496 388 3010 0 1 1 0 2 0 25 0 1 0 74883884 2629632 279 1283457024 134512640 135304128 4292337504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18673/statm: 642 279 239 194 0 29 0 [pid=18686] ppid=18673 vsize=881272 CPUtime=7.42 /proc/18686/stat : 18686 (java) S 18673 18672 29493 34817 29493 4202496 90850 0 1 0 710 32 0 0 25 0 11 0 74883887 902422528 84863 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/18686/statm: 220318 84863 1780 10 0 217409 0 [pid=18686/tid=18687] ppid=18673 vsize=881272 CPUtime=3.9 /proc/18686/task/18687/stat : 18687 (java) S 18673 18672 29493 34817 29493 4202560 50269 0 1 0 372 18 0 0 20 0 11 0 74883888 902422528 84863 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18688] ppid=18673 vsize=881272 CPUtime=0.6 /proc/18686/task/18688/stat : 18688 (java) S 18673 18672 29493 34817 29493 4202560 26829 0 0 0 49 11 0 0 16 0 11 0 74883889 902422528 84863 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 0 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18689] ppid=18673 vsize=881272 CPUtime=0 /proc/18686/task/18689/stat : 18689 (java) S 18673 18672 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 74883890 902422528 84863 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18690] ppid=18673 vsize=881272 CPUtime=0 /proc/18686/task/18690/stat : 18690 (java) S 18673 18672 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 74883890 902422528 84863 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18691] ppid=18673 vsize=881272 CPUtime=0 /proc/18686/task/18691/stat : 18691 (java) S 18673 18672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74883892 902422528 84863 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18692] ppid=18673 vsize=881272 CPUtime=2.48 /proc/18686/task/18692/stat : 18692 (java) S 18673 18672 29493 34817 29493 4202560 10442 0 0 0 246 2 0 0 16 0 11 0 74883892 902422528 84863 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18693] ppid=18673 vsize=881272 CPUtime=0.42 /proc/18686/task/18693/stat : 18693 (java) S 18673 18672 29493 34817 29493 4202560 2228 0 0 0 42 0 0 0 16 0 11 0 74883892 902422528 84863 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18694] ppid=18673 vsize=881272 CPUtime=0 /proc/18686/task/18694/stat : 18694 (java) S 18673 18672 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 11 0 74883892 902422528 84863 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 [pid=18686/tid=18695] ppid=18673 vsize=881272 CPUtime=0 /proc/18686/task/18695/stat : 18695 (java) R 18673 18672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 74883892 902422528 84863 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=18686/tid=18696] ppid=18673 vsize=881272 CPUtime=0 /proc/18686/task/18696/stat : 18696 (java) S 18673 18672 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 24 0 11 0 74884422 902422528 84863 1283457024 134512640 134550932 4290659424 18446744073709551615 4294960130 0 4 0 16800975 18446612132910121024 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 7.45 Current children cumulated vsize (KiB) 886404 Child status: 0 Real time (s): 8.04699 CPU time (s): 7.50047 CPU user time (s): 7.13244 CPU system time (s): 0.368023 CPU usage (%): 93.2084 Max. virtual memory (cumulated for all children) (KiB): 891444 getrusage(RUSAGE_CHILDREN,...) data: user time used= 7.13244 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= 94647 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= 859 involuntary context switches= 867 runsolver used 0 second user time and 0 second system time The end