[Akonadi] [Bug 472282] New: Kolab Now reports too many open connections

[Heinz Wiesinger]

https://bugs.kde.org/show_bug.cgi?id=472282

            Bug ID: 472282
           Summary: Kolab Now reports too many open connections
    Classification: Frameworks and Libraries
           Product: Akonadi
           Version: 5.23.2
          Platform: Slackware
                OS: Linux
            Status: REPORTED
          Severity: major
          Priority: NOR
         Component: IMAP resource
          Assignee: kdepim-bugs at kde.org
          Reporter: pprkut at liwjatan.org
                CC: carl at carlschwan.eu
  Target Milestone: ---

Created attachment 160308
  --> https://bugs.kde.org/attachment.cgi?id=160308&action=edit
Screenshot of the error message

SUMMARY

Kolab Now performed some server side changes on Friday, June 30th 2023:
https://blogs.kolabnow.com/2023/06/16/announcing-service-window-friday-23rd-of-june-the-everlasting-race-to-get-rid-of-old-infrastructure
https://blogs.kolabnow.com/2023/06/23/service-window-postponed-due-diligence

Ever since then, the mail synchronization process in akonadi is acting weird
and running into problems, the most visible of which as an error popup that
login was denied because of "too many open connections".

I collected a log with akonadiconsole which highlights what might be going
wrong. I'll post what I think are the relevant excerpts here. The full log
contains a bit too many personal details for my liking, but I can provide it
privately if you need it :)

The synchronization process actually works fine. Login is successful, new
emails are synced, etc. However, at the end of the synchronization process of
the first folder I see this in the log:

```
[2023-07-15T13:28:43.381Z] akonadi_imap_resource org.kde.pim.kimap
KIMAP::LoginJob(0x2890c50)
[2023-07-15T13:28:43.381Z] akonadi_imap_resource org.kde.pim.kimap Connecting
to IMAP server with no proxy
[2023-07-15T13:28:43.381Z] akonadi_imap_resource org.kde.pim.kimap
connectToHost "imap.kolabnow.com" 143
[2023-07-15T13:28:43.381Z] akonadi_imap_resource org.kde.pim.kimap
KIMAP::LoginJob(0x28d4500)
[2023-07-15T13:28:43.381Z] akonadi_imap_resource org.kde.pim.kimap Connecting
to IMAP server with no proxy
[2023-07-15T13:28:43.381Z] akonadi_imap_resource org.kde.pim.kimap Connection
to server lost  QAbstractSocket::UnknownSocketError
[2023-07-15T13:28:43.381Z] akonadi_imap_resource org.kde.pim.kimap close
[2023-07-15T13:28:43.381Z] akonadi_imap_resource org.kde.pim.kimap
connectToHost "imap.kolabnow.com" 143
[2023-07-15T13:28:43.381Z] akonadi_imap_resource org.kde.pim.akonadiagentbase
Deferring task "19530 Custom startConnect "
[2023-07-15T13:28:43.381Z] akonadi_imap_resource org.kde.pim.imapresource
online= false
[2023-07-15T13:28:43.381Z] akonadi_imap_resource org.kde.pim.kimap
KIMAP::LoginJob(0x2890c50)
[2023-07-15T13:28:43.381Z] akonadi_imap_resource org.kde.pim.kimap close
[2023-07-15T13:28:43.383Z] akonadi_imap_resource org.kde.pim.kimap Connection
to server lost  QAbstractSocket::UnknownSocketError
[2023-07-15T13:28:43.384Z] akonadi_imap_resource org.kde.pim.akonadiagentbase
Deferring task "19530 Custom startConnect "
[2023-07-15T13:28:43.384Z] akonadi_imap_resource org.kde.pim.kimap
KIMAP::LoginJob(0x28d4500)
[2023-07-15T13:28:43.385Z] kmail org.kde.pim.kmail Last resource finished
syncing, mail check done
[2023-07-15T13:28:43.385Z] akonadi_imap_resource org.kde.pim.kimap Connecting
to IMAP server with no proxy
[2023-07-15T13:28:43.385Z] akonadi_imap_resource org.kde.pim.kimap
connectToHost "imap.kolabnow.com" 143
[2023-07-15T13:28:43.385Z] akonadi_imap_resource org.kde.pim.kimap
KIMAP::LoginJob(0x2873850)
[2023-07-15T13:28:44.286Z] akonadi_imap_resource org.kde.pim.kimap Received
BYE:  "LOGOUT received "
[2023-07-15T13:28:44.287Z] akonadi_imap_resource org.kde.pim.kimap close
```

for later folders I see
```
[2023-07-15T13:31:27.908Z] akonadi_imap_resource org.kde.pim.akonadicore
Received:  0 Removed:  0 In total:  0  Wanted:  127
[2023-07-15T13:31:27.908Z] akonadi_imap_resource org.kde.pim.imapresource
Retrieval complete. Elapsed(ms):  547
[2023-07-15T13:31:27.908Z] akonadi_imap_resource org.kde.pim.akonadicore
ItemSync of collection 406 finished
[2023-07-15T13:31:27.909Z] akonadi_imap_resource org.kde.pim.imapresource.trace
RetrieveCollectionMetadataTask
[2023-07-15T13:31:27.909Z] akonadi_imap_resource org.kde.pim.imapresource.trace
starting:  RetrieveCollectionMetadataTask
[2023-07-15T13:31:27.909Z] akonadi_imap_resource org.kde.pim.imapresource
"/folder1"
[2023-07-15T13:31:35.468Z] kmail org.kde.pim.kmail Last resource finished
syncing, mail check done
[2023-07-15T13:31:38.467Z] kmail kf.xmlgui Index  23  is not within range (0 - 
21 )
[2023-07-15T13:31:57.470Z] akonadi_imap_resource org.kde.pim.kimap Socket
timeout!
[2023-07-15T13:31:57.470Z] akonadi_imap_resource org.kde.pim.kimap close
[2023-07-15T13:31:57.471Z] akonadi_imap_resource org.kde.pim.imapresource
online= false
```

and this happens for every folder until eventually the error pops up.

Now, not saying this is an akonadi bug. I just lack the inside to figure out
where things are going wrong, and kolab now's support is telling me (and I
heard also others) to change an akonadi setting that, as far as I can tell,
doesn't exist anymore (Max number of cached server connections). So I hope this
provides some insight into figuring out what the actual problem here is.

SOFTWARE/OS VERSIONS
Linux/KDE Plasma: Slackware64-current
(available in About System)
KDE Plasma Version: 5.27.6
KDE Frameworks Version: 5.107.0
Qt Version: 5.15.10 (d2861d63)

-- 
You are receiving this mail because:
You are the assignee for the bug.