search

craigerl / aprsd

Amateur radio APRS daemon which listens for messages and responds. By KM6LYW.
Apache License 2.0
120 stars 19 forks source link

Email plugin infinite re-send #142

Open alexvotofuture opened 6 months ago

alexvotofuture commented 6 months ago

When using the email plugin, as soon as there's an email available to be read from my inbox via IMAP, the APRSD server keeps re-sending the same email message over APRS-IS multiple times. (See photo below - the failed "Yo!" message is a separate issue I'm having with reaching my SMTP server)

I started getting errors like "Failed to UnPickle /home/.../packettrack.p" and "'MessagePacket' object has no attribute 'msgNo'. I noticed there was no packettrack.p file auto-created in my .config/aprsd file. When I tried creating an empty packettrack.p file, it started populating with packet information after the first re-send, but I got the same errors as before and the re-send issue persisted.

With or without the packettrack.p file, I receive an error before the first re-send only, saying that in the email.py file there's a "FutureWarning: Possible nested set at position [3/9] f= re.search("

Any idea whether I should manually create the packettrack.p file again, how it should be set up (is there an example somewhere?), or whether there's some issue with that f=re.search command that's causing received emails messages to endlessly re-send?

Running APRSD version 3.2.1

aprsd resend

hemna commented 6 months ago

can you paste the exception/log with the MessagePacket has no msgNo error?

hemna commented 6 months ago

You can simply delete the packettrack.p file. It's just for saving some state between aprsd starts and stops.

alexvotofuture commented 6 months ago

can you paste the exception/log with the MessagePacket has no msgNo error?

@hemna This is what is logged when there is an empty packettrack.p file available: [Removed Image]

With or without the packettrack.p file the server immediately starts re-sending the message in the inbox when started, even though it's sent it many times before.

Side note: Is there a quick way to disable auto-sending/re-sending email, so that it just waits for the command to be sent via APRS to deliver new messages?

Thanks again for being so responsive on here!

alexvotofuture commented 6 months ago

Another interesting thing: When I deleted that message from my inbox, the next time I started up the server I started receiving a flood of ack messages from APRS.fi for all the previous re-send attempts.

hemna commented 6 months ago

ok I'm able to reproduce the ERROR Failed to UnPickle /Users/I530566/.config/aprsd//packettrack.p by simply removing all the contents of the file, but leaving it in place.

One thing that you can do is the next time you start aprsd server pass the -f -f will flush all the old data files that it creates and it will start clean. It won't delete your config though.

When you restart aprsd, does it still want to keep sending the same email?

alexvotofuture commented 6 months ago

Nice, glad you were able to reproduce the error.

I passed the -f flag and restarted the server. Same issue as before. Getting that nesting error, and then a bunch of repeat tx's.

hemna commented 6 months ago

I cant really read your log, since it's an image. when I click on it, i get a 404 error.

alexvotofuture commented 6 months ago

