[Bug]: MQTT disconnects, node resets Wi-Fi and becomes unreachable until rebooted #5203

Dude5101 · 2024-10-31T08:49:04Z

Dude5101
Oct 31, 2024

Hardware

Heltec V3, Other

Firmware Version

2.5.6d55c08d

Description

MQTT and Wi-Fi are configured via the Android app.
Connects fine on boot and runs fine with MQTT traffic flowing, after about 40min - 60min serial connection shows "Failed to contact MQTT server directly"

After 5 retries to contact MQTT node reconnects Wi-Fi but becomes unresponsive on app and on network.
Only reboot fixes the issue for another 40-60min

Tried firmware 2.5.6, 2.5.8, 2.5.9
Tried local MQTT and cloud MQTT brokers
Tried different Wi-Fi router with only 2.4GHz active and HT20 set
Same issue on Heltec v3 and LilyGo T-Lora T3-S3

Local broker logs show "client timeout, closing connection"

Suspect it's an MQTT Keepalive issue and then becomes a Wi-Fi issue when it tries to reconnect, please check

Relevant log output

SNR=-0.25 rxRSSI=-104 via MQTT hopStart=5)
DEBUG | 08:29:41 3272 [Router] recentPackets size=102
DEBUG | 08:29:41 3272 [Router] recentPackets size=102 (after clearing expired packets)
DEBUG | 08:29:41 3272 [Router] handleReceived(REMOTE) (id=0x8c9af32c fr=0x5c to=0xff, WantAck=0, HopLim=4 Ch=0x0 Portnum=4 WANTRESP rxtime=1730363381 rxSNR=-0.25 rxRSSI=-104 via MQTT hopSta
DEBUG | 08:29:41 3272 [Router] Module 'nodeinfo' wantsPacket=1
INFO  | 08:29:41 3272 [Router] Received nodeinfo from=0x3364385c, id=0x8c9af32c, portnum=4, payloadlen=74
DEBUG | 08:29:41 3272 [Router] old user ZS1ATX-Home/ATXH, channel=0
DEBUG | 08:29:41 3272 [Router] Incoming Pubkey:  cf dc 6d 66 7a e7 09 06 7f db a6 63 8d 3c 5a 09 f1 01 1c a3 da 3f 86 96 cb af 17 cc d6 cc 99 0f

INFO  | 08:29:41 3272 [Router] Public Key set for node, not updating!
DEBUG | 08:29:41 3272 [Router] Saved Pubkey:  cf dc 6d 66 7a e7 09 06 7f db a6 63 8d 3c 5a 09 f1 01 1c a3 da 3f 86 96 cb af 17 cc d6 cc 99 0f

DEBUG | 08:29:41 3272 [Router] updating changed=0 user ZS1ATX-Home/ATXH, channel=0
DEBUG | 08:29:41 3272 [Router] Skip sending NodeInfo since we just sent it less than 5 minutes ago.
INFO  | 08:29:41 3272 [Router] Asked module 'nodeinfo' to send a response
DEBUG | 08:29:41 3272 [Router] Module 'routing' wantsPacket=1
INFO  | 08:29:41 3272 [Router] Received routing from=0x3364385c, id=0x8c9af32c, portnum=4, payloadlen=74
DEBUG | 08:29:41 3272 [Router] Routing sniffing (id=0x8c9af32c fr=0x5c to=0xff, WantAck=0, HopLim=4 Ch=0x0 Portnum=4 WANTRESP rxtime=1730363381 rxSNR=-0.25 rxRSSI=-104 via MQTT hopStart=5)
INFO  | 08:29:41 3272 [Router] Rebroadcasting received floodmsg
DEBUG | 08:29:41 3272 [Router] Expanding short PSK #1
DEBUG | 08:29:41 3272 [Router] Using AES128 key!
DEBUG | 08:29:41 3272 [Router] enqueuing for send (id=0x8c9af32c fr=0x5c to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1730363381 rxSNR=-0.25 rxRSSI=-104 via MQTT hopStart=5 priority
DEBUG | 08:29:41 3272 [Router] txGood=275,txRelay=261,rxGood=283,rxBad=3
DEBUG | 08:29:41 3272 [Router] rx_snr found. hop_limit:3 rx_snr:-0.250000
DEBUG | 08:29:41 3272 [Router] rx_snr found in packet. Setting tx delay:2156
DEBUG | 08:29:41 3272 [Router] Delivering rx packet (id=0x8c9af32c fr=0x5c to=0xff, WantAck=0, HopLim=4 Ch=0x0 Portnum=4 WANTRESP rxtime=1730363381 rxSNR=-0.25 rxRSSI=-104 via MQTT hopStart
DEBUG | 08:29:41 3272 [Router] Update DB node 0x3364385c, rx_time=1730363381
DEBUG | 08:29:41 3272 [Router] Forwarding to phone (id=0x8c9af32c fr=0x5c to=0xff, WantAck=0, HopLim=4 Ch=0x0 Portnum=4 WANTRESP rxtime=1730363381 rxSNR=-0.25 rxRSSI=-104 via MQTT hopStart=
WARN  | 08:29:41 3272 [Router] ToPhone queue is full, dropping packet.
INFO  | 08:29:41 3272 [Router] Asked module 'routing' to send a response
DEBUG | 08:29:41 3272 [Screen] Screen: Joined: ZS1ATX-Home
DEBUG | 08:29:43 3274 [RadioIf] rx_snr found. hop_limit:3 rx_snr:-0.250000
DEBUG | 08:29:43 3274 [RadioIf] rx_snr found in packet. Setting tx delay:2079
DEBUG | 08:29:45 3276 [RadioIf] Starting low level send (id=0x8c9af32c fr=0x5c to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1730363381 rxSNR=-0.25 rxRSSI=-104 via MQTT hopStart=5 pri
DEBUG | 08:29:45 3276 [RadioIf] Packet transmitted : 952ms
DEBUG | 08:29:46 3277 [RadioIf] Completed sending (id=0x8c9af32c fr=0x5c to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1730363381 rxSNR=-0.25 rxRSSI=-104 via MQTT hopStart=5 priority=
WARN  | 08:29:46 3277 [PositionModule] Channel utilization is >25 percent. Skipping this opportunity to send.
INFO  | 08:29:47 3278 [mqtt] Received MQTT topic msh/ZA/2/e/LongFast/!3364cbb0, len=145
INFO  | 08:29:47 3278 [mqtt] Ignoring decoded message on MQTT, encryption is enabled.
INFO  | 08:29:47 3278 [mqtt] Using non-TLS-encrypted session
INFO  | 08:29:47 3278 [mqtt] Attempting to connect directly to MQTT server mqtt.zr1rf.za.net, port: 1883, username: REDACTED, password: REDACTED
ERROR | 08:29:54 3285 [mqtt] Failed to contact MQTT server directly (1/5)...
WARN  | 08:29:54 3285 [PositionModule] Channel utilization is >25 percent. Skipping this opportunity to send.
DEBUG | 08:29:54 3285 [RadioIf] Lora RX (id=0x8c9af32c fr=0x5c to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=7 rxRSSI=-62 via MQTT hopStart=5)
DEBUG | 08:29:54 3285 [RadioIf] Packet received : 952ms
DEBUG | 08:29:54 3285 [Router] Found existing packet record for fr=0x3364385c,to=0xffffffff,id=0x8c9af32c
DEBUG | 08:29:54 3285 [Router] recentPackets size=102
DEBUG | 08:29:54 3285 [Router] recentPackets size=100 (after clearing expired packets)
DEBUG | 08:29:54 3285 [Router] Found existing packet record for fr=0x3364385c,to=0xffffffff,id=0x8c9af32c
DEBUG | 08:29:54 3285 [Router] Add packet record (id=0x8c9af32c fr=0x5c to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=7 rxRSSI=-62 via MQTT hopStart=5)
DEBUG | 08:29:54 3285 [Router] recentPackets size=100
DEBUG | 08:29:54 3285 [Router] recentPackets size=100 (after clearing expired packets)
DEBUG | 08:29:54 3285 [Router] Ignoring dupe incoming msg (id=0x8c9af32c fr=0x5c to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=7 rxRSSI=-62 via MQTT hopStart=5)
DEBUG | 08:29:54 3285 [Router] cancelSending id=0x8c9af32c, removed=0
DEBUG | 08:29:54 3285 [Router] Incoming msg was filtered from 0x3364385c
WARN  | 08:29:59 3290 [PositionModule] Channel utilization is >25 percent. Skipping this opportunity to send.
DEBUG | 08:29:59 3290 [Power] Battery: usbPower=1, isCharging=1, batMv=4443, batPct=100
DEBUG | 08:30:19 3310 [NeighborInfoModule] Removing neighbor with node ID 0x435977d8
DEBUG | 08:30:19 3310 [NeighborInfoModule] Our NodeDB contains 1 neighbors
DEBUG | 08:30:19 3310 [NeighborInfoModule] Node 0: node_id=0x43561c70, snr=7.75
DEBUG | 08:30:19 3310 [NeighborInfoModule] Partially randomized packet id 1706326205
DEBUG | 08:30:19 3310 [NeighborInfoModule] SENDING NEIGHBORINFO PACKET from Node 0xda5b06f0 to Node 0xda5b06f0 (last sent by 0xda5b06f0)
DEBUG | 08:30:19 3310 [NeighborInfoModule] Packet contains 1 neighbors
DEBUG | 08:30:19 3310 [NeighborInfoModule] Neighbor 0: node_id=0x43561c70, snr=7.75
DEBUG | 08:30:19 3310 [NeighborInfoModule] Update DB node 0xda5b06f0, rx_time=1730363419
DEBUG | 08:30:19 3310 [NeighborInfoModule] handleReceived(LOCAL) (id=0x65b478bd fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363419 priority=10)
DEBUG | 08:30:19 3310 [NeighborInfoModule] No modules interested in portnum=71, src=LOCAL
DEBUG | 08:30:19 3310 [NeighborInfoModule] Add packet record (id=0x65b478bd fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363419 priority=10)
DEBUG | 08:30:19 3310 [NeighborInfoModule] recentPackets size=101
DEBUG | 08:30:19 3310 [NeighborInfoModule] recentPackets size=98 (after clearing expired packets)
DEBUG | 08:30:19 3310 [NeighborInfoModule] Expanding short PSK #1
DEBUG | 08:30:19 3310 [NeighborInfoModule] Using AES128 key!
DEBUG | 08:30:19 3310 [NeighborInfoModule] MQTT onSend - Publishing
DEBUG | 08:30:19 3310 [NeighborInfoModule] encrypted message
INFO  | 08:30:19 3310 [NeighborInfoModule] MQTT not connected, queueing packet
DEBUG | 08:30:19 3310 [NeighborInfoModule] enqueuing for send (id=0x65b478bd fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x8 encrypted rxtime=1730363419 hopStart=7 priority=10)
DEBUG | 08:30:19 3310 [NeighborInfoModule] txGood=276,txRelay=262,rxGood=284,rxBad=3
INFO  | 08:30:19 3310 [NeighborInfoModule] tophone queue status queue is full, discarding oldest
DEBUG | 08:30:19 3310 [NeighborInfoModule] Using channel 0 (hash 0x8)
DEBUG | 08:30:19 3310 [NeighborInfoModule] Expanding short PSK #1
DEBUG | 08:30:19 3310 [NeighborInfoModule] Using AES128 key!
DEBUG | 08:30:19 3310 [NeighborInfoModule] decoded message (id=0x65b478bd fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363419 hopStart=7 priority=10)
WARN  | 08:30:19 3310 [NeighborInfoModule] ToPhone queue is full, dropping packet.
DEBUG | 08:30:19 3310 [Power] Battery: usbPower=1, isCharging=1, batMv=4451, batPct=100
DEBUG | 08:30:20 3311 [RadioIf] Starting low level send (id=0x65b478bd fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x8 encrypted rxtime=1730363419 hopStart=7 priority=10)
DEBUG | 08:30:20 3311 [RadioIf] Drop Tx packet because dest is broadcast no-lora
DEBUG | 08:30:20 3311 [RadioIf] Packet transmitted : 600ms
INFO  | 08:30:24 3315 [mqtt] Using non-TLS-encrypted session
INFO  | 08:30:24 3315 [mqtt] Attempting to connect directly to MQTT server mqtt.zr1rf.za.net, port: 1883, username: meshdev, password: large4cats
ERROR | 08:30:31 3322 [mqtt] Failed to contact MQTT server directly (2/5)...
DEBUG | 08:30:39 3330 [Power] Battery: usbPower=1, isCharging=1, batMv=4427, batPct=100
DEBUG | 08:30:59 3350 [Power] Battery: usbPower=1, isCharging=1, batMv=4443, batPct=100
INFO  | 08:31:01 3352 [mqtt] Using non-TLS-encrypted session
INFO  | 08:31:01 3352 [mqtt] Attempting to connect directly to MQTT server mqtt.zr1rf.za.net, port: 1883, username: meshdev, password: large4cats
ERROR | 08:31:08 3359 [mqtt] Failed to contact MQTT server directly (3/5)...
DEBUG | 08:31:19 3370 [NeighborInfoModule] Removing neighbor with node ID 0x43561c70
DEBUG | 08:31:19 3370 [NeighborInfoModule] Our NodeDB contains 0 neighbors
DEBUG | 08:31:19 3370 [NeighborInfoModule] Partially randomized packet id 3920435390
DEBUG | 08:31:19 3370 [NeighborInfoModule] SENDING NEIGHBORINFO PACKET from Node 0xda5b06f0 to Node 0xda5b06f0 (last sent by 0xda5b06f0)
DEBUG | 08:31:19 3370 [NeighborInfoModule] Packet contains 0 neighbors
DEBUG | 08:31:19 3370 [NeighborInfoModule] Update DB node 0xda5b06f0, rx_time=1730363479
DEBUG | 08:31:19 3370 [NeighborInfoModule] handleReceived(LOCAL) (id=0xe9ad18be fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363479 priority=10)
DEBUG | 08:31:19 3370 [NeighborInfoModule] No modules interested in portnum=71, src=LOCAL
DEBUG | 08:31:19 3370 [NeighborInfoModule] Add packet record (id=0xe9ad18be fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363479 priority=10)
DEBUG | 08:31:19 3370 [NeighborInfoModule] recentPackets size=99
DEBUG | 08:31:19 3370 [NeighborInfoModule] recentPackets size=94 (after clearing expired packets)
DEBUG | 08:31:19 3370 [NeighborInfoModule] Expanding short PSK #1
DEBUG | 08:31:19 3370 [NeighborInfoModule] Using AES128 key!
DEBUG | 08:31:19 3370 [NeighborInfoModule] MQTT onSend - Publishing
DEBUG | 08:31:19 3370 [NeighborInfoModule] encrypted message
INFO  | 08:31:19 3370 [NeighborInfoModule] MQTT not connected, queueing packet
DEBUG | 08:31:19 3370 [NeighborInfoModule] enqueuing for send (id=0xe9ad18be fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x8 encrypted rxtime=1730363479 hopStart=7 priority=10)
DEBUG | 08:31:19 3370 [NeighborInfoModule] txGood=276,txRelay=262,rxGood=284,rxBad=3
INFO  | 08:31:19 3370 [NeighborInfoModule] tophone queue status queue is full, discarding oldest
DEBUG | 08:31:19 3370 [NeighborInfoModule] Using channel 0 (hash 0x8)
DEBUG | 08:31:19 3370 [NeighborInfoModule] Expanding short PSK #1
DEBUG | 08:31:19 3370 [NeighborInfoModule] Using AES128 key!
DEBUG | 08:31:19 3370 [NeighborInfoModule] decoded message (id=0xe9ad18be fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363479 hopStart=7 priority=10)
WARN  | 08:31:19 3370 [NeighborInfoModule] ToPhone queue is full, dropping packet.
DEBUG | 08:31:19 3370 [Power] Battery: usbPower=1, isCharging=1, batMv=4461, batPct=100
DEBUG | 08:31:20 3371 [RadioIf] Starting low level send (id=0xe9ad18be fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x8 encrypted rxtime=1730363479 hopStart=7 priority=10)
DEBUG | 08:31:20 3371 [RadioIf] Drop Tx packet because dest is broadcast no-lora
DEBUG | 08:31:20 3371 [RadioIf] Packet transmitted : 501ms
INFO  | 08:31:38 3389 [mqtt] Using non-TLS-encrypted session
INFO  | 08:31:38 3389 [mqtt] Attempting to connect directly to MQTT server mqtt.zr1rf.za.net, port: 1883, username: REDACTED, password: REDACTED
ERROR | 08:31:45 3396 [mqtt] Failed to contact MQTT server directly (4/5)...
DEBUG | 08:31:45 3396 [Power] Battery: usbPower=1, isCharging=1, batMv=4415, batPct=100
DEBUG | 08:32:05 3416 [Power] Battery: usbPower=1, isCharging=1, batMv=4441, batPct=100
INFO  | 08:32:15 3426 [mqtt] Using non-TLS-encrypted session
INFO  | 08:32:15 3426 [mqtt] Attempting to connect directly to MQTT server mqtt.zr1rf.za.net, port: 1883, username: meshdev, password: large4cats
ERROR | 08:32:22 3433 [mqtt] Failed to contact MQTT server directly (5/5)...
DEBUG | 08:32:22 3433 [NeighborInfoModule] Our NodeDB contains 0 neighbors
DEBUG | 08:32:22 3433 [NeighborInfoModule] Partially randomized packet id 2929090751
DEBUG | 08:32:22 3433 [NeighborInfoModule] SENDING NEIGHBORINFO PACKET from Node 0xda5b06f0 to Node 0xda5b06f0 (last sent by 0xda5b06f0)
DEBUG | 08:32:22 3433 [NeighborInfoModule] Packet contains 0 neighbors
DEBUG | 08:32:22 3433 [NeighborInfoModule] Update DB node 0xda5b06f0, rx_time=1730363542
DEBUG | 08:32:22 3433 [NeighborInfoModule] handleReceived(LOCAL) (id=0xae9660bf fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363542 priority=10)
DEBUG | 08:32:22 3433 [NeighborInfoModule] No modules interested in portnum=71, src=LOCAL
DEBUG | 08:32:22 3433 [NeighborInfoModule] Add packet record (id=0xae9660bf fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363542 priority=10)
DEBUG | 08:32:22 3433 [NeighborInfoModule] recentPackets size=95
DEBUG | 08:32:22 3433 [NeighborInfoModule] recentPackets size=89 (after clearing expired packets)
DEBUG | 08:32:22 3433 [NeighborInfoModule] Expanding short PSK #1
DEBUG | 08:32:22 3433 [NeighborInfoModule] Using AES128 key!
DEBUG | 08:32:22 3433 [NeighborInfoModule] MQTT onSend - Publishing
DEBUG | 08:32:22 3433 [NeighborInfoModule] encrypted message
INFO  | 08:32:22 3433 [NeighborInfoModule] MQTT not connected, queueing packet
DEBUG | 08:32:22 3433 [NeighborInfoModule] enqueuing for send (id=0xae9660bf fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x8 encrypted rxtime=1730363542 hopStart=7 priority=10)
DEBUG | 08:32:22 3433 [NeighborInfoModule] txGood=276,txRelay=262,rxGood=284,rxBad=3
INFO  | 08:32:22 3433 [NeighborInfoModule] tophone queue status queue is full, discarding oldest
DEBUG | 08:32:22 3433 [NeighborInfoModule] Using channel 0 (hash 0x8)
DEBUG | 08:32:22 3433 [NeighborInfoModule] Expanding short PSK #1
DEBUG | 08:32:22 3433 [NeighborInfoModule] Using AES128 key!
DEBUG | 08:32:22 3433 [NeighborInfoModule] decoded message (id=0xae9660bf fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363542 hopStart=7 priority=10)
WARN  | 08:32:22 3433 [NeighborInfoModule] ToPhone queue is full, dropping packet.
INFO  | 08:32:22 3433 [WifiConnect] Reconnecting to WiFi access point dlink
DEBUG | 08:32:22 3433 [WifiConnect] WiFi-Event 5:
INFO  | 08:32:22 3433 [WifiConnect] Disconnected from WiFi access point
WARN  | 08:32:22 3433 [WifiConnect] WiFi lost connection. Reason: 8
DEBUG | 08:32:27 3438 [Power] Battery: usbPower=1, isCharging=1, batMv=4463, batPct=100
DEBUG | 08:32:27 3438 [RadioIf] Starting low level send (id=0xae9660bf fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x8 encrypted rxtime=1730363542 hopStart=7 priority=10)
DEBUG | 08:32:28 3439 [RadioIf] Drop Tx packet because dest is broadcast no-lora
DEBUG | 08:32:28 3439 [RadioIf] Packet transmitted : 501ms
DEBUG | 08:32:28 3439 WiFi-Event 4:
INFO  | 08:32:28 3439 Connected to access point
DEBUG | 08:32:28 3439 WiFi-Event 7:
INFO  | 08:32:28 3439 Obtained IP address: 192.168.68.113
INFO  | 08:32:28 3439 Using non-TLS-encrypted session
INFO  | 08:32:28 3439 Attempting to connect directly to MQTT server mqtt.zr1rf.za.net, port: 1883, username: REDACTED, password: REDACTED
INFO  | 08:32:28 3439 MQTT connected
INFO  | 08:32:28 3439 Subscribing to msh/ZA/2/e/LongFast/+
INFO  | 08:32:28 3439 Subscribing to msh/ZA/2/e/PKI/+
DEBUG | 08:32:47 3458 [Power] Battery: usbPower=1, isCharging=1, batMv=4469, batPct=100
INFO  | 08:32:52 3463 [mqtt] Received MQTT topic msh/ZA/2/e/LongFast/!433efff4, len=91
DEBUG | 08:32:52 3463 [mqtt] Using channel 0 (hash 0x8)
DEBUG | 08:32:52 3463 [mqtt] Expanding short PSK #1
DEBUG | 08:32:52 3463 [mqtt] Using AES128 key!
DEBUG | 08:32:52 3463 [mqtt] decoded message (id=0x47fd7c67 fr=0x7c to=0xff, WantAck=0, HopLim=6 Ch=0x0 Portnum=3 rxtime=1730363550 rxSNR=-5.25 rxRSSI=-112 via MQTT hopStart=7)
DEBUG | 08:32:52 3463 [Router] Add packet record (id=0x47fd7c67 fr=0x7c to=0xff, WantAck=0, HopLim=6 Ch=0x0 Portnum=3 rxtime=1730363550 rxSNR=-5.25 rxRSSI=-112 via MQTT hopStart=7)
DEBUG | 08:32:52 3463 [Router] handleReceived(REMOTE) (id=0x47fd7c67 fr=0x7c to=0xff, WantAck=0, HopLim=6 Ch=0x0 Portnum=3 rxtime=1730363572 rxSNR=-5.25 rxRSSI=-112 via MQTT hopStart=7)
DEBUG | 08:32:52 3463 [Router] Module 'position' wantsPacket=1
INFO  | 08:32:52 3463 [Router] Received position from=0xe2e1f17c, id=0x47fd7c67, portnum=3, payloadlen=20
DEBUG | 08:32:52 3463 [Router] POSITION node=e2e1f17c l=20 lat=-338579456 lon=185495552 msl=50 hae=0 geo=0 pdop=0 hdop=0 vdop=0 siv=0 fxq=0 fxt=0 pts=0 time=1730363551
DEBUG | 08:32:52 3463 [Router] Ignoring time from mesh because we have a GPS, RTC, or Phone/NTP time source in the past day
INFO  | 08:32:52 3463 [Router] updatePosition REMOTE node=0xe2e1f17c time=1730363551 lat=-338579456 lon=185495552
DEBUG | 08:32:52 3463 [Router] Node status update: 17 online, 27 total
DEBUG | 08:32:52 3463 [Router] Module 'position' considered
DEBUG | 08:32:52 3463 [Router] Module 'routing' wantsPacket=1
INFO  | 08:32:52 3463 [Router] Received routing from=0xe2e1f17c, id=0x47fd7c67, portnum=3, payloadlen=20
DEBUG | 08:32:52 3463 [Router] Routing sniffing (id=0x47fd7c67 fr=0x7c to=0xff, WantAck=0, HopLim=6 Ch=0x0 Portnum=3 rxtime=1730363572 rxSNR=-5.25 rxRSSI=-112 via MQTT hopStart=7)
INFO  | 08:32:52 3463 [Router] Rebroadcasting received floodmsg
DEBUG | 08:32:52 3463 [Router] Expanding short PSK #1
DEBUG | 08:32:52 3463 [Router] Using AES128 key!
DEBUG | 08:32:52 3463 [Router] enqueuing for send (id=0x47fd7c67 fr=0x7c to=0xff, WantAck=0, HopLim=5 Ch=0x8 encrypted rxtime=1730363572 rxSNR=-5.25 rxRSSI=-112 via MQTT hopStart=7 priority
DEBUG | 08:32:52 3463 [Router] txGood=276,txRelay=262,rxGood=284,rxBad=3
DEBUG | 08:32:52 3463 [Router] rx_snr found. hop_limit:5 rx_snr:-5.250000
DEBUG | 08:32:52 3463 [Router] rx_snr found in packet. Setting tx delay:1463
DEBUG | 08:32:52 3463 [Router] Delivering rx packet (id=0x47fd7c67 fr=0x7c to=0xff, WantAck=0, HopLim=6 Ch=0x0 Portnum=3 rxtime=1730363572 rxSNR=-5.25 rxRSSI=-112 via MQTT hopStart=7)
DEBUG | 08:32:52 3463 [Router] Update DB node 0xe2e1f17c, rx_time=1730363572
DEBUG | 08:32:52 3463 [Router] Forwarding to phone (id=0x47fd7c67 fr=0x7c to=0xff, WantAck=0, HopLim=6 Ch=0x0 Portnum=3 rxtime=1730363572 rxSNR=-5.25 rxRSSI=-112 via MQTT hopStart=7)
WARN  | 08:32:52 3463 [Router] ToPhone queue is full, dropping packet.
DEBUG | 08:32:52 3463 [Router] Module 'routing' considered
DEBUG | 08:32:54 3465 [RadioIf] Starting low level send (id=0x47fd7c67 fr=0x7c to=0xff, WantAck=0, HopLim=5 Ch=0x8 encrypted rxtime=1730363572 rxSNR=-5.25 rxRSSI=-112 via MQTT hopStart=7 pri
DEBUG | 08:32:54 3465 [RadioIf] Packet transmitted : 534ms
DEBUG | 08:32:54 3465 [RadioIf] Completed sending (id=0x47fd7c67 fr=0x7c to=0xff, WantAck=0, HopLim=5 Ch=0x8 encrypted rxtime=1730363572 rxSNR=-5.25 rxRSSI=-112 via MQTT hopStart=7 priority=
DEBUG | 08:32:57 3468 [RadioIf] Lora RX (id=0x47fd7c67 fr=0x7c to=0xff, WantAck=0, HopLim=4 Ch=0x8 encrypted rxSNR=-2.5 rxRSSI=-98 via MQTT hopStart=7)
DEBUG | 08:32:57 3468 [RadioIf] Packet received : 534ms
DEBUG | 08:32:57 3468 [Router] Found existing packet record for fr=0xe2e1f17c,to=0xffffffff,id=0x47fd7c67
DEBUG | 08:32:57 3468 [Router] Found existing packet record for fr=0xe2e1f17c,to=0xffffffff,id=0x47fd7c67
DEBUG | 08:32:57 3468 [Router] Add packet record (id=0x47fd7c67 fr=0x7c to=0xff, WantAck=0, HopLim=4 Ch=0x8 encrypted rxSNR=-2.5 rxRSSI=-98 via MQTT hopStart=7)
DEBUG | 08:32:57 3468 [Router] Ignoring dupe incoming msg (id=0x47fd7c67 fr=0x7c to=0xff, WantAck=0, HopLim=4 Ch=0x8 encrypted rxSNR=-2.5 rxRSSI=-98 via MQTT hopStart=7)
DEBUG | 08:32:57 3468 [Router] cancelSending id=0x47fd7c67, removed=0
DEBUG | 08:32:57 3468 [Router] Incoming msg was filtered from 0xe2e1f17c
DEBUG | 08:33:06 3477 [RadioIf] Lora RX (id=0x30e7e0c6 fr=0x70 to=0xff, WantAck=0, HopLim=7 Ch=0x8 encrypted rxSNR=5.75 rxRSSI=-61 hopStart=7)
DEBUG | 08:33:06 3477 [RadioIf] Packet received : 600ms
DEBUG | 08:33:06 3477 [Router] Add packet record (id=0x30e7e0c6 fr=0x70 to=0xff, WantAck=0, HopLim=7 Ch=0x8 encrypted rxSNR=5.75 rxRSSI=-61 hopStart=7)
DEBUG | 08:33:06 3477 [Router] recentPackets size=91
DEBUG | 08:33:06 3477 [Router] recentPackets size=86 (after clearing expired packets)
DEBUG | 08:33:06 3477 [Router] Using channel 0 (hash 0x8)
DEBUG | 08:33:06 3477 [Router] Expanding short PSK #1
DEBUG | 08:33:06 3477 [Router] Using AES128 key!
DEBUG | 08:33:06 3477 [Router] decoded message (id=0x30e7e0c6 fr=0x70 to=0xff, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363586 rxSNR=5.75 rxRSSI=-61 hopStart=7)
DEBUG | 08:33:06 3477 [Router] handleReceived(REMOTE) (id=0x30e7e0c6 fr=0x70 to=0xff, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363586 rxSNR=5.75 rxRSSI=-61 hopStart=7)
DEBUG | 08:33:06 3477 [Router] Module 'neighborinfo' wantsPacket=1
INFO  | 08:33:06 3477 [Router] Received neighborinfo from=0x43561c70, id=0x30e7e0c6, portnum=71, payloadlen=27
DEBUG | 08:33:06 3477 [Router] RECEIVED NEIGHBORINFO PACKET from Node 0x43561c70 to Node 0xda5b06f0 (last sent by 0x43561c70)
DEBUG | 08:33:06 3477 [Router] Packet contains 1 neighbors
DEBUG | 08:33:06 3477 [Router] Neighbor 0: node_id=0x435977d8, snr=6.50
DEBUG | 08:33:06 3477 [Router] Module 'neighborinfo' considered
DEBUG | 08:33:06 3477 [Router] Module 'routing' wantsPacket=1
INFO  | 08:33:06 3477 [Router] Received routing from=0x43561c70, id=0x30e7e0c6, portnum=71, payloadlen=27
DEBUG | 08:33:06 3477 [Router] Routing sniffing (id=0x30e7e0c6 fr=0x70 to=0xff, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363586 rxSNR=5.75 rxRSSI=-61 hopStart=7)
INFO  | 08:33:06 3477 [Router] Rebroadcasting received floodmsg
DEBUG | 08:33:06 3477 [Router] Expanding short PSK #1
DEBUG | 08:33:06 3477 [Router] Using AES128 key!
DEBUG | 08:33:06 3477 [Router] enqueuing for send (id=0x30e7e0c6 fr=0x70 to=0xff, WantAck=0, HopLim=6 Ch=0x8 encrypted rxtime=1730363586 rxSNR=5.75 rxRSSI=-61 hopStart=7 priority=64)
DEBUG | 08:33:06 3477 [Router] txGood=277,txRelay=263,rxGood=286,rxBad=3
DEBUG | 08:33:06 3477 [Router] rx_snr found. hop_limit:6 rx_snr:5.750000
DEBUG | 08:33:06 3477 [Router] rx_snr found in packet. Setting tx delay:1540
DEBUG | 08:33:06 3477 [Router] Delivering rx packet (id=0x30e7e0c6 fr=0x70 to=0xff, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363586 rxSNR=5.75 rxRSSI=-61 hopStart=7)
DEBUG | 08:33:06 3477 [Router] Update DB node 0x43561c70, rx_time=1730363586
DEBUG | 08:33:06 3477 [Router] Forwarding to phone (id=0x30e7e0c6 fr=0x70 to=0xff, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363586 rxSNR=5.75 rxRSSI=-61 hopStart=7)
WARN  | 08:33:06 3477 [Router] ToPhone queue is full, dropping packet.
DEBUG | 08:33:06 3477 [Router] Module 'routing' considered
INFO  | 08:33:06 3477 [Router] MQTT onSend - Not forwarding packet due to DontMqttMeBro flag
INFO  | 08:33:07 3478 [mqtt] Using non-TLS-encrypted session
INFO  | 08:33:07 3478 [mqtt] Attempting to connect directly to MQTT server mqtt.zr1rf.za.net, port: 1883, username: REDACTED, password: REDACTED
ERROR | 08:33:10 3481 [mqtt] Failed to contact MQTT server directly (1/5)...
DEBUG | 08:33:10 3481 [Power] Battery: usbPower=1, isCharging=1, batMv=4428, batPct=100
DEBUG | 08:33:10 3481 [RadioIf] Starting low level send (id=0x30e7e0c6 fr=0x70 to=0xff, WantAck=0, HopLim=6 Ch=0x8 encrypted rxtime=1730363586 rxSNR=5.75 rxRSSI=-61 hopStart=7 priority=64)
DEBUG | 08:33:10 3481 [RadioIf] Packet transmitted : 600ms
DEBUG | 08:33:11 3482 [RadioIf] Completed sending (id=0x30e7e0c6 fr=0x70 to=0xff, WantAck=0, HopLim=6 Ch=0x8 encrypted rxtime=1730363586 rxSNR=5.75 rxRSSI=-61 hopStart=7 priority=64)
DEBUG | 08:33:22 3493 [NeighborInfoModule] Our NodeDB contains 1 neighbors
DEBUG | 08:33:22 3493 [NeighborInfoModule] Node 0: node_id=0x43561c70, snr=5.75
DEBUG | 08:33:22 3493 [NeighborInfoModule] Partially randomized packet id 3393581248
DEBUG | 08:33:22 3493 [NeighborInfoModule] SENDING NEIGHBORINFO PACKET from Node 0xda5b06f0 to Node 0xda5b06f0 (last sent by 0xda5b06f0)
DEBUG | 08:33:22 3493 [NeighborInfoModule] Packet contains 1 neighbors
DEBUG | 08:33:22 3493 [NeighborInfoModule] Neighbor 0: node_id=0x43561c70, snr=5.75
DEBUG | 08:33:22 3493 [NeighborInfoModule] Update DB node 0xda5b06f0, rx_time=1730363602
DEBUG | 08:33:22 3493 [NeighborInfoModule] handleReceived(LOCAL) (id=0xca45f0c0 fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363602 priority=10)
DEBUG | 08:33:22 3493 [NeighborInfoModule] No modules interested in portnum=71, src=LOCAL
DEBUG | 08:33:22 3493 [NeighborInfoModule] Add packet record (id=0xca45f0c0 fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363602 priority=10)
DEBUG | 08:33:22 3493 [NeighborInfoModule] Expanding short PSK #1
DEBUG | 08:33:22 3493 [NeighborInfoModule] Using AES128 key!
DEBUG | 08:33:22 3493 [NeighborInfoModule] MQTT onSend - Publishing
DEBUG | 08:33:22 3493 [NeighborInfoModule] encrypted message
INFO  | 08:33:22 3493 [NeighborInfoModule] MQTT not connected, queueing packet
DEBUG | 08:33:22 3493 [NeighborInfoModule] enqueuing for send (id=0xca45f0c0 fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x8 encrypted rxtime=1730363602 hopStart=7 priority=10)
DEBUG | 08:33:22 3493 [NeighborInfoModule] txGood=278,txRelay=264,rxGood=286,rxBad=3
INFO  | 08:33:22 3493 [NeighborInfoModule] tophone queue status queue is full, discarding oldest
DEBUG | 08:33:22 3493 [NeighborInfoModule] Using channel 0 (hash 0x8)
DEBUG | 08:33:22 3493 [NeighborInfoModule] Expanding short PSK #1
DEBUG | 08:33:22 3493 [NeighborInfoModule] Using AES128 key!
DEBUG | 08:33:22 3493 [NeighborInfoModule] decoded message (id=0xca45f0c0 fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363602 hopStart=7 priority=10)
WARN  | 08:33:22 3493 [NeighborInfoModule] ToPhone queue is full, dropping packet.
DEBUG | 08:33:22 3493 [RadioIf] Starting low level send (id=0xca45f0c0 fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x8 encrypted rxtime=1730363602 hopStart=7 priority=10)
DEBUG | 08:33:22 3493 [RadioIf] Drop Tx packet because dest is broadcast no-lora
DEBUG | 08:33:22 3493 [RadioIf] Packet transmitted : 600ms
DEBUG | 08:33:30 3501 [Power] Battery: usbPower=1, isCharging=1, batMv=4415, batPct=100
INFO  | 08:33:40 3511 [mqtt] Using non-TLS-encrypted session
INFO  | 08:33:40 3511 [mqtt] Attempting to connect directly to MQTT server mqtt.zr1rf.za.net, port: 1883, username: REDACTED, password: REDACTED
ERROR | 08:33:43 3514 [mqtt] Failed to contact MQTT server directly (2/5)...
DEBUG | 08:33:50 3521 [Power] Battery: usbPower=1, isCharging=1, batMv=4447, batPct=100
DEBUG | 08:34:10 3541 [Power] Battery: usbPower=1, isCharging=1, batMv=4457, batPct=100
INFO  | 08:34:13 3544 [mqtt] Using non-TLS-encrypted session
INFO  | 08:34:13 3544 [mqtt] Attempting to connect directly to MQTT server mqtt.zr1rf.za.net, port: 1883, username: meshdev, password: large4cats
ERROR | 08:34:16 3547 [mqtt] Failed to contact MQTT server directly (3/5)...
DEBUG | 08:34:22 3553 [NeighborInfoModule] Our NodeDB contains 1 neighbors
DEBUG | 08:34:22 3553 [NeighborInfoModule] Node 0: node_id=0x43561c70, snr=5.75
DEBUG | 08:34:22 3553 [NeighborInfoModule] Partially randomized packet id 519334081
DEBUG | 08:34:22 3553 [NeighborInfoModule] SENDING NEIGHBORINFO PACKET from Node 0xda5b06f0 to Node 0xda5b06f0 (last sent by 0xda5b06f0)
DEBUG | 08:34:22 3553 [NeighborInfoModule] Packet contains 1 neighbors
DEBUG | 08:34:22 3553 [NeighborInfoModule] Neighbor 0: node_id=0x43561c70, snr=5.75
DEBUG | 08:34:22 3553 [NeighborInfoModule] Update DB node 0xda5b06f0, rx_time=1730363662
DEBUG | 08:34:22 3553 [NeighborInfoModule] handleReceived(LOCAL) (id=0x1ef468c1 fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363662 priority=10)
DEBUG | 08:34:22 3553 [NeighborInfoModule] No modules interested in portnum=71, src=LOCAL
DEBUG | 08:34:22 3553 [NeighborInfoModule] Add packet record (id=0x1ef468c1 fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363662 priority=10)
DEBUG | 08:34:22 3553 [NeighborInfoModule] Expanding short PSK #1
DEBUG | 08:34:22 3553 [NeighborInfoModule] Using AES128 key!
DEBUG | 08:34:22 3553 [NeighborInfoModule] MQTT onSend - Publishing
DEBUG | 08:34:22 3553 [NeighborInfoModule] encrypted message
INFO  | 08:34:22 3553 [NeighborInfoModule] MQTT not connected, queueing packet
DEBUG | 08:34:22 3553 [NeighborInfoModule] enqueuing for send (id=0x1ef468c1 fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x8 encrypted rxtime=1730363662 hopStart=7 priority=10)
DEBUG | 08:34:22 3553 [NeighborInfoModule] txGood=278,txRelay=264,rxGood=286,rxBad=3
INFO  | 08:34:22 3553 [NeighborInfoModule] tophone queue status queue is full, discarding oldest
DEBUG | 08:34:22 3553 [NeighborInfoModule] Using channel 0 (hash 0x8)
DEBUG | 08:34:22 3553 [NeighborInfoModule] Expanding short PSK #1
DEBUG | 08:34:22 3553 [NeighborInfoModule] Using AES128 key!
DEBUG | 08:34:22 3553 [NeighborInfoModule] decoded message (id=0x1ef468c1 fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363662 hopStart=7 priority=10)
WARN  | 08:34:22 3553 [NeighborInfoModule] ToPhone queue is full, dropping packet.
DEBUG | 08:34:22 3553 [RadioIf] Starting low level send (id=0x1ef468c1 fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x8 encrypted rxtime=1730363662 hopStart=7 priority=10)
DEBUG | 08:34:22 3553 [RadioIf] Drop Tx packet because dest is broadcast no-lora
DEBUG | 08:34:22 3553 [RadioIf] Packet transmitted : 600ms
DEBUG | 08:34:30 3561 [Power] Battery: usbPower=1, isCharging=1, batMv=4434, batPct=100
INFO  | 08:34:46 3577 [mqtt] Using non-TLS-encrypted session
INFO  | 08:34:46 3577 [mqtt] Attempting to connect directly to MQTT server mqtt.zr1rf.za.net, port: 1883, username: meshdev, password: large4cats
ERROR | 08:34:49 3580 [mqtt] Failed to contact MQTT server directly (4/5)...
DEBUG | 08:34:50 3581 [Power] Battery: usbPower=1, isCharging=1, batMv=4441, batPct=100
DEBUG | 08:35:10 3601 [Power] Battery: usbPower=1, isCharging=1, batMv=4460, batPct=100
INFO  | 08:35:19 3610 [mqtt] Using non-TLS-encrypted session
INFO  | 08:35:19 3610 [mqtt] Attempting to connect directly to MQTT server mqtt.zr1rf.za.net, port: 1883, username: meshdev, password: large4cats
ERROR | 08:35:22 3613 [mqtt] Failed to contact MQTT server directly (5/5)...
INFO  | 08:35:22 3613 [WifiConnect] Reconnecting to WiFi access point dlink
DEBUG | 08:35:22 3613 [WifiConnect] WiFi-Event 5:
INFO  | 08:35:22 3613 [WifiConnect] Disconnected from WiFi access point
WARN  | 08:35:22 3613 [WifiConnect] WiFi lost connection. Reason: 8
DEBUG | 08:35:27 3618 [NeighborInfoModule] Removing neighbor with node ID 0x43561c70
DEBUG | 08:35:28 3619 [NeighborInfoModule] Our NodeDB contains 0 neighbors
DEBUG | 08:35:28 3619 [NeighborInfoModule] Partially randomized packet id 3588265154
DEBUG | 08:35:28 3619 [NeighborInfoModule] SENDING NEIGHBORINFO PACKET from Node 0xda5b06f0 to Node 0xda5b06f0 (last sent by 0xda5b06f0)
DEBUG | 08:35:28 3619 [NeighborInfoModule] Packet contains 0 neighbors
DEBUG | 08:35:28 3619 [NeighborInfoModule] Update DB node 0xda5b06f0, rx_time=1730363728
DEBUG | 08:35:28 3619 [NeighborInfoModule] handleReceived(LOCAL) (id=0xd5e094c2 fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363728 priority=10)
DEBUG | 08:35:28 3619 [NeighborInfoModule] No modules interested in portnum=71, src=LOCAL
DEBUG | 08:35:28 3619 [NeighborInfoModule] Add packet record (id=0xd5e094c2 fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x0 Portnum=71 rxtime=1730363728 priority=10)
DEBUG | 08:35:28 3619 [NeighborInfoModule] WiFi-Event 4:
INFO  | 08:35:28 3619 [NeighborInfoModule] Connected to access point
DEBUG | 08:35:28 3619 [NeighborInfoModule] WiFi-Event 7:
INFO  | 08:35:28 3619 [NeighborInfoModule] Obtained IP address: 192.168.68.113
INFO  | 08:35:28 3619 [NeighborInfoModule] Using non-TLS-encrypted session
INFO  | 08:35:28 3619 [NeighborInfoModule] Attempting to connect directly to MQTT server mqtt.zr1rf.za.net, port: 1883, username: REDACTED, password: REDACTED
DEBUG | 08:35:28 3619 [NeighborInfoModule] Expanding short PSK #1
DEBUG | 08:35:28 3619 [NeighborInfoModule] Using AES128 key!
DEBUG | 08:35:28 3619 [NeighborInfoModule] MQTT onSend - Publishing
DEBUG | 08:35:28 3619 [NeighborInfoModule] encrypted message
INFO  | 08:35:28 3619 [NeighborInfoModule] MQTT not connected, queueing packet
DEBUG | 08:35:28 3619 [NeighborInfoModule] enqueuing for send (id=0xd5e094c2 fr=0xf0 to=0x01, WantAck=0, HopLim=7 Ch=0x8 encrypted rxtime=1730363728 hopStart=7 priority=10)
DEBUG | 08:35:28 3619 [NeighborInfoModule] txGood=278,txRelay=264,rxGood=286,rxBad=3
INFO  | 08:35:28 3619 [NeighborInfoModule] tophone queue status queue is full, discarding oldest
DEBUG | 08:35:28 3619 [NeighborInfoModule] Using channel 0 (hash 0x8)
INFO  | 08:35:28 3619 [NeighborInfoModule] MQTT connected
INFO  | 08:35:28 3619 [NeighborInfoModule] Subscribing to msh/ZA/2/e/LongFast/+
DEBUG | 08:35:28 3619 [NeighborInfoModule] Expanding short PSK #1
INFO  | 08:35:28 3619 [NeighborInfoModule] Subscribing to msh/ZA/2/e/PKI/+
DEBUG | 08:35:28 3619 [NeighborInfoModule] Using AES128 key!

thebentern · 2024-10-31T10:16:17Z

thebentern
Oct 31, 2024
Maintainer

According the library's docs, the default keepalive is 15 seconds which seems reasonable https://pubsubclient.knolleary.net/api

1 reply

Dude5101 Oct 31, 2024
Author

Hello, thanks for the reply

Yes I see the 15 second keepalive request

I have since tried with Proxy to client enabled (Wi-Fi disabled) and the connection is stable so not a core MQTT issue.
Leaning towards a Wi-Fi issue, it seems the node stops communicating over Wi-Fi and then can never recover, even with a Wi-Fi reconnect.

Only solution is a reboot with fresh Wi-Fi connect

Dude5101 · 2024-10-31T16:47:59Z

Dude5101
Oct 31, 2024
Author

I believe I found the problem

Wi-Fi network it was connected to is /22 network (netmask 255.255.252.0)

I tried with static IP and manual config of netmask, problem still existed.

I changed the network to /24 (255.255.255.0) and the problem went away

1 reply

Dude5101 Oct 31, 2024
Author

spoke to soon, connection stays active for longer but still get the same problem

fifieldt · 2024-11-01T00:37:40Z

fifieldt
Nov 1, 2024
Collaborator

Just thinking - we added some code recently that modified behavior for private networks. I don't think it would cause these issues, but just so we can double check - could you provide the IP address of the MQTT server and the client?

3 replies

Dude5101 Nov 1, 2024
Author

Hello

Broker - 192.168.68.82
Client - 192.168.68.113

I have turned off "encryption enabled" in the MQTT settings in the Android app. MQTT and Wi-Fi have now been stable for 10 hours.
I don't understand what this option does but it breaks my connection if I have it turned on.

fifieldt Nov 1, 2024
Collaborator

OK, that may indeed be related to the recent changes. @jp-bennett - our RFC1918 range work made a bad.

Dude5101 Nov 11, 2024
Author

@fifieldt @jp-bennett has this issue been resolved yet in any of the firmware releases?

I would like to enable encrypted messages over MQTT, currently all my direct messages are sent over the MQTT. Only solution is to have another node for comms so that direct messages can't be read by MQTT node

CTassisF · 2024-11-29T18:40:36Z

CTassisF
Nov 29, 2024

Meshtastic Firmware 2.5.15.79da236 Alpha was released with fixes for Wi-Fi connectivity (#5387).

1 reply

Dude5101 Nov 29, 2024
Author

I appreciate the update, thanks

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

[Bug]: MQTT disconnects, node resets Wi-Fi and becomes unreachable until rebooted #5203

{{title}}

Replies: 4 comments 6 replies

{{title}}

{{title}}

{{title}}

{{editor}}'s edit

{{editor}}'s edit

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

Select a reply

[Bug]: MQTT disconnects, node resets Wi-Fi and becomes unreachable until rebooted #5203

Dude5101 Oct 31, 2024

Category

Hardware

Firmware Version

Description

Relevant log output

Replies: 4 comments · 6 replies

thebentern Oct 31, 2024 Maintainer

Dude5101 Oct 31, 2024 Author

Dude5101 Oct 31, 2024 Author

Dude5101 Oct 31, 2024 Author

fifieldt Nov 1, 2024 Collaborator

Dude5101 Nov 1, 2024 Author

fifieldt Nov 1, 2024 Collaborator

Dude5101 Nov 11, 2024 Author

CTassisF Nov 29, 2024

Dude5101 Nov 29, 2024 Author

Dude5101
Oct 31, 2024

Replies: 4 comments 6 replies

thebentern
Oct 31, 2024
Maintainer

Dude5101 Oct 31, 2024
Author

Dude5101
Oct 31, 2024
Author

Dude5101 Oct 31, 2024
Author

fifieldt
Nov 1, 2024
Collaborator

Dude5101 Nov 1, 2024
Author

fifieldt Nov 1, 2024
Collaborator

Dude5101 Nov 11, 2024
Author

CTassisF
Nov 29, 2024

Dude5101 Nov 29, 2024
Author