### Category
BLE
### Hardware
Rak4631
### Firmware Version
2.1.23….04bbdc6
### Description
Tested across multiple RAK devices to expedite and confirm.
Desired effect is to have a Repeater or Router with USB Serial and Bluetooth disabled.
All devices set to US region, each given two PSK channels with one being "admin" slot 2:
In each case, setting Bluetooth disabled seems to create a condition where the device becomes unresponsive to admin commands first, later followed by becoming completely unresponsive and later appears to be powered off (no LED activity). The time to become unresponsive can take up to 72 hours.
Tested with/without power (battery only).
Equipment:
RAK4631
RAK19007
RAK18001 - PWM Buzzer module (on some devices which don't appear to have any bearing on issue/full disclosure in case it becomes relevant)
Tested each of the following modes with Bluetooth disabled, eventually becomes unresponsive regardless of USB serial setting:
Client/router - because issues in router were previously known
Router
Repeater
All devices where Bluetooth is enabled have not exhibited issues. Snip from serial debug shows only Power status when and after the device faults. No other clues are obvious.
### Relevant log output
```Shell
DEBUG | ??:??:?? 8781 [Router] Incoming message was filtered 0x5185b8ba
DEBUG | ??:??:?? 8788 [Power] Battery: usbPower=0, isCharging=0, batMv=4186, batPct=98
DEBUG | ??:??:?? 8808 [Power] Battery: usbPower=0, isCharging=0, batMv=4172, batPct=96
DEBUG | ??:??:?? 8828 [Power] Battery: usbPower=0, isCharging=0, batMv=4188, batPct=98
DEBUG | ??:??:?? 8848 [Power] Battery: usbPower=0, isCharging=0, batMv=4190, batPct=98
DEBUG | ??:??:?? 8868 [Power] Battery: usbPower=0, isCharging=0, batMv=4162, batPct=95
DEBUG | ??:??:?? 8886 [RadioIf] (bw=250, sf=9, cr=4/8) packet symLen=2 ms, payloadSize=39, time 205 ms
DEBUG | ??:??:?? 8886 [RadioIf] Lora RX (id=0x4b25c3d2 fr=0xfd to=0xff, WantAck=0, HopLim=5 Ch=0xb9 encrypted rxSNR=10.5 rxRSSI=-73)
DEBUG | ??:??:?? 8886 [RadioIf] AirTime - Packet received : 205ms
DEBUG | ??:??:?? 8886 [Router] Add packet record (id=0x4b25c3d2 fr=0xfd to=0xff, WantAck=0, HopLim=5 Ch=0xb9 encrypted rxSNR=10.5 rxRSSI=-73)
DEBUG | ??:??:?? 8886 [Router] Using channel 0 (hash 0xb9)
DEBUG | ??:??:?? 8886 [Router] Using AES256 key!
DEBUG | ??:??:?? 8886 [Router] Software encrypt fr=2c1985fd, num=4b25c3d2, numBytes=23!
DEBUG | ??:??:?? 8886 [Router] decoded message (id=0x4b25c3d2 fr=0xfd to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=67 rxSNR=10.5 rxRSSI=-73)
DEBUG | ??:??:?? 8886 [Router] handleReceived(REMOTE) (id=0x4b25c3d2 fr=0xfd to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=67 rxSNR=10.5 rxRSSI=-73)
DEBUG | ??:??:?? 8886 [Router] Module 'DeviceTelemetry' wantsPacket=1
INFO | ??:??:?? 8886 [Router] Received DeviceTelemetry from=0x2c1985fd, id=0x4b25c3d2, portnum=67, payloadlen=19
DEBUG | ??:??:?? 8886 [Router] Module 'DeviceTelemetry' considered
DEBUG | ??:??:?? 8886 [Router] Module 'routing' wantsPacket=1
INFO | ??:??:?? 8886 [Router] Received routing from=0x2c1985fd, id=0x4b25c3d2, portnum=67, payloadlen=19
DEBUG | ??:??:?? 8886 [Router] Routing sniffing (id=0x4b25c3d2 fr=0xfd to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=67 rxSNR=10.5 rxRSSI=-73)
INFO | ??:??:?? 8886 [Router] Rebroadcasting received floodmsg to neighbors
DEBUG | ??:??:?? 8886 [Router] Using AES256 key!
DEBUG | ??:??:?? 8886 [Router] Software encrypt fr=2c1985fd, num=4b25c3d2, numBytes=23!
DEBUG | ??:??:?? 8886 [Router] enqueuing for send (id=0x4b25c3d2 fr=0xfd to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=10.5 rxRSSI=-73)
DEBUG | ??:??:?? 8886 [Router] txGood=100,rxGood=229,rxBad=0
DEBUG | ??:??:?? 8886 [Router] rx_snr found. hop_limit:4 rx_snr:10.500000
DEBUG | ??:??:?? 8886 [Router] rx_snr found in packet. As a router, setting tx delay:294
DEBUG | ??:??:?? 8886 [Router] Delivering rx packet (id=0x4b25c3d2 fr=0xfd to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=67 rxSNR=10.5 rxRSSI=-73)
DEBUG | ??:??:?? 8886 [Router] Update DB node 0x2c1985fd, rx_time=0, channel=0
DEBUG | ??:??:?? 8886 [Router] Forwarding to phone (id=0x4b25c3d2 fr=0xfd to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=67 rxSNR=10.5 rxRSSI=-73)
WARN | ??:??:?? 8886 [Router] ToPhone queue is full, discarding oldest
DEBUG | ??:??:?? 8886 [Router] Module 'routing' considered
DEBUG | ??:??:?? 8886 [RadioIf] Starting low level send (id=0x4b25c3d2 fr=0xfd to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=10.5 rxRSSI=-73 priority=64)
DEBUG | ??:??:?? 8886 [RadioIf] (bw=250, sf=9, cr=4/8) packet symLen=2 ms, payloadSize=39, time 205 ms
DEBUG | ??:??:?? 8886 [RadioIf] AirTime - Packet transmitted : 205ms
DEBUG | ??:??:?? 8886 [RadioIf] Completed sending (id=0x4b25c3d2 fr=0xfd to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=10.5 rxRSSI=-73 priority=64)
DEBUG | ??:??:?? 8887 [RadioIf] (bw=250, sf=9, cr=4/8) packet symLen=2 ms, payloadSize=39, time 205 ms
DEBUG | ??:??:?? 8887 [RadioIf] Lora RX (id=0x4b25c3d2 fr=0xfd to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=11.25 rxRSSI=-61)
DEBUG | ??:??:?? 8887 [RadioIf] AirTime - Packet received : 205ms
DEBUG | ??:??:?? 8887 [Router] Found existing packet record for fr=0x2c1985fd,to=0xffffffff,id=0x4b25c3d2
DEBUG | ??:??:?? 8887 [Router] Add packet record (id=0x4b25c3d2 fr=0xfd to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=11.25 rxRSSI=-61)
DEBUG | ??:??:?? 8887 [Router] Ignoring incoming msg, because we've already seen it (id=0x4b25c3d2 fr=0xfd to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=11.25 rxRSSI=-61)
DEBUG | ??:??:?? 8887 [Router] Incoming message was filtered 0x2c1985fd
DEBUG | ??:??:?? 8888 [Power] Battery: usbPower=0, isCharging=0, batMv=4164, batPct=96
DEBUG | ??:??:?? 8908 [Power] Battery: usbPower=0, isCharging=0, batMv=4164, batPct=96
DEBUG | ??:??:?? 8914 [RadioIf] (bw=250, sf=9, cr=4/8) packet symLen=2 ms, payloadSize=44, time 221 ms
DEBUG | ??:??:?? 8914 [RadioIf] Lora RX (id=0x0fdfc121 fr=0x26 to=0xff, WantAck=0, HopLim=5 Ch=0xb9 encrypted rxSNR=11.75 rxRSSI=-61)
DEBUG | ??:??:?? 8914 [RadioIf] AirTime - Packet received : 221ms
DEBUG | ??:??:?? 8914 [Router] Add packet record (id=0x0fdfc121 fr=0x26 to=0xff, WantAck=0, HopLim=5 Ch=0xb9 encrypted rxSNR=11.75 rxRSSI=-61)
DEBUG | ??:??:?? 8914 [Router] Using channel 0 (hash 0xb9)
DEBUG | ??:??:?? 8914 [Router] Using AES256 key!
DEBUG | ??:??:?? 8914 [Router] Software encrypt fr=98253326, num=fdfc121, numBytes=28!
DEBUG | ??:??:?? 8914 [Router] decoded message (id=0x0fdfc121 fr=0x26 to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=67 rxSNR=11.75 rxRSSI=-61)
DEBUG | ??:??:?? 8914 [Router] handleReceived(REMOTE) (id=0x0fdfc121 fr=0x26 to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=67 rxSNR=11.75 rxRSSI=-61)
DEBUG | ??:??:?? 8914 [Router] Module 'DeviceTelemetry' wantsPacket=1
INFO | ??:??:?? 8914 [Router] Received DeviceTelemetry from=0x98253326, id=0xfdfc121, portnum=67, payloadlen=24
DEBUG | ??:??:?? 8914 [Router] Module 'DeviceTelemetry' considered
DEBUG | ??:??:?? 8914 [Router] Module 'routing' wantsPacket=1
INFO | ??:??:?? 8914 [Router] Received routing from=0x98253326, id=0xfdfc121, portnum=67, payloadlen=24
DEBUG | ??:??:?? 8914 [Router] Routing sniffing (id=0x0fdfc121 fr=0x26 to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=67 rxSNR=11.75 rxRSSI=-61)
INFO | ??:??:?? 8914 [Router] Rebroadcasting received floodmsg to neighbors
DEBUG | ??:??:?? 8914 [Router] Using AES256 key!
DEBUG | ??:??:?? 8914 [Router] Software encrypt fr=98253326, num=fdfc121, numBytes=28!
DEBUG | ??:??:?? 8914 [Router] enqueuing for send (id=0x0fdfc121 fr=0x26 to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=11.75 rxRSSI=-61)
DEBUG | ??:??:?? 8914 [Router] txGood=101,rxGood=231,rxBad=0
DEBUG | ??:??:?? 8914 [Router] rx_snr found. hop_limit:4 rx_snr:11.750000
DEBUG | ??:??:?? 8914 [Router] rx_snr found in packet. As a router, setting tx delay:378
DEBUG | ??:??:?? 8914 [Router] Delivering rx packet (id=0x0fdfc121 fr=0x26 to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=67 rxSNR=11.75 rxRSSI=-61)
DEBUG | ??:??:?? 8914 [Router] Update DB node 0x98253326, rx_time=0, channel=0
DEBUG | ??:??:?? 8914 [Router] Forwarding to phone (id=0x0fdfc121 fr=0x26 to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=67 rxSNR=11.75 rxRSSI=-61)
WARN | ??:??:?? 8914 [Router] ToPhone queue is full, discarding oldest
DEBUG | ??:??:?? 8914 [Router] Module 'routing' considered
DEBUG | ??:??:?? 8914 [RadioIf] Starting low level send (id=0x0fdfc121 fr=0x26 to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=11.75 rxRSSI=-61 priority=64)
DEBUG | ??:??:?? 8914 [RadioIf] (bw=250, sf=9, cr=4/8) packet symLen=2 ms, payloadSize=44, time 221 ms
DEBUG | ??:??:?? 8914 [RadioIf] AirTime - Packet transmitted : 221ms
DEBUG | ??:??:?? 8914 [RadioIf] Completed sending (id=0x0fdfc121 fr=0x26 to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=11.75 rxRSSI=-61 priority=64)
DEBUG | ??:??:?? 8928 [Power] Battery: usbPower=0, isCharging=0, batMv=4173, batPct=97
DEBUG | ??:??:?? 8948 [Power] Battery: usbPower=0, isCharging=0, batMv=4167, batPct=96
DEBUG | ??:??:?? 8968 [Power] Battery: usbPower=0, isCharging=0, batMv=4159, batPct=95
DEBUG | ??:??:?? 8988 [Power] Battery: usbPower=0, isCharging=0, batMv=4168, batPct=96
DEBUG | ??:??:?? 9008 [Power] Battery: usbPower=0, isCharging=0, batMv=4183, batPct=98
DEBUG | ??:??:?? 9028 [Power] Battery: usbPower=0, isCharging=0, batMv=4166, batPct=96
DEBUG | ??:??:?? 9048 [Power] Battery: usbPower=0, isCharging=0, batMv=4177, batPct=97
DEBUG | ??:??:?? 9068 [Power] Battery: usbPower=0, isCharging=0, batMv=4169, batPct=96
DEBUG | ??:??:?? 9088 [Power] Battery: usbPower=0, isCharging=0, batMv=4188, batPct=98
DEBUG | ??:??:?? 9097 [RadioIf] (bw=250, sf=9, cr=4/8) packet symLen=2 ms, payloadSize=42, time 215 ms
DEBUG | ??:??:?? 9097 [RadioIf] Lora RX (id=0x17a1094f fr=0xf5 to=0xff, WantAck=0, HopLim=5 Ch=0xb9 encrypted rxSNR=11.25 rxRSSI=-54)
DEBUG | ??:??:?? 9097 [RadioIf] AirTime - Packet received : 215ms
DEBUG | ??:??:?? 9097 [Router] Add packet record (id=0x17a1094f fr=0xf5 to=0xff, WantAck=0, HopLim=5 Ch=0xb9 encrypted rxSNR=11.25 rxRSSI=-54)
DEBUG | ??:??:?? 9097 [Router] Using channel 0 (hash 0xb9)
DEBUG | ??:??:?? 9097 [Router] Using AES256 key!
DEBUG | ??:??:?? 9097 [Router] Software encrypt fr=76fee5f5, num=17a1094f, numBytes=26!
DEBUG | ??:??:?? 9097 [Router] decoded message (id=0x17a1094f fr=0xf5 to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=67 rxSNR=11.25 rxRSSI=-54)
DEBUG | ??:??:?? 9097 [Router] handleReceived(REMOTE) (id=0x17a1094f fr=0xf5 to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=67 rxSNR=11.25 rxRSSI=-54)
DEBUG | ??:??:?? 9097 [Router] Module 'DeviceTelemetry' wantsPacket=1
INFO | ??:??:?? 9097 [Router] Received DeviceTelemetry from=0x76fee5f5, id=0x17a1094f, portnum=67, payloadlen=22
DEBUG | ??:??:?? 9097 [Router] Module 'DeviceTelemetry' considered
DEBUG | ??:??:?? 9097 [Router] Module 'routing' wantsPacket=1
INFO | ??:??:?? 9097 [Router] Received routing from=0x76fee5f5, id=0x17a1094f, portnum=67, payloadlen=22
DEBUG | ??:??:?? 9097 [Router] Routing sniffing (id=0x17a1094f fr=0xf5 to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=67 rxSNR=11.25 rxRSSI=-54)
INFO | ??:??:?? 9097 [Router] Rebroadcasting received floodmsg to neighbors
DEBUG | ??:??:?? 9097 [Router] Using AES256 key!
DEBUG | ??:??:?? 9097 [Router] Software encrypt fr=76fee5f5, num=17a1094f, numBytes=26!
DEBUG | ??:??:?? 9097 [Router] enqueuing for send (id=0x17a1094f fr=0xf5 to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=11.25 rxRSSI=-54)
DEBUG | ??:??:?? 9097 [Router] txGood=102,rxGood=232,rxBad=0
DEBUG | ??:??:?? 9097 [Router] rx_snr found. hop_limit:4 rx_snr:11.250000
DEBUG | ??:??:?? 9097 [Router] rx_snr found in packet. As a router, setting tx delay:84
DEBUG | ??:??:?? 9097 [Router] Delivering rx packet (id=0x17a1094f fr=0xf5 to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=67 rxSNR=11.25 rxRSSI=-54)
DEBUG | ??:??:?? 9097 [Router] Update DB node 0x76fee5f5, rx_time=0, channel=0
DEBUG | ??:??:?? 9097 [Router] Forwarding to phone (id=0x17a1094f fr=0xf5 to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=67 rxSNR=11.25 rxRSSI=-54)
WARN | ??:??:?? 9097 [Router] ToPhone queue is full, discarding oldest
DEBUG | ??:??:?? 9097 [Router] Module 'routing' considered
DEBUG | ??:??:?? 9098 [RadioIf] Starting low level send (id=0x17a1094f fr=0xf5 to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=11.25 rxRSSI=-54 priority=64)
DEBUG | ??:??:?? 9098 [RadioIf] (bw=250, sf=9, cr=4/8) packet symLen=2 ms, payloadSize=42, time 215 ms
DEBUG | ??:??:?? 9098 [RadioIf] AirTime - Packet transmitted : 215ms
DEBUG | ??:??:?? 9098 [RadioIf] Completed sending (id=0x17a1094f fr=0xf5 to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=11.25 rxRSSI=-54 priority=64)
DEBUG | ??:??:?? 9098 [RadioIf] (bw=250, sf=9, cr=4/8) packet symLen=2 ms, payloadSize=42, time 215 ms
DEBUG | ??:??:?? 9098 [RadioIf] Lora RX (id=0x17a1094f fr=0xf5 to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=11.5 rxRSSI=-61)
DEBUG | ??:??:?? 9098 [RadioIf] AirTime - Packet received : 215ms
DEBUG | ??:??:?? 9098 [Router] Found existing packet record for fr=0x76fee5f5,to=0xffffffff,id=0x17a1094f
DEBUG | ??:??:?? 9098 [Router] Add packet record (id=0x17a1094f fr=0xf5 to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=11.5 rxRSSI=-61)
DEBUG | ??:??:?? 9098 [Router] Ignoring incoming msg, because we've already seen it (id=0x17a1094f fr=0xf5 to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=11.5 rxRSSI=-61)
DEBUG | ??:??:?? 9098 [Router] Incoming message was filtered 0x76fee5f5
DEBUG | ??:??:?? 9108 [Power] Battery: usbPower=0, isCharging=0, batMv=4176, batPct=97
DEBUG | ??:??:?? 9117 [RadioIf] (bw=250, sf=9, cr=4/8) packet symLen=2 ms, payloadSize=44, time 221 ms
DEBUG | ??:??:?? 9117 [RadioIf] Lora RX (id=0x17a10950 fr=0xf5 to=0xff, WantAck=0, HopLim=5 Ch=0xb9 encrypted rxSNR=10.75 rxRSSI=-55)
DEBUG | ??:??:?? 9117 [RadioIf] AirTime - Packet received : 221ms
DEBUG | ??:??:?? 9117 [Router] Add packet record (id=0x17a10950 fr=0xf5 to=0xff, WantAck=0, HopLim=5 Ch=0xb9 encrypted rxSNR=10.75 rxRSSI=-55)
DEBUG | ??:??:?? 9117 [Router] Using channel 0 (hash 0xb9)
DEBUG | ??:??:?? 9117 [Router] Using AES256 key!
DEBUG | ??:??:?? 9117 [Router] Software encrypt fr=76fee5f5, num=17a10950, numBytes=28!
DEBUG | ??:??:?? 9117 [Router] decoded message (id=0x17a10950 fr=0xf5 to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=67 rxSNR=10.75 rxRSSI=-55)
DEBUG | ??:??:?? 9117 [Router] handleReceived(REMOTE) (id=0x17a10950 fr=0xf5 to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=67 rxSNR=10.75 rxRSSI=-55)
DEBUG | ??:??:?? 9117 [Router] Module 'DeviceTelemetry' wantsPacket=1
INFO | ??:??:?? 9117 [Router] Received DeviceTelemetry from=0x76fee5f5, id=0x17a10950, portnum=67, payloadlen=24
DEBUG | ??:??:?? 9117 [Router] Module 'DeviceTelemetry' considered
DEBUG | ??:??:?? 9117 [Router] Module 'routing' wantsPacket=1
INFO | ??:??:?? 9117 [Router] Received routing from=0x76fee5f5, id=0x17a10950, portnum=67, payloadlen=24
DEBUG | ??:??:?? 9117 [Router] Routing sniffing (id=0x17a10950 fr=0xf5 to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=67 rxSNR=10.75 rxRSSI=-55)
INFO | ??:??:?? 9117 [Router] Rebroadcasting received floodmsg to neighbors
DEBUG | ??:??:?? 9117 [Router] Using AES256 key!
DEBUG | ??:??:?? 9117 [Router] Software encrypt fr=76fee5f5, num=17a10950, numBytes=28!
DEBUG | ??:??:?? 9117 [Router] enqueuing for send (id=0x17a10950 fr=0xf5 to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=10.75 rxRSSI=-55)
DEBUG | ??:??:?? 9117 [Router] txGood=103,rxGood=234,rxBad=0
DEBUG | ??:??:?? 9117 [Router] rx_snr found. hop_limit:4 rx_snr:10.750000
DEBUG | ??:??:?? 9117 [Router] rx_snr found in packet. As a router, setting tx delay:378
DEBUG | ??:??:?? 9117 [Router] Delivering rx packet (id=0x17a10950 fr=0xf5 to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=67 rxSNR=10.75 rxRSSI=-55)
DEBUG | ??:??:?? 9117 [Router] Update DB node 0x76fee5f5, rx_time=0, channel=0
DEBUG | ??:??:?? 9117 [Router] Forwarding to phone (id=0x17a10950 fr=0xf5 to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=67 rxSNR=10.75 rxRSSI=-55)
WARN | ??:??:?? 9117 [Router] ToPhone queue is full, discarding oldest
DEBUG | ??:??:?? 9117 [Router] Module 'routing' considered
DEBUG | ??:??:?? 9117 [RadioIf] (bw=250, sf=9, cr=4/8) packet symLen=2 ms, payloadSize=44, time 221 ms
DEBUG | ??:??:?? 9117 [RadioIf] Lora RX (id=0x17a10950 fr=0xf5 to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=11 rxRSSI=-61)
DEBUG | ??:??:?? 9117 [RadioIf] AirTime - Packet received : 221ms
DEBUG | ??:??:?? 9117 [Router] Found existing packet record for fr=0x76fee5f5,to=0xffffffff,id=0x17a10950
DEBUG | ??:??:?? 9117 [Router] Add packet record (id=0x17a10950 fr=0xf5 to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=11 rxRSSI=-61)
DEBUG | ??:??:?? 9117 [Router] Ignoring incoming msg, because we've already seen it (id=0x17a10950 fr=0xf5 to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=11 rxRSSI=-61)
DEBUG | ??:??:?? 9117 [Router] Incoming message was filtered 0x76fee5f5
DEBUG | ??:??:?? 9117 [RadioIf] Starting low level send (id=0x17a10950 fr=0xf5 to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=10.75 rxRSSI=-55 priority=64)
DEBUG | ??:??:?? 9117 [RadioIf] (bw=250, sf=9, cr=4/8) packet symLen=2 ms, payloadSize=44, time 221 ms
DEBUG | ??:??:?? 9117 [RadioIf] AirTime - Packet transmitted : 221ms
DEBUG | ??:??:?? 9117 [RadioIf] Completed sending (id=0x17a10950 fr=0xf5 to=0xff, WantAck=0, HopLim=4 Ch=0xb9 encrypted rxSNR=10.75 rxRSSI=-55 priority=64)
DEBUG | ??:??:?? 9128 [Power] Battery: usbPower=0, isCharging=0, batMv=4164, batPct=96
DEBUG | ??:??:?? 9148 [Power] Battery: usbPower=0, isCharging=0, batMv=4167, batPct=96
DEBUG | ??:??:?? 9168 [Power] Battery: usbPower=0, isCharging=0, batMv=4181, batPct=97
DEBUG | ??:??:?? 9188 [Power] Battery: usbPower=0, isCharging=0, batMv=4168, batPct=96
DEBUG | ??:??:?? 9208 [Power] Battery: usbPower=0, isCharging=0, batMv=4175, batPct=97
DEBUG | ??:??:?? 9219 [RadioIf] (bw=250, sf=9, cr=4/8) packet symLen=2 ms, payloadSize=63, time 291 ms
DEBUG | ??:??:?? 9219 [RadioIf] Lora RX (id=0x1b7fc1ee fr=0xba to=0xff, WantAck=0, HopLim=3 Ch=0xb9 encrypted rxSNR=10.75 rxRSSI=-50)
DEBUG | ??:??:?? 9219 [RadioIf] AirTime - Packet received : 291ms
DEBUG | ??:??:?? 9219 [Router] Add packet record (id=0x1b7fc1ee fr=0xba to=0xff, WantAck=0, HopLim=3 Ch=0xb9 encrypted rxSNR=10.75 rxRSSI=-50)
DEBUG | ??:??:?? 9219 [Router] Using channel 0 (hash 0xb9)
DEBUG | ??:??:?? 9219 [Router] Using AES256 key!
DEBUG | ??:??:?? 9219 [Router] Software encrypt fr=5185b8ba, num=1b7fc1ee, numBytes=47!
DEBUG | ??:??:?? 9219 [Router] decoded message (id=0x1b7fc1ee fr=0xba to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 rxSNR=10.75 rxRSSI=-50)
DEBUG | ??:??:?? 9219 [Router] handleReceived(REMOTE) (id=0x1b7fc1ee fr=0xba to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 rxSNR=10.75 rxRSSI=-50)
DEBUG | ??:??:?? 9219 [Router] Module 'routing' wantsPacket=1
INFO | ??:??:?? 9219 [Router] Received routing from=0x5185b8ba, id=0x1b7fc1ee, portnum=4, payloadlen=43
DEBUG | ??:??:?? 9219 [Router] Routing sniffing (id=0x1b7fc1ee fr=0xba to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 rxSNR=10.75 rxRSSI=-50)
INFO | ??:??:?? 9219 [Router] Rebroadcasting received floodmsg to neighbors
DEBUG | ??:??:?? 9219 [Router] Using AES256 key!
DEBUG | ??:??:?? 9219 [Router] Software encrypt fr=5185b8ba, num=1b7fc1ee, numBytes=47!
DEBUG | ??:??:?? 9219 [Router] enqueuing for send (id=0x1b7fc1ee fr=0xba to=0xff, WantAck=0, HopLim=2 Ch=0xb9 encrypted rxSNR=10.75 rxRSSI=-50)
DEBUG | ??:??:?? 9219 [Router] txGood=104,rxGood=236,rxBad=0
DEBUG | ??:??:?? 9219 [Router] rx_snr found. hop_limit:2 rx_snr:10.750000
DEBUG | ??:??:?? 9219 [Router] rx_snr found in packet. As a router, setting tx delay:84
DEBUG | ??:??:?? 9219 [Router] Delivering rx packet (id=0x1b7fc1ee fr=0xba to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 rxSNR=10.75 rxRSSI=-50)
DEBUG | ??:??:?? 9219 [Router] Update DB node 0x5185b8ba, rx_time=0, channel=0
DEBUG | ??:??:?? 9219 [Router] Forwarding to phone (id=0x1b7fc1ee fr=0xba to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 rxSNR=10.75 rxRSSI=-50)
WARN | ??:??:?? 9219 [Router] ToPhone queue is full, discarding oldest
DEBUG | ??:??:?? 9219 [Router] Module 'routing' considered
DEBUG | ??:??:?? 9220 [RadioIf] Starting low level send (id=0x1b7fc1ee fr=0xba to=0xff, WantAck=0, HopLim=2 Ch=0xb9 encrypted rxSNR=10.75 rxRSSI=-50 priority=64)
DEBUG | ??:??:?? 9220 [RadioIf] (bw=250, sf=9, cr=4/8) packet symLen=2 ms, payloadSize=63, time 291 ms
DEBUG | ??:??:?? 9220 [RadioIf] AirTime - Packet transmitted : 291ms
DEBUG | ??:??:?? 9220 [RadioIf] Completed sending (id=0x1b7fc1ee fr=0xba to=0xff, WantAck=0, HopLim=2 Ch=0xb9 encrypted rxSNR=10.75 rxRSSI=-50 priority=64)
DEBUG | ??:??:?? 9221 [RadioIf] (bw=250, sf=9, cr=4/8) packet symLen=2 ms, payloadSize=63, time 291 ms
DEBUG | ??:??:?? 9221 [RadioIf] Lora RX (id=0x1b7fc1ee fr=0xba to=0xff, WantAck=0, HopLim=2 Ch=0xb9 encrypted rxSNR=11.75 rxRSSI=-61)
DEBUG | ??:??:?? 9221 [RadioIf] AirTime - Packet received : 291ms
DEBUG | ??:??:?? 9221 [Router] Found existing packet record for fr=0x5185b8ba,to=0xffffffff,id=0x1b7fc1ee
DEBUG | ??:??:?? 9221 [Router] Add packet record (id=0x1b7fc1ee fr=0xba to=0xff, WantAck=0, HopLim=2 Ch=0xb9 encrypted rxSNR=11.75 rxRSSI=-61)
DEBUG | ??:??:?? 9221 [Router] Ignoring incoming msg, because we've already seen it (id=0x1b7fc1ee fr=0xba to=0xff, WantAck=0, HopLim=2 Ch=0xb9 encrypted rxSNR=11.75 rxRSSI=-61)
DEBUG | ??:??:?? 9221 [Router] Incoming message was filtered 0x5185b8ba
DEBUG | ??:??:?? 9228 [Power] Battery: usbPower=0, isCharging=0, batMv=4167, batPct=96
DEBUG | ??:??:?? 9240 [RadioIf] (bw=250, sf=9, cr=4/8) packet symLen=2 ms, payloadSize=44, time 221 ms
DEBUG | ??:??:?? 9240 [RadioIf] Lora RX (id=0x1b7fc1ef fr=0xba to=0xff, WantAck=0, HopLim=3 Ch=0xb9 encrypted rxSNR=11 rxRSSI=-50)
DEBUG | ??:??:?? 9240 [RadioIf] AirTime - Packet received : 221ms
DEBUG | ??:??:?? 9240 [Router] Add packet record (id=0x1b7fc1ef fr=0xba to=0xff, WantAck=0, HopLim=3 Ch=0xb9 encrypted rxSNR=11 rxRSSI=-50)
DEBUG | ??:??:?? 9240 [Router] Using channel 0 (hash 0xb9)
DEBUG | ??:??:?? 9240 [Router] Using AES256 key!
DEBUG | ??:??:?? 9240 [Router] Software encrypt fr=5185b8ba, num=1b7fc1ef, numBytes=28!
DEBUG | ??:??:?? 9240 [Router] decoded message (id=0x1b7fc1ef fr=0xba to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxSNR=11 rxRSSI=-50)
DEBUG | ??:??:?? 9240 [Router] handleReceived(REMOTE) (id=0x1b7fc1ef fr=0xba to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxSNR=11 rxRSSI=-50)
DEBUG | ??:??:?? 9240 [Router] Module 'DeviceTelemetry' wantsPacket=1
INFO | ??:??:?? 9240 [Router] Received DeviceTelemetry from=0x5185b8ba, id=0x1b7fc1ef, portnum=67, payloadlen=24
DEBUG | ??:??:?? 9240 [Router] Module 'DeviceTelemetry' considered
DEBUG | ??:??:?? 9240 [Router] Module 'routing' wantsPacket=1
INFO | ??:??:?? 9240 [Router] Received routing from=0x5185b8ba, id=0x1b7fc1ef, portnum=67, payloadlen=24
DEBUG | ??:??:?? 9240 [Router] Routing sniffing (id=0x1b7fc1ef fr=0xba to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxSNR=11 rxRSSI=-50)
INFO | ??:??:?? 9240 [Router] Rebroadcasting received floodmsg to neighbors
DEBUG | ??:??:?? 9240 [Router] Using AES256 key!
DEBUG | ??:??:?? 9240 [Router] Software encrypt fr=5185b8ba, num=1b7fc1ef, numBytes=28!
DEBUG | ??:??:?? 9240 [Router] enqueuing for send (id=0x1b7fc1ef fr=0xba to=0xff, WantAck=0, HopLim=2 Ch=0xb9 encrypted rxSNR=11 rxRSSI=-50)
DEBUG | ??:??:?? 9240 [Router] txGood=105,rxGood=238,rxBad=0
DEBUG | ??:??:?? 9240 [Router] rx_snr found. hop_limit:2 rx_snr:11.000000
DEBUG | ??:??:?? 9240 [Router] rx_snr found in packet. As a router, setting tx delay:42
DEBUG | ??:??:?? 9240 [Router] Delivering rx packet (id=0x1b7fc1ef fr=0xba to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxSNR=11 rxRSSI=-50)
DEBUG | ??:??:?? 9240 [Router] Update DB node 0x5185b8ba, rx_time=0, channel=0
DEBUG | ??:??:?? 9240 [Router] Forwarding to phone (id=0x1b7fc1ef fr=0xba to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxSNR=11 rxRSSI=-50)
WARN | ??:??:?? 9240 [Router] ToPhone queue is full, discarding oldest
DEBUG | ??:??:?? 9240 [Router] Module 'routing' considered
DEBUG | ??:??:?? 9240 [RadioIf] Starting low level send (id=0x1b7fc1ef fr=0xba to=0xff, WantAck=0, HopLim=2 Ch=0xb9 encrypted rxSNR=11 rxRSSI=-50 priority=64)
DEBUG | ??:??:?? 9240 [RadioIf] (bw=250, sf=9, cr=4/8) packet symLen=2 ms, payloadSize=44, time 221 ms
DEBUG | ??:??:?? 9240 [RadioIf] AirTime - Packet transmitted : 221ms
DEBUG | ??:??:?? 9248 [Power] Battery: usbPower=0, isCharging=0, batMv=4169, batPct=96
DEBUG | ??:??:?? 9268 [Power] Battery: usbPower=0, isCharging=0, batMv=4176, batPct=97
DEBUG | ??:??:?? 9288 [Power] Battery: usbPower=0, isCharging=0, batMv=4183, batPct=98
DEBUG | ??:??:?? 9308 [Power] Battery: usbPower=0, isCharging=0, batMv=4163, batPct=96
DEBUG | ??:??:?? 9328 [Power] Battery: usbPower=0, isCharging=0, batMv=4163, batPct=96
DEBUG | ??:??:?? 9348 [Power] Battery: usbPower=0, isCharging=0, batMv=4161, batPct=95
DEBUG | ??:??:?? 9368 [Power] Battery: usbPower=0, isCharging=0, batMv=4166, batPct=96
DEBUG | ??:??:?? 9388 [Power] Battery: usbPower=0, isCharging=0, batMv=4163, batPct=96
DEBUG | ??:??:?? 9408 [Power] Battery: usbPower=0, isCharging=0, batMv=4159, batPct=95
DEBUG | ??:??:?? 9428 [Power] Battery: usbPower=0, isCharging=0, batMv=4180, batPct=97
DEBUG | ??:??:?? 9448 [Power] Battery: usbPower=0, isCharging=0, batMv=4181, batPct=97
DEBUG | ??:??:?? 9468 [Power] Battery: usbPower=0, isCharging=0, batMv=4178, batPct=97
DEBUG | ??:??:?? 9488 [Power] Battery: usbPower=0, isCharging=0, batMv=4175, batPct=97
DEBUG | ??:??:?? 9508 [Power] Battery: usbPower=0, isCharging=0, batMv=4166, batPct=96
DEBUG | ??:??:?? 9528 [Power] Battery: usbPower=0, isCharging=0, batMv=4176, batPct=97
DEBUG | ??:??:?? 9548 [Power] Battery: usbPower=0, isCharging=0, batMv=4166, batPct=96
DEBUG | ??:??:?? 9568 [Power] Battery: usbPower=0, isCharging=0, batMv=4164, batPct=96
DEBUG | ??:??:?? 9588 [Power] Battery: usbPower=0, isCharging=0, batMv=4178, batPct=97
DEBUG | ??:??:?? 9608 [Power] Battery: usbPower=0, isCharging=0, batMv=4167, batPct=96
DEBUG | ??:??:?? 9628 [Power] Battery: usbPower=0, isCharging=0, batMv=4161, batPct=95
DEBUG | ??:??:?? 9648 [Power] Battery: usbPower=0, isCharging=0, batMv=4164, batPct=96
DEBUG | ??:??:?? 9668 [Power] Battery: usbPower=0, isCharging=0, batMv=4180, batPct=97
DEBUG | ??:??:?? 9688 [Power] Battery: usbPower=0, isCharging=0, batMv=4183, batPct=98
DEBUG | ??:??:?? 9708 [Power] Battery: usbPower=0, isCharging=0, batMv=4177, batPct=97
DEBUG | ??:??:?? 9728 [Power] Battery: usbPower=0, isCharging=0, batMv=4163, batPct=96
DEBUG | ??:??:?? 9748 [Power] Battery: usbPower=0, isCharging=0, batMv=4167, batPct=96
DEBUG | ??:??:?? 9768 [Power] Battery: usbPower=0, isCharging=0, batMv=4180, batPct=97
DEBUG | ??:??:?? 9788 [Power] Battery: usbPower=0, isCharging=0, batMv=4177, batPct=97
DEBUG | ??:??:?? 9808 [Power] Battery: usbPower=0, isCharging=0, batMv=4161, batPct=95
DEBUG | ??:??:?? 9828 [Power] Battery: usbPower=0, isCharging=0, batMv=4158, batPct=95
DEBUG | ??:??:?? 9848 [Power] Battery: usbPower=0, isCharging=0, batMv=4159, batPct=95
DEBUG | ??:??:?? 9868 [Power] Battery: usbPower=0, isCharging=0, batMv=4161, batPct=95
DEBUG | ??:??:?? 9888 [Power] Battery: usbPower=0, isCharging=0, batMv=4173, batPct=97
DEBUG | ??:??:?? 9908 [Power] Battery: usbPower=0, isCharging=0, batMv=4158, batPct=95
DEBUG | ??:??:?? 9928 [Power] Battery: usbPower=0, isCharging=0, batMv=4163, batPct=96
DEBUG | ??:??:?? 9948 [Power] Battery: usbPower=0, isCharging=0, batMv=4162, batPct=95
...
DEBUG | ??:??:?? 10808 [AirTime] Rotating airtimes to a new period = 3
DEBUG | ??:??:?? 10809 [Power] Battery: usbPower=0, isCharging=0, batMv=4161, batPct=95
DEBUG | ??:??:?? 10829 [Power] Battery: usbPower=0, isCharging=0, batMv=4176, batPct=97
DEBUG | ??:??:?? 10849 [Power] Battery: usbPower=0, isCharging=0, batMv=4166, batPct=96
DEBUG | ??:??:?? 10869 [Power] Battery: usbPower=0, isCharging=0, batMv=4158, batPct=95
DEBUG | ??:??:?? 10889 [Power] Battery: usbPower=0, isCharging=0, batMv=4163, batPct=96
DEBUG | ??:??:?? 10909 [Power] Battery: usbPower=0, isCharging=0, batMv=4162, batPct=95
DEBUG | ??:??:?? 10929 [Power] Battery: usbPower=0, isCharging=0, batMv=4162, batPct=95
...
DEBUG | ??:??:?? 14408 [AirTime] Rotating airtimes to a new period = 4
DEBUG | ??:??:?? 14409 [Power] Battery: usbPower=0, isCharging=0, batMv=4159, batPct=95
DEBUG | ??:??:?? 14429 [Power] Battery: usbPower=0, isCharging=0, batMv=4159, batPct=95
DEBUG | ??:??:?? 14449 [Power] Battery: usbPower=0, isCharging=0, batMv=4161, batPct=95
DEBUG | ??:??:?? 14469 [Power] Battery: usbPower=0, isCharging=0, batMv=4167, batPct=96
DEBUG | ??:??:?? 14489 [Power] Battery: usbPower=0, isCharging=0, batMv=4157, batPct=95
DEBUG | ??:??:?? 14509 [Power] Battery: usbPower=0, isCharging=0, batMv=4180, batPct=97
DEBUG | ??:??:?? 14529 [Power] Battery: usbPower=0, isCharging=0, batMv=4178, batPct=97
DEBUG | ??:??:?? 14549 [Power] Battery: usbPower=0, isCharging=0, batMv=4177, batPct=97
...
```
