eSync kommt nicht zum Ende

Hallo zusammen,

ich habe nun mein Smartphone via Z-Push (ActiveSync) an die neue EGroupware-Installation egroupware-docker (21.1.20220207) angebunden und das Provisioning hat auch funktioniert. Nur leider scheint es Probleme mit E-Mail-Ordnern zu geben, denn im Log sehe ich die Mitteilungen AddFolder() serverid … und displayname [E-Mail-Ordnername], jedoch kommt es dann immer zu einem “Abbruch” und alles beginnt wieder von Vorne.
E-Mail, Kontakte und Termine erscheinen dabei keine auf dem Endgerät

Weiß ja zufällig jemand, wie ich das besser debuggen könnte?

24/03/2022 16:44:15 [  813] [DEBUG] StateManager::UnLinkState(''): saved state '4667855c-ecb5-4de1-8b2d-4ca2fb99f6b5' will be deleted.
24/03/2022 16:44:15 [  813] [DEBUG] SqlStateMachine->CleanStates(): devid:'androidc1522186291' type:'' key:'4667855c-ecb5-4de1-8b2d-4ca2fb99f6b5' counter:'199998' thisCounterOnly:'false'
24/03/2022 16:44:15 [  813] [DEBUG] SqlStateMachine->CleanStates(): devid:'androidc1522186291' type:'fd' key:'4667855c-ecb5-4de1-8b2d-4ca2fb99f6b5' counter:'false' thisCounterOnly:'false'
24/03/2022 16:44:15 [  813] [DEBUG] SqlStateMachine->CleanStates(): devid:'androidc1522186291' type:'fs' key:'4667855c-ecb5-4de1-8b2d-4ca2fb99f6b5' counter:'199998' thisCounterOnly:'false'
24/03/2022 16:44:15 [  813] [DEBUG] SqlStateMachine->CleanStates(): devid:'androidc1522186291' type:'bs' key:'4667855c-ecb5-4de1-8b2d-4ca2fb99f6b5' counter:'199998' thisCounterOnly:'false'
24/03/2022 16:44:15 [  813] [DEBUG] SqlStateMachine->CleanStates(): devid:'androidc1522186291' type:'hc' key:'4667855c-ecb5-4de1-8b2d-4ca2fb99f6b5' counter:'199998' thisCounterOnly:'false'
24/03/2022 16:44:15 [  813] [DEBUG] StateManager::linkState(#ASDevice, '1d18a3b2-ab1d-43be-860d-8724af27c299','HierarchyCache'): linked to uuid '1d18a3b2-ab1d-43be-860d-8724af27c299'.
24/03/2022 16:44:15 [  813] [DEBUG] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 117
24/03/2022 16:44:15 [  813] [DEBUG] SqlStateMachine->SetState(): devid:'androidc1522186291' type:'hc' key:'1d18a3b2-ab1d-43be-860d-8724af27c299' counter:'1'
24/03/2022 16:44:15 [  813] [DEBUG] SqlStateMachine->SetState(): devid:'androidc1522186291' type:'' key:'1d18a3b2-ab1d-43be-860d-8724af27c299' counter:'1'
24/03/2022 16:44:15 [  813] [DEBUG] SyncCollections::InvalidatePingableFlags(): Invalidating now
24/03/2022 16:44:15 [  813] [DEBUG] SqlStateMachine->SetState(): devid:'androidc1522186291' type:'fd' key:'1d18a3b2-ab1d-43be-860d-8724af27c299' counter:'false'
24/03/2022 16:44:15 [  813] [WBXML] O  </FolderHierarchy:FolderSync>
24/03/2022 16:44:15 [  813] [DEBUG] WBXMLEncoder->endTag() WBXML output completed
24/03/2022 16:44:15 [  813] [WBXML] WBXML-OUT: xxx
24/03/2022 16:44:15 [  813] [WBXML] WBXML-IN : xxx
24/03/2022 16:44:15 [  813] [DEBUG] Special header: MS-ASProtocolVersions: 12.0,12.1,14.0
24/03/2022 16:44:15 [  813] [DEBUG] Special header: MS-ASProtocolCommands: Sync,SendMail,SmartForward,SmartReply,GetAttachment,GetHierarchy,CreateCollection,DeleteCollection,MoveCollection,FolderSync,FolderCreate,FolderDelete,FolderUpdate,MoveItems,GetItemEstimate,MeetingResponse,ResolveRecipients,ValidateCert,Provision,Search,Ping,Notify,ItemOperations,Settings
24/03/2022 16:44:15 [  813] [DEBUG] LOGOFF
24/03/2022 16:44:15 [  813] [DEBUG] DeviceManager->Save(): Device data changed
24/03/2022 16:44:15 [  813] [DEBUG] esyncpro_zpush::SetState(..., 'androidc1522186291', 'devicedata', '', ) device=ASDevice Object([unsetdata:protected] => Array([useragenthistory] => Array()[hierarchyuuid] => [contentdata] => Array()[wipestatus] => 0[wiperequestedby] => [wiperequestedon] => [wipeactionon] => [lastupdatetime] => 0[conversationmode] => [policyhash] => [policyname] => default[policykey] => -1[forcesave] => [asversion] => [ignoredmessages] => Array()[announcedASversion] => [foldersynccomplete] => 1[additionalfolders] => Array()[koeversion] => [koebuild] => [koebuilddate] => [koegabbackendfolderid] => [koecapabilities] => Array()[koelastaccess] => [syncfiltertype] => )[newdevice:protected] => [hierarchyCache:protected] => ChangesMemoryWrapper Object([changes:ChangesMemoryWrapper:private] => Array([0] => Array([0] => 1[1] => SyncFolder Object([serverid] => 101000000000[parentid] => 0[displayname] => INBOX[type] => 2[Store] => [NoBackendFolder] => [BackendId] => [Flags] => [TypeReal] => [unsetVars:protected] => Array()[supportsPrivateStripping:protected] => [mapping:protected] => Array([FolderHierarchy:ServerEntryId] => Array([1] => serverid[6] => Array([7] => ))[FolderHierarchy:ParentId] => Array([1] => parentid[6] => Array([7] => setToValue0))[FolderHierarchy:DisplayName] => Array([1] => displayname[6] => Array([7] => Unknown))[FolderHierarchy:Type] => Array([1] => type[6] => Array([7] => 18[14] => 0[13] => 20))[FolderHierarchy:IgnoreStore] => Array([1] => Store[3] => 5)[FolderHierarchy:IgnoreNoBackendFolder] => Array([1] => NoBackendFolder[3] => 5)[FolderHierarchy:IgnoreBackendId] => Array([1] => BackendId[3] => 5)[FolderHierarchy:IgnoreFlags] => Array([1] => Flags[3] => 5)[FolderHierarchy:TypeReal] => Array([1] => TypeReal[3] => 5))[flags] => NewMessage[content] => ))[1] => Array([0] => 1[1] => SyncFolder Object([serverid] => 101000000001[parentid] => 0[displayname] => Outbox[type] => 6[Store] => [NoBackendFolder] => [BackendId] => [Flags] => [TypeReal] => [unsetVars:protected] => Array()[supportsPrivateStripping:protected] => [mapping:protected] => Array([FolderHierarchy:ServerEntryId] => Array([1] => serverid[6] => Array([7] => ))[FolderHierarchy:ParentId] => Array([1] => parentid[6] => Array([7] => setToValue0))[FolderHierarchy:DisplayName] => Array([1] => displayname[6] => Array([7] => Unknown))[FolderHierarchy:Type] => Array([1] => type[6] => Array([7] => 18[14] => 0[13] => 20))[FolderHierarchy:IgnoreStore] => Array([1] => Store[3] => 5)[FolderHierarchy:IgnoreNoBackendFolder] => Array([1] => NoBackendFolder[3] => 5)[FolderHierarchy:IgnoreBackendId] => Array([1] => BackendId[3] => 5)[FolderHierarchy:IgnoreFlags] => Array([1] => Flags[3] => 5)[FolderHierarchy:TypeReal] => Array([1] => TypeReal[3] => 5))[flags] => NewMessage[content] => ))[2] => Array([0] => 1[1] => SyncFolder Object([serverid] => 101000000002[parentid] => 0[displayname] => Entwürfe[type] => 3[Store] => [NoBackendFolder] => [BackendId] => [Flags] => [TypeReal] => [unsetVars:protected] => Array()[supportsPrivateStripping:protected] => [mapping:protected] => Array([FolderHierarchy:ServerEntryId] => Array([1] => serverid[6] => Array([7] => ))[FolderHierarchy:ParentId] => Array([1] => parentid[6] => Array([7] => setToValue0))[FolderHierarchy:DisplayName] => Array([1] => displayname[6] => Array([7] => Unknown))[FolderHierarchy:Type] => Array([1] => type[6] => Array([7] => 18[14] => 0[13] => 20))[FolderHierarchy:IgnoreStore] => Array([1] => Store[3] => 5)[FolderHierarchy:IgnoreNoBackendFolder] => Array([1] => NoBackendFolder[3] => 5)[FolderHierarchy:IgnoreBackendId] => Array([1] => BackendId[3] => 5)[FolderHierarchy:IgnoreFlags] => Array([1] => Flags[3] => 5)[FolderHierarchy:TypeReal] => Array([1] => TypeReal[3] => 5))[flags] => NewMessage[content] => ))[3] => Array([0] => 1[1] => SyncFolder Object([serverid] => 101000000003[parentid] => 0[displayname] => Gelöschte Objekte[type] => 4[Store] => [NoBackendFolder] => [BackendId] => [Flags] => [TypeReal] => [unsetVars:protected] => Array()[supportsPrivateStripping:protected] => [mapping:protected] => Array([FolderHierarchy:ServerEntryId] => Array([1] => serverid[6] => Array([7] => ))[FolderHierarchy:ParentId] => Array([1] => parentid[6] => Array([7] => setToValue0))[FolderHierarchy:DisplayName] => Array([1] => displayname[6] => Array([7] => Unknown))[FolderHierarchy:Type] => Array([1] => type[6] => Array([7] => 18[14] => 0[13] => 20))[FolderHierarchy:IgnoreStore] => Array([1] => Store[3] => 5)[FolderHierarchy:IgnoreNoBackendFolder] => Array([1] => NoBackendFolder[3] => 5)[FolderHierarchy:IgnoreBackendId] => Array([1] => BackendId[3] => 5)[FolderHierarchy:IgnoreFlags] => Array([1] => Flags[3] => 5)[FolderHierarchy:TypeReal] => Array([1] => TypeReal[3] => 5))[flags] => NewMessage[content] => ))[4] => Array([0] => 1[1] => SyncFolder Object([serverid] => 101000000004[parentid] => 0[displayname] => Gesendete Objekte[type] => 5[Store] => [NoBackendFolder] => [BackendId] => [Flags] => [TypeReal] => [unsetVars:protected] => Array()[supportsPrivateStripping:protected] => [mapping:protected] => Array([FolderHierarchy:ServerEntryId] => Array([1] => serverid[6] => Array([7] => ))[FolderHierarchy:ParentId] => Array([1] => parentid[6] => Array([7] => setToValue0))[FolderHierarchy:DisplayName] => Array([1] => displayname[6] => Array([7] => Unknown))[FolderHierarchy:Type] => Array([1] => type[6] => Array([7] => 18[14] => 0[13] => 20))[FolderHierarchy:IgnoreStore] => Array([1] => Store[3] => 5)[FolderHierarchy:IgnoreNoBackendFolder] => Array([1] => NoBackendFolder[3] => 5)[FolderHierarchy:IgnoreBackendId] => Array([1] => BackendId[3] => 5)[FolderHierarchy:IgnoreFlags] => Array([1] => Flags[3] => 5)[FolderHierarchy:TypeReal] => Array([1] => TypeReal[3] => 5))[flags] => NewMessage[content] => ))[5] => Array([0] => 1[1] => SyncFolder Object([serverid] => 101000000005[parentid] => 101000000004[displayname] => 2003[type] => 12[Store] => [NoBackendFolder] => [BackendId] => [Flags] => [TypeReal] => [unsetVars:protected] => Array()[supportsPrivateStripping:protected] => [mapping:protected] => Array([FolderHierarchy:ServerEntryId] => Array([1] => serverid[6] => Array([7] => ))[FolderHierarchy:ParentId] => Array([1] => parentid[6] => Array([7] => setToValue0))[FolderHierarchy:DisplayName] => Array([1] => displayname[6] => Array([7] => Unknown))[FolderHierarchy:Type] => Array([1] => type[6] => Array([7] => 18[14] => 0[13] => 20))[FolderHierarchy:IgnoreStore] => Array([1] => Store[3] => 5)[FolderHierarchy:IgnoreNoBackendFolder] => Array([1] => NoBackendFolder[3] => 5)[FolderHierarchy:IgnoreBackendId] => Array([1] => BackendId[3] => 5)[FolderHierarchy:IgnoreFlags] => Array([1] => Flags[3] => 5)[FolderHierarchy:TypeReal] => Array([1] => TypeReal[3] => 5))[flags] => NewMessage[content] => ))[6] => Array([0] => 1[1] => SyncFolder Object([serverid] => 101000000006[parentid] => 101000000004[displayname] => 2004[type] => 12[Store] => [NoBackendFolder] => [BackendId] => [Flags] => [TypeReal] => [unsetVars:protected] => Array()[supportsPrivateStripping:protected] => [mapping:protected] => Array([FolderHierarchy:ServerEntryId] => Array([1] => serverid[6] => Array([7] => ))[FolderHierarchy:ParentId] => Array([1] => parentid[6] => Array([7] => setToValue0))[FolderHierarchy:DisplayName] => Array([1] => displayname[6] => Array([7] => Unknown))[FolderHierarchy:Type] => Array([1] => type[6] => Array([7] => 18[14] => 0[13] => 20))[FolderHierarchy:IgnoreStore] => Array([1] => Store[3] => 5)[FolderHierarchy:IgnoreNoBackendFolder] => Array([1] => NoBackendFolder[3] => 5)[FolderHierarchy:IgnoreBackendId] => Array([1] => BackendId[3] => 5)[FolderHierarchy:IgnoreFlags] => Array([1] => Flags[3] => 5)[FolderHierarchy:TypeReal] => Array([1] => TypeReal[3] => 5))[flags] => NewMessage[content] => ))[7] => Array([0] => 1[1] => SyncFolder Object([serverid] => 101000000007[parentid] => 101000000004[displayname] => 2005[type] => 12[Store] => [NoBackendFolder] => [BackendId] => [Flags] => [TypeReal] => [unsetVars:protected] => Array()[supportsPrivateStripping:protected] => [mapping:protected] => Array([FolderHierarchy:ServerEntryId] => Array([1] => serverid[6] => Array([7] => ))[FolderHierarchy:ParentId] => Array([1] => parentid[6] => Array([7] => setToValue0))[FolderHierarchy:DisplayName] => Array([1] => displayname[6] => Array([7] => Unknown))[FolderHierarchy:Type] => Array([1] => type[6] => Array([7] => 18[14] => 0[13] => 20))[FolderHierarchy:IgnoreStore] => Array([1] => Store[3] => 5)[FolderHierarchy:IgnoreNoBackendFolder] => Array([1] => NoBackendFolder[3] => 5)[FolderHierarchy:IgnoreBackendId] => Array([1] => BackendId[3] => 5)[FolderHierarchy:IgnoreFlags] => Array([1] => Flags[3] => 5)[FolderHierarchy:TypeReal] => Array([1] => TypeReal[3] => 5))[flags] => NewMessage[content] => ))[8] => Array([0] => 1[1] => SyncFolder Object([serverid] => 101000000008[parentid] => 101000000004[displayname] => 2006[type] => 12[Store] => [NoBackendFolder] => [BackendId] => [Flags] => [TypeReal] => [unsetVars:protected] => Array()[supportsPrivateStripping:protected] => [mapping:protected] => Array([FolderHierarchy:ServerEntryId] => Array([1] => serverid[6] => Array([7] => ))[FolderHierarchy:ParentId] => Array([1] => parentid[6] => Array([7] => setToValue0))[FolderHierarchy:DisplayName] => Array([1] => displayname[6] => Array([7] => Unknown))[FolderHierarchy:Type] => Array([1] => type[6] => Array([7] => 18[14] => 0[13] => 20))[FolderHierarchy:IgnoreStore] => Array([1] => Store[3] => 5)[FolderHierarchy:IgnoreNoBackendFolder] => Array([1] => NoBackendFolder[3] => 5)[FolderHierarchy:IgnoreBackendId] => Array([1] => BackendId[3] => 5)[FolderHierarchy:IgnoreFlags] => Array([1] => Flags[3] => 5)[FolderHierarchy:TypeReal] => Array([1] => TypeReal[3] => 5))[flags] => NewMessage[content] => ))[9] => Array([0] => 1[1] => SyncFolder Object([serverid] => 101000000009[parentid] => 101000000004[displayname] => 2007[type] => 12[Store] => [NoBackendFolder] => [BackendId] => [Flags] => [TypeReal] => [unsetVars:protected] => Array()[supportsPrivateStripping:protected] => [mapping:protected] => Array([FolderHierarchy:ServerEntryId] => Array([1] => serverid[6] => Array([7] => ))[FolderHierarchy:ParentId] => Array([1] => parentid[6] => Array([7] =>  <log message with 267603 bytes truncated>
24/03/2022 16:44:15 [  813] [DEBUG] esyncpro_zpush::SetState() device->save(Array([dev_uid] => androidc1522186291[dev_id] => 1[account_id] => 7[dev_name] => [dev_model] => [dev_phoneno] => [dev_operator] => [dev_type] => Android[dev_imei] => [dev_os] => [dev_lang] => [dev_useragent] => Android-Mail/2022.02.20.431583552.Release[dev_access_status] => [dev_access_via] => [policy_id] => 1[dev_policy_applied] => 1648140159[dev_as_version] => 14.0[dev_last_sync] => 1648143759[dev_modified] => 2022-03-24 13:12:25[dev_modifier] => [dev_rw_status] => 1[dev_wiped] => [dev_last_cmd] => FolderSync[user_timezone_read] => Europe/Berlin[policykey] => 724120499))
24/03/2022 16:44:15 [  813] [DEBUG] SqlStateMachine->SetState(): devid:'androidc1522186291' type:'devicedata' key:'null' counter:'false'
24/03/2022 16:44:15 [  813] [DEBUG] DeviceManager->Save(): Device data saved
24/03/2022 16:44:15 [  813] [DEBUG] LoopDetection->ProcessLoopDetectionTerminate()
24/03/2022 16:44:15 [  813] [ INFO] cmd='FolderSync' memory='8.02 MiB/10.00 MiB' time='95.51s' devType='Android' devId='androidc1522186291' getUser='c.frey' from='172.19.0.1' idle='0s' version='EGroupware-21.1.20220207' method='POST' httpcode='200'
24/03/2022 16:44:15 [  813] [DEBUG] -------- End

Danke vorab für jegliche Info.
Christian.

Hallo Christian.

Ein Log kann man hier

mitlesen.


Fragen:
Wer bricht ab?
Hat der Mailer Ordner Synchronisiert? Wo hört der auf?
Gibt es Ordner mit Sonderzeichen Punkten, …?
Ordnertiefe?
Was für ein Android/ActiveSync-Client?


Ich habe ActiveSync auf einem Apple problemlos laufen.

Stefan

Hallo Stefan,

danke für die Rückmeldung.
Ich befürchte, Z-Push brincht bei der Vorbereitung zum Sync ab. Nach unzähligen AddFolder-Meldungen kommen noch einige Meldungen bzgl. Hyrarchie und dann endet das Ganze mit dem obigen Log-Ausschnitt.
Es gibt Ordner mit Punkten, Klammern oder Umlauten im Namen.
Auf dem Client erscheinen gar keine Daten, soweit scheint der Sync noch nicht einmal zu kommen.
Die selben Ordner / Daten werden aktuell vom Kopano auch via Z-Push zum Mobiltelefon synchronisiert und nun wollte ich den Sync über EGroupware zwecks möglichem Umsteig testen.

Christian.

Hi Christian.

Der EGw-Mail-Client hat aber bei dem Zugriff auf das Konto kein Problem?

Allein das mit den Punkten kann doch fast schon nicht sein. Punkte sind Trenner von Ordnern und Unterordnern bei IMAP.

Auf was für einen Mail-Server greifst du zu?

Hättest du ein Mail-Konto mit (nur) Standard-Ordnern zum Testen?

Was hast du auf der Client-Seite für Ein Android? Ich hätte hier ein Test-Gerät mit Android 9.

Stefan

Hallo Stefan,

aktuell nutze ich zum Test vor einer möglichen Migration noch unseren Kopano-Mailserver und greife via IMAP auf das Kopano-Gateway zu. Ich denke schon, dass Punkte dabei korrekt “escaped” werden, sonst würde die Anzeige der Ordner in Eurer WebApp ja auch nicht funktionieren. Ob Ihr dann bei der Umsetzung zu Z-Push eventuell einen “Hänger” habt, kann ich leider nicht so einfach evaluieren.

Nächste Woche werde ich mal einen weiteren Ordner anbinden und diesen dann für eSync (Z-Push) nutzen. Mal sehen, was dann psasiert.

Auf der Clientseite nutze ich ein Pixel 4 mit aktuellstem Patchlevel.

Christian.

Hallo Stefan,

irgendwie scheint Eure eSync Implementation von Z-Push nicht mit der IMAP-Mailanbindung des Kopano klar zu kommen, ich habe alle Punkte aus Ordnernamen entfernt, aber es klappt trotzdem nicht.
Sobald ich Dovecot am Laufen habe, teste ich es damit nochmal, aber leider gibt es dort mit der Aktivierung der Konten auch Probleme.

Es beginnt mit der “folder diff engine”, dann versucht er mit AddFolder alle Unterordner zu erfassen und knallt dann mit einer ganzen Latte an

05/04/2022 11:22:00 [  469] [WBXML] O    <FolderHierarchy:Add>
05/04/2022 11:22:00 [  469] [WBXML] O     <FolderHierarchy:ServerEntryId>
05/04/2022 11:22:00 [  469] [WBXML] O     10100000006A
05/04/2022 11:22:00 [  469] [WBXML] O     </FolderHierarchy:ServerEntryId>
05/04/2022 11:22:00 [  469] [WBXML] O     <FolderHierarchy:ParentId>
05/04/2022 11:22:00 [  469] [WBXML] O     101000000061
05/04/2022 11:22:00 [  469] [WBXML] O     </FolderHierarchy:ParentId>
05/04/2022 11:22:00 [  469] [WBXML] O     <FolderHierarchy:DisplayName>
05/04/2022 11:22:00 [  469] [WBXML] O     Lux
05/04/2022 11:22:00 [  469] [WBXML] O     </FolderHierarchy:DisplayName>
05/04/2022 11:22:00 [  469] [WBXML] O     <FolderHierarchy:Type>
05/04/2022 11:22:00 [  469] [WBXML] O     12
05/04/2022 11:22:00 [  469] [WBXML] O     </FolderHierarchy:Type>
05/04/2022 11:22:00 [  469] [WBXML] O    </FolderHierarchy:Add>

Meldungen weg.

VG, Christian.

OK, das Thema hat sich nun erledigt, nach dem Umzug aller Mails auf Dovecot funktioniert auch eSync schnell und fehlerfrei.
Da scheint es wohl definitiv Probleme mit der IMAP-Implementation des Kopano-Gateways zu geben, aber das ist ja sowieso nicht der Weg, den man gehen möchte, daher kann man dieses Problem auch getrost ignorieren.

Gruß, Christian.

Hallo Christian.

Gut zu hören. Wir hätten uns da vermutlich viel Arbeit gemacht das zu debuggen. Ohne dass es sich gelohnt hätte…

Hast du den EGroupware-Dovecot auf der EGroupware-Installation nun in Verwendung?


Thema als gelöst markieren:
Da gibt es ein Häkchen zu setzen:

Edit: Sehe gerade dass du es gefunen hast.

Stefan

Hallo @StefanU,

Gut zu hören. Wir hätten uns da vermutlich viel Arbeit gemacht das zu debuggen. Ohne dass es sich gelohnt hätte…

Definitiv.
Ich dachte es mir schon fast, dass es mit der Kopano-MAPI->IMAP->EGroupware->Z-Push Wandlung liegen wird. Besonders, da auch die IMAP-Anzeige des Postfachs unerträglich langsam war, was nach der Migration auf Dovecot nun “rasend” schnell ist.

Hast du den EGroupware-Dovecot auf der EGroupware-Installation nun in Verwendung?

Ja genau den, allerdings gibt es noch einige Probleme mit Berechtigungen, die auch @RalfBecker erst einmal mit Fragezeichen in den Augen quittieren und die Fehlersuche abbrechen musste.

VG, Christian.