Created the log file and enabled logging. Here's another round in text: [12/18/2023 06:47:52 PM] [MainThread ] [WARNI] Version 3.2.1 of aprsd is outdated. Version 3.2.2 was released Wednesday November 22, 2023. - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/cmds/server.py:42] [12/18/2023 06:47:52 PM] [MainThread ] [INFO ] APRSD Started version: 3.2.1 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/cmds/server.py:45] [12/18/2023 06:47:52 PM] [MainThread ] [INFO ] Loading Plugin Manager and registering plugins - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/cmds/server.py:55] [12/18/2023 06:47:52 PM] [MainThread ] [INFO ] Loading APRSD Plugins - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/plugin.py:454] [12/18/2023 06:47:52 PM] [MainThread ] [INFO ] Email shortcuts {REDACTED} - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/plugins/email.py:96] [12/18/2023 06:47:52 PM] [MainThread ] [INFO ] Registering Regex plugin 'aprsd.plugins.email.EmailPlugin'(3.2.1) -- ^-. - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/plugin.py:421] [12/18/2023 06:47:52 PM] [MainThread ] [WARNI] Plugin FortunePlugin is disabled - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/plugin.py:430] [12/18/2023 06:47:52 PM] [MainThread ] [INFO ] Registering Regex plugin 'aprsd.plugins.location.LocationPlugin'(3.2.1) -- ^([l]|[l]\s|location) - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/plugin.py:421] [12/18/2023 06:47:52 PM] [MainThread ] [INFO ] Registering Regex plugin 'aprsd.plugins.ping.PingPlugin'(3.2.1) -- ^([p]|[p]\s|ping) - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/plugin.py:421] [12/18/2023 06:47:52 PM] [MainThread ] [INFO ] Registering Regex plugin 'aprsd.plugins.query.QueryPlugin'(3.2.1) -- ^!. - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/plugin.py:421] [12/18/2023 06:47:52 PM] [MainThread ] [INFO ] Registering Regex plugin 'aprsd.plugins.time.TimePlugin'(3.2.1) -- ^([t]|[t]\s|time) - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/plugin.py:421] [12/18/2023 06:47:52 PM] [MainThread ] [ERROR] Config.owm_weather_plugin.apiKey is not set. Disabling - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/plugins/weather.py:198] [12/18/2023 06:47:52 PM] [MainThread ] [WARNI] Plugin OWMWeatherPlugin is disabled - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/plugin.py:430] [12/18/2023 06:47:52 PM] [MainThread ] [INFO ] Registering Regex plugin 'aprsd.plugins.version.VersionPlugin'(3.2.1) -- ^([v]|[v]\s|version) - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/plugin.py:421] [12/18/2023 06:47:52 PM] [MainThread ] [WARNI] Plugin NotifySeenPlugin is disabled - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/plugin.py:418] [12/18/2023 06:47:52 PM] [MainThread ] [INFO ] Completed Plugin Loading. - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/plugin.py:470] [12/18/2023 06:47:52 PM] [MainThread ] [INFO ] Message Plugins enabled and running: - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/cmds/server.py:63] [12/18/2023 06:47:52 PM] [MainThread ] [INFO ] <aprsd.plugins.time.TimePlugin object at 0x72acd448> - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/cmds/server.py:65] [12/18/2023 06:47:52 PM] [MainThread ] [INFO ] <aprsd.plugins.version.VersionPlugin object at 0x7270f8c8> - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/cmds/server.py:65] [12/18/2023 06:47:52 PM] [MainThread ] [INFO ] <aprsd.plugins.ping.PingPlugin object at 0x72b52af0> - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/cmds/server.py:65] [12/18/2023 06:47:52 PM] [MainThread ] [INFO ] <aprsd.plugin.HelpPlugin object at 0x7270f118> - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/cmds/server.py:65] [12/18/2023 06:47:52 PM] [MainThread ] [INFO ] <aprsd.plugins.location.LocationPlugin object at 0x72b52940> - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/cmds/server.py:65] [12/18/2023 06:47:52 PM] [MainThread ] [INFO ] <aprsd.plugins.email.EmailPlugin object at 0x72bff160> - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/cmds/server.py:65] [12/18/2023 06:47:52 PM] [MainThread ] [INFO ] <aprsd.plugins.query.QueryPlugin object at 0x72acd3a0> - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/cmds/server.py:65] [12/18/2023 06:47:52 PM] [MainThread ] [INFO ] Watchlist Plugins enabled and running: - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/cmds/server.py:66] [12/18/2023 06:47:52 PM] [KeepAlive ] [INFO ] KN6UYO-10 - Uptime 00:00:00 RX:0 TX:0 Tracker:0 Msgs TX:0 RX:0 Last:00:00:00 Email: N/A - RAM Current:4KB Peak:4KB Threads:2 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/keep_alive.py:66] [12/18/2023 06:47:52 PM] [RX_MSG ] [INFO ] Creating APRS client - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/client.py:53] [12/18/2023 06:47:52 PM] [KeepAlive ] [INFO ] APRSDEmailThread:True:0:00:00.577923,KeepAliveThread:True:0:00:00.080985,APRSDPluginRXThread:True:0:00:00.072289,APRSDPluginProcessPacketThread:True:0:00:00.070004,APRSDRPCThread:True:0:00:00.023173 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/keep_alive.py:80] [12/18/2023 06:47:52 PM] [RX_MSG ] [INFO ] Creating aprslib client - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/client.py:152] [12/18/2023 06:47:52 PM] [RX_MSG ] [INFO ] Attempting connection to noam.aprs2.net:14580 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprslib/inet.py:226] [12/18/2023 06:47:53 PM] [RX_MSG ] [INFO ] Connected to ('2001:19f0:b001:999:5400:4ff:feb0:e9a7', 14580, 0, 0) - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprslib/inet.py:233] [12/18/2023 06:47:53 PM] [RX_MSG ] [INFO ] Login successful - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/clients/aprsis.py:142] [12/18/2023 06:47:53 PM] [RX_MSG ] [INFO ] Connected to T2CAEAST - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/clients/aprsis.py:144] [12/18/2023 06:48:52 PM] [KeepAlive ] [INFO ] KN6UYO-10 - Uptime 00:01:00 RX:0 TX:0 Tracker:0 Msgs TX:0 RX:0 Last:00:00:19 Email: 00:00:00 - RAM Current:197KB Peak:198KB Threads:6 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/keep_alive.py:66] [12/18/2023 06:48:53 PM] [KeepAlive ] [INFO ] APRSDEmailThread:True:0:00:05.812491,KeepAliveThread:True:0:00:00.059946,APRSDPluginRXThread:True:0:00:00.897058,APRSDPluginProcessPacketThread:True:0:00:00.306541,APRSDRPCThread:True:0:01:00.319254,LogMonitorThread:True:0:00:00.263709 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/keep_alive.py:80] [12/18/2023 06:49:53 PM] [KeepAlive ] [INFO ] KN6UYO-10 - Uptime 00:02:00 RX:0 TX:0 Tracker:0 Msgs TX:0 RX:0 Last:00:00:00 Email: 00:00:04 - RAM Current:211KB Peak:224KB Threads:6 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/keep_alive.py:66] [12/18/2023 06:49:53 PM] [KeepAlive ] [INFO ] APRSDEmailThread:True:0:00:04.562637,KeepAliveThread:True:0:00:00.064282,APRSDPluginRXThread:True:0:00:00.183353,APRSDPluginProcessPacketThread:True:0:00:00.475522,APRSDRPCThread:True:0:02:00.510051,LogMonitorThread:True:0:00:00.444746 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/keep_alive.py:80] [12/18/2023 06:50:53 PM] [KeepAlive ] [INFO ] KN6UYO-10 - Uptime 00:03:00 RX:0 TX:0 Tracker:0 Msgs TX:0 RX:0 Last:00:00:00 Email: 00:00:03 - RAM Current:224KB Peak:236KB Threads:6 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/keep_alive.py:66] [12/18/2023 06:50:53 PM] [KeepAlive ] [INFO ] APRSDEmailThread:True:0:00:03.586226,KeepAliveThread:True:0:00:00.058799,APRSDPluginRXThread:True:0:00:01.150508,APRSDPluginProcessPacketThread:True:0:00:00.632156,APRSDRPCThread:True:0:03:00.688079,LogMonitorThread:True:0:00:00.613267 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/keep_alive.py:80] [12/18/2023 06:51:53 PM] [KeepAlive ] [INFO ] KN6UYO-10 - Uptime 00:04:00 RX:0 TX:0 Tracker:0 Msgs TX:0 RX:0 Last:00:00:00 Email: 00:00:03 - RAM Current:222KB Peak:246KB Threads:6 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/keep_alive.py:66] [12/18/2023 06:51:53 PM] [KeepAlive ] [INFO ] APRSDEmailThread:True:0:00:03.721923,KeepAliveThread:True:0:00:00.061678,APRSDPluginRXThread:True:0:00:01.549687,APRSDPluginProcessPacketThread:True:0:00:00.811310,APRSDRPCThread:True:0:04:00.889093,LogMonitorThread:True:0:00:00.804337 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/keep_alive.py:80] [12/18/2023 06:52:26 PM] [TXPKT-2-KN6UY ] [INFO ]

