We read every piece of feedback, and take your input very seriously.
To see all available qualifiers, see our documentation.
Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.
By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.
Already on GitHub? Sign in to your account
Appears to be a test race condition.
Failed RUN TestLedgerCatchpointSPVerificationTracker testingLogger.go:40: time="2023-12-06T20:43:42.299457 +0000" level=info msg="trackerDBInitialize upgrading database schema from version 0 to version 10" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=72 testingLogger.go:40: time="2023-12-06T20:43:42.299882 +0000" level=info msg="trackerDBInitialize performing upgrade from version 0" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:42.300399 +0000" level=info msg="upgradeDatabaseSchema0 initializing schema" file=trackerdbV2.go function="github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.(*trackerDBSchemaInitializer).upgradeDatabaseSchema0" line=183 testingLogger.go:40: time="2023-12-06T20:43:42.302036 +0000" level=info msg="trackerDBInitialize performing upgrade from version 1" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:42.302347 +0000" level=info msg="trackerDBInitialize performing upgrade from version 2" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:42.302616 +0000" level=info msg="trackerDBInitialize performing upgrade from version 3" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:42.303550 +0000" level=info msg="trackerDBInitialize performing upgrade from version 4" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:42.303792 +0000" level=info msg="upgradeDatabaseSchema4: deleted 0 rows" file=trackerdbV2.go function="github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.(*trackerDBSchemaInitializer).upgradeDatabaseSchema4" line=332 testingLogger.go:40: time="2023-12-06T20:43:42.304083 +0000" level=info msg="trackerDBInitialize performing upgrade from version 5" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:42.304800 +0000" level=info msg="upgradeDatabaseSchema5 upgraded 1 out of 12 accounts [ 8.3% ]" file=trackerdbV2.go function="github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.(*trackerDBSchemaInitializer).upgradeDatabaseSchema5.func1" line=359 testingLogger.go:40: time="2023-12-06T20:43:42.306713 +0000" level=info msg="trackerDBInitialize performing upgrade from version 6" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:42.307456 +0000" level=info msg="upgradeDatabaseSchema6 upgraded 1 out of 12 accounts [ 8.3% ]" file=trackerdbV2.go function="github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.(*trackerDBSchemaInitializer).upgradeDatabaseSchema6.func1" line=426 testingLogger.go:40: time="2023-12-06T20:43:42.308524 +0000" level=info msg="trackerDBInitialize performing upgrade from version 7" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:42.308943 +0000" level=info msg="trackerDBInitialize performing upgrade from version 8" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:42.309591 +0000" level=info msg="trackerDBInitialize performing upgrade from version 9" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:42.310051 +0000" level=info msg="trackerDBInitialize database schema upgrade complete" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=143 testingLogger.go:40: time="2023-12-06T20:43:42.339083 +0000" level=info msg="initializeHashes rebuilding merkle trie for round 0" file=catchpointtracker.go function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).initializeHashes" line=1499 testingLogger.go:40: time="2023-12-06T20:43:42.340565 +0000" level=info msg="initializeHashes rebuilt the merkle trie with 12 entries in 1.359698ms" file=catchpointtracker.go function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).initializeHashes" line=1616 testingLogger.go:40: time="2023-12-06T20:43:42.603278 +0000" level=info msg="finishing catchpoint's first stage dbRound: 256" file=catchpointtracker.go function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).finishFirstStage" line=213 testingLogger.go:40: time="2023-12-06T20:43:42.605475 +0000" level=info msg="Catchpoint data file was generated" CPUTime=930263 MerkleTrieRootHash=Z6HN42JBIPM5QLCLLLK5LBC54KEC2TQPZWOC2IN4AQV4TWUJHPBQ SPVerificationCtxsHash=PBH2RGQ4BMBFPO7OEXXNRBNQKYLOMUDQJC6LVWN3ICTGSAYFPBSA accountsCount=12 accountsRound=256 balancesWriteTime=5527028 file=catchpointtracker.go fileSize=820 function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).recordFirstStageInfo" kvsCount=0 line=1326 writingDuration=1482003 testingLogger.go:40: time="2023-12-06T20:43:42.918893 +0000" level=info msg="finishing catchpoint round: 576 accountsRound: 256" file=catchpointtracker.go function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).finishCatchpoint" line=869 testingLogger.go:40: time="2023-12-06T20:43:42.919580 +0000" level=info msg="creating catchpoint round: 576 accountsRound: 256 label: 576#I5ASW6IFEMYP5SGBXFLLIJBG4AIBDJJN6KP7QLXQTRGXK4626JMA" file=catchpointtracker.go function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).createCatchpoint" line=773 testingLogger.go:40: time="2023-12-06T20:43:42.924757 +0000" level=info msg="Catchpoint file was created" accountsCount=12 accountsRound=256 catchpointLabel="576#I5ASW6IFEMYP5SGBXFLLIJBG4AIBDJJN6KP7QLXQTRGXK4626JMA" file=catchpointtracker.go fileSize=988 filepath=catchpoints/02/576.catchpoint function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).createCatchpoint" kvsCount=0 line=859 writingDuration=5381773 testingLogger.go:40: time="2023-12-06T20:43:43.183248 +0000" level=info msg="finishing catchpoint's first stage dbRound: 832" file=catchpointtracker.go function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).finishFirstStage" line=213 testingLogger.go:40: time="2023-12-06T20:43:43.185479 +0000" level=info msg="Catchpoint data file was generated" CPUTime=899368 MerkleTrieRootHash=3WBYY6BOVPRY26ULCV7NZUJZMKBHAYCYHQX5V5JVEAIZHBKS2XSQ SPVerificationCtxsHash=L5GIM5TORPPUDFWHVMHFHOFG63E56FYQKXVJF2NHIDQAKH2N6H2Q accountsCount=12 accountsRound=832 balancesWriteTime=6160963 file=catchpointtracker.go fileSize=832 function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).recordFirstStageInfo" kvsCount=0 line=1326 writingDuration=1378253 WARNING: DATA RACE Read at 0x00c0021f8a78 by goroutine 3257: runtime.raceread() :1 +0x24 github.com/algorand/go-algorand/ledger.(*trackerRegistry).scheduleCommit() /opt/cibuild/project/ledger/tracker.go:456 +0x364 github.com/algorand/go-algorand/ledger.(*trackerRegistry).committedUpTo() /opt/cibuild/project/ledger/tracker.go:395 +0x1d8 github.com/algorand/go-algorand/ledger.(*Ledger).notifyCommit() /opt/cibuild/project/ledger/ledger.go:455 +0x19c github.com/algorand/go-algorand/ledger.(*blockQueue).syncer() /opt/cibuild/project/ledger/blockqueue.go:166 +0x813 github.com/algorand/go-algorand/ledger.(*blockQueue).start.func3() /opt/cibuild/project/ledger/blockqueue.go:92 +0x39 Previous write at 0x00c0021f8a78 by goroutine 3240: runtime.racewrite() :1 +0x24 github.com/algorand/go-algorand/ledger.(*trackerRegistry).waitAccountsWriting() /opt/cibuild/project/ledger/tracker.go:476 +0x2f7 github.com/algorand/go-algorand/ledger.triggerTrackerFlush() /opt/cibuild/project/ledger/ledger_test.go:1469 +0x29b github.com/algorand/go-algorand/ledger.TestLedgerCatchpointSPVerificationTracker() /opt/cibuild/project/ledger/ledger_test.go:3170 +0x6ac testing.tRunner() /opt/cibuild/.gimme/versions/go1.20.7.linux.amd64/src/testing/testing.go:1576 +0x216 testing.(*T).Run.func1() /opt/cibuild/.gimme/versions/go1.20.7.linux.amd64/src/testing/testing.go:1629 +0x47 Goroutine 3257 (running) created at: github.com/algorand/go-algorand/ledger.(*blockQueue).start() /opt/cibuild/project/ledger/blockqueue.go:92 +0x525 github.com/algorand/go-algorand/ledger.(*Ledger).reloadLedger() /opt/cibuild/project/ledger/ledger.go:218 +0x1af github.com/algorand/go-algorand/ledger.OpenLedger... /opt/cibuild/project/ledger/ledger.go:193 +0x1b4b github.com/algorand/go-algorand/ledger.TestLedgerCatchpointSPVerificationTracker() /opt/cibuild/project/ledger/ledger_test.go:3154 +0x412 testing.tRunner() /opt/cibuild/.gimme/versions/go1.20.7.linux.amd64/src/testing/testing.go:1576 +0x216 testing.(*T).Run.func1() /opt/cibuild/.gimme/versions/go1.20.7.linux.amd64/src/testing/testing.go:1629 +0x47 Goroutine 3240 (running) created at: testing.(*T).Run() /opt/cibuild/.gimme/versions/go1.20.7.linux.amd64/src/testing/testing.go:1629 +0x805 testing.runTests.func1() /opt/cibuild/.gimme/versions/go1.20.7.linux.amd64/src/testing/testing.go:2036 +0x8d testing.tRunner() /opt/cibuild/.gimme/versions/go1.20.7.linux.amd64/src/testing/testing.go:1576 +0x216 testing.runTests() /opt/cibuild/.gimme/versions/go1.20.7.linux.amd64/src/testing/testing.go:2034 +0x87c testing.(*M).Run() /opt/cibuild/.gimme/versions/go1.20.7.linux.amd64/src/testing/testing.go:1906 +0xb44 main.main() _testmain.go:756 +0x2fc testingLogger.go:40: time="2023-12-06T20:43:43.538916 +0000" level=info msg="finishing catchpoint round: 1152 accountsRound: 832" file=catchpointtracker.go function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).finishCatchpoint" line=869 testingLogger.go:40: time="2023-12-06T20:43:43.539058 +0000" level=warning msg="error creating catchpoint round: 1152 err: context canceled" file=catchpointtracker.go function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).postCommitUnlocked" line=957 testingLogger.go:40: time="2023-12-06T20:43:43.539203 +0000" level=warning msg="error pruning first stage records and data dcc.newBase: 1153 err: context canceled" file=catchpointtracker.go function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).postCommitUnlocked" line=966 testingLogger.go:40: time="2023-12-06T20:43:43.558340 +0000" level=info msg="trackerDBInitialize upgrading database schema from version 0 to version 10" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=72 testingLogger.go:40: time="2023-12-06T20:43:43.558462 +0000" level=info msg="trackerDBInitialize performing upgrade from version 0" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.558642 +0000" level=info msg="upgradeDatabaseSchema0 initializing schema" file=trackerdbV2.go function="github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.(*trackerDBSchemaInitializer).upgradeDatabaseSchema0" line=183 testingLogger.go:40: time="2023-12-06T20:43:43.559672 +0000" level=info msg="trackerDBInitialize performing upgrade from version 1" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.559841 +0000" level=info msg="trackerDBInitialize performing upgrade from version 2" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.560050 +0000" level=info msg="trackerDBInitialize performing upgrade from version 3" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.560804 +0000" level=info msg="trackerDBInitialize performing upgrade from version 4" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.560890 +0000" level=info msg="upgradeDatabaseSchema4: deleted 0 rows" file=trackerdbV2.go function="github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.(*trackerDBSchemaInitializer).upgradeDatabaseSchema4" line=332 testingLogger.go:40: time="2023-12-06T20:43:43.561114 +0000" level=info msg="trackerDBInitialize performing upgrade from version 5" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.561698 +0000" level=info msg="upgradeDatabaseSchema5 upgraded 1 out of 12 accounts [ 8.3% ]" file=trackerdbV2.go function="github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.(*trackerDBSchemaInitializer).upgradeDatabaseSchema5.func1" line=359 testingLogger.go:40: time="2023-12-06T20:43:43.563048 +0000" level=info msg="trackerDBInitialize performing upgrade from version 6" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.563599 +0000" level=info msg="upgradeDatabaseSchema6 upgraded 1 out of 12 accounts [ 8.3% ]" file=trackerdbV2.go function="github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.(*trackerDBSchemaInitializer).upgradeDatabaseSchema6.func1" line=426 testingLogger.go:40: time="2023-12-06T20:43:43.564439 +0000" level=info msg="trackerDBInitialize performing upgrade from version 7" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.564744 +0000" level=info msg="trackerDBInitialize performing upgrade from version 8" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.564927 +0000" level=info msg="trackerDBInitialize performing upgrade from version 9" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.565236 +0000" level=info msg="trackerDBInitialize database schema upgrade complete" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=143 testingLogger.go:40: time="2023-12-06T20:43:43.623225 +0000" level=info msg="initializeHashes rebuilding merkle trie for round 0" file=catchpointtracker.go function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).initializeHashes" line=1499 testingLogger.go:40: time="2023-12-06T20:43:43.625238 +0000" level=info msg="initializeHashes rebuilt the merkle trie with 12 entries in 1.876136ms" file=catchpointtracker.go function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).initializeHashes" line=1616 testingLogger.go:40: time="2023-12-06T20:43:43.640644 +0000" level=info msg="trackerDBInitialize upgrading database schema from version 0 to version 6" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=72 testingLogger.go:40: time="2023-12-06T20:43:43.640768 +0000" level=info msg="trackerDBInitialize performing upgrade from version 0" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.640911 +0000" level=info msg="upgradeDatabaseSchema0 initializing schema" file=trackerdbV2.go function="github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.(*trackerDBSchemaInitializer).upgradeDatabaseSchema0" line=183 testingLogger.go:40: time="2023-12-06T20:43:43.641917 +0000" level=info msg="trackerDBInitialize performing upgrade from version 1" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.642095 +0000" level=info msg="trackerDBInitialize performing upgrade from version 2" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.642282 +0000" level=info msg="trackerDBInitialize performing upgrade from version 3" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.643048 +0000" level=info msg="trackerDBInitialize performing upgrade from version 4" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.643176 +0000" level=info msg="upgradeDatabaseSchema4: deleted 0 rows" file=trackerdbV2.go function="github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.(*trackerDBSchemaInitializer).upgradeDatabaseSchema4" line=332 testingLogger.go:40: time="2023-12-06T20:43:43.643446 +0000" level=info msg="trackerDBInitialize performing upgrade from version 5" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.644188 +0000" level=info msg="upgradeDatabaseSchema5 upgraded 1 out of 12 accounts [ 8.3% ]" file=trackerdbV2.go function="github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.(*trackerDBSchemaInitializer).upgradeDatabaseSchema5.func1" line=359 testingLogger.go:40: time="2023-12-06T20:43:43.645821 +0000" level=info msg="trackerDBInitialize database schema upgrade complete" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=143 testingLogger.go:40: time="2023-12-06T20:43:43.648972 +0000" level=info msg="trackerDBInitialize upgrading database schema from version 6 to version 10" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=72 testingLogger.go:40: time="2023-12-06T20:43:43.649065 +0000" level=info msg="trackerDBInitialize performing upgrade from version 6" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.650290 +0000" level=info msg="trackerDBInitialize performing upgrade from version 7" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.650550 +0000" level=info msg="trackerDBInitialize performing upgrade from version 8" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.650723 +0000" level=info msg="trackerDBInitialize performing upgrade from version 9" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.651002 +0000" level=info msg="trackerDBInitialize database schema upgrade complete" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=143 testingLogger.go:40: time="2023-12-06T20:43:43.712045 +0000" level=info msg="initializeHashes rebuilding merkle trie for round 0" file=catchpointtracker.go function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).initializeHashes" line=1499 testingLogger.go:40: time="2023-12-06T20:43:43.712851 +0000" level=info msg="initializeHashes rebuilt the merkle trie with 0 entries in 675.063µs" file=catchpointtracker.go function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).initializeHashes" line=1616 testing.go:1446: race detected during execution of test --- FAIL: TestLedgerCatchpointSPVerificationTracker (1.43s)
WARNING: DATA RACE Read at 0x00c0021f8a78 by goroutine 3257: runtime.raceread() :1 +0x24 github.com/algorand/go-algorand/ledger.(*trackerRegistry).scheduleCommit() /opt/cibuild/project/ledger/tracker.go:456 +0x364 github.com/algorand/go-algorand/ledger.(*trackerRegistry).committedUpTo() /opt/cibuild/project/ledger/tracker.go:395 +0x1d8 github.com/algorand/go-algorand/ledger.(*Ledger).notifyCommit() /opt/cibuild/project/ledger/ledger.go:455 +0x19c github.com/algorand/go-algorand/ledger.(*blockQueue).syncer() /opt/cibuild/project/ledger/blockqueue.go:166 +0x813 github.com/algorand/go-algorand/ledger.(*blockQueue).start.func3() /opt/cibuild/project/ledger/blockqueue.go:92 +0x39
Previous write at 0x00c0021f8a78 by goroutine 3240: runtime.racewrite() :1 +0x24 github.com/algorand/go-algorand/ledger.(*trackerRegistry).waitAccountsWriting() /opt/cibuild/project/ledger/tracker.go:476 +0x2f7 github.com/algorand/go-algorand/ledger.triggerTrackerFlush() /opt/cibuild/project/ledger/ledger_test.go:1469 +0x29b github.com/algorand/go-algorand/ledger.TestLedgerCatchpointSPVerificationTracker() /opt/cibuild/project/ledger/ledger_test.go:3170 +0x6ac testing.tRunner() /opt/cibuild/.gimme/versions/go1.20.7.linux.amd64/src/testing/testing.go:1576 +0x216 testing.(*T).Run.func1() /opt/cibuild/.gimme/versions/go1.20.7.linux.amd64/src/testing/testing.go:1629 +0x47
Goroutine 3257 (running) created at: github.com/algorand/go-algorand/ledger.(*blockQueue).start() /opt/cibuild/project/ledger/blockqueue.go:92 +0x525 github.com/algorand/go-algorand/ledger.(*Ledger).reloadLedger() /opt/cibuild/project/ledger/ledger.go:218 +0x1af github.com/algorand/go-algorand/ledger.OpenLedger... /opt/cibuild/project/ledger/ledger.go:193 +0x1b4b github.com/algorand/go-algorand/ledger.TestLedgerCatchpointSPVerificationTracker() /opt/cibuild/project/ledger/ledger_test.go:3154 +0x412 testing.tRunner() /opt/cibuild/.gimme/versions/go1.20.7.linux.amd64/src/testing/testing.go:1576 +0x216 testing.(*T).Run.func1() /opt/cibuild/.gimme/versions/go1.20.7.linux.amd64/src/testing/testing.go:1629 +0x47
testingLogger.go:40: time="2023-12-06T20:43:43.538916 +0000" level=info msg="finishing catchpoint round: 1152 accountsRound: 832" file=catchpointtracker.go function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).finishCatchpoint" line=869 testingLogger.go:40: time="2023-12-06T20:43:43.539058 +0000" level=warning msg="error creating catchpoint round: 1152 err: context canceled" file=catchpointtracker.go function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).postCommitUnlocked" line=957 testingLogger.go:40: time="2023-12-06T20:43:43.539203 +0000" level=warning msg="error pruning first stage records and data dcc.newBase: 1153 err: context canceled" file=catchpointtracker.go function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).postCommitUnlocked" line=966 testingLogger.go:40: time="2023-12-06T20:43:43.558340 +0000" level=info msg="trackerDBInitialize upgrading database schema from version 0 to version 10" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=72 testingLogger.go:40: time="2023-12-06T20:43:43.558462 +0000" level=info msg="trackerDBInitialize performing upgrade from version 0" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.558642 +0000" level=info msg="upgradeDatabaseSchema0 initializing schema" file=trackerdbV2.go function="github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.(*trackerDBSchemaInitializer).upgradeDatabaseSchema0" line=183 testingLogger.go:40: time="2023-12-06T20:43:43.559672 +0000" level=info msg="trackerDBInitialize performing upgrade from version 1" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.559841 +0000" level=info msg="trackerDBInitialize performing upgrade from version 2" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.560050 +0000" level=info msg="trackerDBInitialize performing upgrade from version 3" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.560804 +0000" level=info msg="trackerDBInitialize performing upgrade from version 4" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.560890 +0000" level=info msg="upgradeDatabaseSchema4: deleted 0 rows" file=trackerdbV2.go function="github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.(*trackerDBSchemaInitializer).upgradeDatabaseSchema4" line=332 testingLogger.go:40: time="2023-12-06T20:43:43.561114 +0000" level=info msg="trackerDBInitialize performing upgrade from version 5" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.561698 +0000" level=info msg="upgradeDatabaseSchema5 upgraded 1 out of 12 accounts [ 8.3% ]" file=trackerdbV2.go function="github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.(*trackerDBSchemaInitializer).upgradeDatabaseSchema5.func1" line=359 testingLogger.go:40: time="2023-12-06T20:43:43.563048 +0000" level=info msg="trackerDBInitialize performing upgrade from version 6" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.563599 +0000" level=info msg="upgradeDatabaseSchema6 upgraded 1 out of 12 accounts [ 8.3% ]" file=trackerdbV2.go function="github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.(*trackerDBSchemaInitializer).upgradeDatabaseSchema6.func1" line=426 testingLogger.go:40: time="2023-12-06T20:43:43.564439 +0000" level=info msg="trackerDBInitialize performing upgrade from version 7" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.564744 +0000" level=info msg="trackerDBInitialize performing upgrade from version 8" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.564927 +0000" level=info msg="trackerDBInitialize performing upgrade from version 9" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.565236 +0000" level=info msg="trackerDBInitialize database schema upgrade complete" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=143 testingLogger.go:40: time="2023-12-06T20:43:43.623225 +0000" level=info msg="initializeHashes rebuilding merkle trie for round 0" file=catchpointtracker.go function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).initializeHashes" line=1499 testingLogger.go:40: time="2023-12-06T20:43:43.625238 +0000" level=info msg="initializeHashes rebuilt the merkle trie with 12 entries in 1.876136ms" file=catchpointtracker.go function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).initializeHashes" line=1616 testingLogger.go:40: time="2023-12-06T20:43:43.640644 +0000" level=info msg="trackerDBInitialize upgrading database schema from version 0 to version 6" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=72 testingLogger.go:40: time="2023-12-06T20:43:43.640768 +0000" level=info msg="trackerDBInitialize performing upgrade from version 0" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.640911 +0000" level=info msg="upgradeDatabaseSchema0 initializing schema" file=trackerdbV2.go function="github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.(*trackerDBSchemaInitializer).upgradeDatabaseSchema0" line=183 testingLogger.go:40: time="2023-12-06T20:43:43.641917 +0000" level=info msg="trackerDBInitialize performing upgrade from version 1" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.642095 +0000" level=info msg="trackerDBInitialize performing upgrade from version 2" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.642282 +0000" level=info msg="trackerDBInitialize performing upgrade from version 3" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.643048 +0000" level=info msg="trackerDBInitialize performing upgrade from version 4" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.643176 +0000" level=info msg="upgradeDatabaseSchema4: deleted 0 rows" file=trackerdbV2.go function="github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.(*trackerDBSchemaInitializer).upgradeDatabaseSchema4" line=332 testingLogger.go:40: time="2023-12-06T20:43:43.643446 +0000" level=info msg="trackerDBInitialize performing upgrade from version 5" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.644188 +0000" level=info msg="upgradeDatabaseSchema5 upgraded 1 out of 12 accounts [ 8.3% ]" file=trackerdbV2.go function="github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.(*trackerDBSchemaInitializer).upgradeDatabaseSchema5.func1" line=359 testingLogger.go:40: time="2023-12-06T20:43:43.645821 +0000" level=info msg="trackerDBInitialize database schema upgrade complete" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=143 testingLogger.go:40: time="2023-12-06T20:43:43.648972 +0000" level=info msg="trackerDBInitialize upgrading database schema from version 6 to version 10" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=72 testingLogger.go:40: time="2023-12-06T20:43:43.649065 +0000" level=info msg="trackerDBInitialize performing upgrade from version 6" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.650290 +0000" level=info msg="trackerDBInitialize performing upgrade from version 7" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.650550 +0000" level=info msg="trackerDBInitialize performing upgrade from version 8" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.650723 +0000" level=info msg="trackerDBInitialize performing upgrade from version 9" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=76 testingLogger.go:40: time="2023-12-06T20:43:43.651002 +0000" level=info msg="trackerDBInitialize database schema upgrade complete" file=trackerdbV2.go function=github.com/algorand/go-algorand/ledger/store/trackerdb/sqlitedriver.RunMigrations line=143 testingLogger.go:40: time="2023-12-06T20:43:43.712045 +0000" level=info msg="initializeHashes rebuilding merkle trie for round 0" file=catchpointtracker.go function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).initializeHashes" line=1499 testingLogger.go:40: time="2023-12-06T20:43:43.712851 +0000" level=info msg="initializeHashes rebuilt the merkle trie with 0 entries in 675.063µs" file=catchpointtracker.go function="github.com/algorand/go-algorand/ledger.(*catchpointTracker).initializeHashes" line=1616 testing.go:1446: race detected during execution of test
--- FAIL: TestLedgerCatchpointSPVerificationTracker (1.43s)
The text was updated successfully, but these errors were encountered:
No branches or pull requests
Appears to be a test race condition.
The text was updated successfully, but these errors were encountered: