MAPI_E_INVALID_ENTRYID in search.log

Hello,

i’ve just upgraded from KC 8.3.1-42 to 8.4.2.0-10, did some research on logs for errors and found this one in my search.log:

2017-11-04 18:34:03,665 - index3 - ERROR - Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/kopano/log.py", line 87, in log_exc
    try: yield
  File "/usr/lib/python2.7/site-packages/kopano_search/__init__.py", line 199, in main
    folder = kopano.Folder(store, folderid)
  File "/usr/lib/python2.7/site-packages/kopano/folder.py", line 79, in __init__
    self.mapiobj = store.mapiobj.OpenEntry(entryid, IID_IMAPIFolder, MAPI_MODIFY)
  File "/usr/lib/python2.7/site-packages/MAPICore.py", line 441, in OpenEntry
    def OpenEntry(self, *args): return _MAPICore.IMsgStore_OpenEntry(self, *args)
MAPIErrorInvalidEntryid: MAPI error 80040107 (MAPI_E_INVALID_ENTRYID)

2017-11-04 18:34:03,665 - search - INFO - queue processed in 0.05 seconds (0 changes, ~0.00/sec)
2017-11-04 18:34:03,666 - search - INFO - saved server sync state = 4D0501005A9C2103
2017-11-04 18:35:03,759 - index0 - ERROR - Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/kopano/log.py", line 87, in log_exc
    try: yield
  File "/usr/lib/python2.7/site-packages/kopano_search/__init__.py", line 199, in main
    folder = kopano.Folder(store, folderid)
  File "/usr/lib/python2.7/site-packages/kopano/folder.py", line 79, in __init__
    self.mapiobj = store.mapiobj.OpenEntry(entryid, IID_IMAPIFolder, MAPI_MODIFY)
  File "/usr/lib/python2.7/site-packages/MAPICore.py", line 441, in OpenEntry
    def OpenEntry(self, *args): return _MAPICore.IMsgStore_OpenEntry(self, *args)
MAPIErrorInvalidEntryid: MAPI error 80040107 (MAPI_E_INVALID_ENTRYID)

2017-11-04 18:35:03,793 - index2 - ERROR - Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/kopano/log.py", line 87, in log_exc
    try: yield
  File "/usr/lib/python2.7/site-packages/kopano_search/__init__.py", line 199, in main
    folder = kopano.Folder(store, folderid)
  File "/usr/lib/python2.7/site-packages/kopano/folder.py", line 79, in __init__
    self.mapiobj = store.mapiobj.OpenEntry(entryid, IID_IMAPIFolder, MAPI_MODIFY)
  File "/usr/lib/python2.7/site-packages/MAPICore.py", line 441, in OpenEntry
    def OpenEntry(self, *args): return _MAPICore.IMsgStore_OpenEntry(self, *args)
MAPIErrorInvalidEntryid: MAPI error 80040107 (MAPI_E_INVALID_ENTRYID)

2017-11-04 18:35:03,793 - search - INFO - queue processed in 0.05 seconds (0 changes, ~0.00/sec)
2017-11-04 18:35:03,794 - search - INFO - saved server sync state = 4D0501005E9C2103
2017-11-04 18:36:03,887 - index1 - ERROR - Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/kopano/log.py", line 87, in log_exc
    try: yield
  File "/usr/lib/python2.7/site-packages/kopano_search/__init__.py", line 199, in main
    folder = kopano.Folder(store, folderid)
  File "/usr/lib/python2.7/site-packages/kopano/folder.py", line 79, in __init__
    self.mapiobj = store.mapiobj.OpenEntry(entryid, IID_IMAPIFolder, MAPI_MODIFY)
  File "/usr/lib/python2.7/site-packages/MAPICore.py", line 441, in OpenEntry
    def OpenEntry(self, *args): return _MAPICore.IMsgStore_OpenEntry(self, *args)
MAPIErrorInvalidEntryid: MAPI error 80040107 (MAPI_E_INVALID_ENTRYID)


i cant find any problems if i search… results are dispalyed, its working fast (0,05 sec for 427 results), but every minute it displays this error
does someone know this issue?

Coffee_is_life

