error processing packet #119

Closed
opened 2026-10-05 01:24:22 +02:00 by adam · 7 comments
Owner

Looks like user just wrote message to the bot:

2025-11-22 00:07:39,278 | DEBUG | System: Packet HopDebugger: hop_away:0 hop_limit:5 hop_start:5 calculated_hop_count:0 final_hop_value:Direct Relay:40 via_mqtt:False transport_mechanism:TRANSPORT_LORA Hostname:None
2025-11-22 00:07:39,280 | WARNING | Device:1 Ignoring DM: Привет! From: Meshtastic svv01
2025-11-22 00:07:39,280 | CRITICAL | System: Error processing packet: 'nodeID' Device:1
2025-11-22 00:07:39,282 | DEBUG | System: Error Packet = {'from': 2896880704, 'to': 2997298808, 'decoded': {'portnum': 'TEXT_MESSAGE_APP', 'payload': b'\xd0\x9f\xd1\x80\xd0\xb8\xd0\xb2\xd0\xb5\xd1\x82!', 'bitfield': 0, 'text': 'Привет!'}, 'id': 4055215677, 'rxTime': 1763759259, 'rxSnr': -15.5, 'hopLimit': 5, 'wantAck': True, 'rxRssi': -106, 'hopStart': 5, 'publicKey': 'b5fsRucFDCoz8lZVRKvjB9aTOoyFfVAFzCCHkXUW20o=', 'pkiEncrypted': True, 'relayNode': 64, 'transportMechanism': 'TRANSPORT_LORA', 'raw': from: 2896880704
to: 2997298808
decoded {
portnum: TEXT_MESSAGE_APP
payload: "\320\237\321\200\320\270\320\262\320\265\321\202!"
bitfield: 0
}
id: 4055215677
rx_time: 1763759259
rx_snr: -15.5
hop_limit: 5
want_ack: true
rx_rssi: -106
hop_start: 5
public_key: "o\227\354F\347\005\014*3\362VUD\253\343\007\326\223:\214\205}P\005\314 \207\221u\026\333J"
pki_encrypted: true
relay_node: 64
transport_mechanism: TRANSPORT_LORA
, 'fromId': '!acaae440', 'toId': '!b2a72678'}

Looks like user just wrote message to the bot: 2025-11-22 00:07:39,278 | DEBUG | System: Packet HopDebugger: hop_away:0 hop_limit:5 hop_start:5 calculated_hop_count:0 final_hop_value:Direct Relay:40 via_mqtt:False transport_mechanism:TRANSPORT_LORA Hostname:None 2025-11-22 00:07:39,280 | WARNING | Device:1 Ignoring DM: Привет! From: Meshtastic svv01 2025-11-22 00:07:39,280 | CRITICAL | System: Error processing packet: 'nodeID' Device:1 2025-11-22 00:07:39,282 | DEBUG | System: Error Packet = {'from': 2896880704, 'to': 2997298808, 'decoded': {'portnum': 'TEXT_MESSAGE_APP', 'payload': b'\xd0\x9f\xd1\x80\xd0\xb8\xd0\xb2\xd0\xb5\xd1\x82!', 'bitfield': 0, 'text': 'Привет!'}, 'id': 4055215677, 'rxTime': 1763759259, 'rxSnr': -15.5, 'hopLimit': 5, 'wantAck': True, 'rxRssi': -106, 'hopStart': 5, 'publicKey': 'b5fsRucFDCoz8lZVRKvjB9aTOoyFfVAFzCCHkXUW20o=', 'pkiEncrypted': True, 'relayNode': 64, 'transportMechanism': 'TRANSPORT_LORA', 'raw': from: 2896880704 to: 2997298808 decoded { portnum: TEXT_MESSAGE_APP payload: "\320\237\321\200\320\270\320\262\320\265\321\202!" bitfield: 0 } id: 4055215677 rx_time: 1763759259 rx_snr: -15.5 hop_limit: 5 want_ack: true rx_rssi: -106 hop_start: 5 public_key: "o\227\354F\347\005\014*3\362VUD\253\343\007\326\223:\214\205}P\005\314 \207\221u\026\333J" pki_encrypted: true relay_node: 64 transport_mechanism: TRANSPORT_LORA , 'fromId': '!acaae440', 'toId': '!b2a72678'} <!-- github-sync-id:3653092763 -->
adam closed this issue 2026-10-05 01:24:22 +02:00
Author
Owner

