questdb icon indicating copy to clipboard operation
questdb copied to clipboard

Published 20 hours ago • verified publisher icon questdb

Flaky LineTcpReceiverFuzzTest.testAddColumns

Open bluestreak01 opened this issue 3 years ago • 0 comments

Describe the bug

2022-02-23T21:38:06.240886Z I i.q.c.TableWriter closed 'WEATHER1'
2022-02-23T21:38:06.240889Z I i.q.c.p.WriterPool closed [table=`WEATHER1`, reason=IDLE, by=1]
2022-02-23T21:38:06.240999Z I i.q.c.p.ReaderPool closed 'weather2' [at=0:0, reason=IDLE]
2022-02-23T21:38:06.241025Z I i.q.c.p.ReaderPool closed 'weather2' [at=0:1, reason=IDLE]
2022-02-23T21:38:06.241082Z I i.q.c.p.ReaderPool closed 'weather2' [at=0:2, reason=IDLE]
2022-02-23T21:38:06.241169Z I i.q.c.p.ReaderPool closed 'weather3' [at=0:0, reason=IDLE]
2022-02-23T21:38:06.241224Z I i.q.c.p.ReaderPool closed 'weather3' [at=0:1, reason=IDLE]
2022-02-23T21:38:06.241309Z I i.q.c.p.ReaderPool closed 'weather0' [at=0:0, reason=IDLE]
2022-02-23T21:38:06.241331Z I i.q.c.p.ReaderPool closed 'weather0' [at=0:1, reason=IDLE]
2022-02-23T21:38:06.241382Z I i.q.c.p.ReaderPool closed 'weather0' [at=0:2, reason=IDLE]
2022-02-23T21:38:06.241463Z I i.q.c.p.ReaderPool closed 'weather4' [at=0:0, reason=IDLE]
2022-02-23T21:38:06.241509Z I i.q.c.p.ReaderPool closed 'weather4' [at=0:1, reason=IDLE]
2022-02-23T21:38:06.241590Z I i.q.c.p.ReaderPool closed 'WEATHER1' [at=0:0, reason=IDLE]
2022-02-23T21:38:06.241629Z I i.q.c.p.ReaderPool closed 'WEATHER1' [at=0:1, reason=IDLE]
2022-02-23T21:38:06.241646Z I i.q.c.AbstractCairoTest Tearing down test LineTcpReceiverFuzzTest#testDuplicatesReorderingColumns
2022-02-23T21:38:06.246590Z I i.q.c.AbstractCairoTest Starting test LineTcpReceiverFuzzTest#testAddColumns
2022-02-23T21:38:06.246813Z I i.q.c.l.t.LineTcpReceiverFuzzTest random seed : 1645652286246, 577923368031041
2022-02-23T21:38:06.246840Z I i.q.c.l.t.LineTcpReceiver using default context
2022-02-23T21:38:06.246933Z A IODispatcher listening on 0.0.0.0:9002 [fd=17 backlog=128]
2022-02-23T21:38:06.247153Z I i.q.c.l.t.AbstractLineTcpReceiverTest os scheduled [name=questdb-worker-5326]
2022-02-23T21:38:06.247189Z I i.q.c.l.t.AbstractLineTcpReceiverTest os scheduled [name=questdb-worker-5327]
2022-02-23T21:38:06.247226Z I i.q.c.l.t.AbstractLineTcpReceiverTest started
2022-02-23T21:38:06.247232Z I i.q.c.l.t.AbstractLineTcpReceiverTest os scheduled [name=questdb-worker-5328]
2022-02-23T21:38:06.247281Z I i.q.c.l.t.AbstractLineTcpReceiverTest os scheduled [name=questdb-worker-5329]
2022-02-23T21:38:06.247825Z I IODispatcher connected [ip=127.0.0.1, fd=21]
2022-02-23T21:38:06.247833Z I IODispatcher connected [ip=127.0.0.1, fd=22]
2022-02-23T21:38:06.248059Z I i.q.c.l.t.LineTcpMeasurementScheduler creating table [tableName=weather4]
2022-02-23T21:38:06.248150Z I i.q.c.CairoEngine locked [table=`weather4`, thread=11666]
2022-02-23T21:38:06.249106Z I i.q.c.p.WriterPool created [table=`weather4`, thread=11666]
2022-02-23T21:38:06.249112Z I i.q.c.TableWriter open 'weather4'
2022-02-23T21:38:06.249526Z I i.q.c.TableWriter purging non attached partitions [path=/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather4]
2022-02-23T21:38:06.249561Z I i.q.c.CairoEngine unlocked [table=`weather4`]
2022-02-23T21:38:06.249565Z I i.q.c.p.WriterPool >> [table=`weather4`, thread=11666]
2022-02-23T21:38:06.249571Z I i.q.c.l.t.LineTcpMeasurementScheduler assigned weather4 to thread 0
2022-02-23T21:38:06.249576Z I i.q.c.l.t.TableUpdateDetails network IO thread using table [workerId=0, tableName=weather4, nNetworkIoWorkers=1]
2022-02-23T21:38:06.249581Z I i.q.c.p.ReaderPool open 'weather4' [at=0:0]
2022-02-23T21:38:06.249854Z I i.q.c.l.t.LineTcpMeasurementScheduler creating table [tableName=weather3]
2022-02-23T21:38:06.249885Z I i.q.c.CairoEngine locked [table=`weather3`, thread=11666]
2022-02-23T21:38:06.250795Z I i.q.c.p.WriterPool created [table=`weather3`, thread=11666]
2022-02-23T21:38:06.250798Z I i.q.c.TableWriter open 'weather3'
2022-02-23T21:38:06.251268Z I i.q.c.TableWriter purging non attached partitions [path=/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather3]
2022-02-23T21:38:06.251290Z I i.q.c.CairoEngine unlocked [table=`weather3`]
2022-02-23T21:38:06.251291Z I i.q.c.p.WriterPool >> [table=`weather3`, thread=11666]
2022-02-23T21:38:06.251293Z I i.q.c.l.t.LineTcpMeasurementScheduler assigned weather3 to thread 1
2022-02-23T21:38:06.251294Z I i.q.c.l.t.TableUpdateDetails network IO thread using table [workerId=0, tableName=weather3, nNetworkIoWorkers=1]
2022-02-23T21:38:06.251295Z I i.q.c.p.ReaderPool open 'weather3' [at=0:0]
2022-02-23T21:38:06.251363Z I i.q.c.l.t.LineTcpMeasurementScheduler creating table [tableName=WEATHER0]
2022-02-23T21:38:06.251416Z I i.q.c.CairoEngine locked [table=`WEATHER0`, thread=11668]
2022-02-23T21:38:06.251667Z I i.q.c.l.t.LineTcpWriterJob assigned table to writer thread [tableName=weather3, threadId=1]
2022-02-23T21:38:06.252259Z I i.q.c.TableWriter switched partition [path='/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather3/2016-06-13']
2022-02-23T21:38:06.252849Z I i.q.c.p.WriterPool created [table=`WEATHER0`, thread=11668]
2022-02-23T21:38:06.252854Z I i.q.c.TableWriter open 'WEATHER0'
2022-02-23T21:38:06.253367Z I i.q.c.TableWriter purging non attached partitions [path=/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/WEATHER0]
2022-02-23T21:38:06.253390Z I i.q.c.CairoEngine unlocked [table=`WEATHER0`]
2022-02-23T21:38:06.253392Z I i.q.c.p.WriterPool >> [table=`WEATHER0`, thread=11668]
2022-02-23T21:38:06.253394Z I i.q.c.l.t.LineTcpMeasurementScheduler assigned WEATHER0 to thread 2
2022-02-23T21:38:06.253395Z I i.q.c.l.t.TableUpdateDetails network IO thread using table [workerId=2, tableName=WEATHER0, nNetworkIoWorkers=1]
2022-02-23T21:38:06.253397Z I i.q.c.p.ReaderPool open 'WEATHER0' [at=0:0]
2022-02-23T21:38:06.253424Z I i.q.c.l.t.LineTcpMeasurementScheduler creating table [tableName=weather2]
2022-02-23T21:38:06.253457Z I i.q.c.CairoEngine locked [table=`weather2`, thread=11666]
2022-02-23T21:38:06.254218Z I i.q.c.p.WriterPool created [table=`weather2`, thread=11666]
2022-02-23T21:38:06.254222Z I i.q.c.TableWriter open 'weather2'
2022-02-23T21:38:06.254569Z I i.q.c.TableWriter purging non attached partitions [path=/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2]
2022-02-23T21:38:06.254589Z I i.q.c.CairoEngine unlocked [table=`weather2`]
2022-02-23T21:38:06.254590Z I i.q.c.p.WriterPool >> [table=`weather2`, thread=11666]
2022-02-23T21:38:06.254592Z I i.q.c.l.t.LineTcpMeasurementScheduler assigned weather2 to thread 3
2022-02-23T21:38:06.254593Z I i.q.c.l.t.TableUpdateDetails network IO thread using table [workerId=0, tableName=weather2, nNetworkIoWorkers=1]
2022-02-23T21:38:06.254594Z I i.q.c.p.ReaderPool open 'weather2' [at=0:0]
2022-02-23T21:38:06.254595Z I i.q.c.l.t.TableUpdateDetails network IO thread using table [workerId=2, tableName=weather3, nNetworkIoWorkers=2]
2022-02-23T21:38:06.254597Z I i.q.c.l.t.LineTcpWriterJob assigned table to writer thread [tableName=WEATHER0, threadId=2]
2022-02-23T21:38:06.254864Z I i.q.c.l.t.LineTcpWriterJob assigned table to writer thread [tableName=weather2, threadId=3]
2022-02-23T21:38:06.254868Z I i.q.c.l.t.LineTcpMeasurementScheduler creating table [tableName=weather1]
2022-02-23T21:38:06.254908Z I i.q.c.CairoEngine locked [table=`weather1`, thread=11666]
2022-02-23T21:38:06.255212Z I i.q.c.TableWriter switched partition [path='/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/WEATHER0/2016-06-13']
2022-02-23T21:38:06.255733Z I i.q.c.TableWriter switched partition [path='/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2/2016-06-13']
2022-02-23T21:38:06.256565Z I i.q.c.p.WriterPool created [table=`weather1`, thread=11666]
2022-02-23T21:38:06.256568Z I i.q.c.TableWriter open 'weather1'
2022-02-23T21:38:06.257199Z I i.q.c.TableWriter purging non attached partitions [path=/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather1]
2022-02-23T21:38:06.257236Z I i.q.c.CairoEngine unlocked [table=`weather1`]
2022-02-23T21:38:06.257238Z I i.q.c.p.WriterPool >> [table=`weather1`, thread=11666]
2022-02-23T21:38:06.257240Z I i.q.c.l.t.LineTcpMeasurementScheduler assigned weather1 to thread 0
2022-02-23T21:38:06.257240Z I i.q.c.l.t.TableUpdateDetails network IO thread using table [workerId=0, tableName=weather1, nNetworkIoWorkers=1]
2022-02-23T21:38:06.257242Z I i.q.c.l.t.LineTcpWriterJob assigned table to writer thread [tableName=weather4, threadId=0]
2022-02-23T21:38:06.258222Z I i.q.c.TableWriter switched partition [path='/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather4/2016-06-13']
2022-02-23T21:38:06.258893Z I i.q.c.p.ReaderPool open 'weather1' [at=0:0]
2022-02-23T21:38:06.259097Z I i.q.c.l.t.TableUpdateDetails network IO thread using table [workerId=2, tableName=weather2, nNetworkIoWorkers=2]
2022-02-23T21:38:06.259377Z I i.q.c.l.t.TableUpdateDetails network IO thread using table [workerId=2, tableName=weather4, nNetworkIoWorkers=2]
2022-02-23T21:38:06.259445Z I i.q.c.l.t.LineTcpWriterJob assigned table to writer thread [tableName=weather1, threadId=0]
2022-02-23T21:38:06.259591Z I i.q.c.l.t.TableUpdateDetails network IO thread using table [workerId=2, tableName=weather3, nNetworkIoWorkers=3]
2022-02-23T21:38:06.260495Z I i.q.c.TableWriter switched partition [path='/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather1/2016-06-13']
2022-02-23T21:38:06.266254Z I i.q.c.TableWriter adding column 'humidity0[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather3
2022-02-23T21:38:06.266580Z I i.q.c.TableWriter adding column 'city1[SYMBOL]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.267606Z I i.q.c.TableWriter ADDED column 'humidity0[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather3
2022-02-23T21:38:06.267666Z I i.q.c.TableWriter adding column 'terület0[STRING]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather4
2022-02-23T21:38:06.267805Z I i.q.c.TableWriter adding column 'terület1[STRING]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather3
2022-02-23T21:38:06.268851Z I i.q.c.TableWriter ADDED column 'city1[SYMBOL]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.268899Z I i.q.c.TableWriter ADDED column 'terület0[STRING]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather4
2022-02-23T21:38:06.269174Z I i.q.c.TableWriter adding column 'notes1[STRING]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather4
2022-02-23T21:38:06.269238Z I i.q.c.TableWriter ADDED column 'terület1[STRING]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather3
2022-02-23T21:38:06.269288Z I i.q.c.l.t.TableUpdateDetails network IO thread using table [workerId=2, tableName=weather1, nNetworkIoWorkers=2]
2022-02-23T21:38:06.270162Z I i.q.c.TableWriter ADDED column 'notes1[STRING]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather4
2022-02-23T21:38:06.270192Z I i.q.c.TableWriter adding column 'temperature0[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather4
2022-02-23T21:38:06.270853Z I i.q.c.TableWriter ADDED column 'temperature0[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather4
2022-02-23T21:38:06.270886Z I i.q.c.TableWriter adding column 'location0[SYMBOL]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather1
2022-02-23T21:38:06.270901Z I i.q.c.TableWriter adding column 'hőmérséklet1[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/WEATHER0
2022-02-23T21:38:06.271381Z I i.q.c.TableWriter ADDED column 'hőmérséklet1[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/WEATHER0
2022-02-23T21:38:06.271421Z I i.q.c.TableWriter adding column 'hőmérséklet0[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/WEATHER0
2022-02-23T21:38:06.271802Z I i.q.c.TableWriter ADDED column 'location0[SYMBOL]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather1
2022-02-23T21:38:06.271898Z I i.q.c.TableWriter adding column 'temperature1[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather1
2022-02-23T21:38:06.271969Z I i.q.c.TableWriter ADDED column 'hőmérséklet0[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/WEATHER0
2022-02-23T21:38:06.272167Z I i.q.c.l.t.TableUpdateDetails network IO thread using table [workerId=2, tableName=weather1, nNetworkIoWorkers=3]
2022-02-23T21:38:06.272172Z I i.q.c.TableWriter adding column 'notes1[STRING]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/WEATHER0
2022-02-23T21:38:06.272412Z I i.q.c.TableWriter adding column 'ветер1[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather3
2022-02-23T21:38:06.272546Z I i.q.c.TableWriter ADDED column 'temperature1[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather1
2022-02-23T21:38:06.272708Z I i.q.c.l.t.TableUpdateDetails network IO thread using table [workerId=0, tableName=weather2, nNetworkIoWorkers=3]
2022-02-23T21:38:06.272718Z I i.q.c.TableWriter switched to o3 [table=weather2]
2022-02-23T21:38:06.272732Z I i.q.c.TableWriter adding column 'location0[SYMBOL]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.273012Z I i.q.c.TableWriter ADDED column 'notes1[STRING]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/WEATHER0
2022-02-23T21:38:06.273091Z I i.q.c.TableWriter adding column 'temperature0[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/WEATHER0
2022-02-23T21:38:06.273227Z I i.q.c.TableWriter ADDED column 'ветер1[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather3
2022-02-23T21:38:06.273276Z I i.q.c.TableWriter adding column 'terület0[STRING]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather3
2022-02-23T21:38:06.273726Z I i.q.c.TableWriter ADDED column 'temperature0[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/WEATHER0
2022-02-23T21:38:06.273818Z I i.q.c.l.t.TableUpdateDetails network IO thread using table [workerId=0, tableName=weather3, nNetworkIoWorkers=4]
2022-02-23T21:38:06.273931Z I i.q.c.TableWriter ADDED column 'terület0[STRING]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather3
2022-02-23T21:38:06.273996Z I i.q.c.TableWriter ADDED column 'location0[SYMBOL]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.273997Z I i.q.c.TableWriter switched to o3 [table=weather2]
2022-02-23T21:38:06.274027Z I i.q.c.TableWriter adding column 'location1[SYMBOL]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.274028Z I i.q.c.TableWriter sorting o3 [table=weather2]
2022-02-23T21:38:06.274087Z I i.q.c.TableWriter sorted [table=weather2]
2022-02-23T21:38:06.274202Z I i.q.c.l.t.TableUpdateDetails network IO thread using table [workerId=0, tableName=weather4, nNetworkIoWorkers=3]
2022-02-23T21:38:06.274207Z I i.q.c.TableWriter adding column 'notes0[STRING]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather1
2022-02-23T21:38:06.275015Z I i.q.c.TableWriter ADDED column 'notes0[STRING]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather1
2022-02-23T21:38:06.275067Z I i.q.c.TableWriter switched to o3 [table=weather3]
2022-02-23T21:38:06.275097Z I i.q.c.TableWriter adding column 'city0[SYMBOL]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather3
2022-02-23T21:38:06.275247Z I i.q.c.TableWriter closing last partition [table=weather2]
2022-02-23T21:38:06.275842Z I i.q.c.TableWriter merged partition [table=`weather2`, ts=2016-06-13T00:00:00.000000Z, txn=4]
2022-02-23T21:38:06.275978Z I i.q.c.TableWriter ADDED column 'city0[SYMBOL]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather3
2022-02-23T21:38:06.275980Z I i.q.c.TableWriter switched to o3 [table=weather3]
2022-02-23T21:38:06.276011Z I i.q.c.TableWriter adding column 'notes1[STRING]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather3
2022-02-23T21:38:06.276140Z I i.q.c.TableWriter switched partition [path='/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2/2016-06-13.4']
2022-02-23T21:38:06.276606Z I i.q.c.TableWriter ADDED column 'notes1[STRING]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather3
2022-02-23T21:38:06.276608Z I i.q.c.TableWriter switched to o3 [table=weather3]
2022-02-23T21:38:06.277454Z I i.q.c.TableWriter purged [path=/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2/2016-06-13]
2022-02-23T21:38:06.278186Z I i.q.c.TableWriter ADDED column 'location1[SYMBOL]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.278187Z I i.q.c.TableWriter switched to o3 [table=weather2]
2022-02-23T21:38:06.278201Z I i.q.c.TableWriter adding column 'temperature0[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.278599Z I i.q.c.TableWriter ADDED column 'temperature0[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.278600Z I i.q.c.TableWriter switched to o3 [table=weather2]
2022-02-23T21:38:06.278608Z I i.q.c.TableWriter adding column 'humidity0[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.278608Z I i.q.c.TableWriter sorting o3 [table=weather2]
2022-02-23T21:38:06.278618Z I i.q.c.TableWriter sorted [table=weather2]
2022-02-23T21:38:06.278792Z I i.q.c.TableReader open partition /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2/2016-06-13.4 [rowCount=3, partitionNameTxn=4, transientRowCount=3, partitionIndex=0, partitionCount=1]
2022-02-23T21:38:06.279129Z I i.q.c.l.t.TableUpdateDetails network IO thread using table [workerId=2, tableName=weather2, nNetworkIoWorkers=4]
2022-02-23T21:38:06.279645Z I i.q.c.TableWriter closing last partition [table=weather2]
2022-02-23T21:38:06.280204Z I i.q.c.TableWriter merged partition [table=`weather2`, ts=2016-06-13T00:00:00.000000Z, txn=7]
2022-02-23T21:38:06.280507Z I i.q.c.TableWriter switched partition [path='/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2/2016-06-13.7']
2022-02-23T21:38:06.281827Z I i.q.c.TableWriter purged [path=/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2/2016-06-13.4]
2022-02-23T21:38:06.282234Z I i.q.c.TableWriter ADDED column 'humidity0[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.282235Z I i.q.c.TableWriter switched to o3 [table=weather2]
2022-02-23T21:38:06.282244Z I i.q.c.TableWriter adding column 'hőmérséklet1[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.282245Z I i.q.c.TableWriter sorting o3 [table=weather2]
2022-02-23T21:38:06.282253Z I i.q.c.TableWriter sorted [table=weather2]
2022-02-23T21:38:06.282284Z I i.q.c.l.t.TableUpdateDetails network IO thread using table [workerId=0, tableName=WEATHER0, nNetworkIoWorkers=2]
2022-02-23T21:38:06.282285Z I i.q.c.TableWriter switched to o3 [table=weather4]
2022-02-23T21:38:06.282311Z I i.q.c.TableWriter adding column 'city0[SYMBOL]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather4
2022-02-23T21:38:06.282534Z I i.q.c.TableReader open partition /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2/2016-06-13.7 [rowCount=4, partitionNameTxn=7, transientRowCount=4, partitionIndex=0, partitionCount=1]
2022-02-23T21:38:06.282911Z I i.q.c.l.t.TableUpdateDetails network IO thread using table [workerId=2, tableName=weather4, nNetworkIoWorkers=4]
2022-02-23T21:38:06.283869Z I i.q.c.TableWriter ADDED column 'city0[SYMBOL]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather4
2022-02-23T21:38:06.283870Z I i.q.c.TableWriter switched to o3 [table=weather4]
2022-02-23T21:38:06.283893Z I i.q.c.TableWriter adding column 'temperature1[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather4
2022-02-23T21:38:06.284106Z I i.q.c.TableWriter closing last partition [table=weather2]
2022-02-23T21:38:06.314289Z I i.q.c.TableWriter ADDED column 'temperature1[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather4
2022-02-23T21:38:06.314295Z I i.q.c.TableWriter switched to o3 [table=weather4]
2022-02-23T21:38:06.314363Z I i.q.c.TableWriter adding column 'ветер1[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather4
2022-02-23T21:38:06.314365Z I i.q.c.TableWriter sorting o3 [table=weather4]
2022-02-23T21:38:06.314445Z I i.q.c.TableWriter sorted [table=weather4]
2022-02-23T21:38:06.315307Z I i.q.c.TableWriter merged partition [table=`weather2`, ts=2016-06-13T00:00:00.000000Z, txn=9]
2022-02-23T21:38:06.315737Z I i.q.c.TableWriter switched partition [path='/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2/2016-06-13.9']
2022-02-23T21:38:06.316620Z I i.q.c.TableWriter closing last partition [table=weather4]
2022-02-23T21:38:06.317703Z I i.q.c.TableWriter merged partition [table=`weather4`, ts=2016-06-13T00:00:00.000000Z, txn=7]
2022-02-23T21:38:06.318429Z I i.q.c.TableWriter switched partition [path='/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather4/2016-06-13.7']
2022-02-23T21:38:06.318431Z I i.q.c.TableWriter purged [path=/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2/2016-06-13.7]
2022-02-23T21:38:06.319507Z I i.q.c.TableWriter ADDED column 'hőmérséklet1[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.319707Z I i.q.c.TableWriter adding column 'temperature1[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.320774Z I i.q.c.TableWriter ADDED column 'temperature1[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.320831Z I i.q.c.TableWriter adding column 'city0[SYMBOL]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.321412Z I i.q.c.TableReader error reloading metadata [table=weather4, errno=2, error=could not open read-only [file=/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather4/2016-06-13/temperature0.top]]
2022-02-23T21:38:06.321793Z I i.q.c.TableWriter purged [path=/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather4/2016-06-13]
2022-02-23T21:38:06.321874Z E i.q.c.l.t.LineTcpConnectionContext [22] could not process line data [table=WEATHER4, ex=
java.lang.NullPointerException: Cannot invoke "io.questdb.cairo.SymbolMapReader.updateSymbolCount(int)" because the return value of "io.questdb.std.ObjList.getQuick(int)" is null
	at io.questdb.cairo.TableReader.reloadSymbolMapCounts(TableReader.java:1203)
	at io.questdb.cairo.TableReader.reloadMetadata(TableReader.java:1138)
	at io.questdb.cairo.TableReader.reloadSlow(TableReader.java:1376)
	at io.questdb.cairo.TableReader.reload(TableReader.java:457)
	at io.questdb.cairo.TableReader.goActive(TableReader.java:317)
	at io.questdb.cairo.pool.ReaderPool.get(ReaderPool.java:98)
	at io.questdb.cairo.CairoEngine.getReader(CairoEngine.java:245)
	at io.questdb.cairo.CairoEngine.getReader(CairoEngine.java:236)
	at io.questdb.cutlass.line.tcp.TableUpdateDetails$ThreadLocalDetails.getColumnIndexFromReader(TableUpdateDetails.java:376)
	at io.questdb.cutlass.line.tcp.TableUpdateDetails$ThreadLocalDetails.getColumnIndex(TableUpdateDetails.java:347)
	at io.questdb.cutlass.line.tcp.LineTcpMeasurementEvent.createMeasurementEvent(LineTcpMeasurementEvent.java:439)
	at io.questdb.cutlass.line.tcp.LineTcpMeasurementScheduler.scheduleEvent(LineTcpMeasurementScheduler.java:354)
	at io.questdb.cutlass.line.tcp.LineTcpConnectionContext.parseMeasurements(LineTcpConnectionContext.java:179)
	at io.questdb.cutlass.line.tcp.LineTcpConnectionContext.handleIO(LineTcpConnectionContext.java:162)
	at io.questdb.cutlass.line.tcp.LineTcpNetworkIOJob.handleIO(LineTcpNetworkIOJob.java:122)
	at io.questdb.cutlass.line.tcp.LineTcpNetworkIOJob.run(LineTcpNetworkIOJob.java:97)
	at io.questdb.mp.Worker.run(Worker.java:117)
]
2022-02-23T21:38:06.322351Z I i.q.c.TableWriter ADDED column 'city0[SYMBOL]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.322403Z I i.q.c.TableWriter adding column 'notes1[STRING]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.322550Z I i.q.c.TableWriter ADDED column 'ветер1[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather4
2022-02-23T21:38:06.322551Z I i.q.c.TableWriter switched to o3 [table=weather4]
2022-02-23T21:38:06.322590Z I IODispatcher scheduling disconnect [fd=22, reason=0]
2022-02-23T21:38:06.322594Z I IODispatcher disconnected [ip=127.0.0.1, fd=22, src=queue]
2022-02-23T21:38:06.323403Z I i.q.c.TableWriter ADDED column 'notes1[STRING]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.323937Z I i.q.c.TableReader open partition /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2/2016-06-13.9 [rowCount=6, partitionNameTxn=9, transientRowCount=6, partitionIndex=0, partitionCount=1]
2022-02-23T21:38:06.324466Z I i.q.c.TableWriter switched to o3 [table=WEATHER0]
2022-02-23T21:38:06.324636Z I i.q.c.TableWriter switched to o3 [table=weather2]
2022-02-23T21:38:06.324765Z I i.q.c.TableWriter switched to o3 [table=weather1]
2022-02-23T21:38:06.324793Z I i.q.c.TableWriter adding column 'city1[SYMBOL]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather1
2022-02-23T21:38:06.326047Z I i.q.c.TableWriter ADDED column 'city1[SYMBOL]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather1
2022-02-23T21:38:06.326049Z I i.q.c.TableWriter switched to o3 [table=weather1]
2022-02-23T21:38:06.402363Z I i.q.c.l.t.TableUpdateDetails network IO thread using table [workerId=0, tableName=weather1, nNetworkIoWorkers=4]
2022-02-23T21:38:06.402653Z I i.q.c.TableWriter adding column 'location0[SYMBOL]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather3
2022-02-23T21:38:06.402655Z I i.q.c.TableWriter sorting o3 [table=weather3]
2022-02-23T21:38:06.402724Z I i.q.c.TableWriter sorted [table=weather3]
2022-02-23T21:38:06.403979Z I i.q.c.TableWriter closing last partition [table=weather3]
2022-02-23T21:38:06.404621Z I i.q.c.TableWriter merged partition [table=`weather3`, ts=2016-06-13T00:00:00.000000Z, txn=9]
2022-02-23T21:38:06.404992Z I i.q.c.TableWriter switched partition [path='/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather3/2016-06-13.9']
2022-02-23T21:38:06.407034Z I i.q.c.TableWriter purged [path=/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather3/2016-06-13]
2022-02-23T21:38:06.407866Z I i.q.c.TableWriter ADDED column 'location0[SYMBOL]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather3
2022-02-23T21:38:06.450031Z I i.q.c.TableWriter adding column 'terület0[STRING]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.450044Z I i.q.c.TableWriter sorting o3 [table=weather2]
2022-02-23T21:38:06.450078Z I i.q.c.TableWriter sorted [table=weather2]
2022-02-23T21:38:06.452679Z I i.q.c.TableWriter closing last partition [table=weather2]
2022-02-23T21:38:06.480397Z I i.q.c.TableWriter merged partition [table=`weather2`, ts=2016-06-13T00:00:00.000000Z, txn=15]
2022-02-23T21:38:06.480893Z I i.q.c.TableWriter switched partition [path='/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2/2016-06-13.15']
2022-02-23T21:38:06.482799Z I i.q.c.TableWriter purged [path=/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2/2016-06-13.9]
2022-02-23T21:38:06.483419Z I i.q.c.TableWriter ADDED column 'terület0[STRING]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.483422Z I i.q.c.TableWriter switched to o3 [table=weather2]
2022-02-23T21:38:06.483436Z I i.q.c.TableWriter adding column 'ветер0[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.483861Z I i.q.c.TableWriter ADDED column 'ветер0[DOUBLE]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.483862Z I i.q.c.TableWriter switched to o3 [table=weather2]
2022-02-23T21:38:06.483868Z I i.q.c.TableWriter adding column 'terület1[STRING]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.484937Z I i.q.c.TableWriter ADDED column 'terület1[STRING]' to /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2
2022-02-23T21:38:06.484938Z I i.q.c.TableWriter switched to o3 [table=weather2]
2022-02-23T21:38:06.485207Z E i.q.c.l.t.LineTcpConnectionContext [21] could not process line data [table=weather4, ex=
java.lang.NullPointerException: Cannot invoke "io.questdb.cairo.SymbolMapReader.updateSymbolCount(int)" because the return value of "io.questdb.std.ObjList.getQuick(int)" is null
	at io.questdb.cairo.TableReader.reloadSymbolMapCounts(TableReader.java:1203)
	at io.questdb.cairo.TableReader.reloadMetadata(TableReader.java:1138)
	at io.questdb.cairo.TableReader.reloadSlow(TableReader.java:1376)
	at io.questdb.cairo.TableReader.reload(TableReader.java:457)
	at io.questdb.cairo.TableReader.goActive(TableReader.java:317)
	at io.questdb.cairo.pool.ReaderPool.get(ReaderPool.java:98)
	at io.questdb.cairo.CairoEngine.getReader(CairoEngine.java:245)
	at io.questdb.cairo.CairoEngine.getReader(CairoEngine.java:236)
	at io.questdb.cutlass.line.tcp.TableUpdateDetails$ThreadLocalDetails.getColumnIndexFromReader(TableUpdateDetails.java:376)
	at io.questdb.cutlass.line.tcp.TableUpdateDetails$ThreadLocalDetails.getColumnIndex(TableUpdateDetails.java:347)
	at io.questdb.cutlass.line.tcp.LineTcpMeasurementEvent.createMeasurementEvent(LineTcpMeasurementEvent.java:439)
	at io.questdb.cutlass.line.tcp.LineTcpMeasurementScheduler.scheduleEvent(LineTcpMeasurementScheduler.java:354)
	at io.questdb.cutlass.line.tcp.LineTcpConnectionContext.parseMeasurements(LineTcpConnectionContext.java:179)
	at io.questdb.cutlass.line.tcp.LineTcpConnectionContext.handleIO(LineTcpConnectionContext.java:162)
	at io.questdb.cutlass.line.tcp.LineTcpNetworkIOJob.handleIO(LineTcpNetworkIOJob.java:122)
	at io.questdb.cutlass.line.tcp.LineTcpNetworkIOJob.run(LineTcpNetworkIOJob.java:97)
	at io.questdb.mp.Worker.run(Worker.java:117)
]
2022-02-23T21:38:06.485270Z I IODispatcher scheduling disconnect [fd=21, reason=0]
2022-02-23T21:38:06.485273Z I IODispatcher disconnected [ip=127.0.0.1, fd=21, src=queue]
2022-02-23T21:38:06.593350Z I i.q.c.l.t.TableUpdateDetails network IO thread released table [workerId=2, tableName=WEATHER0, nNetworkIoWorkers=1]
2022-02-23T21:38:06.595662Z I i.q.c.l.t.TableUpdateDetails network IO thread released table [workerId=0, tableName=WEATHER0, nNetworkIoWorkers=0]
2022-02-23T21:38:06.595666Z I i.q.c.l.t.LineTcpMeasurementScheduler active table going idle [tableName=WEATHER0]
2022-02-23T21:38:06.595683Z I i.q.c.l.t.LineTcpMeasurementScheduler releasing writer, its been idle since 2022-02-23T21:38:06.322000Z[tableName=WEATHER0]
2022-02-23T21:38:06.595695Z I i.q.c.TableWriter sorting o3 [table=WEATHER0]
2022-02-23T21:38:06.595768Z I i.q.c.TableWriter sorted [table=WEATHER0]
2022-02-23T21:38:06.596924Z I i.q.c.TableWriter closing last partition [table=WEATHER0]
2022-02-23T21:38:06.597574Z I i.q.c.TableWriter merged partition [table=`WEATHER0`, ts=2016-06-13T00:00:00.000000Z, txn=6]
2022-02-23T21:38:06.597883Z I i.q.c.TableWriter switched partition [path='/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/WEATHER0/2016-06-13.6']
2022-02-23T21:38:06.599670Z I i.q.c.TableWriter purged [path=/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/WEATHER0/2016-06-13]
2022-02-23T21:38:06.599674Z I i.q.c.p.WriterPool << [table=`WEATHER0`, thread=11668]
2022-02-23T21:38:06.599707Z I i.q.c.TableReader open partition /var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/WEATHER0/2016-06-13.6 [rowCount=4, partitionNameTxn=6, transientRowCount=4, partitionIndex=0, partitionCount=1]
2022-02-23T21:38:06.600252Z I i.q.c.l.t.LineTcpReceiverFuzzTest table.getName(): weather0, tableName: WEATHER0, table.size(): 8, reader.size(): 4
2022-02-23T21:38:06.717932Z I i.q.c.l.t.TableUpdateDetails network IO thread released table [workerId=0, tableName=weather3, nNetworkIoWorkers=3]
2022-02-23T21:38:06.718391Z I i.q.c.l.t.TableUpdateDetails network IO thread released table [workerId=0, tableName=weather2, nNetworkIoWorkers=3]
2022-02-23T21:38:06.718582Z I i.q.c.l.t.TableUpdateDetails network IO thread released table [workerId=0, tableName=weather1, nNetworkIoWorkers=3]
2022-02-23T21:38:06.718584Z I i.q.c.l.t.TableUpdateDetails network IO thread released table [workerId=0, tableName=weather2, nNetworkIoWorkers=2]
2022-02-23T21:38:06.718585Z I i.q.c.l.t.TableUpdateDetails network IO thread released table [workerId=0, tableName=weather3, nNetworkIoWorkers=2]
2022-02-23T21:38:06.718585Z I i.q.c.l.t.TableUpdateDetails network IO thread released table [workerId=0, tableName=weather1, nNetworkIoWorkers=2]
2022-02-23T21:38:06.721279Z I i.q.c.l.t.TableUpdateDetails network IO thread released table [workerId=2, tableName=weather3, nNetworkIoWorkers=1]
2022-02-23T21:38:06.721301Z I i.q.c.l.t.TableUpdateDetails network IO thread released table [workerId=2, tableName=weather2, nNetworkIoWorkers=1]
2022-02-23T21:38:06.721304Z I i.q.c.l.t.TableUpdateDetails network IO thread released table [workerId=2, tableName=weather3, nNetworkIoWorkers=0]
2022-02-23T21:38:06.721307Z I i.q.c.l.t.LineTcpMeasurementScheduler active table going idle [tableName=weather3]
2022-02-23T21:38:06.721309Z I i.q.c.l.t.LineTcpMeasurementScheduler releasing writer, its been idle since 2022-02-23T21:38:06.402000Z[tableName=weather3]
2022-02-23T21:38:06.721314Z I i.q.c.p.WriterPool << [table=`weather3`, thread=11667]
2022-02-23T21:38:06.721336Z I i.q.c.l.t.TableUpdateDetails network IO thread released table [workerId=2, tableName=weather1, nNetworkIoWorkers=1]
2022-02-23T21:38:06.721336Z I i.q.c.l.t.TableUpdateDetails network IO thread released table [workerId=2, tableName=weather1, nNetworkIoWorkers=0]
2022-02-23T21:38:06.721337Z I i.q.c.l.t.LineTcpMeasurementScheduler active table going idle [tableName=weather1]
2022-02-23T21:38:06.721337Z I i.q.c.l.t.LineTcpMeasurementScheduler releasing writer, its been idle since 2022-02-23T21:38:06.402000Z[tableName=weather1]
2022-02-23T21:38:06.721338Z I i.q.c.TableWriter sorting o3 [table=weather1]
2022-02-23T21:38:06.721467Z I i.q.c.TableWriter sorted [table=weather1]
2022-02-23T21:38:06.723190Z I i.q.c.TableWriter closing last partition [table=weather1]
2022-02-23T21:38:06.723804Z I i.q.c.TableWriter merged partition [table=`weather1`, ts=2016-06-13T00:00:00.000000Z, txn=7]
2022-02-23T21:38:06.724152Z I i.q.c.TableWriter switched partition [path='/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather1/2016-06-13.7']
2022-02-23T21:38:06.725880Z I i.q.c.TableWriter purged [path=/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather1/2016-06-13]
2022-02-23T21:38:06.725882Z I i.q.c.p.WriterPool << [table=`weather1`, thread=11666]
2022-02-23T21:38:06.725884Z I i.q.c.l.t.TableUpdateDetails network IO thread released table [workerId=2, tableName=weather2, nNetworkIoWorkers=0]
2022-02-23T21:38:06.725884Z I i.q.c.l.t.LineTcpMeasurementScheduler active table going idle [tableName=weather2]
2022-02-23T21:38:06.817320Z I i.q.c.l.t.LineTcpMeasurementScheduler releasing writer, its been idle since 2022-02-23T21:38:06.402000Z[tableName=weather2]
2022-02-23T21:38:06.817325Z I i.q.c.TableWriter sorting o3 [table=weather2]
2022-02-23T21:38:06.817372Z I i.q.c.TableWriter sorted [table=weather2]
2022-02-23T21:38:06.820422Z I i.q.c.TableWriter closing last partition [table=weather2]
2022-02-23T21:38:06.848561Z I i.q.c.TableWriter merged partition [table=`weather2`, ts=2016-06-13T00:00:00.000000Z, txn=19]
2022-02-23T21:38:06.849297Z I i.q.c.TableWriter switched partition [path='/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2/2016-06-13.19']
2022-02-23T21:38:06.852836Z I i.q.c.TableWriter purged [path=/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather2/2016-06-13.15]
2022-02-23T21:38:06.852841Z I i.q.c.p.WriterPool << [table=`weather2`, thread=11669]
2022-02-23T21:38:06.853015Z I i.q.c.l.t.TableUpdateDetails network IO thread released table [workerId=0, tableName=weather4, nNetworkIoWorkers=3]
2022-02-23T21:38:06.853049Z I i.q.c.l.t.TableUpdateDetails network IO thread released table [workerId=2, tableName=weather4, nNetworkIoWorkers=2]
2022-02-23T21:38:06.853051Z I i.q.c.l.t.TableUpdateDetails network IO thread released table [workerId=0, tableName=weather4, nNetworkIoWorkers=1]
2022-02-23T21:38:06.853069Z I i.q.c.l.t.TableUpdateDetails network IO thread released table [workerId=2, tableName=weather4, nNetworkIoWorkers=0]
2022-02-23T21:38:06.853088Z I i.q.c.l.t.LineTcpMeasurementScheduler active table going idle [tableName=weather4]
2022-02-23T21:38:06.853090Z I i.q.c.l.t.LineTcpMeasurementScheduler releasing writer, its been idle since 2022-02-23T21:38:06.484000Z[tableName=weather4]
2022-02-23T21:38:06.853093Z I i.q.c.TableWriter sorting o3 [table=weather4]
2022-02-23T21:38:06.853112Z I i.q.c.TableWriter sorted [table=weather4]
2022-02-23T21:38:06.854403Z I i.q.c.TableWriter closing last partition [table=weather4]
2022-02-23T21:38:06.855388Z I i.q.c.TableWriter merged partition [table=`weather4`, ts=2016-06-13T00:00:00.000000Z, txn=9]
2022-02-23T21:38:06.855788Z I i.q.c.TableWriter switched partition [path='/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather4/2016-06-13.9']
2022-02-23T21:38:06.857737Z I i.q.c.TableWriter purged [path=/var/folders/bz/_w281sjx1nb_f2wv8ns6lh700000gn/T/junit11080528500620690616/dbRoot/weather4/2016-06-13.7]
2022-02-23T21:38:06.857740Z I i.q.c.p.WriterPool << [table=`weather4`, thread=11666]
2022-02-23T21:40:06.414815Z E i.q.c.l.t.AbstractLineTcpReceiverTest Stopping ILP worker pool because of an error
2022-02-23T21:40:06.417220Z I i.q.c.l.t.LineTcpWriterJob line protocol writer closing [threadId=3]
2022-02-23T21:40:06.417515Z I i.q.c.l.t.LineTcpReceiver closed
2022-02-23T21:40:06.417537Z I i.q.c.l.t.AbstractLineTcpReceiverTest cleaned [worker=3]
2022-02-23T21:40:06.420250Z I i.q.c.l.t.LineTcpWriterJob line protocol writer closing [threadId=2]
2022-02-23T21:40:06.420491Z I i.q.c.l.t.LineTcpReceiver closed
2022-02-23T21:40:06.420507Z I i.q.c.l.t.AbstractLineTcpReceiverTest cleaned [worker=2]
2022-02-23T21:40:06.482786Z I i.q.c.l.t.LineTcpWriterJob line protocol writer closing [threadId=1]
2022-02-23T21:40:06.482857Z I i.q.c.l.t.LineTcpReceiver closed
2022-02-23T21:40:06.482863Z I i.q.c.l.t.AbstractLineTcpReceiverTest cleaned [worker=1]
2022-02-23T21:40:06.512483Z I i.q.c.l.t.LineTcpWriterJob line protocol writer closing [threadId=0]
2022-02-23T21:40:06.512568Z I i.q.c.l.t.LineTcpReceiver closed
2022-02-23T21:40:06.512570Z I i.q.c.l.t.AbstractLineTcpReceiverTest cleaned [worker=0]
2022-02-23T21:40:06.512672Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=WEATHER0]
2022-02-23T21:40:06.512673Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=WEATHER0]
2022-02-23T21:40:06.512673Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=WEATHER0]
2022-02-23T21:40:06.512674Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=WEATHER0]
2022-02-23T21:40:06.512676Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather3]
2022-02-23T21:40:06.512677Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather3]
2022-02-23T21:40:06.512677Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather3]
2022-02-23T21:40:06.512678Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather3]
2022-02-23T21:40:06.512680Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather1]
2022-02-23T21:40:06.512680Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather1]
2022-02-23T21:40:06.512680Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather1]
2022-02-23T21:40:06.512681Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather1]
2022-02-23T21:40:06.512683Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather2]
2022-02-23T21:40:06.512684Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather2]
2022-02-23T21:40:06.512684Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather2]
2022-02-23T21:40:06.512684Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather2]
2022-02-23T21:40:06.512685Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather4]
2022-02-23T21:40:06.512685Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather4]
2022-02-23T21:40:06.512686Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather4]
2022-02-23T21:40:06.512687Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather4]
2022-02-23T21:40:06.512691Z I i.q.c.l.t.TableUpdateDetails closing table writer [tableName=weather4]
2022-02-23T21:40:06.512691Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather4]
2022-02-23T21:40:06.512692Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather4]
2022-02-23T21:40:06.512693Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather4]
2022-02-23T21:40:06.512693Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather4]
2022-02-23T21:40:06.512693Z I i.q.c.l.t.TableUpdateDetails closing table writer [tableName=weather3]
2022-02-23T21:40:06.512693Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather3]
2022-02-23T21:40:06.512694Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather3]
2022-02-23T21:40:06.512694Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather3]
2022-02-23T21:40:06.512695Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather3]
2022-02-23T21:40:06.512695Z I i.q.c.l.t.TableUpdateDetails closing table writer [tableName=weather1]
2022-02-23T21:40:06.512696Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather1]
2022-02-23T21:40:06.512696Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather1]
2022-02-23T21:40:06.512696Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather1]
2022-02-23T21:40:06.512696Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather1]
2022-02-23T21:40:06.512697Z I i.q.c.l.t.TableUpdateDetails closing table writer [tableName=weather2]
2022-02-23T21:40:06.512697Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather2]
2022-02-23T21:40:06.512697Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather2]
2022-02-23T21:40:06.512698Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather2]
2022-02-23T21:40:06.512698Z I i.q.c.l.t.TableUpdateDetails closing table parsers [tableName=weather2]
2022-02-23T21:40:06.512722Z I IODispatcher closed
2022-02-23T21:40:06.512735Z E i.q.c.l.t.AbstractLineTcpReceiverTest Stopping ILP receiver because of an error
2022-02-23T21:40:06.513137Z I i.q.c.p.ReaderPool closed 'weather2' [at=0:0, reason=POOL_CLOSED]
2022-02-23T21:40:06.513208Z I i.q.c.p.ReaderPool closed 'weather3' [at=0:0, reason=POOL_CLOSED]
2022-02-23T21:40:06.513245Z I i.q.c.p.ReaderPool closed 'weather1' [at=0:0, reason=POOL_CLOSED]
2022-02-23T21:40:06.513272Z I i.q.c.p.ReaderPool closed 'weather4' [at=0:0, reason=POOL_CLOSED]
2022-02-23T21:40:06.513336Z I i.q.c.p.ReaderPool closed 'WEATHER0' [at=0:0, reason=POOL_CLOSED]
2022-02-23T21:40:06.517485Z I i.q.c.TableWriter closed 'weather2'
2022-02-23T21:40:06.517487Z I i.q.c.p.WriterPool closed [table=`weather2`, reason=POOL_CLOSED, by=1]
2022-02-23T21:40:06.519437Z I i.q.c.TableWriter closed 'weather3'
2022-02-23T21:40:06.519445Z I i.q.c.p.WriterPool closed [table=`weather3`, reason=POOL_CLOSED, by=1]
2022-02-23T21:40:06.552019Z I i.q.c.TableWriter closed 'weather1'
2022-02-23T21:40:06.552026Z I i.q.c.p.WriterPool closed [table=`weather1`, reason=POOL_CLOSED, by=1]
2022-02-23T21:40:06.553435Z I i.q.c.TableWriter closed 'weather4'
2022-02-23T21:40:06.553436Z I i.q.c.p.WriterPool closed [table=`weather4`, reason=POOL_CLOSED, by=1]
2022-02-23T21:40:06.554544Z I i.q.c.TableWriter closed 'WEATHER0'
2022-02-23T21:40:06.554545Z I i.q.c.p.WriterPool closed [table=`WEATHER0`, reason=POOL_CLOSED, by=1]
2022-02-23T21:40:06.555921Z I i.q.c.AbstractCairoTest Tearing down test LineTcpReceiverFuzzTest#testAddColumns