I noticed something similar here: kc entryid-corruption.
++umgfoin.

Hello again,

I’ve just searched for the start of my MAPI_E_INVALID_ENTRYID erro and found it:

2017-11-04 17:24:04,238 - search - INFO - queue processed in 0.07 seconds (4 changes, ~56.63/sec)
2017-11-04 17:24:04,239 - search - INFO - saved server sync state = 4D05010021902103
2017-11-04 17:24:19,276 - search - ERROR - Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/kopano/log.py", line 87, in log_exc
    try: yield
  File "/usr/lib/python2.7/site-packages/kopano_search/__init__.py", line 351, in incremental_sync
    new_state = self.server.sync(importer, self.state, log=self.log)
  File "/usr/lib/python2.7/site-packages/kopano/server.py", line 566, in sync
    return _utils.sync(self, self.mapistore, importer, state, log or self.log, max_changes, window=window, begin=begin, end=end, stats=stats)
  File "/usr/lib/python2.7/site-packages/kopano/utils.py", line 287, in sync
    exporter.Config(stream, SYNC_NORMAL | SYNC_ASSOCIATED | SYNC_UNICODE, importer, restriction, None, None, 0)
  File "/usr/lib/python2.7/site-packages/MAPICore.py", line 1341, in Config
    def Config(self, *args): return _MAPICore.IExchangeExportChanges_Config(self, *args)
MAPIErrorNetworkError: MAPI error 80040115 (MAPI_E_NETWORK_ERROR)

2017-11-04 17:24:24,278 - search - ERROR - Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/kopano/log.py", line 87, in log_exc
    try: yield
  File "/usr/lib/python2.7/site-packages/kopano_search/__init__.py", line 351, in incremental_sync
    new_state = self.server.sync(importer, self.state, log=self.log)
  File "/usr/lib/python2.7/site-packages/kopano/server.py", line 566, in sync
    return _utils.sync(self, self.mapistore, importer, state, log or self.log, max_changes, window=window, begin=begin, end=end, stats=stats)
  File "/usr/lib/python2.7/site-packages/kopano/utils.py", line 287, in sync
    exporter.Config(stream, SYNC_NORMAL | SYNC_ASSOCIATED | SYNC_UNICODE, importer, restriction, None, None, 0)
  File "/usr/lib/python2.7/site-packages/MAPICore.py", line 1341, in Config
    def Config(self, *args): return _MAPICore.IExchangeExportChanges_Config(self, *args)
MAPIErrorNetworkError: MAPI error 80040115 (MAPI_E_NETWORK_ERROR)

2017-11-04 17:24:29,284 - search - ERROR - Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/kopano/log.py", line 87, in log_exc
    try: yield
  File "/usr/lib/python2.7/site-packages/kopano_search/__init__.py", line 351, in incremental_sync
    new_state = self.server.sync(importer, self.state, log=self.log)
  File "/usr/lib/python2.7/site-packages/kopano/server.py", line 566, in sync
    return _utils.sync(self, self.mapistore, importer, state, log or self.log, max_changes, window=window, begin=begin, end=end, stats=stats)
  File "/usr/lib/python2.7/site-packages/kopano/utils.py", line 287, in sync
    exporter.Config(stream, SYNC_NORMAL | SYNC_ASSOCIATED | SYNC_UNICODE, importer, restriction, None, None, 0)
  File "/usr/lib/python2.7/site-packages/MAPICore.py", line 1341, in Config
    def Config(self, *args): return _MAPICore.IExchangeExportChanges_Config(self, *args)
MAPIErrorNetworkError: MAPI error 80040115 (MAPI_E_NETWORK_ERROR)

