How to get greater log detail from Mail.app in Lion (slow attachment caching)
I have an IMAP account, hosted by FuseMail, that is taking an extraordinary amount of time to catch attachments. I would say it is taking upwards of 10 minutes to cache one attachment. This is causing a side-effect in mail that it keeps spawning new threads for "Fetching new mail" which eventually makes Mail.app thread-bound and it stops responding (see image below of an example of what happened over night when I wasn't there to kill the treads).
I would like to see a more detailed trascript of the connection with this IMAP server to see if the delay is with the server or with my client. However, I have been unable to capture what I'd like to see:
If I use the Connection Doctor, turn off all other accounts and look at "Show Detail", I can see some log entries for READS and WRITES (FETCH and PEEK) but it's still difficult to see what's happening between those steps:
WROTE Sep 17 12:47:39.874 [kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.socialogue.com -- port:993 -- socket:0x7f8d32e16d30 -- thread:0x7f8d345d7870
84.46 UID FETCH 7330 BODY.PEEK[2]<4110304.16384>
WROTE Sep 17 12:47:46.098 [kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:mail.me.com -- port:993 -- socket:0x7f8d348b91a0 -- thread:0x7f8d34b57490
DONE
READ Sep 17 12:47:46.101 [kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.socialogue.com -- port:993 -- socket:0x7f8d32e16d30 -- thread:0x7f8d345d7870
* 6869 FETCH (UID 7330 BODY[2]<4110304> {16384}
READ Sep 17 12:47:46.150 [kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.socialogue.com -- port:993 -- socket:0x7f8d32e16d30 -- thread:0x7f8d345d7870
Lvrf+t3/AIKHf8EyPgF/wUD/AGDPFX7BusaZ4f8Ag94JTT9Pj8Cahofh6ydf
hzfaeFGnzWFqyCOGKONTavFAYGeynubdJYRLvX8gf2Nv2Iv+DgX9hD/gmj4O/YC/Z81n/glBpPjb
<----8X----snip----8X----->
7aHwA/4Kf/sXfEv4P/Cn9tD4d6h4b1ZvD3jfQpP+EV+IV9o+rW91Y3Oo3enBb6GWOBZIJJNly08N
tYW6G0WIzHoP2kP+CZP/AAU1/wCCruj6P8Ef+Cm/7TPwA/Zq/Y40/wDs2+1jwJ+zTLql3qnx
READ Sep 17 12:47:46.158 [kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:mail.me.com -- port:993 -- socket:0x7f8d348b91a0 -- thread:0x7f8d34b57490
47.47 OK Completed
READ Sep 17 12:47:46.195 [kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.socialogue.com -- port:993 -- socket:0x7f8d32e16d30 -- thread:0x7f8d345d7870
O1RL
ACWTUtU1y3RLO0t9SiS8tbNLS6QpN5c7ST2ltfD9n/gp+yZ8G/2Q/gFqHwJ/Yi+GHwf/AGa9Jh09
<----8X----snip----8X----->
hG4+MHwt1nxKNc0bwh5Uaahc2mlTaVFFJ4guZTc3Umoi9thJJcylI7W4Zb6L9Hv+CZ//AARa/YM/
4JR6drd5+zD8P/EGqfFbV9POk678
WROTE Sep 17 12:47:46.206 [kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:mail.me.com -- port:993 -- socket:0x7f8d348b91a0 -- thread:0x7f8d377d87f0
48.47 NOOP
READ Sep 17 12:47:46.216 [kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.socialogue.com -- port:993 -- socket:0x7f8d32e16d30 -- thread:0x7f8d345d7870
QfFuojUvEWr2P2qS5W2LokdtaxBmiVo7OC3WYWlq04mkiSQf
gD/wXk/4N6f+Ckn/AAVl/bzu/wBoz4a/E39iDwX8HNF8H6R4K8H2uua1r1nrMljAZryeS/SHTbmA
<----8X----snip----8X----->
9qTxB8ULbwP8UNP+KPgD4UfDnxfqa+AoPEVna+XB4g1GK5t7eS51BJDEIxDHCESyVJZbuK4e2iPj
X/wb0/toftvf8FvLD9v/APbt+Jv7MHj/APYv0zxglzpPgTSda1a+vl8K6WkjaL
READ Sep 17 12:47:46.285 [kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.socialogue.com -- port:993 -- socket:0x7f8d32e16d30 -- thread:0x7f8d345d7870
pEmn6pps1j5VzP
Hby6nbJMIZWvdUaLY0y1+/3/AAVz/Yh8Vf8ABRz/AIJ1ftMfsa+BfHHh/wCHXjfxfp+nSaPqurW8
<----8X----snip----8X----->
BYbWKS5uWhgh86QN+UH/AAXp/wCDfXUf+Cwfir4afGb4f/tIeH/gj8VvBng/VNBsNK1bwXa3lj4m
maQ3FnFcanb+XqNrEJ
READ Sep 17 12:47:46.301 [kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.socialogue.com -- port:993 -- socket:0x7f8d32e16d30 -- thread:0x7f8d345d7870
mkVzJ9viiWUyW9rFI119s6DwD/AMG3PwJ8afAnxr8Ov+Cjf7W37X//AAUy
+LWs+H7zQNO8bfEHxbeP/wAK2868W4N74ZtLme6/sq7f7Npomllmu/O+weWQttcXNpL4B/wSW/4N
<----8X----snip----8X----->
UUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUU
UUUUUUUUUUUUUUUUUUUU)
84.46 OK FETCH completed.
WROTE Sep 17 12:47:46.322 [kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.socialogue.com -- port:993 -- socket:0x7f8d32e16d30 -- thread:0x7f8d333741d0
85.46 UID FETCH 7330 BODY.PEEK[2]<4126688.16384>
READ Sep 17 12:47:46.412 [kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:mail.me.com -- port:993 -- socket:0x7f8d348b91a0 -- thread:0x7f8d377d87f0
48.47 OK Completed
WROTE Sep 17 12:47:46.434 [kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:mail.me.com -- port:993 -- socket:0x7f8d348b91a0 -- thread:0x7f8d345d7870
49.47 IDLE
READ Sep 17 12:47:46.579 [kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:mail.me.com -- port:993 -- socket:0x7f8d348b91a0 -- thread:0x7f8d345d7870
+ idling
I just can't see enough to figure out if the problem is on my end or the FuseMail end. I have been looking around trying to figure out how to increase the debug level without much luck.
I found this about the sqlite3 db but the Envelope file is not in the same place anymore:
http://www.macworld.com/article/56673/2007/03/mailfix.html
I also played around with the defaults but could not find anything specific to the connection/transfer/caching protocols:
http://hints.macworld.com/article.php?story=2004101603285984
I also tried the "Turn on Logging" Mail script:
but if it is turning anything on in the Console, I can't see it.
Any suggestons?
MacBook Pro, iPhone, iPad, MobileMe, Mac OS X (10.6.5)