sneak/routewatch
1
0
Fork 0
Code Issues Pull Requests Actions Packages Projects Releases Wiki Activity
c35b76deb8
routewatch/log.txt
sneak c35b76deb8 Optimize AS detail queries and increase PrefixHandler batch size 
- Increase PrefixHandler batch size from 20k to 25k (25% increase)
- Add missing index on origin_asn for live_routes table
- This index significantly speeds up AS detail page queries
- Add code to create missing indexes on existing databases
2025-07-28 22:07:27 +02:00

861 lines
173 KiB
Plaintext
Raw Blame History

[Fx] PROVIDE fx.Lifecycle <= go.uber.org/fx.New.func1()
[Fx] PROVIDE fx.Shutdowner <= go.uber.org/fx.(*App).shutdowner-fm()
[Fx] PROVIDE fx.DotGraph <= go.uber.org/fx.(*App).dotGraph-fm()
[Fx] PROVIDE *logger.Logger <= git.eeqj.de/sneak/routewatch/internal/logger.New()
[Fx] PROVIDE *config.Config <= git.eeqj.de/sneak/routewatch/internal/config.New()
[Fx] PROVIDE *metrics.Tracker <= git.eeqj.de/sneak/routewatch/internal/metrics.New()
[Fx] PROVIDE database.Store <= fx.Annotate(git.eeqj.de/sneak/routewatch/internal/database.New(), fx.As([[database.Store]])
[Fx] PROVIDE *streamer.Streamer <= git.eeqj.de/sneak/routewatch/internal/streamer.New()
[Fx] PROVIDE *server.Server <= git.eeqj.de/sneak/routewatch/internal/server.New()
[Fx] PROVIDE *routewatch.RouteWatch <= git.eeqj.de/sneak/routewatch/internal/routewatch.New()
[Fx] INVOKE git.eeqj.de/sneak/routewatch/internal/routewatch.CLIEntry.func1()
[Fx] BEFORE RUN provide: go.uber.org/fx.New.func1()
[Fx] RUN provide: go.uber.org/fx.New.func1() in 4.292µs
[Fx] BEFORE RUN provide: git.eeqj.de/sneak/routewatch/internal/config.New()
[Fx] RUN provide: git.eeqj.de/sneak/routewatch/internal/config.New() in 5.542µs
[Fx] BEFORE RUN provide: git.eeqj.de/sneak/routewatch/internal/logger.New()
[Fx] RUN provide: git.eeqj.de/sneak/routewatch/internal/logger.New() in 4.667µs
[Fx] BEFORE RUN provide: fx.Annotate(git.eeqj.de/sneak/routewatch/internal/database.New(), fx.As([[database.Store]])
{"time":"2025-07-28T22:05:38.373796+02:00","level":"INFO","msg":"Opening database","source":"database.go:61","func":"database.New","path":"/Users/user/Library/Application Support/berlin.sneak.app.routewatch/db.sqlite"}
{"time":"2025-07-28T22:05:53.735187+02:00","level":"DEBUG","msg":"Slow query","source":"slowquery.go:17","func":"database.logSlowQuery","query":"PRAGMA wal_checkpoint(TRUNCATE)","duration":4304952583}
{"time":"2025-07-28T22:05:53.735747+02:00","level":"INFO","msg":"Running VACUUM to optimize database (this may take a moment)","source":"database.go:125","func":"database.(*Database).Initialize"}
{"time":"2025-07-28T22:06:10.50962+02:00","level":"DEBUG","msg":"Slow query","source":"slowquery.go:17","func":"database.logSlowQuery","query":"VACUUM","duration":16771382375}
[Fx] RUN provide: fx.Annotate(git.eeqj.de/sneak/routewatch/internal/database.New(), fx.As([[database.Store]]) in 32.137852958s
[Fx] BEFORE RUN provide: git.eeqj.de/sneak/routewatch/internal/metrics.New()
[Fx] RUN provide: git.eeqj.de/sneak/routewatch/internal/metrics.New() in 466.959µs
[Fx] BEFORE RUN provide: git.eeqj.de/sneak/routewatch/internal/streamer.New()
[Fx] RUN provide: git.eeqj.de/sneak/routewatch/internal/streamer.New() in 173.375µs
[Fx] BEFORE RUN provide: git.eeqj.de/sneak/routewatch/internal/server.New()
[Fx] RUN provide: git.eeqj.de/sneak/routewatch/internal/server.New() in 257.292µs
[Fx] BEFORE RUN provide: git.eeqj.de/sneak/routewatch/internal/routewatch.New()
[Fx] RUN provide: git.eeqj.de/sneak/routewatch/internal/routewatch.New() in 4.917µs
[Fx] BEFORE RUN provide: go.uber.org/fx.(*App).shutdowner-fm()
[Fx] RUN provide: go.uber.org/fx.(*App).shutdowner-fm() in 8.833µs
[Fx] HOOK OnStart git.eeqj.de/sneak/routewatch/internal/routewatch.CLIEntry.func1.1() executing (caller: git.eeqj.de/sneak/routewatch/internal/routewatch.CLIEntry.func1)
[Fx] HOOK OnStart git.eeqj.de/sneak/routewatch/internal/routewatch.CLIEntry.func1.1() called by git.eeqj.de/sneak/routewatch/internal/routewatch.CLIEntry.func1 ran successfully in 295.5µs
[Fx] RUNNING
{"time":"2025-07-28T22:06:10.5161+02:00","level":"INFO","msg":"Starting RouteWatch","source":"app.go:64","func":"routewatch.(*RouteWatch).Run"}
{"time":"2025-07-28T22:06:10.516303+02:00","level":"INFO","msg":"Using batched database handlers for improved performance","source":"app.go:76","func":"routewatch.(*RouteWatch).Run"}
{"time":"2025-07-28T22:06:10.517982+02:00","level":"INFO","msg":"Starting HTTP server","source":"server.go:52","func":"server.(*Server).Start","port":"8080"}
{"time":"2025-07-28T22:06:13.171541+02:00","level":"INFO","msg":"Connected to RIS Live stream","source":"streamer.go:413","func":"streamer.(*Streamer).stream"}
{"time":"2025-07-28T22:06:13.526167+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:13.526195+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:14.080201+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:14.517968+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:14.759124+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":1232}
{"time":"2025-07-28T22:06:14.759145+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:14.759131+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:14.958983+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":199}
{"time":"2025-07-28T22:06:14.958989+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:14.961951+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":2}
{"time":"2025-07-28T22:06:14.961959+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:14.962699+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":0}
{"time":"2025-07-28T22:06:14.962706+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":15002,"unique_prefixes":4040,"success":4040,"duration_ms":1444}
{"time":"2025-07-28T22:06:14.967499+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:14.967511+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:15.057852+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:15.509654+02:00","level":"WARN","msg":"BGP notification","source":"streamer.go:509","func":"streamer.(*Streamer).stream","peer":"195.208.208.187","peer_asn":"9002"}
{"time":"2025-07-28T22:06:15.700523+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":732}
{"time":"2025-07-28T22:06:15.700545+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:15.70055+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:15.707031+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:15.707039+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":6}
{"time":"2025-07-28T22:06:15.707051+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25025,"unique_prefixes":4206,"success":4206,"duration_ms":743}
{"time":"2025-07-28T22:06:15.711882+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:15.717319+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":10}
{"time":"2025-07-28T22:06:15.717335+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:15.718515+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:15.843046+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:16.208683+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"217.29.66.232","peer_asn":"54104"}
{"time":"2025-07-28T22:06:16.261113+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":543}
{"time":"2025-07-28T22:06:16.261132+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:16.261137+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:16.263457+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:16.263513+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":2}
{"time":"2025-07-28T22:06:16.263537+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25001,"unique_prefixes":4853,"success":4853,"duration_ms":555}
{"time":"2025-07-28T22:06:16.275864+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:16.294769+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:16.294769+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":31}
{"time":"2025-07-28T22:06:16.297071+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:16.297924+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:16.297943+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":3}
{"time":"2025-07-28T22:06:16.577547+02:00","level":"WARN","msg":"BGP notification","source":"streamer.go:509","func":"streamer.(*Streamer).stream","peer":"27.111.229.239","peer_asn":"17639"}
{"time":"2025-07-28T22:06:16.753285+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:16.753285+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":455}
{"time":"2025-07-28T22:06:16.753319+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:16.771728+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":18}
{"time":"2025-07-28T22:06:16.771743+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:16.773378+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":1}
{"time":"2025-07-28T22:06:16.773387+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25001,"unique_prefixes":6202,"success":6202,"duration_ms":507}
{"time":"2025-07-28T22:06:16.78008+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:16.780089+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:17.176344+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:17.225406+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":445}
{"time":"2025-07-28T22:06:17.225415+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:17.225422+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:17.242115+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":16}
{"time":"2025-07-28T22:06:17.242132+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:17.243674+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":1}
{"time":"2025-07-28T22:06:17.243683+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25002,"unique_prefixes":7954,"success":7954,"duration_ms":469}
{"time":"2025-07-28T22:06:17.330666+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:17.330679+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:17.396854+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:18.231576+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":900}
{"time":"2025-07-28T22:06:18.231634+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:18.231599+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:18.244061+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":12}
{"time":"2025-07-28T22:06:18.244086+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:18.250707+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:18.250985+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":6}
{"time":"2025-07-28T22:06:18.251003+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25059,"unique_prefixes":7393,"success":7393,"duration_ms":927}
{"time":"2025-07-28T22:06:18.25105+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:18.268857+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":17}
{"time":"2025-07-28T22:06:18.795748+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:18.795764+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:19.062881+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":267}
{"time":"2025-07-28T22:06:19.062896+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:19.0629+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:19.066952+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":4}
{"time":"2025-07-28T22:06:19.066968+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25016,"unique_prefixes":5037,"success":5037,"duration_ms":277}
{"time":"2025-07-28T22:06:19.436596+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:19.436636+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:19.464446+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":27}
{"time":"2025-07-28T22:06:19.678563+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:19.678613+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:19.682977+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"80.249.210.89","peer_asn":"31042"}
{"time":"2025-07-28T22:06:19.691263+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":12}
{"time":"2025-07-28T22:06:19.899012+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"196.60.8.170","peer_asn":"327781"}
{"time":"2025-07-28T22:06:19.970392+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:19.970406+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:20.225584+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:20.26698+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":296}
{"time":"2025-07-28T22:06:20.267003+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:20.267039+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:20.285609+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":18}
{"time":"2025-07-28T22:06:20.285642+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:20.286756+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":1}
{"time":"2025-07-28T22:06:20.286767+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25060,"unique_prefixes":5413,"success":5413,"duration_ms":321}
{"time":"2025-07-28T22:06:21.006745+02:00","level":"WARN","msg":"BGP notification","source":"streamer.go:509","func":"streamer.(*Streamer).stream","peer":"80.81.192.113","peer_asn":"35320"}
{"time":"2025-07-28T22:06:21.026459+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"80.81.194.92","peer_asn":"57111"}
{"time":"2025-07-28T22:06:21.076441+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:21.076463+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:21.089448+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":12}
{"time":"2025-07-28T22:06:21.286262+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:21.286276+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:21.419527+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:21.483303+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":197}
{"time":"2025-07-28T22:06:21.483312+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:21.483321+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:21.486117+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":2}
{"time":"2025-07-28T22:06:21.486136+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:21.486628+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":0}
{"time":"2025-07-28T22:06:21.486634+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25009,"unique_prefixes":3834,"success":3834,"duration_ms":206}
{"time":"2025-07-28T22:06:21.933989+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:21.93402+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:21.953655+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":19}
{"time":"2025-07-28T22:06:22.32989+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:22.329911+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:22.587439+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":257}
{"time":"2025-07-28T22:06:22.587455+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:22.587459+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:22.589183+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":1}
{"time":"2025-07-28T22:06:22.589193+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25004,"unique_prefixes":7822,"success":7822,"duration_ms":275}
{"time":"2025-07-28T22:06:22.688939+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:22.688955+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:22.703874+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":14}
{"time":"2025-07-28T22:06:23.083139+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:23.083169+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:23.089052+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":5}
{"time":"2025-07-28T22:06:23.172741+02:00","level":"INFO","msg":"Stream statistics","source":"streamer.go:271","func":"streamer.(*Streamer).logMetrics","uptime":10001090292,"total_messages":59222,"total_bytes":46946947,"total_mb":"44.77","messages_per_sec":"520.18","bits_per_sec":"3453271","mbps":"3.45","total_dropped":0}
{"time":"2025-07-28T22:06:23.172764+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.ASHandler","index":0,"queue_len":0,"queue_cap":100000,"processed":58719,"dropped":0,"avg_time":48198,"min_time":0,"max_time":879332917}
{"time":"2025-07-28T22:06:23.172773+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PeerHandler","index":1,"queue_len":0,"queue_cap":100000,"processed":58719,"dropped":0,"avg_time":30805,"min_time":0,"max_time":847898542}
{"time":"2025-07-28T22:06:23.172779+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PrefixHandler","index":2,"queue_len":0,"queue_cap":100000,"processed":58719,"dropped":0,"avg_time":97181,"min_time":0,"max_time":1409291417}
{"time":"2025-07-28T22:06:23.172784+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PeeringHandler","index":3,"queue_len":0,"queue_cap":100000,"processed":58719,"dropped":0,"avg_time":1449,"min_time":0,"max_time":3400875}
{"time":"2025-07-28T22:06:23.68097+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:23.680993+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:24.005982+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":324}
{"time":"2025-07-28T22:06:24.005997+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:24.006001+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:24.006015+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:24.007342+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":1}
{"time":"2025-07-28T22:06:24.007354+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25002,"unique_prefixes":7208,"success":7208,"duration_ms":338}
{"time":"2025-07-28T22:06:24.007358+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:24.019125+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":11}
{"time":"2025-07-28T22:06:24.078938+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"186.211.128.34","peer_asn":"14840"}
{"time":"2025-07-28T22:06:24.083891+02:00","level":"WARN","msg":"BGP notification","source":"streamer.go:509","func":"streamer.(*Streamer).stream","peer":"186.211.128.34","peer_asn":"14840"}
2025/07/28 22:06:24 [akrotiri/CDxcs67wDd-000001] "GET http://127.0.0.1:8080/status HTTP/1.1" from 127.0.0.1:61543 - 200 14619B in 1.20975ms
{"time":"2025-07-28T22:06:24.557693+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:24.557712+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:24.767681+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":209}
{"time":"2025-07-28T22:06:24.767704+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:24.767708+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:24.769659+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":1}
{"time":"2025-07-28T22:06:24.769672+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25077,"unique_prefixes":4418,"success":4418,"duration_ms":217}
{"time":"2025-07-28T22:06:24.815657+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:24.815713+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:24.827843+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":12}
{"time":"2025-07-28T22:06:24.984444+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:24.984461+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:24.988723+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":4}
2025/07/28 22:06:25 [akrotiri/CDxcs67wDd-000002] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3740B in 841.889375ms
{"time":"2025-07-28T22:06:25.684819+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:25.684831+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
2025/07/28 22:06:25 [akrotiri/CDxcs67wDd-000003] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61563 - 200 3743B in 623.636208ms
{"time":"2025-07-28T22:06:25.90325+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":218}
{"time":"2025-07-28T22:06:26.124638+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:26.124669+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
2025/07/28 22:06:26 [akrotiri/CDxcs67wDd-000004] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3743B in 704.217125ms
{"time":"2025-07-28T22:06:26.630537+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:26.658544+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:27.254468+02:00","level":"ERROR","msg":"Database stats timeout","source":"handlers.go:249","func":"server.(*Server).setupRoutes.func1.(*Server).handleStats.1"}
2025/07/28 22:06:27 [akrotiri/CDxcs67wDd-000005] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61563 - 200 0B in 1.000163708s
2025/07/28 22:06:27 [akrotiri/CDxcs67wDd-000006] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3749B in 739.590167ms
2025/07/28 22:06:27 [akrotiri/CDxcs67wDd-000007] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3749B in 750.517625ms
{"time":"2025-07-28T22:06:28.224637+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":2099}
{"time":"2025-07-28T22:06:28.22465+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:28.224659+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
2025/07/28 22:06:28 [akrotiri/CDxcs67wDd-000008] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3750B in 704.245959ms
{"time":"2025-07-28T22:06:28.403945+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:28.403947+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":179}
{"time":"2025-07-28T22:06:28.40513+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:28.407526+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":3}
{"time":"2025-07-28T22:06:28.407547+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:28.422831+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":15}
{"time":"2025-07-28T22:06:28.422846+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25200,"unique_prefixes":12548,"success":12548,"duration_ms":2313}
{"time":"2025-07-28T22:06:28.422836+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:28.432887+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:28.46032+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":37}
{"time":"2025-07-28T22:06:28.46033+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:28.707645+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"2001:12f8::121","peer_asn":"42473"}
{"time":"2025-07-28T22:06:28.707669+02:00","level":"WARN","msg":"BGP notification","source":"streamer.go:509","func":"streamer.(*Streamer).stream","peer":"2001:12f8::121","peer_asn":"42473"}
{"time":"2025-07-28T22:06:28.708546+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:28.781295+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
2025/07/28 22:06:28 [akrotiri/CDxcs67wDd-000009] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3749B in 756.9745ms
2025/07/28 22:06:29 [akrotiri/CDxcs67wDd-000010] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3753B in 763.037166ms
{"time":"2025-07-28T22:06:29.611376+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"195.208.209.174","peer_asn":"25227"}
2025/07/28 22:06:29 [akrotiri/CDxcs67wDd-000011] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3753B in 678.081ms
{"time":"2025-07-28T22:06:29.914714+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:29.91471+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":1454}
{"time":"2025-07-28T22:06:29.914754+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:30.040249+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":125}
{"time":"2025-07-28T22:06:30.040259+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:30.041388+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:30.044274+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":4}
{"time":"2025-07-28T22:06:30.044295+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:30.055687+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":11}
{"time":"2025-07-28T22:06:30.055701+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25268,"unique_prefixes":8160,"success":8160,"duration_ms":1631}
{"time":"2025-07-28T22:06:30.055692+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:30.062939+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:30.08672+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":31}
{"time":"2025-07-28T22:06:30.086725+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
2025/07/28 22:06:30 [akrotiri/CDxcs67wDd-000012] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3748B in 664.597375ms
{"time":"2025-07-28T22:06:30.554231+02:00","level":"WARN","msg":"BGP notification","source":"streamer.go:509","func":"streamer.(*Streamer).stream","peer":"2400:c3e0::2","peer_asn":"142271"}
2025/07/28 22:06:30 [akrotiri/CDxcs67wDd-000013] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3747B in 668.257917ms
{"time":"2025-07-28T22:06:31.051869+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:31.107905+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
2025/07/28 22:06:31 [akrotiri/CDxcs67wDd-000014] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3753B in 670.752417ms
{"time":"2025-07-28T22:06:31.445361+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":1358}
{"time":"2025-07-28T22:06:31.445382+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:31.445371+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:31.568492+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":123}
{"time":"2025-07-28T22:06:31.568499+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:31.572955+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":4}
{"time":"2025-07-28T22:06:31.573023+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:31.588203+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":15}
{"time":"2025-07-28T22:06:31.588219+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25063,"unique_prefixes":7699,"success":7699,"duration_ms":1531}
{"time":"2025-07-28T22:06:31.594241+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:31.594251+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
2025/07/28 22:06:31 [akrotiri/CDxcs67wDd-000015] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3748B in 665.950958ms
2025/07/28 22:06:32 [akrotiri/CDxcs67wDd-000016] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3752B in 699.108458ms
{"time":"2025-07-28T22:06:32.503624+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:32.583552+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":989}
{"time":"2025-07-28T22:06:32.583561+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:32.58357+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:32.607824+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"2001:7f8:4::73ab:1","peer_asn":"29611"}
{"time":"2025-07-28T22:06:32.621747+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":38}
{"time":"2025-07-28T22:06:32.621759+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:32.636923+02:00","level":"WARN","msg":"BGP notification","source":"streamer.go:509","func":"streamer.(*Streamer).stream","peer":"2001:7f8::8b0e:0:1","peer_asn":"35598"}
{"time":"2025-07-28T22:06:32.639883+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":18}
{"time":"2025-07-28T22:06:32.639897+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25199,"unique_prefixes":5899,"success":5899,"duration_ms":1050}
{"time":"2025-07-28T22:06:32.64409+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:32.644099+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:32.657387+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"2001:7f8::8b0e:0:1","peer_asn":"35598"}
2025/07/28 22:06:32 [akrotiri/CDxcs67wDd-000017] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3752B in 693.084708ms
{"time":"2025-07-28T22:06:33.142504+02:00","level":"WARN","msg":"BGP notification","source":"streamer.go:509","func":"streamer.(*Streamer).stream","peer":"193.239.118.249","peer_asn":"41255"}
{"time":"2025-07-28T22:06:33.172321+02:00","level":"INFO","msg":"Stream statistics","source":"streamer.go:271","func":"streamer.(*Streamer).logMetrics","uptime":20000600042,"total_messages":106496,"total_bytes":82519386,"total_mb":"78.70","messages_per_sec":"1259.43","bits_per_sec":"7815951","mbps":"7.82","total_dropped":0}
{"time":"2025-07-28T22:06:33.172345+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.ASHandler","index":0,"queue_len":0,"queue_cap":100000,"processed":105543,"dropped":0,"avg_time":65635,"min_time":0,"max_time":1773980458}
{"time":"2025-07-28T22:06:33.172351+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PeerHandler","index":1,"queue_len":0,"queue_cap":100000,"processed":105543,"dropped":0,"avg_time":50226,"min_time":0,"max_time":1749405083}
{"time":"2025-07-28T22:06:33.172355+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PrefixHandler","index":2,"queue_len":3230,"queue_cap":100000,"processed":102312,"dropped":0,"avg_time":125064,"min_time":0,"max_time":2313097750}
{"time":"2025-07-28T22:06:33.172359+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PeeringHandler","index":3,"queue_len":0,"queue_cap":100000,"processed":105543,"dropped":0,"avg_time":1376,"min_time":0,"max_time":3400875}
2025/07/28 22:06:33 [akrotiri/CDxcs67wDd-000018] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3753B in 676.837125ms
{"time":"2025-07-28T22:06:33.445612+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":801}
{"time":"2025-07-28T22:06:33.445629+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:33.445633+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:33.451434+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":5}
{"time":"2025-07-28T22:06:33.451447+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25025,"unique_prefixes":3555,"success":3555,"duration_ms":810}
{"time":"2025-07-28T22:06:33.643095+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:33.64311+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
2025/07/28 22:06:33 [akrotiri/CDxcs67wDd-000019] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3751B in 659.737125ms
{"time":"2025-07-28T22:06:33.799753+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:33.800146+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:34.186838+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":543}
{"time":"2025-07-28T22:06:34.186852+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:34.186857+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:34.238562+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":51}
{"time":"2025-07-28T22:06:34.238627+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:34.25364+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":15}
{"time":"2025-07-28T22:06:34.253647+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:34.260047+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":6}
{"time":"2025-07-28T22:06:34.260062+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25002,"unique_prefixes":3570,"success":3570,"duration_ms":620}
2025/07/28 22:06:34 [akrotiri/CDxcs67wDd-000020] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3748B in 673.683667ms
2025/07/28 22:06:34 [akrotiri/CDxcs67wDd-000021] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3748B in 638.654375ms
{"time":"2025-07-28T22:06:35.059333+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:35.059351+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:35.077698+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":18}
2025/07/28 22:06:35 [akrotiri/CDxcs67wDd-000022] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3747B in 626.574458ms
{"time":"2025-07-28T22:06:35.391213+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:35.391227+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
2025/07/28 22:06:35 [akrotiri/CDxcs67wDd-000023] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3751B in 708.742958ms
{"time":"2025-07-28T22:06:35.994347+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":603}
{"time":"2025-07-28T22:06:35.994384+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:35.994389+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:36.019076+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":24}
{"time":"2025-07-28T22:06:36.019096+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25000,"unique_prefixes":3674,"success":3674,"duration_ms":631}
{"time":"2025-07-28T22:06:36.058931+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:36.058942+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:36.114023+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":55}
{"time":"2025-07-28T22:06:36.283261+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:36.283301+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:36.315099+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":31}
2025/07/28 22:06:36 [akrotiri/CDxcs67wDd-000024] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3748B in 695.195541ms
2025/07/28 22:06:36 [akrotiri/CDxcs67wDd-000025] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3748B in 642.154792ms
{"time":"2025-07-28T22:06:36.981772+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:36.981786+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:37.372859+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
2025/07/28 22:06:37 [akrotiri/CDxcs67wDd-000026] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3752B in 671.656291ms
{"time":"2025-07-28T22:06:37.738034+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":756}
{"time":"2025-07-28T22:06:37.738053+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:37.738198+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:37.825702+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":87}
{"time":"2025-07-28T22:06:37.825713+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:37.830704+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":4}
{"time":"2025-07-28T22:06:37.830718+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25031,"unique_prefixes":6184,"success":6184,"duration_ms":853}
2025/07/28 22:06:37 [akrotiri/CDxcs67wDd-000027] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3748B in 686.848125ms
2025/07/28 22:06:38 [akrotiri/CDxcs67wDd-000028] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3748B in 663.914625ms
{"time":"2025-07-28T22:06:38.421247+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:38.421259+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:38.426499+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:38.427686+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":6}
{"time":"2025-07-28T22:06:38.427699+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:38.697108+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
2025/07/28 22:06:38 [akrotiri/CDxcs67wDd-000029] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3754B in 663.779834ms
{"time":"2025-07-28T22:06:39.074229+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:39.07423+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":646}
{"time":"2025-07-28T22:06:39.074268+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:39.151976+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":77}
{"time":"2025-07-28T22:06:39.151984+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:39.156165+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":4}
{"time":"2025-07-28T22:06:39.156178+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25001,"unique_prefixes":4793,"success":4793,"duration_ms":734}
2025/07/28 22:06:39 [akrotiri/CDxcs67wDd-000030] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3750B in 671.421ms
2025/07/28 22:06:39 [akrotiri/CDxcs67wDd-000031] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3750B in 657.66825ms
{"time":"2025-07-28T22:06:39.909903+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:39.909918+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:39.913823+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:39.936372+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":26}
{"time":"2025-07-28T22:06:39.936376+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
2025/07/28 22:06:40 [akrotiri/CDxcs67wDd-000032] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3753B in 667.81825ms
{"time":"2025-07-28T22:06:40.505261+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"2001:7f8:1::a503:5598:1","peer_asn":"35598"}
{"time":"2025-07-28T22:06:40.507887+02:00","level":"WARN","msg":"BGP notification","source":"streamer.go:509","func":"streamer.(*Streamer).stream","peer":"2001:7f8:1::a503:5598:1","peer_asn":"35598"}
{"time":"2025-07-28T22:06:40.518215+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:40.725938+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:40.725932+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":789}
{"time":"2025-07-28T22:06:40.726004+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:40.793823+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":67}
{"time":"2025-07-28T22:06:40.793885+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:40.797454+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":3}
{"time":"2025-07-28T22:06:40.797468+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25004,"unique_prefixes":6371,"success":6371,"duration_ms":889}
2025/07/28 22:06:40 [akrotiri/CDxcs67wDd-000033] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3750B in 678.936292ms
{"time":"2025-07-28T22:06:40.982913+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:40.982925+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:41.002914+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":19}
{"time":"2025-07-28T22:06:41.006332+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:41.006343+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:41.264302+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"193.107.13.3","peer_asn":"47787"}
2025/07/28 22:06:41 [akrotiri/CDxcs67wDd-000034] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3751B in 667.160917ms
{"time":"2025-07-28T22:06:41.685222+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":678}
{"time":"2025-07-28T22:06:41.685238+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:41.685242+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:41.700169+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":14}
{"time":"2025-07-28T22:06:41.700182+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25007,"unique_prefixes":6032,"success":6032,"duration_ms":698}
2025/07/28 22:06:41 [akrotiri/CDxcs67wDd-000035] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3750B in 663.800209ms
{"time":"2025-07-28T22:06:42.140327+02:00","level":"WARN","msg":"BGP notification","source":"streamer.go:509","func":"streamer.(*Streamer).stream","peer":"2602:fa91:0:cccc::20","peer_asn":"53427"}
2025/07/28 22:06:42 [akrotiri/CDxcs67wDd-000036] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3750B in 631.061667ms
{"time":"2025-07-28T22:06:42.402348+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:42.402364+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:42.40629+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:42.468984+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":66}
{"time":"2025-07-28T22:06:42.468995+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
2025/07/28 22:06:42 [akrotiri/CDxcs67wDd-000037] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3753B in 666.842292ms
{"time":"2025-07-28T22:06:43.005973+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":536}
{"time":"2025-07-28T22:06:43.00599+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:43.005994+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:43.035138+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":29}
{"time":"2025-07-28T22:06:43.035155+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25013,"unique_prefixes":4692,"success":4692,"duration_ms":632}
{"time":"2025-07-28T22:06:43.153852+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:43.153866+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:43.172026+02:00","level":"INFO","msg":"Stream statistics","source":"streamer.go:271","func":"streamer.(*Streamer).logMetrics","uptime":30000240583,"total_messages":147456,"total_bytes":114242028,"total_mb":"108.95","messages_per_sec":"1718.23","bits_per_sec":"10600313","mbps":"10.60","total_dropped":0}
{"time":"2025-07-28T22:06:43.17206+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.ASHandler","index":0,"queue_len":0,"queue_cap":100000,"processed":146026,"dropped":0,"avg_time":57968,"min_time":0,"max_time":1773980458}
{"time":"2025-07-28T22:06:43.172066+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PeerHandler","index":1,"queue_len":114,"queue_cap":100000,"processed":145911,"dropped":0,"avg_time":41292,"min_time":0,"max_time":1749405083}
{"time":"2025-07-28T22:06:43.172071+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PrefixHandler","index":2,"queue_len":0,"queue_cap":100000,"processed":146026,"dropped":0,"avg_time":127884,"min_time":0,"max_time":2313097750}
{"time":"2025-07-28T22:06:43.172074+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PeeringHandler","index":3,"queue_len":0,"queue_cap":100000,"processed":146026,"dropped":0,"avg_time":1395,"min_time":0,"max_time":3400875}
{"time":"2025-07-28T22:06:43.209132+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":55}
2025/07/28 22:06:43 [akrotiri/CDxcs67wDd-000038] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3750B in 697.561667ms
{"time":"2025-07-28T22:06:43.507824+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:43.507839+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:43.519765+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
2025/07/28 22:06:43 [akrotiri/CDxcs67wDd-000039] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3755B in 675.506041ms
{"time":"2025-07-28T22:06:44.107355+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:44.107351+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":599}
{"time":"2025-07-28T22:06:44.107381+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:44.178056+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:44.178061+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":70}
{"time":"2025-07-28T22:06:44.18455+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":6}
{"time":"2025-07-28T22:06:44.184564+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25000,"unique_prefixes":5304,"success":5304,"duration_ms":682}
{"time":"2025-07-28T22:06:44.261332+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:44.261347+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:44.336495+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":75}
2025/07/28 22:06:44 [akrotiri/CDxcs67wDd-000040] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3750B in 686.964042ms
{"time":"2025-07-28T22:06:44.506943+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:44.506957+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:44.76795+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
2025/07/28 22:06:44 [akrotiri/CDxcs67wDd-000041] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3755B in 669.086667ms
{"time":"2025-07-28T22:06:44.992506+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"102.67.56.1","peer_asn":"328474"}
{"time":"2025-07-28T22:06:45.246659+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
2025/07/28 22:06:45 [akrotiri/CDxcs67wDd-000042] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3757B in 703.737417ms
{"time":"2025-07-28T22:06:45.891437+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"2001:12f8::221:238","peer_asn":"262317"}
2025/07/28 22:06:46 [akrotiri/CDxcs67wDd-000043] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3759B in 810.926291ms
2025/07/28 22:06:46 [akrotiri/CDxcs67wDd-000044] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3761B in 822.023333ms
{"time":"2025-07-28T22:06:46.533304+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":2026}
{"time":"2025-07-28T22:06:46.533323+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:46.533312+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:46.536197+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":2}
{"time":"2025-07-28T22:06:46.53625+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:46.538415+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:46.833591+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":297}
{"time":"2025-07-28T22:06:46.833673+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:46.834734+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:46.850144+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":16}
{"time":"2025-07-28T22:06:46.850151+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:46.850162+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25001,"unique_prefixes":7301,"success":7301,"duration_ms":2350}
{"time":"2025-07-28T22:06:46.853816+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":3}
{"time":"2025-07-28T22:06:46.853836+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:46.858759+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:46.908164+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":54}
{"time":"2025-07-28T22:06:46.908173+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:46.909393+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
2025/07/28 22:06:46 [akrotiri/CDxcs67wDd-000045] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3755B in 741.661416ms
{"time":"2025-07-28T22:06:47.228593+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
2025/07/28 22:06:47 [akrotiri/CDxcs67wDd-000046] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3760B in 784.205666ms
2025/07/28 22:06:48 [akrotiri/CDxcs67wDd-000047] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3760B in 858.748959ms
2025/07/28 22:06:48 [akrotiri/CDxcs67wDd-000048] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3762B in 922.35575ms
2025/07/28 22:06:48 [akrotiri/CDxcs67wDd-000049] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3762B in 717.44775ms
{"time":"2025-07-28T22:06:49.070689+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":2162}
{"time":"2025-07-28T22:06:49.07071+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:49.07069+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:49.262706+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:49.262703+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":191}
{"time":"2025-07-28T22:06:49.264146+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:49.268862+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":6}
{"time":"2025-07-28T22:06:49.268962+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:49.271413+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:49.275211+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":6}
{"time":"2025-07-28T22:06:49.275223+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:49.275227+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25022,"unique_prefixes":8451,"success":8451,"duration_ms":2423}
{"time":"2025-07-28T22:06:49.289595+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:49.334214+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":58}
{"time":"2025-07-28T22:06:49.334808+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:49.335476+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:49.336923+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":2}
{"time":"2025-07-28T22:06:49.33696+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:49.339465+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
2025/07/28 22:06:49 [akrotiri/CDxcs67wDd-000050] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3762B in 700.93825ms
2025/07/28 22:06:49 [akrotiri/CDxcs67wDd-000051] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3761B in 723.658584ms
2025/07/28 22:06:50 [akrotiri/CDxcs67wDd-000052] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3762B in 723.958167ms
2025/07/28 22:06:51 [akrotiri/CDxcs67wDd-000053] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3763B in 789.249666ms
{"time":"2025-07-28T22:06:51.345984+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:51.345981+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":2009}
{"time":"2025-07-28T22:06:51.346045+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
2025/07/28 22:06:51 [akrotiri/CDxcs67wDd-000054] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3763B in 729.796792ms
{"time":"2025-07-28T22:06:51.570378+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":224}
{"time":"2025-07-28T22:06:51.570384+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:51.571522+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:52.01069+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:52.010713+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":440}
{"time":"2025-07-28T22:06:52.012718+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:52.014759+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":4}
{"time":"2025-07-28T22:06:52.014771+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25000,"unique_prefixes":12177,"success":12177,"duration_ms":2738}
{"time":"2025-07-28T22:06:52.014789+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:52.027547+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:52.077048+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":62}
{"time":"2025-07-28T22:06:52.07706+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:52.078084+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:52.078855+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":1}
{"time":"2025-07-28T22:06:52.078905+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:52.080704+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
2025/07/28 22:06:52 [akrotiri/CDxcs67wDd-000055] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3764B in 761.892209ms
2025/07/28 22:06:52 [akrotiri/CDxcs67wDd-000056] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3763B in 748.435166ms
2025/07/28 22:06:53 [akrotiri/CDxcs67wDd-000057] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61563 - 200 3763B in 818.889875ms
{"time":"2025-07-28T22:06:53.171897+02:00","level":"INFO","msg":"Stream statistics","source":"streamer.go:271","func":"streamer.(*Streamer).logMetrics","uptime":40000054958,"total_messages":236070,"total_bytes":173546136,"total_mb":"165.51","messages_per_sec":"2394.38","bits_per_sec":"14348680","mbps":"14.35","total_dropped":0}
{"time":"2025-07-28T22:06:53.171939+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.ASHandler","index":0,"queue_len":49325,"queue_cap":100000,"processed":184824,"dropped":0,"avg_time":86734,"min_time":0,"max_time":2353906708}
{"time":"2025-07-28T22:06:53.171946+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PeerHandler","index":1,"queue_len":18240,"queue_cap":100000,"processed":215911,"dropped":0,"avg_time":60302,"min_time":0,"max_time":2671848958}
{"time":"2025-07-28T22:06:53.17195+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PrefixHandler","index":2,"queue_len":51257,"queue_cap":100000,"processed":182895,"dropped":0,"avg_time":146934,"min_time":0,"max_time":2738066542}
{"time":"2025-07-28T22:06:53.171953+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PeeringHandler","index":3,"queue_len":7,"queue_cap":100000,"processed":234145,"dropped":0,"avg_time":1348,"min_time":0,"max_time":3400875}
2025/07/28 22:06:53 [akrotiri/CDxcs67wDd-000058] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3763B in 908.789542ms
2025/07/28 22:06:53 [akrotiri/CDxcs67wDd-000059] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3763B in 636.898625ms
{"time":"2025-07-28T22:06:54.262311+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:54.262305+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":2183}
{"time":"2025-07-28T22:06:54.262387+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:54.553415+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":291}
{"time":"2025-07-28T22:06:54.553453+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:54.554658+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:54.559549+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":6}
{"time":"2025-07-28T22:06:54.559575+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:54.561501+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:54.573833+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":14}
{"time":"2025-07-28T22:06:54.573848+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25001,"unique_prefixes":12370,"success":12370,"duration_ms":2557}
{"time":"2025-07-28T22:06:54.573861+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
2025/07/28 22:06:54 [akrotiri/CDxcs67wDd-000060] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3762B in 830.803042ms
{"time":"2025-07-28T22:06:54.585075+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:54.630347+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":56}
{"time":"2025-07-28T22:06:54.630359+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:54.631495+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:54.632104+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":1}
{"time":"2025-07-28T22:06:54.632164+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:54.633981+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:54.707675+02:00","level":"WARN","msg":"BGP notification","source":"streamer.go:509","func":"streamer.(*Streamer).stream","peer":"198.32.160.113","peer_asn":"15547"}
2025/07/28 22:06:55 [akrotiri/CDxcs67wDd-000061] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61563 - 200 3763B in 955.094167ms
2025/07/28 22:06:55 [akrotiri/CDxcs67wDd-000062] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3763B in 843.890625ms
2025/07/28 22:06:55 [akrotiri/CDxcs67wDd-000063] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3763B in 660.594792ms
2025/07/28 22:06:56 [akrotiri/CDxcs67wDd-000064] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3763B in 757.125042ms
{"time":"2025-07-28T22:06:56.53063+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":1898}
{"time":"2025-07-28T22:06:56.530648+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:56.530637+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:56.851986+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":321}
{"time":"2025-07-28T22:06:56.852004+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:56.853359+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:56.856731+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":4}
{"time":"2025-07-28T22:06:56.856742+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:56.858701+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:56.859794+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":3}
{"time":"2025-07-28T22:06:56.859802+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25001,"unique_prefixes":10446,"success":10446,"duration_ms":2283}
{"time":"2025-07-28T22:06:56.859804+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:56.875979+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:56.937328+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":77}
{"time":"2025-07-28T22:06:56.937364+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:56.938878+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:56.939663+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":2}
{"time":"2025-07-28T22:06:56.939695+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:56.941705+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
2025/07/28 22:06:57 [akrotiri/CDxcs67wDd-000065] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61563 - 200 3763B in 878.294792ms
2025/07/28 22:06:57 [akrotiri/CDxcs67wDd-000066] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3763B in 780.700416ms
2025/07/28 22:06:57 [akrotiri/CDxcs67wDd-000067] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3763B in 653.111125ms
{"time":"2025-07-28T22:06:58.418225+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"2001:7f8:1::a503:1042:1","peer_asn":"31042"}
2025/07/28 22:06:58 [akrotiri/CDxcs67wDd-000068] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3762B in 699.209708ms
{"time":"2025-07-28T22:06:58.876076+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":1936}
{"time":"2025-07-28T22:06:58.876098+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:06:58.876102+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
2025/07/28 22:06:58 [akrotiri/CDxcs67wDd-000069] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61563 - 200 3762B in 672.349791ms
{"time":"2025-07-28T22:06:59.006173+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"2001:de8:4::1:8403:1","peer_asn":"18403"}
{"time":"2025-07-28T22:06:59.139374+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:59.139376+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":263}
{"time":"2025-07-28T22:06:59.139407+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25007,"unique_prefixes":12611,"success":12611,"duration_ms":2276}
{"time":"2025-07-28T22:06:59.155793+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:59.22431+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:06:59.224311+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":84}
{"time":"2025-07-28T22:06:59.225611+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:06:59.226273+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:06:59.226276+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":1}
{"time":"2025-07-28T22:06:59.227775+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
2025/07/28 22:06:59 [akrotiri/CDxcs67wDd-000070] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3763B in 832.9905ms
2025/07/28 22:07:00 [akrotiri/CDxcs67wDd-000071] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3767B in 925.902083ms
2025/07/28 22:07:00 [akrotiri/CDxcs67wDd-000072] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3773B in 828.523333ms
2025/07/28 22:07:01 [akrotiri/CDxcs67wDd-000073] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3775B in 969.562875ms
2025/07/28 22:07:01 [akrotiri/CDxcs67wDd-000074] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3775B in 928.70175ms
{"time":"2025-07-28T22:07:01.857903+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:01.857902+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":2631}
{"time":"2025-07-28T22:07:01.857956+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:07:01.906676+02:00","level":"WARN","msg":"BGP notification","source":"streamer.go:509","func":"streamer.(*Streamer).stream","peer":"103.200.113.1","peer_asn":"131477"}
{"time":"2025-07-28T22:07:02.118761+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"2001:43f8:6d0::60","peer_asn":"37468"}
{"time":"2025-07-28T22:07:02.234542+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:02.234542+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":376}
{"time":"2025-07-28T22:07:02.236452+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:02.239925+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:07:02.239938+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":5}
{"time":"2025-07-28T22:07:02.242231+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:02.246023+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:02.246031+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":6}
{"time":"2025-07-28T22:07:02.24604+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25009,"unique_prefixes":11965,"success":11965,"duration_ms":3104}
{"time":"2025-07-28T22:07:02.260574+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:07:02.320905+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:02.320991+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":74}
{"time":"2025-07-28T22:07:02.322112+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:02.324538+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:07:02.324562+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":3}
{"time":"2025-07-28T22:07:02.326403+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
2025/07/28 22:07:02 [akrotiri/CDxcs67wDd-000075] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3774B in 956.63075ms
{"time":"2025-07-28T22:07:02.906462+02:00","level":"WARN","msg":"Failed to get live route counts","source":"handlers.go:271","func":"server.(*Server).setupRoutes.func1.(*Server).handleStats.1","error":"failed to count IPv4 routes: context deadline exceeded"}
2025/07/28 22:07:02 [akrotiri/CDxcs67wDd-000076] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61568 - 200 3765B in 1.010923375s
{"time":"2025-07-28T22:07:03.172417+02:00","level":"INFO","msg":"Stream statistics","source":"streamer.go:271","func":"streamer.(*Streamer).logMetrics","uptime":50000497625,"total_messages":359424,"total_bytes":257032534,"total_mb":"245.13","messages_per_sec":"3576.23","bits_per_sec":"20396734","mbps":"20.40","total_dropped":69356}
{"time":"2025-07-28T22:07:03.172535+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.ASHandler","index":0,"queue_len":100000,"queue_cap":100000,"processed":215623,"dropped":41454,"avg_time":121843,"min_time":0,"max_time":3009525458}
{"time":"2025-07-28T22:07:03.172543+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PeerHandler","index":1,"queue_len":71166,"queue_cap":100000,"processed":285911,"dropped":0,"avg_time":81360,"min_time":0,"max_time":3012558583}
{"time":"2025-07-28T22:07:03.172548+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PrefixHandler","index":2,"queue_len":100000,"queue_cap":100000,"processed":229175,"dropped":27902,"avg_time":161892,"min_time":0,"max_time":3104266083}
{"time":"2025-07-28T22:07:03.172552+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PeeringHandler","index":3,"queue_len":0,"queue_cap":100000,"processed":357078,"dropped":0,"avg_time":1541,"min_time":0,"max_time":13416875}
2025/07/28 22:07:03 [akrotiri/CDxcs67wDd-000077] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61563 - 200 3777B in 939.839417ms
2025/07/28 22:07:03 [akrotiri/CDxcs67wDd-000078] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3778B in 877.470541ms
2025/07/28 22:07:04 [akrotiri/CDxcs67wDd-000079] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3778B in 874.863ms
{"time":"2025-07-28T22:07:04.336724+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"196.60.8.60","peer_asn":"37468"}
{"time":"2025-07-28T22:07:04.338639+02:00","level":"WARN","msg":"BGP notification","source":"streamer.go:509","func":"streamer.(*Streamer).stream","peer":"196.60.8.60","peer_asn":"37468"}
2025/07/28 22:07:04 [akrotiri/CDxcs67wDd-000080] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3776B in 835.631334ms
{"time":"2025-07-28T22:07:04.957401+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:04.957402+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":2632}
{"time":"2025-07-28T22:07:04.957482+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:07:05.303358+02:00","level":"WARN","msg":"Failed to get live route counts","source":"handlers.go:271","func":"server.(*Server).setupRoutes.func1.(*Server).handleStats.1","error":"failed to count IPv6 routes: context deadline exceeded"}
2025/07/28 22:07:05 [akrotiri/CDxcs67wDd-000081] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3769B in 1.000867334s
{"time":"2025-07-28T22:07:05.343269+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:05.343283+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":385}
{"time":"2025-07-28T22:07:05.345223+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:07:05.345259+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":1}
{"time":"2025-07-28T22:07:05.345564+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:05.346992+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:05.359816+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":14}
{"time":"2025-07-28T22:07:05.359833+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25000,"unique_prefixes":12724,"success":12724,"duration_ms":3111}
{"time":"2025-07-28T22:07:05.359841+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:05.378899+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:07:05.468416+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":108}
{"time":"2025-07-28T22:07:05.468427+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:05.469748+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:05.46996+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":1}
{"time":"2025-07-28T22:07:05.469987+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:07:05.471944+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:05.806114+02:00","level":"WARN","msg":"Failed to get live route counts","source":"handlers.go:271","func":"server.(*Server).setupRoutes.func1.(*Server).handleStats.1","error":"failed to count IPv6 routes: context deadline exceeded"}
2025/07/28 22:07:05 [akrotiri/CDxcs67wDd-000082] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3766B in 1.000603209s
2025/07/28 22:07:06 [akrotiri/CDxcs67wDd-000083] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61563 - 200 3778B in 878.315917ms
2025/07/28 22:07:06 [akrotiri/CDxcs67wDd-000084] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3779B in 801.562959ms
{"time":"2025-07-28T22:07:06.674009+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"80.81.194.190","peer_asn":"31042"}
2025/07/28 22:07:07 [akrotiri/CDxcs67wDd-000085] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3781B in 839.2745ms
{"time":"2025-07-28T22:07:07.637638+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:07.637634+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":2167}
{"time":"2025-07-28T22:07:07.63771+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
2025/07/28 22:07:07 [akrotiri/CDxcs67wDd-000086] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3781B in 828.468084ms
{"time":"2025-07-28T22:07:07.931037+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":293}
{"time":"2025-07-28T22:07:07.931148+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:07.934027+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:07.937872+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":6}
{"time":"2025-07-28T22:07:07.937902+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:07:07.94102+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:07.943393+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":5}
{"time":"2025-07-28T22:07:07.943405+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25009,"unique_prefixes":10456,"success":10456,"duration_ms":2580}
{"time":"2025-07-28T22:07:07.943426+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:07.959086+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:07:08.010262+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":66}
{"time":"2025-07-28T22:07:08.010282+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:08.012005+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":1}
{"time":"2025-07-28T22:07:08.012034+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:07:08.012205+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:08.015222+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
2025/07/28 22:07:08 [akrotiri/CDxcs67wDd-000087] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61563 - 200 3779B in 810.253292ms
2025/07/28 22:07:08 [akrotiri/CDxcs67wDd-000088] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3779B in 864.130166ms
2025/07/28 22:07:09 [akrotiri/CDxcs67wDd-000089] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3780B in 834.624666ms
2025/07/28 22:07:09 [akrotiri/CDxcs67wDd-000090] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3781B in 709.624708ms
{"time":"2025-07-28T22:07:10.01452+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:10.01452+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":2002}
{"time":"2025-07-28T22:07:10.014601+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
2025/07/28 22:07:10 [akrotiri/CDxcs67wDd-000091] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3782B in 808.166ms
{"time":"2025-07-28T22:07:10.287222+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:10.287218+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":272}
{"time":"2025-07-28T22:07:10.288314+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:10.289105+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":1}
{"time":"2025-07-28T22:07:10.289116+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:07:10.290657+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:10.305809+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":16}
{"time":"2025-07-28T22:07:10.305828+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:10.305832+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25000,"unique_prefixes":11138,"success":11138,"duration_ms":2360}
{"time":"2025-07-28T22:07:10.321073+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:07:10.346572+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"80.81.194.204","peer_asn":"48166"}
{"time":"2025-07-28T22:07:10.358916+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":53}
{"time":"2025-07-28T22:07:10.358927+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:10.359936+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:10.360355+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":1}
{"time":"2025-07-28T22:07:10.36039+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:07:10.362102+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:10.517511+02:00","level":"DEBUG","msg":"System stats","source":"cli.go:40","func":"routewatch.logDebugStats","goroutines":37,"alloc_mb":253,"total_alloc_mb":1909,"sys_mb":434,"num_gc":46,"heap_alloc_mb":253,"heap_sys_mb":412,"heap_idle_mb":109,"heap_inuse_mb":303,"heap_released_mb":4,"stack_inuse_mb":3}
2025/07/28 22:07:10 [akrotiri/CDxcs67wDd-000092] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3778B in 896.543791ms
2025/07/28 22:07:11 [akrotiri/CDxcs67wDd-000093] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3779B in 729.286083ms
2025/07/28 22:07:11 [akrotiri/CDxcs67wDd-000094] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3780B in 732.767625ms
2025/07/28 22:07:12 [akrotiri/CDxcs67wDd-000095] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3781B in 836.217334ms
2025/07/28 22:07:12 [akrotiri/CDxcs67wDd-000096] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3781B in 661.503417ms
{"time":"2025-07-28T22:07:12.989753+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"2001:7f8::7942:0:1","peer_asn":"31042"}
2025/07/28 22:07:13 [akrotiri/CDxcs67wDd-000097] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3781B in 734.383792ms
{"time":"2025-07-28T22:07:13.172111+02:00","level":"INFO","msg":"Stream statistics","source":"streamer.go:271","func":"streamer.(*Streamer).logMetrics","uptime":60000150292,"total_messages":498039,"total_bytes":350905557,"total_mb":"334.65","messages_per_sec":"5258.80","bits_per_sec":"29343421","mbps":"29.34","total_dropped":331660}
{"time":"2025-07-28T22:07:13.172128+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.ASHandler","index":0,"queue_len":100000,"queue_cap":100000,"processed":242219,"dropped":152977,"avg_time":141638,"min_time":0,"max_time":3021689916}
{"time":"2025-07-28T22:07:13.172134+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PeerHandler","index":1,"queue_len":100000,"queue_cap":100000,"processed":345911,"dropped":49285,"avg_time":90466,"min_time":0,"max_time":3019238458}
{"time":"2025-07-28T22:07:13.172137+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PrefixHandler","index":2,"queue_len":100000,"queue_cap":100000,"processed":265798,"dropped":129398,"avg_time":169899,"min_time":0,"max_time":3111744000}
{"time":"2025-07-28T22:07:13.172142+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PeeringHandler","index":3,"queue_len":0,"queue_cap":100000,"processed":495197,"dropped":0,"avg_time":1558,"min_time":0,"max_time":13416875}
{"time":"2025-07-28T22:07:13.333656+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:13.333656+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":2973}
{"time":"2025-07-28T22:07:13.33369+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
2025/07/28 22:07:13 [akrotiri/CDxcs67wDd-000098] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3783B in 700.670583ms
{"time":"2025-07-28T22:07:13.639137+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":305}
{"time":"2025-07-28T22:07:13.639147+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:13.640319+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:13.644871+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":5}
{"time":"2025-07-28T22:07:13.644905+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:07:13.647654+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:13.649036+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":4}
{"time":"2025-07-28T22:07:13.649047+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25000,"unique_prefixes":14401,"success":14401,"duration_ms":3341}
{"time":"2025-07-28T22:07:13.649057+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:13.657595+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:07:13.702912+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:13.702911+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":53}
{"time":"2025-07-28T22:07:13.703676+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":0}
{"time":"2025-07-28T22:07:13.703729+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:07:13.704234+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:13.705377+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
2025/07/28 22:07:14 [akrotiri/CDxcs67wDd-000099] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3781B in 725.974541ms
2025/07/28 22:07:14 [akrotiri/CDxcs67wDd-000100] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3781B in 713.777ms
{"time":"2025-07-28T22:07:14.838155+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:14.83816+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":1134}
{"time":"2025-07-28T22:07:14.838379+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:07:15.077597+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":239}
{"time":"2025-07-28T22:07:15.077628+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:15.078786+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:15.082773+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":5}
{"time":"2025-07-28T22:07:15.082812+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:07:15.084499+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
2025/07/28 22:07:15 [akrotiri/CDxcs67wDd-000101] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3781B in 720.285125ms
{"time":"2025-07-28T22:07:15.092807+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":10}
{"time":"2025-07-28T22:07:15.092819+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25001,"unique_prefixes":4903,"success":4903,"duration_ms":1442}
{"time":"2025-07-28T22:07:15.09282+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:15.110434+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:07:15.163984+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":71}
{"time":"2025-07-28T22:07:15.163997+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:15.165144+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:15.165716+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":1}
{"time":"2025-07-28T22:07:15.165737+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:07:15.167331+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:15.468158+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"2001:b18:10::3","peer_asn":"47787"}
2025/07/28 22:07:15 [akrotiri/CDxcs67wDd-000102] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3779B in 856.589042ms
2025/07/28 22:07:16 [akrotiri/CDxcs67wDd-000103] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3780B in 757.343208ms
2025/07/28 22:07:16 [akrotiri/CDxcs67wDd-000104] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3781B in 730.44575ms
2025/07/28 22:07:17 [akrotiri/CDxcs67wDd-000105] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3781B in 733.7785ms
{"time":"2025-07-28T22:07:17.542673+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":2376}
{"time":"2025-07-28T22:07:17.542696+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:07:17.542711+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
2025/07/28 22:07:17 [akrotiri/CDxcs67wDd-000106] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3781B in 682.142834ms
{"time":"2025-07-28T22:07:18.223171+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":680}
{"time":"2025-07-28T22:07:18.223196+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:18.225991+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:18.234377+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":11}
{"time":"2025-07-28T22:07:18.234574+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:07:18.237769+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:18.238983+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:18.238985+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":4}
{"time":"2025-07-28T22:07:18.239009+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25000,"unique_prefixes":13578,"success":13578,"duration_ms":3143}
{"time":"2025-07-28T22:07:18.256265+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:07:18.320259+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":81}
{"time":"2025-07-28T22:07:18.320267+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:18.321115+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":0}
{"time":"2025-07-28T22:07:18.321157+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:07:18.32123+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:18.323214+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:18.379602+02:00","level":"WARN","msg":"Failed to get live route counts","source":"handlers.go:271","func":"server.(*Server).setupRoutes.func1.(*Server).handleStats.1","error":"failed to count IPv4 routes: context deadline exceeded"}
2025/07/28 22:07:18 [akrotiri/CDxcs67wDd-000107] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3769B in 1.001144583s
{"time":"2025-07-28T22:07:18.882464+02:00","level":"WARN","msg":"Failed to get live route counts","source":"handlers.go:271","func":"server.(*Server).setupRoutes.func1.(*Server).handleStats.1","error":"failed to count IPv4 routes: context deadline exceeded"}
2025/07/28 22:07:18 [akrotiri/CDxcs67wDd-000108] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3769B in 1.00075575s
2025/07/28 22:07:19 [akrotiri/CDxcs67wDd-000109] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3782B in 757.815542ms
{"time":"2025-07-28T22:07:19.381612+02:00","level":"WARN","msg":"BGP notification","source":"streamer.go:509","func":"streamer.(*Streamer).stream","peer":"195.208.208.187","peer_asn":"9002"}
2025/07/28 22:07:19 [akrotiri/CDxcs67wDd-000110] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3784B in 697.442083ms
2025/07/28 22:07:20 [akrotiri/CDxcs67wDd-000111] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3784B in 677.413417ms
{"time":"2025-07-28T22:07:20.29597+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:20.295967+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":1974}
{"time":"2025-07-28T22:07:20.295998+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
2025/07/28 22:07:20 [akrotiri/CDxcs67wDd-000112] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3784B in 724.694334ms
{"time":"2025-07-28T22:07:20.625237+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":329}
{"time":"2025-07-28T22:07:20.625255+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:20.62666+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:20.632501+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":7}
{"time":"2025-07-28T22:07:20.632576+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:07:20.636547+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:20.640065+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":7}
{"time":"2025-07-28T22:07:20.640083+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25045,"unique_prefixes":11471,"success":11471,"duration_ms":2398}
{"time":"2025-07-28T22:07:20.640082+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:20.653597+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:07:20.666295+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"27.111.229.239","peer_asn":"17639"}
{"time":"2025-07-28T22:07:20.688306+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":48}
{"time":"2025-07-28T22:07:20.688317+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:20.689718+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:20.689887+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":1}
{"time":"2025-07-28T22:07:20.689912+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:07:20.691608+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:21.18074+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"217.29.66.232","peer_asn":"54104"}
2025/07/28 22:07:21 [akrotiri/CDxcs67wDd-000113] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3781B in 881.200125ms
2025/07/28 22:07:21 [akrotiri/CDxcs67wDd-000114] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3781B in 838.895125ms
{"time":"2025-07-28T22:07:22.056506+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"196.60.8.170","peer_asn":"327781"}
2025/07/28 22:07:22 [akrotiri/CDxcs67wDd-000115] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3783B in 605.655833ms
2025/07/28 22:07:22 [akrotiri/CDxcs67wDd-000116] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3783B in 725.924291ms
{"time":"2025-07-28T22:07:22.790165+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:22.790161+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":2100}
{"time":"2025-07-28T22:07:22.790194+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:07:23.075685+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:23.075683+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":285}
{"time":"2025-07-28T22:07:23.077533+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:23.080427+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":4}
{"time":"2025-07-28T22:07:23.080438+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:07:23.082943+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:23.085914+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":5}
{"time":"2025-07-28T22:07:23.085924+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25000,"unique_prefixes":10722,"success":10722,"duration_ms":2443}
{"time":"2025-07-28T22:07:23.085946+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:23.104499+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:07:23.161087+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":75}
{"time":"2025-07-28T22:07:23.161096+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:23.162217+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:23.162633+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":1}
{"time":"2025-07-28T22:07:23.162677+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:07:23.164719+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:23.173082+02:00","level":"INFO","msg":"Stream statistics","source":"streamer.go:271","func":"streamer.(*Streamer).logMetrics","uptime":70001058208,"total_messages":582656,"total_bytes":412603415,"total_mb":"393.49","messages_per_sec":"6118.41","bits_per_sec":"34222236","mbps":"34.22","total_dropped":427933}
{"time":"2025-07-28T22:07:23.173096+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.ASHandler","index":0,"queue_len":91615,"queue_cap":100000,"processed":287368,"dropped":200349,"avg_time":163924,"min_time":0,"max_time":3279691916}
{"time":"2025-07-28T22:07:23.1731+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PeerHandler","index":1,"queue_len":79690,"queue_cap":100000,"processed":445911,"dropped":53731,"avg_time":98873,"min_time":0,"max_time":3283178708}
{"time":"2025-07-28T22:07:23.173104+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PrefixHandler","index":2,"queue_len":90107,"queue_cap":100000,"processed":315372,"dropped":173853,"avg_time":183704,"min_time":0,"max_time":3341028125}
{"time":"2025-07-28T22:07:23.173107+02:00","level":"INFO","msg":"Handler statistics","source":"streamer.go:304","func":"streamer.(*Streamer).logMetrics","handler":"*routewatch.PeeringHandler","index":3,"queue_len":0,"queue_cap":100000,"processed":579333,"dropped":0,"avg_time":1515,"min_time":0,"max_time":13416875}
2025/07/28 22:07:23 [akrotiri/CDxcs67wDd-000117] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3782B in 840.749375ms
2025/07/28 22:07:23 [akrotiri/CDxcs67wDd-000118] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3784B in 738.02125ms
2025/07/28 22:07:24 [akrotiri/CDxcs67wDd-000119] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3785B in 721.498875ms
{"time":"2025-07-28T22:07:24.610404+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"80.249.210.89","peer_asn":"31042"}
2025/07/28 22:07:24 [akrotiri/CDxcs67wDd-000120] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3785B in 722.397541ms
{"time":"2025-07-28T22:07:25.034457+02:00","level":"INFO","msg":"BGP session opened","source":"streamer.go:501","func":"streamer.(*Streamer).stream","peer":"80.81.194.92","peer_asn":"57111"}
{"time":"2025-07-28T22:07:25.036308+02:00","level":"WARN","msg":"BGP notification","source":"streamer.go:509","func":"streamer.(*Streamer).stream","peer":"80.81.192.113","peer_asn":"35320"}
2025/07/28 22:07:25 [akrotiri/CDxcs67wDd-000121] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3785B in 667.370375ms
{"time":"2025-07-28T22:07:25.244224+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:25.24422+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpsertLiveRouteBatch (database.go:181)","duration_ms":2081}
{"time":"2025-07-28T22:07:25.244252+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:07:25.53672+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:25.53672+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":292}
{"time":"2025-07-28T22:07:25.538192+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:25.541033+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":4}
{"time":"2025-07-28T22:07:25.541041+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"DeleteLiveRouteBatch","caller":"database.go:262"}
{"time":"2025-07-28T22:07:25.542603+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:25.554606+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"DeleteLiveRouteBatch (database.go:262)","duration_ms":13}
{"time":"2025-07-28T22:07:25.554624+02:00","level":"DEBUG","msg":"Flushed prefix batch","source":"prefixhandler.go:223","func":"routewatch.(*PrefixHandler).flushBatchLocked","batch_size":25000,"unique_prefixes":14210,"success":14210,"duration_ms":2466}
{"time":"2025-07-28T22:07:25.554648+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:25.575785+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:07:25.619578+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"GetOrCreateASNBatch (database.go:332)","duration_ms":64}
{"time":"2025-07-28T22:07:25.619599+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
{"time":"2025-07-28T22:07:25.621313+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"GetOrCreateASNBatch","caller":"database.go:332"}
{"time":"2025-07-28T22:07:25.62235+02:00","level":"DEBUG","msg":"Database lock released","source":"database.go:162","func":"database.(*Database).unlock","held_by":"UpdatePeerBatch (database.go:640)","duration_ms":2}
{"time":"2025-07-28T22:07:25.622393+02:00","level":"DEBUG","msg":"Database lock acquired","source":"database.go:150","func":"database.(*Database).lock","operation":"UpsertLiveRouteBatch","caller":"database.go:181"}
{"time":"2025-07-28T22:07:25.624641+02:00","level":"DEBUG","msg":"Acquiring database lock","source":"database.go:144","func":"database.(*Database).lock","operation":"UpdatePeerBatch","caller":"database.go:640"}
2025/07/28 22:07:25 [akrotiri/CDxcs67wDd-000122] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3783B in 860.813ms
2025/07/28 22:07:26 [akrotiri/CDxcs67wDd-000123] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3783B in 789.487167ms
2025/07/28 22:07:26 [akrotiri/CDxcs67wDd-000124] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:62108 - 200 3783B in 723.284542ms
2025/07/28 22:07:27 [akrotiri/CDxcs67wDd-000125] "GET http://127.0.0.1:8080/api/v1/stats HTTP/1.1" from 127.0.0.1:61543 - 200 3783B in 684.08625ms
Reference in New Issue View Git Blame Copy Permalink