#rms RMSGW hanging up #rms


@K5LVL
 

I've had a digi/rmsgw setup going for a few months and I've always had this problem since setup, but I've mitigated with nightly reboot about 0200.  When someone connects it will never send data to/from the remote side.  Usually a reboot fixes the issue, however that's a bandaid fix and has apparently started hanging more frequently as of late.

My rmsgw callsign is K5LVL-10  posting some logs from rms.debug below

Oct  5 12:46:57 draws rmsgw[14152]: getopt saw '-P'
Oct  5 12:46:57 draws rmsgw[14152]:      optarg = udr0
Oct  5 12:46:57 draws rmsgw[14152]: configfilearg = (null)
Oct  5 12:46:57 draws rmsgw[14152]: configfile = /usr/local/etc/rmsgw/gateway.conf
Oct  5 12:46:57 draws rmsgw[14152]: using config file /usr/local/etc/rmsgw/gateway.conf
Oct  5 12:46:57 draws rmsgw[14152]: using version file /usr/local/etc/rmsgw/.version_info
Oct  5 12:46:57 draws rmsgw[14152]: #012K5LVL-10 - Linux RMS Gateway 2.5.1 Nov 10 2020 (EM51hi)#012
Oct  5 12:46:57 draws rmsgw[14152]: Channel: K5LVL-10 on udr0 (441150000 Hz, mode 0)
Oct  5 12:46:57 draws rmsgw[14152]: Login WB5TJK on udr0 connected to cms.winlink.org
Oct  5 12:46:57 draws rmsgw[14152]: *** Secure Gateway Logon
Oct  5 12:48:36 draws rmsgw[14152]: Logout WB5TJK    tx:54 rx:0 99.0s 0.5 Bytes/s (1)
Oct  5 13:14:01 draws rmsgw_aci[20675]: K5LVL-10 - Linux RMS Gateway ACI 2.5.1 Nov 10 2020 (EM51hi)
Oct  5 13:14:01 draws rmsgw_aci[20675]: Channel: K5LVL-10 on udr0 (441150000 Hz, mode 0)
Oct  5 13:14:01 draws rmsgw_aci[20675]: Channel Stats: 1 read, 1 active, 0 down, 0 updated, 0 errors


Basil Gunn
 

Set LOGMASK=DEBUG in config file /etc/rmsgw/gateway.conf

Reboot, then post the output of a 'good' session and a 'bad' session in
/var/log/rms.debug

What are you using for an Internet connection, wired or WiFi?

Refresh your local repository:
cd
cd n7nix
git pull
cd config
./bin_refresh.sh

What are your ALSA settings before and after a reboot?
show-alsa.sh

What power setting are you using on your radio? (low, medium, high)
What kind (manufacturer & model number) of radio are you using?
Which mDin6 connector are you using? (left or right)

Please post the output of buginfo.sh after refreshing your local repo

cd
cd n7nix/bin
./buginfo.sh

In a console run:
sudo su
listen -a

Post output of a 'good' & 'bad' session.


kylemcdonald87@gmail.com writes:

I've had a digi/rmsgw setup going for a few months and I've always had
this problem since setup, but I've mitigated with nightly reboot about
0200. When someone connects it will never send data to/from the
remote side. Usually a reboot fixes the issue, however that's a
bandaid fix and has apparently started hanging more frequently as of
late.

My rmsgw callsign is K5LVL-10 posting some logs from rms.debug below

Oct 5 12:46:57 draws rmsgw[14152]: getopt saw '-P'
Oct 5 12:46:57 draws rmsgw[14152]: optarg = udr0
Oct 5 12:46:57 draws rmsgw[14152]: configfilearg = (null)
Oct 5 12:46:57 draws rmsgw[14152]: configfile = /usr/local/etc/rmsgw/gateway.conf
Oct 5 12:46:57 draws rmsgw[14152]: using config file /usr/local/etc/rmsgw/gateway.conf
Oct 5 12:46:57 draws rmsgw[14152]: using version file /usr/local/etc/rmsgw/.version_info
Oct 5 12:46:57 draws rmsgw[14152]: #012K5LVL-10 - Linux RMS Gateway 2.5.1 Nov 10 2020 (EM51hi)#012
Oct 5 12:46:57 draws rmsgw[14152]: Channel: K5LVL-10 on udr0 (441150000 Hz, mode 0)
Oct 5 12:46:57 draws rmsgw[14152]: Login WB5TJK on udr0 connected to cms.winlink.org
Oct 5 12:46:57 draws rmsgw[14152]: *** Secure Gateway Logon
Oct 5 12:48:36 draws rmsgw[14152]: Logout WB5TJK tx:54 rx:0 99.0s 0.5 Bytes/s (1)
Oct 5 13:14:01 draws rmsgw_aci[20675]: K5LVL-10 - Linux RMS Gateway ACI 2.5.1 Nov 10 2020 (EM51hi)
Oct 5 13:14:01 draws rmsgw_aci[20675]: Channel: K5LVL-10 on udr0 (441150000 Hz, mode 0)
Oct 5 13:14:01 draws rmsgw_aci[20675]: Channel Stats: 1 read, 1 active, 0 down, 0 updated, 0 errors


