{"msg":"Opened existing region file \"/var/tmp/test_up/8830/region.json\"","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.663788276Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186} {"msg":"Database read version 1","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.664030492Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186} {"msg":"Database write version 1","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.664050401Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186} {"msg":"UUID: 12345678-0000-0000-0000-000000008830","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.670179284Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186} {"msg":"Blocks per extent:10 Total Extents: 5","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.67024238Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186} {"msg":"Crucible Version: Crucible Version: 0.0.1\nCommit SHA: 56eb785b9570b1bd468a7aafe9af80e337a7fae9\nCommit timestamp: 2024-02-15T02:33:40.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-15T03:01:33.670572351Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186,"task":"main"} {"msg":"Upstairs <-> Downstairs Message Version: 5","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.670638458Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186,"task":"main"} {"msg":"Repair listens on 0.0.0.0:12830","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.671775233Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186,"task":"repair"} {"msg":"listening","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.672067227Z","hostname":"ip-10-150-1-38.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-15T03:01:33.67218945Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186,"task":"main"} {"msg":"No SSL acceptor configured","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.672222848Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186,"task":"main"} {"msg":"listening on 0.0.0.0:8830","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.672291024Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186,"task":"main"} {"msg":"accepted connection from 127.0.0.1:56911","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.742245089Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186,"task":"main"} {"msg":"Connection request from ea928a12-cf6d-4da3-9292-abd6a3d96e74 with version 5","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.742964199Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186,"task":"proc"} {"msg":"upstairs UpstairsConnection { upstairs_id: ea928a12-cf6d-4da3-9292-abd6a3d96e74, session_id: f4af6c5f-f0cc-4c7c-9622-de7088e0273b, gen: 33 } connected, version 5","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.743061523Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186,"task":"proc"} {"msg":"UpstairsConnection { upstairs_id: ea928a12-cf6d-4da3-9292-abd6a3d96e74, session_id: f4af6c5f-f0cc-4c7c-9622-de7088e0273b, gen: 33 } is now active (read-write)","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.743507568Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186} {"msg":"Current flush_numbers [0..12]: [1668, 1668, 1668, 1668, 1668]","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.744021109Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186,"task":"proc"} {"msg":"Downstairs has completed Negotiation","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.744048477Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186,"task":"proc"} {"msg":"Created copy dir \"/var/tmp/test_up/8830/00/000/004.copy\"","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.74541912Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186} {"msg":"eid:4 Found repair files: [\"004\"]","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.796156104Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186} {"msg":"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-15T03:01:33.799553012Z","hostname":"ip-10-150-1-38.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-15T03:01:33.801120963Z","hostname":"ip-10-150-1-38.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-15T03:01:33.802392031Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186} {"msg":"Created copy dir \"/var/tmp/test_up/8830/00/000/000.copy\"","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.806260502Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186} {"msg":"eid:0 Found repair files: [\"000\"]","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.838291576Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186} {"msg":"Repair files downloaded, move directory \"/var/tmp/test_up/8830/00/000/000.copy\" to \"/var/tmp/test_up/8830/00/000/000.replace\"","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.840578687Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186} {"msg":"Copy files from \"/var/tmp/test_up/8830/00/000/000.replace\" in \"/var/tmp/test_up/8830/00/000\"","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.842305139Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186} {"msg":"Move directory \"/var/tmp/test_up/8830/00/000/000.replace\" to \"/var/tmp/test_up/8830/00/000/000.completed\"","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.843451764Z","hostname":"ip-10-150-1-38.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-15T03:01:33.8491637Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186} {"msg":"eid:2 Found repair files: [\"002\"]","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.879368268Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186} {"msg":"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-15T03:01:33.882002919Z","hostname":"ip-10-150-1-38.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-15T03:01:33.884242092Z","hostname":"ip-10-150-1-38.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-15T03:01:33.885474192Z","hostname":"ip-10-150-1-38.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-15T03:01:33.905123799Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186} {"msg":"eid:1 Found repair files: [\"001\"]","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:33.935494517Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186} {"msg":"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-15T03:01:33.937885232Z","hostname":"ip-10-150-1-38.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-15T03:01:33.93934093Z","hostname":"ip-10-150-1-38.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-15T03:01:33.940790617Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186} {"msg":"upstairs UpstairsConnection { upstairs_id: ea928a12-cf6d-4da3-9292-abd6a3d96e74, session_id: f4af6c5f-f0cc-4c7c-9622-de7088e0273b, gen: 33 } disconnected, 0 jobs left","v":0,"name":"crucible","level":40,"time":"2024-02-15T03:01:37.990645517Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186,"task":"main"} {"msg":"upstairs UpstairsConnection { upstairs_id: ea928a12-cf6d-4da3-9292-abd6a3d96e74, session_id: f4af6c5f-f0cc-4c7c-9622-de7088e0273b, gen: 33 } was previously active, clearing","v":0,"name":"crucible","level":40,"time":"2024-02-15T03:01:37.990716873Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186,"task":"main"} {"msg":"connection (127.0.0.1:56911): all done","v":0,"name":"crucible","level":30,"time":"2024-02-15T03:01:37.990745091Z","hostname":"ip-10-150-1-38.us-west-2.compute.internal","pid":1186}