Skip to content

[Bug]: Traceroute shows "Meshtastic ffff" and "? dB" when all involved nodes are using recent firmwares #9792

Description

@domusonline

Category

Other

Hardware

Not Applicable

Is this bug report about any UI component firmware like InkHUD or Meshtatic UI (MUI)?

  • Meshtastic UI aka MUI colorTFT
  • InkHUD ePaper
  • OLED slide UI on any display

Firmware Version

2.7.17/18/19

Description

We're used to see traceroutes that show a node as "Meshtastic ffff" with an SNR of "0 dB". It is well known that this happens when we have the following situation:

NodeA Traces NodeC and the packet goes through some NodeB that decrements one hop, but doesn't add itself to the trace. And the usual explanation is that NodeB is using an old firmware. When NodeC receives the packet and compares the hops spent with the number of entries in the list, it adds the missing one using SNR = -128 (-32) and the broadast address as the missing node ID.
The following screen captures show this as shown directly on the screen (Android APP 2.7.13) and when we check the history (the difference by itself should be another APP bug):

Image

Image

However, this is reproducible with recent firmware versions and end nodes as CLIENT_MUTE and middle node as CLIENT (the role may not be a factor, but clearly this doesn't need "repeaters" or more esoteric roles)

For the follwoning explanation:

Source node: ends in ca70. DomusOnlineHT-CL-8. CLIENT_MUTE role. Hops: 5. Firmware 2.7.17
Middle node: ends in 9468. DomusOnlineT2. CLIENT role. Firmware 2.7.19
Target node: ends in 8418 DomusOnlineH1-8. CLIENT_MUTE role. Firmware 2.7.18

(firmware versions are not very relevant I suspect)

I'm attaching the log captured in syslog, filtered by the middle node. That's the node that is not adding itself.

What I believe it's happening:
1- Source (ca70) sends the trace
2- Middle (9468) gets the packet. Calls TraceRoute and Router modules. The packet to be sent is modified and includes the node 9468
3- The sending of the packet is significantly delayed (algorithm or ch. util or both).
4- Source (ca70) sends the packet again
5- Middle (9468) gets the second packet. Detects a dupe and cancels "sending". But I believe that at that stage it has two packets to send: The one which was received first, went through the Traceroute module and was modified, and the later one which was considered a duplicate. Both have the same ID. I believe the canceling is removing the changed one and leaves the original, unmodified, to be sent.

Please check the trace.

Relevant log output

Mar  2 22:34:33 DOH2_9468 Position [161]: Ch. util >25%. Skip send
Mar  2 22:34:34 DOH2_9468 RadioIf [161]: Corrected frequency offset: -62.968750
Mar  2 22:34:34 DOH2_9468 RadioIf [162]: Lora RX (id=0xd11ead77 fr=0xba6a7b20 to=0x9e763cc0, transport = 0, WantAck=1, HopLim=0 Ch=0x8 encrypted len=113 rxRSSI=-99 via MQTT hopStart=7 relay=0xef)
Mar  2 22:34:34 DOH2_9468 RadioIf [162]: Packet RX: 1091ms
Mar  2 22:34:34 DOH2_9468 Router [162]: Packet History - insert: Using new slot @uptime 162.026s TRACE NEW
Mar  2 22:34:34 DOH2_9468 Router [162]: Use channel 1 (hash 0x8)
Mar  2 22:34:34 DOH2_9468 Router [162]: Expand short PSK #1
Mar  2 22:34:34 DOH2_9468 Router [162]: Use AES128 key!
Mar  2 22:34:34 DOH2_9468 Router [162]: decoded message (id=0xd11ead77 fr=0xba6a7b20 to=0x9e763cc0, transport = 1, WantAck=1, HopLim=0 Ch=0x1 Portnum=70 WANTRESP dest=9e763cc0 rxtime=1772490984 rxRSSI=-99 via MQTT hopStart=7 relay=0xef)
Mar  2 22:34:34 DOH2_9468 Router [162]: handleReceived(REMOTE) (id=0xd11ead77 fr=0xba6a7b20 to=0x9e763cc0, transport = 1, WantAck=1, HopLim=0 Ch=0x1 Portnum=70 WANTRESP dest=9e763cc0 rxtime=1772490984 rxRSSI=-99 via MQTT hopStart=7 relay=0xef)
Mar  2 22:34:34 DOH2_9468 Router [162]: Module 'traceroute' wantsPacket=1
Mar  2 22:34:34 DOH2_9468 Router [162]: Received traceroute from=0xba6a7b20, id=0xd11ead77, portnum=70, payloadlen=84
Mar  2 22:34:34 DOH2_9468 Router [162]: Route traced:#0120xba6a7b20 --> 0x16cfa144 (0.00dB) --> 0x9ea14c6c (-6.00dB) --> 0x433daba4 (-16.25dB) --> 0xcd973087 (-17.50dB) --> 0x3cb60fd2 (-8.75dB) --> 0x89c98753 (-12.50dB) --> 0xe20a02ef (0.00dB) --> 0x433c9468 (0.00dB) --> ...
Mar  2 22:34:34 DOH2_9468 Router [162]: Module 'traceroute' considered
Mar  2 22:34:34 DOH2_9468 Router [162]: Module 'routing' wantsPacket=1
Mar  2 22:34:34 DOH2_9468 Router [162]: Routing sniffing (id=0xd11ead77 fr=0xba6a7b20 to=0x9e763cc0, transport = 1, WantAck=1, HopLim=0 Ch=0x1 Portnum=70 WANTRESP dest=9e763cc0 rxtime=1772490984 rxRSSI=-99 via MQTT hopStart=7 relay=0xef)
Mar  2 22:34:34 DOH2_9468 Router [162]: Module 'routing' considered
Mar  2 22:34:35 DOH2_9468 RadioIf [163]: Ignore received packet due to error=-7 (maybe id=0x9bd2db7f fr=0xb03caea4 to=0xffffffff flags=0xc0 rxSNR=-4.25 rxRSSI=-113 nextHop=0x0 relay=0x53)
Mar  2 22:34:35 DOH2_9468 RadioIf [163]: Packet RX (noise?) : 1009ms
Mar  2 22:34:36 DOH2_9468 RadioIf [164]: Corrected frequency offset: -620.968750
Mar  2 22:34:37 DOH2_9468 RadioIf [164]: Lora RX (id=0x26cb9f89 fr=0xa1975706 to=0xb03caea4, transport = 0, WantAck=1, HopLim=1 Ch=0x8 encrypted len=61 rxSNR=7 rxRSSI=-65 hopStart=4 relay=0x82)
Mar  2 22:34:37 DOH2_9468 RadioIf [164]: Packet RX: 722ms
Mar  2 22:34:37 DOH2_9468 Router [164]: Packet History - insert: Using new slot @uptime 164.974s TRACE NEW
Mar  2 22:34:37 DOH2_9468 Router [164]: Use channel 1 (hash 0x8)
Mar  2 22:34:37 DOH2_9468 Router [164]: Expand short PSK #1
Mar  2 22:34:37 DOH2_9468 Router [164]: Use AES128 key!
Mar  2 22:34:37 DOH2_9468 Router [165]: decoded message (id=0x26cb9f89 fr=0xa1975706 to=0xb03caea4, transport = 1, WantAck=1, HopLim=1 Ch=0x1 Portnum=70 WANTRESP rxtime=1772490987 rxSNR=7 rxRSSI=-65 hopStart=4 relay=0x82)
Mar  2 22:34:37 DOH2_9468 Router [165]: handleReceived(REMOTE) (id=0x26cb9f89 fr=0xa1975706 to=0xb03caea4, transport = 1, WantAck=1, HopLim=1 Ch=0x1 Portnum=70 WANTRESP rxtime=1772490987 rxSNR=7 rxRSSI=-65 hopStart=4 relay=0x82)
Mar  2 22:34:37 DOH2_9468 Router [165]: Module 'traceroute' wantsPacket=1
Mar  2 22:34:37 DOH2_9468 Router [165]: Received traceroute from=0xa1975706, id=0x26cb9f89, portnum=70, payloadlen=37
Mar  2 22:34:37 DOH2_9468 Router [165]: Route traced:#0120xa1975706 --> 0xcd973087 (-13.00dB) --> 0x29f088f9 (-5.75dB) --> 0xacd18b82 (3.00dB) --> 0x433c9468 (7.00dB) --> ...
Mar  2 22:34:37 DOH2_9468 Router [165]: Module 'traceroute' considered
Mar  2 22:34:37 DOH2_9468 Router [165]: Module 'routing' wantsPacket=1
Mar  2 22:34:37 DOH2_9468 Router [165]: Routing sniffing (id=0x26cb9f89 fr=0xa1975706 to=0xb03caea4, transport = 1, WantAck=1, HopLim=1 Ch=0x1 Portnum=70 WANTRESP rxtime=1772490987 rxSNR=7 rxRSSI=-65 hopStart=4 relay=0x82)
Mar  2 22:34:37 DOH2_9468 Router [165]: Rebroadcast received message coming from 82
Mar  2 22:34:37 DOH2_9468 Router [165]: Packet History - insert: Reusing slot aged 0.134s TRACE MATCHED PACKET
Mar  2 22:34:37 DOH2_9468 Router [165]: Expand short PSK #1
Mar  2 22:34:37 DOH2_9468 Router [165]: Use AES128 key!
Mar  2 22:34:37 DOH2_9468 Router [165]: enqueue for send (id=0x26cb9f89 fr=0xa1975706 to=0xb03caea4, transport = 1, WantAck=1, HopLim=0 Ch=0x8 encrypted len=66 rxtime=1772490987 rxSNR=7 rxRSSI=-65 hopStart=4 relay=0x68 priority=70)
Mar  2 22:34:37 DOH2_9468 Router [165]: txGood=8,txRelay=8,rxGood=28,rxBad=34
Mar  2 22:34:37 DOH2_9468 Router [165]: rx_snr found. hop_limit:0 rx_snr:7.000000
Mar  2 22:34:37 DOH2_9468 Router [165]: rx_snr found in packet. Setting tx delay:616
Mar  2 22:34:37 DOH2_9468 Router [165]: Module 'routing' considered



START


Mar  2 22:34:37 DOH2_9468 RadioIf [165]: Corrected frequency offset: 53.281250

======> Traceroute packet received:

Mar  2 22:34:37 DOH2_9468 RadioIf [165]: Lora RX (id=0x482c7fc3 fr=0xdb2aca70 to=0x55fc8418, transport = 0, WantAck=1, HopLim=5 Ch=0x8 encrypted len=22 rxSNR=6 rxRSSI=-88 hopStart=5 nextHop=0x68 relay=0x70)
Mar  2 22:34:37 DOH2_9468 RadioIf [165]: Packet RX: 395ms
Mar  2 22:34:37 DOH2_9468 RadioIf [165]: rx_snr found. hop_limit:0 rx_snr:7.000000
Mar  2 22:34:37 DOH2_9468 RadioIf [165]: rx_snr found in packet. Setting tx delay:2940
Mar  2 22:34:37 DOH2_9468 Router [165]: Packet History - insert: Using new slot @uptime 165.613s TRACE NEW

======> LongFast as secondary channel
Mar  2 22:34:37 DOH2_9468 Router [165]: Use channel 1 (hash 0x8)
Mar  2 22:34:37 DOH2_9468 Router [165]: Expand short PSK #1
Mar  2 22:34:37 DOH2_9468 Router [165]: Use AES128 key!

======> Packet decoded. NextHop directed to us... not flooding?...
Mar  2 22:34:37 DOH2_9468 Router [165]: decoded message (id=0x482c7fc3 fr=0xdb2aca70 to=0x55fc8418, transport = 1, WantAck=1, HopLim=5 Ch=0x1 Portnum=70 WANTRESP rxtime=1772490987 rxSNR=6 rxRSSI=-88 hopStart=5 nextHop=0x68 relay=0x70)
Mar  2 22:34:37 DOH2_9468 Router [165]: handleReceived(REMOTE) (id=0x482c7fc3 fr=0xdb2aca70 to=0x55fc8418, transport = 1, WantAck=1, HopLim=5 Ch=0x1 Portnum=70 WANTRESP rxtime=1772490987 rxSNR=6 rxRSSI=-88 hopStart=5 nextHop=0x68 relay=0x70)
Mar  2 22:34:37 DOH2_9468 Router [165]: Module 'traceroute' wantsPacket=1
Mar  2 22:34:37 DOH2_9468 Router [165]: Received traceroute from=0xdb2aca70, id=0x482c7fc3, portnum=70, payloadlen=0
Mar  2 22:34:37 DOH2_9468 Router [165]: Route traced:#0120xdb2aca70 --> 0x433c9468 (6.00dB) --> ...
Mar  2 22:34:37 DOH2_9468 Router [165]: Module 'traceroute' considered
Mar  2 22:34:37 DOH2_9468 Router [165]: Module 'routing' wantsPacket=1
Mar  2 22:34:37 DOH2_9468 Router [165]: Routing sniffing (id=0x482c7fc3 fr=0xdb2aca70 to=0x55fc8418, transport = 1, WantAck=1, HopLim=5 Ch=0x1 Portnum=70 WANTRESP rxtime=1772490987 rxSNR=6 rxRSSI=-88 hopStart=5 nextHop=0x68 relay=0x70)

======> Prepare to rebroadcast the changed packet
Mar  2 22:34:37 DOH2_9468 Router [165]: Rebroadcast received message coming from 70
Mar  2 22:34:37 DOH2_9468 Router [165]: Packet History - insert: Reusing slot aged 0.132s TRACE MATCHED PACKET
Mar  2 22:34:37 DOH2_9468 Router [165]: Setting next hop for packet with dest 55fc8418 to 18

======> Delay of 7s to retransmit?! Packet to send is enqued
Mar  2 22:34:37 DOH2_9468 Router [165]: Setting next retransmission in 7246 msecs: 
Mar  2 22:34:37 DOH2_9468 Router [165]:  (id=0x482c7fc3 fr=0xdb2aca70 to=0x55fc8418, transport = 1, WantAck=1, HopLim=4 Ch=0x1 Portnum=70 WANTRESP rxtime=1772490987 rxSNR=6 rxRSSI=-88 hopStart=5 nextHop=0x18 relay=0x68)
Mar  2 22:34:37 DOH2_9468 Router [165]: Expand short PSK #1
Mar  2 22:34:37 DOH2_9468 Router [165]: Use AES128 key!
Mar  2 22:34:37 DOH2_9468 Router [165]: enqueue for send (id=0x482c7fc3 fr=0xdb2aca70 to=0x55fc8418, transport = 1, WantAck=1, HopLim=4 Ch=0x8 encrypted len=33 rxtime=1772490987 rxSNR=6 rxRSSI=-88 hopStart=5 nextHop=0x18 relay=0x68 priority=70)
Mar  2 22:34:37 DOH2_9468 Router [165]: txGood=8,txRelay=8,rxGood=29,rxBad=34

Mar  2 22:34:37 DOH2_9468 Router [165]: rx_snr found. hop_limit:0 rx_snr:7.000000
Mar  2 22:34:37 DOH2_9468 Router [165]: rx_snr found in packet. Setting tx delay:3052
Mar  2 22:34:37 DOH2_9468 Router [165]: Module 'routing' considered

======> High ch. util... apparently irrelvant stuff
Mar  2 22:34:38 DOH2_9468 Position [166]: Ch. util >25%. Skip send
Mar  2 22:34:40 DOH2_9468 RadioIf [168]: Can not send yet, busyRx
Mar  2 22:34:40 DOH2_9468 RadioIf [168]: rx_snr found. hop_limit:0 rx_snr:7.000000
Mar  2 22:34:40 DOH2_9468 RadioIf [168]: rx_snr found in packet. Setting tx delay:4004
Mar  2 22:34:40 DOH2_9468 RadioIf [168]: Ignore received packet due to error=-7 (maybe id=0xd7f1911e fr=0x2f9f7808 to=0xffffffff flags=0x90 rxSNR=-18.75 rxRSSI=-124 nextHop=0x0 relay=0x87)
Mar  2 22:34:40 DOH2_9468 RadioIf [168]: Packet RX (noise?) : 641ms
Mar  2 22:34:40 DOH2_9468 RadioIf [168]: rx_snr found. hop_limit:0 rx_snr:7.000000
Mar  2 22:34:40 DOH2_9468 RadioIf [168]: rx_snr found in packet. Setting tx delay:2184
Mar  2 22:34:41 DOH2_9468 RadioIf [169]: Corrected frequency offset: -68.781242
Mar  2 22:34:41 DOH2_9468 RadioIf [169]: Lora RX (id=0x26cb9f89 fr=0xa1975706 to=0xb03caea4, transport = 0, WantAck=1, HopLim=0 Ch=0x8 encrypted len=66 rxSNR=4.75 rxRSSI=-100 hopStart=4 relay=0xef)
Mar  2 22:34:41 DOH2_9468 RadioIf [169]: Packet RX: 722ms
Mar  2 22:34:41 DOH2_9468 RadioIf [169]: rx_snr found. hop_limit:0 rx_snr:7.000000
Mar  2 22:34:41 DOH2_9468 RadioIf [169]: rx_snr found in packet. Setting tx delay:1456
Mar  2 22:34:41 DOH2_9468 Router [169]: Packet History - insert: Reusing slot aged 4.511s TRACE MATCHED PACKET
Mar  2 22:34:41 DOH2_9468 Router [169]: Ignore dupe incoming msg (id=0x26cb9f89 fr=0xa1975706 to=0xb03caea4, transport = 1, WantAck=1, HopLim=0 Ch=0x8 encrypted len=66 rxSNR=4.75 rxRSSI=-100 hopStart=4 relay=0xef)
Mar  2 22:34:41 DOH2_9468 Router [169]: cancelSending id=0x26cb9f89, removed=1
Mar  2 22:34:41 DOH2_9468 Router [169]: Incoming msg was filtered from 0xa1975706
Mar  2 22:34:43 DOH2_9468 RadioIf [171]: Can not send yet, busyRx
Mar  2 22:34:43 DOH2_9468 RadioIf [171]: rx_snr found. hop_limit:4 rx_snr:6.000000
Mar  2 22:34:43 DOH2_9468 RadioIf [171]: rx_snr found in packet. Setting tx delay:1596
Mar  2 22:34:43 DOH2_9468 Position [171]: Ch. util >25%. Skip send
Mar  2 22:34:44 DOH2_9468 RadioIf [172]: Corrected frequency offset: 53.281250


======> Source sent it again?
Mar  2 22:34:44 DOH2_9468 RadioIf [172]: Lora RX (id=0x482c7fc3 fr=0xdb2aca70 to=0x55fc8418, transport = 0, WantAck=1, HopLim=5 Ch=0x8 encrypted len=22 rxSNR=5.75 rxRSSI=-88 hopStart=5 nextHop=0x68 relay=0x70)
Mar  2 22:34:44 DOH2_9468 RadioIf [172]: Packet RX: 395ms
Mar  2 22:34:44 DOH2_9468 RadioIf [172]: rx_snr found. hop_limit:4 rx_snr:6.000000
Mar  2 22:34:44 DOH2_9468 RadioIf [172]: rx_snr found in packet. Setting tx delay:2352
Mar  2 22:34:44 DOH2_9468 Router [172]: Packet History - insert: Reusing slot aged 6.666s TRACE MATCHED PACKET
Mar  2 22:34:44 DOH2_9468 Router [172]: Ignore dupe incoming msg (id=0x482c7fc3 fr=0xdb2aca70 to=0x55fc8418, transport = 1, WantAck=1, HopLim=5 Ch=0x8 encrypted len=22 rxSNR=5.75 rxRSSI=-88 hopStart=5 nextHop=0x68 relay=0x70)

======> And we cancel the sending of one... Note that probably because it's a dupe, no modules are called
Mar  2 22:34:44 DOH2_9468 Router [172]: cancelSending id=0x482c7fc3, removed=1

======> And apparently it prepareds the packet (the new one?) to be sent
Mar  2 22:34:44 DOH2_9468 Router [172]: Rebroadcast received message coming from 70
Mar  2 22:34:44 DOH2_9468 Router [172]: Packet History - insert: Reusing slot aged 0.044s TRACE MATCHED PACKET
Mar  2 22:34:44 DOH2_9468 Router [172]: Setting next hop for packet with dest 55fc8418 to 18
Mar  2 22:34:44 DOH2_9468 Router [172]: Setting next retransmission in 7328 msecs: 
Mar  2 22:34:44 DOH2_9468 Router [172]:  (id=0x482c7fc3 fr=0xdb2aca70 to=0x55fc8418, transport = 1, WantAck=1, HopLim=4 Ch=0x8 encrypted len=22 rxSNR=5.75 rxRSSI=-88 hopStart=5 nextHop=0x18 relay=0x68)
Mar  2 22:34:44 DOH2_9468 Router [172]: enqueue for send (id=0x482c7fc3 fr=0xdb2aca70 to=0x55fc8418, transport = 1, WantAck=1, HopLim=4 Ch=0x8 encrypted len=22 rxSNR=5.75 rxRSSI=-88 hopStart=5 nextHop=0x18 relay=0x68 priority=70)
Mar  2 22:34:44 DOH2_9468 Router [172]: txGood=8,txRelay=8,rxGood=31,rxBad=35
Mar  2 22:34:44 DOH2_9468 Router [172]: rx_snr found. hop_limit:4 rx_snr:5.750000
Mar  2 22:34:44 DOH2_9468 Router [172]: rx_snr found in packet. Setting tx delay:756
Mar  2 22:34:44 DOH2_9468 Router [172]: Incoming msg was filtered from 0xdb2aca70
Mar  2 22:34:46 DOH2_9468 RadioIf [174]: Can not send yet, busyRx
Mar  2 22:34:46 DOH2_9468 RadioIf [174]: rx_snr found. hop_limit:4 rx_snr:5.750000
Mar  2 22:34:46 DOH2_9468 RadioIf [174]: rx_snr found in packet. Setting tx delay:532
Mar  2 22:34:47 DOH2_9468 RadioIf [175]: Ignore false preamble detection

======> And we send it... but probably the wrong one...
Mar  2 22:34:47 DOH2_9468 RadioIf [175]: Started Tx (id=0x482c7fc3 fr=0xdb2aca70 to=0x55fc8418, transport = 1, WantAck=1, HopLim=4 Ch=0x8 encrypted len=22 rxSNR=5.75 rxRSSI=-88 hopStart=5 nextHop=0x18 relay=0x68 priority=70)
Mar  2 22:34:47 DOH2_9468 RadioIf [175]: 0 packets remain in the TX queue
Mar  2 22:34:47 DOH2_9468 RadioIf [175]: Packet TX: 395ms
Mar  2 22:34:47 DOH2_9468 RadioIf [175]: Completed sending (id=0x482c7fc3 fr=0xdb2aca70 to=0x55fc8418, transport = 1, WantAck=1, HopLim=4 Ch=0x8 encrypted len=22 rxSNR=5.75 rxRSSI=-88 hopStart=5 nextHop=0x18 relay=0x68 priority=70)
Mar  2 22:34:48 DOH2_9468 Position [176]: Ch. util >25%. Skip send
Mar  2 22:34:48 DOH2_9468 RadioIf [176]: Corrected frequency offset: -233.468735





RETURN: Not relevant for the issue from here on


Mar  2 22:34:48 DOH2_9468 RadioIf [176]: Lora RX (id=0xd3d15b73 fr=0x55fc8418 to=0xdb2aca70, transport = 0, WantAck=1, HopLim=3 Ch=0x8 encrypted len=46 rxSNR=-1 rxRSSI=-96 hopStart=3 relay=0x18)
Mar  2 22:34:48 DOH2_9468 RadioIf [176]: Packet RX: 600ms
Mar  2 22:34:48 DOH2_9468 Router [176]: Packet History - insert: Using new slot @uptime 176.788s TRACE NEW
Mar  2 22:34:48 DOH2_9468 Router [176]: Use channel 1 (hash 0x8)
Mar  2 22:34:48 DOH2_9468 Router [176]: Expand short PSK #1
Mar  2 22:34:48 DOH2_9468 Router [176]: Use AES128 key!
Mar  2 22:34:48 DOH2_9468 Router [176]: decoded message (id=0xd3d15b73 fr=0x55fc8418 to=0xdb2aca70, transport = 1, WantAck=1, HopLim=3 Ch=0x1 Portnum=70 requestId=482c7fc3 rxtime=1772490998 rxSNR=-1 rxRSSI=-96 hopStart=3 relay=0x18)
Mar  2 22:34:48 DOH2_9468 Router [176]: handleReceived(REMOTE) (id=0xd3d15b73 fr=0x55fc8418 to=0xdb2aca70, transport = 1, WantAck=1, HopLim=3 Ch=0x1 Portnum=70 requestId=482c7fc3 rxtime=1772490998 rxSNR=-1 rxRSSI=-96 hopStart=3 relay=0x18)
Mar  2 22:34:48 DOH2_9468 Router [176]: Module 'traceroute' wantsPacket=1
Mar  2 22:34:48 DOH2_9468 Router [176]: Received traceroute from=0x55fc8418, id=0xd3d15b73, portnum=70, payloadlen=19
Mar  2 22:34:48 DOH2_9468 Router [176]: Route traced:#0120xdb2aca70 --> 0xffffffff (?dB) --> 0x55fc8418 (7.00dB)#012...(-1.00dB) 0x433c9468 <-- 0x55fc8418
Mar  2 22:34:48 DOH2_9468 Router [176]: Module 'traceroute' considered
Mar  2 22:34:48 DOH2_9468 Router [176]: Module 'routing' wantsPacket=1
Mar  2 22:34:48 DOH2_9468 Router [176]: Routing sniffing (id=0xd3d15b73 fr=0x55fc8418 to=0xdb2aca70, transport = 1, WantAck=1, HopLim=3 Ch=0x1 Portnum=70 requestId=482c7fc3 rxtime=1772490998 rxSNR=-1 rxRSSI=-96 hopStart=3 relay=0x18)
Mar  2 22:34:48 DOH2_9468 Router [176]: cancelSending id=0x482c7fc3, removed=0
Mar  2 22:34:48 DOH2_9468 Router [176]: cancelSending id=0x482c7fc3, removed=0
Mar  2 22:34:48 DOH2_9468 Router [176]: Rebroadcast received message coming from 18
Mar  2 22:34:48 DOH2_9468 Router [176]: Packet History - insert: Reusing slot aged 0.149s TRACE MATCHED PACKET
Mar  2 22:34:49 DOH2_9468 Router [176]: Expand short PSK #1
Mar  2 22:34:49 DOH2_9468 Router [176]: Use AES128 key!
Mar  2 22:34:49 DOH2_9468 Router [176]: enqueue for send (id=0xd3d15b73 fr=0x55fc8418 to=0xdb2aca70, transport = 1, WantAck=1, HopLim=2 Ch=0x8 encrypted len=64 rxtime=1772490998 rxSNR=-1 rxRSSI=-96 hopStart=3 relay=0x68 priority=80)
Mar  2 22:34:49 DOH2_9468 Router [176]: txGood=9,txRelay=9,rxGood=32,rxBad=35
Mar  2 22:34:49 DOH2_9468 Router [176]: rx_snr found. hop_limit:2 rx_snr:-1.000000

Metadata

Metadata

Assignees

No one assigned

    Labels

    bugSomething isn't working

    Type

    No type
    No fields configured for issues without a type.

    Projects

    No projects

    Milestone

    No milestone

    Relationships

    None yet

    Development

    No branches or pull requests

    Issue actions