There is a bug in the email imap proxy

Every so often I get time out errors on both ISP email accounts using IMAPS. At the same time outlook throws certificate errors. If I wait awhile, the error self heals. No it is not a network connection, speed tests show 50/20mbs. I would not mind so much if I was fiddling at the time, but I was elsewhere annoying weeds.

Why am I so sure it is the XG mail proxy, because I have another rule which does not use the mail proxy for the iPhones and iPads and they do not have an issue connecting and collecting messages.

Ian

  • Did you already checked the warren.log in this timeframe?

    Also possible is to keep warren in Debug and check it afterwards. 

  • In reply to LuCar Toni:

    Hi MBP,

    a restore fixed the problem for the moment. Next time I will review the warren.log when I am not under pressure to get it fixed because the tickets for the nights concert are stuck.

    Ian

  • In reply to rfcat_vk:

    Hi MBP,

    again tonight for a short time the mail clients were advising timeout errors. But this time the connection restored, downloaded mail messages.

    tail of warren.log

    ERROR     Sep 23 21:17:56 [4137970496]: relay_connection: Read event on Client  
    ERROR     Sep 23 21:17:56 [4137970496]: relay_connection: Read event on Server  
    ERROR     Sep 23 21:17:56 [4137970496]: relay_connection: Read event on Client  
    ERROR     Sep 23 21:17:56 [4137970496]: relay_connection: Read event on Server  
    ERROR     Sep 23 21:17:56 [4137970496]: relay_connection: Read event on Client  
    ERROR     Sep 23 21:17:56 [4137970496]: relay_connection: Read event on Server  
    ERROR     Sep 23 21:17:56 [4137970496]: relay_connection: Read event on Client  
    ERROR     Sep 23 21:17:56 [4137970496]: relay_connection: Read event on Server  
    ERROR     Sep 23 21:17:56 [4137970496]: relay_connection: Read event on Client  
    ERROR     Sep 23 21:17:56 [4137970496]: relay_connection: Read event on Server 


    Sorry, but doesn't mean much to me, hopefully it will to you?
    Ian
  • In reply to rfcat_vk:

    Not really .. But you could turn on the warren debug with service warren:debug -ds nosync     and let this state running until this issue occurs. But keep an eye on your partition (df -h). Same command disables the debug. 

  • In reply to LuCar Toni:

    I'm seeing this same issue as well. My devices will randomly stop working with IMAP from Gmail and iCloud. It's usually specific to a device. For example, my iPhone might be working just fine but my iPad is having IMAP issues (or my iMac). It's usually only to one IMAP server as well (i.e. Gmail works fine but iCloud does not or vice versa). Here's some lines from my warren.log file with service warren:debug -ds nosync while I am having the issue on my iPhone with Gmail IMAP. In my Mail app on the iPhone, it states "Cannot Get Mail. The mail server map.gmail.com is not responding. Verify that you have entered the correct account info in Mail settings." My iPhone's IP address is 172.16.16.67 and 74.125.28.109 is a Google server for mail services.

    INFO Sep 23 10:53:24 [4124048192]: accept(): protocol IMAP4S: fd: 17
    INFO Sep 23 10:53:24 [4124048192]: 172.16.16.67 === IMAP4S ===> 74.125.28.109
    INFO Sep 23 10:53:24 [4124048192]: thread_tmpfile: /var/tmp/warren_4124048192
    INFO Sep 23 10:53:24 [4124048192]: compare_network_2: l = r Ret 0
    INFO Sep 23 10:53:24 [4124048192]: initialize_conn: POP[1] POPS[1] IMAP[1] IMAPS[1]
    DEBUG Sep 23 10:53:24 [4124048192]: relay_connection: _enter for loop
    ERROR Sep 23 10:53:24 [4124048192]: relay_connection: Read event on Client
    DEBUG Sep 23 10:53:24 [4124048192]: peek '188' bytes from client hello
    DEBUG Sep 23 10:53:24 [4124048192]: Validating Higher Version Client Hello.
    DEBUG Sep 23 10:53:24 [4124048192]: Handshake Version : 769
    DEBUG Sep 23 10:53:24 [4124048192]: Client Hello Version : 771
    INFO Sep 23 10:53:24 [4124048192]: valid client hello
    INFO Sep 23 10:53:24 [4124048192]: h-ver '3' , chel-ver '5'
    INFO Sep 23 10:53:24 [4124048192]: SSL session established with server: '74.125.28.109'
    DEBUG Sep 23 10:53:24 [4124048192]: CommonName: imap.gmail.com
    DEBUG Sep 23 10:53:24 [4124048192]: server certificate OK
    DEBUG Sep 23 10:53:24 [4124048192]: Acquired read-lock.
    DEBUG Sep 23 10:53:24 [4124048192]: certificate for CN('imap.gmail.com') found in cache
    INFO Sep 23 10:53:24 [4124048192]: SSL_accept() failed: error:1408F119:SSL routines:SSL3_GET_RECORD:decryption failed or bad record mac
    DEBUG Sep 23 10:53:24 [4124048192]: SSL_accept() failed: this is last msg!!!
    ERROR Sep 23 10:53:24 [4124048192]: relay_connection: Read event on Client [In break]
    DEBUG Sep 23 10:53:24 [4124048192]: relay_connection: _exit loop
    DEBUG Sep 23 10:53:24 [4124048192]: Freeing client 17
    DEBUG Sep 23 10:53:24 [4124048192]: SSL session with '172.16.16.67' closed
    DEBUG Sep 23 10:53:24 [4124048192]: Freeing client 18
    DEBUG Sep 23 10:53:24 [4124048192]: SSL session with '74.125.28.109' closed
    INFO Sep 23 10:53:24 [4124048192]: fd: 17 closed
    INFO Sep 23 10:53:24 [4120902464]: accept(): protocol IMAP4S: fd: 17
    INFO Sep 23 10:53:24 [4120902464]: 172.16.16.67 === IMAP4S ===> 74.125.28.109
    INFO Sep 23 10:53:24 [4120902464]: thread_tmpfile: /var/tmp/warren_4120902464
    INFO Sep 23 10:53:24 [4120902464]: compare_network_2: l = r Ret 0
    INFO Sep 23 10:53:24 [4120902464]: initialize_conn: POP[1] POPS[1] IMAP[1] IMAPS[1]
    DEBUG Sep 23 10:53:24 [4120902464]: relay_connection: _enter for loop
    ERROR Sep 23 10:53:24 [4120902464]: relay_connection: Read event on Client
    DEBUG Sep 23 10:53:24 [4120902464]: peek '188' bytes from client hello
    DEBUG Sep 23 10:53:24 [4120902464]: Validating Higher Version Client Hello.
    DEBUG Sep 23 10:53:24 [4120902464]: Handshake Version : 769
    DEBUG Sep 23 10:53:24 [4120902464]: Client Hello Version : 771
    INFO Sep 23 10:53:24 [4120902464]: valid client hello
    INFO Sep 23 10:53:24 [4120902464]: h-ver '3' , chel-ver '5'
    INFO Sep 23 10:53:24 [4120902464]: SSL session established with server: '74.125.28.109'
    DEBUG Sep 23 10:53:24 [4120902464]: CommonName: imap.gmail.com
    DEBUG Sep 23 10:53:24 [4120902464]: server certificate OK
    DEBUG Sep 23 10:53:24 [4120902464]: Acquired read-lock.
    DEBUG Sep 23 10:53:24 [4120902464]: certificate for CN('imap.gmail.com') found in cache
    INFO Sep 23 10:53:24 [4120902464]: SSL_accept() failed: error:1408F119:SSL routines:SSL3_GET_RECORD:decryption failed or bad record mac
    DEBUG Sep 23 10:53:24 [4120902464]: SSL_accept() failed: this is last msg!!!
    ERROR Sep 23 10:53:24 [4120902464]: relay_connection: Read event on Client [In break]
    DEBUG Sep 23 10:53:24 [4120902464]: relay_connection: _exit loop
    DEBUG Sep 23 10:53:24 [4120902464]: Freeing client 17
    DEBUG Sep 23 10:53:24 [4120902464]: SSL session with '172.16.16.67' closed
    DEBUG Sep 23 10:53:24 [4120902464]: Freeing client 18
    DEBUG Sep 23 10:53:24 [4120902464]: SSL session with '74.125.28.109' closed
    INFO Sep 23 10:53:24 [4120902464]: fd: 17 closed
    INFO Sep 23 10:53:24 [4126145344]: accept(): protocol IMAP4S: fd: 17
    INFO Sep 23 10:53:24 [4126145344]: 172.16.16.67 === IMAP4S ===> 74.125.28.109
    INFO Sep 23 10:53:24 [4126145344]: thread_tmpfile: /var/tmp/warren_4126145344
    INFO Sep 23 10:53:24 [4126145344]: compare_network_2: l = r Ret 0
    INFO Sep 23 10:53:24 [4126145344]: initialize_conn: POP[1] POPS[1] IMAP[1] IMAPS[1]
    DEBUG Sep 23 10:53:24 [4126145344]: relay_connection: _enter for loop
    ERROR Sep 23 10:53:24 [4126145344]: relay_connection: Read event on Client
    DEBUG Sep 23 10:53:24 [4126145344]: peek '188' bytes from client hello
    DEBUG Sep 23 10:53:24 [4126145344]: Validating Higher Version Client Hello.
    DEBUG Sep 23 10:53:24 [4126145344]: Handshake Version : 769
    DEBUG Sep 23 10:53:24 [4126145344]: Client Hello Version : 771
    INFO Sep 23 10:53:24 [4126145344]: valid client hello
    INFO Sep 23 10:53:24 [4126145344]: h-ver '3' , chel-ver '5'
    INFO Sep 23 10:53:25 [4126145344]: SSL session established with server: '74.125.28.109'
    DEBUG Sep 23 10:53:25 [4126145344]: CommonName: imap.gmail.com
    DEBUG Sep 23 10:53:25 [4126145344]: server certificate OK
    DEBUG Sep 23 10:53:25 [4126145344]: Acquired read-lock.
    DEBUG Sep 23 10:53:25 [4126145344]: certificate for CN('imap.gmail.com') found in cache
    INFO Sep 23 10:53:25 [4126145344]: SSL_accept() failed: error:1408F119:SSL routines:SSL3_GET_RECORD:decryption failed or bad record mac
    DEBUG Sep 23 10:53:25 [4126145344]: SSL_accept() failed: this is last msg!!!
    ERROR Sep 23 10:53:25 [4126145344]: relay_connection: Read event on Client [In break]
    DEBUG Sep 23 10:53:25 [4126145344]: relay_connection: _exit loop
    DEBUG Sep 23 10:53:25 [4126145344]: Freeing client 17
    DEBUG Sep 23 10:53:25 [4126145344]: SSL session with '172.16.16.67' closed
    DEBUG Sep 23 10:53:25 [4126145344]: Freeing client 18
    DEBUG Sep 23 10:53:25 [4126145344]: SSL session with '74.125.28.109' closed
    INFO Sep 23 10:53:25 [4126145344]: fd: 17 closed

    Additionally, similar to rfkat_uk, I'm also seeing these entries in warren.log (with debugging disabled). Any idea what's causing these? I can't imagine something that says ERROR with 10+ entries every second is normal.

    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Server
    ERROR Sep 23 11:01:03 [4140186432]: header 'To' is not registered cache header
    ERROR Sep 23 11:01:03 [4140186432]: header 'Message-ID' is not registered cache header
    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Client
    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Server
    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Server
    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Server
    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Server
    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Server
    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Server
    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Server
    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Server
    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Server
    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Server
    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Server
    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Server
    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Server
    ERROR Sep 23 11:01:03 [4140186432]: header 'To' is not registered cache header
    ERROR Sep 23 11:01:03 [4140186432]: header 'Message-ID' is not registered cache header
    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Server
    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Client
    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Server
    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Client
    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Client
    ERROR Sep 23 11:01:03 [4140186432]: relay_connection: Read event on Client [In break]

  • In reply to shred:

    could you reproduce the issue with turning those options on/off. 

    Just to be sure, there is no config issue here.

  • In reply to LuCar Toni:

    I currently have it configured like you have pictured:

    • Allow Invalid Certificate - Enabled
    • Disable Legacy TLS protocols - Not enabled (disabled)

    I will try running with 'Disable Legacy TLS protools' enabled. If I still see the issue, I'll try disabling both.

  • In reply to shred:

    Changed my settings to disable legacy TLS and the debug collection.

    Not sure why disabling TLS would intermittently cause connections to time out, but time will tell.

    Ian

  • In reply to shred:

    shred

    I currently have it configured like you have pictured:

    • Allow Invalid Certificate - Enabled
    • Disable Legacy TLS protocols - Not enabled (disabled)

    I will try running with 'Disable Legacy TLS protools' enabled. If I still see the issue, I'll try disabling both.

     
    With both Allow Invalid Certificate and Disable Legacy TLS protocols enabled, the issue occurred again (on my iMac). The next thing I did was disable Allow Invalid Certificate and refreshed my Mail application and everything work. Could have been coincidence but usually when I have this issue, it won't work until I leave it alone for 30+ minutes then it randomly starts working. I'll continue to run Sophos XG with Allow Invalid Certificate disabled and Disable Legacy TLS protocols enabled.
  • In reply to shred:

    Hi Shred,

    I get the same using mac outlook and mac mail on one MBP more than I do on I my MBP.

    Ian

  • In reply to rfcat_vk:

    Some food for thought. Since I have reduced my email accounts to one I have not suffered any new occurrences, my wife has 3 email accounts which are accessible by either ms outlook or mac mail and she is seeing the time out issue quite often, sometime only for seconds eg long enough to see the message.

    Ian

  • In reply to rfcat_vk:

    Well, it's been a couple days now and I haven't seen the issue after disabling "Allow Invalid Certificate". Kind of odd if this does in fact fix it. My wife and I are also running two email accounts (Gmail and iCloud) on all of our devices (iMac, iPhones, iPads, MacBook Air). I'll continue to keep an eye on this.

  • In reply to shred:

    Hi Shred,

    there is another thread on tLS timeouts for web surfing. Similar vein to the mail issue. I might put  my accounts back on to my map instead of redirecting to see what happens.

    Ian

  • In reply to rfcat_vk:

    This is just plain stupid, my mail broke and has been for about two hours. Restart MBP did not fix the issue. Changed two firewall rules which do not affect the mail scanning and bingo my mail is working again. I wonder if my facebook is now working. It has been broken for a couple of days, just mine.

    Ian

     

    Update, another restore and all is working again, maybe.

  • In reply to LuCar Toni:

    This issue occurred again on both my iPhone and iMac this morning. Here is the warren.log. 172.16.16.66 is my iMac where the error is occurring:

     

    DEBUG Sep 26 08:32:24 [4124048192]: Client Hello Version : 771
    INFO Sep 26 08:32:24 [4124048192]: valid client hello
    INFO Sep 26 08:32:24 [4124048192]: h-ver '3' , chel-ver '5'
    INFO Sep 26 08:32:24 [4142947136]: SSL session established with server: '17.142.163.22'
    DEBUG Sep 26 08:32:24 [4142947136]: CommonName: *.mail.me.com
    DEBUG Sep 26 08:32:24 [4142947136]: CommonName: mail.me.com
    DEBUG Sep 26 08:32:24 [4142947136]: server certificate OK
    DEBUG Sep 26 08:32:24 [4142947136]: Acquired read-lock.
    DEBUG Sep 26 08:32:24 [4142947136]: certificate for CN('mail.me.com') found in cache
    INFO Sep 26 08:32:24 [4142947136]: SSL_accept() failed: error:1408F119:SSL routines:SSL3_GET_RECORD:decryption failed or bad record mac
    DEBUG Sep 26 08:32:24 [4142947136]: SSL_accept() failed: this is last msg!!!
    ERROR Sep 26 08:32:24 [4142947136]: relay_connection: Read event on Client [In break]
    DEBUG Sep 26 08:32:24 [4142947136]: relay_connection: _exit loop
    DEBUG Sep 26 08:32:24 [4142947136]: Freeing client 21
    DEBUG Sep 26 08:32:24 [4142947136]: SSL session with '172.16.16.66' closed
    DEBUG Sep 26 08:32:24 [4142947136]: Freeing client 22
    DEBUG Sep 26 08:32:24 [4142947136]: SSL session with '17.142.163.22' closed
    INFO Sep 26 08:32:24 [4142947136]: fd: 21 closed
    INFO Sep 26 08:32:24 [4121951040]: accept(): protocol IMAP4: fd: 21
    INFO Sep 26 08:32:24 [4121951040]: 172.16.16.66 === IMAP4 ===> 17.142.163.22
    INFO Sep 26 08:32:24 [4124048192]: SSL session established with server: '74.125.28.109'
    DEBUG Sep 26 08:32:24 [4124048192]: CommonName: imap.gmail.com
    DEBUG Sep 26 08:32:24 [4124048192]: server certificate OK
    DEBUG Sep 26 08:32:24 [4124048192]: Acquired read-lock.
    DEBUG Sep 26 08:32:24 [4124048192]: certificate for CN('imap.gmail.com') found in cache
    INFO Sep 26 08:32:24 [4124048192]: SSL_accept() failed: error:1408F119:SSL routines:SSL3_GET_RECORD:decryption failed or bad record mac
    DEBUG Sep 26 08:32:24 [4124048192]: SSL_accept() failed: this is last msg!!!
    ERROR Sep 26 08:32:24 [4124048192]: relay_connection: Read event on Client [In break]
    DEBUG Sep 26 08:32:24 [4124048192]: relay_connection: _exit loop
    DEBUG Sep 26 08:32:24 [4124048192]: Freeing client 23
    DEBUG Sep 26 08:32:24 [4124048192]: SSL session with '172.16.16.66' closed
    DEBUG Sep 26 08:32:24 [4124048192]: Freeing client 24
    DEBUG Sep 26 08:32:24 [4124048192]: SSL session with '74.125.28.109' closed
    INFO Sep 26 08:32:24 [4124048192]: fd: 23 closed
    INFO Sep 26 08:32:24 [4120902464]: accept(): protocol IMAP4: fd: 23
    INFO Sep 26 08:32:24 [4120902464]: 172.16.16.66 === IMAP4 ===> 74.125.28.109
    SFVH_SO01_SFOS 17.1.2 MR-2# tail -80 warren.log
    DEBUG Sep 26 08:32:23 [4128242496]: Acquired read-lock.
    DEBUG Sep 26 08:32:23 [4128242496]: certificate for CN('imap.gmail.com') found in cache
    INFO Sep 26 08:32:23 [4128242496]: SSL_accept() failed: error:1408F119:SSL routines:SSL3_GET_RECORD:decryption failed or bad record mac
    DEBUG Sep 26 08:32:23 [4128242496]: SSL_accept() failed: this is last msg!!!
    ERROR Sep 26 08:32:23 [4128242496]: relay_connection: Read event on Client [In break]
    DEBUG Sep 26 08:32:23 [4128242496]: relay_connection: _exit loop
    DEBUG Sep 26 08:32:23 [4128242496]: Freeing client 19
    DEBUG Sep 26 08:32:23 [4128242496]: SSL session with '172.16.16.66' closed
    DEBUG Sep 26 08:32:23 [4128242496]: Freeing client 20
    DEBUG Sep 26 08:32:23 [4128242496]: SSL session with '74.125.28.109' closed
    INFO Sep 26 08:32:23 [4128242496]: fd: 19 closed
    INFO Sep 26 08:32:23 [4146092864]: accept(): protocol IMAP4: fd: 19
    INFO Sep 26 08:32:23 [4146092864]: 172.16.16.66 === IMAP4 ===> 74.125.28.109
    INFO Sep 26 08:32:24 [4142947136]: accept(): protocol IMAP4S: fd: 21
    INFO Sep 26 08:32:24 [4142947136]: 172.16.16.66 === IMAP4S ===> 17.142.163.22
    INFO Sep 26 08:32:24 [4124048192]: accept(): protocol IMAP4S: fd: 23
    INFO Sep 26 08:32:24 [4124048192]: 172.16.16.66 === IMAP4S ===> 74.125.28.109
    INFO Sep 26 08:32:24 [4142947136]: thread_tmpfile: /var/tmp/warren_4142947136
    INFO Sep 26 08:32:24 [4142947136]: compare_network_2: l = r Ret 0
    INFO Sep 26 08:32:24 [4142947136]: initialize_conn: POP[1] POPS[1] IMAP[1] IMAPS[1]
    DEBUG Sep 26 08:32:24 [4142947136]: relay_connection: _enter for loop
    ERROR Sep 26 08:32:24 [4142947136]: relay_connection: Read event on Client
    DEBUG Sep 26 08:32:24 [4142947136]: peek '194' bytes from client hello
    DEBUG Sep 26 08:32:24 [4142947136]: Validating Higher Version Client Hello.
    DEBUG Sep 26 08:32:24 [4142947136]: Handshake Version : 769
    DEBUG Sep 26 08:32:24 [4142947136]: Client Hello Version : 771
    INFO Sep 26 08:32:24 [4142947136]: valid client hello
    INFO Sep 26 08:32:24 [4142947136]: h-ver '3' , chel-ver '5'
    INFO Sep 26 08:32:24 [4124048192]: thread_tmpfile: /var/tmp/warren_4124048192
    INFO Sep 26 08:32:24 [4124048192]: compare_network_2: l = r Ret 0
    INFO Sep 26 08:32:24 [4124048192]: initialize_conn: POP[1] POPS[1] IMAP[1] IMAPS[1]
    DEBUG Sep 26 08:32:24 [4124048192]: relay_connection: _enter for loop
    ERROR Sep 26 08:32:24 [4124048192]: relay_connection: Read event on Client
    DEBUG Sep 26 08:32:24 [4124048192]: peek '188' bytes from client hello
    DEBUG Sep 26 08:32:24 [4124048192]: Validating Higher Version Client Hello.
    DEBUG Sep 26 08:32:24 [4124048192]: Handshake Version : 769
    DEBUG Sep 26 08:32:24 [4124048192]: Client Hello Version : 771
    INFO Sep 26 08:32:24 [4124048192]: valid client hello
    INFO Sep 26 08:32:24 [4124048192]: h-ver '3' , chel-ver '5'
    INFO Sep 26 08:32:24 [4142947136]: SSL session established with server: '17.142.163.22'
    DEBUG Sep 26 08:32:24 [4142947136]: CommonName: *.mail.me.com
    DEBUG Sep 26 08:32:24 [4142947136]: CommonName: mail.me.com
    DEBUG Sep 26 08:32:24 [4142947136]: server certificate OK
    DEBUG Sep 26 08:32:24 [4142947136]: Acquired read-lock.
    DEBUG Sep 26 08:32:24 [4142947136]: certificate for CN('mail.me.com') found in cache
    INFO Sep 26 08:32:24 [4142947136]: SSL_accept() failed: error:1408F119:SSL routines:SSL3_GET_RECORD:decryption failed or bad record mac
    DEBUG Sep 26 08:32:24 [4142947136]: SSL_accept() failed: this is last msg!!!
    ERROR Sep 26 08:32:24 [4142947136]: relay_connection: Read event on Client [In break]
    DEBUG Sep 26 08:32:24 [4142947136]: relay_connection: _exit loop
    DEBUG Sep 26 08:32:24 [4142947136]: Freeing client 21
    DEBUG Sep 26 08:32:24 [4142947136]: SSL session with '172.16.16.66' closed
    DEBUG Sep 26 08:32:24 [4142947136]: Freeing client 22
    DEBUG Sep 26 08:32:24 [4142947136]: SSL session with '17.142.163.22' closed
    INFO Sep 26 08:32:24 [4142947136]: fd: 21 closed
    INFO Sep 26 08:32:24 [4121951040]: accept(): protocol IMAP4: fd: 21
    INFO Sep 26 08:32:24 [4121951040]: 172.16.16.66 === IMAP4 ===> 17.142.163.22
    INFO Sep 26 08:32:24 [4124048192]: SSL session established with server: '74.125.28.109'
    DEBUG Sep 26 08:32:24 [4124048192]: CommonName: imap.gmail.com
    DEBUG Sep 26 08:32:24 [4124048192]: server certificate OK
    DEBUG Sep 26 08:32:24 [4124048192]: Acquired read-lock.
    DEBUG Sep 26 08:32:24 [4124048192]: certificate for CN('imap.gmail.com') found in cache
    INFO Sep 26 08:32:24 [4124048192]: SSL_accept() failed: error:1408F119:SSL routines:SSL3_GET_RECORD:decryption failed or bad record mac
    DEBUG Sep 26 08:32:24 [4124048192]: SSL_accept() failed: this is last msg!!!
    ERROR Sep 26 08:32:24 [4124048192]: relay_connection: Read event on Client [In break]
    DEBUG Sep 26 08:32:24 [4124048192]: relay_connection: _exit loop
    DEBUG Sep 26 08:32:24 [4124048192]: Freeing client 23
    DEBUG Sep 26 08:32:24 [4124048192]: SSL session with '172.16.16.66' closed
    DEBUG Sep 26 08:32:24 [4124048192]: Freeing client 24
    DEBUG Sep 26 08:32:24 [4124048192]: SSL session with '74.125.28.109' closed
    INFO Sep 26 08:32:24 [4124048192]: fd: 23 closed
    INFO Sep 26 08:32:24 [4120902464]: accept(): protocol IMAP4: fd: 23
    INFO Sep 26 08:32:24 [4120902464]: 172.16.16.66 === IMAP4 ===> 74.125.28.109
    ERROR Sep 26 08:32:38 [4125096768]: opensock: connect() error "Connection timed out".
    INFO Sep 26 08:32:38 [4125096768]: fd: 12 closed
    ERROR Sep 26 08:32:38 [4146092864]: opensock: connect() error "Connection timed out".
    INFO Sep 26 08:32:38 [4146092864]: fd: 19 closed
    ERROR Sep 26 08:32:39 [4121951040]: opensock: connect() error "Connection timed out".
    INFO Sep 26 08:32:39 [4121951040]: fd: 21 closed
    ERROR Sep 26 08:32:39 [4120902464]: opensock: connect() error "Connection timed out".
    INFO Sep 26 08:32:39 [4120902464]: fd: 23 closed