Docker context detected with the file /.dockerenv
No log by default in Docker context. Use –log to trigger logging to the logfile.
Changing current directory to /var/tmp/uid_65534
Here is imapsync 2.178 on host aa437e5c4b09, a linux system with 0.8/7.6 free GiB of RAM
with Perl 5.32.1 and Mail::IMAPClient 3.42
Transfer started at Wednesday 9 November 2022-11-09 14:58:49 +0100 CET
PID is 62937 my PPID is 62873
No log file because of option –nolog
Load is 0.96 1.04 1.16 2/967 on 4 cores
Current directory is /var/tmp/uid_65534
Real user id is nobody (uid 65534)
Effective user id is nobody (euid 65534)
$RCSfile: imapsync,v $ $Revision: 2.178 $ $Date: 2022/01/12 21:28:37 $
Command line used, run by /usr/bin/perl:
/usr/local/bin/imapsync –tmpdir /tmp –nofoldersizes –addheader –delete2duplicates –subscribeall –delete –automap –ssl1 –host1 moose.mxrouting.net –user1 rfk@mydomain.tld –passfile1 /tmp/imapsync.nuTKZdz –port1 993 –host2 localhost –user2 rfk@myotherdomain.tldiu2zkvhtymeeud0d@mailcow.local –passfile2 /tmp/imapsync.HOmnLHM –no-modulesversion –noreleasecheck –debugssl=4 –debugimap
Temp directory is /tmp ( to change it use –tmpdir dirpath )
Under docker context so installing only signals to exit
kill -INT 62937 # special behavior: call to sub catch_exit
kill -QUIT 62937 # special behavior: call to sub catch_exit
kill -TERM 62937 # special behavior: call to sub catch_exit
No variable pid_filename
PID file is unset ( to set it, use –pidfile filepath ; to avoid it use –pidfile "" )
Info: will resync flags for already transferred messages. Use –noresyncflags to not resync flags.
Host2: probing ssl on port 993 ( use –nosslcheck to avoid this ssl probe )
Host2: sslcheck detected open ssl port 993 so turning ssl on (use –nossl2 –notls2 to turn off SSL and TLS wizardry)
SSL debug mode level is –debugssl 4 (can be set from 0 meaning no debug to 4 meaning max debug)
Host1: SSL default mode is like –sslargs1 “SSL_verify_mode=0”, meaning for host1 SSL_VERIFY_NONE, ie, do not check the certificate server.
Host1: Use –sslargs1 SSL_verify_mode=1 to have SSL_VERIFY_PEER, ie, check the certificate server of host1
Host2: SSL default mode is like –sslargs2 “SSL_verify_mode=0”, meaning for host2 SSL_VERIFY_NONE, ie, do not check the certificate server.
Host2: Use –sslargs2 SSL_verify_mode=1 to have SSL_VERIFY_PEER, ie, check the certificate server of host2
Info: turning on –expunge1 because –delete1 –noexpunge1 is very dangerous on the second run.
Info: if expunging after each message slows down too much the sync then use –noexpungeaftereach to speed up
Info: will act as –uidexpunge2
Info: turned ON syncinternaldates, will set the internal dates (arrival dates) on host2 same as host1.
Host1: will try to use LOGIN authentication on host1
Host2: will try to use LOGIN authentication on host2
Host1: imap connection timeout is 120 seconds
Host2: imap connection timeout is 120 seconds
Host1: imap connection keepalive is on on host1. Use –nokeepalive1 to disable it.
Host2: imap connection keepalive is on on host2. Use –nokeepalive2 to disable it.
Host1: IMAP server [moose.mxrouting.net] port [993] user [rfk@mydomain.tld]
Host2: IMAP server [localhost] port [993] user [rfk@myotherdomain.tldiu2zkvhtymeeud0d@mailcow.local]
Host1: connecting and login on host1 [moose.mxrouting.net] port [993] with user [rfk@mydomain.tld]
Connecting with IO::Socket::SSL PeerAddr moose.mxrouting.net PeerPort 993 Proto tcp Timeout 120 Debug 1 SSL_verify_mode 0 SSL_verifycn_scheme imap SSL_cipher_list DEFAULT:!DH
DEBUG: …/IO/Socket/SSL.pm:3010: new ctx 93940562209040
DEBUG: …/IO/Socket/SSL.pm:762: socket not yet connected
DEBUG: …/IO/Socket/SSL.pm:1177: global error: Undefined SSL object
DEBUG: …/IO/Socket/SSL.pm:1177: global error: Undefined SSL object
DEBUG: …/IO/Socket/SSL.pm:764: socket connected
DEBUG: …/IO/Socket/SSL.pm:787: ssl handshake not started
DEBUG: …/IO/Socket/SSL.pm:829: using SNI with hostname moose.mxrouting.net
DEBUG: …/IO/Socket/SSL.pm:880: set socket to non-blocking to enforce timeout=120
DEBUG: …/IO/Socket/SSL.pm:894: call Net::SSLeay::connect
DEBUG: …/IO/Socket/SSL.pm:897: done Net::SSLeay::connect -> -1
DEBUG: …/IO/Socket/SSL.pm:907: ssl handshake in progress
DEBUG: …/IO/Socket/SSL.pm:917: waiting for fd to become ready: SSL wants a read first
DEBUG: …/IO/Socket/SSL.pm:937: socket ready, retrying connect
DEBUG: …/IO/Socket/SSL.pm:894: call Net::SSLeay::connect
DEBUG: …/IO/Socket/SSL.pm:897: done Net::SSLeay::connect -> 1
DEBUG: …/IO/Socket/SSL.pm:952: ssl handshake done
Connected to moose.mxrouting.net
Read: * OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ AUTH=PLAIN] Dovecot DA ready.
Host1 IP address: 149.56.31.210
Host1 banner: * OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ AUTH=PLAIN] Dovecot DA ready.
Sending: 1 CAPABILITY
Sent 14 bytes
Read: * CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ AUTH=PLAIN
Read: 1 OK Pre-login capabilities listed, post-login capabilities have more.
Host1 capability before authentication: IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ AUTH=PLAIN AUTH
Sending: 2 LOGIN rfk@mydomain.tld [Redact: Count=2 Showcredentials=OFF]
Sent 37 bytes
ERROR: socket closed while reading data from server at /usr/share/perl5/Mail/IMAPClient.pm line 1705.
Mail::IMAPClient::read_line(Mail::IMAPClient=HASH(0×55703eab4478)) called at /usr/share/perl5/Mail/IMAPClient.pm line 1406
Mail::IMAPClient::get_response(Mail::IMAPClient=HASH(0×55703eab4478), 2, undef) called at /usr/share/perl5/Mail/IMAPClient.pm line 1340
Mail::IMAPClient::imap_command_do(Mail::IMAPClient=HASH(0×55703eab4478), “LOGIN rfk@mydomain.tld ********”) called at /usr/share/perl5/Mail/IMAPClient.pm line 1238
Mail::IMAPClient::imap_command(Mail::IMAPClient=HASH(0×55703eab4478), “LOGIN rfk@mydomain.tld *****”) called at /usr/share/perl5/Mail/IMAPClient.pm line 591
Mail::IMAPClient::login(Mail::IMAPClient=HASH(0×55703eab4478)) called at /usr/local/bin/imapsync line 8211
main::authenticate_imap(Mail::IMAPClient=HASH(0×55703eab4478), “moose.mxrouting.net”, 993, “rfk@mydomain.tld”, “**”, 1, undef, 1, …) called at /usr/local/bin/imapsync line 8085
main::login_imap(“moose.mxrouting.net”, 993, “rfk@mydomain.tld”, “*****”, 1, undef, 1, 100, …) called at /usr/local/bin/imapsync line 2020
main::single_sync(HASH(0×55703ea7fae8), HASH(0×55703cbce4d8), HASH(0×55703ea8c6b8)) called at /usr/local/bin/imapsync line 1350
reconnecting to moose.mxrouting.net, last error: socket closed while reading data from server
Connecting with IO::Socket::SSL PeerAddr moose.mxrouting.net PeerPort 993 Proto tcp Timeout 120 Debug 1 SSL_verify_mode 0 SSL_verifycn_scheme imap SSL_cipher_list DEFAULT:!DH
DEBUG: …/IO/Socket/SSL.pm:3010: new ctx 93940561463808
DEBUG: …/IO/Socket/SSL.pm:762: socket not yet connected
DEBUG: …/IO/Socket/SSL.pm:1177: global error: Undefined SSL object
DEBUG: …/IO/Socket/SSL.pm:1177: global error: Undefined SSL object
DEBUG: …/IO/Socket/SSL.pm:764: socket connected
DEBUG: …/IO/Socket/SSL.pm:787: ssl handshake not started
DEBUG: …/IO/Socket/SSL.pm:829: using SNI with hostname moose.mxrouting.net
DEBUG: …/IO/Socket/SSL.pm:880: set socket to non-blocking to enforce timeout=120
DEBUG: …/IO/Socket/SSL.pm:894: call Net::SSLeay::connect
DEBUG: …/IO/Socket/SSL.pm:897: done Net::SSLeay::connect -> -1
DEBUG: …/IO/Socket/SSL.pm:907: ssl handshake in progress
DEBUG: …/IO/Socket/SSL.pm:917: waiting for fd to become ready: SSL wants a read first
DEBUG: …/IO/Socket/SSL.pm:937: socket ready, retrying connect
DEBUG: …/IO/Socket/SSL.pm:894: call Net::SSLeay::connect
DEBUG: …/IO/Socket/SSL.pm:897: done Net::SSLeay::connect -> -1
DEBUG: …/IO/Socket/SSL.pm:907: ssl handshake in progress
DEBUG: …/IO/Socket/SSL.pm:917: waiting for fd to become ready: SSL wants a read first
DEBUG: …/IO/Socket/SSL.pm:937: socket ready, retrying connect
DEBUG: …/IO/Socket/SSL.pm:894: call Net::SSLeay::connect
DEBUG: …/IO/Socket/SSL.pm:897: done Net::SSLeay::connect -> 1
DEBUG: …/IO/Socket/SSL.pm:952: ssl handshake done
Connected to moose.mxrouting.net
DEBUG: …/IO/Socket/SSL.pm:3059: free ctx 93940562209040 open=93940561463808 93940562209040
DEBUG: …/IO/Socket/SSL.pm:3070: OK free ctx 93940562209040
Read: * OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ AUTH=PLAIN] Dovecot DA ready.
Sending: 3 LOGIN rfk@mydomain.tld [Redact: Count=3 Showcredentials=OFF]
Sent 37 bytes
Read: 3 OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS BINARY MOVE SNIPPET=FUZZY PREVIEW=FUZZY PREVIEW STATUS=SIZE SAVEDATE LITERAL+ NOTIFY SPECIAL-USE QUOTA] Logged in
reconnect success(1) on try #⅓
Sending: 4 LOGIN rfk@mydomain.tld ********
Sent 37 bytes
Read: 4 BAD Error in IMAP command LOGIN: Unknown command (0.001 + 0.000 secs).
ERROR: 4 BAD Error in IMAP command LOGIN: Unknown command (0.001 + 0.000 secs). at /usr/share/perl5/Mail/IMAPClient.pm line 1378.
Mail::IMAPClient::ANON(“4 BAD Error in IMAP command LOGIN: Unknown command (0.001 + 0”…) called at /usr/share/perl5/Mail/IMAPClient.pm line 1414
Mail::IMAPClient::get_response(Mail::IMAPClient=HASH(0×55703eab4478), 4, undef) called at /usr/share/perl5/Mail/IMAPClient.pm line 1340
Mail::IMAPClient::imap_command_do(Mail::IMAPClient=HASH(0×55703eab4478), “LOGIN rfk@mydomain.tld ********”) called at /usr/share/perl5/Mail/IMAPClient.pm line 1238
Mail::IMAPClient::imap_command(Mail::IMAPClient=HASH(0×55703eab4478), “LOGIN rfk@mydomain.tld *****”) called at /usr/share/perl5/Mail/IMAPClient.pm line 591
Mail::IMAPClient::login(Mail::IMAPClient=HASH(0×55703eab4478)) called at /usr/local/bin/imapsync line 8211
main::authenticate_imap(Mail::IMAPClient=HASH(0×55703eab4478), “moose.mxrouting.net”, 993, “rfk@mydomain.tld”, “**”, 1, undef, 1, …) called at /usr/local/bin/imapsync line 8085
main::login_imap(“moose.mxrouting.net”, 993, “rfk@mydomain.tld”, “*****”, 1, undef, 1, 100, …) called at /usr/local/bin/imapsync line 2020
main::single_sync(HASH(0×55703ea7fae8), HASH(0×55703cbce4d8), HASH(0×55703ea8c6b8)) called at /usr/local/bin/imapsync line 1350
ERROR: socket closed while reading data from server; 4 BAD Error in IMAP command LOGIN: Unknown command (0.001 + 0.000 secs). at /usr/share/perl5/Mail/IMAPClient.pm line 1288.
Mail::IMAPClient::imap_command(Mail::IMAPClient=HASH(0×55703eab4478), “LOGIN rfk@mydomain.tld *****”) called at /usr/share/perl5/Mail/IMAPClient.pm line 591
Mail::IMAPClient::login(Mail::IMAPClient=HASH(0×55703eab4478)) called at /usr/local/bin/imapsync line 8211
main::authenticate_imap(Mail::IMAPClient=HASH(0×55703eab4478), “moose.mxrouting.net”, 993, “rfk@mydomain.tld”, “**”, 1, undef, 1, …) called at /usr/local/bin/imapsync line 8085
main::login_imap(“moose.mxrouting.net”, 993, “rfk@mydomain.tld”, “****”, 1, undef, 1, 100, …) called at /usr/local/bin/imapsync line 2020
main::single_sync(HASH(0×55703ea7fae8), HASH(0×55703cbce4d8), HASH(0×55703ea8c6b8)) called at /usr/local/bin/imapsync line 1350
Host1 info: authmech [LOGIN] user [rfk@mydomain.tld] authuser [] IsUnconnected []
Host1 failure: Error login on [moose.mxrouting.net] with user [rfk@mydomain.tld] auth [LOGIN]: socket closed while reading data from server; 4 BAD Error in IMAP command LOGIN: Unknown command (0.001 + 0.000 secs).
Host1: failed login on [moose.mxrouting.net] with user [rfk@mydomain.tld] auth [LOGIN]
DEBUG: …/IO/Socket/SSL.pm:3059: free ctx 93940561463808 open=93940561463808
DEBUG: …/IO/Socket/SSL.pm:3070: OK free ctx 93940561463808
Host2: connecting and login on host2 [localhost] port [993] with user [rfk@myotherdomain.tldiu2zkvhtymeeud0d@mailcow.local]
Connecting with IO::Socket::SSL PeerAddr localhost PeerPort 993 Proto tcp Timeout 120 Debug 1 SSL_verifycn_scheme imap SSL_verify_mode 0 SSL_cipher_list DEFAULT:!DH
DEBUG: …/IO/Socket/SSL.pm:3010: new ctx 93940561463808
DEBUG: …/IO/Socket/SSL.pm:762: socket not yet connected
DEBUG: …/IO/Socket/SSL.pm:1177: global error: Undefined SSL object
DEBUG: …/IO/Socket/SSL.pm:1177: global error: Undefined SSL object
DEBUG: …/IO/Socket/SSL.pm:764: socket connected
DEBUG: …/IO/Socket/SSL.pm:787: ssl handshake not started
DEBUG: …/IO/Socket/SSL.pm:829: using SNI with hostname localhost
DEBUG: …/IO/Socket/SSL.pm:880: set socket to non-blocking to enforce timeout=120
DEBUG: …/IO/Socket/SSL.pm:894: call Net::SSLeay::connect
DEBUG: …/IO/Socket/SSL.pm:897: done Net::SSLeay::connect -> -1
DEBUG: …/IO/Socket/SSL.pm:907: ssl handshake in progress
DEBUG: …/IO/Socket/SSL.pm:917: waiting for fd to become ready: SSL wants a read first
DEBUG: …/IO/Socket/SSL.pm:937: socket ready, retrying connect
DEBUG: …/IO/Socket/SSL.pm:894: call Net::SSLeay::connect
DEBUG: …/IO/Socket/SSL.pm:897: done Net::SSLeay::connect -> 1
DEBUG: …/IO/Socket/SSL.pm:952: ssl handshake done
Connected to localhost
Read: * OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ AUTH=PLAIN AUTH=LOGIN] Dovecot ready.
Host2 IP address: ::1
Host2 banner: * OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ AUTH=PLAIN AUTH=LOGIN] Dovecot ready.
Sending: 1 CAPABILITY
Sent 14 bytes
Read: * CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ AUTH=PLAIN AUTH=LOGIN
Read: 1 OK Pre-login capabilities listed, post-login capabilities have more.
Host2 capability before authentication: IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ AUTH=PLAIN AUTH=LOGIN AUTH
Host2: localhost says it has CAPABILITY for AUTHENTICATE LOGIN
Sending: 2 LOGIN “rfk@myotherdomain.tldiu2zkvhtymeeud0d@mailcow.local” [Redact: Count=2 Showcredentials=OFF]
Sent 89 bytes
Read: * CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS BINARY MOVE SNIPPET=FUZZY PREVIEW=FUZZY PREVIEW STATUS=SIZE SAVEDATE LITERAL+ NOTIFY METADATA SPECIAL-USE COMPRESS=DEFLATE QUOTA ACL RIGHTS=texk
2 OK Logged in
Host2: success login on [localhost] with user [rfk@myotherdomain.tldiu2zkvhtymeeud0d@mailcow.local] auth [LOGIN] or [LOGIN]
Host2 Buffer I/O: 4096
++++ Listing 1 errors encountered during the sync ( avoid this listing with –noerrorsdump ).
Err 1/1: Host1 failure: Error login on [moose.mxrouting.net] with user [rfk@mydomain.tld] auth [LOGIN]: socket closed while reading data from server; 4 BAD Error in IMAP command LOGIN: Unknown command (0.001 + 0.000 secs).
The most frequent error is ERR_AUTHENTICATION_FAILURE_USER1. Check the credentials for rfk@mydomain.tld.
Exiting with return value 161 (EXIT_AUTHENTICATION_FAILURE_USER1) 1/50 nb_errors/max_errors PID 62937
Disconnecting from host2 localhost user2 rfk@myotherdomain.tld*iu2zkvhtymeeud0d@mailcow.local
Sending: 3 LOGOUT
Sent 10 bytes
Read: * BYE Logging out
3 OK Logout completed (0.001 + 0.000 secs).
No log file because of option –nolog
DEBUG: …/IO/Socket/SSL.pm:3059: free ctx 93940561463808 open=93940561463808
DEBUG: …/IO/Socket/SSL.pm:3070: OK free ctx 93940561463808