Errors after updating z-push to 2.4.1-100

Hello Forum,

updated z-push from 2.3.9 to 2.4.1-100 this weekend and now i’m getting several error-messages:

this is my z-push.log:

14/05/2018 09:03:18 [15880] [WARN] [<user1>] KopanoBackend->HasReadACLs(): error, could not open folder with entryid 0000000098762d924cdf425da122d27688a9de510100000003000000d1cc812a9c9b4f6db6c0d2f1dc40d02d00000000 on store Resource id #51
14/05/2018 09:03:18 [15880] [WARN] [<user1>] KopanoBackend->HasSecretaryACLs(): error, could not open folder with entryid 0000000098762d924cdf425da122d27688a9de510100000003000000d1cc812a9c9b4f6db6c0d2f1dc40d02d00000000 on store Resource id #51
[...]
14/05/2018 09:05:20 [17061] [WARN] [<user1>] ImportChangesICS->lazyLoadConflicts(): conflict detection abandoned as there are too many (2461) changes to be exported.
[...]
14/05/2018 09:06:34 [ 7022] [ERROR] [<user2>] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'FolderSync' at 14/05/2018 09:06:34 with pid '17067' terminated unexpectedly or is still running.
14/05/2018 09:06:34 [ 7022] [ERROR] [<user2>] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
14/05/2018 09:06:34 [15695] [ERROR] [<user2>] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'FolderSync' at 14/05/2018 09:06:34 with pid '17067' terminated unexpectedly or is still running.
14/05/2018 09:06:34 [15695] [ERROR] [<user2>] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
14/05/2018 09:06:34 [14418] [ERROR] [<user2>] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'FolderSync' at 14/05/2018 09:06:34 with pid '17067' terminated unexpectedly or is still running.
14/05/2018 09:06:34 [14418] [ERROR] [<user2>] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
[...]
14/05/2018 12:49:13 [23800] [ERROR] [<userX>] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'FolderSync' at 14/05/2018 12:49:13 with pid '30555' terminated unexpectedly or is still running.
14/05/2018 12:49:13 [23800] [ERROR] [<userX>] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.

any suggestions?

coffee_is_life

further im getting many HasReadACL errors:

14/05/2018 12:28:04 [10513] [WARN] [<user3>] KopanoBackend->HasReadACLs(): error, could not open folder with entryid 0000000098762d924cdf425da122d27688a9de510100000003000000d1cc812a9c9b4f6db6c0d2f1dc40d02d00000000 on store Resource id #65
14/05/2018 12:28:05 [18957] [WARN] [<user3>] KopanoBackend->HasReadACLs(): error, could not open folder with entryid 0000000098762d924cdf425da122d27688a9de510100000003000000d1cc812a9c9b4f6db6c0d2f1dc40d02d00000000 on store Resource id #38
14/05/2018 12:28:16 [10513] [WARN] [<user3>] KopanoBackend->HasReadACLs(): error, could not open folder with entryid 0000000098762d924cdf425da122d27688a9de510100000003000000d1cc812a9c9b4f6db6c0d2f1dc40d02d00000000 on store Resource id #65
14/05/2018 12:29:08 [ 4185] [WARN] [<user4>] KopanoBackend->HasReadACLs(): error, could not open folder with entryid 0000000098762d924cdf425da122d27688a9de51010000000300000013b147ea137d4379a14b81f91a6df02900000000 on store Resource id #591
14/05/2018 12:29:08 [ 4185] [WARN] [<user4>] KopanoBackend->HasReadACLs(): error, could not open folder with entryid 0000000098762d924cdf425da122d27688a9de510100000003000000d1cc812a9c9b4f6db6c0d2f1dc40d02d00000000 on store Resource id #591

How do i check which Ressource is the id #65 or #591 etc and their permissions?
is the table “acl” there is no such id.

coffee_is_life

Hi coffee_is_life,

do you have any shared or public folders configured in config.php and do user3, user4, user5 have permissions to access them? Have user3, user4, user5 opened shared or public folders via KOE or via z-push-admin and do they have permissions to open them?

Resource #x is PHP’s way to represent a resource as string. More information which store it is should be available on DEBUG level.

Manfred

good morning @Manfred,

the Users got several shared folders, added via KOE before updating z-push - i’ve tried to remove and add via z-push-admin and KOE.
Permissions are set via webapp, the folder is readable and writable in outlook.

This is the DEBUG from HasReadACLs error:

15/05/2018 08:32:43 [ 3566] [DEBUG] ChangesMemoryWrapper->Config(AdditionalFolders) : process folder 'Kalender - another-user'
15/05/2018 08:32:43 [ 3566] [DEBUG] KopanoBackend->Setup(): Checking for 'read' ACLs on 'f0bc882a8e0f410aa9765bd325976fe71c6e00000000' of store 'another-user': 'true'
15/05/2018 08:32:43 [ 3566] [DEBUG] ChangesMemoryWrapper->ImportFolderChange(): Change for folder 'Kalender - another-user' will not be sent as modification is not relevant.
15/05/2018 08:32:43 [ 3566] [DEBUG] ChangesMemoryWrapper->Config(AdditionalFolders) : process folder 'Kalender'
15/05/2018 08:32:43 [ 3566] [WARN] KopanoBackend->HasReadACLs(): error, could not open folder with entryid 0000000098762d924cdf425da122d27688a9de510100000003000000d1cc812a9c9b4f6db6c0d2f1dc40d02d00000000 on store Resource id #214
15/05/2018 08:32:43 [ 3566] [DEBUG] KopanoBackend->Setup(): Checking for 'read' ACLs on 'f0bc882a8e0f410aa9765bd325976fe7ec0f02000000' of store 'another-user': 'false'

the permissions are:
complete postbox: read, list folders
calendar: secretary
i can access the items in outlook and if i want to remove and readd this via KOE, all folders are visible in adding screen.

coffee_is_life

Hello,

i’ve tried to get some information about this entryid…
used resolve-entryid.py:

python ./resolve-entryid.py --entryid 0000000098762d924cdf425da122d27688a9de510100000003000000d1cc812a9c9b4f6db6c0d2f1dc40d02d00000000
Entryid : 0000000098762d924cdf425da122d27688a9de510100000003000000d1cc812a9c9b4f6db6c0d2f1dc40d02d00000000
Guessed storeid : 98762d924cdf425da122d27688a9de51
Traceback (most recent call last):
  File "./resolve-entryid.py", line 69, in <module>
    main()
  File "./resolve-entryid.py", line 27, in main
    print ('Store : {}\nUser : {} ({})'.format(store.name, store.user.name, store.user.fullname))
UnicodeEncodeError: 'ascii' codec can't encode character u'\xe9' in position 4: ordinal not in range(128)

the string which should print is: “André”

its messing with the “é” - i bet its the same error why this entry cant be accessed via the HasReadACLs module

any ideas to get this working?

coffee_is_life

@coffee_is_life said in Errors after updating z-push to 2.4.1-100:

i bet its the same error

what are you willing to bet?

Did you also update Kopano Groupware core at the same time? from which version to which version?

good morning,

i’ve updated from -8.5.4.0-9.1 to 8.5.8.2-27.1
z-push from 2.3.9+0-90 to 2.4.1+0-100.1

and i encountered another warning:

