MMDVM restarts then only works with TS2

Help with DMR issues
Post Reply
kc5afm
Posts: 1
Joined: Wed Jun 02, 2021 3:28 am

MMDVM restarts then only works with TS2

Post by kc5afm »

I have reproduced this on multiple hotspots, though they are nearly identical hardware. All are the generic Chinese MMDVM duplex boards on raspberry Pi.

The issue is the MMDVMHost service seems to be crashing and restarting and then after it comes back up, only the talkgroup from TS2 works and I never hear anything on TS1 (even though if I talk on TS1 it is heard on the talkgroup, but is one -way). This is easy to see if I set both TS1 and TS2 to the same talkgroup as I have in the logs below. You will see both TS1 and TS2 are receiving the same TG, then it appears MMDVM crashes and restarts and then only TS2 is receiving the TG.

I'll likely post this in the pistar forums too, just wondered if anyone else saw this as it may only affect duplex boards.

The hotspots are all running Pistar 4.1.4 with firmware 1.5.2.

Here's what I see in the logs, and this seems to be caused by something cron.daily is running (I haven't made any changes to cron, not using dmr gateway in fact dmrgateway shows disabled, etc. just standard config):

M: 2021-05-26 07:56:46.882 DMR Slot 1, received network end of voice transmission from VE3RD to TG 31665, 0.1 seconds, 0% packet loss, BER: 0.0%
M: 2021-05-26 08:11:08.204 DMR Slot 2, received network voice header from K9SAH to TG 31665
M: 2021-05-26 08:11:08.209 DMR Slot 1, received network voice header from K9SAH to TG 31665
M: 2021-05-26 08:11:08.313 DMR Slot 2, received network end of voice transmission from K9SAH to TG 31665, 0.1 seconds, 0% packet loss, BER: 0.0%
M: 2021-05-26 08:11:08.318 DMR Slot 1, received network end of voice transmission from K9SAH to TG 31665, 0.1 seconds, 0% packet loss, BER: 0.0%
M: 2021-05-26 08:12:23.181 DMR Slot 2, received network voice header from K9SAH to TG 31665
M: 2021-05-26 08:12:23.187 DMR Slot 1, received network voice header from K9SAH to TG 31665
M: 2021-05-26 08:12:23.275 DMR Slot 2, received network end of voice transmission from K9SAH to TG 31665, 0.1 seconds, 0% packet loss, BER: 0.0%
M: 2021-05-26 08:12:23.280 DMR Slot 1, received network end of voice transmission from K9SAH to TG 31665, 0.1 seconds, 0% packet loss, BER: 0.0%
M: 2021-05-26 08:57:07.367 Closing the MMDVM
I: 2021-05-26 08:57:08.311 Stopped the DMR Id lookup reload thread
M: 2021-05-26 08:57:08.311 DMR, Closing DMR Network
I: 2021-05-26 08:57:08.311 MMDVMHost-20200615_Pi-Star_v4 exited on receipt of SIGTERM
I: 2021-05-26 08:57:14.854 This software is for use on amateur radio networks only,
I: 2021-05-26 08:57:14.854 it is to be used for educational purposes only. Its use on
I: 2021-05-26 08:57:14.854 commercial networks is strictly prohibited.
I: 2021-05-26 08:57:14.854 Copyright(C) 2015-2020 by Jonathan Naylor, G4KLX and others
M: 2021-05-26 08:57:14.854 MMDVMHost-20200615_Pi-Star_v4 is starting
M: 2021-05-26 08:57:14.854 Built 17:21:54 Jun 15 2020 (GitID #3313fe8)
~~skipping the dump of parameters here

It seems MMDVM is crashing and restarting and when it comes back, I only have TS2, not receiving anything from TS1:

M: 2021-05-26 09:01:07.426 DMR Slot 2, received network voice header from K4WPO to TG 31665
M: 2021-05-26 09:01:08.915 DMR Slot 2, received network end of voice transmission from K4WPO to TG 31665, 1.5 seconds, 0% packet loss, BER: 0.0%
M: 2021-05-26 09:14:41.064 DMR Slot 2, received network voice header from W8TVR to TG 31665
M: 2021-05-26 09:14:42.621 DMR Slot 2, network watchdog has expired, 0.1 seconds, 0% packet loss, BER: 0.0%
M: 2021-05-26 09:30:54.628 DMR Slot 2, received network voice header from N8KQ to TG 31665
M: 2021-05-26 09:30:55.167 DMR Slot 2, received network end of voice transmission from N8KQ to TG 31665, 0.5 seconds, 0% packet loss, BER: 0.0%
M: 2021-05-26 09:36:06.424 DMR Slot 2, received network voice header from VK6KBY to TG 31665
M: 2021-05-26 09:36:08.401 DMR Slot 2, received network end of voice transmission from VK6KBY to TG 31665, 1.9 seconds, 0% packet loss, BER: 0.0%



pi-star@pi-star(rw):pi-star$ grep Stopped *
MMDVM-2021-05-26.log:I: 2021-05-26 08:57:08.311 Stopped the DMR Id lookup reload thread
MMDVM-2021-05-27.log:I: 2021-05-27 08:57:08.228 Stopped the DMR Id lookup reload thread


I see that cron.daily runs every morning on the 57 minute of hour 3, which would be 08:57 for central time:

57 3 * * * root mount -o remount,rw / && cd / && run-parts --report /etc/cron.daily

in syslog, I see:
May 27 03:57:01 pi-star CRON[17744]: (root) CMD (mount -o remount,rw / && cd / && run-parts --report /etc/cron.daily)
May 27 03:57:01 pi-star kernel: [887565.720408] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
May 27 03:57:02 pi-star kernel: [887566.604431] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
May 27 03:57:04 pi-star pistar-watchdog.service[8216]: Warning: Stopping dmrgateway.service, but it can still be activated by:
May 27 03:57:04 pi-star pistar-watchdog.service[8216]: dmrgateway.timer
May 27 03:57:06 pi-star systemd[1]: Starting DMRGateway Radio Servce...
May 27 03:57:06 pi-star systemd[1]: dmrgateway.service: Control process exited, code=exited, status=1/FAILURE
May 27 03:57:06 pi-star systemd[1]: dmrgateway.service: Failed with result 'exit-code'.
May 27 03:57:06 pi-star systemd[1]: Failed to start DMRGateway Radio Servce.
May 27 03:57:06 pi-star pistar-watchdog.service[8216]: Job for dmrgateway.service failed because the control process exited with error code.
May 27 03:57:06 pi-star pistar-watchdog.service[8216]: See "systemctl status dmrgateway.service" and "journalctl -xe" for details.
May 27 03:57:07 pi-star systemd[1]: Stopping PiStar-Watchdog Service Watchdog...
May 27 03:57:07 pi-star pistar-watchdog.service[18085]: Killing pistar-watchdog PID 8218
May 27 03:57:07 pi-star systemd[1]: pistar-watchdog.service: Main process exited, code=killed, status=15/TERM
May 27 03:57:07 pi-star systemd[1]: pistar-watchdog.service: Succeeded.
May 27 03:57:07 pi-star systemd[1]: Stopped PiStar-Watchdog Service Watchdog.
May 27 03:57:07 pi-star systemd[1]: Stopping MMDVMHost Radio Servce...
May 27 03:57:07 pi-star mmdvmhost.service[18091]: Killing MMDVMHost PID 8208
May 27 03:57:08 pi-star systemd[1]: mmdvmhost.service: Succeeded.
May 27 03:57:08 pi-star systemd[1]: Stopped MMDVMHost Radio Servce.

What could be causing it to try to restart the services?
Post Reply