{"msg":"Opened existing region file \"/var/tmp/dsc/region/8830/region.json\"","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:09.234551583Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156} {"msg":"Database read version 1","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:09.234740971Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156} {"msg":"Database write version 1","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:09.234749301Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156} {"msg":"UUID: 12345678-0000-0000-0000-000000008830","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:09.257178367Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156} {"msg":"Blocks per extent:262144 Total Extents: 32","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:09.257192497Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156} {"msg":"Crucible Version: Crucible Version: 0.0.1\nCommit SHA: ebef7f4917e579ad4649dc0478073aaafe487733\nCommit timestamp: 2024-02-08T06:19:45.000000000Z branch: alan/downstairs-clone\nrustc: 1.75.0 stable x86_64-unknown-illumos\nCargo: x86_64-unknown-illumos Debug: false Opt level: 3","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:09.257323226Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"main"} {"msg":"Upstairs <-> Downstairs Message Version: 5","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:09.257338626Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"main"} {"msg":"Repair listens on 0.0.0.0:12830 for path:\"/var/tmp/dsc/region/8830\"","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:09.257892872Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"repair"} {"msg":"listening","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:09.25807852Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"local_addr":"0.0.0.0:12830","task":"repair"} {"msg":"Using repair address: 0.0.0.0:12830","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:09.258146889Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"main"} {"msg":"No SSL acceptor configured","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:09.258155809Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"main"} {"msg":"downstairs listening on 0.0.0.0:8830","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:09.258224979Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"main"} {"msg":"accepted connection from 127.0.0.1:57764","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:12.417767424Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"main"} {"msg":"Connection request from 00000000-0000-0000-0000-000000000000 with version 5","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:12.41817446Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"proc"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 69b9e7ff-2d52-4ec8-a169-29f711bdcfd5, gen: 1 } connected, version 5","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:12.41820285Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"proc"} {"msg":"UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 69b9e7ff-2d52-4ec8-a169-29f711bdcfd5, gen: 1 } is now active (read-write)","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:12.418531137Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156} {"msg":"Current flush_numbers [0..12]: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:12.418925804Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"proc"} {"msg":"Downstairs has completed Negotiation","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:12.418937654Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"proc"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 69b9e7ff-2d52-4ec8-a169-29f711bdcfd5, gen: 1 } disconnected, 0 jobs left","v":0,"name":"crucible","level":40,"time":"2024-02-08T06:40:27.212395863Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"main"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 69b9e7ff-2d52-4ec8-a169-29f711bdcfd5, gen: 1 } was previously active, clearing","v":0,"name":"crucible","level":40,"time":"2024-02-08T06:40:27.212412462Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"main"} {"msg":"connection (127.0.0.1:57764): all done","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:27.212420002Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156} {"msg":"accepted connection from 127.0.0.1:52059","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:27.252994099Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"main"} {"msg":"Connection request from 00000000-0000-0000-0000-000000000000 with version 5","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:27.253159728Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"proc"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 1693da45-d5c4-4e47-b223-39f3abb54269, gen: 2 } connected, version 5","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:27.253174998Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"proc"} {"msg":"UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 1693da45-d5c4-4e47-b223-39f3abb54269, gen: 2 } is now active (read-write)","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:27.253349187Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156} {"msg":"Current flush_numbers [0..12]: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:27.253793083Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"proc"} {"msg":"Downstairs has completed Negotiation","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:27.253806023Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"proc"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 1693da45-d5c4-4e47-b223-39f3abb54269, gen: 2 } disconnected, 0 jobs left","v":0,"name":"crucible","level":40,"time":"2024-02-08T06:40:39.425541737Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"main"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 1693da45-d5c4-4e47-b223-39f3abb54269, gen: 2 } was previously active, clearing","v":0,"name":"crucible","level":40,"time":"2024-02-08T06:40:39.425614046Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"main"} {"msg":"connection (127.0.0.1:52059): all done","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:39.425624426Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156} {"msg":"accepted connection from 127.0.0.1:40987","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:39.808564026Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"main"} {"msg":"Connection request from 00000000-0000-0000-0000-000000000000 with version 5","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:39.808640766Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"proc"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 54419c9d-7435-4244-964d-e24329d14733, gen: 3 } connected, version 5","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:39.808650716Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"proc"} {"msg":"UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 54419c9d-7435-4244-964d-e24329d14733, gen: 3 } is now active (read-write)","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:39.808803494Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156} {"msg":"Current flush_numbers [0..12]: [1, 1, 2, 3, 3, 3, 4, 4, 5, 5, 6, 6]","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:39.811322374Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"proc"} {"msg":"Downstairs has completed Negotiation","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:39.811330924Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"proc"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 54419c9d-7435-4244-964d-e24329d14733, gen: 3 } disconnected, 0 jobs left","v":0,"name":"crucible","level":40,"time":"2024-02-08T06:40:54.342094936Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"main"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 54419c9d-7435-4244-964d-e24329d14733, gen: 3 } was previously active, clearing","v":0,"name":"crucible","level":40,"time":"2024-02-08T06:40:54.342117656Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"main"} {"msg":"connection (127.0.0.1:40987): all done","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:54.342125726Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156} {"msg":"accepted connection from 127.0.0.1:42339","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:55.834267021Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"main"} {"msg":"Connection request from 00000000-0000-0000-0000-000000000000 with version 5","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:55.83444085Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"proc"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 33530571-f6fb-4f77-b256-d3cc6415eea7, gen: 4 } connected, version 5","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:55.83445173Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"proc"} {"msg":"UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 33530571-f6fb-4f77-b256-d3cc6415eea7, gen: 4 } is now active (read-write)","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:55.834690638Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156} {"msg":"Current flush_numbers [0..12]: [20, 20, 21, 21, 22, 22, 22, 23, 24, 24, 25, 25]","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:55.835276423Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"proc"} {"msg":"Downstairs has completed Negotiation","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:40:55.835285583Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"proc"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 33530571-f6fb-4f77-b256-d3cc6415eea7, gen: 4 } disconnected, 0 jobs left","v":0,"name":"crucible","level":40,"time":"2024-02-08T06:41:17.355885686Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"main"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 33530571-f6fb-4f77-b256-d3cc6415eea7, gen: 4 } was previously active, clearing","v":0,"name":"crucible","level":40,"time":"2024-02-08T06:41:17.355905096Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"main"} {"msg":"connection (127.0.0.1:42339): all done","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:41:17.355913956Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156} {"msg":"accepted connection from 127.0.0.1:34147","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:41:17.402024118Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"main"} {"msg":"Connection request from 00000000-0000-0000-0000-000000000000 with version 5","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:41:17.402152277Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"proc"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 4febfa69-e3e3-426d-96c7-3501bc8bdca9, gen: 5 } connected, version 5","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:41:17.402158667Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"proc"} {"msg":"UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 4febfa69-e3e3-426d-96c7-3501bc8bdca9, gen: 5 } is now active (read-write)","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:41:17.402320065Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156} {"msg":"Current flush_numbers [0..12]: [20, 20, 21, 21, 22, 22, 22, 23, 24, 24, 25, 25]","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:41:17.403218738Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"proc"} {"msg":"Downstairs has completed Negotiation","v":0,"name":"crucible","level":30,"time":"2024-02-08T06:41:17.403227948Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156,"task":"proc"} {"msg":"connection (127.0.0.1:34147) Exits with error: Error reading from Upstairs: bytes remaining on stream","v":0,"name":"crucible","level":50,"time":"2024-02-08T06:43:20.90218307Z","hostname":"ip-10-150-1-105.us-west-2.compute.internal","pid":1156}