Oswald Buddenhagen <oswald.buddenha...@gmx.de> writes:
> On Sun, Nov 19, 2023 at 03:38:36PM +0000, tom...@posteo.net wrote:
>>IMAP command 'AUTHENTICATE NTLM
>>TlRMTVNTUAABAAAABwIAAAAAAAAgAAAAAAAAACAAAAA=' returned an error: NO
>>AUTHENTICATE failed.
>>
> can you get a log from offlineimap to see what it is doing? my guess
> would be that it's not using NTLM, either because it doesn't use SASL at
> all or because the mechanism is excluded.
I attach a log produced with the offlineimap flags -d imap and -l
<file>. It managed to produce 13 MB of output and the attached version
is cut, saving the top and bottom output of the original log.
Does it confirm any suspicions maybe?
--
Tomas
2023-11-25 13:27:00 INFO: OfflineImap 8.0.0 starting...
Python: 3.11.2 Platform: linux
Args: /usr/bin/offlineimap -l offlineout.log -d imap
2023-11-25 13:27:00 INFO: imaplib2 v3.05, Python v3.11.2, OpenSSL 3.0.11 19 Sep
2023
2023-11-25 13:27:00 INFO: Debug mode: Forcing to singlethreaded.
2023-11-25 13:27:00 DEBUG: Now debugging for imap: IMAP protocol debugging
2023-11-25 13:27:00 DEBUG: Now debugging for : Other offlineimap related sync
messages
2023-11-25 13:27:00 DEBUG: [imap]: Using authentication mechanisms ['GSSAPI',
'XOAUTH2', 'CRAM-MD5', 'PLAIN', 'LOGIN']
2023-11-25 13:27:00 INFO: *** Processing account KTH
2023-11-25 13:27:00 INFO: Establishing connection to webmail.kth.se:993
(remote_KTH)
2023-11-25 13:27:00 DEBUG: [imap]: remote_KTH: level 'tls_compat', version
'None'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.31 Account sync KTH imaplib2 version
3.05
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.31 Account sync KTH imaplib2 debug
level 5, buffer level 3
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.44 Account sync KTH connected to
webmail.kth.se on port 993
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.44 webmail.kth.se writer starting
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.44 webmail.kth.se reader starting
using poll
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.44 Account sync KTH
_request_push(continuation, welcome, {}) = b'OMJM0'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.44 Account sync KTH
welcome:b'OMJM0'.ready.wait
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.50 webmail.kth.se reader poll =>
[(5, 1)]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.50 webmail.kth.se reader rcvd 53
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.50 webmail.kth.se reader < b'* OK
The Microsoft Exchange IMAP4 service is ready.\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.54 webmail.kth.se handler starting
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.54 webmail.kth.se handler
_put_response(b'* OK The Microsoft Exchange IMAP4 service is ready.')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.54 webmail.kth.se handler
untagged_responses[OK] 0 += ["b'The Microsoft Exchange IMAP4 service is
ready.'"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.54 webmail.kth.se handler
_request_pop(continuation, (False, b'* OK The Microsoft Exchange IMAP4 service
is ready.')) [0] = b'OMJM0'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.54 webmail.kth.se handler
welcome:b'OMJM0'.ready.set
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.55 Account sync KTH
_get_untagged_response(OK) => [b'The Microsoft Exchange IMAP4 service is
ready.']
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.55 webmail.kth.se handler
state_change_free.set
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.55 Account sync KTH state => NONAUTH
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.55 Account sync KTH [async]
CAPABILITY ()
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.55 Account sync KTH
state_change_pending.acquire
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.55 Account sync KTH
state_change_pending.release
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.55 Account sync KTH
_request_push(b'OMJM1', CAPABILITY, {}) = b'OMJM1'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.55 Account sync KTH data=b'OMJM1
CAPABILITY'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.55 Account sync KTH
CAPABILITY:b'OMJM1'.ready.wait
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.55 webmail.kth.se writer > b'OMJM1
CAPABILITY\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.59 webmail.kth.se reader poll =>
[(5, 1)]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.59 webmail.kth.se reader rcvd 161
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.59 webmail.kth.se reader < b'*
CAPABILITY IMAP4 IMAP4rev1 AUTH=PLAIN AUTH=NTLM AUTH=GSSAPI SASL-IR UIDPLUS
MOVE ID UNSELECT CHILDREN IDLE NAMESPACE LITERAL+\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.59 webmail.kth.se reader < b'OMJM1
OK CAPABILITY completed.\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.59 webmail.kth.se handler
_put_response(b'* CAPABILITY IMAP4 IMAP4rev1 AUTH=PLAIN AUTH=NTLM AUTH=GSSAPI
SASL-IR UIDPLUS MOVE ID UNSELECT CHILDREN IDLE NAMESPACE LITERAL+')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.59 webmail.kth.se handler
untagged_responses[CAPABILITY] 0 += ["b'IMAP4 IMAP4rev1 AUTH=PLAIN AUTH=NTLM
AUTH=GSSAPI SASL-IR UIDPLUS MOVE ID UNSEL"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.59 webmail.kth.se handler
_put_response(b'OMJM1 OK CAPABILITY completed.')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.59 webmail.kth.se handler
_request_pop(b'OMJM1', ('OK', [b'CAPABILITY completed.'])) [0] = b'OMJM1'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.59 webmail.kth.se handler
CAPABILITY:b'OMJM1'.ready.set
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.59 Account sync KTH
_get_untagged_response(CAPABILITY) => [b'IMAP4 IMAP4rev1 AUTH=PLAIN AUTH=NTLM
AUTH=GSSAPI SASL-IR UIDPLUS MOVE ID UNSE
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.59 webmail.kth.se handler
state_change_free.set
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.59 Account sync KTH
_untagged_response(OK, ?, CAPABILITY) => [b'IMAP4 IMAP4rev1 AUTH=PLAIN
AUTH=NTLM AUTH=GSSAPI SASL-IR UIDPLUS MOVE ID UNSE
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.59 Account sync KTH CAPABILITY:
('IMAP4', 'IMAP4REV1', 'AUTH=PLAIN', 'AUTH=NTLM', 'AUTH=GSSAPI', 'SASL-IR',
'UIDPLUS', 'MOVE', 'ID', 'UNSELECT', 'CHILDREN', 'IDLE', 'NAMESPACE',
'LITERAL+')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.59 Account sync KTH [async] ID
('("name" "OfflineIMAP" "version" "8.0.0")',)
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.59 Account sync KTH
state_change_pending.acquire
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.59 Account sync KTH
state_change_pending.release
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.59 Account sync KTH
_request_push(b'OMJM2', ID, {}) = b'OMJM2'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.59 Account sync KTH data=b'OMJM2 ID
("name" "OfflineIMAP" "version" "8.0.0")'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.59 Account sync KTH
ID:b'OMJM2'.ready.wait
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.59 webmail.kth.se writer > b'OMJM2
ID ("name" "OfflineIMAP" "version" "8.0.0")\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.63 webmail.kth.se reader poll =>
[(5, 1)]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.63 webmail.kth.se reader rcvd 94
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.63 webmail.kth.se reader < b'* ID
("name" "Microsoft.Exchange.Imap4.Imap4Server" "version" "15.2")\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.63 webmail.kth.se reader < b'OMJM2
OK ID completed\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.63 webmail.kth.se handler
_put_response(b'* ID ("name" "Microsoft.Exchange.Imap4.Imap4Server" "version"
"15.2")')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.63 webmail.kth.se handler
untagged_responses[ID] 0 += ["b'("name" "Microsoft.Exchange.Imap4.Imap4Server"
"version" "15.2")'"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.63 webmail.kth.se handler
_put_response(b'OMJM2 OK ID completed')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.63 webmail.kth.se handler
_request_pop(b'OMJM2', ('OK', [b'ID completed'])) [0] = b'OMJM2'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.63 webmail.kth.se handler
ID:b'OMJM2'.ready.set
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.63 Account sync KTH
_get_untagged_response(ID) => [b'("name" "Microsoft.Exchange.Imap4.Imap4Server"
"version" "15.2")']
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.64 webmail.kth.se handler
state_change_free.set
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.64 Account sync KTH
_untagged_response(OK, ?, ID) => [b'("name"
"Microsoft.Exchange.Imap4.Imap4Server" "version" "15.2")']
2023-11-25 13:27:00 DEBUG: [imap]: Attempting GSSAPI authentication
2023-11-25 13:27:00 DEBUG: [imap]: Attempting PLAIN authentication
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.64 Account sync KTH [sync]
AUTHENTICATE ('PLAIN',)
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.64 Account sync KTH
state_change_pending.acquire
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.64 Account sync KTH
_request_push(b'OMJM3', AUTHENTICATE, {}) = b'OMJM3'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.64 Account sync KTH data=b'OMJM3
AUTHENTICATE PLAIN'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.64 Account sync KTH
_request_push(continuation, b'AUTHENTICATE', {}) = b'OMJM4'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.64 Account sync KTH
b'AUTHENTICATE':b'OMJM4'.ready.wait
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.64 webmail.kth.se writer > b'OMJM3
AUTHENTICATE PLAIN\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.66 webmail.kth.se reader poll =>
[(5, 1)]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.66 webmail.kth.se reader rcvd 4
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.66 webmail.kth.se reader < b'+ \r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.66 webmail.kth.se handler
_put_response(b'+ ')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.66 webmail.kth.se handler
_request_pop(continuation, (True, b'')) [1] = b'OMJM4'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.66 webmail.kth.se handler
b'AUTHENTICATE':b'OMJM4'.ready.set
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.66 Account sync KTH continuation =>
True, b''
2023-11-25 13:27:00 DEBUG: [imap]: __plainhandler: returning tomasn (passwd
hidden for log)
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.66 Account sync KTH
_request_push(continuation, b'AUTHENTICATE', {}) = b'OMJM5'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.66 Account sync KTH write literal
size 24
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.67 Account sync KTH
b'AUTHENTICATE':b'OMJM5'.ready.wait
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.67 webmail.kth.se writer >
b'AHRvbWFzbgBCYWJiZWw4OA==\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.88 webmail.kth.se reader poll =>
[(5, 1)]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.88 webmail.kth.se reader rcvd 34
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.88 webmail.kth.se reader < b'OMJM3
OK AUTHENTICATE completed.\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.88 webmail.kth.se handler
_put_response(b'OMJM3 OK AUTHENTICATE completed.')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.88 webmail.kth.se handler
_request_pop(b'OMJM3', ('OK', [b'AUTHENTICATE completed.'])) [1] = b'OMJM3'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.88 webmail.kth.se handler
AUTHENTICATE:b'OMJM3'.ready.set
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.88 webmail.kth.se handler
_request_pop(continuation, (False, b'OMJM3 OK AUTHENTICATE completed.')) [0] =
b'OMJM5'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.88 webmail.kth.se handler
b'AUTHENTICATE':b'OMJM5'.ready.set
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.88 Account sync KTH continuation =>
False, b'OMJM3 OK AUTHENTICATE completed.'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.88 webmail.kth.se handler
state_change_free.set
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.88 Account sync KTH
AUTHENTICATE:b'OMJM3'.ready.wait
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.88 Account sync KTH state => AUTH
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.89 Account sync KTH
state_change_pending.release
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.89 Account sync KTH [async]
CAPABILITY ()
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.89 Account sync KTH
state_change_pending.acquire
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.89 Account sync KTH
state_change_pending.release
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.89 Account sync KTH
_request_push(b'OMJM6', CAPABILITY, {}) = b'OMJM6'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.89 Account sync KTH data=b'OMJM6
CAPABILITY'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.89 Account sync KTH
CAPABILITY:b'OMJM6'.ready.wait
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.89 webmail.kth.se writer > b'OMJM6
CAPABILITY\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.90 webmail.kth.se reader poll =>
[(5, 1)]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.90 webmail.kth.se reader rcvd 199
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.90 webmail.kth.se reader < b'*
CAPABILITY IMAP4 IMAP4rev1 AUTH=PLAIN AUTH=NTLM AUTH=GSSAPI SASL-IR UIDPLUS
MOVE ID UNSELECT XPROXY3 CLIENTNETWORKPRESENCELOCATION CHILDREN IDLE NAMESPACE
LITERAL+\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.90 webmail.kth.se reader < b'OMJM6
OK CAPABILITY completed.\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.91 webmail.kth.se handler
_put_response(b'* CAPABILITY IMAP4 IMAP4rev1 AUTH=PLAIN AUTH=NTLM AUTH=GSSAPI
SASL-IR UIDPLUS MOVE ID UNSELECT XPROXY3 CLIENTNETWORKPRESENCELOCATION CHILDREN
IDLE NAMESPACE LITERAL+')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.91 webmail.kth.se handler
untagged_responses[CAPABILITY] 0 += ["b'IMAP4 IMAP4rev1 AUTH=PLAIN AUTH=NTLM
AUTH=GSSAPI SASL-IR UIDPLUS MOVE ID UNSEL"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.91 webmail.kth.se handler
_put_response(b'OMJM6 OK CAPABILITY completed.')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.91 webmail.kth.se handler
_request_pop(b'OMJM6', ('OK', [b'CAPABILITY completed.'])) [0] = b'OMJM6'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.91 webmail.kth.se handler
CAPABILITY:b'OMJM6'.ready.set
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.91 Account sync KTH
_get_untagged_response(CAPABILITY) => [b'IMAP4 IMAP4rev1 AUTH=PLAIN AUTH=NTLM
AUTH=GSSAPI SASL-IR UIDPLUS MOVE ID UNSE
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.91 webmail.kth.se handler
state_change_free.set
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.91 Account sync KTH
_untagged_response(OK, ?, CAPABILITY) => [b'IMAP4 IMAP4rev1 AUTH=PLAIN
AUTH=NTLM AUTH=GSSAPI SASL-IR UIDPLUS MOVE ID UNSE
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.91 Account sync KTH [async] LIST
('""', '""')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.91 Account sync KTH
state_change_pending.acquire
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.91 Account sync KTH
state_change_pending.release
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.91 Account sync KTH
_request_push(b'OMJM7', LIST, {}) = b'OMJM7'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.91 Account sync KTH data=b'OMJM7
LIST "" ""'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.91 Account sync KTH
LIST:b'OMJM7'.ready.wait
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.91 webmail.kth.se writer > b'OMJM7
LIST "" ""\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.93 webmail.kth.se reader poll =>
[(5, 1)]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.93 webmail.kth.se reader rcvd 66
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.93 webmail.kth.se reader < b'* LIST
(\\Noselect \\HasChildren) "/" ""\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.93 webmail.kth.se reader < b'OMJM7
OK LIST completed.\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.93 webmail.kth.se handler
_put_response(b'* LIST (\\Noselect \\HasChildren) "/" ""')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.93 webmail.kth.se handler
untagged_responses[LIST] 0 += ["b'(\\Noselect \\HasChildren) "/" ""'"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.93 webmail.kth.se handler
_put_response(b'OMJM7 OK LIST completed.')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.93 webmail.kth.se handler
_request_pop(b'OMJM7', ('OK', [b'LIST completed.'])) [0] = b'OMJM7'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.93 webmail.kth.se handler
LIST:b'OMJM7'.ready.set
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.93 Account sync KTH
_get_untagged_response(LIST) => [b'(\\Noselect \\HasChildren) "/" ""']
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.93 webmail.kth.se handler
state_change_free.set
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.93 Account sync KTH
_untagged_response(OK, ?, LIST) => [b'(\\Noselect \\HasChildren) "/" ""']
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.93 Account sync KTH [async] LIST
('""', '"*"')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.93 Account sync KTH
state_change_pending.acquire
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.93 Account sync KTH
state_change_pending.release
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.93 Account sync KTH
_request_push(b'OMJM8', LIST, {}) = b'OMJM8'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.93 Account sync KTH data=b'OMJM8
LIST "" "*"'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.93 Account sync KTH
LIST:b'OMJM8'.ready.wait
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.93 webmail.kth.se writer > b'OMJM8
LIST "" "*"\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.98 webmail.kth.se reader poll =>
[(5, 1)]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.98 webmail.kth.se reader rcvd 840
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.98 webmail.kth.se reader < b'* LIST
(\\HasNoChildren) "/" Calendar\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se reader < b'* LIST
(\\HasChildren) "/" Contacts\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
_put_response(b'* LIST (\\HasNoChildren) "/" Calendar')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se reader < b'* LIST
(\\HasNoChildren \\Trash) "/" "Deleted Items"\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se reader < b'* LIST
(\\HasNoChildren \\Drafts) "/" Drafts\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se reader < b'* LIST
(\\Marked \\HasChildren) "/" INBOX\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se reader < b'* LIST
(\\HasChildren) "/" INBOX/MAIL1\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se reader < b'* LIST
(\\HasNoChildren) "/" INBOX/MAIL1/Drafts\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se reader < b'* LIST
(\\HasNoChildren) "/" INBOX/MAIL1/Sent\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se reader < b'* LIST
(\\HasNoChildren) "/" INBOX/MAIL1/Trash\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se reader < b'* LIST
(\\HasNoChildren) "/" Journal\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se reader < b'* LIST
(\\HasNoChildren \\Junk) "/" "Junk E-Mail"\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
untagged_responses[LIST] 0 += ["b'(\\HasNoChildren) "/" Calendar'"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se reader < b'* LIST
(\\HasNoChildren) "/" Notes\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se reader < b'* LIST
(\\HasNoChildren) "/" Outbox\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se reader < b'* LIST
(\\HasNoChildren) "/" Papperskorg\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se reader < b'* LIST
(\\HasNoChildren) "/" Sent\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se reader < b'* LIST
(\\HasNoChildren \\Sent) "/" "Sent Items"\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se reader < b'* LIST
(\\HasNoChildren) "/" Skickat\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
_put_response(b'* LIST (\\HasChildren) "/" Contacts')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se reader < b'* LIST
(\\HasNoChildren) "/" Tasks\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
untagged_responses[LIST] 1 += ["b'(\\HasChildren) "/" Contacts'"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se reader < b'* LIST
(\\HasNoChildren) "/" Trash\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se reader < b'* LIST
(\\HasNoChildren) "/" Utkast\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se reader < b'OMJM8
OK LIST completed.\r\n'
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
_put_response(b'* LIST (\\HasNoChildren \\Trash) "/" "Deleted Items"')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
untagged_responses[LIST] 2 += ["b'(\\HasNoChildren \\Trash) "/" "Deleted
Items"'"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
_put_response(b'* LIST (\\HasNoChildren \\Drafts) "/" Drafts')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
untagged_responses[LIST] 3 += ["b'(\\HasNoChildren \\Drafts) "/" Drafts'"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
_put_response(b'* LIST (\\Marked \\HasChildren) "/" INBOX')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
untagged_responses[LIST] 4 += ["b'(\\Marked \\HasChildren) "/" INBOX'"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
_put_response(b'* LIST (\\HasChildren) "/" INBOX/MAIL1')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
untagged_responses[LIST] 5 += ["b'(\\HasChildren) "/" INBOX/MAIL1'"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
_put_response(b'* LIST (\\HasNoChildren) "/" INBOX/MAIL1/Drafts')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
untagged_responses[LIST] 6 += ["b'(\\HasNoChildren) "/" INBOX/MAIL1/Drafts'"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
_put_response(b'* LIST (\\HasNoChildren) "/" INBOX/MAIL1/Sent')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
untagged_responses[LIST] 7 += ["b'(\\HasNoChildren) "/" INBOX/MAIL1/Sent'"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
_put_response(b'* LIST (\\HasNoChildren) "/" INBOX/MAIL1/Trash')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
untagged_responses[LIST] 8 += ["b'(\\HasNoChildren) "/" INBOX/MAIL1/Trash'"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
_put_response(b'* LIST (\\HasNoChildren) "/" Journal')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
untagged_responses[LIST] 9 += ["b'(\\HasNoChildren) "/" Journal'"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
_put_response(b'* LIST (\\HasNoChildren \\Junk) "/" "Junk E-Mail"')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
untagged_responses[LIST] 10 += ["b'(\\HasNoChildren \\Junk) "/" "Junk E-Mail"'"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
_put_response(b'* LIST (\\HasNoChildren) "/" Notes')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
untagged_responses[LIST] 11 += ["b'(\\HasNoChildren) "/" Notes'"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
_put_response(b'* LIST (\\HasNoChildren) "/" Outbox')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
untagged_responses[LIST] 12 += ["b'(\\HasNoChildren) "/" Outbox'"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
_put_response(b'* LIST (\\HasNoChildren) "/" Papperskorg')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
untagged_responses[LIST] 13 += ["b'(\\HasNoChildren) "/" Papperskorg'"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
_put_response(b'* LIST (\\HasNoChildren) "/" Sent')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
untagged_responses[LIST] 14 += ["b'(\\HasNoChildren) "/" Sent'"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
_put_response(b'* LIST (\\HasNoChildren \\Sent) "/" "Sent Items"')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
untagged_responses[LIST] 15 += ["b'(\\HasNoChildren \\Sent) "/" "Sent Items"'"]
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
_put_response(b'* LIST (\\HasNoChildren) "/" Skickat')
2023-11-25 13:27:00 DEBUG: [imap]: 27:00.99 webmail.kth.se handler
untagged_responses[LIST] 16 += ["b'(\\HasNoChildren) "/" Skickat'"]
2023-11-25 13:27:01 DEBUG: [imap]: 27:01.00 webmail.kth.se handler
_put_response(b'* LIST (\\HasNoChildren) "/" Tasks')
2023-11-25 13:27:01 DEBUG: [imap]: 27:01.00 webmail.kth.se handler
untagged_responses[LIST] 17 += ["b'(\\HasNoChildren) "/" Tasks'"]
2023-11-25 13:27:01 DEBUG: [imap]: 27:01.00 webmail.kth.se handler
_put_response(b'* LIST (\\HasNoChildren) "/" Trash')
2023-11-25 13:27:01 DEBUG: [imap]: 27:01.00 webmail.kth.se handler
untagged_responses[LIST] 18 += ["b'(\\HasNoChildren) "/" Trash'"]
2023-11-25 13:27:01 DEBUG: [imap]: 27:01.00 webmail.kth.se handler
_put_response(b'* LIST (\\HasNoChildren) "/" Utkast')
2023-11-25 13:27:01 DEBUG: [imap]: 27:01.00 webmail.kth.se handler
untagged_responses[LIST] 19 += ["b'(\\HasNoChildren) "/" Utkast'"]
2023-11-25 13:27:01 DEBUG: [imap]: 27:01.00 webmail.kth.se handler
_put_response(b'OMJM8 OK LIST completed.')
2023-11-25 13:27:01 DEBUG: [imap]: 27:01.00 webmail.kth.se handler
_request_pop(b'OMJM8', ('OK', [b'LIST completed.'])) [0] = b'OMJM8'
2023-11-25 13:27:01 DEBUG: [imap]: 27:01.00 webmail.kth.se handler
LIST:b'OMJM8'.ready.set
2023-11-25 13:27:01 DEBUG: [imap]: 27:01.00 webmail.kth.se handler
state_change_free.set
2023-11-25 13:27:01 DEBUG: [imap]: 27:01.00 Account sync KTH
_get_untagged_response(LIST) => [b'(\\HasNoChildren) "/" Calendar',
b'(\\HasChildren) "/" Contacts', b'(\\HasNoC
2023-11-25 13:27:01 DEBUG: [imap]: 27:01.00 Account sync KTH
_untagged_response(OK, ?, LIST) => [b'(\\HasNoChildren) "/" Calendar',
b'(\\HasChildren) "/" Contacts', b'(\\HasNoC
2023-11-25 13:27:01 DEBUG: []: Filtering out 'Calendar'[remote_KTH] due to
folderfilter
2023-11-25 13:27:01 DEBUG: []: Filtering out 'Contacts'[remote_KTH] due to
folderfilter
2023-11-25 13:27:01 DEBUG: []: Filtering out 'Deleted Items'[remote_KTH] due to
folderfilter
2023-11-25 13:27:01 DEBUG: []: Filtering out 'Drafts'[remote_KTH] due to
folderfilter
2023-11-25 13:27:01 DEBUG: []: Filtering out 'INBOX/MAIL1'[remote_KTH] due to
folderfilter
2023-11-25 13:27:01 DEBUG: []: Filtering out 'INBOX/MAIL1/Drafts'[remote_KTH]
due to folderfilter
2023-11-25 13:27:01 DEBUG: []: Filtering out 'INBOX/MAIL1/Sent'[remote_KTH] due
to folderfilter
2023-11-25 13:27:01 DEBUG: []: Filtering out 'INBOX/MAIL1/Trash'[remote_KTH]
due to folderfilter
2023-11-25 13:27:01 DEBUG: []: Filtering out 'Journal'[remote_KTH] due to
folderfilter
2023-11-25 13:27:01 DEBUG: []: Filtering out 'Junk E-Mail'[remote_KTH] due to
folderfilter
2023-11-25 13:27:01 DEBUG: []: Filtering out 'Notes'[remote_KTH] due to
folderfilter
2023-11-25 13:27:01 DEBUG: []: Filtering out 'Outbox'[remote_KTH] due to
folderfilter
2023-11-25 13:27:01 DEBUG: []: Filtering out 'Papperskorg'[remote_KTH] due to
folderfilter
2023-11-25 13:27:01 DEBUG: []: Filtering out 'Sent Items'[remote_KTH] due to
folderfilter
2023-11-25 13:27:01 DEBUG: []: Filtering out 'Skickat'[remote_KTH] due to
folderfilter
2023-11-25 13:27:01 DEBUG: []: Filtering out 'Tasks'[remote_KTH] due to
folderfilter
2023-11-25 13:27:01 DEBUG: []: Filtering out 'Trash'[remote_KTH] due to
folderfilter
2023-11-25 13:27:01 DEBUG: []: Filtering out 'Utkast'[remote_KTH] due to
folderfilter
2023-11-25 13:27:01 DEBUG: []: Copying folder structure from remote_KTH to
local_KTH
2023-11-25 13:27:01 DEBUG: []: Not syncing filtered folder
'Calendar'[remote_KTH]
2023-11-25 13:27:01 DEBUG: []: Not syncing filtered folder
'Contacts'[remote_KTH]
2023-11-25 13:27:01 DEBUG: []: Not syncing filtered folder 'Deleted
Items'[remote_KTH]
2023-11-25 13:27:01 DEBUG: []: Not syncing filtered folder 'Drafts'[remote_KTH]
2023-11-25 13:27:01 INFO: Syncing INBOX: IMAP -> Maildir
2023-11-25 13:27:01 DEBUG: Loading message list for Maildir[INBOX]
2023-11-25 13:27:01 DEBUG: Message list for Maildir[INBOX] loaded: 4441 messages
...
2023-11-25 13:27:25 DEBUG: []: Not syncing filtered folder 'Sent
Items'[remote_KTH]
2023-11-25 13:27:25 DEBUG: []: Not syncing filtered folder 'Skickat'[remote_KTH]
2023-11-25 13:27:25 DEBUG: []: Not syncing filtered folder 'Tasks'[remote_KTH]
2023-11-25 13:27:25 DEBUG: []: Not syncing filtered folder 'Trash'[remote_KTH]
2023-11-25 13:27:25 DEBUG: []: Not syncing filtered folder 'Utkast'[remote_KTH]
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.04 Account sync KTH state => LOGOUT
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.04 Account sync KTH [sync] LOGOUT ()
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.04 Account sync KTH
state_change_pending.acquire
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.04 Account sync KTH
_request_push(b'OMJM37', LOGOUT, {}) = b'OMJM37'
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.04 Account sync KTH data=b'OMJM37
LOGOUT'
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.04 Account sync KTH
LOGOUT:b'OMJM37'.ready.wait
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.05 webmail.kth.se writer > b'OMJM37
LOGOUT\r\n'
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.06 webmail.kth.se reader poll =>
[(5, 1)]
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.06 webmail.kth.se reader rcvd 93
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.06 webmail.kth.se reader < b'* BYE
Microsoft Exchange Server 2016 IMAP4 server signing off.\r\n'
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.06 webmail.kth.se reader < b'OMJM37
OK LOGOUT completed.\r\n'
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.06 webmail.kth.se handler
_put_response(b'* BYE Microsoft Exchange Server 2016 IMAP4 server signing off.')
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.06 webmail.kth.se handler
untagged_responses[BYE] 0 += ["b'Microsoft Exchange Server 2016 IMAP4 server
signing off.'"]
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.06 webmail.kth.se handler BYE
response: b'Microsoft Exchange Server 2016 IMAP4 server signing off.'
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.06 webmail.kth.se handler
terminating: 'connection terminated'
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.06 webmail.kth.se handler
LOGOUT:b'OMJM37'.ready.set
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.06 Account sync KTH ["<class
'imaplib2.IMAP4.abort'>: command: LOGOUT => connection terminated"]
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.06 webmail.kth.se writer finished
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.06 webmail.kth.se handler
state_change_free.set
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.06 Account sync KTH _close_threads
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.06 webmail.kth.se reader poll =>
[(5, 1)]
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.06 webmail.kth.se handler finished
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.06 Account sync KTH call shutdown
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.06 webmail.kth.se reader rcvd 0
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.16 webmail.kth.se reader finished
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.16 Account sync KTH
state_change_pending.release
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.16 Account sync KTH connection closed
2023-11-25 13:27:25 DEBUG: [imap]: 27:25.16 Account sync KTH
_get_untagged_response(BYE) => [b'Microsoft Exchange Server 2016 IMAP4 server
signing off.']
2023-11-25 13:27:25 INFO: *** Finished account 'KTH' in 0:24
_______________________________________________
isync-devel mailing list
isync-devel@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/isync-devel
