search

meshtastic / python

The Python CLI and API for talking to Meshtastic devices
https://meshtastic.org
370 stars 157 forks source link

[Bug]: Remote admin --seturl functionality not changing LoRa freq slot. #649

Open ayysasha opened 3 weeks ago

ayysasha commented 3 weeks ago

Category

Other

Hardware

Rak4631

Firmware Version

2.4.0.46d7b82

Description

When attempting to set a new url on a remote note, which includes a change in LoRa channel slot, the remote node fails to take the new Channel Slot settings and only applies the new channel and PSK settings.

This is using --dest xxx and --seturl xxxx

The new URL contains both a new primary and new LoRa freq slot, but the same psk and slot for the admin channel.

Reproduction Steps:

meshtastic --dest xxx --seturl https://meshtastic.org/e/#CjISIDhLvMAdwCLRgb82uGEh4fuWty5Vv3Qifp1q-0jWTLGhGgpCdXJudGFzdGljOgIIIAopEiCs-O6_F1BdAZ80_FJPUw2dVGwyD-HNujCNeI5TGVJmsRoFYWRtaW4SEwgBEAY4AUADSAFQHlgPaAHABgE

(The node is orig. on channel 39 and this URL intends to switch it to channel 15, shortfast, with a few channels)

Behaviour: The channels and but not the lora frequency slot.

I have captured one of these failures with meshtastic --noproto in the log output below, which is from the target device.

Relevant log output