17/05/2018 08:11:19 [30358] [WARN] [<user>] StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF80040116 - code: 12 - file: /usr/share/z-push/backend/kopano/exporter.php:230
17/05/2018 08:11:19 [30358] [WARN] [<user>] StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF80040116 - code: 12 - file: /usr/share/z-push/backend/kopano/exporter.php:230
17/05/2018 08:11:19 [30358] [WARN] [<user>] StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF80040116 - code: 12 - file: /usr/share/z-push/backend/kopano/exporter.php:230
17/05/2018 08:11:19 [30358] [WARN] [<user>] StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF80040116 - code: 12 - file: /usr/share/z-push/backend/kopano/exporter.php:230
17/05/2018 08:11:19 [30358] [WARN] [<user>] StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF80040116 - code: 12 - file: /usr/share/z-push/backend/kopano/exporter.php:230
17/05/2018 08:11:19 [30358] [WARN] [<user>] StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF8004010F - code: 12 - file: /usr/share/z-push/backend/kopano/exporter.php:230
17/05/2018 08:11:19 [30358] [WARN] [<user>] StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF80040116 - code: 12 - file: /usr/share/z-push/backend/kopano/exporter.php:230
17/05/2018 08:11:19 [30358] [WARN] [<user>] StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF80040116 - code: 12 - file: /usr/share/z-push/backend/kopano/exporter.php:230
17/05/2018 08:11:19 [30358] [WARN] [<user>] StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF80040116 - code: 12 - file: /usr/share/z-push/backend/kopano/exporter.php:230

logs from 1 to (max seen) 84 times when outlook from this user starts - multiple users are effected by this message
cant see missing mails or other impairments

any ideas?

coffee_is_life

@coffee_is_life

Can you try if the updated resolv-entryid.py now works for this entryid, though this is/was just a python issue.

Thanks

@markb,

thanks for the fix, now the script works but it tells me:

Entryid : 0000000098762d924cdf425da122d27688a9de51010000000300000013b147ea137d4379a14b81f91a6df02900000000
Guessed storeid : 98762d924cdf425da122d27688a9de51
Store : adierssen
User : adierssen (André Dierßen)
Error no item with entryid '0000000098762d924cdf425da122d27688a9de51010000000300000013b147ea137d4379a14b81f91a6df02900000000'

the second error with this “HasACLs” is in the same store but another entryid
how it comes that 4 users who have access to this account (mail and calendar) get the errors above when the item is not present?
something wrong within my db?

coffee_is_life

Hi coffee_is_life,

80040116 is a MAPI_E_DISK_ERROR which can be a lot of things, but it means that something’s wrong with the store.

17/05/2018 08:11:19 [30358] [WARN] [<user>] StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF8004010F - code: 12 - file: /usr/share/z-push/backend/kopano/exporter.php:230

8004010F is MAPI_E_NOT_FOUND which probably means that this folder was moved or deleted but Outlook is not aware of it and still tries to synchronise it.
In addition to missing read permissions I’d say that something’s wrong with that folder.
Was this store hooked and unhooked? Or restored from the backup?

Manfred

hello @Manfred,

nothing was done with this store. maybe this is an error existing a long time and i didnt see it because there was no one who accessed the store as shared folder before monday.

i could unhook and rehook or create a kopano-backup, unhook and create a new one, than restore it
suggested?

Coffee_is_life

Hi Coffee_is_life,

did you run listfolders.php on that store and compared the entryids?
For the outlook user it might be enough to run z-push-admin -a resync -t hierarchy -u user -d deviceid or if that doesn’t help to create a new Outlook profile.
The users who opened that shared store will probably have to reconfigure it. How did they configured that share? Using z-push-admin or KOE or MDM plugin in Webapp?

Manfred