Curious..

image

Curious.. ![image](https://github.com/user-attachments/assets/aa930a62-fa30-46f6-86e9-b7eae278bbc3) <!-- github-comment-id:3565162430 -->
Author
Owner

That's kinda weird. Any ideas of how to get it?)

Somehow you can't replicate the issue, what is your setup?

That's kinda weird. Any ideas of how to get it?) Somehow you can't replicate the issue, what is your setup? <!-- github-comment-id:3566757240 -->
Author
Owner

As i can atleast reproduce reconnect issues it quite regularly, is there more debug can be enabled to findout of what is wrong?

As i can atleast reproduce reconnect issues it quite regularly, is there more debug can be enabled to findout of what is wrong? <!-- github-comment-id:3569812712 -->
Author
Owner

What client did you use? I'm not sure why there are issues. Do you have a mostly default install? Is it repeating?

What client did you use? I'm not sure why there are issues. Do you have a mostly default install? Is it repeating? <!-- github-comment-id:3573187262 -->
Author
Owner

Disabled readnews and qrz, also disabled autoreconnect via dont_retry_disconnect

Something has changed but stiil weird:

2025-11-25 15:15:57,005 | INFO | System: 🚨 Admin packet detected from Device: 1 Channel: 0 NodeID:2191488881 ShortName:Z
ed3
2025-11-25 15:21:17,649 | ERROR | System: communicating with interface1, trying to reconnect: Timed out waiting for an ack
nowledgment
2025-11-25 15:21:37,652 | CRITICAL | System: dont_retry_disconnect is set, not retrying interface1
2025-11-25 15:21:37,653 | DEBUG | System: Closing Autoresponder
2025-11-25 15:21:37,657 | DEBUG | System: Closing Interface1
2025-11-25 15:22:02,844 | WARNING | System: Abrupt Disconnection of Interface detected, attempting reconnect...
Unexpected error in deferred execution <class 'OSError'>
2025-11-25 15:22:02,846 | DEBUG | System: Saving data/bbsdb.pkl
2025-11-25 15:22:02,852 | DEBUG | System: Saving Updated BBS Direct Messages data/bbsdm.pkl
2025-11-25 15:22:02,855 | DEBUG | System: BBS Messages Saved
2025-11-25 15:22:02,858 | DEBUG | System: Mesh Leaderboard saved to leaderboard.pkl
2025-11-25 15:22:02,861 | DEBUG | System: Exiting
2025-11-25 15:22:02,865 | DEBUG | System: Cleaning up async tasks

Why it says about reconnect?

Disabled readnews and qrz, also disabled autoreconnect via dont_retry_disconnect Something has changed but stiil weird: 2025-11-25 15:15:57,005 | INFO | System: 🚨 Admin packet detected from Device: 1 Channel: 0 NodeID:2191488881 ShortName:Z ed3 2025-11-25 15:21:17,649 | ERROR | System: communicating with interface1, trying to reconnect: Timed out waiting for an ack nowledgment 2025-11-25 15:21:37,652 | CRITICAL | System: dont_retry_disconnect is set, not retrying interface1 2025-11-25 15:21:37,653 | DEBUG | System: Closing Autoresponder 2025-11-25 15:21:37,657 | DEBUG | System: Closing Interface1 2025-11-25 15:22:02,844 | WARNING | System: Abrupt Disconnection of Interface detected, attempting reconnect... Unexpected error in deferred execution <class 'OSError'> 2025-11-25 15:22:02,846 | DEBUG | System: Saving data/bbsdb.pkl 2025-11-25 15:22:02,852 | DEBUG | System: Saving Updated BBS Direct Messages data/bbsdm.pkl 2025-11-25 15:22:02,855 | DEBUG | System: BBS Messages Saved 2025-11-25 15:22:02,858 | DEBUG | System: Mesh Leaderboard saved to leaderboard.pkl 2025-11-25 15:22:02,861 | DEBUG | System: Exiting 2025-11-25 15:22:02,865 | DEBUG | System: Cleaning up async tasks Why it says about reconnect? <!-- github-comment-id:3575408625 -->
Author
Owner

Or even this:
2025-11-25 15:24:16,951 | INFO | System: 🚨 Admin packet detected from Device: 1 Channel: 0 NodeID:2191488881 ShortName:Z
ed3
2025-11-25 15:29:30,436 | ERROR | System: communicating with interface1, trying to reconnect: Timed out waiting for an ack
nowledgment
2025-11-25 15:29:50,439 | CRITICAL | System: dont_retry_disconnect is set, not retrying interface1
2025-11-25 15:29:50,440 | DEBUG | System: Closing Autoresponder
2025-11-25 15:29:50,441 | DEBUG | System: Closing Interface1
2025-11-25 15:30:24,611 | WARNING | System: Abrupt Disconnection of Interface detected, attempting reconnect...
Unexpected error in deferred execution <class 'BrokenPipeError'>
2025-11-25 15:30:24,616 | DEBUG | System: Saving data/bbsdb.pkl
2025-11-25 15:30:24,619 | DEBUG | System: Saving Updated BBS Direct Messages data/bbsdm.pkl
2025-11-25 15:30:24,623 | DEBUG | System: BBS Messages Saved
2025-11-25 15:30:24,626 | DEBUG | System: Mesh Leaderboard saved to leaderboard.pkl
2025-11-25 15:30:24,628 | DEBUG | System: Exiting
2025-11-25 15:30:24,633 | DEBUG | System: Cleaning up async tasks

Or even this: 2025-11-25 15:24:16,951 | INFO | System: 🚨 Admin packet detected from Device: 1 Channel: 0 NodeID:2191488881 ShortName:Z ed3 2025-11-25 15:29:30,436 | ERROR | System: communicating with interface1, trying to reconnect: Timed out waiting for an ack nowledgment 2025-11-25 15:29:50,439 | CRITICAL | System: dont_retry_disconnect is set, not retrying interface1 2025-11-25 15:29:50,440 | DEBUG | System: Closing Autoresponder 2025-11-25 15:29:50,441 | DEBUG | System: Closing Interface1 2025-11-25 15:30:24,611 | WARNING | System: Abrupt Disconnection of Interface detected, attempting reconnect... Unexpected error in deferred execution <class 'BrokenPipeError'> 2025-11-25 15:30:24,616 | DEBUG | System: Saving data/bbsdb.pkl 2025-11-25 15:30:24,619 | DEBUG | System: Saving Updated BBS Direct Messages data/bbsdm.pkl 2025-11-25 15:30:24,623 | DEBUG | System: BBS Messages Saved 2025-11-25 15:30:24,626 | DEBUG | System: Mesh Leaderboard saved to leaderboard.pkl 2025-11-25 15:30:24,628 | DEBUG | System: Exiting 2025-11-25 15:30:24,633 | DEBUG | System: Cleaning up async tasks <!-- github-comment-id:3575426055 -->
Author
Owner

Second log i have enabled qrz and readnews, node was disabled wifi and rebooted, and after a few mins enabled and connected.

Second log i have enabled qrz and readnews, node was disabled wifi and rebooted, and after a few mins enabled and connected. <!-- github-comment-id:3575429574 -->
Sign in to join this conversation.
No labels
1 Participants
Notifications
Due Date
No due date set.
Dependencies

No dependencies set.

Reference: adam/meshing-around#119