A46 prealpha ./mis poll forced consuming 100% CPU
From
Zip@21:1/202 to
g00r00 on Sun Mar 15 13:13:14 2020
Hello g00r00!
Just noticed that my A46 prealpha MIS poll subprocess ('./mis poll forced') is consuming almost 100% CPU since 00:28 this morning:
bbs 11329 0.0 0.0 647572 5468 ? SLsl mar14 0:33 /home/bbs/mystic/mis root /home/bbs/mystic daemon
bbs 26825 0.0 0.0 2388 756 ? S 00:28 0:00 /bin/sh -c ./mis poll forced 1> /dev/null 2> /dev/null
bbs 26826 99.9 0.0 225636 5620 ? RLl 00:28 755:39 ./mis poll forced
From 'top':
PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND 26826 bbs 20 0 225636 5620 4528 R 93,3 0,1 757:52.23 mis
It is constantly doing this:
bbs@glimmer:~$ strace -f -vvv -s65535 -p 26826 2>&1
/../
[pid 26826] clock_gettime(CLOCK_MONOTONIC, {tv_sec=3034047,
tv_nsec=429325184}) = 0
[pid 26826] select(1, [0], NULL, NULL, {tv_sec=0, tv_usec=250000}) = 1 (in
[0], left {tv_sec=0, tv_usec=249999})
[pid 26826] select(1, [0], NULL, NULL, {tv_sec=0, tv_usec=0}) = 1 (in [0],
left {tv_sec=0, tv_usec=0})
[pid 26826] read(0, "", 256) = 0
[pid 26826] select(1, [0], NULL, NULL, {tv_sec=0, tv_usec=0}) = 1 (in [0],
left {tv_sec=0, tv_usec=0})
[pid 26826] read(0, "", 256) = 0
[pid 26826] clock_gettime(CLOCK_MONOTONIC, {tv_sec=3034047,
tv_nsec=429457506}) = 0
[pid 26826] select(1, [0], NULL, NULL, {tv_sec=0, tv_usec=250000}) = 1 (in
[0], left {tv_sec=0, tv_usec=249999})
[pid 26826] select(1, [0], NULL, NULL, {tv_sec=0, tv_usec=0}) = 1 (in [0],
left {tv_sec=0, tv_usec=0})
[pid 26826] read(0, "", 256) = 0
[pid 26826] select(1, [0], NULL, NULL, {tv_sec=0, tv_usec=0}) = 1 (in [0],
left {tv_sec=0, tv_usec=0})
[pid 26826] read(0, "", 256) = 0
bbs@glimmer:~$ ps uaxwww |grep mis
bbs 5123 0.0 0.0 8932 820 pts/0 S+ 13:03 0:00 grep mis
bbs 11329 0.0 0.0 647572 5468 ? SLsl mar14 0:33 /home/bbs/mystic/mis root /home/bbs/mystic daemon
bbs 26825 0.0 0.0 2388 756 ? S 00:28 0:00 /bin/sh -c ./mis poll forced 1> /dev/null 2> /dev/null
bbs 26826 99.9 0.0 225636 5620 ? RLl 00:28 755:39 ./mis poll forced
When I check poll.log, it appears that it succeeded polling:
--------------------- POLL v1.12 A46 2020/03/11 Sun, Mar 15 2020 (loglevel 2) + 2020.03.15 00:28:04 Polling all nodes of session type ALL
+ 2020.03.15 00:28:04 1-Polling 21:1/100 on slot 1 via BINKP
+ 2020.03.15 00:28:04 1-Connecting to agency.bbs.nz on port 24553
+ 2020.03.15 00:28:07 1-Connected by IPV4 SSL to 219.89.83.33
+ 2020.03.15 00:28:08 1-System fsxHUB Risa [NET1]
+ 2020.03.15 00:28:08 1-SysOp Avon
+ 2020.03.15 00:28:08 1-Info TIME Sun, 15 Mar 2020 12:27:57 +1300
+ 2020.03.15 00:28:08 1-Mailer Mystic/1.12A46 binkp/1.0
+ 2020.03.15 00:28:08 1-Info BUILD 2020/03/12 04:19:41 Windows/32
+ 2020.03.15 00:28:09 1-Remote Queue: 0 files 0 bytes
+ 2020.03.15 00:28:09 1-Session ended (0 sent, 0 rcvd, 0 skip)
+ 2020.03.15 00:28:10 Polled 1 systems
Any ideas what could be causing this?
I had to restart MIS to resolve this.
Many thanks in advance! =)
Best regards
Zip
--- Mystic BBS v1.12 A46 2020/03/11 (Linux/64)
* Origin: Star Collision BBS, Uppsala, Sweden (21:1/202)