questdb / questdb/questdb

Flaky testConcurrentGetAndGetCopyOfFuzz

Open
#5,510 0 comments 0 reactions 0 assignees View on GitHub

Nobody has claimed this yet.

Bug Schrödinger's bug
Dominant language
Java
Stars
17.3k
Forks
1.6k
Avg merge
5d 10h
Merged PRs (30d)
28

Description

To reproduce

Unable to easily reproduce. However, this failed on Linux-zfs ci, with seeds:

random seeds: 2619385209160L, 1742829770703L

Error:

java.lang.AssertionError: Memory usage by tag: NATIVE_PATH, difference: 256 expected:<2235778> but was:<2236034>
	at org.junit.Assert.fail(Assert.java:89)
	at org.junit.Assert.failNotEquals(Assert.java:835)
	at org.junit.Assert.assertEquals(Assert.java:647)
	at io.questdb.test.tools.TestUtils$LeakCheck.close(TestUtils.java:2112)
	at io.questdb.test.tools.TestUtils.assertMemoryLeak(TestUtils.java:699)
	at io.questdb.test.cairo.pool.ReaderPoolTest.assertWithPool(ReaderPoolTest.java:1345)
	at io.questdb.test.cairo.pool.ReaderPoolTest.assertWithPool(ReaderPoolTest.java:1341)
	at io.questdb.test.cairo.pool.ReaderPoolTest.testConcurrentGetAndGetCopyOfFuzz(ReaderPoolTest.java:279)

