{"msg":"Opened existing region file \"/var/tmp/test_repair/8820/region.json\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.049677959Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Database read version 1","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.050383449Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Database write version 1","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.050409913Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"UUID: 12345678-0000-0000-0000-000000008820","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.053638072Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Blocks per extent:20 Total Extents: 30","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.053662017Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Crucible Version: Crucible Version: 0.0.1\nCommit SHA: 008b9b7890522309c5657fa001c5655325f06c1f\nCommit timestamp: 2025-01-15T05:03:03.000000000Z  branch: renovate/http-1.x\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-01-15T05:26:52.098536223Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978,"task":"main"}
{"msg":"Upstairs <-> Downstairs Message Version: 12","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.098585372Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978,"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-01-15T05:26:52.099732862Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978,"task":"repair"}
{"msg":"listening","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.100015538Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978,"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-01-15T05:26:52.100218192Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978,"task":"main"}
{"msg":"No SSL acceptor configured","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.100252345Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978,"task":"main"}
{"msg":"downstairs listening on 0.0.0.0:8820","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.100276869Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978,"task":"main"}
{"msg":"accepted connection from 127.0.0.1:44708","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.10115482Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978,"task":"main"}
{"msg":"connection (127.0.0.1:44708): tasks spawned","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.1013777Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978,"id":"0"}
{"msg":"Connection request from b0234de0-81e3-4004-8947-42242bc7e864 with version 12","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.101617696Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"upstairs UpstairsConnection { upstairs_id: b0234de0-81e3-4004-8947-42242bc7e864, session_id: 1118c72b-9607-4937-be94-47524f68d7c8, gen: 199 } connected, version 12","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.101661166Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"UpstairsConnection { upstairs_id: b0234de0-81e3-4004-8947-42242bc7e864, session_id: 1118c72b-9607-4937-be94-47524f68d7c8, gen: 199 } is now active (read-write)","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.102287704Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Current flush_numbers [0..12]: [346, 347, 328, 327, 344, 331, 335, 348, 348, 343, 319, 345]","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.103086223Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Created copy dir \"/var/tmp/test_repair/8820/00/000/009.copy\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.104646519Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"eid:9 Found repair files: [\"009\"]","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.106293856Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Verify extent 9 still ready for copy","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.108040221Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"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-01-15T05:26:52.108497297Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"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-01-15T05:26:52.108589956Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"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-01-15T05:26:52.108714428Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Created copy dir \"/var/tmp/test_repair/8820/00/000/00E.copy\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.110199292Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"eid:14 Found repair files: [\"00E\"]","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.110748827Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Verify extent 14 still ready for copy","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.112376639Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"1 repair files downloaded, move directory \"/var/tmp/test_repair/8820/00/000/00E.copy\" to \"/var/tmp/test_repair/8820/00/000/00E.replace\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.112958497Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Copy files from \"/var/tmp/test_repair/8820/00/000/00E.replace\" in \"/var/tmp/test_repair/8820/00/000\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.113017934Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Move directory  \"/var/tmp/test_repair/8820/00/000/00E.replace\" to \"/var/tmp/test_repair/8820/00/000/00E.completed\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.113189305Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Created copy dir \"/var/tmp/test_repair/8820/00/000/01C.copy\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.11466603Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"eid:28 Found repair files: [\"01C\"]","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.115171186Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Verify extent 28 still ready for copy","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.116809885Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"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-01-15T05:26:52.119757137Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"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-01-15T05:26:52.119820263Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"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-01-15T05:26:52.119956512Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Created copy dir \"/var/tmp/test_repair/8820/00/000/01D.copy\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.121339709Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"eid:29 Found repair files: [\"01D\"]","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.121851523Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Verify extent 29 still ready for copy","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.123518355Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"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-01-15T05:26:52.123996587Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"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-01-15T05:26:52.124086287Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"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-01-15T05:26:52.124211119Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Created copy dir \"/var/tmp/test_repair/8820/00/000/010.copy\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.125531129Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"eid:16 Found repair files: [\"010\"]","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.126008381Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Verify extent 16 still ready for copy","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.127613468Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"1 repair files downloaded, move directory \"/var/tmp/test_repair/8820/00/000/010.copy\" to \"/var/tmp/test_repair/8820/00/000/010.replace\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.128230688Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Copy files from \"/var/tmp/test_repair/8820/00/000/010.replace\" in \"/var/tmp/test_repair/8820/00/000\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.128257122Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Move directory  \"/var/tmp/test_repair/8820/00/000/010.replace\" to \"/var/tmp/test_repair/8820/00/000/010.completed\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.128334225Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Created copy dir \"/var/tmp/test_repair/8820/00/000/013.copy\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.12963686Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"eid:19 Found repair files: [\"013\"]","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.130373713Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Verify extent 19 still ready for copy","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.132056542Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"1 repair files downloaded, move directory \"/var/tmp/test_repair/8820/00/000/013.copy\" to \"/var/tmp/test_repair/8820/00/000/013.replace\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.132566576Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Copy files from \"/var/tmp/test_repair/8820/00/000/013.replace\" in \"/var/tmp/test_repair/8820/00/000\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.1325951Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Move directory  \"/var/tmp/test_repair/8820/00/000/013.replace\" to \"/var/tmp/test_repair/8820/00/000/013.completed\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.132722321Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Created copy dir \"/var/tmp/test_repair/8820/00/000/01B.copy\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.134123323Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"eid:27 Found repair files: [\"01B\"]","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.134622641Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Verify extent 27 still ready for copy","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.136285584Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"1 repair files downloaded, move directory \"/var/tmp/test_repair/8820/00/000/01B.copy\" to \"/var/tmp/test_repair/8820/00/000/01B.replace\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.13683093Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Copy files from \"/var/tmp/test_repair/8820/00/000/01B.replace\" in \"/var/tmp/test_repair/8820/00/000\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.136865602Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Move directory  \"/var/tmp/test_repair/8820/00/000/01B.replace\" to \"/var/tmp/test_repair/8820/00/000/01B.completed\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.13700653Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Created copy dir \"/var/tmp/test_repair/8820/00/000/012.copy\"","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.138345027Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"eid:18 Found repair files: [\"012\"]","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.139048068Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"Verify extent 18 still ready for copy","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:52.140571373Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"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-01-15T05:26:52.145623119Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"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-01-15T05:26:52.145655152Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"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-01-15T05:26:52.145804318Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"extent 9 was in dirty_extents but not actually dirty","v":0,"name":"crucible","level":40,"time":"2025-01-15T05:26:52.661348837Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"extent 14 was in dirty_extents but not actually dirty","v":0,"name":"crucible","level":40,"time":"2025-01-15T05:26:52.661397246Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"extent 16 was in dirty_extents but not actually dirty","v":0,"name":"crucible","level":40,"time":"2025-01-15T05:26:52.66142347Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"extent 18 was in dirty_extents but not actually dirty","v":0,"name":"crucible","level":40,"time":"2025-01-15T05:26:52.661447585Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"extent 19 was in dirty_extents but not actually dirty","v":0,"name":"crucible","level":40,"time":"2025-01-15T05:26:52.661471709Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"extent 27 was in dirty_extents but not actually dirty","v":0,"name":"crucible","level":40,"time":"2025-01-15T05:26:52.661497223Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"extent 28 was in dirty_extents but not actually dirty","v":0,"name":"crucible","level":40,"time":"2025-01-15T05:26:52.661522908Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"extent 29 was in dirty_extents but not actually dirty","v":0,"name":"crucible","level":40,"time":"2025-01-15T05:26:52.661546522Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"upstairs disconnected","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:56.201633749Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978,"task":"recv","id":"0"}
{"msg":"recv_task sending ConnectionClosed","v":0,"name":"crucible","level":40,"time":"2025-01-15T05:26:56.201685397Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978,"task":"recv","id":"0"}
{"msg":"connection closed; disconnection","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:56.201721219Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"upstairs UpstairsConnection { upstairs_id: b0234de0-81e3-4004-8947-42242bc7e864, session_id: 1118c72b-9607-4937-be94-47524f68d7c8, gen: 199 } (ConnectionId(0)) removed, 0 jobs left","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:56.201751882Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}
{"msg":"upstairs UpstairsConnection { upstairs_id: b0234de0-81e3-4004-8947-42242bc7e864, session_id: 1118c72b-9607-4937-be94-47524f68d7c8, gen: 199 } (ConnectionId(0)) was previously active, clearing","v":0,"name":"crucible","level":30,"time":"2025-01-15T05:26:56.201777516Z","hostname":"w-01JHM6ARD39SFM9SXVPVHKAJ0E","pid":1978}