Skip to content

Trace logging for ci debugging #12755

Trace logging for ci debugging

Trace logging for ci debugging #12755

Triggered via push January 17, 2025 15:42
Status Failure
Total duration 6m 29s
Artifacts

ci.yml

on: push
Matrix: test-sgcollect
Matrix: test-stats-definition-exporter
Matrix: test
Fit to window
Zoom out
Zoom in

Annotations

8 errors and 10 warnings
test-default-collection
Process completed with exit code 1.
test (macos)
Process completed with exit code 1.
test (ubuntu)
Process completed with exit code 1.
test (windows): topologytest/peer_test.go#L310
peer_test.go:310: TopologyTest: created peer cbs1 (bucket:sg_int_rosmar_ce81f93b646c6b795274a20b8ac7b303,sourceid:nkvcSMf0NP+V1Z6bykxsTg) peer_test.go:310: TopologyTest: created peer cbs2 (bucket:sg_int_rosmar_2adc30daa86e562fae24d615b084cf39,sourceid:tAWlCU4RzkRCuqWJWCbTtQ) 2025-01-17T15:45:42.301Z [TRC] t:TestMultiActorDelete/2x_CBL<->SG<->CBS_XDCR_only_1.3 Did not detect a cgroup for a memory limit -- rest.getTotalMemory() at server_context.go:2167 2025-01-17T15:45:42.301Z [INF] t:TestMultiActorDelete/2x_CBL<->SG<->CBS_XDCR_only_1.3 Logging stats with frequency: &{1m0s} 2025-01-17T15:45:42.301Z [INF] t:TestMultiActorDelete/2x_CBL<->SG<->CBS_XDCR_only_1.3 Initializing bootstrap connection.. 2025-01-17T15:45:42.301Z [DBG] Walrus+: Opened collection B#57c0 on sg_int_rosmar_2adc30daa86e562fae24d615b084cf39._default._default 2025-01-17T15:45:42.301Z [DBG] Walrus+: UNregisterBucket B#57 sg_int_rosmar_2adc30daa86e562fae24d615b084cf39 at file:/?mode=memory 2025-01-17T15:45:42.301Z [INF] Config: t:TestMultiActorDelete/2x_CBL<->SG<->CBS_XDCR_only_1.3 Unable to retrieve 3.0 config during config migration for bucket: sg_int_rosmar_2adc30daa86e562fae24d615b084cf39, groupID: f0790754-bd1a-4b09-893b-a5e8189db36a: key "_sync:dbconfig:f0790754-bd1a-4b09-893b-a5e8189db36a" missing 2025-01-17T15:45:42.301Z [DBG] Walrus+: Opened collection B#58c0 on sg_int_rosmar_ce81f93b646c6b795274a20b8ac7b303._default._default 2025-01-17T15:45:42.301Z [DBG] Walrus+: UNregisterBucket B#58 sg_int_rosmar_ce81f93b646c6b795274a20b8ac7b303 at file:/?mode=memory 2025-01-17T15:45:42.301Z [INF] Config: t:TestMultiActorDelete/2x_CBL<->SG<->CBS_XDCR_only_1.3 Unable to retrieve 3.0 config during config migration for bucket: sg_int_rosmar_ce81f93b646c6b795274a20b8ac7b303, groupID: f0790754-bd1a-4b09-893b-a5e8189db36a: key "_sync:dbconfig:f0790754-bd1a-4b09-893b-a5e8189db36a" missing 2025-01-17T15:45:42.301Z [TRC] Config+: t:TestMultiActorDelete/2x_CBL<->SG<->CBS_XDCR_only_1.3 b:sg_int_rosmar_2adc30daa86e562fae24d615b084cf39 Checking for configs for group "f0790754-bd1a-4b09-893b-a5e8189db36a" -- rest.(*ServerContext).FetchConfigs() at config.go:1940 2025-01-17T15:45:42.301Z [DBG] Config+: t:TestMultiActorDelete/2x_CBL<->SG<->CBS_XDCR_only_1.3 b:sg_int_rosmar_2adc30daa86e562fae24d615b084cf39 Checking for database config (attempt 1/5) 2025-01-17T15:45:42.301Z [DBG] Walrus+: Opened collection B#57c0 on sg_int_rosmar_2adc30daa86e562fae24d615b084cf39._default._default 2025-01-17T15:45:42.301Z [DBG] Walrus+: UNregisterBucket B#57 sg_int_rosmar_2adc30daa86e562fae24d615b084cf39 at file:/?mode=memory 2025-01-17T15:45:42.301Z [DBG] Walrus+: Opened collection B#57c0 on sg_int_rosmar_2adc30daa86e562fae24d615b084cf39._default._default 2025-01-17T15:45:42.301Z [DBG] Walrus+: UNregisterBucket B#57 sg_int_rosmar_2adc30daa86e562fae24d615b084cf39 at file:/?mode=memory 2025-01-17T15:45:42.301Z [DBG] Config+: t:TestMultiActorDelete/2x_CBL<->SG<->CBS_XDCR_only_1.3 b:sg_int_rosmar_2adc30daa86e562fae24d615b084cf39 Bucket "sg_int_rosmar_2adc30daa86e562fae24d615b084cf39" did not contain any configs for group "f0790754-bd1a-4b09-893b-a5e8189db36a" 2025-01-17T15:45:42.301Z [TRC] Config+: t:TestMultiActorDelete/2x_CBL<->SG<->CBS_XDCR_only_1.3 b:sg_int_rosmar_ce81f93b646c6b795274a20b8ac7b303 Checking for configs for group "f0790754-bd1a-4b09-893b-a5e8189db36a" -- rest.(*ServerContext).FetchConfigs() at config.go:1940 2025-01-17T15:45:42.301Z [DBG] Config+: t:TestMultiActorDelete/2x_CBL<->SG<->CBS_XDCR_only_1.3 b:sg_int_rosmar_ce81f93b646c6b795274a20b8ac7b303 Checking for database config (attempt 1/5) 2025-01-17T15:45:42.301Z [DBG] Walrus+: Opened collection B#58c0 on sg_int_rosmar_ce81f93b646c6b795274a20b8ac7b303._default._default 2025-01-17T15:45:42.301Z [DBG] Walrus+: UNregisterBucket B#58 sg_int_rosmar_ce81f93b646c6b795274a20b8ac7b303 at file:/?mode=memory 2025-01-17T15:45:42.301Z [DBG] Walrus+: Opened collection B#58c0 on sg_int_rosmar_ce81f93b646c6b795274a20b8ac7b303._default._default 2025-01-17T15:45:42.301Z [DBG] Walrus+: UNregisterBucket B#58 sg_int_rosmar_ce81f93b646c6b795274
test (windows): topologytest/peer_test.go#L310
peer_test.go:310: TopologyTest: created peer sg1 (bucket:sg_int_rosmar_b3bac6fa97a275c89bc281855f3159f9,sourceid:4b6ty3XeBrMx8lfMc/UbLw) peer_test.go:310: TopologyTest: created peer cbs1 (bucket:sg_int_rosmar_b3bac6fa97a275c89bc281855f3159f9,sourceid:4b6ty3XeBrMx8lfMc/UbLw) peer_test.go:310: TopologyTest: created peer cbs2 (bucket:sg_int_rosmar_06632fde7332e87bbe4af6ac41975f8d,sourceid:OlUhb6OA1d92WM6yhzsbDg) peer_test.go:310: TopologyTest: created peer cbl1 (sourceid:cbl1) 2025/01/17 15:45:47 Creating user: {"name":"user","collection_access":{"sg_test_0":{"sg_test_0":{"admin_channels":["*"]}}},"password":"letmein"} 2025-01-17T15:45:47.276Z [INF] HTTP: c:#124 db:sg1 POST http://localhost/sg1/_user/ (as ADMIN) 2025-01-17T15:45:47.276Z [DBG] Walrus+: ViewQuery("sync_gateway_2.1", "access", map[key:user stale:false]) 2025-01-17T15:45:47.276Z [DBG] Walrus+: Updating view B#69c1/sync_gateway_2.1/access index to cas 1737128747270602752 (from 0) 2025-01-17T15:45:47.283Z [DBG] Walrus+: Queried view B#69c1/sync_gateway_2.1/access --> 0 rows 2025/01/17 15:45:47 SG-Bucket: ... view returned 0 rows 2025-01-17T15:45:47.283Z [DBG] Walrus+: View --> 0 rows 2025-01-17T15:45:47.283Z [INF] Access: c:#124 db:sg1 Recomputed channels for "<ud>user</ud>" (sg_test_0.sg_test_0): <ud>!</ud>:1 2025-01-17T15:45:47.283Z [DBG] Walrus+: ViewQuery("sync_gateway_2.1", "role_access", map[key:user stale:false]) 2025-01-17T15:45:47.283Z [DBG] Walrus+: Updating view B#69c1/sync_gateway_2.1/role_access index to cas 1737128747270602752 (from 0) 2025-01-17T15:45:47.295Z [DBG] Walrus+: Queried view B#69c1/sync_gateway_2.1/role_access --> 0 rows 2025/01/17 15:45:47 SG-Bucket: ... view returned 0 rows 2025-01-17T15:45:47.295Z [DBG] Walrus+: View --> 0 rows 2025-01-17T15:45:47.295Z [INF] Access: c:#124 db:sg1 Computed roles for "<ud>user</ud>": 2025-01-17T15:45:47.296Z [DBG] Walrus+: INCR: result=0, err=key "_sync:m_sg1:seq" missing 2025-01-17T15:45:47.296Z [DBG] Walrus+: DCP: B#69c0 cas 0x181b856457ba0000: "_sync:m_sg1:seq" = `1` ---- xattrs `` 2025-01-17T15:45:47.296Z [DBG] Walrus+: DCP: B#69c0 cas 0x181b856457ba0001: "_sync:user:sg1:user" = `{"name":"user","all_channels":null,"sequence":1,"c` ---- xattrs `` 2025-01-17T15:45:47.296Z [INF] Auth: c:#124 db:sg1 Saved principal w/ name:<ud>user</ud>, seq: #1 2025-01-17T15:45:47.296Z [INF] HTTP+: c:#124 db:sg1 #124: --> 201 Created (20.5 ms) 2025-01-17T15:45:47.296Z [DBG] Walrus+: Feed(B#69c0 SG) lastCas = 0x181b856457ba0000 2025-01-17T15:45:47.296Z [INF] Changes: db:sg1 Received #1 ("<ud>_user/user</ud>") 2025-01-17T15:45:47.296Z [DBG] Changes+: Notifying that "sg_int_rosmar_b3bac6fa97a275c89bc281855f3159f9" changed (key="<ud>_sync:user:sg1:user</ud>") count=2 2025-01-17T15:45:47.296Z [DBG] Walrus+: Feed(B#69c0 SG) lastCas = 0x181b856457ba0001 2025-01-17T15:45:47.299Z [DBG] Walrus+: ViewQuery("sync_gateway_2.1", "access", map[key:user stale:false]) 2025-01-17T15:45:47.299Z [DBG] Walrus+: Queried view B#69c1/sync_gateway_2.1/access --> 0 rows 2025/01/17 15:45:47 SG-Bucket: ... view returned 0 rows 2025-01-17T15:45:47.299Z [DBG] Walrus+: View --> 0 rows 2025-01-17T15:45:47.299Z [INF] Access: c:#125 db:sg1 Recomputed channels for "<ud>user</ud>" (sg_test_0.sg_test_0): <ud>!</ud>:1,<ud>*</ud>:1 2025-01-17T15:45:47.300Z [DBG] Walrus+: DCP: B#69c0 cas 0x181b856457e50000: "_sync:user:sg1:user" = `{"name":"user","all_channels":null,"sequence":1,"c` ---- xattrs `` 2025-01-17T15:45:47.300Z [INF] Changes: db:sg1 Received #1 ("<ud>_user/user</ud>") 2025-01-17T15:45:47.300Z [DBG] Cache+: db:sg1 Ignoring duplicate of #1 2025-01-17T15:45:47.300Z [DBG] Changes+: Notifying that "sg_int_rosmar_b3bac6fa97a275c89bc281855f3159f9" changed (key="<ud>_sync:user:sg1:user</ud>") count=3 2025-01-17T15:45:47.300Z [DBG] Walrus+: Feed(B#69c0 SG) lastCas = 0x181b856457e50000 2025-01-17T15:45:47.303Z [INF] HTTP: c:#125 db:sg1 GET /sg1/_blipsync (as <ud>user</ud>) 2025-01-17T15:45:47.304Z [TRC] WSFrame+: t:TestMultiActorConflictUpdate/CBL<->SG<->CBS1_CBS1<->CBS2_1.2 Sender starting -- db.NewSGBlipContextWithProtocols.
test (windows): topologytest/hlv_test.go#L102
hlv_test.go:102: Global doc "doc_MultiActorConflictUpdate_CBL<->SG<->CBS1_CBS1<->CBS2_1.2" state for all peers: ==== peer(sg1) ---- DocMetadata{ DocID: "doc_MultiActorConflictUpdate_CBL<->SG<->CBS1_CBS1<->CBS2_1.2", RevTreeID:"2-524b23860762bbf14f2bcae3252eb92b", HLV:&{CurrentVersionCAS:1737128747316477954 SourceID:OlUhb6OA1d92WM6yhzsbDg Version:1737128747316477954 MergeVersions:map[] PreviousVersions:map[]}, Mou:<nil>, Cas:1737128747328405504, ImplicitHLV:<nil>, } body:map[action:create activePeer:cbs2 topology:CBL<->SG<->CBS1 CBS1<->CBS2 1.2] ==== peer(cbs1) ---- DocMetadata{ DocID: "doc_MultiActorConflictUpdate_CBL<->SG<->CBS1_CBS1<->CBS2_1.2", RevTreeID:"2-524b23860762bbf14f2bcae3252eb92b", HLV:&{CurrentVersionCAS:1737128747316477954 SourceID:OlUhb6OA1d92WM6yhzsbDg Version:1737128747316477954 MergeVersions:map[] PreviousVersions:map[]}, Mou:{CAS:1737128747328405504 PreviousCAS:1737128747316477954 PreviousRevSeqNo:0}, Cas:1737128747328405504, ImplicitHLV:<nil>, } body:map[action:create activePeer:cbs2 topology:CBL<->SG<->CBS1 CBS1<->CBS2 1.2] ==== peer(cbs2) ---- DocMetadata{ DocID: "doc_MultiActorConflictUpdate_CBL<->SG<->CBS1_CBS1<->CBS2_1.2", RevTreeID:"", HLV:<nil>, Mou:<nil>, Cas:1737128747316477954, ImplicitHLV:&{CurrentVersionCAS:0 SourceID:OlUhb6OA1d92WM6yhzsbDg Version:1737128747316477954 MergeVersions:map[] PreviousVersions:map[]}, } body:map[action:create activePeer:cbs2 topology:CBL<->SG<->CBS1 CBS1<->CBS2 1.2] ==== peer(cbl1) ---- DocMetadata{ DocID: "doc_MultiActorConflictUpdate_CBL<->SG<->CBS1_CBS1<->CBS2_1.2", RevTreeID:"", HLV:&{CurrentVersionCAS:0 SourceID:btc-1637909658 Version:1737128747332999800 MergeVersions:map[] PreviousVersions:map[OlUhb6OA1d92WM6yhzsbDg:1737128747316477954]}, Mou:<nil>, Cas:0, ImplicitHLV:&{CurrentVersionCAS:0 SourceID:btc-1637909658 Version:1737128747332999800 MergeVersions:map[] PreviousVersions:map[OlUhb6OA1d92WM6yhzsbDg:1737128747316477954]}, } body:map[action:create activePeer:cbl1 topology:CBL<->SG<->CBS1 CBS1<->CBS2 1.2] hlv_test.go:90: Error Trace: D:/a/sync_gateway/sync_gateway/topologytest/hlv_test.go:90 D:/a/sync_gateway/sync_gateway/topologytest/multi_actor_conflict_test.go:51 Error: Peers did not converge on version Test: TestMultiActorConflictUpdate/CBL<->SG<->CBS1_CBS1<->CBS2_1.2
test (windows)
Process completed with exit code 1.
test-race
Process completed with exit code 1.
python-lint
ubuntu-latest pipelines will use ubuntu-24.04 soon. For more details, see https://github.com/actions/runner-images/issues/10636
python-format
ubuntu-latest pipelines will use ubuntu-24.04 soon. For more details, see https://github.com/actions/runner-images/issues/10636
addlicense
ubuntu-latest pipelines will use ubuntu-24.04 soon. For more details, see https://github.com/actions/runner-images/issues/10636
test-sgcollect (ubuntu-latest)
ubuntu-latest pipelines will use ubuntu-24.04 soon. For more details, see https://github.com/actions/runner-images/issues/10636
build
ubuntu-latest pipelines will use ubuntu-24.04 soon. For more details, see https://github.com/actions/runner-images/issues/10636
test-stats-definition-exporter (ubuntu-latest)
ubuntu-latest pipelines will use ubuntu-24.04 soon. For more details, see https://github.com/actions/runner-images/issues/10636
lint
ubuntu-latest pipelines will use ubuntu-24.04 soon. For more details, see https://github.com/actions/runner-images/issues/10636
test-default-collection
ubuntu-latest pipelines will use ubuntu-24.04 soon. For more details, see https://github.com/actions/runner-images/issues/10636
test (ubuntu)
ubuntu-latest pipelines will use ubuntu-24.04 soon. For more details, see https://github.com/actions/runner-images/issues/10636
test-race
ubuntu-latest pipelines will use ubuntu-24.04 soon. For more details, see https://github.com/actions/runner-images/issues/10636