Tickets :: [#12031] Errors in IMAP commands

6.0.0-beta1

7/16/25

Summary	Errors in IMAP commands
Queue	Horde Framework Packages
Queue Version	Git master
Type	Bug
State	Resolved
Priority	1. Low
Owners	jan (at) horde (dot) org, slusarz (at) horde (dot) org
Requester	jan (at) horde (dot) org
Created	02/10/2013 (4539 days ago)
Due
Updated	03/11/2013 (4510 days ago)
Assigned	02/12/2013 (4537 days ago)
Resolved	03/11/2013 (4510 days ago)
Github Issue Link
Github Pull Request
Milestone
Patch	No

03/11/2013 07:18:11 PM	Michael Slusarz	Comment #16 State ⇒ Resolved	Reply to this comment
Since this matches what would be expected from the previous ticket, I'm going to close as resolved.

03/11/2013 11:18:16 AM	Jan Schneider	Comment #15	Reply to this comment
I haven't seen this in a while after I had to clear the cache completely, even before fixing that bug. So far no broken data seems to have been added to the cache again.

03/08/2013 09:59:10 PM	Michael Slusarz	Comment #14 Assigned to Jan Schneider	Reply to this comment
Almost positive ~~Bug #12097~~ fixed. Can you verify?

03/07/2013 08:53:37 PM	Michael Slusarz	Comment #13	Reply to this comment
Possible duplicate of ~~#12097~~.

02/25/2013 05:19:56 AM	Michael Slusarz	Comment #12	Reply to this comment
Weird - I never received notification of this. I'm out of ideas. I have no idea how an empty string could be stored. You're pretty much going to have to look at Horde_Imap_Client_Cache#set() yourself and determine how/why $data is being passed in with a non-numeric UID.

02/13/2013 08:57:17 AM	Jan Schneider	Comment #11	Reply to this comment
S: * OK [CAPABILITY IMAP4rev1 LITERAL+ ID ENABLE XIMAPPROXY] neo Cyrus IMAP v2. 4.16-Debian-2.4.16-1 server ready C: [LOGIN Command - username: jan] S: * OK [XPROXYREUSE] IMAP connection reused by imapproxy S: 1 OK User logged in C: 2 SELECT "INBOX.Quarant&AOQ-ne" (QRESYNC (1240054819 18015 ,63052:63077)) S: 2 BAD Invalid QRESYNC parameter list in Select C: 3 STATUS "INBOX" (RECENT) S: * STATUS INBOX (RECENT 0) S: 3 OK Completed C: 4 STATUS "INBOX.horde.apps" (RECENT) 2013-02-13T09:56:05+01:00 DEBUG: Variable information: array(27) { [0] => string(0) "" [1] => int(63052) [2] => int(63053) [3] => int(63054) [4] => int(63055) [5] => int(63056) [6] => int(63057) [7] => int(63058) [8] => int(63059) [9] => int(63060) [10] => int(63061) [11] => int(63062) [12] => int(63063) [13] => int(63064) [14] => int(63065) [15] => int(63066) [16] => int(63067) [17] => int(63068) [18] => int(63069) [19] => int(63070) [20] => int(63071) [21] => int(63072) [22] => int(63073) [23] => int(63074) [24] => int(63075) [25] => int(63076) [26] => int(63077) } Backtrace: 1. Horde_Core_Ajax_Application->doAction() /home/jan/horde-develop/horde/services/ajax.php:56 2. call_user_func() /home/jan/horde-develop/framework/Core/lib/Horde/Core/Ajax/Application.php:155 3. IMP_Ajax_Application_Handler_Common->viewPort() 4. IMP_Ajax_Application->changed() /home/jan/horde-develop/imp/lib/Ajax/Application/Handler/Common.php:84 5. IMP_Imap->openMailbox() /home/jan/horde-develop/imp/lib/Ajax/Application.php:272 6. IMP_Imap->__call() /home/jan/horde-develop/imp/lib/Ajax/Application.php:272 7. call_user_func_array() /home/jan/horde-develop/imp/lib/Imap.php:385 8. Horde_Imap_Client_Base->openMailbox() 9. Horde_Imap_Client_Socket->_openMailbox() /home/jan/horde-develop/framework/Imap_Client/lib/Horde/Imap/Client/Base.php:938 10. Horde::debug() /home/jan/horde-develop/framework/Imap_Client/lib/Horde/Imap/Client/Socket.php:917

02/13/2013 05:30:55 AM	Michael Slusarz	Comment #10	Reply to this comment
C: 2 SELECT "INBOX.Quarant&AOQ-ne" (QRESYNC (1240054819 17954 ,63006:63041)) [snip] [Show Quoted Text - 9 lines][Hide Quoted Text] The cache results are: Array ( ) Array ( [uidvalid] => ) That can't be right, since the UID list is generated from the cached data. Best to do a print_r($uids) at line ~916 (Socket.php): $uids = $this->_cache->get($mailbox); print_r($uids);

02/12/2013 10:49:52 AM	Jan Schneider	Comment #9 State ⇒ Feedback	Reply to this comment
It just happened again. It didn't happen when entering the mailbox, but when scrolling down to earlier messages. S: * OK [CAPABILITY IMAP4rev1 LITERAL+ ID ENABLE XIMAPPROXY] neo Cyrus IMAP v2.4.16-Debian-2.4.16-1 server ready C: [LOGIN Command - username: jan] S: * OK [XPROXYREUSE] IMAP connection reused by imapproxy S: 1 OK User logged in C: 2 SELECT "INBOX.Quarant&AOQ-ne" (QRESYNC (1240054819 17954 ,63006:63041)) S: 2 BAD Invalid QRESYNC parameter list in Select C: 3 LOGOUT S: * BYE LOGOUT received S: 3 OK Completed The cache results are: Array ( ) Array ( [uidvalid] => )

02/12/2013 08:55:04 AM	Jan Schneider	Comment #8 State ⇒ Resolved	Reply to this comment
[Show Quoted Text - 12 lines][Hide Quoted Text] C: 7 UID FETCH 307495,307499,307501 UID (VANISHED CHANGEDSINCE 0) S: 7 BAD Missing required argument to UID Fetch CHANGEDSINCE can't be empty. It appears that your cache data is broken, but I would need a dump of that data to be sure. Spoke too early, this still shows up in the logs. This should be fixed. As for the other issue - the cache data provided does not correspond to that mailbox. Yes, those were two different mailboxes with different logs and different behavior. The other issue indeed didn't show up anymore after a logout.

02/12/2013 08:45:29 AM	Michael Slusarz	Comment #7	Reply to this comment
C: 7 UID FETCH 307495,307499,307501 UID (VANISHED CHANGEDSINCE 0) S: 7 BAD Missing required argument to UID Fetch CHANGEDSINCE can't be empty. It appears that your cache data is broken, but I would need a dump of that data to be sure. Spoke too early, this still shows up in the logs. This should be fixed. As for the other issue - the cache data provided does not correspond to that mailbox.

02/12/2013 08:44:58 AM	Git Commit	Comment #6	Reply to this comment
Changes have been made in Git (master): commit 5ef6b9418512d7e2faecb8336e765838a6b27b5f Author: Michael M Slusarz <slusarz@horde.org> Date: Tue Feb 12 02:19:01 2013 -0700 [mms] Make sure the modseq given to the vanished() command is always at least 1 (~~Bug #12031~~). .../Imap_Client/lib/Horde/Imap/Client/Base.php \| 2 +- .../lib/Horde/Imap/Client/Data/Sync.php \| 2 +- framework/Imap_Client/package.xml \| 4 ++-- 3 files changed, 4 insertions(+), 4 deletions(-) http://git.horde.org/horde-git/-/commit/5ef6b9418512d7e2faecb8336e765838a6b27b5f

02/11/2013 10:09:52 PM	Jan Schneider	Comment #5 State ⇒ Feedback	Reply to this comment
C: 7 UID FETCH 307495,307499,307501 UID (VANISHED CHANGEDSINCE 0) S: 7 BAD Missing required argument to UID Fetch CHANGEDSINCE can't be empty. It appears that your cache data is broken, but I would need a dump of that data to be sure. Spoke too early, this still shows up in the logs. Array ( [0] => 304702 [1] => 304850 [2] => 304851 [3] => 304852 [4] => 304853 [5] => 304854 [6] => 304855 [7] => 304856 [8] => 304857 [9] => 304858 [10] => 304859 [11] => 304860 [12] => 304861 [13] => 304862 [14] => 304863 [15] => 304864 [16] => 304868 [17] => 304869 [18] => 307888 [19] => 307940 [20] => 307942 [21] => 307947 [22] => 307985 [23] => 103113 [24] => 108842 [25] => 119429 [26] => 122132 [27] => 127950 [28] => 128290 [29] => 135928 [30] => 135940 [31] => 142273 [32] => 142914 [33] => 166869 [34] => 170018 [35] => 171562 [36] => 173586 [37] => 173662 [38] => 173706 [39] => 175599 [40] => 175692 [41] => 175816 [42] => 177278 [43] => 177358 [44] => 178049 [45] => 178661 [46] => 179694 [47] => 180119 [48] => 181094 [49] => 181118 [50] => 181122 [51] => 184164 [52] => 186094 [53] => 186756 [54] => 187059 [55] => 187076 [56] => 188416 [57] => 188466 [58] => 188642 [59] => 296881 [60] => 296882 [61] => 296883 [62] => 296884 [63] => 296885 [64] => 296886 [65] => 296887 [66] => 296888 [67] => 296889 [68] => 296890 [69] => 296891 [70] => 296892 [71] => 296893 [72] => 296894 [73] => 296895 [74] => 296896 [75] => 296897 [76] => 296898 [77] => 296899 [78] => 296900 [79] => 296901 [80] => 296902 [81] => 296903 [82] => 296904 [83] => 296905 [84] => 296906 [85] => 296907 [86] => 296908 [87] => 296909 [88] => 296910 [89] => 296911 [90] => 296912 [91] => 296913 [92] => 296914 [93] => 296915 [94] => 296916 [95] => 296917 [96] => 296918 [97] => 296919 [98] => 296920 [99] => 296921 [100] => 296922 [101] => 296923 [102] => 296924 [103] => 296925 [104] => 296926 [105] => 296927 [106] => 296928 [107] => 296929 [108] => 296930 [109] => 296931 [110] => 296932 [111] => 296933 [112] => 296934 [113] => 296935 [114] => 296936 [115] => 296937 [116] => 296938 [117] => 296939 [118] => 296940 [119] => 296941 [120] => 296942 [121] => 296943 [122] => 296944 [123] => 296945 [124] => 296946 [125] => 296947 [126] => 296948 [127] => 296949 [128] => 296950 [129] => 296951 [130] => 296952 [131] => 296953 [132] => 296954 [133] => 296955 [134] => 296956 [135] => 296957 [136] => 296958 [137] => 296960 [138] => 296961 [139] => 296962 [140] => 296963 [141] => 296964 [142] => 296965 [143] => 296966 [144] => 296967 [145] => 296968 [146] => 296969 [147] => 296970 [148] => 296971 [149] => 296972 [150] => 296973 [151] => 296974 [152] => 296976 [153] => 296977 [154] => 296978 [155] => 296979 [156] => 296980 [157] => 296981 [158] => 296982 [159] => 296983 [160] => 296985 [161] => 296987 [162] => 296988 [163] => 296989 [164] => 296990 [165] => 296991 [166] => 296992 [167] => 296993 [168] => 296994 [169] => 296995 [170] => 296996 [171] => 296998 [172] => 296999 [173] => 297000 [174] => 297001 [175] => 297002 [176] => 297003 [177] => 297004 [178] => 297005 [179] => 297008 [180] => 297009 [181] => 297010 [182] => 297013 [183] => 297014 [184] => 297015 [185] => 297016 [186] => 297018 [187] => 297019 [188] => 297028 [189] => 297034 [190] => 297036 [191] => 297043 [192] => 297044 [193] => 297046 [194] => 297048 [195] => 297049 [196] => 297050 [197] => 297051 [198] => 297055 [199] => 297360 [200] => 299132 [201] => 299225 [202] => 299257 [203] => 302984 [204] => 303037 [205] => 303328 [206] => 303408 [207] => 306243 [208] => 306623 [209] => 306715 [210] => 306721 [211] => 306783 [212] => 306784 [213] => 307455 [214] => 307456 [215] => 307749 [216] => 307750 [217] => 307755 [218] => 307855 [219] => 307864 [220] => 308055 [221] => 308147 [222] => 308151 [223] => 308152 [224] => 308153 ) Array ( [uidvalid] => 968256253 [HICmodseq] => 66081 [HICsearch] => Array ( [cacheid] => V968256253\|H66081 [dc861924f5d0f35c6215ee03013aa8cc] => C:29:"Horde_Imap_Client_Data_Thread":2517:{[[{"103113":0},{"108842":0},{"119429":0},{"122132":0},{"128290":0,"127950":1},{"135928":0,"135940":1},{"142273":0},{"142914":0},{"166869":0},{"170018":0},{"171562":0},{"173586":0},{"173662":0},{"173706":0},{"175599":0},{"175692":0},{"175816":0},{"177278":0,"178049":1},{"177358":0},{"178661":0},{"179694":0},{"180119":0,"181094":1,"181118":2,"181122":3},{"184164":0},{"186094":0},{"186756":0},{"187059":0,"187076":1},{"188416":0},{"188466":0},{"188642":0},{"296881":0},{"296882":0,"296883":1},{"296887":0},{"296884":0},{"296885":0},{"296886":0},{"296888":0},{"296889":0},{"296890":0},{"296891":0},{"296892":0},{"296893":0},{"296894":0},{"296895":0},{"296896":0,"296898":1},{"296897":0},{"296899":0},{"296900":0},{"296901":0},{"296902":0},{"296903":0},{"296904":0},{"296905":0},{"296906":0,"296907":1,"296908":2,"296909":3,"296910":4,"296911":5,"296912":5,"296913":6,"296914":7,"296915":8,"296917":9,"296918":10,"296919":11,"296920":12},{"296916":0},{"2969 21":0},{"296922":0},{"296923":0},{"296924":0},{"296925":0},{"296926":0},{"296927":0},{"296928":0},{"296929":0,"296930":1},{"296931":0},{"296932":0},{"296933":0},{"296934":0},{"296935":0,"296936":1},{"296937":0},{"296938":0},{"296939":0},{"296940":0},{"296941":0},{"296942":0},{"296943":0},{"296944":0},{"296945":0},{"296946":0},{"296947":0},{"296948":0},{"296949":0,"296950":1},{"296951":0,"296983":0},{"296952":0},{"296979":0},{"296953":0},{"296954":0},{"296955":0,"296956":1},{"296957":0,"296961":0,"296962":1},{"296958":0},{"296960":0},{"296963":0},{"296964":0},{"296965":0},{"296966":0},{"296967":0,"296976":1,"296968":2,"296977":3,"296969":4,"296970":5,"296971":6,"296972":7,"296974":8},{"296973":0},{"296978":0,"296980":1},{"296981":0,"296982":0},{"296985":0},{"296987":0,"296988":1,"296989":1,"296990":2,"296991":3,"296992":3,"296993":4,"296994":5,"296995":6,"296996":7,"296998":8,"296999":8,"297000":8,"297001":9,"297002":10,"297008":11,"297003":9,"297004":10,"297005":9,"297013":10,"297014 ":10,"297015":11,"297016":12,"297019":10},{"297009":0},{"297010":0},{"297018":0},{"297028":0},{"297034":0},{"297036":0},{"297043":0},{"297044":0,"297046":1,"297048":0},{"297049":0,"297050":1,"297051":2,"297055":2},{"297360":0,"303328":0},{"299132":0},{"299225":0},{"299257":0},{"302984":0},{"303037":0},{"303408":0},{"306243":0},{"306784":0},{"306623":0},{"306715":0},{"306721":0},{"306783":0},{"307455":0},{"307456":0},{"307749":0,"307750":0,"307755":0},{"307855":0},{"307864":0},{"307940":0},{"307985":0},{"308147":0},{"308151":0},{"308152":0,"308153":0}],"uid"]} [7ac4644f9339b963a065796d3125390b] => a:2:{s:5:"match";s:0:"";s:5:"count";s:1:"0";} [c95c5b383c5ae4cb27712d48f6c8860a] => a:2:{s:3:"min";s:3:"200";s:5:"count";s:1:"4";} ) )

02/11/2013 09:45:39 PM	Jan Schneider	Comment #4 State ⇒ Not A Bug	Reply to this comment
This went away, after a logout-login-cycle and didn't show up again yet.

02/11/2013 08:07:53 PM	Michael Slusarz	Comment #3 State ⇒ Feedback	Reply to this comment
C: 7 UID FETCH 307495,307499,307501 UID (VANISHED CHANGEDSINCE 0) S: 7 BAD Missing required argument to UID Fetch CHANGEDSINCE can't be empty. It appears that your cache data is broken, but I would need a dump of that data to be sure. C: 2 SELECT "INBOX.Quarant&AOQ-ne" (QRESYNC (1240054819 17703 62857:62885,62893: 62906,)) S: 2 BAD Invalid QRESYNC parameter list in Select See above - it looks like your cached data is hosed. But no way for me to verify unless I can see contents of the cache file. In IMP 6.0, this will work: print_r($injector->getInstance('IMP_Factory_Imap')->create()->getCache()->get("INBOX.Quarant&AOQ-ne")); print_r($injector->getInstance('IMP_Factory_Imap')->create()->getCache()->getMetaData("INBOX.Quarant&AOQ-ne"));

02/11/2013 05:30:20 PM	Jan Schneider	Comment #2	Reply to this comment
Bump

02/10/2013 04:28:16 PM	Jan Schneider	Comment #1 Priority ⇒ 1. Low State ⇒ Assigned Patch ⇒ No Milestone ⇒ Assigned to Michael Slusarz Summary ⇒ Errors in IMAP commands Type ⇒ Bug Queue ⇒ Horde Framework Packages	Reply to this comment
I see these two BAD replies in my IMAP log: S: * OK [CAPABILITY IMAP4rev1 LITERAL+ ID ENABLE XIMAPPROXY] neo Cyrus IMAP v2. 4.16-Debian-2.4.16-1 server ready C: [LOGIN Command - username: jan] S: * OK [XPROXYREUSE] IMAP connection reused by imapproxy S: * CAPABILITY IMAP4rev1 LITERAL+ ID ENABLE ACL RIGHTS=kxte QUOTA MAILBOX-REFER RALS NAMESPACE UIDPLUS NO_ATOMIC_RENAME UNSELECT CHILDREN MULTIAPPEND BINARY CAT ENATE CONDSTORE ESEARCH SORT SORT=MODSEQ SORT=DISPLAY THREAD=ORDEREDSUBJECT THRE AD=REFERENCES ANNOTATEMORE LIST-EXTENDED WITHIN QRESYNC SCAN XLIST URLAUTH URLAU TH=BINARY LOGINDISABLED COMPRESS=DEFLATE IDLE CONFIG: IGNORING these IMAP capabilities: BINARY S: 2 OK Completed C: 3 ENABLE QRESYNC S: 1 OK User logged in C: 2 CAPABILITY S: * ENABLED S: 3 OK Completed S: * CAPABILITY IMAP4rev1 LITERAL+ ID ENABLE ACL RIGHTS=kxte QUOTA MAILBOX-REFER RALS NAMESPACE UIDPLUS NO_ATOMIC_RENAME UNSELECT CHILDREN MULTIAPPEND BINARY CAT ENATE CONDSTORE ESEARCH SORT SORT=MODSEQ SORT=DISPLAY THREAD=ORDEREDSUBJECT THRE AD=REFERENCES ANNOTATEMORE LIST-EXTENDED WITHIN QRESYNC SCAN XLIST URLAUTH URLAU TH=BINARY LOGINDISABLED COMPRESS=DEFLATE IDLE CONFIG: IGNORING these IMAP capabilities: BINARY S: 2 OK Completed C: 3 ENABLE QRESYNC S: * ENABLED S: 3 OK Completed C: 4 NAMESPACE S: * NAMESPACE (("INBOX." ".")) (("user." ".")) (("" ".")) S: 4 OK Completed C: 5 STATUS "INBOX" (UIDNEXT UIDVALIDITY) S: * STATUS INBOX (UIDNEXT 308049 UIDVALIDITY 968256253) S: 5 OK Completed C: 6 EXAMINE "INBOX" (CONDSTORE) S: * 202 EXISTS S: * 0 RECENT S: * FLAGS (\Answered \Flagged \Draft \Deleted \Seen NonJunk $Label2 $Label1 $La bel3 $Label4 $Label5 Junk $Forwarded NotJunk foo AMMMa Consulting $NotJunk JunkR ecorded $Junk Training $MDNSent KMAILFORWARDED KMAILTODO KMAILWATCHED KMAILIGNOR ED $TODO $WATCHED $IGNORED H3) S: * OK [PERMANENTFLAGS ()] Ok S: * OK [UIDVALIDITY 968256253] Ok S: * OK [UIDNEXT 308049] Ok S: * OK [HIGHESTMODSEQ 65819] Ok S: * OK [URLMECH INTERNAL] Ok S: 6 OK [READ-ONLY] Completed SEARCH: Retrieved search from cache (mailbox: INBOX; id: 5fd99061a89a1fd40bb0 cb74be6580fe) SEARCH: Retrieved search from cache (mailbox: INBOX; id: fda71d87c52aef737acb 2e815cc102d9) CACHE: Retrieved messages (mailbox: INBOX; UIDs: 103113,108842,119429,122132, 127950,128290,135928,135940,142273,142914,166869,170018,171562,173586,173662,173 706,175599,175692,175816,177278,177358,178049,178661,179694,180119,181094,181118 ,181122,184164,186094,186756,187059,187076,188416,188466,188642,296881:296958,29 6960:296974,296976:296983,296985,296987:296996,296998:297005,297008:297010,29701 3:297016,297018:297019,297028,297034,297036,297043:297044,297046,297048:297051,2 97055,297360,299132,299225,299257,302984,303037,303328,303408,306243,306623,3067 15,306721,306748,306783:306784,307455:307456,307749:307750,307755,307855,307864, 307940,307984:307986) C: 7 UID FETCH 307495,307499,307501 UID (VANISHED CHANGEDSINCE 0) C: 4 NAMESPACE S: 7 BAD Missing required argument to UID Fetch S: * NAMESPACE (("INBOX." ".")) (("user." ".")) (("" ".")) S: 4 OK Completed which doesn't seem have any side-effects, and: S: * OK [CAPABILITY IMAP4rev1 LITERAL+ ID ENABLE XIMAPPROXY] neo Cyrus IMAP v2. 4.16-Debian-2.4.16-1 server ready C: [LOGIN Command - username: jan] S: * OK [XPROXYREUSE] IMAP connection reused by imapproxy S: 1 OK User logged in C: 2 SELECT "INBOX.Quarant&AOQ-ne" (QRESYNC (1240054819 17703 62857:62885,62893: 62906,)) S: 2 BAD Invalid QRESYNC parameter list in Select which make opening the folder impossible (empty message list)