{"msg":"Opened existing region file \"/var/tmp/test_repair/8820/region.json\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:56.972032862Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Database read version 1","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:56.972746634Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Database write version 1","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:56.972772091Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"UUID: 12345678-0000-0000-0000-000000008820","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:56.976098546Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Blocks per extent:20 Total Extents: 30","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:56.976128892Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Crucible Version: Crucible Version: 0.0.1\nCommit SHA: f72bfdb36aa576722b377a737f26a9b5ef54faf2\nCommit timestamp: 2025-03-17T21:58:36.000000000Z  branch: renovate/tempfile-3.x-lockfile\nrustc: 1.84.0 stable x86_64-unknown-illumos\nCargo: x86_64-unknown-illumos  Debug: true Opt level: 0","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.01940023Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982,"task":"main"}
{"msg":"Upstairs <-> Downstairs Message Version: 13","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.019468323Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982,"task":"main"}
{"msg":"Repair listens on 0.0.0.0:12820 for path:\"/var/tmp/test_repair/8820\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.020930553Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982,"task":"repair"}
{"msg":"listening","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.02131706Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982,"local_addr":"0.0.0.0:12820","task":"repair"}
{"msg":"Using repair address: 0.0.0.0:12820","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.021539136Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982,"task":"main"}
{"msg":"No SSL acceptor configured","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.021564933Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982,"task":"main"}
{"msg":"downstairs listening on 0.0.0.0:8820","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.021662472Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982,"task":"main"}
{"msg":"accepted connection from 127.0.0.1:59414","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.021697178Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982,"task":"main"}
{"msg":"connection (127.0.0.1:59414): tasks spawned","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.021849341Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982,"id":"0"}
{"msg":"Connection request from 3d7d1ca1-ca85-440c-8a91-a91958a37c51 with version 13","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.022095334Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"upstairs UpstairsConnection { upstairs_id: 3d7d1ca1-ca85-440c-8a91-a91958a37c51, session_id: 35dca443-2f1b-4526-b24b-8fea4248c3b8, gen: 199 } connected, version 13","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.022121552Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"UpstairsConnection { upstairs_id: 3d7d1ca1-ca85-440c-8a91-a91958a37c51, session_id: 35dca443-2f1b-4526-b24b-8fea4248c3b8, gen: 199 } is now active (read-write)","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.02249778Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Current flush_numbers [0..12]: [330, 330, 331, 322, 330, 326, 285, 318, 330, 329, 329, 313]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.023124041Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Created copy dir \"/var/tmp/test_repair/8820/00/000/000.copy\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.024352147Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"eid:0 Found repair files: [\"000\"]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.025993917Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Verify extent 0 still ready for copy","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.027959201Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"1 repair files downloaded, move directory \"/var/tmp/test_repair/8820/00/000/000.copy\" to \"/var/tmp/test_repair/8820/00/000/000.replace\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.028469654Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Copy files from \"/var/tmp/test_repair/8820/00/000/000.replace\" in \"/var/tmp/test_repair/8820/00/000\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.02851428Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Move directory  \"/var/tmp/test_repair/8820/00/000/000.replace\" to \"/var/tmp/test_repair/8820/00/000/000.completed\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.028656814Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Created copy dir \"/var/tmp/test_repair/8820/00/000/012.copy\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.029983668Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"eid:18 Found repair files: [\"012\"]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.030503531Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Verify extent 18 still ready for copy","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.032220423Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"1 repair files downloaded, move directory \"/var/tmp/test_repair/8820/00/000/012.copy\" to \"/var/tmp/test_repair/8820/00/000/012.replace\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.032816287Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Copy files from \"/var/tmp/test_repair/8820/00/000/012.replace\" in \"/var/tmp/test_repair/8820/00/000\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.032848914Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Move directory  \"/var/tmp/test_repair/8820/00/000/012.replace\" to \"/var/tmp/test_repair/8820/00/000/012.completed\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.033020435Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Created copy dir \"/var/tmp/test_repair/8820/00/000/009.copy\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.034400863Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"eid:9 Found repair files: [\"009\"]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.034937214Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Verify extent 9 still ready for copy","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.036719349Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"1 repair files downloaded, move directory \"/var/tmp/test_repair/8820/00/000/009.copy\" to \"/var/tmp/test_repair/8820/00/000/009.replace\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.037277797Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Copy files from \"/var/tmp/test_repair/8820/00/000/009.replace\" in \"/var/tmp/test_repair/8820/00/000\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.037311983Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Move directory  \"/var/tmp/test_repair/8820/00/000/009.replace\" to \"/var/tmp/test_repair/8820/00/000/009.completed\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.037445759Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Created copy dir \"/var/tmp/test_repair/8820/00/000/008.copy\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.038768794Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"eid:8 Found repair files: [\"008\"]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.039227663Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Verify extent 8 still ready for copy","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.040818189Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"1 repair files downloaded, move directory \"/var/tmp/test_repair/8820/00/000/008.copy\" to \"/var/tmp/test_repair/8820/00/000/008.replace\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.041325053Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Copy files from \"/var/tmp/test_repair/8820/00/000/008.replace\" in \"/var/tmp/test_repair/8820/00/000\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.041382776Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Move directory  \"/var/tmp/test_repair/8820/00/000/008.replace\" to \"/var/tmp/test_repair/8820/00/000/008.completed\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.041540029Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Created copy dir \"/var/tmp/test_repair/8820/00/000/00D.copy\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.043224684Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"eid:13 Found repair files: [\"00D\"]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.043737628Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Verify extent 13 still ready for copy","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.045469698Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"1 repair files downloaded, move directory \"/var/tmp/test_repair/8820/00/000/00D.copy\" to \"/var/tmp/test_repair/8820/00/000/00D.replace\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.045990011Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Copy files from \"/var/tmp/test_repair/8820/00/000/00D.replace\" in \"/var/tmp/test_repair/8820/00/000\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.046031656Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Move directory  \"/var/tmp/test_repair/8820/00/000/00D.replace\" to \"/var/tmp/test_repair/8820/00/000/00D.completed\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.046160452Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Created copy dir \"/var/tmp/test_repair/8820/00/000/01D.copy\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.047671596Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"eid:29 Found repair files: [\"01D\"]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.048395017Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Verify extent 29 still ready for copy","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.05008818Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"1 repair files downloaded, move directory \"/var/tmp/test_repair/8820/00/000/01D.copy\" to \"/var/tmp/test_repair/8820/00/000/01D.replace\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.050586306Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Copy files from \"/var/tmp/test_repair/8820/00/000/01D.replace\" in \"/var/tmp/test_repair/8820/00/000\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.050620572Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Move directory  \"/var/tmp/test_repair/8820/00/000/01D.replace\" to \"/var/tmp/test_repair/8820/00/000/01D.completed\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.050764656Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Created copy dir \"/var/tmp/test_repair/8820/00/000/014.copy\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.052070323Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"eid:20 Found repair files: [\"014\"]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.052579987Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Verify extent 20 still ready for copy","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.054315346Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"1 repair files downloaded, move directory \"/var/tmp/test_repair/8820/00/000/014.copy\" to \"/var/tmp/test_repair/8820/00/000/014.replace\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.054850218Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Copy files from \"/var/tmp/test_repair/8820/00/000/014.replace\" in \"/var/tmp/test_repair/8820/00/000\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.054884014Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Move directory  \"/var/tmp/test_repair/8820/00/000/014.replace\" to \"/var/tmp/test_repair/8820/00/000/014.completed\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.055022449Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Created copy dir \"/var/tmp/test_repair/8820/00/000/01C.copy\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.056334894Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"eid:28 Found repair files: [\"01C\"]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.057031108Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Verify extent 28 still ready for copy","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.058712913Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"1 repair files downloaded, move directory \"/var/tmp/test_repair/8820/00/000/01C.copy\" to \"/var/tmp/test_repair/8820/00/000/01C.replace\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.059274022Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Copy files from \"/var/tmp/test_repair/8820/00/000/01C.replace\" in \"/var/tmp/test_repair/8820/00/000\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.059341475Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"Move directory  \"/var/tmp/test_repair/8820/00/000/01C.replace\" to \"/var/tmp/test_repair/8820/00/000/01C.completed\"","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:36:57.059456752Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"extent 0 was in dirty_extents but not actually dirty","v":0,"name":"crucible","level":40,"time":"2025-03-17T22:36:57.574466977Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"extent 8 was in dirty_extents but not actually dirty","v":0,"name":"crucible","level":40,"time":"2025-03-17T22:36:57.574574055Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"extent 9 was in dirty_extents but not actually dirty","v":0,"name":"crucible","level":40,"time":"2025-03-17T22:36:57.574609261Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"extent 13 was in dirty_extents but not actually dirty","v":0,"name":"crucible","level":40,"time":"2025-03-17T22:36:57.574627559Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"extent 18 was in dirty_extents but not actually dirty","v":0,"name":"crucible","level":40,"time":"2025-03-17T22:36:57.574643797Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"extent 20 was in dirty_extents but not actually dirty","v":0,"name":"crucible","level":40,"time":"2025-03-17T22:36:57.574655246Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"extent 28 was in dirty_extents but not actually dirty","v":0,"name":"crucible","level":40,"time":"2025-03-17T22:36:57.574666685Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"extent 29 was in dirty_extents but not actually dirty","v":0,"name":"crucible","level":40,"time":"2025-03-17T22:36:57.574678033Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"upstairs disconnected","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:37:01.116126233Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982,"task":"recv","id":"0"}
{"msg":"recv_task sending ConnectionClosed","v":0,"name":"crucible","level":40,"time":"2025-03-17T22:37:01.116283406Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982,"task":"recv","id":"0"}
{"msg":"connection closed; disconnection","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:37:01.116315922Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"upstairs UpstairsConnection { upstairs_id: 3d7d1ca1-ca85-440c-8a91-a91958a37c51, session_id: 35dca443-2f1b-4526-b24b-8fea4248c3b8, gen: 199 } (ConnectionId(0)) removed, 0 jobs left","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:37:01.116339309Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}
{"msg":"upstairs UpstairsConnection { upstairs_id: 3d7d1ca1-ca85-440c-8a91-a91958a37c51, session_id: 35dca443-2f1b-4526-b24b-8fea4248c3b8, gen: 199 } (ConnectionId(0)) was previously active, clearing","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:37:01.116363147Z","hostname":"w-01JPK2S6VD48B0H0HC5JJCJYTE","pid":1982}