@K5LVL
 

Hey Basil,

Sorry for the late response, I didn't get an email notification that you had replied.  I did the refresh on the local repository and nothing has worked correctly since.  I may end up having to image my SD card back(luckily i have a recent backup).  The internet connection is on Wi-Fi, however I'm pretty certain there are no issues with random disconnects unless the raspberry pi has some kind of power management I'm not aware of.

I'll try to get you some log captures after I get it back up and going,

This is what is currently showing over and over in the rms.debug log
 /usr/local/bin/rmschanstat ax25 udr0 K5LVL-10 reports unavailable


Kyle


@K5LVL
 

Just did a bit of troubleshooting... aplay -l is not finding the udrc device.. output of showudrc.sh below

==== Sound Card ====
No udrc sound card found.

==== Pi Ver ====
 Pi 3 Model B+, Rev 1.3, Mfg by Sony UK with WiFi
Hardware        : BCM2835
Revision        : a020d3
Serial          : 000000006f2b226d
Model           : Raspberry Pi 3 Model B Plus Rev 1.3

==== Pi Firmware VideoCore Ver ====
Nov 30 2020 22:13:46
Copyright (c) 2012 Broadcom
version ab1181cc0cb6df52bfae3b1d3fef0ce7c325166c (clean) (release) (start)

==== Pi Firmware EEPROM Ver ====
unknown

==== Pi Firmware EEPROM Config ====


==== udrc Ver ====
Found a DRAWS

     HAT ID EEPROM
Name:        hat
Product:     Digital Radio Amateur Work Station
Product ID:  0x0004
Product ver: 0x0108
UUID:        5dc76af8-9d48-48b9-a4cd-17a136a49da9
Vendor:      NW Digital Radio

==== sys Ver ====
----- image version
2021 02 16 13:42:49 PST: SD image version: nwdr19
----- /proc/version
Linux version 5.4.79-v7+ (dom@buildbot) (gcc version 8.4.0 (Ubuntu/Linaro 8.4.0-3ubuntu1)) #1373 SMP Mon Nov 23 13:22:33 GMT 2020