2017-11-04 17:24:31,525 - search - INFO - stopping search
2017-11-04 17:37:34,166 - search - INFO - starting search
2017-11-04 17:39:06,308 - search - INFO - found previous server sync state: 4D05010021902103
2017-11-04 17:39:06,314 - search - INFO - starting incremental sync
2017-11-04 17:39:06,356 - query - INFO - listening on socket file:///var/run/kopano/search.sock
2017-11-04 17:39:06,378 - query - INFO - Command: PROPS
2017-11-04 17:39:06,379 - query - INFO - Response: OK:125 100 3102 117 26510 26511 26
2017-11-04 17:39:11,555 - index0 - ERROR - Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/kopano/log.py", line 87, in log_exc
    try: yield
  File "/usr/lib/python2.7/site-packages/kopano_search/__init__.py", line 199, in main
    folder = kopano.Folder(store, folderid)
  File "/usr/lib/python2.7/site-packages/kopano/folder.py", line 79, in __init__
    self.mapiobj = store.mapiobj.OpenEntry(entryid, IID_IMAPIFolder, MAPI_MODIFY)
  File "/usr/lib/python2.7/site-packages/MAPICore.py", line 441, in OpenEntry
    def OpenEntry(self, *args): return _MAPICore.IMsgStore_OpenEntry(self, *args)
MAPIErrorInvalidEntryid: MAPI error 80040107 (MAPI_E_INVALID_ENTRYID)


2017-11-04 17:39:11,570 - index2 - ERROR - Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/kopano/log.py", line 87, in log_exc
    try: yield
  File "/usr/lib/python2.7/site-packages/kopano_search/__init__.py", line 199, in main
    folder = kopano.Folder(store, folderid)
  File "/usr/lib/python2.7/site-packages/kopano/folder.py", line 79, in __init__
    self.mapiobj = store.mapiobj.OpenEntry(entryid, IID_IMAPIFolder, MAPI_MODIFY)
  File "/usr/lib/python2.7/site-packages/MAPICore.py", line 441, in OpenEntry
    def OpenEntry(self, *args): return _MAPICore.IMsgStore_OpenEntry(self, *args)
MAPIErrorInvalidEntryid: MAPI error 80040107 (MAPI_E_INVALID_ENTRYID)

2017-11-04 17:39:11,571 - search - INFO - queue processed in 0.06 seconds (0 changes, ~0.00/sec)
2017-11-04 17:39:11,573 - search - INFO - saved server sync state = 4D0501002D902103
2017-11-04 17:39:41,612 - search - INFO - queue processed in 0.00 seconds (0 changes, ~0.00/sec)
2017-11-04 17:39:41,619 - search - INFO - saved server sync state = 4D0501002E902103
2017-11-04 17:40:01,722 - index3 - ERROR - Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/kopano/log.py", line 87, in log_exc
    try: yield
  File "/usr/lib/python2.7/site-packages/kopano_search/__init__.py", line 199, in main
    folder = kopano.Folder(store, folderid)
  File "/usr/lib/python2.7/site-packages/kopano/folder.py", line 79, in __init__
    self.mapiobj = store.mapiobj.OpenEntry(entryid, IID_IMAPIFolder, MAPI_MODIFY)
  File "/usr/lib/python2.7/site-packages/MAPICore.py", line 441, in OpenEntry
    def OpenEntry(self, *args): return _MAPICore.IMsgStore_OpenEntry(self, *args)
MAPIErrorInvalidEntryid: MAPI error 80040107 (MAPI_E_INVALID_ENTRYID)

2017-11-04 17:40:01,757 - index1 - ERROR - Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/kopano/log.py", line 87, in log_exc
    try: yield
  File "/usr/lib/python2.7/site-packages/kopano_search/__init__.py", line 199, in main
    folder = kopano.Folder(store, folderid)
  File "/usr/lib/python2.7/site-packages/kopano/folder.py", line 79, in __init__
    self.mapiobj = store.mapiobj.OpenEntry(entryid, IID_IMAPIFolder, MAPI_MODIFY)
  File "/usr/lib/python2.7/site-packages/MAPICore.py", line 441, in OpenEntry
    def OpenEntry(self, *args): return _MAPICore.IMsgStore_OpenEntry(self, *args)
MAPIErrorInvalidEntryid: MAPI error 80040107 (MAPI_E_INVALID_ENTRYID)

