StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF8004010F

Tommy2002

Hello Manfred,

I deleted the profile on my smartphone and ran the following command via z-push-admin:

z-push-admin -a remove -d <deviceId>

Then I have the profile newly set up on the smartphone, but I have the same problem again. No emails are synced.

The log I have set to WBXML. The problem seems to be well, that does not find the INBOX folder.

The log can be viewed at https://gitlab.com/Tommy2002MW/z-push/blob/master/error_log_20180822.txt

Thank you

Tommy2002

Manfred

Hello Tommy2002,

does the log end like that or is it not complete? It’s also not necessary to set the level to WBXMLSTACK, WBXML is enough.

Did this error start to occur after Z-Push or Kopano update?

Was the user’s store unhooked and hooked again or restored from the backup or something like that? Or any scripts executed on his store?

Manfred

Tommy2002

Hello Manfred,

we have updated the Kopano version but this is already a few days ago.
The log was just an excerpt. Do you need the complete log?

greeting

Tommy2002

Manfred

Hello Tommy2002,

yes, the complete log would be helpful.

Manfred

kg

Hey. Is there any update on that issue? I ran into the same and have no idea on how to fix it.
I already removed the device from z-push and the profile on the phone. Now it’s not able to run the initial sync anymore and I therefore have no access to mails, calendars and contacts on my phone.
I tried to check the logs on the kopano server, but even for debug there is no messages in there indicating any error.

kg

Seems like this https://forum.kopano.io/topic/369/solved-missing-or-broken-rss-folder-how-to-repair/6 fixed it for me.

kg

As it turns out, my fix from above is not really fixing it. After a few hours or sometimes days, the behavior appears again - always for the same users.

The really weird thing is that it happens from one moment to the other, without any changes happening. And sometimes the issue disappears the same way on it’s own.

Anybody any ideas what it could be caused by or how it can be fixed permanently?

Kopano Core: 8.7.80
Z-Push: 2.4.5+0-0

Edit: If it helps, I can provide a WBXML log where the issue is happening.

Manfred

Hi kg,

yes, the WBXML log would be helpful.

Manfred

kg

@Manfred Thank you. Sent you the wbxml log in a private message.
Hope this allows us to finf the issue. If you need more info, please let me know.

Manfred

Hi kg,

do you have WBXML log from earlier? According to the log the folder with the issue was successfully synced at 15:15 UTC.

Could you also post z-push-admin output for that device?

z-push-admin -a list -u user -d deviceid

Manfred

kg

Hey.
Sadly I don’t have any wbxml log from earlier. I can remove the device and add it again and then capture a longer one, if that helps.

The z-push-admin output:

-----------------------------------------------------
DeviceId:               androidc769649512
Device type:            Android
UserAgent:              Android-Mail/8.11.25.224448671.release
Device Model:           TA-1053
Device friendly name:   TA-1053
Device OS:              Android 8.1.0
ActiveSync version:     14.1
First sync:             2019-01-15 18:46
Last sync:              2019-01-23 21:45
Sync Period:            unlimited (0)
Total folders:          33
Short folder Ids:       Yes
Synchronized folders:   23
Synchronized data:      Emails(17) Contacts(3) Calendars(2) Tasks
Additional Folders:     none
Status:                 OK
WipeRequest on:         not set
WipeRequest by:         not set
Wiped on:               not set
Policy name:            default
Attention needed:       No errors known

Thank you

kg

Maybe this helps as well: Currently the z-push-top output for the affected user looks like this:

z-push-top output

While I’m trying to sync, constantly new gray rows appear…

Manfred

Hi kg,

@kg said in StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF8004010F:

Hey.
Sadly I don’t have any wbxml log from earlier. I can remove the device and add it again and then capture a longer one, if that helps.

Yes, that would help.

Manfred

kg

Alright. I removed the account from the device and then from the server, rebooted the device and created the account again on the device. I sent you that log again as a message.
Looking forward to your analysis.