java.lang.AssertionError: Timed out on waiting for the data to be ingested

	at org.junit.Assert.fail(Assert.java:89)
	at [email protected]/io.questdb.cutlass.line.tcp.AbstractLineTcpReceiverFuzzTest.assertTable(AbstractLineTcpReceiverFuzzTest.java:169)
	at [email protected]/io.questdb.cutlass.line.tcp.AbstractLineTcpReceiverFuzzTest.lambda$runTest$3(AbstractLineTcpReceiverFuzzTest.java:424)
	at [email protected]/io.questdb.cutlass.line.tcp.AbstractLineTcpReceiverTest.lambda$runInContext$0(AbstractLineTcpReceiverTest.java:192)
	at [email protected]/io.questdb.cairo.AbstractCairoTest.lambda$assertMemoryLeak$1(AbstractCairoTest.java:243)
	at [email protected]/io.questdb.test.tools.TestUtils.assertMemoryLeak(TestUtils.java:407)
	at [email protected]/io.questdb.cairo.AbstractCairoTest.assertMemoryLeak(AbstractCairoTest.java:240)
	at [email protected]/io.questdb.cairo.AbstractCairoTest.assertMemoryLeak(AbstractCairoTest.java:235)
	at [email protected]/io.questdb.cutlass.line.tcp.AbstractLineTcpReceiverTest.runInContext(AbstractLineTcpReceiverTest.java:182)
	at [email protected]/io.questdb.cutlass.line.tcp.AbstractLineTcpReceiverFuzzTest.runTest(AbstractLineTcpReceiverFuzzTest.java:388)
	at [email protected]/io.questdb.cutlass.line.tcp.AbstractLineTcpReceiverFuzzTest.runTest(AbstractLineTcpReceiverFuzzTest.java:363)
	at [email protected]/io.questdb.cutlass.line.tcp.LineTcpReceiverFuzzTest.testAddColumns(LineTcpReceiverFuzzTest.java:52)
	at jdk.internal.reflect.GeneratedMethodAccessor20.invoke(Unknown Source)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:568)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
	at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:61)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100)
	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63)
	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:54)
	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:137)
	at com.intellij.junit4.JUnit4IdeaTestRunner.startRunnerWithArgs(JUnit4IdeaTestRunner.java:69)
	at com.intellij.rt.junit.IdeaTestRunner$Repeater.startRunnerWithArgs(IdeaTestRunner.java:52)
	at com.intellij.rt.junit.JUnitStarter.prepareStreamsAndStart(JUnitStarter.java:235)
	at com.intellij.rt.junit.JUnitStarter.main(JUnitStarter.java:54)

To reproduce

No response

Expected Behavior

No response

Environment

- **QuestDB version**: 6.2.1-SNAP
- **OS**: OSX M1
- **Browser**:

Additional context

No response

bluestreak01 avatar Feb 23 '22 21:02 bluestreak01