[Bug]: Board RAK4630 factory resets when updating settings from Android App, but works fine with CLI

[thatSFguy]

Category

Other

Hardware

Rak4631

Firmware Version

2.3.13.83f5ba0

Description

With the most recent update on Android, I decided to update all my devices at the same time to the latest firmware. The issue is that if I try to connect via BLE + Android app, it just never pairs and just resets. If I configure the device via USB and CLI (2.3.2) it configures fine and works fine. If I attempt any change via the app via the admin channel, the device just factory resets and I have to reconfigure it via USB. Not sure if this is a HW issue or something with the Android App.

I unplugged it, to make sure it's not a power issue that causes the settings to be wiped out and I can confirm that it keeps the settings.

Relevant log output

No response

[thatSFguy]

Update: It looks like a HW problem now. Not sure what caused it, but it looks like it's not getting enough power. Definitely getting enough power.

--- More details at https://bit.ly/pio-monitor-filters
--- Quit: Ctrl+C | Menu: Ctrl+T | Help: Ctrl+T followed by Ctrl+H
␛[32m␀INFO ␛[0m| ??:??:?? 60 [GPS] ␛[32m␀Saving /prefs/db.proto
␛[0m␀␛[31m␀ERROR ␛[0m| ??:??:?? 68 ␛[31m␀NOTE! Recording critical error 9 at src/platform/nrf52/main-nrf52.cpp:134
␛[0m␀␛[34m␀DEBUG ␛[0m| ??:??:?? 69 [Power] ␛[34m␀Battery: usbPower=1, isCharging=1, batMv=4261, batPct=100
␛[0m␀␛[31m␀ERROR ␛[0m| ??:??:?? 70 ␛[31m␀NOTE! Recording critical error 9 at src/platform/nrf52/main-nrf52.cpp:134
␛[0m␀␛[31m␀ERROR ␛[0m| ??:??:?? 75 ␛[31m␀NOTE! Recording critical error 9 at src/platform/nrf52/main-nrf52.cpp:134
␛[0m␀␛[31m␀ERROR ␛[0m| ??:??:?? 80 ␛[31m␀NOTE! Recording critical error 9 at src/platform/nrf52/main-nrf52.cpp:134
␛[0m␀␛[31m␀ERROR ␛[0m| ??:??:?? 84 ␛[31m␀NOTE! Recording critical error 9 at src/platform/nrf52/main-nrf52.cpp:134

So then I disabled BLE and the error went away.

Now I'm reflashing to an older version: firmware-rak4631-2.1.22.191a69d and I still don't see the error.

DEBUG | ??:??:?? 29 [Power] Battery: usbPower=1, isCharging=1, batMv=4248, batPct=100
INFO | ??:??:?? 37 [NodeInfoModule] Sending our nodeinfo to mesh (wantReplies=1)
INFO | ??:??:?? 37 [NodeInfoModule] sending owner !84e3b8e4/Meshtastic b8e4/b8e4
DEBUG | ??:??:?? 37 [NodeInfoModule] Update DB node 0x84e3b8e4, rx_time=0, channel=0
DEBUG | ??:??:?? 37 [NodeInfoModule] handleReceived(LOCAL) (id=0x5366d849 fr=0xe4 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4
WANTRESP priority=10)
DEBUG | ??:??:?? 37 [NodeInfoModule] No modules interested in portnum=4, src=LOCAL
DEBUG | ??:??:?? 37 [NodeInfoModule] localSend to channel 0
DEBUG | ??:??:?? 37 [NodeInfoModule] Add packet record (id=0x5366d849 fr=0xe4 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP priority=10)
DEBUG | ??:??:?? 37 [NodeInfoModule] Expanding short PSK #1
DEBUG | ??:??:?? 37 [NodeInfoModule] Using AES128 key!
DEBUG | ??:??:?? 37 [NodeInfoModule] nRF52 encrypt fr=84e3b8e4, num=5366d849, numBytes=50!
WARN | ??:??:?? 37 [NodeInfoModule] send - lora tx disable because RegionCode_Unset
DEBUG | ??:??:?? 49 [Power] Battery: usbPower=1, isCharging=1, batMv=4244, batPct=100
INFO | ??:??:?? 52 [DeviceTelemetryModule] (Sending): air_util_tx=0.000000, channel_utilization=0.000000, battery_level=101, voltage=4.244000
DEBUG | ??:??:?? 52 [DeviceTelemetryModule] updateTelemetry LOCAL
DEBUG | ??:??:?? 52 [DeviceTelemetryModule] Node status update: 1 online, 1 total
INFO | ??:??:?? 52 [DeviceTelemetryModule] Sending packet to mesh
DEBUG | ??:??:?? 52 [DeviceTelemetryModule] Update DB node 0x84e3b8e4, rx_time=0, channel=0
DEBUG | ??:??:?? 52 [DeviceTelemetryModule] handleReceived(LOCAL) (id=0x5366d84a fr=0xe4 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=1)
DEBUG | ??:??:?? 52 [DeviceTelemetryModule] No modules interested in portnum=67, src=LOCAL
DEBUG | ??:??:?? 52 [DeviceTelemetryModule] localSend to channel 0
DEBUG | ??:??:?? 52 [DeviceTelemetryModule] Add packet record (id=0x5366d84a fr=0xe4 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=1)
DEBUG | ??:??:?? 52 [DeviceTelemetryModule] Expanding short PSK #1
DEBUG | ??:??:?? 52 [DeviceTelemetryModule] Using AES128 key!
DEBUG | ??:??:?? 52 [DeviceTelemetryModule] nRF52 encrypt fr=84e3b8e4, num=5366d84a, numBytes=18!
WARN | ??:??:?? 52 [DeviceTelemetryModule] send - lora tx disable because RegionCode_Unset
DEBUG | ??:??:?? 52 [DeviceTelemetryModule] Using channel 0 (hash 0x8)
DEBUG | ??:??:?? 52 [DeviceTelemetryModule] Expanding short PSK #1
DEBUG | ??:??:?? 52 [DeviceTelemetryModule] Using AES128 key!
DEBUG | ??:??:?? 52 [DeviceTelemetryModule] nRF52 encrypt fr=84e3b8e4, num=5366d84a, numBytes=18!
DEBUG | ??:??:?? 53 [DeviceTelemetryModule] decoded message (id=0x5366d84a fr=0xe4 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=1)
DEBUG | ??:??:?? 69 [Power] Battery: usbPower=1, isCharging=1, batMv=4243, batPct=100
DEBUG | ??:??:?? 82 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=43, time 747 ms
DEBUG | ??:??:?? 82 [RadioIf] Lora RX (id=0x38b23e7a fr=0x2c to=0xff, WantAck=0, HopLim=3 Ch=0xa7 encrypted rxSNR=6.25 rxRSSI=-53)
DEBUG | ??:??:?? 82 [RadioIf] AirTime - Packet received : 747ms
DEBUG | ??:??:?? 82 [Router] Add packet record (id=0x38b23e7a fr=0x2c to=0xff, WantAck=0, HopLim=3 Ch=0xa7 encrypted rxSNR=6.25
rxRSSI=-53)
WARN | ??:??:?? 82 [Router] No suitable channel found for decoding, hash was 0xa7!
DEBUG | ??:??:?? 82 [Router] packet decoding failed or skipped (no PSK?) (id=0x38b23e7a fr=0x2c to=0xff, WantAck=0, HopLim=3 Ch=0xa7 encrypted rxSNR=6.25 rxRSSI=-53)
DEBUG | ??:??:?? 82 [Router] Module 'routing' wantsPacket=1
INFO | ??:??:?? 82 [Router] Received routing from=0x1c31422c, id=0x38b23e7a, portnum=27, payloadlen=29797
DEBUG | ??:??:?? 82 [Router] Routing sniffing (id=0x38b23e7a fr=0x2c to=0xff, WantAck=0, HopLim=3 Ch=0xa7 encrypted rxSNR=6.25 rxRSSI=-53)
INFO | ??:??:?? 82 [Router] Rebroadcasting received floodmsg to neighbors
WARN | ??:??:?? 82 [Router] send - lora tx disable because RegionCode_Unset
DEBUG | ??:??:?? 82 [Router] Delivering rx packet (id=0x38b23e7a fr=0x2c to=0xff, WantAck=0, HopLim=3 Ch=0xa7 encrypted rxSNR=6.25 rxRSSI=-53)
DEBUG | ??:??:?? 82 [Router] Forwarding to phone (id=0x38b23e7a fr=0x2c to=0xff, WantAck=0, HopLim=3 Ch=0xa7 encrypted rxSNR=6.25 rxRSSI=-53)
WARN | ??:??:?? 82 [Router] No suitable channel found for decoding, hash was 0xa7!
DEBUG | ??:??:?? 82 [Router] Module 'routing' considered
DEBUG | ??:??:?? 82 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=43, time 747 ms
DEBUG | ??:??:?? 82 [RadioIf] Lora RX (id=0x38b23e7a fr=0x2c to=0xff, WantAck=0, HopLim=2 Ch=0xa7 encrypted rxSNR=6.5 rxRSSI=-45)
DEBUG | ??:??:?? 82 [RadioIf] AirTime - Packet received : 747ms
DEBUG | ??:??:?? 82 [Router] Found existing packet record for fr=0x1c31422c,to=0xffffffff,id=0x38b23e7a
DEBUG | ??:??:?? 82 [Router] Found existing packet record for fr=0x1c31422c,to=0xffffffff,id=0x38b23e7a
DEBUG | ??:??:?? 82 [Router] Add packet record (id=0x38b23e7a fr=0x2c to=0xff, WantAck=0, HopLim=2 Ch=0xa7 encrypted rxSNR=6.5 rxRSSI=-45)
DEBUG | ??:??:?? 82 [Router] Ignoring incoming msg, because we've already seen it (id=0x38b23e7a fr=0x2c to=0xff, WantAck=0, HopLim=2 Ch=0xa7 encrypted rxSNR=6.5 rxRSSI=-45)
DEBUG | ??:??:?? 82 [Router] cancelSending id=0x38b23e7a, removed=0
DEBUG | ??:??:?? 82 [Router] Incoming message was filtered 0x1c31422c
DEBUG | ??:??:?? 89 [Power] Battery: usbPower=1, isCharging=1, batMv=4243, batPct=100
DEBUG | ??:??:?? 109 [Power] Battery: usbPower=1, isCharging=1, batMv=4244, batPct=100
DEBUG | ??:??:?? 129 [Power] Battery: usbPower=1, isCharging=1, batMv=4242, batPct=100
DEBUG | ??:??:?? 149 [Power] Battery: usbPower=1, isCharging=1, batMv=4240, batPct=100
DEBUG | ??:??:?? 169 [Power] Battery: usbPower=1, isCharging=1, batMv=4243, batPct=100
DEBUG | ??:??:?? 189 [Power] Battery: usbPower=1, isCharging=1, batMv=4240, batPct=100
DEBUG | ??:??:?? 209 [Power] Battery: usbPower=1, isCharging=1, batMv=4240, batPct=100
DEBUG | ??:??:?? 229 [Power] Battery: usbPower=1, isCharging=1, batMv=4239, batPct=100
DEBUG | ??:??:?? 230 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=43, time 747 ms
DEBUG | ??:??:?? 230 [RadioIf] Lora RX (id=0x28caccc7 fr=0x30 to=0xff, WantAck=0, HopLim=3 Ch=0x76 encrypted rxSNR=6 rxRSSI=-62)DEBUG | ??:??:?? 230 [RadioIf] AirTime - Packet received : 747ms
DEBUG | ??:??:?? 230 [Router] Add packet record (id=0x28caccc7 fr=0x30 to=0xff, WantAck=0, HopLim=3 Ch=0x76 encrypted rxSNR=6 rxRSSI=-62)
WARN | ??:??:?? 230 [Router] No suitable channel found for decoding, hash was 0x76!
DEBUG | ??:??:?? 230 [Router] packet decoding failed or skipped (no PSK?) (id=0x28caccc7 fr=0x30 to=0xff, WantAck=0, HopLim=3 Ch=0x76 encrypted rxSNR=6 rxRSSI=-62)
DEBUG | ??:??:?? 230 [Router] Module 'routing' wantsPacket=1
INFO | ??:??:?? 230 [Router] Received routing from=0x25d63230, id=0x28caccc7, portnum=27, payloadlen=50800
DEBUG | ??:??:?? 230 [Router] Routing sniffing (id=0x28caccc7 fr=0x30 to=0xff, WantAck=0, HopLim=3 Ch=0x76 encrypted rxSNR=6 rxRSSI=-62)
INFO | ??:??:?? 230 [Router] Rebroadcasting received floodmsg to neighbors
WARN | ??:??:?? 230 [Router] send - lora tx disable because RegionCode_Unset
DEBUG | ??:??:?? 230 [Router] Delivering rx packet (id=0x28caccc7 fr=0x30 to=0xff, WantAck=0, HopLim=3 Ch=0x76 encrypted rxSNR=6 rxRSSI=-62)
DEBUG | ??:??:?? 230 [Router] Forwarding to phone (id=0x28caccc7 fr=0x30 to=0xff, WantAck=0, HopLim=3 Ch=0x76 encrypted rxSNR=6
rxRSSI=-62)
WARN | ??:??:?? 230 [Router] No suitable channel found for decoding, hash was 0x76!
DEBUG | ??:??:?? 230 [Router] Module 'routing' considered
DEBUG | ??:??:?? 231 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=43, time 747 ms
DEBUG | ??:??:?? 231 [RadioIf] Lora RX (id=0x28caccc7 fr=0x30 to=0xff, WantAck=0, HopLim=2 Ch=0x76 encrypted rxSNR=6 rxRSSI=-43)DEBUG | ??:??:?? 231 [RadioIf] AirTime - Packet received : 747ms
DEBUG | ??:??:?? 231 [Router] Found existing packet record for fr=0x25d63230,to=0xffffffff,id=0x28caccc7
DEBUG | ??:??:?? 231 [Router] Found existing packet record for fr=0x25d63230,to=0xffffffff,id=0x28caccc7
DEBUG | ??:??:?? 231 [Router] Add packet record (id=0x28caccc7 fr=0x30 to=0xff, WantAck=0, HopLim=2 Ch=0x76 encrypted rxSNR=6 rxRSSI=-43)
DEBUG | ??:??:?? 231 [Router] Ignoring incoming msg, because we've already seen it (id=0x28caccc7 fr=0x30 to=0xff, WantAck=0, HopLim=2 Ch=0x76 encrypted rxSNR=6 rxRSSI=-43)
DEBUG | ??:??:?? 231 [Router] cancelSending id=0x28caccc7, removed=0
DEBUG | ??:??:?? 231 [Router] Incoming message was filtered 0x25d63230
DEBUG | ??:??:?? 249 [Power] Battery: usbPower=1, isCharging=1, batMv=4240, batPct=100
DEBUG | ??:??:?? 269 [Power] Battery: usbPower=1, isCharging=1, batMv=4239, batPct=100

I'll do more testing and update this as investigation progresses.

[thatSFguy]

Well, after configuring the node, the error 9 came back even with the older firmware. I guess I'll buy a new board.

[thatSFguy]

Probably Hardware issue with RAK4631 starter kit.