Hello Patrick,
thank you for your feedback. I'd like you to update the smtpsegv branch,
and recompile piler-smtp. I've refactored the timeout checks, and pushed
the commit to the branch on bitbucket.
Janos
On 2021-02-15 14:34, Wagner, Patrick wrote:
Hello Janos,
happened Sa->So this time.
Logs (piler-smtp as compiled out of smtpsegv.tar.gz source code) see
below, SIGSEGV follows right after free(session) done.
It is also interesting to see that the piler daemon finishes
processing a message 2 whole minutes after the smtp process has
already segfaulted and and stopped supplying any new messages.
Thanks, Patrick
Feb 14 02:29:59 hostname piler-smtp[19066]: connected from
192.168.165.154:9501 on fd=6 (active connections: 1)
Feb 14 02:29:59 hostname piler-smtp[19066]: received:
Q056C70SAJJT9OFH, from=, size=4944, client=192.168.165.154, fd=6,
fsync=1831
Feb 14 02:29:59 hostname piler-smtp[19066]: disconnected from
192.168.165.154 on fd=6, slot=0, reason=done (0 active connections)
Feb 14 02:29:59 hostname piler-smtp[19066]: free_smtp_session()
Feb 14 02:29:59 hostname piler-smtp[19066]: SSL_shutdown()
Feb 14 02:29:59 hostname piler-smtp[19066]: SSL_free()
Feb 14 02:29:59 hostname piler-smtp[19066]: SSL_CTX_free
Feb 14 02:29:59 hostname piler-smtp[19066]: freeing session
Feb 14 02:29:59 hostname piler-smtp[19066]: free(session) done
Feb 14 02:30:00 hostname piler[19070]: 1/Q056C70SAJJT9OFH:
4000000060287d2205ab9b5400229f77e67a, size=4944/1928, attachments=0,
reference=, message-id=<4DdV5z0DlSz
5vfP@mailrelay.intern>, retention=7350, folder=0, delay=0.0425,
status=stored
Feb 14 02:30:01 hostname CRON[7835]: (piler) CMD (/usr/bin/find
/var/www/piler/tmp -type f -name i.\* -exec rm -f {} \;)
Feb 14 02:30:01 hostname CRON[7836]: (piler) CMD (/usr/bin/find
/var/piler/error -type f|wc -l > /var/piler/stat/error)
Feb 14 02:30:01 hostname CRON[7842]: (piler) CMD (/usr/bin/indexer
--quiet note1 --rotate)
Feb 14 02:30:01 hostname CRON[7837]: (piler) CMD (/usr/bin/indexer
--quiet tag1 --rotate)
Feb 14 02:30:01 hostname CRON[7838]: (piler) CMD
(/usr/local/libexec/piler/indexer.main.sh)
Feb 14 02:30:01 hostname CRON[7833]: (CRON) info (No MTA installed,
discarding output)
Feb 14 02:30:01 hostname CRON[7832]: (CRON) info (No MTA installed,
discarding output)
Feb 14 02:30:01 hostname piler: INDEXER INFO: merging to main started
Feb 14 02:30:40 hostname piler-smtp[19066]: connected from
192.168.165.252:37934 on fd=6 (active connections: 1)
Feb 14 02:30:41 hostname piler-smtp[19066]: connected from
192.168.165.252:37936 on fd=9 (active connections: 2)
Feb 14 02:30:41 hostname piler-smtp[19066]: disconnected from
192.168.165.252 on fd=6, slot=0, reason=done (1 active connections)
Feb 14 02:30:41 hostname piler-smtp[19066]: free_smtp_session()
Feb 14 02:30:41 hostname piler-smtp[19066]: SSL_shutdown()
Feb 14 02:30:41 hostname piler-smtp[19066]: SSL_free()
Feb 14 02:30:41 hostname piler-smtp[19066]: SSL_CTX_free
Feb 14 02:30:41 hostname piler-smtp[19066]: freeing session
Feb 14 02:30:41 hostname piler-smtp[19066]: free(session) done
Feb 14 02:30:41 hostname piler-smtp[19066]: disconnected from
192.168.165.252 on fd=9, slot=1, reason=done (0 active connections)
Feb 14 02:30:41 hostname piler-smtp[19066]: free_smtp_session()
Feb 14 02:30:41 hostname piler-smtp[19066]: SSL_shutdown()
Feb 14 02:30:41 hostname piler-smtp[19066]: SSL_free()
Feb 14 02:30:41 hostname piler-smtp[19066]: SSL_CTX_free
Feb 14 02:30:41 hostname piler-smtp[19066]: freeing session
Feb 14 02:30:41 hostname piler-smtp[19066]: free(session) done
Feb 14 02:31:44 hostname piler-smtp[19066]: connected from
192.168.165.252:40584 on fd=6 (active connections: 1)
Feb 14 02:31:53 hostname piler-smtp[19066]: connected from
192.168.165.252:40586 on fd=9 (active connections: 2)
Feb 14 02:31:53 hostname piler-smtp[19066]: disconnected from
192.168.165.252 on fd=6, slot=0, reason=done (1 active connections)
Feb 14 02:31:53 hostname piler-smtp[19066]: free_smtp_session()
Feb 14 02:31:53 hostname piler-smtp[19066]: SSL_shutdown()
Feb 14 02:31:53 hostname piler-smtp[19066]: SSL_free()
Feb 14 02:31:53 hostname piler-smtp[19066]: SSL_CTX_free
Feb 14 02:31:53 hostname piler-smtp[19066]: freeing session
Feb 14 02:31:53 hostname piler-smtp[19066]: free(session) done
Feb 14 02:31:53 hostname piler-smtp[19066]: disconnected from
192.168.165.252 on fd=9, slot=1, reason=done (0 active connections)
Feb 14 02:31:53 hostname piler-smtp[19066]: free_smtp_session()
Feb 14 02:31:53 hostname piler-smtp[19066]: SSL_shutdown()
Feb 14 02:31:53 hostname piler-smtp[19066]: SSL_free()
Feb 14 02:31:53 hostname piler-smtp[19066]: SSL_CTX_free
Feb 14 02:31:53 hostname piler-smtp[19066]: freeing session
Feb 14 02:31:53 hostname piler-smtp[19066]: free(session) done
Feb 14 02:32:40 hostname piler-smtp[19066]: connected from
192.168.165.252:43330 on fd=6 (active connections: 1)
Feb 14 02:32:40 hostname piler-smtp[19066]: connected from
192.168.165.252:43334 on fd=9 (active connections: 2)
Feb 14 02:32:40 hostname piler-smtp[19066]: disconnected from
192.168.165.252 on fd=6, slot=0, reason=done (1 active connections)
Feb 14 02:32:40 hostname piler-smtp[19066]: free_smtp_session()
Feb 14 02:32:40 hostname piler-smtp[19066]: SSL_shutdown()
Feb 14 02:32:40 hostname piler-smtp[19066]: SSL_free()
Feb 14 02:32:40 hostname piler-smtp[19066]: SSL_CTX_free
Feb 14 02:32:40 hostname piler-smtp[19066]: freeing session
Feb 14 02:32:40 hostname piler-smtp[19066]: free(session) done
Feb 14 02:32:40 hostname piler-smtp[19066]: disconnected from
192.168.165.252 on fd=9, slot=1, reason=done (0 active connections)
Feb 14 02:32:40 hostname piler-smtp[19066]: free_smtp_session()
Feb 14 02:32:40 hostname piler-smtp[19066]: SSL_shutdown()
Feb 14 02:32:40 hostname piler-smtp[19066]: SSL_free()
Feb 14 02:32:40 hostname piler-smtp[19066]: SSL_CTX_free
Feb 14 02:32:40 hostname piler-smtp[19066]: freeing session
Feb 14 02:32:40 hostname piler-smtp[19066]: free(session) done
Feb 14 02:33:05 hostname piler-smtp[19066]: connected from
192.168.165.154:9813 on fd=6 (active connections: 1)
Feb 14 02:34:57 hostname piler-smtp[19066]: received:
0GQZDHT6RCMA7ZQC, from=, size=4944, client=192.168.165.154, fd=6,
fsync=111399876
Feb 14 02:34:57 hostname piler-smtp[19066]: client 192.168.165.154
timeout, lasttime: 1613266385
Feb 14 02:34:57 hostname piler-smtp[19066]: disconnected from
192.168.165.154 on fd=6, slot=0, reason=timeout (0 active connections)
Feb 14 02:34:57 hostname piler-smtp[19066]: free_smtp_session()
Feb 14 02:34:57 hostname piler-smtp[19066]: SSL_shutdown()
Feb 14 02:34:57 hostname piler-smtp[19066]: SSL_free()
Feb 14 02:34:57 hostname piler-smtp[19066]: SSL_CTX_free
Feb 14 02:34:57 hostname piler-smtp[19066]: freeing session
Feb 14 02:34:57 hostname piler-smtp[19066]: free(session) done
Feb 14 02:34:57 hostname piler-smtp[19066]: got signal: 11,
Segmentation fault
Feb 14 02:34:57 hostname piler-smtp[19066]: piler-smtp has been
terminated
Feb 14 02:35:01 hostname CRON[8225]: (piler) CMD (/usr/bin/find
/var/piler/error -type f|wc -l > /var/piler/stat/error)
Feb 14 02:35:01 hostname CRON[8226]: (root) CMD (command -v debian-sa1
> /dev/null && debian-sa1 1 1)
Feb 14 02:35:01 hostname CRON[8230]: (piler) CMD (/usr/bin/find
/var/www/piler/tmp -type f -name i.\* -exec rm -f {} \;)
Feb 14 02:35:01 hostname CRON[8232]: (piler) CMD
(/usr/local/libexec/piler/indexer.delta.sh)
Feb 14 02:35:01 hostname piler: INDEXER ERROR: indexer merging to main
index is already running. It started at So 14. Feb 02:30:01 CET 2021
Feb 14 02:36:59 hostname piler[19077]: 8/0GQZDHT6RCMA7ZQC:
4000000060287e4b1794e35c0014bdcfcaf0, size=4944/1944, attachments=0,
reference=, message-id=<4DdV9Y1pLfz5vfv@mailrelay.intern>,
retention=7350, folder=0, delay=121.7791, status=stored
Feb 14 02:39:01 hostname systemd[1]: Starting Clean php session
files...
Feb 14 02:39:01 hostname CRON[8603]: (root) CMD ( [ -x
/usr/lib/php5/maxlifetime ] && [ -x /usr/lib/php5/sessionclean ] && [
-d /var/lib/php5 ] && /usr/lib/php5/sessionclean /var/lib/php5
$(/usr/lib/php5/maxlifetime))
Feb 14 02:39:01 hostname CRON[8604]: (root) CMD ( [ -x
/usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then
/usr/lib/php/sessionclean; fi)
Feb 14 02:39:01 hostname systemd[1]: Started Clean php session files.
Feb 14 02:40:01 hostname CRON[8731]: (piler) CMD (/usr/bin/find
/var/piler/error -type f|wc -l > /var/piler/stat/error)
Feb 14 02:40:01 hostname CRON[8732]: (piler) CMD (/usr/bin/find
/var/www/piler/tmp -type f -name i.\* -exec rm -f {} \;)
