[opensuse] Got Panic in dovecot.
-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1 I was moving thousands of messages from one mbox folder to another, using Alpine. <18>1 2017-05-05T00:10:06.458407+02:00 Telcontar dovecot - - - imap(cer): Panic: file mbox-sync.c: line 665 (mbox_sync_handle_header): assertion failed: (mail_ctx->mail.from_offset + move_diff != 1 && mail_ctx->mail.from_offset + move_diff != 2) <19>1 2017-05-05T00:10:06.459018+02:00 Telcontar dovecot - - - imap(cer): Error: Raw backtrace: /usr/lib64/dovecot/libdovecot.so.0(+0x8c9a0) [0x7fd520af69a0] -> /usr/lib64/dovecot/libdovecot.so.0(+0x8ca7e) [0x7fd520af6a7e] -> /usr/lib64/dovecot/libdovecot.so.0(i_fatal+0) [0x7fd520a98ec5] -> /usr/lib64/dovecot/libdovecot-storage.so.0(+0x841cb) [0x7fd520dfe1cb] -> /usr/lib64/dovecot/libdovecot-storage.so.0(mbox_sync+0x3b) [0x7fd520dfe33b] -> /usr/lib64/dovecot/libdovecot-storage.so.0(mbox_storage_sync_init+0x4c) [0x7fd520dfe46c] -> /usr/lib64/dovecot/libdovecot-storage.so.0(mailbox_sync_init+0x2b) [0x7fd520dc185b] -> dovecot/imap(imap_sync_init+0x71) [0x55f9ade12431] -> dovecot/imap(cmd_sync_delayed+0x1c9) [0x55f9ade13219] -> dovecot/imap(client_handle_input+0x1f8) [0x55f9ade072d8] -> dovecot/imap(client_input+0x75) [0x55f9ade07755] -> /usr/lib64/dovecot/libdovecot.so.0(io_loop_call_io+0x4c) [0x7fd520b0979c] -> /usr/lib64/dovecot/libdovecot.so.0(io_loop_handler_run_internal+0xd7) [0x7 fd520b0aaa7] -> /usr/lib64/dovecot/libdovecot.so.0(io_loop_handler_run+0x25) [0x7fd520b09825] -> /usr/lib64/dovecot/libdovecot.so.0(io_loop_run+0x38) [0x7fd520b099d8] -> /usr/lib64/dovecot/libdovecot.so.0(master_service_run+0x13) [0x7fd520a9efa3] -> dovecot/imap(main+0x322) [0x55f9addfae72] -> /lib64/libc.so.6(__libc_start_main+0xf5) [0x7fd5206e76e5] -> dovecot/imap(_start+0x29) [0x55f9addfaff9] <18>1 2017-05-05T00:10:06.570722+02:00 Telcontar dovecot - - - imap(cer): Fatal: master: service(imap): child 23383 killed with signal 6 (core dumped) <22>1 2017-05-05T00:10:06.905576+02:00 Telcontar dovecot - - - imap-login: Login: user=<cer>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=24126, TLS, session=<G7UVCbpOtOl/AAAB> <18>1 2017-05-05T00:10:08.904911+02:00 Telcontar dovecot - - - imap(cer): Panic: file mbox-sync.c: line 665 (mbox_sync_handle_header): assertion failed: (mail_ctx->mail.from_offset + move_diff != 1 && mail_ctx->mail.from_offset + move_diff != 2) <19>1 2017-05-05T00:10:08.905520+02:00 Telcontar dovecot - - - imap(cer): Error: Raw backtrace: /usr/lib64/dovecot/libdovecot.so.0(+0x8c9a0) [0x7f0b9b6419a0] -> /usr/lib64/dovecot/libdovecot.so.0(+0x8ca7e) [0x7f0b9b641a7e] -> /usr/lib64/dovecot/libdovecot.so.0(i_fatal+0) [0x7f0b9b5e3ec5] -> /usr/lib64/dovecot/libdovecot-storage.so.0(+0x841cb) [0x7f0b9b9491cb] -> /usr/lib64/dovecot/libdovecot-storage.so.0(mbox_sync+0x3b) [0x7f0b9b94933b] -> /usr/lib64/dovecot/libdovecot-storage.so.0(mbox_storage_sync_init+0x4c) [0x7f0b9b94946c] -> /usr/lib64/dovecot/libdovecot-storage.so.0(mailbox_sync_init+0x2b) [0x7f0b9b90c85b] -> /usr/lib64/dovecot/libdovecot-storage.so.0(mailbox_sync+0x27) [0x7f0b9b90c967] -> dovecot/imap(cmd_select_full+0x163) [0x562e6610fe93] -> dovecot/imap(command_exec+0x8d) [0x562e661168bd] -> dovecot/imap(+0x1ae1f) [0x562e66114e1f] -> dovecot/imap(+0x1aeaf) [0x562e66114eaf] -> dovecot/imap(client_handle_input+0x175) [0x562e66115255] -> dovecot/imap(client_input+0x75) [0x562 e66115755] -> /usr/lib64/dovecot/libdovecot.so.0(io_loop_call_io+0x4c) [0x7f0b9b65479c] -> /usr/lib64/dovecot/libdovecot.so.0(io_loop_handler_run_internal+0xd7) [0x7f0b9b655aa7] -> /usr/lib64/dovecot/libdovecot.so.0(io_loop_handler_run+0x25) [0x7f0b9b654825] -> /usr/lib64/dovecot/libdovecot.so.0(io_loop_run+0x38) [0x7f0b9b6549d8] -> /usr/lib64/dovecot/libdovecot.so.0(master_service_run+0x13) [0x7f0b9b5e9fa3] -> dovecot/imap(main+0x322) [0x562e66108e72] -> /lib64/libc.so.6(__libc_start_main+0xf5) [0x7f0b9b2326e5] -> dovecot/imap(_start+0x29) [0x562e66108ff9] <18>1 2017-05-05T00:10:08.932605+02:00 Telcontar dovecot - - - imap(cer): Fatal: master: service(imap): child 20270 killed with signal 6 (core dumped) <2>1 2017-05-05T00:10:09.607089+02:00 Telcontar systemd-coredump 24122 - - Process 23383 (imap) of user 1000 dumped core. <2>1 2017-05-05T00:10:11.193878+02:00 Telcontar systemd-coredump 24129 - - Process 20270 (imap) of user 1000 dumped core. <22>1 2017-05-05T00:10:18.562790+02:00 Telcontar dovecot - - - imap-login: Login: user=<cer>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=24138, TLS, session=<WDnICbpOuul/AAAB> <18>1 2017-05-05T00:10:26.000123+02:00 Telcontar dovecot - - - imap(cer): Panic: file mbox-sync.c: line 665 (mbox_sync_handle_header): assertion failed: (mail_ctx->mail.from_offset + move_diff != 1 && mail_ctx->mail.from_offset + move_diff != 2) <19>1 2017-05-05T00:10:26.000672+02:00 Telcontar dovecot - - - imap(cer): Error: Raw backtrace: /usr/lib64/dovecot/libdovecot.so.0(+0x8c9a0) [0x7f5c7f2ad9a0] -> /usr/lib64/dovecot/libdovecot.so.0(+0x8ca7e) [0x7f5c7f2ada7e] -> /usr/lib64/dovecot/libdovecot.so.0(i_fatal+0) [0x7f5c7f24fec5] -> /usr/lib64/dovecot/libdovecot-storage.so.0(+0x841cb) [0x7f5c7f5b51cb] -> /usr/lib64/dovecot/libdovecot-storage.so.0(mbox_sync+0x3b) [0x7f5c7f5b533b] -> /usr/lib64/dovecot/libdovecot-storage.so.0(mbox_storage_sync_init+0x4c) [0x7f5c7f5b546c] -> /usr/lib64/dovecot/libdovecot-storage.so.0(mailbox_sync_init+0x2b) [0x7f5c7f57885b] -> /usr/lib64/dovecot/libdovecot-storage.so.0(mailbox_sync+0x27) [0x7f5c7f578967] -> dovecot/imap(cmd_select_full+0x163) [0x55df3b092e93] -> dovecot/imap(command_exec+0x8d) [0x55df3b0998bd] -> dovecot/imap(+0x1ae1f) [0x55df3b097e1f] -> dovecot/imap(+0x1aeaf) [0x55df3b097eaf] -> dovecot/imap(client_handle_input+0x175) [0x55df3b098255] -> dovecot/imap(client_input+0x75) [0x55d f3b098755] -> /usr/lib64/dovecot/libdovecot.so.0(io_loop_call_io+0x4c) [0x7f5c7f2c079c] -> /usr/lib64/dovecot/libdovecot.so.0(io_loop_handler_run_internal+0xd7) [0x7f5c7f2c1aa7] -> /usr/lib64/dovecot/libdovecot.so.0(io_loop_handler_run+0x25) [0x7f5c7f2c0825] -> /usr/lib64/dovecot/libdovecot.so.0(io_loop_run+0x38) [0x7f5c7f2c09d8] -> /usr/lib64/dovecot/libdovecot.so.0(master_service_run+0x13) [0x7f5c7f255fa3] -> dovecot/imap(main+0x322) [0x55df3b08be72] -> /lib64/libc.so.6(__libc_start_main+0xf5) [0x7f5c7ee9e6e5] -> dovecot/imap(_start+0x29) [0x55df3b08bff9] <18>1 2017-05-05T00:10:26.007253+02:00 Telcontar dovecot - - - imap(cer): Fatal: master: service(imap): child 24138 killed with signal 6 (core dumped) <22>1 2017-05-05T00:10:26.068222+02:00 Telcontar dovecot - - - imap-login: Login: user=<cer>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=24147, TLS, session=<6786CrpOvul/AAAB> <2>1 2017-05-05T00:10:26.830948+02:00 Telcontar systemd-coredump 24145 - - Process 24138 (imap) of user 1000 dumped core. <18>1 2017-05-05T00:11:31.948646+02:00 Telcontar dovecot - - - imap(cer): Panic: file mbox-sync.c: line 665 (mbox_sync_handle_header): assertion failed: (mail_ctx->mail.from_offset + move_diff != 1 && mail_ctx->mail.from_offset + move_diff != 2) <19>1 2017-05-05T00:11:31.949407+02:00 Telcontar dovecot - - - imap(cer): Error: Raw backtrace: /usr/lib64/dovecot/libdovecot.so.0(+0x8c9a0) [0x7f7e7773f9a0] -> /usr/lib64/dovecot/libdovecot.so.0(+0x8ca7e) [0x7f7e7773fa7e] -> /usr/lib64/dovecot/libdovecot.so.0(i_fatal+0) [0x7f7e776e1ec5] -> /usr/lib64/dovecot/libdovecot-storage.so.0(+0x841cb) [0x7f7e77a471cb] -> /usr/lib64/dovecot/libdovecot-storage.so.0(mbox_sync+0x3b) [0x7f7e77a4733b] -> /usr/lib64/dovecot/libdovecot-storage.so.0(mbox_storage_sync_init+0x4c) [0x7f7e77a4746c] -> /usr/lib64/dovecot/libdovecot-storage.so.0(mailbox_sync_init+0x2b) [0x7f7e77a0a85b] -> /usr/lib64/dovecot/libdovecot-storage.so.0(mailbox_sync+0x27) [0x7f7e77a0a967] -> dovecot/imap(cmd_select_full+0x163) [0x5632f6ad9e93] -> dovecot/imap(command_exec+0x8d) [0x5632f6ae08bd] -> dovecot/imap(+0x1ae1f) [0x5632f6adee1f] -> dovecot/imap(+0x1aeaf) [0x5632f6adeeaf] -> dovecot/imap(client_handle_input+0x175) [0x5632f6adf255] -> dovecot/imap(client_input+0x75) [0x563 2f6adf755] -> /usr/lib64/dovecot/libdovecot.so.0(io_loop_call_io+0x4c) [0x7f7e7775279c] -> /usr/lib64/dovecot/libdovecot.so.0(io_loop_handler_run_internal+0xd7) [0x7f7e77753aa7] -> /usr/lib64/dovecot/libdovecot.so.0(io_loop_handler_run+0x25) [0x7f7e77752825] -> /usr/lib64/dovecot/libdovecot.so.0(io_loop_run+0x38) [0x7f7e777529d8] -> /usr/lib64/dovecot/libdovecot.so.0(master_service_run+0x13) [0x7f7e776e7fa3] -> dovecot/imap(main+0x322) [0x5632f6ad2e72] -> /lib64/libc.so.6(__libc_start_main+0xf5) [0x7f7e773306e5] -> dovecot/imap(_start+0x29) [0x5632f6ad2ff9] <18>1 2017-05-05T00:11:31.955137+02:00 Telcontar dovecot - - - imap(cer): Fatal: master: service(imap): child 24147 killed with signal 6 (core dumped) <22>1 2017-05-05T00:11:32.108457+02:00 Telcontar dovecot - - - imap-login: Login: user=<cer>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=24193, TLS, session=<IK4pDrpO4Ol/AAAB> <2>1 2017-05-05T00:11:32.761616+02:00 Telcontar systemd-coredump 24188 - - Process 24147 (imap) of user 1000 dumped core. <86>1 2017-05-05T00:12:01.013662+02:00 Telcontar cron 24207 - - pam_unix(crond:session): session opened for user news by (uid=0) <30>1 2017-05-05T00:12:01.024383+02:00 Telcontar systemd 1 - - Created slice User Slice of news. <30>1 2017-05-05T00:12:01.079640+02:00 Telcontar systemd 1 - - Starting User Manager for UID 9... Stopping dovecot, using Alpine on folder to clear issue. Messages that were marked for deletion had not been deleted. Clearing. I noticed one malformed post. Then restarted dovecot. <30>1 2017-05-05T00:12:44.839660+02:00 Telcontar systemd 1 - - Stopping Dovecot IMAP/POP3 email server... <20>1 2017-05-05T00:12:44.839947+02:00 Telcontar dovecot - - - config: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill) <20>1 2017-05-05T00:12:44.866118+02:00 Telcontar dovecot - - - master: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill) <20>1 2017-05-05T00:12:44.896407+02:00 Telcontar dovecot - - - anvil: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill) <20>1 2017-05-05T00:12:44.896653+02:00 Telcontar dovecot - - - imap: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill) <22>1 2017-05-05T00:12:44.896782+02:00 Telcontar dovecot - - - imap(cer): Server shutting down. in=173776 out=734235 <20>1 2017-05-05T00:12:44.896913+02:00 Telcontar dovecot - - - imap: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill) <22>1 2017-05-05T00:12:44.897118+02:00 Telcontar dovecot - - - imap(cer): Server shutting down. in=13907 out=53255 <22>1 2017-05-05T00:12:44.897279+02:00 Telcontar dovecot - - - imap(cer): Connection closed (IDLE running for 0.001 + waiting input for 41.786 secs, 2 B in + 10+0 B out, state=wait-input) in=13597 out=52417 <20>1 2017-05-05T00:12:44.897415+02:00 Telcontar dovecot - - - imap: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill) <22>1 2017-05-05T00:12:44.897542+02:00 Telcontar dovecot - - - imap(cer): Server shutting down. in=592 out=19556 <20>1 2017-05-05T00:12:44.897669+02:00 Telcontar dovecot - - - imap: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill) <22>1 2017-05-05T00:12:44.897794+02:00 Telcontar dovecot - - - imap(cer): Server shutting down. in=129 out=978 <22>1 2017-05-05T00:12:44.897921+02:00 Telcontar dovecot - - - imap(cer): Connection closed (IDLE running for 0.001 + waiting input for 44.292 secs, 2 B in + 10+0 B out, state=wait-input) in=2384 out=12247 <22>1 2017-05-05T00:12:44.898053+02:00 Telcontar dovecot - - - imap(cer): Connection closed (IDLE running for 0.001 + waiting input for 44.215 secs, 2 B in + 10+0 B out, state=wait-input) in=11181 out=3672904 <22>1 2017-05-05T00:12:44.898189+02:00 Telcontar dovecot - - - imap(cer): Connection closed (IDLE running for 0.001 + waiting input for 44.170 secs, 2 B in + 10+0 B out, state=wait-input) in=5187 out=842730 <20>1 2017-05-05T00:12:44.898320+02:00 Telcontar dovecot - - - log: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill) <19>1 2017-05-05T00:12:44.898448+02:00 Telcontar dovecot - - - imap-login: Error: read(anvil) failed: EOF <20>1 2017-05-05T00:12:44.898574+02:00 Telcontar dovecot - - - imap-login: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill) <19>1 2017-05-05T00:12:44.898699+02:00 Telcontar dovecot - - - imap-login: Error: read(anvil) failed: EOF <19>1 2017-05-05T00:12:44.898823+02:00 Telcontar dovecot - - - imap-login: Error: read(anvil) failed: EOF <20>1 2017-05-05T00:12:44.898946+02:00 Telcontar dovecot - - - imap-login: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill) <19>1 2017-05-05T00:12:44.899070+02:00 Telcontar dovecot - - - imap-login: Error: read(anvil) failed: EOF <19>1 2017-05-05T00:12:44.899197+02:00 Telcontar dovecot - - - imap-login: Error: read(anvil) failed: EOF <20>1 2017-05-05T00:12:44.899320+02:00 Telcontar dovecot - - - imap-login: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill) <19>1 2017-05-05T00:12:44.899471+02:00 Telcontar dovecot - - - imap-login: Error: read(anvil) failed: EOF <20>1 2017-05-05T00:12:44.899594+02:00 Telcontar dovecot - - - imap-login: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill) <19>1 2017-05-05T00:12:44.899718+02:00 Telcontar dovecot - - - imap-login: Error: read(anvil) failed: EOF <19>1 2017-05-05T00:12:44.899842+02:00 Telcontar dovecot - - - imap-login: Error: read(anvil) failed: EOF <20>1 2017-05-05T00:12:44.899965+02:00 Telcontar dovecot - - - ssl-params: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill) <30>1 2017-05-05T00:12:44.980653+02:00 Telcontar systemd 1 - - Stopped Dovecot IMAP/POP3 email server. <30>1 2017-05-05T00:14:18.923565+02:00 Telcontar systemd 1 - - Started Dovecot IMAP/POP3 email server. <22>1 2017-05-05T00:14:19.013813+02:00 Telcontar dovecot - - - master: Dovecot v2.2.25 (7be1766) starting up for imap, pop3, lmtp (core dumps disabled) <22>1 2017-05-05T00:14:30.873403+02:00 Telcontar dovecot - - - imap-login: Login: user=<cer>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=24380, TLS, session=<OzCjGLpOkOp/AAAB> <22>1 2017-05-05T00:14:37.291953+02:00 Telcontar dovecot - - - imap-login: Login: user=<cer>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=24388, TLS, session=<XR40GbpOlup/AAAB> <20>1 2017-05-05T00:14:37.294807+02:00 Telcontar dovecot - - - imap(cer): Warning: UIDVALIDITY changed (1371339015 -> 1493936019) in mbox file /home/cer/Mail/_Lists/r_xfs <22>1 2017-05-05T00:15:03.010854+02:00 Telcontar dovecot - - - imap-login: Login: user=<cer>, method=PLAIN, rip=192.168.1.14, lip=192.168.1.14, mpid=24501, TLS, session=<iY28GrpOvsjAqAEO> <22>1 2017-05-05T00:15:03.051344+02:00 Telcontar dovecot - - - imap-login: Login: user=<cer>, method=PLAIN, rip=192.168.1.14, lip=192.168.1.14, mpid=24502, TLS, session=<hSy9GrpOwMjAqAEO> cer@Telcontar:~> rpm -qa | grep -i dovecot dovecot-2.2-7.3.noarch dovecot22-backend-pgsql-2.2.25-3.1.x86_64 dovecot22-fts-2.2.25-3.1.x86_64 dovecot22-backend-sqlite-2.2.25-3.1.x86_64 dovecot22-backend-mysql-2.2.25-3.1.x86_64 dovecot22-fts-squat-2.2.25-3.1.x86_64 dovecot22-2.2.25-3.1.x86_64 cer@Telcontar:~> - -- Cheers Carlos E. R. (from 42.2 x86_64 "Malachite" at Telcontar) -----BEGIN PGP SIGNATURE----- Version: GnuPG v2 iEYEARECAAYFAlkLrAwACgkQtTMYHG2NR9W8aQCfen92XXzgOFYEOdJiOvODEq1F 39EAoJVIAXPzjocyd6EaE1YAkiK9yVpy =FR4a -----END PGP SIGNATURE-----
On 05/04/2017 05:32 PM, Carlos E. R. wrote:
<18>1 2017-05-05T00:10:06.458407+02:00 Telcontar dovecot - - - imap(cer): Panic: file mbox-sync.c: line 665 (mbox_sync_handle_header): assertion failed: (mail_ctx->mail.from_offset + move_diff != 1 && mail_ctx->mail.from_offset + move_diff != 2) <snip> <18>1 2017-05-05T00:10:06.570722+02:00 Telcontar dovecot - - - imap(cer): Fatal: master: service(imap): child 23383 killed with signal 6 (core dumped)
I've run dovecot and dovecot2 for 15+ years and I've never seen that one before. Could this user have a massively corrupt mail store or could there be a permission problem on the user inbox? -- David C. Rankin, J.D.,P.E.
On 05/13/2017 02:02 AM, David C. Rankin wrote:
On 05/04/2017 05:32 PM, Carlos E. R. wrote:
<18>1 2017-05-05T00:10:06.458407+02:00 Telcontar dovecot - - - imap(cer): Panic: file mbox-sync.c: line 665 (mbox_sync_handle_header): assertion failed: (mail_ctx->mail.from_offset + move_diff != 1 && mail_ctx->mail.from_offset + move_diff != 2) <snip> <18>1 2017-05-05T00:10:06.570722+02:00 Telcontar dovecot - - - imap(cer): Fatal: master: service(imap): child 23383 killed with signal 6 (core dumped)
I've run dovecot and dovecot2 for 15+ years and I've never seen that one before. Could this user have a massively corrupt mail store or could there be a permission problem on the user inbox?
and could this be a side-effect of your spamd ipv4 listening issue? -- David C. Rankin, J.D.,P.E.
On 2017-05-13 09:05, David C. Rankin wrote:
On 05/13/2017 02:02 AM, David C. Rankin wrote:
On 05/04/2017 05:32 PM, Carlos E. R. wrote:
<18>1 2017-05-05T00:10:06.458407+02:00 Telcontar dovecot - - - imap(cer): Panic: file mbox-sync.c: line 665 (mbox_sync_handle_header): assertion failed: (mail_ctx->mail.from_offset + move_diff != 1 && mail_ctx->mail.from_offset + move_diff != 2) <snip> <18>1 2017-05-05T00:10:06.570722+02:00 Telcontar dovecot - - - imap(cer): Fatal: master: service(imap): child 23383 killed with signal 6 (core dumped)
I've run dovecot and dovecot2 for 15+ years and I've never seen that one before. Could this user have a massively corrupt mail store or could there be a permission problem on the user inbox?
No, because after restart service I could access that mail boxes just fine. An assertion in C code means that the code reached a point that the programmer thought it would never do, but it did. And aborts, handling that situation is not designed into the program. The message should tell the programmer where and why, but... Basically it means that it is a signal for the programmer to redo the code.
and could this be a side-effect of your spamd ipv4 listening issue?
Seems unrelated. -- Cheers / Saludos, Carlos E. R. (from 42.2 x86_64 "Malachite" (Minas Tirith))
participants (2)
-
Carlos E. R. -
David C. Rankin