DEBUG | 08:55:11 73 [Router] Module 'Admin' wantsPacket=1
INFO  | 08:55:11 73 [Router] Received Admin from=0x49bdab6e, id=0x5446db92, portnum=6, payloadlen=2
INFO  | 08:55:11 73 [Router] Client is getting channel 0
INFO  | 08:55:11 73 [Router] Asked module 'Admin' to send a response
DEBUG | 08:55:11 73 [Router] Module 'routing' wantsPacket=1
INFO  | 08:55:11 73 [Router] Received routing from=0x49bdab6e, id=0x5446db92, portnum=6, payloadlen=2
DEBUG | 08:55:11 73 [Router] Routing sniffing (id=0x5446db92 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971311 rxSNR=11 rxRSSI=-30 hopStart=3)
DEBUG | 08:55:11 73 [Router] Delivering rx packet (id=0x5446db92 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971311 rxSNR=11 rxRSSI=-30 hopStart=3)
DEBUG | 08:55:11 73 [Router] Update DB node 0x49bdab6e, rx_time=1723971311
DEBUG | 08:55:11 73 [Router] Forwarding to phone (id=0x5446db92 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971311 rxSNR=11 rxRSSI=-30 hopStart=3)
DEBUG | 08:55:11 73 [Router] Module 'routing' considered
DEBUG | 08:55:11 73 [Router] Sending response (id=0x1cfec3f6 fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x1 Portnum=6 requestId=5446db92 rxtime=1723971311 priority=70)
DEBUG | 08:55:11 73 [Router] Update DB node 0x7c546c7c, rx_time=1723971311
DEBUG | 08:55:11 73 [Router] Add packet record (id=0x1cfec3f6 fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x1 Portnum=6 requestId=5446db92 rxtime=1723971311 priority=70)
DEBUG | 08:55:11 73 [Router] Using AES256 key!
DEBUG | 08:55:11 73 [Router] Software encrypt fr=7c546c7c, num=1cfec3f6, numBytes=63!
DEBUG | 08:55:11 73 [Router] enqueuing for send (id=0x1cfec3f6 fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971311 hopStart=2 priority=70)
DEBUG | 08:55:11 73 [Router] txGood=2,rxGood=5,rxBad=0
DEBUG | 08:55:11 73 [RadioIf] Starting low level send (id=0x1cfec3f6 fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971311 hopStart=2 priority=70)
DEBUG | 08:55:11 73 [RadioIf] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=79, time 240 ms
DEBUG | 08:55:11 73 [RadioIf] AirTime - Packet transmitted : 240ms
DEBUG | 08:55:12 74 [RadioIf] Completed sending (id=0x1cfec3f6 fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971311 hopStart=2 priority=70)
DEBUG | 08:55:12 74 [RadioIf] Starting low level send (id=0x5446db91 fr=0x6e to=0xff, WantAck=0, HopLim=2 Ch=0x9a encrypted rxSNR=11 rxRSSI=-30 hopStart=3 priority=64)
DEBUG | 08:55:12 74 [RadioIf] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=37, time 145 ms
DEBUG | 08:55:12 74 [RadioIf] AirTime - Packet transmitted : 145ms
DEBUG | 08:55:12 74 [RadioIf] Completed sending (id=0x5446db91 fr=0x6e to=0xff, WantAck=0, HopLim=2 Ch=0x9a encrypted rxSNR=11 rxRSSI=-30 hopStart=3 priority=64)
DEBUG | 08:55:12 74 [RadioIf] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=24, time 115 ms
DEBUG | 08:55:12 74 [RadioIf] Lora RX (id=0x5446db93 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxSNR=10.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:12 74 [RadioIf] AirTime - Packet received : 115ms
DEBUG | 08:55:12 74 [Router] Add packet record (id=0x5446db93 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxSNR=10.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:12 74 [Router] Using channel 1 (hash 0x56)
DEBUG | 08:55:12 74 [Router] Using AES256 key!
DEBUG | 08:55:12 74 [Router] Software encrypt fr=49bdab6e, num=5446db93, numBytes=8!
DEBUG | 08:55:12 74 [Router] decoded message (id=0x5446db93 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971312 rxSNR=10.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:12 74 [Router] handleReceived(REMOTE) (id=0x5446db93 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971312 rxSNR=10.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:12 74 [Router] Module 'Admin' wantsPacket=1
INFO  | 08:55:12 74 [Router] Received Admin from=0x49bdab6e, id=0x5446db93, portnum=6, payloadlen=2
INFO  | 08:55:12 74 [Router] Client is getting channel 1
INFO  | 08:55:12 74 [Router] Asked module 'Admin' to send a response
DEBUG | 08:55:12 74 [Router] Module 'routing' wantsPacket=1
INFO  | 08:55:12 74 [Router] Received routing from=0x49bdab6e, id=0x5446db93, portnum=6, payloadlen=2
DEBUG | 08:55:12 74 [Router] Routing sniffing (id=0x5446db93 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971312 rxSNR=10.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:12 74 [Router] Delivering rx packet (id=0x5446db93 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971312 rxSNR=10.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:12 74 [Router] Update DB node 0x49bdab6e, rx_time=1723971312
DEBUG | 08:55:12 74 [Router] Forwarding to phone (id=0x5446db93 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971312 rxSNR=10.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:12 74 [Router] Module 'routing' considered
DEBUG | 08:55:12 74 [Router] Sending response (id=0x1cfec3f7 fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x1 Portnum=6 requestId=5446db93 rxtime=1723971312 priority=70)
DEBUG | 08:55:12 74 [Router] Update DB node 0x7c546c7c, rx_time=1723971312
DEBUG | 08:55:12 74 [Router] Add packet record (id=0x1cfec3f7 fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x1 Portnum=6 requestId=5446db93 rxtime=1723971312 priority=70)
DEBUG | 08:55:12 74 [Router] Using AES256 key!
DEBUG | 08:55:12 74 [Router] Software encrypt fr=7c546c7c, num=1cfec3f7, numBytes=58!
DEBUG | 08:55:12 74 [Router] enqueuing for send (id=0x1cfec3f7 fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971312 hopStart=2 priority=70)
DEBUG | 08:55:12 74 [Router] txGood=4,rxGood=6,rxBad=0
DEBUG | 08:55:12 74 [RadioIf] Starting low level send (id=0x1cfec3f7 fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971312 hopStart=2 priority=70)
DEBUG | 08:55:12 74 [RadioIf] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=74, time 229 ms
DEBUG | 08:55:12 74 [RadioIf] AirTime - Packet transmitted : 229ms
DEBUG | 08:55:13 75 [RadioIf] Completed sending (id=0x1cfec3f7 fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971312 hopStart=2 priority=70)
DEBUG | 08:55:13 75 [RadioIf] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=24, time 115 ms
DEBUG | 08:55:13 75 [RadioIf] Lora RX (id=0x5446db94 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxSNR=10.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:13 75 [RadioIf] AirTime - Packet received : 115ms
DEBUG | 08:55:13 75 [Router] Add packet record (id=0x5446db94 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxSNR=10.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:13 75 [Router] Using channel 1 (hash 0x56)
DEBUG | 08:55:13 75 [Router] Using AES256 key!
DEBUG | 08:55:13 75 [Router] Software encrypt fr=49bdab6e, num=5446db94, numBytes=8!
DEBUG | 08:55:13 75 [Router] decoded message (id=0x5446db94 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971313 rxSNR=10.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:13 75 [Router] handleReceived(REMOTE) (id=0x5446db94 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971313 rxSNR=10.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:13 75 [Router] Module 'Admin' wantsPacket=1
INFO  | 08:55:13 75 [Router] Received Admin from=0x49bdab6e, id=0x5446db94, portnum=6, payloadlen=2
INFO  | 08:55:13 75 [Router] Client is getting channel 2
INFO  | 08:55:13 75 [Router] Asked module 'Admin' to send a response
DEBUG | 08:55:13 75 [Router] Module 'routing' wantsPacket=1
INFO  | 08:55:13 75 [Router] Received routing from=0x49bdab6e, id=0x5446db94, portnum=6, payloadlen=2
DEBUG | 08:55:13 75 [Router] Routing sniffing (id=0x5446db94 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971313 rxSNR=10.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:13 75 [Router] Delivering rx packet (id=0x5446db94 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971313 rxSNR=10.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:13 75 [Router] Update DB node 0x49bdab6e, rx_time=1723971313
DEBUG | 08:55:13 75 [Router] Forwarding to phone (id=0x5446db94 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971313 rxSNR=10.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:13 75 [Router] Module 'routing' considered
DEBUG | 08:55:13 75 [Router] Sending response (id=0x1cfec3f8 fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x1 Portnum=6 requestId=5446db94 rxtime=1723971313 priority=70)
DEBUG | 08:55:13 75 [Router] Update DB node 0x7c546c7c, rx_time=1723971313
DEBUG | 08:55:13 75 [Router] Add packet record (id=0x1cfec3f8 fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x1 Portnum=6 requestId=5446db94 rxtime=1723971313 priority=70)
DEBUG | 08:55:13 75 [Router] Using AES256 key!
DEBUG | 08:55:13 75 [Router] Software encrypt fr=7c546c7c, num=1cfec3f8, numBytes=64!
DEBUG | 08:55:13 75 [Router] enqueuing for send (id=0x1cfec3f8 fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971313 hopStart=2 priority=70)
DEBUG | 08:55:13 75 [Router] txGood=5,rxGood=7,rxBad=0
DEBUG | 08:55:13 75 [RadioIf] Starting low level send (id=0x1cfec3f8 fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971313 hopStart=2 priority=70)
DEBUG | 08:55:13 75 [RadioIf] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=80, time 242 ms
DEBUG | 08:55:13 75 [RadioIf] AirTime - Packet transmitted : 242ms
DEBUG | 08:55:13 75 [RadioIf] Completed sending (id=0x1cfec3f8 fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971313 hopStart=2 priority=70)
DEBUG | 08:55:14 76 [RadioIf] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=24, time 115 ms
DEBUG | 08:55:14 76 [RadioIf] Lora RX (id=0x5446db95 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxSNR=10.5 rxRSSI=-34 hopStart=3)
DEBUG | 08:55:14 76 [RadioIf] AirTime - Packet received : 115ms
DEBUG | 08:55:14 76 [Router] Add packet record (id=0x5446db95 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxSNR=10.5 rxRSSI=-34 hopStart=3)
DEBUG | 08:55:14 76 [Router] Using channel 1 (hash 0x56)
DEBUG | 08:55:14 76 [Router] Using AES256 key!
DEBUG | 08:55:14 76 [Router] Software encrypt fr=49bdab6e, num=5446db95, numBytes=8!
DEBUG | 08:55:14 76 [Router] decoded message (id=0x5446db95 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971314 rxSNR=10.5 rxRSSI=-34 hopStart=3)
DEBUG | 08:55:14 76 [Router] handleReceived(REMOTE) (id=0x5446db95 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971314 rxSNR=10.5 rxRSSI=-34 hopStart=3)
DEBUG | 08:55:14 76 [Router] Module 'Admin' wantsPacket=1
INFO  | 08:55:14 76 [Router] Received Admin from=0x49bdab6e, id=0x5446db95, portnum=6, payloadlen=2
INFO  | 08:55:14 76 [Router] Client is getting channel 3
INFO  | 08:55:14 76 [Router] Asked module 'Admin' to send a response
DEBUG | 08:55:14 76 [Router] Module 'routing' wantsPacket=1
INFO  | 08:55:14 76 [Router] Received routing from=0x49bdab6e, id=0x5446db95, portnum=6, payloadlen=2
DEBUG | 08:55:14 76 [Router] Routing sniffing (id=0x5446db95 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971314 rxSNR=10.5 rxRSSI=-34 hopStart=3)
DEBUG | 08:55:14 76 [Router] Delivering rx packet (id=0x5446db95 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971314 rxSNR=10.5 rxRSSI=-34 hopStart=3)
DEBUG | 08:55:14 76 [Router] Update DB node 0x49bdab6e, rx_time=1723971314
DEBUG | 08:55:14 76 [Router] Forwarding to phone (id=0x5446db95 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971314 rxSNR=10.5 rxRSSI=-34 hopStart=3)
DEBUG | 08:55:14 76 [Router] Module 'routing' considered
DEBUG | 08:55:14 76 [Router] Sending response (id=0x1cfec3f9 fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x1 Portnum=6 requestId=5446db95 rxtime=1723971314 priority=70)
DEBUG | 08:55:14 76 [Router] Update DB node 0x7c546c7c, rx_time=1723971314
DEBUG | 08:55:14 76 [Router] Add packet record (id=0x1cfec3f9 fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x1 Portnum=6 requestId=5446db95 rxtime=1723971314 priority=70)
DEBUG | 08:55:14 76 [Router] Using AES256 key!
DEBUG | 08:55:14 76 [Router] Software encrypt fr=7c546c7c, num=1cfec3f9, numBytes=15!
DEBUG | 08:55:14 76 [Router] enqueuing for send (id=0x1cfec3f9 fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971314 hopStart=2 priority=70)
DEBUG | 08:55:14 76 [Router] txGood=6,rxGood=8,rxBad=0
DEBUG | 08:55:14 76 [RadioIf] Starting low level send (id=0x1cfec3f9 fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971314 hopStart=2 priority=70)
DEBUG | 08:55:14 76 [RadioIf] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=31, time 131 ms
DEBUG | 08:55:14 76 [RadioIf] AirTime - Packet transmitted : 131ms
DEBUG | 08:55:14 76 [RadioIf] Completed sending (id=0x1cfec3f9 fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971314 hopStart=2 priority=70)
DEBUG | 08:55:15 77 [RadioIf] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=24, time 115 ms
DEBUG | 08:55:15 77 [RadioIf] Lora RX (id=0x5446db96 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxSNR=11 rxRSSI=-32 hopStart=3)
DEBUG | 08:55:15 77 [RadioIf] AirTime - Packet received : 115ms
DEBUG | 08:55:15 77 [Router] Add packet record (id=0x5446db96 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxSNR=11 rxRSSI=-32 hopStart=3)
DEBUG | 08:55:15 77 [Router] Using channel 1 (hash 0x56)
DEBUG | 08:55:15 77 [Router] Using AES256 key!
DEBUG | 08:55:15 77 [Router] Software encrypt fr=49bdab6e, num=5446db96, numBytes=8!
DEBUG | 08:55:15 77 [Router] decoded message (id=0x5446db96 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971315 rxSNR=11 rxRSSI=-32 hopStart=3)
DEBUG | 08:55:15 77 [Router] handleReceived(REMOTE) (id=0x5446db96 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971315 rxSNR=11 rxRSSI=-32 hopStart=3)
DEBUG | 08:55:15 77 [Router] Module 'Admin' wantsPacket=1
INFO  | 08:55:15 77 [Router] Received Admin from=0x49bdab6e, id=0x5446db96, portnum=6, payloadlen=2
INFO  | 08:55:15 77 [Router] Client is getting channel 4
INFO  | 08:55:15 77 [Router] Asked module 'Admin' to send a response
DEBUG | 08:55:15 77 [Router] Module 'routing' wantsPacket=1
INFO  | 08:55:15 77 [Router] Received routing from=0x49bdab6e, id=0x5446db96, portnum=6, payloadlen=2
DEBUG | 08:55:15 77 [Router] Routing sniffing (id=0x5446db96 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971315 rxSNR=11 rxRSSI=-32 hopStart=3)
DEBUG | 08:55:15 77 [Router] Delivering rx packet (id=0x5446db96 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971315 rxSNR=11 rxRSSI=-32 hopStart=3)
DEBUG | 08:55:15 77 [Router] Update DB node 0x49bdab6e, rx_time=1723971315
DEBUG | 08:55:15 77 [Router] Forwarding to phone (id=0x5446db96 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971315 rxSNR=11 rxRSSI=-32 hopStart=3)
DEBUG | 08:55:15 77 [Router] Module 'routing' considered
DEBUG | 08:55:15 77 [Router] Sending response (id=0x1cfec3fa fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x1 Portnum=6 requestId=5446db96 rxtime=1723971315 priority=70)
DEBUG | 08:55:15 77 [Router] Update DB node 0x7c546c7c, rx_time=1723971315
DEBUG | 08:55:15 77 [Router] Add packet record (id=0x1cfec3fa fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x1 Portnum=6 requestId=5446db96 rxtime=1723971315 priority=70)
DEBUG | 08:55:15 77 [Router] Using AES256 key!
DEBUG | 08:55:15 77 [Router] Software encrypt fr=7c546c7c, num=1cfec3fa, numBytes=15!
DEBUG | 08:55:15 77 [Router] enqueuing for send (id=0x1cfec3fa fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971315 hopStart=2 priority=70)
DEBUG | 08:55:15 77 [Router] txGood=7,rxGood=9,rxBad=0
DEBUG | 08:55:15 77 [RadioIf] Starting low level send (id=0x1cfec3fa fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971315 hopStart=2 priority=70)
DEBUG | 08:55:15 77 [RadioIf] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=31, time 131 ms
DEBUG | 08:55:15 77 [RadioIf] AirTime - Packet transmitted : 131ms
DEBUG | 08:55:15 77 [RadioIf] Completed sending (id=0x1cfec3fa fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971315 hopStart=2 priority=70)
DEBUG | 08:55:16 78 [RadioIf] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=24, time 115 ms
DEBUG | 08:55:16 78 [RadioIf] Lora RX (id=0x5446db97 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxSNR=11.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:16 78 [RadioIf] AirTime - Packet received : 115ms
DEBUG | 08:55:16 78 [Router] Add packet record (id=0x5446db97 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxSNR=11.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:16 78 [Router] Using channel 1 (hash 0x56)
DEBUG | 08:55:16 78 [Router] Using AES256 key!
DEBUG | 08:55:16 78 [Router] Software encrypt fr=49bdab6e, num=5446db97, numBytes=8!
DEBUG | 08:55:16 78 [Router] decoded message (id=0x5446db97 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971316 rxSNR=11.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:16 78 [Router] handleReceived(REMOTE) (id=0x5446db97 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971316 rxSNR=11.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:16 78 [Router] Module 'Admin' wantsPacket=1
INFO  | 08:55:16 78 [Router] Received Admin from=0x49bdab6e, id=0x5446db97, portnum=6, payloadlen=2
INFO  | 08:55:16 78 [Router] Client is getting channel 5
INFO  | 08:55:16 78 [Router] Asked module 'Admin' to send a response
DEBUG | 08:55:16 78 [Router] Module 'routing' wantsPacket=1
INFO  | 08:55:16 78 [Router] Received routing from=0x49bdab6e, id=0x5446db97, portnum=6, payloadlen=2
DEBUG | 08:55:16 78 [Router] Routing sniffing (id=0x5446db97 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971316 rxSNR=11.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:16 78 [Router] Delivering rx packet (id=0x5446db97 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971316 rxSNR=11.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:16 78 [Router] Update DB node 0x49bdab6e, rx_time=1723971316
DEBUG | 08:55:16 78 [Router] Forwarding to phone (id=0x5446db97 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971316 rxSNR=11.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:16 78 [Router] Module 'routing' considered
DEBUG | 08:55:16 78 [Router] Sending response (id=0x1cfec3fb fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x1 Portnum=6 requestId=5446db97 rxtime=1723971316 priority=70)
DEBUG | 08:55:16 78 [Router] Update DB node 0x7c546c7c, rx_time=1723971316
DEBUG | 08:55:16 78 [Router] Add packet record (id=0x1cfec3fb fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x1 Portnum=6 requestId=5446db97 rxtime=1723971316 priority=70)
DEBUG | 08:55:16 78 [Router] Using AES256 key!
DEBUG | 08:55:16 78 [Router] Software encrypt fr=7c546c7c, num=1cfec3fb, numBytes=15!
DEBUG | 08:55:16 78 [Router] enqueuing for send (id=0x1cfec3fb fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971316 hopStart=2 priority=70)
DEBUG | 08:55:16 78 [Router] txGood=8,rxGood=10,rxBad=0
DEBUG | 08:55:16 78 [RadioIf] Starting low level send (id=0x1cfec3fb fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971316 hopStart=2 priority=70)
DEBUG | 08:55:16 78 [RadioIf] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=31, time 131 ms
DEBUG | 08:55:16 78 [RadioIf] AirTime - Packet transmitted : 131ms
DEBUG | 08:55:16 78 [RadioIf] Completed sending (id=0x1cfec3fb fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971316 hopStart=2 priority=70)
DEBUG | 08:55:17 79 [RadioIf] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=24, time 115 ms
DEBUG | 08:55:17 79 [RadioIf] Lora RX (id=0x5446db98 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxSNR=10.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:17 79 [RadioIf] AirTime - Packet received : 115ms
DEBUG | 08:55:17 79 [Router] Add packet record (id=0x5446db98 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxSNR=10.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:17 79 [Router] Using channel 1 (hash 0x56)
DEBUG | 08:55:17 79 [Router] Using AES256 key!
DEBUG | 08:55:17 79 [Router] Software encrypt fr=49bdab6e, num=5446db98, numBytes=8!
DEBUG | 08:55:17 79 [Router] decoded message (id=0x5446db98 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971317 rxSNR=10.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:17 79 [Router] handleReceived(REMOTE) (id=0x5446db98 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971317 rxSNR=10.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:17 79 [Router] Module 'Admin' wantsPacket=1
INFO  | 08:55:17 79 [Router] Received Admin from=0x49bdab6e, id=0x5446db98, portnum=6, payloadlen=2
INFO  | 08:55:17 79 [Router] Client is getting channel 6
INFO  | 08:55:17 79 [Router] Asked module 'Admin' to send a response
DEBUG | 08:55:17 79 [Router] Module 'routing' wantsPacket=1
INFO  | 08:55:17 79 [Router] Received routing from=0x49bdab6e, id=0x5446db98, portnum=6, payloadlen=2
DEBUG | 08:55:17 79 [Router] Routing sniffing (id=0x5446db98 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971317 rxSNR=10.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:17 79 [Router] Delivering rx packet (id=0x5446db98 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971317 rxSNR=10.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:17 79 [Router] Update DB node 0x49bdab6e, rx_time=1723971317
DEBUG | 08:55:17 79 [Router] Forwarding to phone (id=0x5446db98 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971317 rxSNR=10.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:17 79 [Router] Module 'routing' considered
DEBUG | 08:55:17 79 [Router] Sending response (id=0x1cfec3fc fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x1 Portnum=6 requestId=5446db98 rxtime=1723971317 priority=70)
DEBUG | 08:55:17 79 [Router] Update DB node 0x7c546c7c, rx_time=1723971317
DEBUG | 08:55:17 79 [Router] Add packet record (id=0x1cfec3fc fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x1 Portnum=6 requestId=5446db98 rxtime=1723971317 priority=70)
DEBUG | 08:55:17 79 [Router] Using AES256 key!
DEBUG | 08:55:17 79 [Router] Software encrypt fr=7c546c7c, num=1cfec3fc, numBytes=15!
DEBUG | 08:55:17 79 [Router] enqueuing for send (id=0x1cfec3fc fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971317 hopStart=2 priority=70)
DEBUG | 08:55:17 79 [Router] txGood=9,rxGood=11,rxBad=0
DEBUG | 08:55:17 79 [RadioIf] Starting low level send (id=0x1cfec3fc fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971317 hopStart=2 priority=70)
DEBUG | 08:55:17 79 [RadioIf] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=31, time 131 ms
DEBUG | 08:55:17 79 [RadioIf] AirTime - Packet transmitted : 131ms
DEBUG | 08:55:17 79 [RadioIf] Completed sending (id=0x1cfec3fc fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971317 hopStart=2 priority=70)
DEBUG | 08:55:18 80 [RadioIf] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=24, time 115 ms
DEBUG | 08:55:18 80 [RadioIf] Lora RX (id=0x5446db99 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxSNR=11.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:18 80 [RadioIf] AirTime - Packet received : 115ms
DEBUG | 08:55:18 80 [Router] Add packet record (id=0x5446db99 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxSNR=11.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:18 80 [Router] Using channel 1 (hash 0x56)
DEBUG | 08:55:18 80 [Router] Using AES256 key!
DEBUG | 08:55:18 80 [Router] Software encrypt fr=49bdab6e, num=5446db99, numBytes=8!
DEBUG | 08:55:18 80 [Router] decoded message (id=0x5446db99 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971318 rxSNR=11.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:18 80 [Router] handleReceived(REMOTE) (id=0x5446db99 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971318 rxSNR=11.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:18 80 [Router] Module 'Admin' wantsPacket=1
INFO  | 08:55:18 80 [Router] Received Admin from=0x49bdab6e, id=0x5446db99, portnum=6, payloadlen=2
INFO  | 08:55:18 80 [Router] Client is getting channel 7
INFO  | 08:55:18 80 [Router] Asked module 'Admin' to send a response
DEBUG | 08:55:18 80 [Router] Module 'routing' wantsPacket=1
INFO  | 08:55:18 80 [Router] Received routing from=0x49bdab6e, id=0x5446db99, portnum=6, payloadlen=2
DEBUG | 08:55:18 80 [Router] Routing sniffing (id=0x5446db99 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971318 rxSNR=11.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:18 80 [Router] Delivering rx packet (id=0x5446db99 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971318 rxSNR=11.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:18 80 [Router] Update DB node 0x49bdab6e, rx_time=1723971318
DEBUG | 08:55:18 80 [Router] Forwarding to phone (id=0x5446db99 fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971318 rxSNR=11.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:18 80 [Router] Module 'routing' considered
DEBUG | 08:55:18 80 [Router] Sending response (id=0x1cfec3fd fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x1 Portnum=6 requestId=5446db99 rxtime=1723971318 priority=70)
DEBUG | 08:55:18 80 [Router] Update DB node 0x7c546c7c, rx_time=1723971318
DEBUG | 08:55:18 80 [Router] Add packet record (id=0x1cfec3fd fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x1 Portnum=6 requestId=5446db99 rxtime=1723971318 priority=70)
DEBUG | 08:55:18 80 [Router] Using AES256 key!
DEBUG | 08:55:18 80 [Router] Software encrypt fr=7c546c7c, num=1cfec3fd, numBytes=15!
DEBUG | 08:55:18 80 [Router] enqueuing for send (id=0x1cfec3fd fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971318 hopStart=2 priority=70)
DEBUG | 08:55:18 80 [Router] txGood=10,rxGood=12,rxBad=0
DEBUG | 08:55:18 80 [RadioIf] Starting low level send (id=0x1cfec3fd fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971318 hopStart=2 priority=70)
DEBUG | 08:55:18 80 [RadioIf] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=31, time 131 ms
DEBUG | 08:55:18 80 [RadioIf] AirTime - Packet transmitted : 131ms
DEBUG | 08:55:18 80 [RadioIf] Completed sending (id=0x1cfec3fd fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971318 hopStart=2 priority=70)
DEBUG | 08:55:19 81 [RadioIf] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=79, time 240 ms
DEBUG | 08:55:19 81 [RadioIf] Lora RX (id=0x5446db9a fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxSNR=10.5 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:19 81 [RadioIf] AirTime - Packet received : 240ms
DEBUG | 08:55:19 81 [Router] Add packet record (id=0x5446db9a fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxSNR=10.5 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:19 81 [Router] Using channel 1 (hash 0x56)
DEBUG | 08:55:19 81 [Router] Using AES256 key!
DEBUG | 08:55:19 81 [Router] Software encrypt fr=49bdab6e, num=5446db9a, numBytes=63!
DEBUG | 08:55:19 81 [Router] decoded message (id=0x5446db9a fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971319 rxSNR=10.5 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:19 81 [Router] handleReceived(REMOTE) (id=0x5446db9a fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971319 rxSNR=10.5 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:19 81 [Router] Module 'Admin' wantsPacket=1
INFO  | 08:55:19 81 [Router] Received Admin from=0x49bdab6e, id=0x5446db9a, portnum=6, payloadlen=57
INFO  | 08:55:19 81 [Router] Client is setting channel 0
INFO  | 08:55:19 81 [Router] Saving changes to disk
INFO  | 08:55:19 81 [Router] Wanted region 1, using US
DEBUG | 08:55:19 81 [Router] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=0, time 61 ms
DEBUG | 08:55:19 81 [Router] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=253, time 637 ms
INFO  | 08:55:19 81 [Router] Radio freq=911.625, config.lora.frequency_offset=0.000
INFO  | 08:55:19 81 [Router] Set radio: region=US, name=Burntastic, config=4, ch=38, power=30
INFO  | 08:55:19 81 [Router] Radio myRegion->freqStart -> myRegion->freqEnd: 902.000000 -> 928.000000 (26.000000 MHz)
INFO  | 08:55:19 81 [Router] Radio myRegion->numChannels: 104 x 250.000kHz
INFO  | 08:55:19 81 [Router] Radio channel_num: 39
INFO  | 08:55:19 81 [Router] Radio frequency: 911.625000
INFO  | 08:55:19 81 [Router] Slot time: 42 msec
INFO  | 08:55:20 81 [Router] Saving /prefs/channels.proto
INFO  | 08:55:21 83 [Router] Asked module 'Admin' to send a response
DEBUG | 08:55:21 83 [Router] Module 'routing' wantsPacket=1
INFO  | 08:55:21 83 [Router] Received routing from=0x49bdab6e, id=0x5446db9a, portnum=6, payloadlen=57
DEBUG | 08:55:21 83 [Router] Routing sniffing (id=0x5446db9a fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971319 rxSNR=10.5 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:21 83 [Router] Delivering rx packet (id=0x5446db9a fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971319 rxSNR=10.5 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:21 83 [Router] Update DB node 0x49bdab6e, rx_time=1723971319
DEBUG | 08:55:21 83 [Router] Forwarding to phone (id=0x5446db9a fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971319 rxSNR=10.5 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:21 83 [Router] Module 'routing' considered
DEBUG | 08:55:21 83 [Router] Sending response (id=0x1cfec3fe fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x1 Portnum=5 requestId=5446db9a rxtime=1723971321 priority=120)
DEBUG | 08:55:21 83 [Router] Update DB node 0x7c546c7c, rx_time=1723971321
DEBUG | 08:55:21 83 [Router] Add packet record (id=0x1cfec3fe fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x1 Portnum=5 requestId=5446db9a rxtime=1723971321 priority=120)
DEBUG | 08:55:21 83 [Router] Using AES256 key!
DEBUG | 08:55:21 83 [Router] Software encrypt fr=7c546c7c, num=1cfec3fe, numBytes=11!
DEBUG | 08:55:21 83 [Router] enqueuing for send (id=0x1cfec3fe fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971321 hopStart=2 priority=120)
DEBUG | 08:55:21 83 [Router] txGood=11,rxGood=13,rxBad=0
DEBUG | 08:55:21 83 [RadioIf] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=72, time 225 ms
DEBUG | 08:55:21 83 [RadioIf] Lora RX (id=0x5446db9b fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxSNR=10.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:21 83 [RadioIf] AirTime - Packet received : 225ms
DEBUG | 08:55:21 83 [Router] Add packet record (id=0x5446db9b fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxSNR=10.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:21 83 [Router] Using channel 1 (hash 0x56)
DEBUG | 08:55:21 83 [Router] Using AES256 key!
DEBUG | 08:55:21 83 [Router] Software encrypt fr=49bdab6e, num=5446db9b, numBytes=56!
DEBUG | 08:55:21 83 [Router] decoded message (id=0x5446db9b fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971321 rxSNR=10.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:21 83 [Router] handleReceived(REMOTE) (id=0x5446db9b fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971321 rxSNR=10.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:21 83 [Router] Module 'Admin' wantsPacket=1
INFO  | 08:55:21 83 [Router] Received Admin from=0x49bdab6e, id=0x5446db9b, portnum=6, payloadlen=50
INFO  | 08:55:21 83 [Router] Client is setting channel 1
INFO  | 08:55:21 83 [Router] Saving changes to disk
INFO  | 08:55:21 83 [Router] Wanted region 1, using US
DEBUG | 08:55:21 83 [Router] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=0, time 61 ms
DEBUG | 08:55:21 83 [Router] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=253, time 637 ms
INFO  | 08:55:21 83 [Router] Radio freq=911.625, config.lora.frequency_offset=0.000
INFO  | 08:55:21 83 [Router] Set radio: region=US, name=Burntastic, config=4, ch=38, power=30
INFO  | 08:55:21 83 [Router] Radio myRegion->freqStart -> myRegion->freqEnd: 902.000000 -> 928.000000 (26.000000 MHz)
INFO  | 08:55:21 83 [Router] Radio myRegion->numChannels: 104 x 250.000kHz
INFO  | 08:55:21 83 [Router] Radio channel_num: 39
INFO  | 08:55:21 83 [Router] Radio frequency: 911.625000
INFO  | 08:55:21 83 [Router] Slot time: 42 msec
DEBUG | 08:55:21 83 [Router] Starting low level send (id=0x1cfec3fe fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971321 hopStart=2 priority=120)
DEBUG | 08:55:21 83 [Router] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=27, time 123 ms
DEBUG | 08:55:21 83 [Router] AirTime - Packet transmitted : 123ms
DEBUG | 08:55:21 83 [Router] Completed sending (id=0x1cfec3fe fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x56 encrypted rxtime=1723971321 hopStart=2 priority=120)
INFO  | 08:55:21 83 [Router] Saving /prefs/channels.proto
INFO  | 08:55:22 84 [Router] Asked module 'Admin' to send a response
DEBUG | 08:55:22 84 [Router] Module 'routing' wantsPacket=1
INFO  | 08:55:22 84 [Router] Received routing from=0x49bdab6e, id=0x5446db9b, portnum=6, payloadlen=50
DEBUG | 08:55:22 84 [Router] Routing sniffing (id=0x5446db9b fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971321 rxSNR=10.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:22 84 [Router] Delivering rx packet (id=0x5446db9b fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971321 rxSNR=10.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:22 84 [Router] Update DB node 0x49bdab6e, rx_time=1723971321
DEBUG | 08:55:22 84 [Router] Forwarding to phone (id=0x5446db9b fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x1 Portnum=6 WANTRESP rxtime=1723971321 rxSNR=10.25 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:22 84 [Router] Module 'routing' considered
DEBUG | 08:55:22 84 [Router] Sending response (id=0x1cfec3ff fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x1 Portnum=5 requestId=5446db9b rxtime=1723971322 priority=120)
DEBUG | 08:55:22 84 [Router] Update DB node 0x7c546c7c, rx_time=1723971322
DEBUG | 08:55:22 84 [Router] Add packet record (id=0x1cfec3ff fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0x1 Portnum=5 requestId=5446db9b rxtime=1723971322 priority=120)
DEBUG | 08:55:22 84 [Router] Using AES256 key!
DEBUG | 08:55:22 84 [Router] Software encrypt fr=7c546c7c, num=1cfec3ff, numBytes=11!
DEBUG | 08:55:22 84 [Router] enqueuing for send (id=0x1cfec3ff fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0xef encrypted rxtime=1723971322 hopStart=2 priority=120)
DEBUG | 08:55:22 84 [Router] txGood=12,rxGood=14,rxBad=0
DEBUG | 08:55:22 84 [RadioIf] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=46, time 166 ms
DEBUG | 08:55:22 84 [RadioIf] Lora RX (id=0x5446db9c fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxSNR=10.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:22 84 [RadioIf] AirTime - Packet received : 166ms
DEBUG | 08:55:22 84 [Router] Add packet record (id=0x5446db9c fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxSNR=10.75 rxRSSI=-31 hopStart=3)
WARN  | 08:55:22 84 [Router] No suitable channel found for decoding, hash was 0x56!
DEBUG | 08:55:22 84 [Router] packet decoding failed or skipped (no PSK?) (id=0x5446db9c fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxtime=1723971322 rxSNR=10.75 rxRSSI=-31 hop
DEBUG | 08:55:22 84 [Router] Module 'routing' wantsPacket=1
INFO  | 08:55:22 84 [Router] Received routing from=0x49bdab6e, id=0x5446db9c, portnum=30, payloadlen=26205
DEBUG | 08:55:22 84 [Router] Routing sniffing (id=0x5446db9c fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxtime=1723971322 rxSNR=10.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:22 84 [Router] Delivering rx packet (id=0x5446db9c fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxtime=1723971322 rxSNR=10.75 rxRSSI=-31 hopStart=3)
DEBUG | 08:55:22 84 [Router] Forwarding to phone (id=0x5446db9c fr=0x6e to=0x7c, WantAck=0, HopLim=3 Ch=0x56 encrypted rxtime=1723971322 rxSNR=10.75 rxRSSI=-31 hopStart=3)
WARN  | 08:55:22 84 [Router] No suitable channel found for decoding, hash was 0x56!
DEBUG | 08:55:22 84 [Router] Module 'routing' considered
DEBUG | 08:55:22 84 [RadioIf] Starting low level send (id=0x1cfec3ff fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0xef encrypted rxtime=1723971322 hopStart=2 priority=120)
DEBUG | 08:55:22 84 [RadioIf] (bw=250, sf=9, cr=4/5) packet symLen=2 ms, payloadSize=27, time 123 ms
DEBUG | 08:55:22 84 [RadioIf] AirTime - Packet transmitted : 123ms
DEBUG | 08:55:22 84 [RadioIf] Completed sending (id=0x1cfec3ff fr=0x7c to=0x6e, WantAck=0, HopLim=2 Ch=0xef encrypted rxtime=1723971322 hopStart=2 priority=120)
DEBUG | 08:55:23 85 [Power] Battery: usbPower=0, isCharging=0, batMv=4099, batPct=93
DEBUG | 08:55:43 105 [Power] Battery: usbPower=0, isCharging=0, batMv=4100, batPct=93
DEBUG | 08:56:03 125 [Power] Battery: usbPower=0, isCharging=0, batMv=4101, batPct=93
DEBUG | 08:56:23 145 [Power] Battery: usbPower=0, isCharging=0, batMv=4102, batPct=93
DEBUG | 08:56:43 165 [Power] Battery: usbPower=0, isCharging=0, batMv=4105, batPct=93
DEBUG | 08:57:03 185 [Power] Battery: usbPower=0, isCharging=0, batMv=4107, batPct=94
ayysasha commented 3 weeks ago

Also FYI for the CLI its 2.3.15

jp-bennett commented 3 weeks ago

My guess is that the channel setting gets applied first, then the LoRa command is missed because the channel has already changed.