/var/tmp/bins/crutest verify -t 127.0.0.1:8810 -t 127.0.0.1:8820 -t 127.0.0.1:8830 --verify-out /tmp/test_repair_verify.data --verify-in /tmp/test_repair_verify.data --range -q -g 199 {"msg":"look at: http://127.0.0.1:12810","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.853492333Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"task":"crutest"} {"msg":"RI is: RegionDefinition { block_size: 4096, extent_size: Block { value: 20, shift: 12 }, extent_count: 30, uuid: 12345678-0000-0000-0000-000000008810, encrypted: false, database_read_version: 1, database_write_version: 1 }","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.899884593Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"task":"crutest"} {"msg":"Upstairs starts","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.900557678Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984} {"msg":"Crucible Version: BuildInfo {\n version: \"0.0.1\",\n git_sha: \"acc6b11fa639390710fd9a0449e05c901b048ed2\",\n git_commit_timestamp: \"2025-03-17T22:02:02.000000000Z\",\n git_branch: \"renovate/itertools-0.x\",\n rustc_semver: \"1.84.0\",\n rustc_channel: \"stable\",\n rustc_host_triple: \"x86_64-unknown-illumos\",\n rustc_commit_sha: \"9fc6b43126469e3858e2fe86cafb4f0fd5068869\",\n cargo_triple: \"x86_64-unknown-illumos\",\n debug: true,\n opt_level: 0,\n}","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.90060905Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984} {"msg":"Upstairs <-> Downstairs Message Version: 13","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.900627193Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984} {"msg":"Using region definition RegionDefinition { block_size: 4096, extent_size: Block { value: 20, shift: 12 }, extent_count: 30, uuid: 00000000-0000-0000-0000-000000000000, encrypted: false, database_read_version: 1, database_write_version: 1 }","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.900644807Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984} {"msg":"Crucible 9ef4068e-f3fe-4519-bd42-ee7171aa7806 has session id: ecfb0ab9-913c-47a0-8495-18a5592d432e","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.901205218Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"Upstairs opts: Upstairs UUID: 9ef4068e-f3fe-4519-bd42-ee7171aa7806, Targets: [127.0.0.1:8810, 127.0.0.1:8820, 127.0.0.1:8830], lossy: false, flush_timeout: None, key populated: false, cert_pem populated: false, key_pem populated: false, root_cert_pem populated: false, Control: None, read_only: false","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.901291217Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"Crucible stats registered with UUID: 9ef4068e-f3fe-4519-bd42-ee7171aa7806","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.901322603Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"could not find Downstairs address for Nexus","v":0,"name":"crucible","level":40,"time":"2025-03-17T22:34:11.901343918Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"Downstairs in volume = 3","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.901760298Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"task":"crutest"} {"msg":"The guest has requested activation with gen:199","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.901793945Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984} {"msg":"9ef4068e-f3fe-4519-bd42-ee7171aa7806 active request set","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.901849817Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"sending connect oneshot to client","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.901876803Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"0","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"client set_active_request while in Connecting { state: Start { auto_promote: true }, mode: New }; waiting...","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.901912761Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"0","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"sending connect oneshot to client","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.901929414Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"1","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"client set_active_request while in Connecting { state: Start { auto_promote: true }, mode: New }; waiting...","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.901947498Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"1","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"sending connect oneshot to client","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.901971363Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"2","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"client set_active_request while in Connecting { state: Start { auto_promote: true }, mode: New }; waiting...","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.901999129Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"2","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"connecting to 127.0.0.1:8830","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.902024525Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"io task","client":"2","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"connecting to 127.0.0.1:8810","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.902051471Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"io task","client":"0","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"connecting to 127.0.0.1:8820","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.902076076Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"io task","client":"1","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"ds_connection connected from Ok(127.0.0.1:60487)","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.902101772Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"io task","client":"2","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"ds_connection connected from Ok(127.0.0.1:57401)","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.90214371Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"io task","client":"1","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"ds_connection connected from Ok(127.0.0.1:57300)","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.902274159Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"io task","client":"0","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"version negotiation from state Connecting { state: WaitForPromote, mode: New }","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.902918838Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"0","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"version negotiation from state Connecting { state: WaitForPromote, mode: New }","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.902946274Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"1","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"downstairs client at Some(127.0.0.1:8810) has region UUID 12345678-0000-0000-0000-000000008810","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.903601215Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"0","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"downstairs client at Some(127.0.0.1:8820) has region UUID 12345678-0000-0000-0000-000000008820","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.903739585Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"1","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"version negotiation from state Connecting { state: WaitForPromote, mode: New }","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.903871293Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"2","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"Waiting for more clients to be ready","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.904167727Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"Waiting for more clients to be ready","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.904291134Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"downstairs client at Some(127.0.0.1:8830) has region UUID 12345678-0000-0000-0000-000000008830","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.904564373Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"2","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"[0]R flush_numbers[0..12]: [298, 307, 311, 304, 296, 317, 313, 310, 313, 317, 315, 317]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905092047Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"[0]R generation[0..12]: [187, 192, 194, 192, 185, 198, 196, 194, 196, 198, 197, 198]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905119773Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"[0]R dirty[0..12]: [false, true, false, false, false, false, false, false, false, false, true, true]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905135186Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"[1]R flush_numbers[0..12]: [298, 307, 311, 304, 296, 317, 313, 310, 313, 317, 315, 317]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905149389Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"[1]R generation[0..12]: [187, 192, 194, 192, 185, 198, 196, 194, 196, 198, 197, 198]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905163242Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"[1]R dirty[0..12]: [false, true, false, false, false, false, false, false, false, false, true, true]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905177025Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"[2]R flush_numbers[0..12]: [298, 307, 311, 304, 296, 317, 313, 310, 313, 317, 315, 317]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905190288Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"[2]R generation[0..12]: [187, 192, 194, 192, 185, 198, 196, 194, 196, 198, 197, 198]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905203511Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"[2]R dirty[0..12]: [false, true, false, false, false, false, false, false, false, false, true, true]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905216954Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"Max found gen is 199","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905233427Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"Generation requested: 199 >= found:199","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.90524712Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"Next flush: 318","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905259923Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"extent 1 needs reconciliation: ExtentMetadata { gen: 192, flush: 307, dirty: true } ExtentMetadata { gen: 192, flush: 307, dirty: true } ExtentMetadata { gen: 192, flush: 307, dirty: true }","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905274776Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"mend","":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"extent:0 [ExtentMetadata { gen: 192, flush: 307, dirty: true }, ExtentMetadata { gen: 192, flush: 307, dirty: true }, ExtentMetadata { gen: 192, flush: 307, dirty: true }] => 0","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.9052906Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"mend","":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"found dest for source 0 for extent at index 1 => [ClientId(1), ClientId(2)]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905304783Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"mend","":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"extent 10 needs reconciliation: ExtentMetadata { gen: 197, flush: 315, dirty: true } ExtentMetadata { gen: 197, flush: 315, dirty: true } ExtentMetadata { gen: 197, flush: 315, dirty: true }","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905319936Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"mend","":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"extent:0 [ExtentMetadata { gen: 197, flush: 315, dirty: true }, ExtentMetadata { gen: 197, flush: 315, dirty: true }, ExtentMetadata { gen: 197, flush: 315, dirty: true }] => 0","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905335499Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"mend","":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"found dest for source 0 for extent at index 10 => [ClientId(1), ClientId(2)]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905349032Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"mend","":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"extent 11 needs reconciliation: ExtentMetadata { gen: 198, flush: 317, dirty: true } ExtentMetadata { gen: 198, flush: 317, dirty: true } ExtentMetadata { gen: 198, flush: 317, dirty: true }","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905364005Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"mend","":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"extent:0 [ExtentMetadata { gen: 198, flush: 317, dirty: true }, ExtentMetadata { gen: 198, flush: 317, dirty: true }, ExtentMetadata { gen: 198, flush: 317, dirty: true }] => 0","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905378608Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"mend","":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"found dest for source 0 for extent at index 11 => [ClientId(1), ClientId(2)]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905392191Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"mend","":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"extent 12 needs reconciliation: ExtentMetadata { gen: 197, flush: 315, dirty: true } ExtentMetadata { gen: 197, flush: 315, dirty: true } ExtentMetadata { gen: 197, flush: 315, dirty: true }","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905410675Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"mend","":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"extent:0 [ExtentMetadata { gen: 197, flush: 315, dirty: true }, ExtentMetadata { gen: 197, flush: 315, dirty: true }, ExtentMetadata { gen: 197, flush: 315, dirty: true }] => 0","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905425969Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"mend","":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"found dest for source 0 for extent at index 12 => [ClientId(1), ClientId(2)]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905439442Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"mend","":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"extent 21 needs reconciliation: ExtentMetadata { gen: 187, flush: 298, dirty: true } ExtentMetadata { gen: 187, flush: 298, dirty: true } ExtentMetadata { gen: 187, flush: 298, dirty: true }","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905454385Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"mend","":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"extent:0 [ExtentMetadata { gen: 187, flush: 298, dirty: true }, ExtentMetadata { gen: 187, flush: 298, dirty: true }, ExtentMetadata { gen: 187, flush: 298, dirty: true }] => 0","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905469038Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"mend","":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"found dest for source 0 for extent at index 21 => [ClientId(1), ClientId(2)]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905482471Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"mend","":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"extent 22 needs reconciliation: ExtentMetadata { gen: 194, flush: 311, dirty: true } ExtentMetadata { gen: 194, flush: 311, dirty: true } ExtentMetadata { gen: 194, flush: 311, dirty: true }","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905497284Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"mend","":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"extent:0 [ExtentMetadata { gen: 194, flush: 311, dirty: true }, ExtentMetadata { gen: 194, flush: 311, dirty: true }, ExtentMetadata { gen: 194, flush: 311, dirty: true }] => 0","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905511877Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"mend","":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"found dest for source 0 for extent at index 22 => [ClientId(1), ClientId(2)]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.90552523Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"mend","":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"Transition from Connecting { state: WaitQuorum, mode: New } to Reconcile","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905544644Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"0","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"Transition from Connecting { state: WaitQuorum, mode: New } to Reconcile","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905558407Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"1","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"Transition from Connecting { state: WaitQuorum, mode: New } to Reconcile","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905573441Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"2","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"Full repair list: {ExtentId(1): ExtentFix { source: ClientId(0), dest: [ClientId(1), ClientId(2)] }, ExtentId(22): ExtentFix { source: ClientId(0), dest: [ClientId(1), ClientId(2)] }, ExtentId(11): ExtentFix { source: ClientId(0), dest: [ClientId(1), ClientId(2)] }, ExtentId(21): ExtentFix { source: ClientId(0), dest: [ClientId(1), ClientId(2)] }, ExtentId(12): ExtentFix { source: ClientId(0), dest: [ClientId(1), ClientId(2)] }, ExtentId(10): ExtentFix { source: ClientId(0), dest: [ClientId(1), ClientId(2)] }}","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905592665Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"Task list: [ReconcileIO { id: ReconciliationId(0), op: ExtentFlush { repair_id: ReconciliationId(0), extent_id: ExtentId(1), client_id: ClientId(0), flush_number: 318, gen_number: 199 }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(1), op: ExtentClose { repair_id: ReconciliationId(1), extent_id: ExtentId(1) }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(2), op: ExtentRepair { repair_id: ReconciliationId(2), extent_id: ExtentId(1), source_client_id: ClientId(0), source_repair_address: 0.0.0.0:12810, dest_clients: [ClientId(1), ClientId(2)] }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(3), op: ExtentReopen { repair_id: ReconciliationId(3), extent_id: ExtentId(1) }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(4), op: ExtentFlush { repair_id: ReconciliationId(4), extent_id: ExtentId(22), client_id: ClientId(0), flush_number: 318, gen_number: 199 }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(5), op: ExtentClose { repair_id: ReconciliationId(5), extent_id: ExtentId(22) }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(6), op: ExtentRepair { repair_id: ReconciliationId(6), extent_id: ExtentId(22), source_client_id: ClientId(0), source_repair_address: 0.0.0.0:12810, dest_clients: [ClientId(1), ClientId(2)] }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(7), op: ExtentReopen { repair_id: ReconciliationId(7), extent_id: ExtentId(22) }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(8), op: ExtentFlush { repair_id: ReconciliationId(8), extent_id: ExtentId(11), client_id: ClientId(0), flush_number: 318, gen_number: 199 }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(9), op: ExtentClose { repair_id: ReconciliationId(9), extent_id: ExtentId(11) }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(10), op: ExtentRepair { repair_id: ReconciliationId(10), extent_id: ExtentId(11), source_client_id: ClientId(0), source_repair_address: 0.0.0.0:12810, dest_clients: [ClientId(1), ClientId(2)] }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(11), op: ExtentReopen { repair_id: ReconciliationId(11), extent_id: ExtentId(11) }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(12), op: ExtentFlush { repair_id: ReconciliationId(12), extent_id: ExtentId(21), client_id: ClientId(0), flush_number: 318, gen_number: 199 }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(13), op: ExtentClose { repair_id: ReconciliationId(13), extent_id: ExtentId(21) }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(14), op: ExtentRepair { repair_id: ReconciliationId(14), extent_id: ExtentId(21), source_client_id: ClientId(0), source_repair_address: 0.0.0.0:12810, dest_clients: [ClientId(1), ClientId(2)] }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(15), op: ExtentReopen { repair_id: ReconciliationId(15), extent_id: ExtentId(21) }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(16), op: ExtentFlush { repair_id: ReconciliationId(16), extent_id: ExtentId(12), client_id: ClientId(0), flush_number: 318, gen_number: 199 }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(17), op: ExtentClose { repair_id: ReconciliationId(17), extent_id: ExtentId(12) }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(18), op: ExtentRepair { repair_id: ReconciliationId(18), extent_id: ExtentId(12), source_client_id: ClientId(0), source_repair_address: 0.0.0.0:12810, dest_clients: [ClientId(1), ClientId(2)] }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(19), op: ExtentReopen { repair_id: ReconciliationId(19), extent_id: ExtentId(12) }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(20), op: ExtentFlush { repair_id: ReconciliationId(20), extent_id: ExtentId(10), client_id: ClientId(0), flush_number: 318, gen_number: 199 }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(21), op: ExtentClose { repair_id: ReconciliationId(21), extent_id: ExtentId(10) }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(22), op: ExtentRepair { repair_id: ReconciliationId(22), extent_id: ExtentId(10), source_client_id: ClientId(0), source_repair_address: 0.0.0.0:12810, dest_clients: [ClientId(1), ClientId(2)] }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(23), op: ExtentReopen { repair_id: ReconciliationId(23), extent_id: ExtentId(10) }, state: ClientData([New, New, New]) }]","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905672702Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"starting reconciliation 3b757121-03d0-4d76-81e1-c22180256c15: found 24 extents that need repair","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905699568Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"sending reconcile request ReconciliationId(2)","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.905994712Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"1","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"sending reconcile request ReconciliationId(2)","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.90603359Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"2","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"sending reconcile request ReconciliationId(6)","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.911847815Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"1","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"sending reconcile request ReconciliationId(6)","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.911884662Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"2","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"sending reconcile request ReconciliationId(10)","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.916223629Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"1","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"sending reconcile request ReconciliationId(10)","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.916259306Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"2","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"sending reconcile request ReconciliationId(14)","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.920550112Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"1","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"sending reconcile request ReconciliationId(14)","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.920583279Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"2","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"sending reconcile request ReconciliationId(18)","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.925078959Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"1","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"sending reconcile request ReconciliationId(18)","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.925107725Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"2","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"sending reconcile request ReconciliationId(22)","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.92943969Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"1","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"sending reconcile request ReconciliationId(22)","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.929467076Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"2","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"done with reconciliation","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.933189459Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"":"downstairs","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"Transition from Reconcile to Active","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.933216275Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"0","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"Transition from Reconcile to Active","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.933230838Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"1","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"Transition from Reconcile to Active","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.933245481Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"client":"2","":"downstairs","session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"All required reconciliation work is completed","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.933258874Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"Set Downstairs and Upstairs active after reconciliation","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.933270997Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"9ef4068e-f3fe-4519-bd42-ee7171aa7806 is now active with session: ecfb0ab9-913c-47a0-8495-18a5592d432e","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.93328362Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984,"session_id":"ecfb0ab9-913c-47a0-8495-18a5592d432e"} {"msg":"The guest has finished waiting for activation with:199","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.933296552Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984} {"msg":"Activated sub_volume 9ef4068e-f3fe-4519-bd42-ee7171aa7806","v":0,"name":"crucible","level":30,"time":"2025-03-17T22:34:11.933336451Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984} Wait for a query_work_queue command to finish before sending IO Disk: sv:1 bs:4096 ts:2457600 tb:600 max_io:256 or 1048576 Loading write count information from file "/tmp/test_repair_verify.data" Read and Verify all blocks (0..600 range:true) Wrote out file "/tmp/test_repair_verify.data" Verify test completed Wrote out file "/tmp/test_repair_verify.data" CLIENT: Tests done. All submitted work has been ACK'd ---------------------------------------------------------------- Crucible gen:199 GIO:true work queues: Upstairs:0 downstairs:3 States: Active Active Active JOBID ACK TYPE BKS/EXT DS:0 DS:1 DS:2 REPLAY 1000 Acked Read 88 Done Done Done false 1001 Acked Read 256 Done Done Done false 1002 Acked Read 256 Done Done Done false STATES DS:0 DS:1 DS:2 TOTAL Sent 0 0 0 0 Done 3 3 3 9 Skipped 0 0 0 0 Error 0 0 0 0 Last Flush: None None None Downstairs last five completed: Upstairs last five completed: 1002 1001 1000 CLIENT: Up:0 ds:3 act:3 ---------------------------------------------------------------- Crucible gen:199 GIO:true work queues: Upstairs:0 downstairs:0 Downstairs last five completed: 1003 1002 1001 1000 Upstairs last five completed: 1003 1002 1001 1000 CLIENT: Up:0 ds:0 act:3 CLIENT: All crucible jobs finished, exiting program {"msg":"Guest handle has been dropped","v":0,"name":"crucible","level":40,"time":"2025-03-17T22:34:15.984466273Z","hostname":"w-01JPK2XVX1460JZZ10JG7J7E5Z","pid":1984}