Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

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

Open
ayysasha opened this issue Aug 18, 2024 · 2 comments
Open

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

ayysasha opened this issue Aug 18, 2024 · 2 comments
Labels
bug Something isn't working

Comments

@ayysasha
Copy link

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 ayysasha added the bug Something isn't working label Aug 18, 2024
@garthvh garthvh transferred this issue from meshtastic/firmware Aug 18, 2024
@ayysasha
Copy link
Author

Also FYI for the CLI its 2.3.15

@jp-bennett
Copy link
Contributor

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

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug Something isn't working
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@jp-bennett @ayysasha