{"msg":"Opened existing region file \"/var/tmp/test_up/8830/region.json\"","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.303092289Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"Database read version 1","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.303361121Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"Database write version 1","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.30337986Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"UUID: 12345678-0000-0000-0000-000000008830","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.303552548Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"Blocks per extent:10 Total Extents: 5","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.303570007Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"Crucible Version: Crucible Version: 0.0.1\nCommit SHA: 80b6f734175c2bc8e222c8d6f7875e53cbd5c9ca\nCommit timestamp: 2024-02-28T21:51:33.000000000Z  branch: main\nrustc: 1.75.0 stable x86_64-unknown-illumos\nCargo: x86_64-unknown-illumos  Debug: true Opt level: 0","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.303771883Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186,"task":"main"}
{"msg":"Upstairs <-> Downstairs Message Version: 5","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.303801561Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186,"task":"main"}
{"msg":"Repair listens on 0.0.0.0:12830 for path:\"/var/tmp/test_up/8830\"","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.304734199Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186,"task":"repair"}
{"msg":"listening","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.304997101Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186,"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-28T22:15:44.305119803Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186,"task":"main"}
{"msg":"No SSL acceptor configured","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.305140802Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186,"task":"main"}
{"msg":"downstairs listening on 0.0.0.0:8830","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.30516055Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186,"task":"main"}
{"msg":"accepted connection from 127.0.0.1:49470","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.384615246Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186,"task":"main"}
{"msg":"Connection request from 9b87dbd2-8724-429c-8827-d9a59001605a with version 5","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.385015329Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186,"task":"proc"}
{"msg":"upstairs UpstairsConnection { upstairs_id: 9b87dbd2-8724-429c-8827-d9a59001605a, session_id: 337c5d07-9b8f-4125-9d4b-cd50a18c5805, gen: 33 } connected, version 5","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.385053137Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186,"task":"proc"}
{"msg":"UpstairsConnection { upstairs_id: 9b87dbd2-8724-429c-8827-d9a59001605a, session_id: 337c5d07-9b8f-4125-9d4b-cd50a18c5805, gen: 33 } is now active (read-write)","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.385557473Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"Current flush_numbers [0..12]: [1649, 1649, 1649, 1649, 1649]","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.386067669Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186,"task":"proc"}
{"msg":"Downstairs has completed Negotiation","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.386107976Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186,"task":"proc"}
{"msg":"Created copy dir \"/var/tmp/test_up/8830/00/000/003.copy\"","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.389065419Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"eid:3 Found repair files: [\"003\"]","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.459689515Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"Verify extent 3 still ready for copy","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.463624052Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"1 repair files downloaded, move directory \"/var/tmp/test_up/8830/00/000/003.copy\" to \"/var/tmp/test_up/8830/00/000/003.replace\"","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.464335675Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"Copy files from \"/var/tmp/test_up/8830/00/000/003.replace\" in \"/var/tmp/test_up/8830/00/000\"","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.465984414Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"Move directory  \"/var/tmp/test_up/8830/00/000/003.replace\" to \"/var/tmp/test_up/8830/00/000/003.completed\"","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.467760605Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"Created copy dir \"/var/tmp/test_up/8830/00/000/002.copy\"","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.471400922Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"eid:2 Found repair files: [\"002\"]","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.50327966Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"Verify extent 2 still ready for copy","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.506077703Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"1 repair files downloaded, move directory \"/var/tmp/test_up/8830/00/000/002.copy\" to \"/var/tmp/test_up/8830/00/000/002.replace\"","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.506495435Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"Copy files from \"/var/tmp/test_up/8830/00/000/002.replace\" in \"/var/tmp/test_up/8830/00/000\"","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.507931029Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"Move directory  \"/var/tmp/test_up/8830/00/000/002.replace\" to \"/var/tmp/test_up/8830/00/000/002.completed\"","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.509694671Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"Created copy dir \"/var/tmp/test_up/8830/00/000/004.copy\"","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.514122845Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"eid:4 Found repair files: [\"004\"]","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.546113915Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"Verify extent 4 still ready for copy","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.549033409Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"1 repair files downloaded, move directory \"/var/tmp/test_up/8830/00/000/004.copy\" to \"/var/tmp/test_up/8830/00/000/004.replace\"","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.549515246Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"Copy files from \"/var/tmp/test_up/8830/00/000/004.replace\" in \"/var/tmp/test_up/8830/00/000\"","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.551123189Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"Move directory  \"/var/tmp/test_up/8830/00/000/004.replace\" to \"/var/tmp/test_up/8830/00/000/004.completed\"","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.553037971Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"Created copy dir \"/var/tmp/test_up/8830/00/000/001.copy\"","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.557526341Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"eid:1 Found repair files: [\"001\"]","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.586962522Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"Verify extent 1 still ready for copy","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.589699359Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"1 repair files downloaded, move directory \"/var/tmp/test_up/8830/00/000/001.copy\" to \"/var/tmp/test_up/8830/00/000/001.replace\"","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.590215735Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"Copy files from \"/var/tmp/test_up/8830/00/000/001.replace\" in \"/var/tmp/test_up/8830/00/000\"","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.591496569Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"Move directory  \"/var/tmp/test_up/8830/00/000/001.replace\" to \"/var/tmp/test_up/8830/00/000/001.completed\"","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:44.592755654Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}
{"msg":"upstairs UpstairsConnection { upstairs_id: 9b87dbd2-8724-429c-8827-d9a59001605a, session_id: 337c5d07-9b8f-4125-9d4b-cd50a18c5805, gen: 33 } disconnected, 0 jobs left","v":0,"name":"crucible","level":40,"time":"2024-02-28T22:15:48.638468996Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186,"task":"main"}
{"msg":"upstairs UpstairsConnection { upstairs_id: 9b87dbd2-8724-429c-8827-d9a59001605a, session_id: 337c5d07-9b8f-4125-9d4b-cd50a18c5805, gen: 33 } was previously active, clearing","v":0,"name":"crucible","level":40,"time":"2024-02-28T22:15:48.638549911Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186,"task":"main"}
{"msg":"connection (127.0.0.1:49470): all done","v":0,"name":"crucible","level":30,"time":"2024-02-28T22:15:48.638591848Z","hostname":"ip-10-150-1-146.us-west-2.compute.internal","pid":1186}