autoarchive
autoarchive copied to clipboard
Published
20 hours ago •
wangvisual
wangvisual
archive "hangs" until I interrupt?
AAA 0.6, Thunderbird 31.3.0 (Windows 7), Dovecot IMAP 1.1.4
I have a set of IMAP folders with a large number of messages that I'm archiving - Auto Archive seems to get "stuck" now and then. Sometimes if I interrupt it ("Stop Now"), it will make more progress on the next iteration, but it now appears to be getting stuck each time it is restarted. I'm wondering if it might be due to being unable to create new output files? (perhaps related to the folder hierarchy? )
The logs don't mean much to me - here's an extract where I started the archive - watched it get "stuck" -- then waited about 10 minutes and "stopped" it -- it then printed out some stats about archiving things - but I'm not sure it actually did archive anything. Running it again appears to make no additional progress - it's still getting stuck:
2015-01-02 11:04:09.956 autoArchiveService doArchive
----------
2015-01-02 11:04:09.962 rules: Array
+ 0 (object) [object Object]
| + enable (boolean) true
| + action (string)'archive'
| + src (string)'imap://[email protected]/INBOX'
| + age (number) 180
| *
+ 1 (object) [object Object]
| + enable (boolean) true
| + action (string)'archive'
| + src (string)'imap://[email protected]/purchases'
| + age (number) 180
| *
+ 2 (object) [object Object]
| + enable (boolean) true
| + action (string)'archive'
| + src (string)'imap://[email protected]/Sent'
| + age (number) 180
| *
+ 3 (object) [object Object]
| + enable (boolean) true
| + action (string)'archive'
| + src (string)'imap://[email protected]/SPOOL'
| + age (number) 180
| *
*
----------
2015-01-02 11:04:09.962 Total 4 rule(s)
----------
2015-01-02 11:04:09.968 this.rules: Array
+ 0 (object) [object Object]
| + enable (boolean) true
| + action (string)'archive'
| + src (string)'imap://[email protected]/INBOX'
| + age (number) 180
| *
+ 1 (object) [object Object]
| + enable (boolean) true
| + action (string)'archive'
| + src (string)'imap://[email protected]/purchases'
| + age (number) 180
| *
+ 2 (object) [object Object]
| + enable (boolean) true
| + action (string)'archive'
| + src (string)'imap://[email protected]/Sent'
| + age (number) 180
| *
+ 3 (object) [object Object]
| + enable (boolean) true
| + action (string)'archive'
| + src (string)'imap://[email protected]/SPOOL'
| + age (number) 180
| *
*
----------
2015-01-02 11:04:09.970 Running rule archive imap://[email protected]/INBOX with filter { age => 180 }
----------
2015-01-02 11:04:09.976 needCheck mail server: [email protected]
----------
2015-01-02 11:04:09.981 Checking if server server1 on line using imap://[email protected]:143/verifyLogon
----------
2015-01-02 11:04:10.100 OnStopRunningUrl: server server1
----------
2015-01-02 11:04:10.100 imap://[email protected]:143 OK
----------
2015-01-02 11:04:10.100 All servers checking done, has bad server? : false
----------
2015-01-02 11:04:10.100 updateFolder imap://[email protected]/INBOX
----------
2015-01-02 11:04:10.122 FolderLoaded imap://[email protected]/INBOX
----------
2015-01-02 11:04:10.123 All FolderLoaded
----------
2015-01-02 11:04:10.123 Searching
imap://[email protected]/INBOX
with
AND (age in days,is greater than,180) AND (status,isn't,)
----------
2015-01-02 11:04:10.177 Total 97 messages hit
----------
2015-01-02 11:04:10.178 skipReason: [object Object]
+ duplicate (number) 0
+ exceed (number) 0
+ cantDelete (number) 0
+ deleted (number) 0
+ srcLocked (number) 0
+ destLocked (number) 0
+ flaged (number) 0
+ unread (number) 0
+ tags (number) 0
+ cantArchive (number) 0
+ offline (number) 0
*
----------
2015-01-02 11:04:10.178 will archive 97 messages, total 5.25M bytes
----------
2015-01-02 11:04:10.178 Need create these folders: [object Object]
*
----------
2015-01-02 11:04:10.178 Start doing archive
----------
2015-01-02 11:04:10.217 BatchMessageMover processNextBatch
----------
2015-01-02 11:04:10.217 add update folders imap://[email protected]/INBOX => imap://[email protected]/Archives/2014/
----------
2015-01-02 11:04:10.218 add update folders imap://[email protected]/INBOX => imap://[email protected]/Archives/2013/
----------
2015-01-02 11:04:10.257 BatchMessageMover processNextBatch
----------
2015-01-02 11:04:10.314 BatchMessageMover processNextBatch
----------
2015-01-02 11:04:10.314 BatchMessageMover OnStopCopy/OnStopRunningUrl
----------
2015-01-02 11:04:10.315 BatchMessageMover Done
----------
2015-01-02 11:04:10.315 updateFolder imap://[email protected]/INBOX
----------
2015-01-02 11:04:10.322 BatchMessageMover OnStopCopy/OnStopRunningUrl exit
----------
2015-01-02 11:04:10.322 BatchMessageMover processNextBatch exit
----------
2015-01-02 11:04:10.322 BatchMessageMover OnStopCopy/OnStopRunningUrl
----------
2015-01-02 11:04:10.323 BatchMessageMover OnStopCopy/OnStopRunningUrl exit
----------
2015-01-02 11:04:10.323 BatchMessageMover OnStopCopy/OnStopRunningUrl
----------
2015-01-02 11:04:10.323 BatchMessageMover OnStopCopy/OnStopRunningUrl exit
----------
2015-01-02 11:04:10.323 BatchMessageMover processNextBatch exit
----------
2015-01-02 11:04:10.323 BatchMessageMover OnStopCopy/OnStopRunningUrl
----------
2015-01-02 11:04:10.323 BatchMessageMover OnStopCopy/OnStopRunningUrl exit
----------
2015-01-02 11:04:10.323 BatchMessageMover OnStopCopy/OnStopRunningUrl
----------
2015-01-02 11:04:10.323 BatchMessageMover OnStopCopy/OnStopRunningUrl exit
----------
2015-01-02 11:04:10.323 BatchMessageMover processNextBatch exit
----------
2015-01-02 11:04:10.939 FolderLoaded imap://[email protected]/INBOX
----------
2015-01-02 11:04:10.939 updateFolder imap://[email protected]/Archives/2014/
----------
2015-01-02 11:04:10.940 updateFolder imap://[email protected]/Archives/2013/
----------
2015-01-02 11:04:10.940 All FolderLoaded
----------
2015-01-02 11:04:10.942 Running rule archive imap://[email protected]/purchases with filter { age => 180 }
----------
2015-01-02 11:04:10.945 updateFolder imap://[email protected]/purchases
----------
2015-01-02 11:04:11.067 FolderLoaded imap://[email protected]/purchases
----------
2015-01-02 11:04:11.067 All FolderLoaded
----------
2015-01-02 11:04:11.068 Searching
imap://[email protected]/purchases
with
AND (age in days,is greater than,180) AND (status,isn't,)
----------
2015-01-02 11:04:11.075 Total 0 messages hit
----------
2015-01-02 11:04:11.076 skipReason: [object Object]
+ duplicate (number) 0
+ exceed (number) 0
+ cantDelete (number) 0
+ deleted (number) 0
+ srcLocked (number) 0
+ destLocked (number) 0
+ flaged (number) 0
+ unread (number) 0
+ tags (number) 0
+ cantArchive (number) 0
+ offline (number) 0
*
----------
2015-01-02 11:04:11.079 Running rule archive imap://[email protected]/Sent with filter { age => 180 }
----------
2015-01-02 11:04:11.082 updateFolder imap://[email protected]/Sent
----------
2015-01-02 11:04:11.195 FolderLoaded imap://[email protected]/Sent
----------
2015-01-02 11:04:11.196 All FolderLoaded
----------
2015-01-02 11:04:11.196 Searching
imap://[email protected]/Sent
with
AND (age in days,is greater than,180) AND (status,isn't,)
----------
2015-01-02 11:04:11.204 Total 0 messages hit
----------
2015-01-02 11:04:11.204 skipReason: [object Object]
+ duplicate (number) 0
+ exceed (number) 0
+ cantDelete (number) 0
+ deleted (number) 0
+ srcLocked (number) 0
+ destLocked (number) 0
+ flaged (number) 0
+ unread (number) 0
+ tags (number) 0
+ cantArchive (number) 0
+ offline (number) 0
*
----------
2015-01-02 11:04:11.208 Running rule archive imap://[email protected]/SPOOL with filter { age => 180 }
----------
2015-01-02 11:04:11.213 updateFolder imap://[email protected]/SPOOL
----------
2015-01-02 11:04:11.216 FolderLoaded imap://[email protected]/SPOOL
----------
2015-01-02 11:04:11.216 updateFolder imap://[email protected]/SPOOL/AA-JUNK-AUTO-CHECKED
----------
2015-01-02 11:04:15.796 FolderLoaded imap://[email protected]/SPOOL/AA-JUNK-AUTO-CHECKED
----------
2015-01-02 11:04:15.796 updateFolder imap://[email protected]/SPOOL/Admin
----------
2015-01-02 11:04:15.897 FolderLoaded imap://[email protected]/SPOOL/Admin
----------
2015-01-02 11:04:15.897 updateFolder imap://[email protected]/SPOOL/Ale
----------
2015-01-02 11:04:18.198 FolderLoaded imap://[email protected]/SPOOL/Ale
----------
2015-01-02 11:04:18.198 updateFolder imap://[email protected]/SPOOL/Fetchmail
----------
2015-01-02 11:04:18.290 FolderLoaded imap://[email protected]/SPOOL/Fetchmail
----------
2015-01-02 11:04:18.290 updateFolder imap://[email protected]/SPOOL/Gmail
----------
2015-01-02 11:04:20.378 FolderLoaded imap://[email protected]/SPOOL/Gmail
----------
2015-01-02 11:04:20.378 updateFolder imap://[email protected]/SPOOL/JUNK-expected
----------
2015-01-02 11:04:20.548 FolderLoaded imap://[email protected]/SPOOL/JUNK-expected
----------
2015-01-02 11:04:20.548 updateFolder imap://[email protected]/SPOOL/JUNK
----------
2015-01-02 11:04:21.119 FolderLoaded imap://[email protected]/SPOOL/JUNK
----------
2015-01-02 11:04:21.119 updateFolder imap://[email protected]/SPOOL/Laurie
----------
2015-01-02 11:04:21.842 FolderLoaded imap://[email protected]/SPOOL/Laurie
----------
2015-01-02 11:04:21.842 updateFolder imap://[email protected]/SPOOL/NOT-JUNK
----------
2015-01-02 11:04:22.122 FolderLoaded imap://[email protected]/SPOOL/NOT-JUNK
----------
2015-01-02 11:04:22.123 updateFolder imap://[email protected]/SPOOL/SPAM
----------
2015-01-02 11:04:22.323 FolderLoaded imap://[email protected]/SPOOL/SPAM
----------
2015-01-02 11:04:22.324 updateFolder imap://[email protected]/SPOOL/SM
----------
2015-01-02 11:04:22.771 FolderLoaded imap://[email protected]/SPOOL/SM
----------
2015-01-02 11:04:22.771 updateFolder imap://[email protected]/SPOOL/Ster
----------
2015-01-02 11:04:22.815 FolderLoaded imap://[email protected]/SPOOL/Ster
----------
2015-01-02 11:04:22.815 updateFolder imap://[email protected]/SPOOL/Sysadmin
----------
2015-01-02 11:04:23.452 FolderLoaded imap://[email protected]/SPOOL/Sysadmin
----------
2015-01-02 11:04:23.452 updateFolder imap://[email protected]/SPOOL/myuser
----------
2015-01-02 11:04:23.745 FolderLoaded imap://[email protected]/SPOOL/myuser
----------
2015-01-02 11:04:23.745 updateFolder imap://[email protected]/SPOOL/Vers
----------
2015-01-02 11:04:23.825 FolderLoaded imap://[email protected]/SPOOL/Vers
----------
2015-01-02 11:04:23.825 updateFolder imap://[email protected]/SPOOL/Voicemail
----------
2015-01-02 11:04:28.477 FolderLoaded imap://[email protected]/SPOOL/Voicemail
----------
2015-01-02 11:04:28.477 updateFolder imap://[email protected]/SPOOL/W2W
----------
2015-01-02 11:04:29.006 FolderLoaded imap://[email protected]/SPOOL/W2W
----------
2015-01-02 11:04:29.006 All FolderLoaded
----------
2015-01-02 11:04:29.018 Searching
imap://[email protected]/SPOOL
imap://[email protected]/SPOOL/AA-JUNK-AUTO-CHECKED
imap://[email protected]/SPOOL/Admin
imap://[email protected]/SPOOL/Ale
imap://[email protected]/SPOOL/Fetchmail
imap://[email protected]/SPOOL/Gmail
imap://[email protected]/SPOOL/JUNK-expected
imap://[email protected]/SPOOL/JUNK
imap://[email protected]/SPOOL/Laurie
imap://[email protected]/SPOOL/NOT-JUNK
imap://[email protected]/SPOOL/SPAM
imap://[email protected]/SPOOL/SM
imap://[email protected]/SPOOL/Ster
imap://[email protected]/SPOOL/Sysadmin
imap://[email protected]/SPOOL/myuser
imap://[email protected]/SPOOL/Vers
imap://[email protected]/SPOOL/Voicemail
imap://[email protected]/SPOOL/W2W
with
AND (age in days,is greater than,180) AND (status,isn't,)
----------
2015-01-02 11:04:32.551 Total 150402 messages hit
----------
2015-01-02 11:04:32.551 skipReason: [object Object]
+ duplicate (number) 0
+ exceed (number) 149999
+ cantDelete (number) 0
+ deleted (number) 0
+ srcLocked (number) 0
+ destLocked (number) 0
+ flaged (number) 0
+ unread (number) 0
+ tags (number) 0
+ cantArchive (number) 0
+ offline (number) 0
*
----------
2015-01-02 11:04:32.551 will archive 403 messages, total 8.26M bytes
----------
2015-01-02 11:04:32.551 Need create these folders: [object Object]
*
----------
2015-01-02 11:04:32.551 Start doing archive
----------
2015-01-02 11:04:32.709 BatchMessageMover processNextBatch
----------
2015-01-02 11:04:32.710 add update folders imap://[email protected]/SPOOL/AA-JUNK-AUTO-CHECKED => imap://[email protected]/Archives/2012/SPOOL/AA-JUNK-AUTO-CHECKED
----------
2015-01-02 11:04:32.710 add update folders imap://[email protected]/SPOOL/AA-JUNK-AUTO-CHECKED => imap://[email protected]/Archives/2009/SPOOL/AA-JUNK-AUTO-CHECKED
----------
2015-01-02 11:04:32.710 add update folders imap://[email protected]/SPOOL/AA-JUNK-AUTO-CHECKED => imap://[email protected]/Archives/2011/SPOOL/AA-JUNK-AUTO-CHECKED
----------
2015-01-02 11:04:32.710 add update folders imap://[email protected]/SPOOL/AA-JUNK-AUTO-CHECKED => imap://[email protected]/Archives/2010/SPOOL/AA-JUNK-AUTO-CHECKED
----------
2015-01-02 11:04:32.710 add update folders imap://[email protected]/SPOOL/AA-JUNK-AUTO-CHECKED => imap://[email protected]/Archives/2008/SPOOL/AA-JUNK-AUTO-CHECKED
----------
2015-01-02 11:04:32.711 add update folders imap://[email protected]/SPOOL/AA-JUNK-AUTO-CHECKED => imap://[email protected]/Archives/2007/SPOOL/AA-JUNK-AUTO-CHECKED
----------
2015-01-02 11:04:32.711 add update folders imap://[email protected]/SPOOL/AA-JUNK-AUTO-CHECKED => imap://[email protected]/Archives/2006/SPOOL/AA-JUNK-AUTO-CHECKED
----------
2015-01-02 11:04:32.713 BatchMessageMover OnStopCopy/OnStopRunningUrl
----------
2015-01-02 11:04:32.713 BatchMessageMover OnStopCopy/OnStopRunningUrl exit
----------
2015-01-02 11:04:32.713 BatchMessageMover processNextBatch exit
Here's where it got stuck. At 11:16, I selected "Stop Now" and then the following showed up on the log:
----------
2015-01-02 11:16:43.217 Proposed to change 500 messages, 13.51M bytes
----------
2015-01-02 11:16:43.218 Limitation reached, set next
----------
2015-01-02 11:16:43.219 Archie: Processed 500 msgs (500 archived)
----------
2015-01-02 11:16:43.232 autoArchiveService closeAllFoldersDB
----------
2015-01-02 11:16:43.232 not close msgDatabase for imap://[email protected]/INBOX
----------
2015-01-02 11:16:43.233 close msgDatabase for imap://[email protected]/Archives/2014/
----------
2015-01-02 11:16:43.234 close msgDatabase for imap://[email protected]/Archives/2013/
----------
2015-01-02 11:16:43.235 close msgDatabase for imap://[email protected]/purchases
----------
2015-01-02 11:16:43.235 not close msgDatabase for imap://[email protected]/Sent
----------
2015-01-02 11:16:43.235 close msgDatabase for imap://[email protected]/SPOOL
----------
2015-01-02 11:16:43.235 close msgDatabase for imap://[email protected]/SPOOL/AA-JUNK-AUTO-CHECKED
----------
2015-01-02 11:16:43.235 close msgDatabase for imap://[email protected]/SPOOL/Admin
----------
2015-01-02 11:16:43.236 close msgDatabase for imap://[email protected]/SPOOL/Ale
----------
2015-01-02 11:16:43.236 close msgDatabase for imap://[email protected]/SPOOL/Fetchmail
----------
2015-01-02 11:16:43.236 close msgDatabase for imap://[email protected]/SPOOL/Gmail
----------
2015-01-02 11:16:43.236 close msgDatabase for imap://[email protected]/SPOOL/JUNK-expected
----------
2015-01-02 11:16:43.236 close msgDatabase for imap://[email protected]/SPOOL/JUNK
----------
2015-01-02 11:16:43.236 close msgDatabase for imap://[email protected]/SPOOL/Laurie
----------
2015-01-02 11:16:43.237 close msgDatabase for imap://[email protected]/SPOOL/NOT-JUNK
----------
2015-01-02 11:16:43.237 close msgDatabase for imap://[email protected]/SPOOL/SPAM
----------
2015-01-02 11:16:43.237 close msgDatabase for imap://[email protected]/SPOOL/SM
----------
2015-01-02 11:16:43.237 close msgDatabase for imap://[email protected]/SPOOL/Ster
----------
2015-01-02 11:16:43.237 not close msgDatabase for imap://[email protected]/SPOOL/Sysadmin
----------
2015-01-02 11:16:43.237 close msgDatabase for imap://[email protected]/SPOOL/myuser
----------
2015-01-02 11:16:43.237 close msgDatabase for imap://[email protected]/SPOOL/Vers
----------
2015-01-02 11:16:43.237 close msgDatabase for imap://[email protected]/SPOOL/Voicemail
----------
2015-01-02 11:16:43.238 close msgDatabase for imap://[email protected]/SPOOL/W2W
----------
2015-01-02 11:16:43.240 close msgDatabase for imap://[email protected]/Archives/2012/SPOOL/AA-JUNK-AUTO-CHECKED
----------
2015-01-02 11:16:43.241 close msgDatabase for imap://[email protected]/Archives/2009/SPOOL/AA-JUNK-AUTO-CHECKED
----------
2015-01-02 11:16:43.242 close msgDatabase for imap://[email protected]/Archives/2011/SPOOL/AA-JUNK-AUTO-CHECKED
----------
2015-01-02 11:16:43.243 close msgDatabase for imap://[email protected]/Archives/2010/SPOOL/AA-JUNK-AUTO-CHECKED
----------
2015-01-02 11:16:43.244 close msgDatabase for imap://[email protected]/Archives/2008/SPOOL/AA-JUNK-AUTO-CHECKED
----------
2015-01-02 11:16:43.245 close msgDatabase for imap://[email protected]/Archives/2007/SPOOL/AA-JUNK-AUTO-CHECKED
----------
2015-01-02 11:16:43.246 close msgDatabase for imap://[email protected]/Archives/2006/SPOOL/AA-JUNK-AUTO-CHECKED
----------
2015-01-02 11:16:43.246 Schedule Disabled
Possibly relevant -- in the Activity Manager dialog, I see the following messages. I don't see a corresponding "error" in the error console, but I wonder if this is related to the hang?
The current operation on 'Inbox' did not succeed. The mail server for account [email protected] responded: Mailbox isn't selectable: Archives/2013.
The current operation on 'Inbox' did not succeed. The mail server for account [email protected] responded: Mailbox isn't selectable: Archives/2014.
The current command did not succeed. The mail server for account [email protected] responded: Mailbox exists.
I've gotten a little further by some combination of the following:
- Unselected "Show Only Subscribed Folders" on the account settings (this helps the archiver to be able to "see" the unsubscribed, but target Archive folders??)
- created empty mailboxes on the IMAP server - I noticed, for example that some "folders" in the archive hierarchy got created as simple files -- so deleting those and mkdir'ing a corresponding folder (then restarting thunderbird to resynch with IMAP) seemed to help.
I'm stuck again in a hang - so far without a clue why it's hanging - but perhaps this additional info will give you a clue about a more general bug that could be fixed to solve this sort of "hang"....?
Seems hang when doing real archive, the archive function was built-in in TB. And if the creation of folder failed, it might hang, as you mentioned 'Mailbox exists.'
So if you select some mails in SPOOL and it's sub folders, and press 'A', will it success? Thanks.