2025-03-24T15:22:50.6970495Z 2025-03-24T15:22:50.695928Z I i.q.t.TestListener >>>> io.questdb.test.cairo.pool.ReaderPoolTest.testConcurrentGetAndGetCopyOfFuzz
2025-03-24T15:22:50.7010726Z 2025-03-24T15:22:50.696069Z I i.q.t.AbstractTest Starting test ReaderPoolTest#testConcurrentGetAndGetCopyOfFuzz
2025-03-24T15:22:50.7011200Z 2025-03-24T15:22:50.696880Z I i.q.c.TableNameRegistryStore reloading tables file [path=/tmp/junit6011679823395823202/dbRoot/tables.d.0, threadId=59348]
2025-03-24T15:22:50.7033225Z 2025-03-24T15:22:50.703067Z I i.q.t.AbstractCairoTest random seeds: 2619385209160L, 1742829770703L
2025-03-24T15:22:50.7033533Z random seeds: 2619385209160L, 1742829770703L
2025-03-24T15:22:50.7135064Z 2025-03-24T15:22:50.713278Z I i.q.c.TableWriter open 'test'
2025-03-24T15:22:50.7154093Z 2025-03-24T15:22:50.715107Z I i.q.c.TableWriter adding column 'sym2[SYMBOL], columnName txn 0 to /test~
2025-03-24T15:22:50.7236184Z 2025-03-24T15:22:50.723132Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.7236500Z 2025-03-24T15:22:50.723144Z I i.q.c.TableWriter adding column 'int2[INT], columnName txn 1 to /test~
2025-03-24T15:22:50.7276846Z 2025-03-24T15:22:50.727203Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.7308519Z 2025-03-24T15:22:50.727211Z I i.q.c.TableWriter adding column 'bool2[BOOLEAN], columnName txn 2 to /test~
2025-03-24T15:22:50.7309030Z 2025-03-24T15:22:50.730577Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.7322811Z 2025-03-24T15:22:50.731988Z I i.q.c.TableWriter switched partition [path=/test~/1970-01-01T00.2]
2025-03-24T15:22:50.7337909Z 2025-03-24T15:22:50.733547Z I i.q.c.TableWriter removing [column=sym2, path=/test~]
2025-03-24T15:22:50.7366631Z 2025-03-24T15:22:50.735342Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7372826Z 2025-03-24T15:22:50.735367Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7379699Z 2025-03-24T15:22:50.735376Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7380478Z 2025-03-24T15:22:50.735383Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7381195Z 2025-03-24T15:22:50.735391Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7428905Z 2025-03-24T15:22:50.735399Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7429443Z 2025-03-24T15:22:50.735407Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7429876Z 2025-03-24T15:22:50.735415Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7430301Z 2025-03-24T15:22:50.735423Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7430738Z 2025-03-24T15:22:50.735431Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7435409Z 2025-03-24T15:22:50.735729Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7435991Z 2025-03-24T15:22:50.735738Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7436535Z 2025-03-24T15:22:50.735746Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7437042Z 2025-03-24T15:22:50.735754Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7437536Z 2025-03-24T15:22:50.735762Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7438022Z 2025-03-24T15:22:50.735886Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7438482Z 2025-03-24T15:22:50.735894Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7438970Z 2025-03-24T15:22:50.735902Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7439643Z 2025-03-24T15:22:50.735910Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7440134Z 2025-03-24T15:22:50.735918Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7440673Z 2025-03-24T15:22:50.735926Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7453897Z 2025-03-24T15:22:50.735934Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7454459Z 2025-03-24T15:22:50.735942Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7459634Z 2025-03-24T15:22:50.735950Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7460183Z 2025-03-24T15:22:50.735958Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7460662Z 2025-03-24T15:22:50.735966Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7461134Z 2025-03-24T15:22:50.735974Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7469095Z 2025-03-24T15:22:50.735984Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7469905Z 2025-03-24T15:22:50.735992Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7476292Z 2025-03-24T15:22:50.736000Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7480189Z 2025-03-24T15:22:50.736105Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7480693Z 2025-03-24T15:22:50.736113Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7481188Z 2025-03-24T15:22:50.736121Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7483840Z 2025-03-24T15:22:50.736130Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7488207Z 2025-03-24T15:22:50.736138Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7490826Z 2025-03-24T15:22:50.736146Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7493481Z 2025-03-24T15:22:50.736154Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7496866Z 2025-03-24T15:22:50.736162Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7500052Z 2025-03-24T15:22:50.736170Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7503584Z 2025-03-24T15:22:50.736178Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7507089Z 2025-03-24T15:22:50.736187Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7510381Z 2025-03-24T15:22:50.736194Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7513650Z 2025-03-24T15:22:50.736202Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7517057Z 2025-03-24T15:22:50.736210Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7520454Z 2025-03-24T15:22:50.736218Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7523746Z 2025-03-24T15:22:50.736226Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7527135Z 2025-03-24T15:22:50.736234Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7530471Z 2025-03-24T15:22:50.736242Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7533876Z 2025-03-24T15:22:50.736250Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7537166Z 2025-03-24T15:22:50.736258Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7540447Z 2025-03-24T15:22:50.736266Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7543850Z 2025-03-24T15:22:50.736274Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7547142Z 2025-03-24T15:22:50.736282Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7550454Z 2025-03-24T15:22:50.736290Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7553803Z 2025-03-24T15:22:50.736298Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7557258Z 2025-03-24T15:22:50.736306Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7560701Z 2025-03-24T15:22:50.736314Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7564255Z 2025-03-24T15:22:50.736322Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7567442Z 2025-03-24T15:22:50.737280Z I i.q.c.TableUtils error reloading metadata [table=test, msg=could not open, file does not exist: /tmp/junit6011679823395823202/dbRoot/test~/_meta], errno=2]
2025-03-24T15:22:50.7569793Z 2025-03-24T15:22:50.738300Z I i.q.c.TableWriter column purge scheduled [table=test, column=sym2, updateTxn=6]
2025-03-24T15:22:50.7572046Z 2025-03-24T15:22:50.738558Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.7574272Z 2025-03-24T15:22:50.738559Z I i.q.c.TableWriter REMOVED column 'sym2[SYMBOL]' from /test~
2025-03-24T15:22:50.7574645Z 2025-03-24T15:22:50.738562Z I i.q.c.TableWriter removing [column=int2, path=/test~]
2025-03-24T15:22:50.7577032Z 2025-03-24T15:22:50.742273Z I i.q.c.TableWriter column purge scheduled [table=test, column=int2, updateTxn=7]
2025-03-24T15:22:50.7577394Z 2025-03-24T15:22:50.742286Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.7579985Z 2025-03-24T15:22:50.742286Z I i.q.c.TableWriter REMOVED column 'int2[INT]' from /test~
2025-03-24T15:22:50.7582299Z 2025-03-24T15:22:50.742289Z I i.q.c.TableWriter removing [column=bool2, path=/test~]
2025-03-24T15:22:50.7582678Z 2025-03-24T15:22:50.746928Z I i.q.c.TableWriter column purge scheduled [table=test, column=bool2, updateTxn=8]
2025-03-24T15:22:50.7585000Z 2025-03-24T15:22:50.746940Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.7587103Z 2025-03-24T15:22:50.746940Z I i.q.c.TableWriter REMOVED column 'bool2[BOOLEAN]' from /test~
2025-03-24T15:22:50.7588674Z 2025-03-24T15:22:50.746952Z I i.q.c.TableWriter adding column 'sym2[SYMBOL], columnName txn 8 to /test~
2025-03-24T15:22:50.7590067Z 2025-03-24T15:22:50.755111Z I i.q.c.TableWriter ADDED column 'sym2[SYMBOL], columnName txn 8 to /test~/1970-01-01T00.2 with columnTop 20
2025-03-24T15:22:50.7591383Z 2025-03-24T15:22:50.756296Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.7592686Z 2025-03-24T15:22:50.756299Z I i.q.c.TableWriter adding column 'int2[INT], columnName txn 9 to /test~
2025-03-24T15:22:50.7615726Z 2025-03-24T15:22:50.761190Z I i.q.c.TableWriter ADDED column 'int2[INT], columnName txn 9 to /test~/1970-01-01T00.2 with columnTop 20
2025-03-24T15:22:50.7629109Z 2025-03-24T15:22:50.762365Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.7629566Z 2025-03-24T15:22:50.762368Z I i.q.c.TableWriter adding column 'bool2[BOOLEAN], columnName txn 10 to /test~
2025-03-24T15:22:50.7659810Z 2025-03-24T15:22:50.765589Z I i.q.c.TableWriter ADDED column 'bool2[BOOLEAN], columnName txn 10 to /test~/1970-01-01T00.2 with columnTop 20
2025-03-24T15:22:50.7672012Z 2025-03-24T15:22:50.766958Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.7682182Z 2025-03-24T15:22:50.767967Z I i.q.c.TableWriter renaming column 'bool2[BOOLEAN]' to 'bool3' in /test~
2025-03-24T15:22:50.7730390Z 2025-03-24T15:22:50.772713Z I i.q.c.TableWriter column purge scheduled [table=test, column=bool2, updateTxn=14]
2025-03-24T15:22:50.7731989Z 2025-03-24T15:22:50.772726Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.7733346Z 2025-03-24T15:22:50.772728Z I i.q.c.TableWriter RENAMED column 'bool2' to 'bool3' from /test~
2025-03-24T15:22:50.7734247Z 2025-03-24T15:22:50.772883Z I i.q.c.TableWriter removing [column=sym2, path=/test~]
2025-03-24T15:22:50.7803291Z 2025-03-24T15:22:50.779997Z I i.q.c.TableWriter column purge scheduled [table=test, column=sym2, updateTxn=27]
2025-03-24T15:22:50.7804508Z 2025-03-24T15:22:50.780010Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.7808302Z 2025-03-24T15:22:50.780011Z I i.q.c.TableWriter REMOVED column 'sym2[SYMBOL]' from /test~
2025-03-24T15:22:50.7809620Z 2025-03-24T15:22:50.780557Z I i.q.c.TableWriter removing [column=int2, path=/test~]
2025-03-24T15:22:50.7855547Z 2025-03-24T15:22:50.785229Z I i.q.c.TableWriter column purge scheduled [table=test, column=int2, updateTxn=28]
2025-03-24T15:22:50.7857638Z 2025-03-24T15:22:50.785241Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.7859283Z 2025-03-24T15:22:50.785242Z I i.q.c.TableWriter REMOVED column 'int2[INT]' from /test~
2025-03-24T15:22:50.7861781Z 2025-03-24T15:22:50.785244Z I i.q.c.TableWriter removing [column=bool3, path=/test~]
2025-03-24T15:22:50.7904301Z 2025-03-24T15:22:50.790082Z I i.q.c.TableWriter column purge scheduled [table=test, column=bool3, updateTxn=29]
2025-03-24T15:22:50.7906783Z 2025-03-24T15:22:50.790094Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.7909053Z 2025-03-24T15:22:50.790094Z I i.q.c.TableWriter REMOVED column 'bool3[BOOLEAN]' from /test~
2025-03-24T15:22:50.7909435Z 2025-03-24T15:22:50.790098Z I i.q.c.TableWriter adding column 'sym2[SYMBOL], columnName txn 29 to /test~
2025-03-24T15:22:50.8009691Z 2025-03-24T15:22:50.800607Z I i.q.c.TableWriter ADDED column 'sym2[SYMBOL], columnName txn 29 to /test~/1970-01-01T00.2 with columnTop 160
2025-03-24T15:22:50.8020451Z 2025-03-24T15:22:50.801807Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.8026157Z 2025-03-24T15:22:50.801811Z I i.q.c.TableWriter adding column 'int2[INT], columnName txn 30 to /test~
2025-03-24T15:22:50.8062222Z 2025-03-24T15:22:50.805876Z I i.q.c.TableWriter ADDED column 'int2[INT], columnName txn 30 to /test~/1970-01-01T00.2 with columnTop 160
2025-03-24T15:22:50.8072532Z 2025-03-24T15:22:50.807018Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.8074286Z 2025-03-24T15:22:50.807021Z I i.q.c.TableWriter adding column 'bool2[BOOLEAN], columnName txn 31 to /test~
2025-03-24T15:22:50.8166238Z 2025-03-24T15:22:50.813594Z I i.q.c.TableWriter ADDED column 'bool2[BOOLEAN], columnName txn 31 to /test~/1970-01-01T00.2 with columnTop 160
2025-03-24T15:22:50.8173184Z 2025-03-24T15:22:50.817080Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.8261675Z 2025-03-24T15:22:50.820777Z I i.q.c.TableWriter converting column [table=test~, column=sym2, from=SYMBOL, to=STRING]
2025-03-24T15:22:50.8314899Z 2025-03-24T15:22:50.822222Z I i.q.g.ConvertOperatorImpl converting column [at=/tmp/junit6011679823395823202/dbRoot/test~/1970-01-01T00.2, column=sym2, from=SYMBOL, to=STRING, rowCount=50]
2025-03-24T15:22:50.8318069Z 2025-03-24T15:22:50.822600Z I i.q.g.ConvertOperatorImpl completed column conversion [at=test~, column=sym2, from=SYMBOL, to=STRING, partitions=1, rows=50, elapsed=0ms]]
2025-03-24T15:22:50.8320261Z 2025-03-24T15:22:50.829362Z I i.q.c.TableWriter column purge scheduled [table=test, column=sym2, updateTxn=38]
2025-03-24T15:22:50.8321766Z 2025-03-24T15:22:50.829409Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.8323048Z 2025-03-24T15:22:50.829446Z I i.q.c.TableWriter removing [column=sym2, path=/test~]
2025-03-24T15:22:50.8361020Z 2025-03-24T15:22:50.835814Z I i.q.c.TableWriter column purge scheduled [table=test, column=sym2, updateTxn=40]
2025-03-24T15:22:50.8365004Z 2025-03-24T15:22:50.835827Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.8365484Z 2025-03-24T15:22:50.835828Z I i.q.c.TableWriter REMOVED column 'sym2[STRING]' from /test~
2025-03-24T15:22:50.8365897Z 2025-03-24T15:22:50.835832Z I i.q.c.TableWriter removing [column=int2, path=/test~]
2025-03-24T15:22:50.8395663Z 2025-03-24T15:22:50.839229Z I i.q.c.TableWriter column purge scheduled [table=test, column=int2, updateTxn=41]
2025-03-24T15:22:50.8397213Z 2025-03-24T15:22:50.839240Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.8398515Z 2025-03-24T15:22:50.839241Z I i.q.c.TableWriter REMOVED column 'int2[INT]' from /test~
2025-03-24T15:22:50.8399717Z 2025-03-24T15:22:50.839244Z I i.q.c.TableWriter removing [column=bool2, path=/test~]
2025-03-24T15:22:50.8441834Z 2025-03-24T15:22:50.843858Z I i.q.c.TableWriter column purge scheduled [table=test, column=bool2, updateTxn=42]
2025-03-24T15:22:50.8443703Z 2025-03-24T15:22:50.843876Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.8444814Z 2025-03-24T15:22:50.843877Z I i.q.c.TableWriter REMOVED column 'bool2[BOOLEAN]' from /test~
2025-03-24T15:22:50.8446028Z 2025-03-24T15:22:50.843949Z I i.q.c.TableWriter adding column 'sym2[SYMBOL], columnName txn 45 to /test~
2025-03-24T15:22:50.8514526Z 2025-03-24T15:22:50.851106Z I i.q.c.TableWriter ADDED column 'sym2[SYMBOL], columnName txn 45 to /test~/1970-01-01T00.2 with columnTop 250
2025-03-24T15:22:50.8523249Z 2025-03-24T15:22:50.852102Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.8524665Z 2025-03-24T15:22:50.852106Z I i.q.c.TableWriter adding column 'int2[INT], columnName txn 46 to /test~
2025-03-24T15:22:50.8558324Z 2025-03-24T15:22:50.855500Z I i.q.c.TableWriter ADDED column 'int2[INT], columnName txn 46 to /test~/1970-01-01T00.2 with columnTop 250
2025-03-24T15:22:50.8568943Z 2025-03-24T15:22:50.856663Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.8570521Z 2025-03-24T15:22:50.856665Z I i.q.c.TableWriter adding column 'bool2[BOOLEAN], columnName txn 47 to /test~
2025-03-24T15:22:50.8599487Z 2025-03-24T15:22:50.859621Z I i.q.c.TableWriter ADDED column 'bool2[BOOLEAN], columnName txn 47 to /test~/1970-01-01T00.2 with columnTop 250
2025-03-24T15:22:50.8609888Z 2025-03-24T15:22:50.860797Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.8614200Z 2025-03-24T15:22:50.861035Z I i.q.c.TableWriter converting column [table=test~, column=sym2, from=SYMBOL, to=STRING]
2025-03-24T15:22:50.8618559Z 2025-03-24T15:22:50.861349Z I i.q.g.ConvertOperatorImpl converting column [at=/tmp/junit6011679823395823202/dbRoot/test~/1970-01-01T00.2, column=sym2, from=SYMBOL, to=STRING, rowCount=90]
2025-03-24T15:22:50.8627031Z 2025-03-24T15:22:50.862180Z I i.q.g.ConvertOperatorImpl completed column conversion [at=test~, column=sym2, from=SYMBOL, to=STRING, partitions=1, rows=90, elapsed=1ms]]
2025-03-24T15:22:50.8675838Z 2025-03-24T15:22:50.866744Z I i.q.c.TableWriter column purge scheduled [table=test, column=sym2, updateTxn=58]
2025-03-24T15:22:50.8680966Z 2025-03-24T15:22:50.866788Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.8681439Z 2025-03-24T15:22:50.866794Z I i.q.c.TableWriter renaming column 'bool2[BOOLEAN]' to 'bool3' in /test~
2025-03-24T15:22:50.8716108Z 2025-03-24T15:22:50.870890Z I i.q.c.TableWriter column purge scheduled [table=test, column=bool2, updateTxn=59]
2025-03-24T15:22:50.8716531Z 2025-03-24T15:22:50.870903Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.8716993Z 2025-03-24T15:22:50.870905Z I i.q.c.TableWriter RENAMED column 'bool2' to 'bool3' from /test~
2025-03-24T15:22:50.8717350Z 2025-03-24T15:22:50.870910Z I i.q.c.TableWriter removing [column=sym2, path=/test~]
2025-03-24T15:22:50.8824200Z 2025-03-24T15:22:50.882126Z I i.q.c.TableWriter column purge scheduled [table=test, column=sym2, updateTxn=60]
2025-03-24T15:22:50.8826710Z 2025-03-24T15:22:50.882143Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.8832420Z 2025-03-24T15:22:50.882144Z I i.q.c.TableWriter REMOVED column 'sym2[STRING]' from /test~
2025-03-24T15:22:50.8832799Z 2025-03-24T15:22:50.882147Z I i.q.c.TableWriter removing [column=int2, path=/test~]
2025-03-24T15:22:50.8875612Z 2025-03-24T15:22:50.886742Z I i.q.c.TableWriter column purge complete [table=test, column=int2, newColumnVersion=60]
2025-03-24T15:22:50.8876128Z 2025-03-24T15:22:50.886755Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.8876477Z 2025-03-24T15:22:50.886756Z I i.q.c.TableWriter REMOVED column 'int2[INT]' from /test~
2025-03-24T15:22:50.8881160Z 2025-03-24T15:22:50.886759Z I i.q.c.TableWriter removing [column=bool3, path=/test~]
2025-03-24T15:22:50.8909866Z 2025-03-24T15:22:50.890209Z I i.q.c.TableWriter column purge complete [table=test, column=bool3, newColumnVersion=61]
2025-03-24T15:22:50.8910301Z 2025-03-24T15:22:50.890220Z I i.q.c.MetadataCache hydrated [table=test~]
2025-03-24T15:22:50.8913536Z 2025-03-24T15:22:50.890221Z I i.q.c.TableWriter REMOVED column 'bool3[BOOLEAN]' from /test~
2025-03-24T15:22:50.8917444Z 2025-03-24T15:22:50.890847Z I i.q.c.TableWriter closed 'test'
2025-03-24T15:22:50.8927570Z 2025-03-24T15:22:50.891063Z I i.q.c.p.ReaderPool closed 'test~' [at=0:0, reason=POOL_CLOSED]
2025-03-24T15:22:50.8927997Z 2025-03-24T15:22:50.891231Z I i.q.c.p.ReaderPool closed 'test~' [at=0:1, reason=POOL_CLOSED]
2025-03-24T15:22:50.8928569Z 2025-03-24T15:22:50.891409Z I i.q.c.p.ReaderPool closed 'test~' [at=0:2, reason=POOL_CLOSED]
2025-03-24T15:22:50.8931736Z 2025-03-24T15:22:50.891516Z I i.q.c.p.ReaderPool closed 'test~' [at=0:3, reason=POOL_CLOSED]
2025-03-24T15:22:50.8939871Z 2025-03-24T15:22:50.891635Z I i.q.c.p.ReaderPool closed 'test~' [at=0:4, reason=POOL_CLOSED]
2025-03-24T15:22:50.8940323Z 2025-03-24T15:22:50.891773Z I i.q.c.p.ReaderPool closed 'test~' [at=0:5, reason=POOL_CLOSED]
2025-03-24T15:22:50.8940680Z 2025-03-24T15:22:50.891905Z I i.q.c.p.ReaderPool closed 'test~' [at=0:6, reason=POOL_CLOSED]
2025-03-24T15:22:50.8948688Z 2025-03-24T15:22:50.891993Z I i.q.c.p.ReaderPool closed 'test~' [at=0:7, reason=POOL_CLOSED]
2025-03-24T15:22:50.8949049Z 2025-03-24T15:22:50.891994Z I i.q.c.p.ReaderPool closed
2025-03-24T15:22:50.8949394Z 2025-03-24T15:22:50.893037Z I i.q.t.AbstractCairoTest Tearing down test ReaderPoolTest#testConcurrentGetAndGetCopyOfFuzz
2025-03-24T15:22:50.8952942Z 2025-03-24T15:22:50.893134Z I i.q.t.AbstractTest Finished test ReaderPoolTest#testConcurrentGetAndGetCopyOfFuzz
2025-03-24T15:22:50.9032169Z 2025-03-24T15:22:50.901343Z E i.q.t.TestListener ***** Test Failed *****io.questdb.test.cairo.pool.ReaderPoolTest.testConcurrentGetAndGetCopyOfFuzz duration_ms=206 : 
2025-03-24T15:22:50.9033925Z java.lang.AssertionError: Memory usage by tag: NATIVE_PATH, difference: 256 expected:<2235778> but was:<2236034>
2025-03-24T15:22:50.9035200Z 	at org.junit.Assert.fail(Assert.java:89)
2025-03-24T15:22:50.9036388Z 	at org.junit.Assert.failNotEquals(Assert.java:835)
2025-03-24T15:22:50.9036657Z 	at org.junit.Assert.assertEquals(Assert.java:647)
2025-03-24T15:22:50.9037910Z 	at io.questdb.test.tools.TestUtils$LeakCheck.close(TestUtils.java:2112)
2025-03-24T15:22:50.9039136Z 	at io.questdb.test.tools.TestUtils.assertMemoryLeak(TestUtils.java:699)
2025-03-24T15:22:50.9040500Z 	at io.questdb.test.cairo.pool.ReaderPoolTest.assertWithPool(ReaderPoolTest.java:1345)
2025-03-24T15:22:50.9042052Z 	at io.questdb.test.cairo.pool.ReaderPoolTest.assertWithPool(ReaderPoolTest.java:1341)
2025-03-24T15:22:50.9043855Z 	at io.questdb.test.cairo.pool.ReaderPoolTest.testConcurrentGetAndGetCopyOfFuzz(ReaderPoolTest.java:279)
2025-03-24T15:22:50.9045262Z 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
2025-03-24T15:22:50.9046977Z 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
2025-03-24T15:22:50.9048672Z 	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
2025-03-24T15:22:50.9049909Z 	at java.lang.reflect.Method.invoke(Method.java:566)
2025-03-24T15:22:50.9051356Z 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
2025-03-24T15:22:50.9053564Z 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
2025-03-24T15:22:50.9055008Z 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
2025-03-24T15:22:50.9056760Z 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
2025-03-24T15:22:50.9057966Z 	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
2025-03-24T15:22:50.9059607Z 	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
2025-03-24T15:22:50.9060796Z 	at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:61)
2025-03-24T15:22:50.9062423Z 	at org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:299)
2025-03-24T15:22:50.9064267Z 	at org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:293)
2025-03-24T15:22:50.9065480Z 	at java.util.concurrent.FutureTask.run(FutureTask.java:264)
2025-03-24T15:22:50.9066638Z 	at java.lang.Thread.run(Thread.java:829)
2025-03-24T15:22:50.9068751Z 2025-03-24T15:22:50.901389Z I i.q.t.TestListener <<<< io.questdb.test.cairo.pool.ReaderPoolTest.testConcurrentGetAndGetCopyOfFuzz duration_ms=206
QuestDB version:

