[imp] [IMP] IMP suddenly stopped with expected end of data

Mon Mar 23 22:15:26 UTC 2015

> Le 23 mars 2015 à 08:20, Jan Schneider <jan at horde.org> a écrit :
>
>
>
> Zitat von Frank Sikora <frank.sikora at capfields.com>:
>
> > Hello List,
> >
> > I've got two instances of Horde/Webmail edition (5.0.4) installed on
> > two distinct servers, both of them connecting to the same IMAP
> > server for mail management via IMP.
> > Everything worked fine for a (long) while till last week: one of my
> > 2 instances generates an error each time I try to access IMP (other
> > Horde applications still work well).
> > When clicking on IMP menu, I get the following error message:
> >
> > Erreur IMAP sur le serveur.
> >
> > 1. Horde_Registry::appInit()
> > /homepages/23/d453220882/htdocs/imp/index.php:18
> > 2. Horde_Registry->pushApp()
> > /homepages/23/d453220882/htdocs/pear/PEAR/Horde/Registry.php:249
> > 3. Horde_Core_LoginTasks->runTasks()
> > /homepages/23/d453220882/htdocs/pear/PEAR/Horde/Registry.php:1560
> > 4. Horde_LoginTasks->runTasks()
> > /homepages/23/d453220882/htdocs/pear/PEAR/Horde/Core/LoginTasks.php:48
> > 5. IMP_LoginTasks_SystemTask_GarbageCollection->execute()
> > /homepages/23/d453220882/htdocs/pear/PEAR/Horde/LoginTasks.php:211
> > 6. Horde_Injector->getInstance()
> > /homepages/23/d453220882/htdocs/imp/lib/LoginTasks/SystemTask/GarbageCollection.php:38
> > 7. Horde_Injector->createInstance()
> > /homepages/23/d453220882/htdocs/pear/PEAR/Horde/Injector.php:247
> > 8. Horde_Injector_Binder_Factory->create()
> > /homepages/23/d453220882/htdocs/pear/PEAR/Horde/Injector.php:213
> > 9. IMP_Factory_Imaptree->create()
> > /homepages/23/d453220882/htdocs/pear/PEAR/Horde/Injector/Binder/Factory.php:111
> > 10. IMP_Imap_Tree->__construct()
> > /homepages/23/d453220882/htdocs/imp/lib/Factory/Imaptree.php:57
> > 11. IMP_Imap_Tree->init()
> > /homepages/23/d453220882/htdocs/imp/lib/Imap/Tree.php:181
> > 12. IMP_Imap_Tree->_getList()
> > /homepages/23/d453220882/htdocs/imp/lib/Imap/Tree.php:272
> > 13. IMP_Imap->listMailboxes()
> > /homepages/23/d453220882/htdocs/imp/lib/Imap/Tree.php:310
> > 14. IMP_Imap->__call()
> > /homepages/23/d453220882/htdocs/imp/lib/Imap/Tree.php:310
> > 15. Horde_Registry::appInit()
> > /homepages/23/d453220882/htdocs/imp/index.php:18
> > 16. Horde_Registry->pushApp()
> > /homepages/23/d453220882/htdocs/pear/PEAR/Horde/Registry.php:249
> > 17. Horde_Core_LoginTasks->runTasks()
> > /homepages/23/d453220882/htdocs/pear/PEAR/Horde/Registry.php:1560
> > 18. Horde_LoginTasks->runTasks()
> > /homepages/23/d453220882/htdocs/pear/PEAR/Horde/Core/LoginTasks.php:48
> > 19. IMP_LoginTasks_SystemTask_GarbageCollection->execute()
> > /homepages/23/d453220882/htdocs/pear/PEAR/Horde/LoginTasks.php:211
> > 20. Horde_Injector->getInstance()
> > /homepages/23/d453220882/htdocs/imp/lib/LoginTasks/SystemTask/GarbageCollection.php:38
> > 21. Horde_Injector->createInstance()
> > /homepages/23/d453220882/htdocs/pear/PEAR/Horde/Injector.php:247
> > 22. Horde_Injector_Binder_Factory->create()
> > /homepages/23/d453220882/htdocs/pear/PEAR/Horde/Injector.php:213
> > 23. IMP_Factory_Imaptree->create()
> > /homepages/23/d453220882/htdocs/pear/PEAR/Horde/Injector/Binder/Factory.php:111
> > 24. IMP_Imap_Tree->__construct()
> > /homepages/23/d453220882/htdocs/imp/lib/Factory/Imaptree.php:57
> > 25. IMP_Imap_Tree->init()
> > /homepages/23/d453220882/htdocs/imp/lib/Imap/Tree.php:181
> > 26. IMP_Imap_Tree->_getList()
> > /homepages/23/d453220882/htdocs/imp/lib/Imap/Tree.php:272
> > 27. IMP_Imap->listMailboxes()
> > /homepages/23/d453220882/htdocs/imp/lib/Imap/Tree.php:310
> > 28. IMP_Imap->__call()
> > /homepages/23/d453220882/htdocs/imp/lib/Imap/Tree.php:310
> > 29. call_user_func_array()
> > /homepages/23/d453220882/htdocs/imp/lib/Imap.php:410
> > 30. Horde_Imap_Client_Base->listMailboxes()
> > 31. Horde_Imap_Client_Socket->_listMailboxes()
> > /homepages/23/d453220882/htdocs/pear/PEAR/Horde/Imap/Client/Base.php:1316
> > 32. Horde_Imap_Client_Socket->_getMailboxList()
> > /homepages/23/d453220882/htdocs/pear/PEAR/Horde/Imap/Client/Socket.php:1113
> > 33. Horde_Imap_Client_Socket->_listMailboxes()
> > /homepages/23/d453220882/htdocs/pear/PEAR/Horde/Imap/Client/Socket.php:1243
> > 34. Horde_Imap_Client_Socket->_getMailboxList()
> > /homepages/23/d453220882/htdocs/pear/PEAR/Horde/Imap/Client/Socket.php:1101
> > 35. Horde_Imap_Client_Socket->_sendLine()
> > /homepages/23/d453220882/htdocs/pear/PEAR/Horde/Imap/Client/Socket.php:1236
> > 36. Horde_Imap_Client_Socket->_getLine()
> > /homepages/23/d453220882/htdocs/pear/PEAR/Horde/Imap/Client/Socket.php:3701
> >
> > In IMP.log file, I have the error lines:
> > 2015-03-22T17:33:24+00:00 ERR: HORDE [imp] Erreur IMAP sur le
> > serveur. expected end of data [pid 6778 on line 412 of
> > "/homepages/23/d453220882/htdocs/imp/lib/Imap.php"]
> > 2015-03-22T17:40:55+00:00 ERR: HORDE [imp] Erreur IMAP sur le
> > serveur. expected end of data [pid 17195 on line 412 of
> > "/homepages/23/d453220882/htdocs/imp/lib/Imap.php"]
> >
> > By adding some traces in Imap.php at line 412, it seems that dynamic
> > call to method 'islistMailboxes' is generating the error.
> >
> > Any idea on what could be the origin of this error? My other
> > instance of Horde works fine. Of course I didn't apply any change to
> > the Horde repository and files.
> >
> > Thanks
> >
> > Frank
> >
> >
> >
> > --
> > imp mailing list
> > Frequently Asked Questions: http://wiki.horde.org/FAQ
> > To unsubscribe, mail: imp-unsubscribe at lists.horde.org
>
> Enable debug imap logging in backends.local.php.
>
> --
> Jan Schneider
> The Horde Project
> http://www.horde.org/
> https://www.facebook.com/hordeproject
>
> --
> imp mailing list
> Frequently Asked Questions: http://wiki.horde.org/FAQ
> To unsubscribe, mail: imp-unsubscribe at lists.horde.org

Here is attached the log produced when trying to launch IMP (debug output). Hope
it helps. Again same config is running in Test env and is querying the same IMAP
server without any issue.

Frank
-------------- next part --------------
------------------------------
>> Mon, 23 Mar 2015 22:01:29 +0000
S: * OK [CAPABILITY IMAP4rev1 CHILDREN ENABLE ID IDLE LIST-EXTENDED LIST-STATUS LITERAL+ MOVE NAMESPACE QUOTA SASL-IR SORT SPECIAL-USE THREAD=ORDEREDSUBJECT UIDPLUS UNSELECT WITHIN STARTTLS AUTH=LOGIN AUTH=PLAIN] IMAP server ready H mieue012 104568 IMAP-0Mh9PB-1Ynj1r2Ek5-00M5ce
C: [SASL-IR AUTHENTICATE Command - username: frank.sikora at capfields.com]
S: 1 OK AUTHENTICATE completed
C: 2 CAPABILITY
S: * CAPABILITY IMAP4rev1 CHILDREN ENABLE ID IDLE LIST-EXTENDED LIST-STATUS LITERAL+ MOVE NAMESPACE QUOTA SASL-IR SORT SPECIAL-USE THREAD=ORDEREDSUBJECT UIDPLUS UNSELECT WITHIN STARTTLS APPENDLIMIT=140000000
S: 2 OK CAPABILITY completed
C: 3 LOGOUT
S: * BYE Server logging out
S: 3 OK LOGOUT completed
------------------------------
>> Mon, 23 Mar 2015 22:01:30 +0000
S: * OK [CAPABILITY IMAP4rev1 CHILDREN ENABLE ID IDLE LIST-EXTENDED LIST-STATUS LITERAL+ MOVE NAMESPACE QUOTA SASL-IR SORT SPECIAL-USE THREAD=ORDEREDSUBJECT UIDPLUS UNSELECT WITHIN STARTTLS AUTH=LOGIN AUTH=PLAIN] IMAP server ready H mieue104 104568 IMAP-0Lz5LE-1ZVLJS1cMC-014FI4
C: [SASL-IR AUTHENTICATE Command - username: frank.sikora at capfields.com]
S: 1 OK AUTHENTICATE completed
C: 2 NAMESPACE
S: * NAMESPACE (("" "/")) NIL NIL
S: 2 OK NAMESPACE completed
C: 3 LOGOUT
S: * BYE Server logging out
S: 3 OK LOGOUT completed
------------------------------
>> Mon, 23 Mar 2015 22:01:36 +0000
S: * OK [CAPABILITY IMAP4rev1 CHILDREN ENABLE ID IDLE LIST-EXTENDED LIST-STATUS LITERAL+ MOVE NAMESPACE QUOTA SASL-IR SORT SPECIAL-USE THREAD=ORDEREDSUBJECT UIDPLUS UNSELECT WITHIN STARTTLS AUTH=LOGIN AUTH=PLAIN] IMAP server ready H mieue006 104568 IMAP-0Mbxbu-1YtU6G2w5u-00JebJ
C: [SASL-IR AUTHENTICATE Command - username: frank.sikora at capfields.com]
S: 1 OK AUTHENTICATE completed
C: 2 LIST (SUBSCRIBED) "" ("*""") RETURN (SUBSCRIBED)
S: 2 BAD expected ")" instead of "\""
C: 3 LSUB "" "*"""
S: 3 BAD expected end of data
C: 4 LOGOUT
S: * BYE Server logging out
S: 4 OK LOGOUT completed