The original user (owner) is one of our few users who still use Outlook 2010 via MAPI. - so a resync via z-push isnt possible, but i can recreate the ol profile.
The users who shared it are on Outlook 2016, addeded via z-push-admin - used my script i posted a while ago
(https://github.com/Coffee-IsLife/Z-Push-shared-folders)

//EDIT: just tried to get the entryid, but i cant find any part of the long id in the log above in listfolders.php -l adierssen these ids are all shorter and obviously folderids and not directly entrys…

coffee_is_life

Hi Coffee_is_life

Now I’m confused.

@coffee_is_life said in Errors after updating z-push to 2.4.1-100:

The original user (owner) is one of our few users who still use Outlook 2010 via MAPI. - so a resync via z-push isnt possible, but i can recreate the ol profile.

@coffee_is_life said in Errors after updating z-push to 2.4.1-100:

and i encountered another warning:

17/05/2018 08:11:19 [30358] [WARN] [<user>] StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF80040116 - code: 12 - file: /usr/share/z-push/backend/kopano/exporter.php:230
17/05/2018 08:11:19 [30358] [WARN] [<user>] StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF80040116 - code: 12 - file: /usr/share/z-push/backend/kopano/exporter.php:230
17/05/2018 08:11:19 [30358] [WARN] [<user>] StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF80040116 - code: 12 - file: /usr/share/z-push/backend/kopano/exporter.php:230
17/05/2018 08:11:19 [30358] [WARN] [<user>] StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF80040116 - code: 12 - file: /usr/share/z-push/backend/kopano/exporter.php:230
17/05/2018 08:11:19 [30358] [WARN] [<user>] StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF80040116 - code: 12 - file: /usr/share/z-push/backend/kopano/exporter.php:230
17/05/2018 08:11:19 [30358] [WARN] [<user>] StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF8004010F - code: 12 - file: /usr/share/z-push/backend/kopano/exporter.php:230
17/05/2018 08:11:19 [30358] [WARN] [<user>] StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF80040116 - code: 12 - file: /usr/share/z-push/backend/kopano/exporter.php:230
17/05/2018 08:11:19 [30358] [WARN] [<user>] StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF80040116 - code: 12 - file: /usr/share/z-push/backend/kopano/exporter.php:230
17/05/2018 08:11:19 [30358] [WARN] [<user>] StatusException: ExportChangesICS->InitializeExporter(): Error, mapi_exportchanges_config() failed: 0xFFFFFFFF80040116 - code: 12 - file: /usr/share/z-push/backend/kopano/exporter.php:230

logs from 1 to (max seen) 84 times when outlook from this user starts - multiple users are effected by this message
cant see missing mails or other impairments

So, the user adierssen is using outlook with the MAPI client but the above errors appear in z-push.log when he starts Outlook? Replacing all usernames with just user and providing only excerpts of log is confusing and not really helpful.

The users who shared it are on Outlook 2016, addeded via z-push-admin - used my script i posted a while ago
(https://github.com/Coffee-IsLife/Z-Push-shared-folders)

I saw your post back then, but I only looked briefly at the script.

//EDIT: just tried to get the entryid, but i cant find any part of the long id in the log above in listfolders.php -l adierssen these ids are all shorter and obviously folderids and not directly entrys…

sorry, listfolders.php lists sourcekeys, not the entryids. Could you post the output of it anyway?

Manfred

to clearify:
the logged errors with mapi exportchange are shown in z-push.log when outlook-users starts ol. multiple useres are effected.

the hasReadACLs (or HasSecretaryACLs) are shown in z-push.log when the user who have the store from adierssen mapped works in OL. - no special action, i think its caused by trying to sync this missing entry in every sync run.
these users got the inbox and calendar mapped via z-push-admin.

the owner of this box is adierssen who worlks with OL2010.

output of listfolder.php:

Available folders in store 'adierssen':
--------------------------------------------------
Folder name:    Kontoübersicht
Folder ID:      f0bc882a8e0f410aa9765bd325976fe7065709000000
Type:           SYNC_FOLDER_TYPE_USER_MAIL

Folder name:    Kontenübersicht
Folder ID:      f0bc882a8e0f410aa9765bd325976fe7341b02000000
Type:           SYNC_FOLDER_TYPE_USER_MAIL

Folder name:    Nils Gehring
Folder ID:      f0bc882a8e0f410aa9765bd325976fe7cd1a02000000
Type:           SYNC_FOLDER_TYPE_USER_MAIL

Folder name:    Andre Dierßen
Folder ID:      f0bc882a8e0f410aa9765bd325976fe7cc1a02000000
Type:           SYNC_FOLDER_TYPE_USER_MAIL

Folder name:    Vorgeschlagene Kontakte
Folder ID:      f0bc882a8e0f410aa9765bd325976fe7286e00000000
Type:           SYNC_FOLDER_TYPE_USER_CONTACT

Folder name:    Junk E-Mail
Folder ID:      f0bc882a8e0f410aa9765bd325976fe7216e00000000
Type:           SYNC_FOLDER_TYPE_USER_MAIL

Folder name:    Aufgaben
Folder ID:      f0bc882a8e0f410aa9765bd325976fe7206e00000000
Type:           SYNC_FOLDER_TYPE_USER_TASK

Folder name:    Notizen
Folder ID:      f0bc882a8e0f410aa9765bd325976fe71f6e00000000
Type:           SYNC_FOLDER_TYPE_USER_NOTE

Folder name:    Entwürfe
Folder ID:      f0bc882a8e0f410aa9765bd325976fe71d6e00000000
Type:           SYNC_FOLDER_TYPE_USER_MAIL

Folder name:    Kalender
Folder ID:      f0bc882a8e0f410aa9765bd325976fe71c6e00000000
Type:           SYNC_FOLDER_TYPE_USER_APPOINTMENT

Folder name:    Kontakte
Folder ID:      f0bc882a8e0f410aa9765bd325976fe71b6e00000000
Type:           SYNC_FOLDER_TYPE_USER_CONTACT

Folder name:    Gesendete Objekte
Folder ID:      f0bc882a8e0f410aa9765bd325976fe71a6e00000000
Type:           SYNC_FOLDER_TYPE_USER_MAIL

Folder name:    Gelöschte Objekte
Folder ID:      f0bc882a8e0f410aa9765bd325976fe7196e00000000
Type:           SYNC_FOLDER_TYPE_USER_MAIL

Folder name:    Postausgang
Folder ID:      f0bc882a8e0f410aa9765bd325976fe7186e00000000
Type:           SYNC_FOLDER_TYPE_USER_MAIL

Folder name:    Posteingang
Folder ID:      f0bc882a8e0f410aa9765bd325976fe7176e00000000
Type:           SYNC_FOLDER_TYPE_USER_MAIL

coffee_is_life

Hi Coffee_is_life,

so, the folder with the entryid 0000000098762d924cdf425da122d27688a9de510100000003000000d1cc812a9c9b4f6db6c0d2f1dc40d02d00000000 and sourcekey f0bc882a8e0f410aa9765bd325976fe7ec0f02000000 is apparently not in the store of the user adierssen as it’s not in the list returned by the listfolders (or it’s hidden or some special folder or was deleted by the user or moved).

All users who have configured it, will have the ACL error. All users who have synced it with Outlook will have the exporter errors.
For those users you could try to remove the folder from the shared list with z-push-admin remove. If only that doesn’t help, then hierarchy resync with z-push-admin. If then there still are errors, then re-create Outlook profile.

If you still have issues after that or additional comments, please open an issue with Kopano support as it’s getting too long for the forum.

Manfred

thanks for the investigation, will try these steps.
just one additional question, how did you get the link from sourcekey to entryid? trying to figure it out since quite some time :)

coffee_is_life

Hi Coffee_is_life,

15/05/2018 08:32:43 [ 3566] [WARN] KopanoBackend->HasReadACLs(): error, could not open folder with entryid 0000000098762d924cdf425da122d27688a9de510100000003000000d1cc812a9c9b4f6db6c0d2f1dc40d02d00000000 on store Resource id #214
15/05/2018 08:32:43 [ 3566] [DEBUG] KopanoBackend->Setup(): Checking for 'read' ACLs on 'f0bc882a8e0f410aa9765bd325976fe7ec0f02000000' of store 'another-user': 'false'

The top one is the entryid, the bottom one the sourcekey.

In PHP-MAPI there’s mapi_msgstore_entryidfromsourcekey($store, $parentsourcekey, $sourcekey); to do the conversion.

Manfred