{"msg":"Opened existing region file \"/var/tmp/dsc/region/8830/region.json\"","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:32:52.588423043Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157} {"msg":"Database read version 1","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:32:52.58850766Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157} {"msg":"Database write version 1","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:32:52.588511908Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157} {"msg":"UUID: 12345678-0000-0000-0000-000000008830","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:32:52.616445961Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157} {"msg":"Blocks per extent:262144 Total Extents: 32","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:32:52.616459484Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157} {"msg":"Crucible Version: Crucible Version: 0.0.1\nCommit SHA: 9f74bb5fe2be09de9f7a47f2db64d7dce836b79c\nCommit timestamp: 2024-02-12T01:11:14.000000000Z branch: main\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-12T01:32:52.616569448Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"main"} {"msg":"Upstairs <-> Downstairs Message Version: 5","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:32:52.616587669Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"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-12T01:32:52.61686147Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"repair"} {"msg":"listening","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:32:52.617001068Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"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-12T01:32:52.617057619Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"main"} {"msg":"No SSL acceptor configured","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:32:52.617067234Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"main"} {"msg":"downstairs listening on 0.0.0.0:8830","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:32:52.617137519Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"main"} {"msg":"accepted connection from 127.0.0.1:36164","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:32:55.759160062Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"main"} {"msg":"Connection request from 00000000-0000-0000-0000-000000000000 with version 5","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:32:55.759687414Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"proc"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 219dfbd0-e0b8-4dc8-a38f-7db78ce1071b, gen: 1 } connected, version 5","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:32:55.759704075Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"proc"} {"msg":"UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 219dfbd0-e0b8-4dc8-a38f-7db78ce1071b, gen: 1 } is now active (read-write)","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:32:55.760177884Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157} {"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-12T01:32:55.760485117Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"proc"} {"msg":"Downstairs has completed Negotiation","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:32:55.760495312Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"proc"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 219dfbd0-e0b8-4dc8-a38f-7db78ce1071b, gen: 1 } disconnected, 0 jobs left","v":0,"name":"crucible","level":40,"time":"2024-02-12T01:33:12.178840509Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"main"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 219dfbd0-e0b8-4dc8-a38f-7db78ce1071b, gen: 1 } was previously active, clearing","v":0,"name":"crucible","level":40,"time":"2024-02-12T01:33:12.178888804Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"main"} {"msg":"connection (127.0.0.1:36164): all done","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:33:12.178967564Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157} {"msg":"accepted connection from 127.0.0.1:34862","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:33:12.217181178Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"main"} {"msg":"Connection request from 00000000-0000-0000-0000-000000000000 with version 5","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:33:12.217389512Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"proc"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 24591aa9-ab31-4d23-8fdf-66a6e88a516e, gen: 2 } connected, version 5","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:33:12.217399257Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"proc"} {"msg":"UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 24591aa9-ab31-4d23-8fdf-66a6e88a516e, gen: 2 } is now active (read-write)","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:33:12.217562484Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157} {"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-12T01:33:12.217967048Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"proc"} {"msg":"Downstairs has completed Negotiation","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:33:12.218010915Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"proc"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 24591aa9-ab31-4d23-8fdf-66a6e88a516e, gen: 2 } disconnected, 0 jobs left","v":0,"name":"crucible","level":40,"time":"2024-02-12T01:33:24.893528876Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"main"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 24591aa9-ab31-4d23-8fdf-66a6e88a516e, gen: 2 } was previously active, clearing","v":0,"name":"crucible","level":40,"time":"2024-02-12T01:33:24.893553963Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"main"} {"msg":"connection (127.0.0.1:34862): all done","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:33:24.893563208Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157} {"msg":"UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 24591aa9-ab31-4d23-8fdf-66a6e88a516e, gen: 2 } cannot grab work lock, 00000000-0000-0000-0000-000000000000 is not active!","v":0,"name":"crucible","level":40,"time":"2024-02-12T01:33:24.893572554Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157} {"msg":"accepted connection from 127.0.0.1:45432","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:33:25.266417359Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"main"} {"msg":"Connection request from 00000000-0000-0000-0000-000000000000 with version 5","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:33:25.266495719Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"proc"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 77e9ef68-6a25-4359-932d-9fe8351746e5, gen: 3 } connected, version 5","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:33:25.266512571Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"proc"} {"msg":"UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 77e9ef68-6a25-4359-932d-9fe8351746e5, gen: 3 } is now active (read-write)","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:33:25.266761754Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157} {"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-12T01:33:25.26916363Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"proc"} {"msg":"Downstairs has completed Negotiation","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:33:25.269171745Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"proc"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 77e9ef68-6a25-4359-932d-9fe8351746e5, gen: 3 } disconnected, 0 jobs left","v":0,"name":"crucible","level":40,"time":"2024-02-12T01:33:54.559531984Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"main"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 77e9ef68-6a25-4359-932d-9fe8351746e5, gen: 3 } was previously active, clearing","v":0,"name":"crucible","level":40,"time":"2024-02-12T01:33:54.559552524Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"main"} {"msg":"connection (127.0.0.1:45432): all done","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:33:54.5595598Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157} {"msg":"accepted connection from 127.0.0.1:47854","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:33:55.116905783Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"main"} {"msg":"Connection request from 00000000-0000-0000-0000-000000000000 with version 5","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:33:55.116965513Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"proc"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 7a53f6e0-12b3-4219-9b9a-89097007b3df, gen: 4 } connected, version 5","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:33:55.116981365Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"proc"} {"msg":"UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 7a53f6e0-12b3-4219-9b9a-89097007b3df, gen: 4 } is now active (read-write)","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:33:55.117134007Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157} {"msg":"Current flush_numbers [0..12]: [20, 20, 21, 21, 22, 22, 23, 23, 24, 24, 25, 25]","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:33:55.117709843Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"proc"} {"msg":"Downstairs has completed Negotiation","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:33:55.117719768Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"proc"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 7a53f6e0-12b3-4219-9b9a-89097007b3df, gen: 4 } disconnected, 0 jobs left","v":0,"name":"crucible","level":40,"time":"2024-02-12T01:34:16.779240072Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"main"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 7a53f6e0-12b3-4219-9b9a-89097007b3df, gen: 4 } was previously active, clearing","v":0,"name":"crucible","level":40,"time":"2024-02-12T01:34:16.779259212Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"main"} {"msg":"connection (127.0.0.1:47854): all done","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:34:16.779273865Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157} {"msg":"accepted connection from 127.0.0.1:45765","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:34:16.82945345Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"main"} {"msg":"Connection request from 00000000-0000-0000-0000-000000000000 with version 5","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:34:16.830383347Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"proc"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 7f45a644-cb01-4cd0-8e19-9ee60fb96389, gen: 5 } connected, version 5","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:34:16.830394261Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"proc"} {"msg":"UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 7f45a644-cb01-4cd0-8e19-9ee60fb96389, gen: 5 } is now active (read-write)","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:34:16.830495319Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157} {"msg":"Current flush_numbers [0..12]: [20, 20, 21, 21, 22, 22, 23, 23, 24, 24, 25, 25]","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:34:16.831320739Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"proc"} {"msg":"Downstairs has completed Negotiation","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:34:16.831326895Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"proc"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 7f45a644-cb01-4cd0-8e19-9ee60fb96389, gen: 5 } disconnected, 0 jobs left","v":0,"name":"crucible","level":40,"time":"2024-02-12T01:35:20.952414879Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"main"} {"msg":"upstairs UpstairsConnection { upstairs_id: 00000000-0000-0000-0000-000000000000, session_id: 7f45a644-cb01-4cd0-8e19-9ee60fb96389, gen: 5 } was previously active, clearing","v":0,"name":"crucible","level":40,"time":"2024-02-12T01:35:20.952434129Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157,"task":"main"} {"msg":"connection (127.0.0.1:45765): all done","v":0,"name":"crucible","level":30,"time":"2024-02-12T01:35:20.952440916Z","hostname":"ip-10-150-1-161.us-west-2.compute.internal","pid":1157}