Details
-
Bug
-
Resolution: Fixed
-
Critical
-
Cheshire-Cat
-
Untriaged
-
1
-
Yes
Description
Started seeing this issue from 7.0.0-2945 in build sanity:
18:12:01 ======================================================================
18:12:01 FAIL: test_flex_with_napa_dataset (tuqquery.flex_index_phase1.FlexIndexTests)
18:12:01 ----------------------------------------------------------------------
18:12:01 Traceback (most recent call last):
18:12:01 File "pytests/tuqquery/flex_index_phase1.py", line 483, in test_flex_with_napa_dataset
18:12:01 name="default_index", source_name=self.bucket_name, doc_count=self.num_items)
18:12:01 File "pytests/tuqquery/tuq.py", line 461, in create_fts_index
18:12:01 self.wait_for_fts_indexing_complete(fts_index, doc_count)
18:12:01 File "pytests/tuqquery/tuq.py", line 479, in wait_for_fts_indexing_complete
18:12:01 doc_count))
18:12:01 AssertionError: FTS indexing did not complete. FTS index count : 9777, Bucket count : 10000
18:12:01
18:12:01 ----------------------------------------------------------------------
From FTS logs:
20-08-25T17:57:57.162-07:00 [INFO] app_herder: indexing proceeding, indexes: 3, waiting: 1, usage: 89385208
2020-08-25T17:57:57.163-07:00 [INFO] app_herder: indexing proceeding, indexes: 3, waiting: 0, usage: 89385208
2020-08-25T17:59:26.946-07:00 [ERRO] (GOCBCORE) memdClient read failure: EOF – cbgt.GocbcoreLogger.Log() at gocbcore_helper.go:635
2020-08-25T17:59:26.971-07:00 [ERRO] (GOCBCORE) memdClient read failure: EOF – cbgt.GocbcoreLogger.Log() at gocbcore_helper.go:635
2020-08-25T17:59:26.989-07:00 [ERRO] (GOCBCORE) memdClient read failure: EOF – cbgt.GocbcoreLogger.Log() at gocbcore_helper.go:635
2020-08-25T17:59:27.090-07:00 [ERRO] (GOCBCORE) memdClient read failure: EOF – cbgt.GocbcoreLogger.Log() at gocbcore_helper.go:635
2020-08-25T17:59:27.090-07:00 [ERRO] (GOCBCORE) memdClient read failure: EOF – cbgt.GocbcoreLogger.Log() at gocbcore_helper.go:635
2020-08-25T17:59:27.090-07:00 [ERRO] (GOCBCORE) memdClient read failure: EOF – cbgt.GocbcoreLogger.Log() at gocbcore_helper.go:635
2020-08-25T17:59:27.090-07:00 [INFO] main: meh.OnFeedError, srcType: gocbcore, err: EOF |
2020-08-25T17:59:27.093-07:00 [INFO] main: meh.OnFeedError, VerifySourceNotExists, srcType: gocbcore, gone: true, indexUUID:
2020-08-25T17:59:27.093-07:00 [INFO] main: meh.OnFeedError, DeleteAllIndexFromSource, srcType: gocbcore, bucketName: default, bucketUUID: e87cf2fc8ec8cf490334f8547c691e3d
2020-08-25T17:59:27.096-07:00 [INFO] manager_api: starting index definition deletion, indexType: fulltext-index, indexName: default_index, indexUUID: 18facb87f1f8d166
2020-08-25T17:59:27.099-07:00 [INFO] cfg_metakv: Set, key: indexDefs, cas: 1, split: false, nodeUUID: 83d1ce30f1a4ee4d26f7d962309a490e
2020-08-25T17:59:27.099-07:00 [INFO] cfg_metakv: Set path: /fts/cbgt/cfg/indexDefs
2020-08-25T17:59:27.102-07:00 [INFO] cfg_metakv: metaKVCallback, path: /fts/cbgt/cfg/indexDefs, key: indexDefs, deletion: false
2020-08-25T17:59:27.112-07:00 [INFO] cfg_metakv: Set, key: indexDefs, cas: 1, split: false, nodeUUID: 83d1ce30f1a4ee4d26f7d962309a490e
2020-08-25T17:59:27.112-07:00 [INFO] cfg_metakv: metaKVCallback, path: /fts/cbgt/cfg/indexDefs, key: indexDefs, deletion: false
2020-08-25T17:59:27.112-07:00 [INFO] ctl: kickIndexDefs, kind: cfgEvent
2020-08-25T17:59:27.112-07:00 [INFO] cfg_metakv: Set path: /fts/cbgt/cfg/indexDefs
2020-08-25T17:59:27.117-07:00 [INFO] manager_api: bumped indexDefs, indexDefsUUID: 58b43fe92c76e856
2020-08-25T17:59:27.117-07:00 [INFO] manager_api: DeleteAllIndexFromSource, index deletions completed
2020-08-25T17:59:27.118-07:00 [INFO] cfg_metakv: metaKVCallback, path: /fts/cbgt/cfg/indexDefs, key: indexDefs, deletion: false
2020-08-25T17:59:27.144-07:00 [INFO] ctl: kickIndexDefs, kind: cfgEvent
2020-08-25T17:59:27.147-07:00 [INFO] ctl: kickIndexDefs, kind: cfgEvent
2020-08-25T17:59:27.155-07:00 [INFO] cfg_metakv: metaKVCallback, path: /fts/cbgt/cfg/indexDefs, key: indexDefs, deletion: false
2020-08-25T17:59:27.157-07:00 [INFO] planner: nodesRemove: []string(nil)
2020-08-25T17:59:27.157-07:00 [INFO] planner: step planner
2020-08-25T17:59:27.161-07:00 [INFO] ctl: kickIndexDefs, kind: cfgEvent
2020-08-25T17:59:27.169-07:00 [ERRO] (GOCBCORE) memdClient read failure: EOF – cbgt.GocbcoreLogger.Log() at gocbcore_helper.go:635
2020-08-25T17:59:27.170-07:00 [ERRO] (GOCBCORE) memdClient read failure: EOF – cbgt.GocbcoreLogger.Log() at gocbcore_helper.go:635
2020-08-25T17:59:27.170-07:00 [ERRO] (GOCBCORE) memdClient read failure: EOF – cbgt.GocbcoreLogger.Log() at gocbcore_helper.go:635
2020-08-25T17:59:27.170-07:00 [INFO] main: meh.OnFeedError, srcType: gocbcore, err: EOF |
2020-08-25T17:59:27.173-07:00 [INFO] main: meh.OnFeedError, VerifySourceNotExists, srcType: gocbcore, gone: true, indexUUID:
2020-08-25T17:59:27.173-07:00 [INFO] main: meh.OnFeedError, DeleteAllIndexFromSource, srcType: gocbcore, bucketName: default, bucketUUID: e87cf2fc8ec8cf490334f8547c691e3d
2020-08-25T17:59:27.177-07:00 [ERRO] (GOCBCORE) Pipeline client `172.23.107.160:11210/0xc0004f8410` failed to shut down errored client socket (close tcp 172.23.105.153:38796->172.23.107.160:11210: use of closed network connection) – cbgt.GocbcoreLogger.Log() at gocbcore_helper.go:635
2020-08-25T17:59:27.188-07:00 [INFO] planner: nodesRemove: []string(nil)
2020-08-25T17:59:27.188-07:00 [INFO] planner: step planner
2020-08-25T17:59:27.232-07:00 [INFO] cfg_metakv: Set, key: planPIndexes, cas: 4e, split: true, nodeUUID: 83d1ce30f1a4ee4d26f7d962309a490e
2020-08-25T17:59:27.246-07:00 [INFO] cfg_metakv_lean: setLeanPlan, val: {"uuid":"4d2aa943ea9826b9","planPIndexes":{},"implVersion":"5.5.0","warnings":{}}
2020-08-25T17:59:27.248-07:00 [INFO] cfg_metakv_lean: setLeanPlan, curMetaKvPlanKey set, val:
2020-08-25T17:59:27.250-07:00 [INFO] cfg_metakv: metaKVCallback, path: /fts/cbgt/cfg/curMetaKvPlanKey, key: curMetaKvPlanKey, deletion: false
2020-08-25T17:59:27.262-07:00 [INFO] cfg_metakv_lean: purgeOrphanedLeanPlans, purged path: /fts/cbgt/cfg/planPIndexesLean/planPIndexesLean-3c2ad32e9106db3948646a4a5aef6581-1598403466061/
2020-08-25T17:59:27.267-07:00 [INFO] cfg_metakv_lean: purgeOrphanedLeanPlans, purged path: /fts/cbgt/cfg/planPIndexesLean/planPIndexesLean-67667e6462a2428a6084d98d64217438-1598403466093/
2020-08-25T17:59:27.287-07:00 [INFO] janitor: awakes, op: kick, msg: cfg changed, key: curMetaKvPlanKey
2020-08-25T17:59:27.287-07:00 [INFO] cfg_metakv: metaKVCallback, path: /fts/cbgt/cfg/planPIndexesLean/planPIndexesLean-3c2ad32e9106db3948646a4a5aef6581-1598403466061/default_index, key: planPIndexesLean/planPIndexesLean-3c2ad32e9106db3948646a4a5aef6581-1598403466061/default_index, deletion: true
2020-08-25T17:59:27.319-07:00 [INFO] cfg_metakv: metaKVCallback, path: /fts/cbgt/cfg/planPIndexesLean/planPIndexesLean-67667e6462a2428a6084d98d64217438-1598403466093/default_index, key: planPIndexesLean/planPIndexesLean-67667e6462a2428a6084d98d64217438-1598403466093/default_index, deletion: true
2020-08-25T17:59:27.331-07:00 [ERRO] (GOCBCORE) memdClient read failure: EOF – cbgt.GocbcoreLogger.Log() at gocbcore_helper.go:635
2020-08-25T17:59:27.332-07:00 [ERRO] (GOCBCORE) memdClient read failure: EOF – cbgt.GocbcoreLogger.Log() at gocbcore_helper.go:635
2020-08-25T17:59:27.332-07:00 [INFO] main: meh.OnFeedError, srcType: gocbcore, err: EOF |
2020-08-25T17:59:27.332-07:00 [ERRO] (GOCBCORE) memdClient read failure: EOF – cbgt.GocbcoreLogger.Log() at gocbcore_helper.go:635
2020-08-25T17:59:27.346-07:00 [INFO] main: meh.OnFeedError, VerifySourceNotExists, srcType: gocbcore, gone: true, indexUUID:
2020-08-25T17:59:27.346-07:00 [INFO] main: meh.OnFeedError, DeleteAllIndexFromSource, srcType: gocbcore, bucketName: default, bucketUUID: e87cf2fc8ec8cf490334f8547c691e3d
2020-08-25T17:59:27.386-07:00 [INFO] janitor: pindexes to remove: 3
Last good build: 7.0.0-2934
It is intermittent though - 2947 was clean and 2948 it started happening again - so marking it as critical only
Change log between 2934 and 2945:
MB-37816 - Fix bleveMaxResultWindow checks with keyset pagination
MB-40768 - FTS upgrade handling for new zap disk format in CC
MB-37285: Enabling OSO Backfill for GOCBCORE feeds
Attachments
Issue Links
- depends on
-
GOCBC-983 [FTS] OpenStream is not invoking callback at times
- Resolved