TX____(MessagePacket TX:1 of 3) Raw : KN6UYO-10>APZ100::KN6UYO-7 :-me Another test, this time with logs.{2 To : KN6UYO-7 From : KN6UYO-10 Message : -me Another test, this time with logs. Msg # : 2 TX____(MessagePacket) - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/packets/core.py:239] [12/18/2023 06:52:29 PM] [RX_MSG ] [INFO ]

RX____(AckPacket) Raw : KN6UYO-7>APFII0,qAC,APRSFI::KN6UYO-10:ack2 To : KN6UYO-10 From : KN6UYO-7 Path : qAC=>APRSFI VIA : APRSFI Msg # : 2 RX____(AckPacket) - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/packets/core.py:239] [12/18/2023 06:52:29 PM] [ProcessPKT ] [INFO ] Got ack for message 2 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/rx.py:137] [12/18/2023 06:52:29 PM] [TXPKT-2-KN6UY ] [INFO ] MessagePacket(2) Message Send Complete via Ack. - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/tx.py:107] [12/18/2023 06:52:53 PM] [KeepAlive ] [INFO ] KN6UYO-10 - Uptime 00:05:01 RX:0 TX:1 Tracker:0 Msgs TX:1 RX:0 Last:00:00:00 Email: 00:00:01 - RAM Current:537KB Peak:623KB Threads:6 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/keep_alive.py:66] [12/18/2023 06:52:53 PM] [KeepAlive ] [INFO ] APRSDEmailThread:True:0:00:01.676191,KeepAliveThread:True:0:00:00.050171,APRSDPluginRXThread:True:0:00:01.718141,APRSDPluginProcessPacketThread:True:0:00:00.238786,APRSDRPCThread:True:0:05:01.065254,LogMonitorThread:True:0:00:00.970212 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/keep_alive.py:80] [12/18/2023 06:53:28 PM] [TXPKT-3-KN6UY ] [INFO ]

TX____(MessagePacket TX:1 of 3) Raw : KN6UYO-10>APZ100::KN6UYO-7 :-me Another test, this time with logs.{3 To : KN6UYO-7 From : KN6UYO-10 Message : -me Another test, this time with logs. Msg # : 3 TX____(MessagePacket) - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/packets/core.py:239] [12/18/2023 06:53:30 PM] [RX_MSG ] [INFO ]

RX____(AckPacket) Raw : KN6UYO-7>APFII0,qAC,APRSFI::KN6UYO-10:ack3 To : KN6UYO-10 From : KN6UYO-7 Path : qAC=>APRSFI VIA : APRSFI Msg # : 3 RX____(AckPacket) - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/packets/core.py:239] [12/18/2023 06:53:30 PM] [ProcessPKT ] [INFO ] Got ack for message 3 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/rx.py:137] [12/18/2023 06:53:31 PM] [TXPKT-3-KN6UY ] [INFO ] MessagePacket(3) Message Send Complete via Ack. - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/tx.py:107] [12/18/2023 06:53:53 PM] [KeepAlive ] [INFO ] KN6UYO-10 - Uptime 00:06:01 RX:0 TX:2 Tracker:0 Msgs TX:2 RX:0 Last:00:00:00 Email: 00:00:04 - RAM Current:559KB Peak:692KB Threads:6 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/keep_alive.py:66] [12/18/2023 06:53:53 PM] [KeepAlive ] [INFO ] APRSDEmailThread:True:0:00:04.769867,KeepAliveThread:True:0:00:00.031611,APRSDPluginRXThread:True:0:00:01.022019,APRSDPluginProcessPacketThread:True:0:00:00.996553,APRSDRPCThread:True:0:06:01.233418,LogMonitorThread:True:0:00:01.116965 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/keep_alive.py:80] [12/18/2023 06:54:30 PM] [TXPKT-4-KN6UY ] [INFO ]

TX____(MessagePacket TX:1 of 3) Raw : KN6UYO-10>APZ100::KN6UYO-7 :-me Another test, this time with logs.{4 To : KN6UYO-7 From : KN6UYO-10 Message : -me Another test, this time with logs. Msg # : 4 TX____(MessagePacket) - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/packets/core.py:239] [12/18/2023 06:54:34 PM] [RX_MSG ] [INFO ]

RX____(AckPacket) Raw : KN6UYO-7>APFII0,qAC,APRSFI::KN6UYO-10:ack4 To : KN6UYO-10 From : KN6UYO-7 Path : qAC=>APRSFI VIA : APRSFI Msg # : 4 RX____(AckPacket) - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/packets/core.py:239] [12/18/2023 06:54:34 PM] [ProcessPKT ] [INFO ] Got ack for message 4 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/rx.py:137] [12/18/2023 06:54:35 PM] [TXPKT-4-KN6UY ] [INFO ] MessagePacket(4) Message Send Complete via Ack. - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/tx.py:107] [12/18/2023 06:54:54 PM] [KeepAlive ] [INFO ] KN6UYO-10 - Uptime 00:07:01 RX:0 TX:3 Tracker:0 Msgs TX:3 RX:0 Last:00:00:01 Email: 00:00:02 - RAM Current:573KB Peak:707KB Threads:6 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/keep_alive.py:66] [12/18/2023 06:54:54 PM] [KeepAlive ] [INFO ] APRSDEmailThread:True:0:00:02.933482,KeepAliveThread:True:0:00:00.030175,APRSDPluginRXThread:True:0:00:00.159847,APRSDPluginProcessPacketThread:True:0:00:00.837526,APRSDRPCThread:True:0:07:01.381604,LogMonitorThread:True:0:00:01.252824 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/keep_alive.py:80] [12/18/2023 06:55:33 PM] [TXPKT-5-KN6UY ] [INFO ]

TX____(MessagePacket TX:1 of 3) Raw : KN6UYO-10>APZ100::KN6UYO-7 :-me Another test, this time with logs.{5 To : KN6UYO-7 From : KN6UYO-10 Message : -me Another test, this time with logs. Msg # : 5 TX____(MessagePacket) - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/packets/core.py:239] [12/18/2023 06:55:35 PM] [RX_MSG ] [INFO ]

RX____(AckPacket) Raw : KN6UYO-7>APFII0,qAC,APRSFI::KN6UYO-10:ack5 To : KN6UYO-10 From : KN6UYO-7 Path : qAC=>APRSFI VIA : APRSFI Msg # : 5 RX____(AckPacket) - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/packets/core.py:239] [12/18/2023 06:55:35 PM] [ProcessPKT ] [INFO ] Got ack for message 5 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/rx.py:137] [12/18/2023 06:55:36 PM] [TXPKT-5-KN6UY ] [INFO ] MessagePacket(5) Message Send Complete via Ack. - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/tx.py:107] [12/18/2023 06:55:54 PM] [KeepAlive ] [INFO ] KN6UYO-10 - Uptime 00:08:01 RX:0 TX:4 Tracker:0 Msgs TX:4 RX:0 Last:00:00:01 Email: 00:00:00 - RAM Current:590KB Peak:726KB Threads:6 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/keep_alive.py:66] [12/18/2023 06:55:54 PM] [KeepAlive ] [INFO ] APRSDEmailThread:True:0:00:00.608913,KeepAliveThread:True:0:00:00.030988,APRSDPluginRXThread:True:0:00:00.181845,APRSDPluginProcessPacketThread:True:0:00:00.446409,APRSDRPCThread:True:0:08:01.527306,LogMonitorThread:True:0:00:01.386622 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/keep_alive.py:80] [12/18/2023 06:56:35 PM] [TXPKT-6-KN6UY ] [INFO ]

TX____(MessagePacket TX:1 of 3) Raw : KN6UYO-10>APZ100::KN6UYO-7 :-me Another test, this time with logs.{6 To : KN6UYO-7 From : KN6UYO-10 Message : -me Another test, this time with logs. Msg # : 6 TX____(MessagePacket) - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/packets/core.py:239] [12/18/2023 06:56:38 PM] [RX_MSG ] [INFO ]

RX____(AckPacket) Raw : KN6UYO-7>APFII0,qAC,APRSFI::KN6UYO-10:ack6 To : KN6UYO-10 From : KN6UYO-7 Path : qAC=>APRSFI VIA : APRSFI Msg # : 6 RX____(AckPacket) - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/packets/core.py:239] [12/18/2023 06:56:38 PM] [ProcessPKT ] [INFO ] Got ack for message 6 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/rx.py:137] [12/18/2023 06:56:39 PM] [TXPKT-6-KN6UY ] [INFO ] MessagePacket(6) Message Send Complete via Ack. - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/tx.py:107] [12/18/2023 06:56:54 PM] [KeepAlive ] [INFO ] KN6UYO-10 - Uptime 00:09:01 RX:0 TX:5 Tracker:0 Msgs TX:5 RX:0 Last:00:00:01 Email: 00:00:03 - RAM Current:592KB Peak:728KB Threads:6 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/keep_alive.py:66] [12/18/2023 06:56:54 PM] [KeepAlive ] [INFO ] APRSDEmailThread:True:0:00:03.243095,KeepAliveThread:True:0:00:00.057265,APRSDPluginRXThread:True:0:00:00.498781,APRSDPluginProcessPacketThread:True:0:00:00.566245,APRSDRPCThread:True:0:09:01.703032,LogMonitorThread:True:0:00:01.552118 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/keep_alive.py:80] [12/18/2023 06:57:38 PM] [TXPKT-7-KN6UY ] [INFO ]

TX____(MessagePacket TX:1 of 3) Raw : KN6UYO-10>APZ100::KN6UYO-7 :-me Another test, this time with logs.{7 To : KN6UYO-7 From : KN6UYO-10 Message : -me Another test, this time with logs. Msg # : 7 TX____(MessagePacket) - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/packets/core.py:239] [12/18/2023 06:57:40 PM] [RX_MSG ] [INFO ]

RX____(AckPacket) Raw : KN6UYO-7>APFII0,qAC,APRSFI::KN6UYO-10:ack7 To : KN6UYO-10 From : KN6UYO-7 Path : qAC=>APRSFI VIA : APRSFI Msg # : 7 RX____(AckPacket) - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/packets/core.py:239] [12/18/2023 06:57:40 PM] [ProcessPKT ] [INFO ] Got ack for message 7 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/rx.py:137] [12/18/2023 06:57:41 PM] [TXPKT-7-KN6UY ] [INFO ] MessagePacket(7) Message Send Complete via Ack. - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/tx.py:107] [12/18/2023 06:57:43 PM] [MainThread ] [INFO ] Stopping Thread EmailThread - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/aprsd.py:38] [12/18/2023 06:57:43 PM] [MainThread ] [INFO ] Stopping Thread KeepAlive - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/aprsd.py:38] [12/18/2023 06:57:43 PM] [MainThread ] [INFO ] Stopping Thread RX_MSG - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/aprsd.py:38] [12/18/2023 06:57:44 PM] [MainThread ] [INFO ] Shutdown Aprsdis client. - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/clients/aprsis.py:33] [12/18/2023 06:57:44 PM] [MainThread ] [INFO ] Stopping Thread ProcessPKT - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/aprsd.py:38] [12/18/2023 06:57:44 PM] [MainThread ] [INFO ] Stopping Thread RPCThread - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/aprsd.py:38] [12/18/2023 06:57:44 PM] [MainThread ] [INFO ] Stopping Thread LogMonitorThread - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/threads/aprsd.py:38] [12/18/2023 06:57:44 PM] [MainThread ] [INFO ] Ctrl+C, Sending all threads exit! Can take up to 10 seconds 2023-12-18 18:57:44.251191 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/main.py:85] [12/18/2023 06:57:45 PM] [MainThread ] [INFO ] WatchList::Saving 1 entries to disk at/home/alessandrovoto/.config/aprsd - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/utils/objectstore.py:71] [12/18/2023 06:57:45 PM] [MainThread ] [INFO ] SeenList::Saving 1 entries to disk at/home/alessandrovoto/.config/aprsd - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/utils/objectstore.py:71] [12/18/2023 06:57:45 PM] [MainThread ] [INFO ] Uptime:0:09:53.334549 Msgs TX:6 RX:0 ACK: TX:0 RX:0 Email TX:0 RX:0 LastLoop:2023-12-18 18:57:43.203186 - [/home/alessandrovoto/.local/lib/python3.9/site-packages/aprsd/main.py:94]

alexvotofuture commented 6 months ago

Another random thought - could this have something to do with the packet_dupe_timeout in the config file? Mine is set to 30 currently.

hemna commented 6 months ago

any chance you can rerun this with debug logging?

aprsd server --loglevel DEBUG

hemna commented 3 months ago

Any updates on this issue for you?