2017-11-04 17:40:01,758 - search - INFO - queue processed in 0.06 seconds (0 changes, ~0.00/sec)
2017-11-04 17:40:01,758 - search - INFO - saved server sync state = 4D05010032902103
2017-11-04 17:41:01,907 - index4 - ERROR - Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/kopano/log.py", line 87, in log_exc
    try: yield
  File "/usr/lib/python2.7/site-packages/kopano_search/__init__.py", line 199, in main
    folder = kopano.Folder(store, folderid)
  File "/usr/lib/python2.7/site-packages/kopano/folder.py", line 79, in __init__
    self.mapiobj = store.mapiobj.OpenEntry(entryid, IID_IMAPIFolder, MAPI_MODIFY)
  File "/usr/lib/python2.7/site-packages/MAPICore.py", line 441, in OpenEntry
    def OpenEntry(self, *args): return _MAPICore.IMsgStore_OpenEntry(self, *args)
MAPIErrorInvalidEntryid: MAPI error 80040107 (MAPI_E_INVALID_ENTRYID)

since then we got MAPI error every minute from each search-worker.
Searching an Item via DeskApp or WebApp results in found items from 04-11-2017 and earlier. All recent items cant be searched!

should i reindex my db since the cause seems to be a Network-Error and maybe an unfinished handle. Even updating the packages (+reboot) at 11-11-2017 didnt resolve it.

im a bit afraid that reindexing results in more Mapi errors and nothing can be searched…

//EDIT: seems my thought was right:
just deleted the index on my cloned mailserver:

1507176 2017-11-23 16:21:13,528 - search - INFO - starting search
1507177 2017-11-23 16:21:13,593 - search - INFO - starting initial sync
1507178 2017-11-23 16:27:34,415 - search - INFO - queued 7477 folders (~713644 changes) for parallel indexing (5 processes)
1507179 2017-11-23 16:27:34,414 - index1 - ERROR - Traceback (most recent call last):
1507180   File "/usr/lib/python2.7/site-packages/kopano/log.py", line 87, in log_exc
1507181     try: yield
1507182   File "/usr/lib/python2.7/site-packages/kopano_search/__init__.py", line 199, in main
1507183     folder = kopano.Folder(store, folderid)
1507184   File "/usr/lib/python2.7/site-packages/kopano/folder.py", line 79, in __init__
1507185     self.mapiobj = store.mapiobj.OpenEntry(entryid, IID_IMAPIFolder, MAPI_MODIFY)
1507186   File "/usr/lib/python2.7/site-packages/MAPICore.py", line 441, in OpenEntry
1507187     def OpenEntry(self, *args): return _MAPICore.IMsgStore_OpenEntry(self, *args)
1507188 MAPIErrorInvalidEntryid: MAPI error 80040107 (MAPI_E_INVALID_ENTRYID)
1507189
1507190 2017-11-23 16:27:34,414 - index3 - ERROR - Traceback (most recent call last):
1507191   File "/usr/lib/python2.7/site-packages/kopano/log.py", line 87, in log_exc
1507192     try: yield
1507193   File "/usr/lib/python2.7/site-packages/kopano_search/__init__.py", line 199, in main
1507194     folder = kopano.Folder(store, folderid)
1507195   File "/usr/lib/python2.7/site-packages/kopano/folder.py", line 79, in __init__
1507196     self.mapiobj = store.mapiobj.OpenEntry(entryid, IID_IMAPIFolder, MAPI_MODIFY)
1507197   File "/usr/lib/python2.7/site-packages/MAPICore.py", line 441, in OpenEntry
1507198     def OpenEntry(self, *args): return _MAPICore.IMsgStore_OpenEntry(self, *args)
1507199 MAPIErrorInvalidEntryid: MAPI error 80040107 (MAPI_E_INVALID_ENTRYID)
[...]

coffee_is_life

Hello Forum,

ive just done an upgrade to 8.4.4.0-29 on productive server (test-env was updated a while ago), updated all libkc elements (hint for kopano-team, check dependencies, otherwise kopano-server wont start cause of unexpected version from libkcserver-ldap.so module (Version of the plugin “libkcserver-ldap.so” is not the same for the server. Expected 0x80420000 (8.4.2.0), plugin 0x80440000) ), renamed index-folder to start another initial-sync.
This is going to take a while. - My test-env was clean after this, so i think on my productive-system should be good aswell.
i will text if not.

coffee_is_life