/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-02-07T20:15:18.543726229Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"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-02-07T20:15:18.586392531Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"task":"crutest"} {"msg":"Upstairs starts","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.587017061Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986} {"msg":"Crucible Version: BuildInfo {\n version: \"0.0.1\",\n git_sha: \"764da36f6a4255a5834591b69c6e5591e6ce3a04\",\n git_commit_timestamp: \"2025-02-07T19:51:46.000000000Z\",\n git_branch: \"alan/agent-needs-workers\",\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-02-07T20:15:18.587057491Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986} {"msg":"Upstairs <-> Downstairs Message Version: 13","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.587072537Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986} {"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-02-07T20:15:18.587086833Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986} {"msg":"Crucible a7869a3f-4e2c-436e-91b3-306486342c3c has session id: 4ba1999c-1946-4130-82ad-174292f5586d","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.587658847Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"Upstairs opts: Upstairs UUID: a7869a3f-4e2c-436e-91b3-306486342c3c, 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-02-07T20:15:18.587697128Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"Crucible stats registered with UUID: a7869a3f-4e2c-436e-91b3-306486342c3c","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.587722801Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"could not find Downstairs address for Nexus","v":0,"name":"crucible","level":40,"time":"2025-02-07T20:15:18.587745085Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"Downstairs in volume = 3","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.588229292Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"task":"crutest"} {"msg":"The guest has requested activation with gen:199","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.588254855Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986} {"msg":"a7869a3f-4e2c-436e-91b3-306486342c3c active request set","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.588311161Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"sending connect oneshot to client","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.588336244Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"0","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"client set_active_request while in Connecting { state: Start { auto_promote: true }, mode: New }; waiting...","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.588363727Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"0","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"sending connect oneshot to client","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.588379433Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"1","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"client set_active_request while in Connecting { state: Start { auto_promote: true }, mode: New }; waiting...","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.58839391Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"1","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"sending connect oneshot to client","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.588407736Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"2","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"client set_active_request while in Connecting { state: Start { auto_promote: true }, mode: New }; waiting...","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.588421432Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"2","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"connecting to 127.0.0.1:8830","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.588434629Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"io task","client":"2","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"connecting to 127.0.0.1:8810","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.588448366Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"io task","client":"0","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"connecting to 127.0.0.1:8820","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.58846957Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"io task","client":"1","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"ds_connection connected from Ok(127.0.0.1:41628)","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.588563946Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"io task","client":"0","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"ds_connection connected from Ok(127.0.0.1:58870)","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.588613243Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"io task","client":"2","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"ds_connection connected from Ok(127.0.0.1:61472)","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.588814032Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"io task","client":"1","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"version negotiation from state Connecting { state: WaitForPromote, mode: New }","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.589327611Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"2","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"version negotiation from state Connecting { state: WaitForPromote, mode: New }","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.589356524Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"0","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"version negotiation from state Connecting { state: WaitForPromote, mode: New }","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.589504656Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"1","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"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-02-07T20:15:18.589955041Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"2","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"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-02-07T20:15:18.590089677Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"1","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"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-02-07T20:15:18.590237379Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"0","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"Waiting for more clients to be ready","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.590511869Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"Waiting for more clients to be ready","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.590723495Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"[0]R flush_numbers[0..12]: [334, 333, 331, 333, 333, 318, 332, 332, 328, 322, 322, 326]","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.590845853Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"[0]R generation[0..12]: [198, 198, 197, 198, 198, 189, 198, 198, 196, 194, 194, 195]","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.590871857Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"[0]R dirty[0..12]: [false, false, false, false, false, false, false, true, false, false, false, false]","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.590890682Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"[1]R flush_numbers[0..12]: [334, 333, 331, 333, 333, 318, 332, 332, 328, 322, 322, 326]","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.590908197Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"[1]R generation[0..12]: [198, 198, 197, 198, 198, 189, 198, 198, 196, 194, 194, 195]","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.590925403Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"[1]R dirty[0..12]: [false, false, false, false, false, false, false, true, false, false, false, false]","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.590942879Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"[2]R flush_numbers[0..12]: [334, 333, 331, 333, 333, 318, 332, 332, 328, 322, 322, 326]","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.590960024Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"[2]R generation[0..12]: [198, 198, 197, 198, 198, 189, 198, 198, 196, 194, 194, 195]","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.59097707Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"[2]R dirty[0..12]: [false, false, false, false, false, false, false, true, false, false, false, false]","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.590994225Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"Max found gen is 199","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.59101415Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"Generation requested: 199 >= found:199","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.591030766Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"Next flush: 335","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.591046922Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"extent 7 needs reconciliation: ExtentMetadata { gen: 198, flush: 332, dirty: true } ExtentMetadata { gen: 198, flush: 332, dirty: true } ExtentMetadata { gen: 198, flush: 332, dirty: true }","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.591065607Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"mend","":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"extent:0 [ExtentMetadata { gen: 198, flush: 332, dirty: true }, ExtentMetadata { gen: 198, flush: 332, dirty: true }, ExtentMetadata { gen: 198, flush: 332, dirty: true }] => 0","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.591084692Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"mend","":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"found dest for source 0 for extent at index 7 => [ClientId(1), ClientId(2)]","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.591101838Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"mend","":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"extent 19 needs reconciliation: ExtentMetadata { gen: 193, flush: 321, dirty: true } ExtentMetadata { gen: 193, flush: 321, dirty: true } ExtentMetadata { gen: 193, flush: 321, dirty: true }","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.591120783Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"mend","":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"extent:0 [ExtentMetadata { gen: 193, flush: 321, dirty: true }, ExtentMetadata { gen: 193, flush: 321, dirty: true }, ExtentMetadata { gen: 193, flush: 321, dirty: true }] => 0","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.591139408Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"mend","":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"found dest for source 0 for extent at index 19 => [ClientId(1), ClientId(2)]","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.591156564Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"mend","":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"extent 24 needs reconciliation: ExtentMetadata { gen: 195, flush: 326, dirty: true } ExtentMetadata { gen: 195, flush: 326, dirty: true } ExtentMetadata { gen: 195, flush: 326, dirty: true }","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.591175299Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"mend","":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"extent:0 [ExtentMetadata { gen: 195, flush: 326, dirty: true }, ExtentMetadata { gen: 195, flush: 326, dirty: true }, ExtentMetadata { gen: 195, flush: 326, dirty: true }] => 0","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.591193854Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"mend","":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"found dest for source 0 for extent at index 24 => [ClientId(1), ClientId(2)]","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.59121111Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"mend","":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"Transition from Connecting { state: WaitQuorum, mode: New } to Reconcile","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.591238853Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"0","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"Transition from Connecting { state: WaitQuorum, mode: New } to Reconcile","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.591257018Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"1","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"Transition from Connecting { state: WaitQuorum, mode: New } to Reconcile","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.591274474Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"2","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"Full repair list: {ExtentId(19): ExtentFix { source: ClientId(0), dest: [ClientId(1), ClientId(2)] }, ExtentId(7): ExtentFix { source: ClientId(0), dest: [ClientId(1), ClientId(2)] }, ExtentId(24): ExtentFix { source: ClientId(0), dest: [ClientId(1), ClientId(2)] }}","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.591294349Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"Task list: [ReconcileIO { id: ReconciliationId(0), op: ExtentFlush { repair_id: ReconciliationId(0), extent_id: ExtentId(19), client_id: ClientId(0), flush_number: 335, gen_number: 199 }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(1), op: ExtentClose { repair_id: ReconciliationId(1), extent_id: ExtentId(19) }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(2), op: ExtentRepair { repair_id: ReconciliationId(2), extent_id: ExtentId(19), 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(19) }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(4), op: ExtentFlush { repair_id: ReconciliationId(4), extent_id: ExtentId(7), client_id: ClientId(0), flush_number: 335, gen_number: 199 }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(5), op: ExtentClose { repair_id: ReconciliationId(5), extent_id: ExtentId(7) }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(6), op: ExtentRepair { repair_id: ReconciliationId(6), extent_id: ExtentId(7), 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(7) }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(8), op: ExtentFlush { repair_id: ReconciliationId(8), extent_id: ExtentId(24), client_id: ClientId(0), flush_number: 335, gen_number: 199 }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(9), op: ExtentClose { repair_id: ReconciliationId(9), extent_id: ExtentId(24) }, state: ClientData([New, New, New]) }, ReconcileIO { id: ReconciliationId(10), op: ExtentRepair { repair_id: ReconciliationId(10), extent_id: ExtentId(24), 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(24) }, state: ClientData([New, New, New]) }]","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.591348695Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"starting reconciliation 5d136647-36aa-474b-8edb-a84d6e67fd5d: found 12 extents that need repair","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.5913692Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"sending reconcile request ReconciliationId(2)","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.591649838Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"1","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"sending reconcile request ReconciliationId(2)","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.591673582Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"2","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"sending reconcile request ReconciliationId(6)","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.597304843Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"1","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"sending reconcile request ReconciliationId(6)","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.597336095Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"2","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"sending reconcile request ReconciliationId(10)","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.60178239Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"1","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"sending reconcile request ReconciliationId(10)","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.60182074Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"2","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"done with reconciliation","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.605640734Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"":"downstairs","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"Transition from Reconcile to Active","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.605668417Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"0","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"Transition from Reconcile to Active","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.605683913Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"1","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"Transition from Reconcile to Active","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.60569773Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"client":"2","":"downstairs","session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"All required reconciliation work is completed","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.605711416Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"Set Downstairs and Upstairs active after reconciliation","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.605723943Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} Wait for a query_work_queue command to finish before sending IO {"msg":"a7869a3f-4e2c-436e-91b3-306486342c3c is now active with session: 4ba1999c-1946-4130-82ad-174292f5586d","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.605743148Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986,"session_id":"4ba1999c-1946-4130-82ad-174292f5586d"} {"msg":"The guest has finished waiting for activation with:199","v":0,"name":"crucible","level":30,"time":"2025-02-07T20:15:18.605755475Z","hostname":"w-01JKH09D388P2M62WHVYSYH6T6","pid":1986} 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