----- /etc/*version: 10.10

----- /etc/*release
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"

----- lsb_release
No LSB modules are available.
Distributor ID: Raspbian
Description:    Raspbian GNU/Linux 10 (buster)
Release:        10
Codename:       buster

---- systemd
   Static hostname: draws
         Icon name: computer
        Machine ID: 27ac1f0c20904cb7a960920e966db545
           Boot ID: 7245520924df4f0784785d3fadb63c11
  Operating System: Raspbian GNU/Linux 10 (buster)
            Kernel: Linux 5.4.79-v7+
      Architecture: arm
        sd card id: 1b534d47423151543015e467ca012c03

---- sound modules
NO UDRC sound devices found

---- i2c devices
ls: cannot access '/dev/i2c*': No such file or directory
ret: 2

---- ALL snd modules
snd_soc_simple_card    20480  0
snd_soc_simple_card_utils    24576  1 snd_soc_simple_card
snd_soc_bcm2835_i2s    16384  0
snd_soc_core          200704  3 snd_soc_simple_card_utils,snd_soc_bcm2835_i2s,snd_soc_simple_card
snd_bcm2835            28672  2
snd_compress           20480  1 snd_soc_core
snd_pcm_dmaengine      16384  1 snd_soc_core
snd_pcm                94208  4 snd_pcm_dmaengine,snd_soc_bcm2835_i2s,snd_bcm2835,snd_soc_core
snd_timer              32768  1 snd_pcm
snd                    73728  9 snd_compress,snd_timer,snd_bcm2835,snd_soc_core,snd_pcm
ret: 0

---- kernel
|/ Err?=(none)/Reinst-required (Status,Err: uppercase=bad)
||/ Name               Version      Architecture Description
+++-==================-============-============-=================================
hi  raspberrypi-kernel 1.20201201-1 armhf        Raspberry Pi bootloader

---- Codec drivers
Directory: /proc/device-tree/soc/i2c@7e804000/tlv320aic32x4@18 exists and status is okay

---- syslog

---- dmesg


----- Dire Wolf DEVELOPMENT version 1.7 A (Feb 15 2021)

==== Filesystem ====
/dev/root        30G  5.8G   23G  21% /

==== boot config ====
[pi4]
dtoverlay=vc4-fkms-v3d
[all]
dtoverlay=
dtoverlay=draws,alsaname=udrc
force_turbo=1
dtparam=audio=on

---- gpsd
/usr/local/sbin/gpsd
gpsd: 3.22 (revision 3.22)
● gpsd.service - GPS (Global Positioning System) Daemon
   Loaded: loaded (/lib/systemd/system/gpsd.service; enabled; vendor preset: enabled)
   Active: active (running) since Fri 2021-10-08 18:20:33 CDT; 3min 19s ago
  Process: 567 ExecStart=/usr/sbin/gpsd $GPSD_OPTIONS $DEVICES (code=exited, status=0/SUCCESS)
 Main PID: 575 (gpsd)
    Tasks: 1 (limit: 2063)
   CGroup: /system.slice/gpsd.service
           └─575 /usr/sbin/gpsd -n /dev/ttySC0 /dev/pps0

Oct 08 18:20:33 draws systemd[1]: Starting GPS (Global Positioning System) Daemon...
Oct 08 18:20:33 draws systemd[1]: Started GPS (Global Positioning System) Daemon.

---- chrony
crw------- 1 root root    239, 0 Oct  8 18:20 /dev/pps0
crw-rw---- 1 root dialout 237, 0 Oct  8 18:20 /dev/ttySC0
crw-rw---- 1 root dialout 237, 1 Oct  8 18:20 /dev/ttySC1
-- chrony sources
210 Number of sources = 10
MS Name/IP address         Stratum Poll Reach LastRx Last sample
===============================================================================
#? GPS                           0   3     0     -     +0ns[   +0ns] +/-    0ns
#? PPS                           0   3     0     -     +0ns[   +0ns] +/-    0ns
^- 165.227.106.11                3   6    77    26  -1274us[-1274us] +/-   62ms
^- radio-sunshine.org            2   6    77    26  +1531us[+1531us] +/-   96ms
^* time.cloudflare.com           3   6    77    26   +380us[ +376us] +/-   25ms
^? ntp14.doctor.com              0   8     0     -     +0ns[   +0ns] +/-    0ns
^? au.kashra.pictures            0   6     0     -     +0ns[   +0ns] +/-    0ns
^? time.gac.edu                  0   6     0     -     +0ns[   +0ns] +/-    0ns
^? ntp.xtom.com                  0   6     0     -     +0ns[   +0ns] +/-    0ns
^? 2620:1d5:0:43::74             0   6     0     -     +0ns[   +0ns] +/-    0ns
-- chrony tracking
Reference ID    : A29FC801 (time.cloudflare.com)
Stratum         : 4
Ref time (UTC)  : Fri Oct 08 23:23:26 2021
System time     : 0.000254401 seconds fast of NTP time
Last offset     : -0.000003491 seconds
RMS offset      : 0.000003491 seconds
Frequency       : 5.548 ppm fast
Residual freq   : -6.567 ppm
Skew            : 0.060 ppm
Root delay      : 0.048275169 seconds
Root dispersion : 0.002238920 seconds
Update interval : 64.4 seconds
Leap status     : Normal
-- chrony sourcestats
210 Number of sources = 10
Name/IP Address            NP  NR  Span  Frequency  Freq Skew  Offset  Std Dev
==============================================================================
GPS                         0   0     0     +0.000   2000.000     +0ns  4000ms
PPS                         0   0     0     +0.000   2000.000     +0ns  4000ms
165.227.106.11              6   6   137     -8.524     41.980  -1518us   655us
radio-sunshine.org          6   4   137     -5.197     80.605  +1164us  1150us
time.cloudflare.com         6   3   136     -6.567     95.824   -175us  1233us
ntp14.doctor.com            0   0     0     +0.000   2000.000     +0ns  4000ms
au.kashra.pictures          0   0     0     +0.000   2000.000     +0ns  4000ms
time.gac.edu                0   0     0     +0.000   2000.000     +0ns  4000ms
ntp.xtom.com                0   0     0     +0.000   2000.000     +0ns  4000ms
2620:1d5:0:43::74           0   0     0     +0.000   2000.000     +0ns  4000ms

---- sensors
-rw-r--r-- 1 root root 209 Aug 21  2020 /etc/sensors.d/draws
iio_hwmon-isa-0000
Adapter: ISA adapter
+12V:        +12.21 V
 +5V:         +5.02 V
User ADC 1:   +0.00 V
User ADC 2:   +0.00 V

cpu_thermal-virtual-0
Adapter: Virtual device
temp1:        +49.4°C

rpi_volt-isa-0000
Adapter: ISA adapter
in0:              N/A


---- throttle
temp=49.9'C
throttled=0x0

---- locale
Locale country codes consistent among WiFi cfg file, iw reg & X11: US
core_config.sh has been run 1 time(s)


Basil Gunn
 

Thanks for the showudrc.sh output. I like to see the buginfo.sh output
as well. From the showudrc.sh output:

Your Linux kernel version is fine
Can read the DRAWS EEPROM
The tlv32aic32x4 codec was found but the drivers did not load
The gps is not working.

Try rebooting and sending me the output of buginfo.sh
Also I should have a new image in the next couple of days you could try.
Not sure why the drivers are not loading, you are running a known good
kernel.

What are you using to power the RPi/DRAWS with?
My power supply is feeding +13.6 with the 5V sensor reading 5.04V

You are powering with 12.2 V and 5V sensor of 5.02V. Should be fine but
may be a little low.


kylemcdonald87@gmail.com writes:

Just did a bit of troubleshooting... aplay -l is not finding the udrc device.. output of showudrc.sh below

==== Sound Card ====
No udrc sound card found.

==== Pi Ver ====
Pi 3 Model B+, Rev 1.3, Mfg by Sony UK with WiFi
Hardware : BCM2835
Revision : a020d3
Serial : 000000006f2b226d
Model : Raspberry Pi 3 Model B Plus Rev 1.3

==== Pi Firmware VideoCore Ver ====
Nov 30 2020 22:13:46
Copyright (c) 2012 Broadcom
version ab1181cc0cb6df52bfae3b1d3fef0ce7c325166c (clean) (release) (start)

==== Pi Firmware EEPROM Ver ====
unknown

==== Pi Firmware EEPROM Config ====

==== udrc Ver ====
Found a DRAWS

HAT ID EEPROM
Name: hat
Product: Digital Radio Amateur Work Station
Product ID: 0x0004
Product ver: 0x0108
UUID: 5dc76af8-9d48-48b9-a4cd-17a136a49da9
Vendor: NW Digital Radio

==== sys Ver ====
----- image version
2021 02 16 13:42:49 PST: SD image version: nwdr19
----- /proc/version
Linux version 5.4.79-v7+ (dom@buildbot) (gcc version 8.4.0 (Ubuntu/Linaro 8.4.0-3ubuntu1)) #1373 SMP Mon Nov 23 13:22:33 GMT 2020

----- /etc/*version: 10.10

----- /etc/*release
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"

----- lsb_release
No LSB modules are available.
Distributor ID: Raspbian
Description: Raspbian GNU/Linux 10 (buster)
Release: 10
Codename: buster

---- systemd
Static hostname: draws
Icon name: computer
Machine ID: 27ac1f0c20904cb7a960920e966db545
Boot ID: 7245520924df4f0784785d3fadb63c11
Operating System: Raspbian GNU/Linux 10 (buster)
Kernel: Linux 5.4.79-v7+
Architecture: arm
sd card id: 1b534d47423151543015e467ca012c03

---- sound modules
NO UDRC sound devices found

---- i2c devices
ls: cannot access '/dev/i2c*': No such file or directory
ret: 2

---- ALL snd modules
snd_soc_simple_card 20480 0
snd_soc_simple_card_utils 24576 1 snd_soc_simple_card
snd_soc_bcm2835_i2s 16384 0
snd_soc_core 200704 3 snd_soc_simple_card_utils,snd_soc_bcm2835_i2s,snd_soc_simple_card
snd_bcm2835 28672 2
snd_compress 20480 1 snd_soc_core
snd_pcm_dmaengine 16384 1 snd_soc_core
snd_pcm 94208 4 snd_pcm_dmaengine,snd_soc_bcm2835_i2s,snd_bcm2835,snd_soc_core
snd_timer 32768 1 snd_pcm
snd 73728 9 snd_compress,snd_timer,snd_bcm2835,snd_soc_core,snd_pcm
ret: 0

---- kernel
|/ Err?=(none)/Reinst-required (Status,Err: uppercase=bad)
||/ Name Version Architecture Description
+++-==================-============-============-=================================
hi raspberrypi-kernel 1.20201201-1 armhf Raspberry Pi bootloader

---- Codec drivers
Directory: /proc/device-tree/soc/i2c@7e804000/tlv320aic32x4@18 exists and status is okay

---- syslog

---- dmesg

----- Dire Wolf DEVELOPMENT version 1.7 A (Feb 15 2021)

==== Filesystem ====
/dev/root 30G 5.8G 23G 21% /

==== boot config ====
[pi4]
dtoverlay=vc4-fkms-v3d
[all]
dtoverlay=
dtoverlay=draws,alsaname=udrc
force_turbo=1
dtparam=audio=on

---- gpsd
/usr/local/sbin/gpsd
gpsd: 3.22 (revision 3.22)
● gpsd.service - GPS (Global Positioning System) Daemon
Loaded: loaded (/lib/systemd/system/gpsd.service; enabled; vendor preset: enabled)
Active: active (running) since Fri 2021-10-08 18:20:33 CDT; 3min 19s ago
Process: 567 ExecStart=/usr/sbin/gpsd $GPSD_OPTIONS $DEVICES (code=exited, status=0/SUCCESS)
Main PID: 575 (gpsd)
Tasks: 1 (limit: 2063)
CGroup: /system.slice/gpsd.service
└─575 /usr/sbin/gpsd -n /dev/ttySC0 /dev/pps0

Oct 08 18:20:33 draws systemd[1]: Starting GPS (Global Positioning System) Daemon...
Oct 08 18:20:33 draws systemd[1]: Started GPS (Global Positioning System) Daemon.

---- chrony
crw------- 1 root root 239, 0 Oct 8 18:20 /dev/pps0
crw-rw---- 1 root dialout 237, 0 Oct 8 18:20 /dev/ttySC0
crw-rw---- 1 root dialout 237, 1 Oct 8 18:20 /dev/ttySC1
-- chrony sources
210 Number of sources = 10
MS Name/IP address Stratum Poll Reach LastRx Last sample
===============================================================================
#? GPS 0 3 0 - +0ns[ +0ns] +/- 0ns
#? PPS 0 3 0 - +0ns[ +0ns] +/- 0ns
^- 165.227.106.11 3 6 77 26 -1274us[-1274us] +/- 62ms
^- radio-sunshine.org 2 6 77 26 +1531us[+1531us] +/- 96ms
^* time.cloudflare.com 3 6 77 26 +380us[ +376us] +/- 25ms
^? ntp14.doctor.com 0 8 0 - +0ns[ +0ns] +/- 0ns
^? au.kashra.pictures 0 6 0 - +0ns[ +0ns] +/- 0ns
^? time.gac.edu 0 6 0 - +0ns[ +0ns] +/- 0ns
^? ntp.xtom.com 0 6 0 - +0ns[ +0ns] +/- 0ns
^? 2620:1d5:0:43::74 0 6 0 - +0ns[ +0ns] +/- 0ns
-- chrony tracking
Reference ID : A29FC801 (time.cloudflare.com)
Stratum : 4
Ref time (UTC) : Fri Oct 08 23:23:26 2021
System time : 0.000254401 seconds fast of NTP time
Last offset : -0.000003491 seconds
RMS offset : 0.000003491 seconds
Frequency : 5.548 ppm fast
Residual freq : -6.567 ppm
Skew : 0.060 ppm
Root delay : 0.048275169 seconds
Root dispersion : 0.002238920 seconds
Update interval : 64.4 seconds
Leap status : Normal
-- chrony sourcestats
210 Number of sources = 10
Name/IP Address NP NR Span Frequency Freq Skew Offset Std Dev
==============================================================================
GPS 0 0 0 +0.000 2000.000 +0ns 4000ms
PPS 0 0 0 +0.000 2000.000 +0ns 4000ms
165.227.106.11 6 6 137 -8.524 41.980 -1518us 655us
radio-sunshine.org 6 4 137 -5.197 80.605 +1164us 1150us
time.cloudflare.com 6 3 136 -6.567 95.824 -175us 1233us
ntp14.doctor.com 0 0 0 +0.000 2000.000 +0ns 4000ms
au.kashra.pictures 0 0 0 +0.000 2000.000 +0ns 4000ms
time.gac.edu 0 0 0 +0.000 2000.000 +0ns 4000ms
ntp.xtom.com 0 0 0 +0.000 2000.000 +0ns 4000ms
2620:1d5:0:43::74 0 0 0 +0.000 2000.000 +0ns 4000ms

---- sensors
-rw-r--r-- 1 root root 209 Aug 21 2020 /etc/sensors.d/draws
iio_hwmon-isa-0000
Adapter: ISA adapter
+12V: +12.21 V
+5V: +5.02 V
User ADC 1: +0.00 V
User ADC 2: +0.00 V

cpu_thermal-virtual-0
Adapter: Virtual device
temp1: +49.4°C

rpi_volt-isa-0000
Adapter: ISA adapter
in0: N/A

---- throttle
temp=49.9'C
throttled=0x0

---- locale
Locale country codes consistent among WiFi cfg file, iw reg & X11: US
core_config.sh has been run 1 time(s)



@K5LVL
 
Edited

Connection is using Wi-Fi.

Just re-imaged from my back up this morning and the direwolf log looks normal again.

I did repo refresh as well as a apt-get update & upgrade with a reboot after.

I've also set gateway.conf to DEBUG mode and I'll update again when I have a "good" and a "bad" logfile.

buginfo.sh  output below

=== Versions ===
== Kernel:
Linux draws 5.4.79-v7+ #1373 SMP Mon Nov 23 13:22:33 GMT 2020 armv7l GNU/Linux

== Firmware:
Nov 30 2020 22:13:46
Copyright (c) 2012 Broadcom
version ab1181cc0cb6df52bfae3b1d3fef0ce7c325166c (clean) (release) (start)

== Pi hardware:
 Pi 3 Model B+, Rev 1.3, Mfg by Sony UK with WiFi
temp=59.1'C

== DRAWS hardware:
Product id: 0x0004, ver: 0x0108, Assembly rev: 1, fab rev: 8

=== Checks ===
== Codec driver check:
[    6.596620] asoc-simple-card soc:sound: tlv320aic32x4-hifi <-> 3f203000.i2s mapping ok

== DRAWS driver check:
udrc card number line: card 2: udrc [udrc], device 0: bcm2835-i2s-tlv320aic32x4-hifi tlv320aic32x4-hifi-0 [bcm2835-i2s-tlv320aic32x4-hifi tlv320aic3                         2x4-hifi-0]
udrc is sound card #2

== Boot 'fail' check:
[    5.901564] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,3-model-b-plus.txt failed with error -2

== GPS check:
Serial devices OK
gpsd OK

== /boot/config file
[pi4]
dtoverlay=vc4-fkms-v3d
[all]
dtoverlay=
dtoverlay=draws,alsaname=udrc
force_turbo=1
dtparam=audio=on


Basil Gunn
 

Kyle,
Thanks for the buginfo.sh output.
Your post is a display of a "good" configuration.
Looking forward to seeing your "bad" rmsgw log file.
/Basil

kylemcdonald87@gmail.com writes:

[Edited Message Follows]

Connection is using Wi-Fi.

Just re-imaged from my back up this morning and the direwolf log looks normal again.

I did repo refresh as well as a apt-get update & upgrade with a reboot after.

I've also set gateway.conf to DEBUG mode and I'll update again when I have a "good" and a "bad" logfile.

buginfo.sh output below

=== Versions ===
== Kernel:
Linux draws 5.4.79-v7+ #1373 SMP Mon Nov 23 13:22:33 GMT 2020 armv7l GNU/Linux

== Firmware:
Nov 30 2020 22:13:46
Copyright (c) 2012 Broadcom
version ab1181cc0cb6df52bfae3b1d3fef0ce7c325166c (clean) (release) (start)

== Pi hardware:
Pi 3 Model B+, Rev 1.3, Mfg by Sony UK with WiFi
temp=59.1'C

== DRAWS hardware:
Product id: 0x0004, ver: 0x0108, Assembly rev: 1, fab rev: 8

=== Checks ===
== Codec driver check:
[ 6.596620] asoc-simple-card soc:sound: tlv320aic32x4-hifi <-> 3f203000.i2s mapping ok

== DRAWS driver check:
udrc card number line: card 2: udrc [udrc], device 0: bcm2835-i2s-tlv320aic32x4-hifi tlv320aic32x4-hifi-0 [bcm2835-i2s-tlv320aic32x4-hifi tlv320aic32x4-hifi-0]
udrc is sound card #2

== Boot 'fail' check:
[ 5.901564] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,3-model-b-plus.txt failed with error -2

== GPS check:
Serial devices OK
gpsd OK

== /boot/config file
[pi4]
dtoverlay=vc4-fkms-v3d
[all]
dtoverlay=
dtoverlay=draws,alsaname=udrc
force_turbo=1
dtparam=audio=on


@K5LVL
 

Hey Basil,

Attaching two sessions that should be in the rms.debug file.  The first is on a VHF CDM1250 and the second is on an ICOM UHF radio(can't remember the model off the top of my head).  I observed the session on each radio and both did show to go into transmit when it was supposed to.

Also below, the session from the remote side.

Packet Soundmodem Session
*** Starting to call K5LVL-10
*** Opening KISS over TCP Host 127.0.0.1 Port 8100
*** Connecting to K5LVL-10
*** Disconnected at 2021/10/25 19:41:41

*** Disconnect reported.


Soundmodem Log of attempts to connect.
1:Fm WB5TJK To K5LVL-10 [14:36:00T]
1:Fm WB5TJK To K5LVL-10 [14:36:05T]
1:Fm WB5TJK To K5LVL-10 [14:36:10T]
1:Fm WB5TJK To K5LVL-10 [14:36:14T]
1:Fm WB5TJK To K5LVL-10 [14:36:18T]
1:Fm WB5TJK To K5LVL-10 [14:39:15T]
1:Fm WB5TJK To K5LVL-10 [14:39:20T]
1:Fm WB5TJK To K5LVL-10 [14:39:24T]
1:Fm WB5TJK To K5LVL-10 [14:39:29T]
1:Fm WB5TJK To K5LVL-10 [14:39:34T]
1:Fm WB5TJK To K5LVL-10 [14:41:18T]
1:Fm WB5TJK To K5LVL-10 [14:41:23T]
1:Fm WB5TJK To K5LVL-10 [14:41:27T]
1:Fm WB5TJK To K5LVL-10 [14:41:31T]
1:Fm WB5TJK To K5LVL-10 [14:41:36T]


Basil Gunn
 

Hi Kyle,

I've never used Soundmodem so I'm not going to be of much help.
If you used ANY of the install scripts then you will have to stop
Direwolf, start Soundmodem and configure it to use AX.25.

I am not seeing any indication that your setup is outputting any packets
and you are definitely not receiving any packets.

I would not try to debug this problem with RMS Gateway.

First confirm you can actually transmit a packet
- use the beacon command on 2M APRS and verify with aprs.fi that it was
received & gated to the Internet.

If you have setup AX.25 with Soundmodem then you can use the 'beacon'
command. Use 'man beacon' to look at all its options. There are a couple
of scripts in n7nix that make that a little easier to use: btest.sh &
beacmin.sh. Note Linux RMS Gateway requires an AX.25 interface.

Look at '5. Direwolf/UDRC testing' in the n7nix repository
https://github.com/nwdigitalradio/n7nix/tree/master/debug#5-direwolfudrc-testing

Confirm that you can receive packets.
- use a packet spy (listen) and confirm that your Soundmodem setup is
receiving packets.
- is there a log file with Soundmodem to look at everything it
receives?
- Linux RMS Gateway listens via the ax.25 daemon. Verify the ax.25
daemon configuration in this file: /etc/ax25/ax25d.conf

If you are sure your radio is transmitting packets but they are NOT
being received by any APRS gateways verify deviation is set up properly
on your radio.

https://github.com/nwdigitalradio/n7nix/tree/master/deviation

This n7nix/deviation section has a number of low level things (ie. does
not require any sound modem or AX.25) you can try. For instance use the
measure_deviate.sh script to output a tone and see if you are able to
hear it on a handy talky.

Once you have an indication that you are successfully sending &
receiving packets verify ax.25 connected mode by doing a peer-to-peer
Winlink connection with someone else.

After all the above checks out, try running RMS Gateway.



kylemcdonald87@gmail.com writes:

Hey Basil,

Attaching two sessions that should be in the rms.debug file. The first is on a VHF CDM1250 and the second is on an ICOM UHF radio(can't remember the model off the top of my head). I observed the session on each radio and both did show to go into transmit when it was supposed to.

Also below, the session from the remote side.

Packet Soundmodem Session
*** Starting to call K5LVL-10
*** Opening KISS over TCP Host 127.0.0.1 Port 8100
*** Connecting to K5LVL-10
*** Disconnected at 2021/10/25 19:41:41

*** Disconnect reported.

Soundmodem Log of attempts to connect.
1:Fm WB5TJK To K5LVL-10 <SABM C P> [14:36:00T]
1:Fm WB5TJK To K5LVL-10 <SABM C P> [14:36:05T]
1:Fm WB5TJK To K5LVL-10 <SABM C P> [14:36:10T]
1:Fm WB5TJK To K5LVL-10 <SABM C P> [14:36:14T]
1:Fm WB5TJK To K5LVL-10 <SABM C P> [14:36:18T]
1:Fm WB5TJK To K5LVL-10 <SABM C P> [14:39:15T]
1:Fm WB5TJK To K5LVL-10 <SABM C P> [14:39:20T]
1:Fm WB5TJK To K5LVL-10 <SABM C P> [14:39:24T]
1:Fm WB5TJK To K5LVL-10 <SABM C P> [14:39:29T]
1:Fm WB5TJK To K5LVL-10 <SABM C P> [14:39:34T]
1:Fm WB5TJK To K5LVL-10 <SABM C P> [14:41:18T]
1:Fm WB5TJK To K5LVL-10 <SABM C P> [14:41:23T]
1:Fm WB5TJK To K5LVL-10 <SABM C P> [14:41:27T]
1:Fm WB5TJK To K5LVL-10 <SABM C P> [14:41:31T]
1:Fm WB5TJK To K5LVL-10 <SABM C P> [14:41:36T]



@K5LVL
 

Hey Basil,

I finally got in a few things so I can test and report back myself.

rmsgw.debug file is attached.  Let me know what else I can do.


@K5LVL
 

After about a minute and a half it looks like it does eventually TX the packets, however the client side(Winlink Express) has by far timed out and is not longer responding.


Basil Gunn
 

- It is difficult to debug your problem just using the RMS Gateway app.

- The symptom I see from your RMS Gateway log file is that you are
transmitting some packets but not receiving any packets.

- From previous posts:
Verify you can transmit valid packets:

Use the btest.sh or beacmin.sh scripts with your radio tuned to the
APRS 2M frequency. Verify the beacon packets were gated to the
Internet on aprs.fi.

Use a handy talky to listen for your packets being transmitted. If
you do NOT see your packets gated on aprs.fi OR hear them on your
handy talky then use the measure_deviate.sh script
(https://github.com/nwdigitalradio/n7nix/tree/master/deviation) to
output a tone. Listen for it on your handy talky. This will verify PTT
and your cable from the DRAWS hat to your radio. It also gives you a
way to measure your deviation settings.

Verify you are receiving packets:

Tune your radio to the 2M APRS frequency because it should be fairly
active, 144.390

start up a packet spy in a console
sudo su
listen -a

Check output from direwolf log file:
tail -f /var/log/direwolf/direwolf.log

Verify that you have the correct ALSA settings for the radio you are
using:

alsa-show.sh

Once you have verified that you are in fact transmitting and receiving
valid packets use a Winlink app to do a point-to-point connection with
someone to verify connected mode.

Once you have verified that all of the above works, look at your RMS
Gateway log for any problems.

kylemcdonald87@gmail.com writes:

Hey Basil,

I finally got in a few things so I can test and report back myself.

rmsgw.debug file is attached. Let me know what else I can do.



Basil Gunn
 

When the Linux AX.25 stack is used from start-up, like after a cold or warm
boot, the first 2 packets sent are parsed as control packets and are not
transmitted.

This is only seen at start-up.

You can mitigate that by using the beacmin.sh or btest.sh scripts twice
before starting to test anything.

Use your packet spy (listen -a) to get a better idea of what is going on.


kylemcdonald87@gmail.com writes:

After about a minute and a half it looks like it does eventually TX
the packets, however the client side(Winlink Express) has by far timed
out and is not longer responding.


@K5LVL
 

Tested with btest.sh and everything works as expected... A reboot of the pi seems to fix the issue for some period of time.  Maybe some kind of memory issue or log file filling up?


Basil Gunn
 

kylemcdonald87@gmail.com writes:

Tested with btest.sh and everything works as expected... A reboot of the pi seems to fix the issue for some period of time. Maybe some kind of memory issue or log file filling up?
OK. Restating the problem so that we are on the same page.

Packet connected mode works initially but stops working due to
latency of response after some period of time.

Do you see this happening with more than just one station?

I need to see your packet spy output with time stamp turned on to
understand what is happening.


@K5LVL
 

packet spy from a successful session.. I'll try it later this afternoon and hopefully be able to get a failure as well.


Basil Gunn
 

That spy trace you captured is a good base line for a successful
connection. Comparing it to a failure case will be interesting.

kylemcdonald87@gmail.com writes:

packet spy from a successful session.. I'll try it later this afternoon and hopefully be able to get a failure as well.


@K5LVL
 

Hey Basil,

Tested a few minutes ago and it did work, however I tried again right after and nothing... I see a lot of the select (gateway) select exit (gateway) messages in the debug log... Both log and packet spy attached.


Basil Gunn
 

Kyle,

From your spy trace it looks like the client is not completing the
expected protocol.

After the client sends a challenge response: ;PR: 14567306.

RMS gateway is expecting: ; K5LVL-10 DE K5LVL (EM51HF)

but it never happens. The client appears to have gone deaf after sending
the challenge response.

What TNC or sound device is Winlink Express using?

If the Winlink Express client radio & the Linux RMS Gateway radio are
both in your shack make sure that the power on both radios are set to
the absolute lowest value when testing between them.

Can you please run the connection test a number of times (at least 5)
with radios set to lowest power level and send me the packet trace for
all the sessions. Thanks.


kylemcdonald87@gmail.com writes:

Hey Basil,

Tested a few minutes ago and it did work, however I tried again right
after and nothing... I see a lot of the select (gateway) select exit
(gateway) messages in the debug log... Both log and packet spy
attached.