8.2.4-SNAPSHOT

OS, in case of Docker specify Docker and the Host OS:

linux

File System, in case of Docker specify Host File System:

zfs

Full Name:

Vlad Ilyushchenko

Affiliation:

QuestDB

Have you followed Linux, MacOs kernel configuration steps to increase Maximum open files and Maximum virtual memory areas limit?
  • Yes, I have
Additional context

No response

Contributor guide

Open the contributing guide

First steps

  1. Read the whole issue, then the project's contributing guide.
  2. Comment on the issue to say you are picking it up — it saves two people doing the same work.
  3. Fork the repository and make your change on a branch.
  4. Open a pull request that references the issue number.

Research direction

Start with ReaderPoolTest.java at testConcurrentGetAndGetCopyOfFuzz and the assertWithPool calls shown in the stack trace. Run the test with seeds 2619385209160L and 1742829770703L, then inspect TestUtils.java around the leak check for the NATIVE_PATH difference. Done means the concurrent test no longer fails its memory-leak assertion.

Written by the indexing model from the issue text.

Assessment

Tech stack
java
Domain
databases, testing
Issue type
Bug
Difficulty
4/5
Estimated time
3-5 days
Activity status
Stale
Clarity
Needs clarification
Newbie friendliness
32/100

Get new issues in your inbox

A short digest of beginner-friendly GitHub issues.