Thank you.

Manfred

Hi kg,

@kg said in StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF8004010F:

Alright. I removed the account from the device and then from the server, rebooted the device and created the account again on the device. I sent you that log again as a message.

By “then from the server” do you mean that you removed the device using z-push-admin? If not, please do that before creating the account again.

It looks like you’re trying to sync 1 week back and there are more than 7000 items in the inbox for that time, is it correct? Did you try to reduce the sync window to 3 days or 1 day?

How many users are on the system?

Manfred

kg

Hey Manfred,

@Manfred said in StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF8004010F:

By “then from the server” do you mean that you removed the device using z-push-admin?

Yes, thats exactly what I did.
It is correct, the device tries to sync one week of emails, but that one week of emails is only ~100 emails (I checked it in the Webapp). The 7000 items is the total number of emails in the inbox which are from May 2017 onwards.

I haven’t tried to reduce the window to less days, but will do so and report back.

There are 14 users on that system: Most of them have a total store size of less than 10MB, 6 of them have a total store size between 200 and 1000MB.
The user that I sent you the log of has a total store size of 400MB.

Thank you

kg

I reduced the sync window on the client to 1 day (the lowest possible one), but the picture is still the same: The same error is reported for every request and the wbxml log looks the same (at least to me) as well.

z-push-admin is still reporting that it tries to sync the whole folder:

Synchronization progress:
	Folder: Posteingang          Sync: Initialized   Status:  0% (0/7135)

For reference: the complete output of z-push-admin -a list -u USER

-----------------------------------------------------
DeviceId:		androidc769649512
Device type:		Android
UserAgent:		Android-Mail/8.11.25.224448671.release
Device Model:		TA-1053
Device friendly name:	TA-1053
Device OS:		Android 8.1.0
ActiveSync version:	14.1
First sync:		2019-01-24 13:37
Last sync:		2019-01-24 15:10
Sync Period:		unlimited (0)
Total folders:		33
Short folder Ids:	Yes
Synchronized folders:	1 (1 in progress)
Synchronized data:	Emails 
Synchronization progress:
	Folder: Posteingang          Sync: Initialized   Status:  0% (0/7135)
Additional Folders:	none
Status:			OK
WipeRequest on:		not set
WipeRequest by:		not set
Wiped on:		not set
Policy name:		default
Attention needed:	No errors known

Manfred

Hi kg,

As mapi_exportchanges_config() call is failing, it might be the core or the store issue. Maybe you could try to unhook his store and hook it again.

Also maybe the ICS log in KC would help. In the server.cfg of kopano set
log_level = 0x00200000

If you already have some log_level set, then sum the values (e.g. if log_level is 3, then set it to 0x00200003).

Manfred

kg

I unhooked the store and hooked it in again - no change.
I also enabled the ICS log, which is very verbose. It produces a lot of lines like these:

Thu Jan 24 16:22:43 2019: [ 200000] Processing: 03C31F4DB8E84DA0B6108B4EA93D8C43279A00000000, match=1
Thu Jan 24 16:22:43 2019: [ 200000] FirstSyncAccepted: sourcekey=03C31F4DB8E84DA0B6108B4EA93D8C43279A00000000, changetype=4097
Thu Jan 24 16:22:43 2019: [ 200000] Processing: 03C31F4DB8E84DA0B6108B4EA93D8C43269A00000000, match=1
Thu Jan 24 16:22:43 2019: [ 200000] FirstSyncAccepted: sourcekey=03C31F4DB8E84DA0B6108B4EA93D8C43269A00000000, changetype=4097
Thu Jan 24 16:22:43 2019: [ 200000] Processing: 03C31F4DB8E84DA0B6108B4EA93D8C43259A00000000, match=1
Thu Jan 24 16:22:43 2019: [ 200000] FirstSyncAccepted: sourcekey=03C31F4DB8E84DA0B6108B4EA93D8C43259A00000000, changetype=4097
Thu Jan 24 16:22:43 2019: [ 200000] Processing: 03C31F4DB8E84DA0B6108B4EA93D8C43249A00000000, match=1
Thu Jan 24 16:22:43 2019: [ 200000] FirstSyncAccepted: sourcekey=03C31F4DB8E84DA0B6108B4EA93D8C43249A00000000, changetype=4097
Thu Jan 24 16:22:43 2019: [ 200000] Processing: 03C31F4DB8E84DA0B6108B4EA93D8C43239A00000000, match=1
Thu Jan 24 16:22:43 2019: [ 200000] FirstSyncAccepted: sourcekey=03C31F4DB8E84DA0B6108B4EA93D8C43239A00000000, changetype=4097
Thu Jan 24 16:22:43 2019: [ 200000] Processing: 03C31F4DB8E84DA0B6108B4EA93D8C43229A00000000, match=1
Thu Jan 24 16:22:43 2019: [ 200000] FirstSyncAccepted: sourcekey=03C31F4DB8E84DA0B6108B4EA93D8C43229A00000000, changetype=4097
Thu Jan 24 16:22:43 2019: [ 200000] Processing: 03C31F4DB8E84DA0B6108B4EA93D8C43219A00000000, match=1
Thu Jan 24 16:22:43 2019: [ 200000] FirstSyncAccepted: sourcekey=03C31F4DB8E84DA0B6108B4EA93D8C43219A00000000, changetype=4097
Thu Jan 24 16:22:43 2019: [ 200000] Processing: 03C31F4DB8E84DA0B6108B4EA93D8C43209A00000000, match=1
Thu Jan 24 16:22:43 2019: [ 200000] FirstSyncAccepted: sourcekey=03C31F4DB8E84DA0B6108B4EA93D8C43209A00000000, changetype=4097
Thu Jan 24 16:22:43 2019: [ 200000] Processing: 03C31F4DB8E84DA0B6108B4EA93D8C431F9A00000000, match=1
Thu Jan 24 16:22:43 2019: [ 200000] FirstSyncAccepted: sourcekey=03C31F4DB8E84DA0B6108B4EA93D8C431F9A00000000, changetype=4097
Thu Jan 24 16:22:43 2019: [ 200000] Processing: 03C31F4DB8E84DA0B6108B4EA93D8C431E9A00000000, match=1
Thu Jan 24 16:22:43 2019: [ 200000] FirstSyncAccepted: sourcekey=03C31F4DB8E84DA0B6108B4EA93D8C431E9A00000000, changetype=4097
Thu Jan 24 16:22:43 2019: [ 200000] Processing: 03C31F4DB8E84DA0B6108B4EA93D8C431D9A00000000, match=1
Thu Jan 24 16:22:43 2019: [ 200000] FirstSyncAccepted: sourcekey=03C31F4DB8E84DA0B6108B4EA93D8C431D9A00000000, changetype=4097
Thu Jan 24 16:22:43 2019: [ 200000] Processing: 03C31F4DB8E84DA0B6108B4EA93D8C431C9A00000000, match=1
Thu Jan 24 16:22:43 2019: [ 200000] FirstSyncAccepted: sourcekey=03C31F4DB8E84DA0B6108B4EA93D8C431C9A00000000, changetype=4097
Thu Jan 24 16:22:44 2019: [ 200000] K-1200: sourcekey=, syncid=425074, changetype=1, flags=46
Thu Jan 24 16:22:44 2019: [ 200000] MatchRestrictions: matching 1000 rows

Anything specific that I can look for in there?

Manfred

Hi kg,

I’m not very familiar with the internals of the core, so unfortunately I can’t help you with that.

If you have a valid subscription, you can open a support case with Kopano.

Otherwise you can open a thread at https://forum.kopano.io/category/15/kopano-groupware-core section as I’m not sure how often the core devs check posts here.

Manfred