{"msg":"Opened existing region file \"/var/tmp/test_up/8830/region.json\"","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.040111706Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"Database read version 1","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.040351034Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"Database write version 1","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.040366241Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"UUID: 12345678-0000-0000-0000-000000008830","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.040704712Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"Blocks per extent:10 Total Extents: 5","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.04072181Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"Crucible Version: Crucible Version: 0.0.1\nCommit SHA: d5ba9129c85ea0d4e5bfb64a325148153466e54b\nCommit timestamp: 2024-03-01T16:08:17.000000000Z  branch: alan/downstairs-builder\nrustc: 1.76.0 stable x86_64-unknown-illumos\nCargo: x86_64-unknown-illumos  Debug: true Opt level: 0","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.040853496Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188,"task":"main"}
{"msg":"Upstairs <-> Downstairs Message Version: 5","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.040878622Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188,"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-03-01T16:27:47.041745931Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188,"task":"repair"}
{"msg":"listening","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.042045229Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188,"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-03-01T16:27:47.042147721Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188,"task":"main"}
{"msg":"No SSL acceptor configured","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.042166578Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188,"task":"main"}
{"msg":"downstairs listening on 0.0.0.0:8830","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.042255672Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188,"task":"main"}
{"msg":"accepted connection from 127.0.0.1:59433","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.113571862Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188,"task":"main"}
{"msg":"Connection request from 84739361-15b1-4cd3-8923-4a4362de512d with version 5","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.113980051Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188,"task":"proc"}
{"msg":"upstairs UpstairsConnection { upstairs_id: 84739361-15b1-4cd3-8923-4a4362de512d, session_id: 3d3a2f00-46eb-40d5-b893-86bcb3b44638, gen: 33 } connected, version 5","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.114013575Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188,"task":"proc"}
{"msg":"UpstairsConnection { upstairs_id: 84739361-15b1-4cd3-8923-4a4362de512d, session_id: 3d3a2f00-46eb-40d5-b893-86bcb3b44638, gen: 33 } is now active (read-write)","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.114560809Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"Current flush_numbers [0..12]: [1535, 1535, 1535, 1535, 1535]","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.115137299Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188,"task":"proc"}
{"msg":"Downstairs has completed Negotiation","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.115163015Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188,"task":"proc"}
{"msg":"Created copy dir \"/var/tmp/test_up/8830/00/000/000.copy\"","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.117853946Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"eid:0 Found repair files: [\"000\"]","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.170347436Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"Verify extent 0 still ready for copy","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.173512134Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"1 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-03-01T16:27:47.174031894Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"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-03-01T16:27:47.17543483Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"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-03-01T16:27:47.177004266Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"Created copy dir \"/var/tmp/test_up/8830/00/000/002.copy\"","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.180990061Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"eid:2 Found repair files: [\"002\"]","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.213099855Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"Verify extent 2 still ready for copy","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.215930981Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"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-03-01T16:27:47.216426755Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"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-03-01T16:27:47.217919834Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"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-03-01T16:27:47.219713182Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"Created copy dir \"/var/tmp/test_up/8830/00/000/003.copy\"","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.223778193Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"eid:3 Found repair files: [\"003\"]","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.256002517Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"Verify extent 3 still ready for copy","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.258388181Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"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-03-01T16:27:47.258811167Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"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-03-01T16:27:47.260359097Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"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-03-01T16:27:47.261845129Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"Created copy dir \"/var/tmp/test_up/8830/00/000/004.copy\"","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.266176543Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"eid:4 Found repair files: [\"004\"]","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.296541181Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"Verify extent 4 still ready for copy","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.299210216Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"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-03-01T16:27:47.299713018Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"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-03-01T16:27:47.301796775Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"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-03-01T16:27:47.303057645Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"Created copy dir \"/var/tmp/test_up/8830/00/000/001.copy\"","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.307186616Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"eid:1 Found repair files: [\"001\"]","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.33803059Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"Verify extent 1 still ready for copy","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:47.340615589Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"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-03-01T16:27:47.34106697Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"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-03-01T16:27:47.342699896Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"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-03-01T16:27:47.345022911Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}
{"msg":"upstairs UpstairsConnection { upstairs_id: 84739361-15b1-4cd3-8923-4a4362de512d, session_id: 3d3a2f00-46eb-40d5-b893-86bcb3b44638, gen: 33 } disconnected, 0 jobs left","v":0,"name":"crucible","level":40,"time":"2024-03-01T16:27:51.358043085Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188,"task":"main"}
{"msg":"upstairs UpstairsConnection { upstairs_id: 84739361-15b1-4cd3-8923-4a4362de512d, session_id: 3d3a2f00-46eb-40d5-b893-86bcb3b44638, gen: 33 } was previously active, clearing","v":0,"name":"crucible","level":40,"time":"2024-03-01T16:27:51.358100506Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188,"task":"main"}
{"msg":"connection (127.0.0.1:59433): all done","v":0,"name":"crucible","level":30,"time":"2024-03-01T16:27:51.35813094Z","hostname":"ip-10-150-1-130.us-west-2.compute.internal","pid":1188}