/var/tmp/bins/dsc create --encrypted --cleanup --region-count 6 --extent-size 10 --extent-count 5 --output-dir /tmp/test_up-build/dsc --ds-bin /var/tmp/bins/crucible-downstairs --region-dir /var/tmp/test_up-build Removing existing dsc directory "/tmp/test_up-build/dsc" Creating region directory at: /var/tmp/test_up-build Creating dsc directory at: /tmp/test_up-build/dsc Downstairs region 0 created at /var/tmp/test_up-build/8810 in 0.18270317 Downstairs region 1 created at /var/tmp/test_up-build/8820 in 0.012787281 Downstairs region 2 created at /var/tmp/test_up-build/8830 in 0.011366325 Downstairs region 3 created at /var/tmp/test_up-build/8840 in 0.010813851 Downstairs region 4 created at /var/tmp/test_up-build/8850 in 0.010950533 Downstairs region 5 created at /var/tmp/test_up-build/8860 in 0.010718238 Created 6 regions /var/tmp/bins/dsc start --output-dir /tmp/test_up-build/dsc --ds-bin /var/tmp/bins/crucible-downstairs --region-dir /var/tmp/test_up-build --region-count 6 Using existing output directory "/tmp/test_up-build/dsc" Update our region info with: Some(RegionExtentInfo { block_size: 4096, blocks_per_extent: 10, extent_count: 5 }) start ds: 8810 start ds: 8820 start ds: 8830 start ds: 8840 start ds: 8850 start ds: 8860 start access at:127.0.0.1:9998 Starting downstairs at port 8830 Starting downstairs at port 8810 Starting downstairs at port 8820 Starting downstairs at port 8840 Make output file at "/tmp/test_up-build/dsc/downstairs-8810.txt" Starting downstairs at port 8850 Make output file at "/tmp/test_up-build/dsc/downstairs-8830.txt" Make output file at "/tmp/test_up-build/dsc/downstairs-8840.txt" Make output file at "/tmp/test_up-build/dsc/downstairs-8820.txt" Make output file at "/tmp/test_up-build/dsc/downstairs-8850.txt" Starting downstairs at port 8860 Make output file at "/tmp/test_up-build/dsc/downstairs-8860.txt" [8830][2] initial start wait reports Starting [8810][0] initial start wait reports Starting [8820][1] initial start wait reports Starting [8840][3] initial start wait reports Starting [8850][4] initial start wait reports Starting [8860][5] initial start wait reports Starting Downstairs /var/tmp/test_up-build/8860 port 8860 PID:Some(1141) [8860][5] initial start wait reports Running Downstairs /var/tmp/test_up-build/8840 port 8840 PID:Some(1145) Downstairs /var/tmp/test_up-build/8820 port 8820 PID:Some(1144) [8840][3] initial start wait reports Running [8820][1] initial start wait reports Running Downstairs /var/tmp/test_up-build/8830 port 8830 PID:Some(1143) Downstairs /var/tmp/test_up-build/8850 port 8850 PID:Some(1142) [8830][2] initial start wait reports Running [8850][4] initial start wait reports Running Downstairs /var/tmp/test_up-build/8810 port 8810 PID:Some(1146) [8810][0] initial start wait reports Running All downstairs are running Apr 15 18:10:15.127 INFO listening, local_addr: 127.0.0.1:9998 Control access at:127.0.0.1:9998 Apr 15 18:10:20.136 INFO accepted connection, remote_addr: 127.0.0.1:56480, local_addr: 127.0.0.1:9998 Apr 15 18:10:20.137 INFO request completed, latency_us: 419, response_code: 200, uri: /state/cid/0, method: GET, req_id: dff1bbbb-8816-45ce-82a2-68a8b359054b, remote_addr: 127.0.0.1:56480, local_addr: 127.0.0.1:9998 Apr 15 18:10:20.151 INFO accepted connection, remote_addr: 127.0.0.1:37312, local_addr: 127.0.0.1:9998 Apr 15 18:10:20.151 INFO request completed, latency_us: 68, response_code: 200, uri: /state/cid/1, method: GET, req_id: f63d2c63-cb96-463a-a90b-6b9cb5c71295, remote_addr: 127.0.0.1:37312, local_addr: 127.0.0.1:9998 Apr 15 18:10:20.164 INFO accepted connection, remote_addr: 127.0.0.1:64694, local_addr: 127.0.0.1:9998 Apr 15 18:10:20.165 INFO request completed, latency_us: 73, response_code: 200, uri: /state/cid/2, method: GET, req_id: 97a1fb94-eec0-4240-96af-adf6bdba691c, remote_addr: 127.0.0.1:64694, local_addr: 127.0.0.1:9998 Apr 15 18:10:20.178 INFO accepted connection, remote_addr: 127.0.0.1:52533, local_addr: 127.0.0.1:9998 Apr 15 18:10:20.178 INFO request completed, latency_us: 65, response_code: 200, uri: /state/cid/3, method: GET, req_id: 114fa9be-c9fc-4d9a-8528-37bface7f12b, remote_addr: 127.0.0.1:52533, local_addr: 127.0.0.1:9998 Apr 15 18:10:20.191 INFO accepted connection, remote_addr: 127.0.0.1:32927, local_addr: 127.0.0.1:9998 Apr 15 18:10:20.191 INFO request completed, latency_us: 71, response_code: 200, uri: /state/cid/4, method: GET, req_id: 8dd3a335-de25-4729-8109-254686253c94, remote_addr: 127.0.0.1:32927, local_addr: 127.0.0.1:9998 Apr 15 18:10:20.204 INFO accepted connection, remote_addr: 127.0.0.1:50480, local_addr: 127.0.0.1:9998 Apr 15 18:10:20.205 INFO request completed, latency_us: 67, response_code: 200, uri: /state/cid/5, method: GET, req_id: 357b1305-e6dc-439b-b466-0ae81ff7b14d, remote_addr: 127.0.0.1:50480, local_addr: 127.0.0.1:9998 Apr 15 18:10:20.217 INFO accepted connection, remote_addr: 127.0.0.1:65355, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc DisableRestartAll disable restart on all: 6 Apr 15 18:10:20.218 INFO request completed, latency_us: 75, response_code: 204, uri: /disablerestart/all, method: POST, req_id: b2461ffa-c9b3-43a5-a008-6694331d15a8, remote_addr: 127.[8810] Disable keep_running 0.0.1:65355, [8830] Disable keep_running local_addr: 127.0.0.[8820] Disable keep_running 1[8840] Disable keep_running :[8860] Disable keep_running 9998[8850] Disable keep_running Apr 15 18:10:20.284 INFO accepted connection, remote_addr: 127.0.0.1:64193, local_addr: 127.0.0.1:9998 Apr 15 18:10:20.285 INFO request completed, latency_us: 257, response_code: 200, uri: /regioninfo, method: GET, req_id: ff36e483-46a6-4c9c-92ce-a66de5d9dd57, remote_addr: 127.0.0.1:64193, local_addr: 127.0.0.1:9998 Apr 15 18:10:20.287 INFO request completed, latency_us: 34, response_code: 200, uri: /regioncount, method: GET, req_id: b9b144a9-318f-48dd-b8d9-2ac688621c2b, remote_addr: 127.0.0.1:64193, local_addr: 127.0.0.1:9998 Apr 15 18:10:20.287 INFO request completed, latency_us: 68, response_code: 200, uri: /port/cid/0, method: GET, req_id: fd396534-95e9-4b7d-9da2-6ab1a5c1756e, remote_addr: 127.0.0.1:64193, local_addr: 127.0.0.1:9998 Apr 15 18:10:20.287 INFO request completed, latency_us: 28, response_code: 200, uri: /port/cid/1, method: GET, req_id: 045735fc-f4f9-43cc-b142-b98a8911bc72, remote_addr: 127.0.0.1:64193, local_addr: 127.0.0.1:9998 Apr 15 18:10:20.288 INFO request completed, latency_us: 24, response_code: 200, uri: /port/cid/2, method: GET, req_id: 93e97437-40d4-4fe2-b201-611cdaf71e06, remote_addr: 127.0.0.1:64193, local_addr: 127.0.0.1:9998 Apr 15 18:10:20.292 INFO request completed, latency_us: 49, response_code: 200, uri: /port/cid/3, method: GET, req_id: 49f287ac-51bc-4c69-acce-3a15d10843c8, remote_addr: 127.0.0.1:64193, local_addr: 127.0.0.1:9998 Apr 15 18:10:20.293 INFO request completed, latency_us: 26, response_code: 200, uri: /port/cid/4, method: GET, req_id: d7058f9a-d533-4f8c-b826-3ee50d74cbf5, remote_addr: 127.0.0.1:64193, local_addr: 127.0.0.1:9998 Apr 15 18:10:20.293 INFO request completed, latency_us: 22, response_code: 200, uri: /port/cid/5, method: GET, req_id: 78291acd-6b3f-4ae9-b515-1bda8f86d35c, remote_addr: 127.0.0.1:64193, local_addr: 127.0.0.1:9998 Apr 15 18:10:29.353 INFO accepted connection, remote_addr: 127.0.0.1:60682, local_addr: 127.0.0.1:9998 Apr 15 18:10:29.354 INFO request completed, latency_us: 156, response_code: 200, uri: /regioninfo, method: GET, req_id: 42e3ef1e-550f-49dc-9b5d-6d9c9c1c7a54, remote_addr: 127.0.0.1:60682, local_addr: 127.0.0.1:9998 Apr 15 18:10:29.354 INFO request completed, latency_us: 52, response_code: 200, uri: /regioncount, method: GET, req_id: bc2427b1-c708-4017-bd88-90ee74f68494, remote_addr: 127.0.0.1:60682, local_addr: 127.0.0.1:9998 Apr 15 18:10:29.355 INFO request completed, latency_us: 68, response_code: 200, uri: /port/cid/0, method: GET, req_id: ff4037ae-a976-47af-99b2-4b4595a940c5, remote_addr: 127.0.0.1:60682, local_addr: 127.0.0.1:9998 Apr 15 18:10:29.355 INFO request completed, latency_us: 37, response_code: 200, uri: /port/cid/1, method: GET, req_id: 7962f626-a235-4719-ba12-6b9803e0dd72, remote_addr: 127.0.0.1:60682, local_addr: 127.0.0.1:9998 Apr 15 18:10:29.355 INFO request completed, latency_us: 35, response_code: 200, uri: /port/cid/2, method: GET, req_id: 79fb0217-f4c0-44ae-9b1b-58588a0f316d, remote_addr: 127.0.0.1:60682, local_addr: 127.0.0.1:9998 Apr 15 18:10:29.357 INFO request completed, latency_us: 67, response_code: 200, uri: /port/cid/3, method: GET, req_id: 9e6be00c-117c-4742-ae00-b0d486305c2a, remote_addr: 127.0.0.1:60682, local_addr: 127.0.0.1:9998 Apr 15 18:10:29.357 INFO request completed, latency_us: 70, response_code: 200, uri: /port/cid/4, method: GET, req_id: 04feee7c-7fe7-4499-a6ad-994871881216, remote_addr: 127.0.0.1:60682, local_addr: 127.0.0.1:9998 Apr 15 18:10:29.358 INFO request completed, latency_us: 62, response_code: 200, uri: /port/cid/5, method: GET, req_id: c5721b09-eb03-49b9-b328-75167428f2a1, remote_addr: 127.0.0.1:60682, local_addr: 127.0.0.1:9998 Apr 15 18:10:38.667 INFO accepted connection, remote_addr: 127.0.0.1:62860, local_addr: 127.0.0.1:9998 Apr 15 18:10:38.668 INFO request completed, latency_us: 154, response_code: 200, uri: /regioninfo, method: GET, req_id: ad0b81f3-db5e-4b60-879e-ff5dca08947a, remote_addr: 127.0.0.1:62860, local_addr: 127.0.0.1:9998 Apr 15 18:10:38.669 INFO request completed, latency_us: 37, response_code: 200, uri: /regioncount, method: GET, req_id: 9d4cc18b-ce8c-45ea-a671-5196980dd48e, remote_addr: 127.0.0.1:62860, local_addr: 127.0.0.1:9998 Apr 15 18:10:38.669 INFO request completed, latency_us: 67, response_code: 200, uri: /port/cid/0, method: GET, req_id: 85291fdd-28e6-46c4-ab1e-a6f47ae28dfc, remote_addr: 127.0.0.1:62860, local_addr: 127.0.0.1:9998 Apr 15 18:10:38.669 INFO request completed, latency_us: 32, response_code: 200, uri: /port/cid/1, method: GET, req_id: c31bea13-cbdb-40e9-ae3b-ecd141c47699, remote_addr: 127.0.0.1:62860, local_addr: 127.0.0.1:9998 Apr 15 18:10:38.669 INFO request completed, latency_us: 37, response_code: 200, uri: /port/cid/2, method: GET, req_id: b974a447-e1af-4920-a0d5-f5e92b14a918, remote_addr: 127.0.0.1:62860, local_addr: 127.0.0.1:9998 Apr 15 18:10:38.671 INFO request completed, latency_us: 55, response_code: 200, uri: /port/cid/3, method: GET, req_id: 2e855a26-b2cc-4c8c-9bd6-cdb0e234f020, remote_addr: 127.0.0.1:62860, local_addr: 127.0.0.1:9998 Apr 15 18:10:38.671 INFO request completed, latency_us: 33, response_code: 200, uri: /port/cid/4, method: GET, req_id: dd15af17-c82e-4a3f-911a-c99c61dad685, remote_addr: 127.0.0.1:62860, local_addr: 127.0.0.1:9998 Apr 15 18:10:38.671 INFO request completed, latency_us: 29, response_code: 200, uri: /port/cid/5, method: GET, req_id: 3d18f209-8dc6-43a8-92bc-c373938daf56, remote_addr: 127.0.0.1:62860, local_addr: 127.0.0.1:9998 Apr 15 18:11:03.511 INFO accepted connection, remote_addr: 127.0.0.1:44741, local_addr: 127.0.0.1:9998 Apr 15 18:11:03.512 INFO request completed, latency_us: 155, response_code: 200, uri: /regioninfo, method: GET, req_id: 1dd6ce44-9d9c-4e37-ab11-63ec7971a699, remote_addr: 127.0.0.1:44741, local_addr: 127.0.0.1:9998 Apr 15 18:11:03.513 INFO request completed, latency_us: 99, response_code: 200, uri: /regioncount, method: GET, req_id: 3e191b0f-d1e7-4567-9102-035e10da792f, remote_addr: 127.0.0.1:44741, local_addr: 127.0.0.1:9998 Apr 15 18:11:03.513 INFO request completed, latency_us: 97, response_code: 200, uri: /port/cid/0, method: GET, req_id: 2ba102bc-a828-4560-b013-2ad12fb82f47, remote_addr: 127.0.0.1:44741, local_addr: 127.0.0.1:9998 Apr 15 18:11:03.514 INFO request completed, latency_us: 63, response_code: 200, uri: /port/cid/1, method: GET, req_id: 174f332f-aa2f-4d49-aee6-7e1873d78ea5, remote_addr: 127.0.0.1:44741, local_addr: 127.0.0.1:9998 Apr 15 18:11:03.514 INFO request completed, latency_us: 62, response_code: 200, uri: /port/cid/2, method: GET, req_id: 93af9883-e870-4e74-98c7-18db498b7620, remote_addr: 127.0.0.1:44741, local_addr: 127.0.0.1:9998 Apr 15 18:11:03.516 INFO request completed, latency_us: 69, response_code: 200, uri: /port/cid/3, method: GET, req_id: a8410786-6143-4403-9abe-9c4d744f57eb, remote_addr: 127.0.0.1:44741, local_addr: 127.0.0.1:9998 Apr 15 18:11:03.516 INFO request completed, latency_us: 64, response_code: 200, uri: /port/cid/4, method: GET, req_id: b042b841-8a30-4109-9513-80a41b95c130, remote_addr: 127.0.0.1:44741, local_addr: 127.0.0.1:9998 Apr 15 18:11:03.516 INFO request completed, latency_us: 67, response_code: 200, uri: /port/cid/5, method: GET, req_id: 36d3f35f-ee33-4811-a4d3-628aa5a21d74, remote_addr: 127.0.0.1:44741, local_addr: 127.0.0.1:9998 Apr 15 18:13:36.432 INFO accepted connection, remote_addr: 127.0.0.1:44546, local_addr: 127.0.0.1:9998 Apr 15 18:13:36.433 INFO request completed, latency_us: 196, response_code: 200, uri: /regioninfo, method: GET, req_id: 8fa74c6c-e7bc-440d-947a-b2f0f707a86f, remote_addr: 127.0.0.1:44546, local_addr: 127.0.0.1:9998 Apr 15 18:13:36.433 INFO request completed, latency_us: 48, response_code: 200, uri: /regioncount, method: GET, req_id: 198566c2-e81d-474d-9e61-f7c6607c5e72, remote_addr: 127.0.0.1:44546, local_addr: 127.0.0.1:9998 Apr 15 18:13:36.434 INFO request completed, latency_us: 71, response_code: 200, uri: /port/cid/0, method: GET, req_id: 9b6a4d8a-da15-45a8-a031-bf205167ddc3, remote_addr: 127.0.0.1:44546, local_addr: 127.0.0.1:9998 Apr 15 18:13:36.434 INFO request completed, latency_us: 43, response_code: 200, uri: /port/cid/1, method: GET, req_id: f723f5a4-6a5e-4126-b4d1-d5b764992bbe, remote_addr: 127.0.0.1:44546, local_addr: 127.0.0.1:9998 Apr 15 18:13:36.434 INFO request completed, latency_us: 38, response_code: 200, uri: /port/cid/2, method: GET, req_id: b082d3ff-f56d-45f4-9f01-5508007465f9, remote_addr: 127.0.0.1:44546, local_addr: 127.0.0.1:9998 Apr 15 18:13:36.436 INFO request completed, latency_us: 97, response_code: 200, uri: /port/cid/3, method: GET, req_id: aa013062-9b18-4cae-aa2b-adfbee6e6159, remote_addr: 127.0.0.1:44546, local_addr: 127.0.0.1:9998 Apr 15 18:13:36.436 INFO request completed, latency_us: 48, response_code: 200, uri: /port/cid/4, method: GET, req_id: fce0c59f-ac85-465e-892c-c4a781ee3d1e, remote_addr: 127.0.0.1:44546, local_addr: 127.0.0.1:9998 Apr 15 18:13:36.436 INFO request completed, latency_us: 41, response_code: 200, uri: /port/cid/5, method: GET, req_id: d5bb7ee2-deb7-4f72-8b92-41e9b9c69e95, remote_addr: 127.0.0.1:44546, local_addr: 127.0.0.1:9998 Apr 15 18:13:58.344 INFO accepted connection, remote_addr: 127.0.0.1:46767, local_addr: 127.0.0.1:9998 Apr 15 18:13:58.344 INFO request completed, latency_us: 156, response_code: 200, uri: /regioninfo, method: GET, req_id: 5833c561-4106-4f87-96a0-98305328394e, remote_addr: 127.0.0.1:46767, local_addr: 127.0.0.1:9998 Apr 15 18:13:58.345 INFO request completed, latency_us: 63, response_code: 200, uri: /regioncount, method: GET, req_id: ddd4bbb7-7e91-4a39-884a-04c0525b9894, remote_addr: 127.0.0.1:46767, local_addr: 127.0.0.1:9998 Apr 15 18:13:58.345 INFO request completed, latency_us: 65, response_code: 200, uri: /port/cid/0, method: GET, req_id: e9a8084a-7899-496e-9396-32491a6acf13, remote_addr: 127.0.0.1:46767, local_addr: 127.0.0.1:9998 Apr 15 18:13:58.345 INFO request completed, latency_us: 33, response_code: 200, uri: /port/cid/1, method: GET, req_id: a04417fa-962c-4c6f-aa11-2467c1bd6539, remote_addr: 127.0.0.1:46767, local_addr: 127.0.0.1:9998 Apr 15 18:13:58.345 INFO request completed, latency_us: 29, response_code: 200, uri: /port/cid/2, method: GET, req_id: 84079425-9c24-4d2d-81ac-b2c5b44e1574, remote_addr: 127.0.0.1:46767, local_addr: 127.0.0.1:9998 Apr 15 18:13:58.347 INFO request completed, latency_us: 48, response_code: 200, uri: /port/cid/3, method: GET, req_id: a9a26d16-2f31-4153-adee-9498f5d67a55, remote_addr: 127.0.0.1:46767, local_addr: 127.0.0.1:9998 Apr 15 18:13:58.347 INFO request completed, latency_us: 29, response_code: 200, uri: /port/cid/4, method: GET, req_id: b22cd112-ba58-4985-92ea-c9002e4e80fe, remote_addr: 127.0.0.1:46767, local_addr: 127.0.0.1:9998 Apr 15 18:13:58.347 INFO request completed, latency_us: 28, response_code: 200, uri: /port/cid/5, method: GET, req_id: c9650edb-ab0d-4926-951e-24e75cbe7297, remote_addr: 127.0.0.1:46767, local_addr: 127.0.0.1:9998 Apr 15 18:14:02.457 INFO accepted connection, remote_addr: 127.0.0.1:62368, local_addr: 127.0.0.1:9998 Apr 15 18:14:02.457 INFO request completed, latency_us: 158, response_code: 200, uri: /regioninfo, method: GET, req_id: c57abe71-137b-4b26-ba24-1d94cad440af, remote_addr: 127.0.0.1:62368, local_addr: 127.0.0.1:9998 Apr 15 18:14:02.458 INFO request completed, latency_us: 86, response_code: 200, uri: /regioncount, method: GET, req_id: 755adcf9-720c-46c6-93c8-e675d24c51d9, remote_addr: 127.0.0.1:62368, local_addr: 127.0.0.1:9998 Apr 15 18:14:02.458 INFO request completed, latency_us: 83, response_code: 200, uri: /port/cid/0, method: GET, req_id: c0c87222-4c26-478e-a933-3edc87eb495b, remote_addr: 127.0.0.1:62368, local_addr: 127.0.0.1:9998 Apr 15 18:14:02.459 INFO request completed, latency_us: 39, response_code: 200, uri: /port/cid/1, method: GET, req_id: 2ecfcaed-9209-490c-8b56-3f7bc62ce93b, remote_addr: 127.0.0.1:62368, local_addr: 127.0.0.1:9998 Apr 15 18:14:02.459 INFO request completed, latency_us: 30, response_code: 200, uri: /port/cid/2, method: GET, req_id: 62710663-811b-46ba-a3b6-379f7c9f23fa, remote_addr: 127.0.0.1:62368, local_addr: 127.0.0.1:9998 Apr 15 18:14:02.460 INFO request completed, latency_us: 59, response_code: 200, uri: /port/cid/3, method: GET, req_id: 5a297eed-96f2-4025-b0c3-4196e319caaf, remote_addr: 127.0.0.1:62368, local_addr: 127.0.0.1:9998 Apr 15 18:14:02.460 INFO request completed, latency_us: 43, response_code: 200, uri: /port/cid/4, method: GET, req_id: 39f29a16-e2b9-40ef-ad04-a14265183381, remote_addr: 127.0.0.1:62368, local_addr: 127.0.0.1:9998 Apr 15 18:14:02.461 INFO request completed, latency_us: 33, response_code: 200, uri: /port/cid/5, method: GET, req_id: 73717f22-099b-4fb5-ae79-d0813d2e754b, remote_addr: 127.0.0.1:62368, local_addr: 127.0.0.1:9998 Apr 15 18:14:06.557 INFO accepted connection, remote_addr: 127.0.0.1:50684, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc DisableRestartAll disable restart on all: 6 Apr 15 18:14:06.558 INFO request completed, latency_us: 140, response_code: 204, uri: /disablerestart/all, method: POST, req_id: c82b9e81-ee83-46bc-ab41-421920bd7ffd[8820] Disable keep_running , remote_addr: [8810] Disable keep_running 127[8830] Disable keep_running .[8850] Disable keep_running 0.0.1:50684[8860] Disable keep_running , local_addr: 127.[8840] Disable keep_running 0.0.1:9998 Apr 15 18:14:06.571 INFO accepted connection, remote_addr: 127.0.0.1:61629, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(2) stop 2 Apr 15 18:14:06.571 INFO request completed, latency_us: 136, response_code: 204, uri: /stop/cid/2, method: POST, req_id: 08c7d0ca-a248-44eb-94ce-892fa3b689dc, remote_addr: 127.0.0.1:61629, local_addr: 127.0.0.1:9998 [8830] Got stop action so:false kr:false [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit Apr 15 18:14:06.585 INFO accepted connection, remote_addr: 127.0.0.1:36365, local_addr: 127.0.0.1:9998 Apr 15 18:14:06.585 INFO request completed, latency_us: 85, response_code: 200, uri: /state/cid/2, method: GET, req_id: fa64df5d-fcb8-4df0-b4a2-f55db33a00cd, remote_addr: 127.0.0.1:36365, local_addr: 127.0.0.1:9998 Apr 15 18:14:06.601 INFO accepted connection, remote_addr: 127.0.0.1:46722, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(2) start 2 Apr 15 18:14:06.601 INFO request completed, latency_us: 80, response_code: 204, uri: /start/cid/2, method: POST, req_id: f48809ee-12bf-40b6-98c7-55655f4910e6, remote_addr: 127.0.0.1:46722, local_addr: 127.0.0.1:9998 Apr 15 18:14:06.633 INFO accepted connection, remote_addr: 127.0.0.1:47507, local_addr: 127.0.0.1:9998 Apr 15 18:14:06.634 INFO request completed, latency_us: 116, response_code: 200, uri: /regioninfo, method: GET, req_id: db40c67d-a361-4377-9115-4f8c528c3279, remote_addr: 127.0.0.1:47507, local_addr: 127.0.0.1:9998 Apr 15 18:14:06.634 INFO request completed, latency_us: 63, response_code: 200, uri: /regioncount, method: GET, req_id: 1569f8c1-fc65-4338-8f25-f2e72a163752, remote_addr: 127.0.0.1:47507, local_addr: 127.0.0.1:9998 Apr 15 18:14:06.635 INFO request completed, latency_us: 72, response_code: 200, uri: /port/cid/0, method: GET, req_id: fa9d70c5-5d11-4aca-ae77-ef93609d4f08, remote_addr: 127.0.0.1:47507, local_addr: 127.0.0.1:9998 Apr 15 18:14:06.635 INFO request completed, latency_us: 58, response_code: 200, uri: /port/cid/1, method: GET, req_id: 82ef1452-dabb-4ddf-b106-c549ffce1c82, remote_addr: 127.0.0.1:47507, local_addr: 127.0.0.1:9998 Apr 15 18:14:06.635 INFO request completed, latency_us: 65, response_code: 200, uri: /port/cid/2, method: GET, req_id: 61d6a02b-4fa2-4990-a5ca-9f954562bbd0, remote_addr: 127.0.0.1:47507, local_addr: 127.0.0.1:9998 Apr 15 18:14:06.637 INFO request completed, latency_us: 73, response_code: 200, uri: /port/cid/3, method: GET, req_id: d2f2ea21-000b-476a-b462-eb4535b5b14b, remote_addr: 127.0.0.1:47507, local_addr: 127.0.0.1:9998 Apr 15 18:14:06.637 INFO request completed, latency_us: 38, response_code: 200, uri: /port/cid/4, method: GET, req_id: 54da0751-60bf-4bc2-9587-97b811bb3fc3, remote_addr: 127.0.0.1:47507, local_addr: 127.0.0.1:9998 Apr 15 18:14:06.637 INFO request completed, latency_us: 28, response_code: 200, uri: /port/cid/5, method: GET, req_id: dedc842b-9963-410f-9f1b-3c4217eff907, remote_addr: 127.0.0.1:47507, local_addr: 127.0.0.1:9998 [8830] got start action [8830] I am going to restart Starting downstairs at port 8830 Make output file at "/tmp/test_up-build/dsc/downstairs-8830.txt" [8830][2] reports Starting Downstairs /var/tmp/test_up-build/8830 port 8830 PID:Some(1197) [8830][2] reports Running Apr 15 18:14:20.773 INFO accepted connection, remote_addr: 127.0.0.1:55878, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Shutdown Shutdown Shut it down Apr 15 18:14:20.773 INFO request completed, latency_us: 132, response_code: 204, uri: /shutdown, method: POST, [8810] Disable keep_running req_id[8820] Disable keep_running :[8840] Disable keep_running [8850] Disable keep_running 34cb99d3-b276-4334-85ef-67739eea212a[8840] Got stop action so:false kr:false , [8860] Disable keep_running remote_addr: [8850] Got stop action so:false kr:false 127.0.0.1:[8820] Got stop action so:false kr:false 55878, local_addr: 127.0.0.1:9998 [8830] Disable keep_running [8830] Got stop action so:false kr:false [8860] Got stop action so:false kr:false [8810] Got stop action so:false kr:false [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit [8860] Exited with: ExitStatus(unix_wait_status(9)) [8850] Exited with: ExitStatus(unix_wait_status(9)) [8860][5] reports Exit [8840] Exited with: ExitStatus(unix_wait_status(9)) [8850][4] reports Exit [8840][3] reports Exit [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] reports Exit [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit /var/tmp/bins/dsc create --encrypted --cleanup --region-count 7 --extent-count 400 --block-size 4096 --output-dir /tmp/test_up-build/dsc --ds-bin /var/tmp/bins/crucible-downstairs --region-dir /var/tmp/test_up-build Removing existing dsc directory "/tmp/test_up-build/dsc" Removing existing region "/var/tmp/test_up-build" Creating region directory at: /var/tmp/test_up-build Creating dsc directory at: /tmp/test_up-build/dsc Downstairs region 0 created at /var/tmp/test_up-build/8810 in 0.029919133 Downstairs region 1 created at /var/tmp/test_up-build/8820 in 0.026471643 Downstairs region 2 created at /var/tmp/test_up-build/8830 in 0.025819836 Downstairs region 3 created at /var/tmp/test_up-build/8840 in 0.025546413 Downstairs region 4 created at /var/tmp/test_up-build/8850 in 0.025039647 Downstairs region 5 created at /var/tmp/test_up-build/8860 in 0.024647832 Downstairs region 6 created at /var/tmp/test_up-build/8870 in 0.03663299 Created 7 regions /var/tmp/bins/dsc start --region-count 7 --output-dir /tmp/test_up-build/dsc --ds-bin /var/tmp/bins/crucible-downstairs --region-dir /var/tmp/test_up-build Using existing output directory "/tmp/test_up-build/dsc" Update our region info with: Some(RegionExtentInfo { block_size: 4096, blocks_per_extent: 100, extent_count: 400 }) start ds: 8810 start access at:127.0.0.1:9998 start ds: 8820 start ds: 8830 start ds: 8840 start ds: 8850 start ds: 8860 Starting downstairs at port 8810 Starting downstairs at port 8820 start ds: 8870 Starting downstairs at port 8830 Make output file at "/tmp/test_up-build/dsc/downstairs-8810.txt" Make output file at "/tmp/test_up-build/dsc/downstairs-8820.txt" Make output file at "/tmp/test_up-build/dsc/downstairs-8830.txt" [8810][0] initial start wait reports Starting Starting downstairs at port 8840 [8820][1] initial start wait reports Starting [8830][2] initial start wait reports Starting Make output file at "/tmp/test_up-build/dsc/downstairs-8840.txt" [8840][3] initial start wait reports Starting Starting downstairs at port 8850 Make output file at "/tmp/test_up-build/dsc/downstairs-8850.txt" Starting downstairs at port 8860 [8850][4] initial start wait reports Starting Make output file at "/tmp/test_up-build/dsc/downstairs-8860.txt" [8860][5] initial start wait reports Starting Starting downstairs at port 8870 Make output file at "/tmp/test_up-build/dsc/downstairs-8870.txt" [8870][6] initial start wait reports Starting Downstairs /var/tmp/test_up-build/8830 port 8830 PID:Some(1214) [8830][2] initial start wait reports Running Downstairs /var/tmp/test_up-build/8870 port 8870 PID:Some(1216) [8870][6] initial start wait reports Running Downstairs /var/tmp/test_up-build/8820 port 8820 PID:Some(1217) [8820][1] initial start wait reports Running Downstairs /var/tmp/test_up-build/8810 port 8810 PID:Some(1219) [8810][0] initial start wait reports Running Downstairs /var/tmp/test_up-build/8860 port 8860 PID:Some(1215) [8860][5] initial start wait reports Running Downstairs /var/tmp/test_up-build/8840 port 8840 PID:Some(1220) [8840][3] initial start wait reports Running Control access at:127.0.0.1:9998 Apr 15 18:14:25.991 INFO listening, local_addr: 127.0.0.1:9998 Downstairs /var/tmp/test_up-build/8850 port 8850 PID:Some(1218) [8850][4] initial start wait reports Running All downstairs are running Apr 15 18:14:31.003 INFO accepted connection, remote_addr: 127.0.0.1:59894, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.004 INFO request completed, latency_us: 298, response_code: 200, uri: /state/cid/0, method: GET, req_id: f22bd68a-20a7-4c87-9797-12fd3cfa66d0, remote_addr: 127.0.0.1:59894, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.018 INFO accepted connection, remote_addr: 127.0.0.1:34056, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.019 INFO request completed, latency_us: 70, response_code: 200, uri: /state/cid/1, method: GET, req_id: e337e4c3-ce7b-4611-bebb-012be60cadf6, remote_addr: 127.0.0.1:34056, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.032 INFO accepted connection, remote_addr: 127.0.0.1:44360, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.032 INFO request completed, latency_us: 68, response_code: 200, uri: /state/cid/2, method: GET, req_id: 6fd506ca-c97b-432f-9ccf-f47954db1841, remote_addr: 127.0.0.1:44360, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.045 INFO accepted connection, remote_addr: 127.0.0.1:43416, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.045 INFO request completed, latency_us: 75, response_code: 200, uri: /state/cid/3, method: GET, req_id: a814c1bc-1e6b-43eb-8d35-78783b8616ad, remote_addr: 127.0.0.1:43416, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.058 INFO accepted connection, remote_addr: 127.0.0.1:48672, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.058 INFO request completed, latency_us: 69, response_code: 200, uri: /state/cid/4, method: GET, req_id: e9e72772-9444-44e7-a228-79d5c40a1510, remote_addr: 127.0.0.1:48672, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.071 INFO accepted connection, remote_addr: 127.0.0.1:41345, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.071 INFO request completed, latency_us: 65, response_code: 200, uri: /state/cid/5, method: GET, req_id: c0a30566-6c49-4355-bd63-25304a7055d1, remote_addr: 127.0.0.1:41345, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.084 INFO accepted connection, remote_addr: 127.0.0.1:36076, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.084 INFO request completed, latency_us: 64, response_code: 200, uri: /state/cid/6, method: GET, req_id: aeb8e73c-2ee0-4111-8d24-42f3db3c18d0, remote_addr: 127.0.0.1:36076, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.097 INFO accepted connection, remote_addr: 127.0.0.1:50885, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.097 INFO request completed, latency_us: 94, response_code: 200, uri: /port/cid/6, method: GET, req_id: 4217f0a2-43e2-42c0-ba39-99ee2e700470, remote_addr: 127.0.0.1:50885, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.116 INFO accepted connection, remote_addr: 127.0.0.1:62836, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.116 INFO request completed, latency_us: 97, response_code: 200, uri: /regioninfo, method: GET, req_id: af713135-be05-4289-9889-dd9e73d66863, remote_addr: 127.0.0.1:62836, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.117 INFO request completed, latency_us: 36, response_code: 200, uri: /regioncount, method: GET, req_id: 642987bd-da80-40ad-a53a-3a6059131019, remote_addr: 127.0.0.1:62836, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.117 INFO request completed, latency_us: 39, response_code: 200, uri: /port/cid/0, method: GET, req_id: 30a39b98-aae4-4669-9324-05bd7fb5b307, remote_addr: 127.0.0.1:62836, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.117 INFO request completed, latency_us: 25, response_code: 200, uri: /port/cid/1, method: GET, req_id: d0e94614-9b7f-4252-907d-6ac5196319aa, remote_addr: 127.0.0.1:62836, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.118 INFO request completed, latency_us: 40, response_code: 200, uri: /port/cid/2, method: GET, req_id: a174ec2d-ddb7-4100-9ddb-227ec35a456e, remote_addr: 127.0.0.1:62836, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.119 INFO request completed, latency_us: 52, response_code: 200, uri: /port/cid/3, method: GET, req_id: 0fa1cfa5-426a-400a-9b69-cd666bc75fdf, remote_addr: 127.0.0.1:62836, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.119 INFO request completed, latency_us: 27, response_code: 200, uri: /port/cid/4, method: GET, req_id: 2732e624-5682-403c-86d2-0f60b9b60d58, remote_addr: 127.0.0.1:62836, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.119 INFO request completed, latency_us: 23, response_code: 200, uri: /port/cid/5, method: GET, req_id: 1f37dc52-2e3b-4487-bf46-3910316783ac, remote_addr: 127.0.0.1:62836, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.142 INFO accepted connection, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.143 INFO request completed, latency_us: 132, response_code: 200, uri: /regioncount, method: GET, req_id: 7fc34494-406f-4c6e-966c-918f7c52f5f4, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Apr 15 18:14:31.532 INFO request completed, latency_us: 232[8810] Got stop action so:false kr:false , response_code: 204, uri: /stop/cid/0, method: POST, req_id: 9327e6ac-6111-4bdc-a00e-516e16825dd5, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:14:31.532 INFO request completed, latency_us: 66, response_code: 200, uri: /state/cid/0, method: GET, req_id: e7a792f2-fefe-4574-b9e1-a11cc9ceb0da, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Apr 15 18:14:35.535 INFO request completed, latency_us: 300, response_code: 200, uri: /state/cid/0, method: GET, req_id: dd412072-62c3-4117-a5cc-57e3d1d6201f, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Apr 15 18:14:35.929 INFO request completed, latency_us: 234, response_code: 204, uri: /start/cid/0, method: POST, req_id: f28b595a-5371-41f6-bb85-b9f45dcc510d, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:14:35.930 INFO request completed, latency_us: 87, response_code: 200, uri: /state/cid/0, method: GET, req_id: 149faa8a-f3c6-4cb3-9ab8-4ef7bbc17b88, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:14:39.932 INFO request completed, latency_us: 265, response_code: 200, uri: /state/cid/0, method: GET, req_id: 5164af38-c91d-4fa8-a578-9db7d8d04e1e, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 [8810] got start action [8810] I am going to restart Starting downstairs at port 8810 Make output file at "/tmp/test_up-build/dsc/downstairs-8810.txt" [8810][0] reports Starting Downstairs /var/tmp/test_up-build/8810 port 8810 PID:Some(1231) [8810][0] reports Running Apr 15 18:14:43.933 INFO request completed, latency_us: 263, response_code: 200, uri: /state/cid/0, method: GET, req_id: 2a8bb4f8-fcc0-4dbe-a112-988ac1f12bc3, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Apr 15 18:14:50.325 INFO[8810] Got stop action so:false kr:false request completed, latency_us: 222, response_code: 204, uri: /stop/cid/0, method: POST, req_id: e3c48f47-50fb-4c86-b9b1-f7c4d2930f94, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:14:50.325 INFO request completed, latency_us: 56, response_code: 200, uri: /state/cid/0, method: GET, req_id: 0327eca4-6db3-49ef-beb1-aee71c0c0374, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Apr 15 18:14:54.328 INFO request completed, latency_us: 296, response_code: 200, uri: /state/cid/0, method: GET, req_id: c7d81f1a-fba2-43f1-afac-532af2ac8aeb, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Apr 15 18:14:54.727 INFO request completed, latency_us: 325, response_code: 204, uri: /start/cid/0, method: POST, req_id: fb144a11-9b35-4713-95d8-8ff9a8a0310f, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:14:54.728 INFO request completed, latency_us: 51, response_code: 200, uri: /state/cid/0, method: GET, req_id: b1520999-fb6a-45a4-8369-3d7e0dd6204e, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 [8810] got start action [8810] I am going to restart Starting downstairs at port 8810 Make output file at "/tmp/test_up-build/dsc/downstairs-8810.txt" [8810][0] reports Starting Downstairs /var/tmp/test_up-build/8810 port 8810 PID:Some(1232) [8810][0] reports Running Apr 15 18:14:58.730 INFO request completed, latency_us: 248, response_code: 200, uri: /state/cid/0, method: GET, req_id: 6474dc0e-ed60-44e6-8d10-bd113bcf9f60, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 [8810] Got stop action so:false kr:false Apr 15 18:15:04.123 INFO request completed, latency_us: 226, response_code: 204, uri: /stop/cid/0, method: POST, req_id: c45afebb-7ab0-4992-b8bb-3bf9823541f9, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:15:04.123 INFO request completed, latency_us: 85, response_code: 200, uri: /state/cid/0, method: GET, req_id: 3bdede36-6bef-4600-8c06-8257e248218c, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Apr 15 18:15:08.125 INFO request completed, latency_us: 286, response_code: 200, uri: /state/cid/0, method: GET, req_id: aedc5f30-0da6-4b1c-8516-c5ef8ff2da6a, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Apr 15 18:15:08.533 INFO request completed, latency_us: 221, response_code: 204, uri: /start/cid/0, method: POST, req_id: a7987d14-b836-417c-b2bc-937b651a7f5f, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:15:08.534 INFO request completed, latency_us: 45, response_code: 200, uri: /state/cid/0, method: GET, req_id: 5c748581-dcfd-4fa2-81ef-7185962b5534, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 [8810] got start action [8810] I am going to restart Starting downstairs at port 8810 Make output file at "/tmp/test_up-build/dsc/downstairs-8810.txt" [8810][0] reports Starting Downstairs /var/tmp/test_up-build/8810 port 8810 PID:Some(1233) [8810][0] reports Running Apr 15 18:15:12.536 INFO request completed, latency_us: 245, response_code: 200, uri: /state/cid/0, method: GET, req_id: 9c3f676b-3c17-4c31-bbe4-b99c448329a3, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 [8810] Got stop action so:false kr:false Apr 15 18:15:16.931 INFO request completed, latency_us: 220, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 57c3bd34-2733-4e0f-b261-7311a9d8c408, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:15:16.931 INFO request completed, latency_us: 70, response_code: 200, uri: /state/cid/0, method: GET, req_id: df041fcb-659f-45cb-aab4-7c50db2ee32b, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Apr 15 18:15:20.934 INFO request completed, latency_us: 287, response_code: 200, uri: /state/cid/0, method: GET, req_id: 5b1ea653-a26b-4769-823c-d2bc5a73985a, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Apr 15 18:15:21.341 INFO request completed, latency_us: 241, response_code: 204, uri: /start/cid/0, method: POST, req_id: d3644d85-3a8a-4d5d-8250-d99275dd695a, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:15:21.341 INFO request completed, latency_us: 117, response_code: 200, uri: /state/cid/0, method: GET, req_id: ad6ee47b-9a6b-42ca-91ba-c8476e636010, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:15:25.343 INFO request completed, latency_us: 271, response_code: 200, uri: /state/cid/0, method: GET, req_id: 63853d7b-2df8-4036-97b3-afda4730fc36, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 [8810] got start action [8810] I am going to restart Starting downstairs at port 8810 Make output file at "/tmp/test_up-build/dsc/downstairs-8810.txt" [8810][0] reports Starting Downstairs /var/tmp/test_up-build/8810 port 8810 PID:Some(1234) [8810][0] reports Running Apr 15 18:15:29.345 INFO request completed, latency_us: 244, response_code: 200, uri: /state/cid/0, method: GET, req_id: 688bb8a5-770e-4691-9fbd-07310ed0b7d7, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Apr 15 18:15:32.735[8810] Got stop action so:false kr:false INFO request completed, latency_us: 275, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 7655b95d-b047-4e87-abca-5909a0e486f7, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:15:32.735 INFO request completed, latency_us: 54, response_code: 200, uri: /state/cid/0, method: GET, req_id: 14dbed2d-707c-41e3-bcd7-f0dfb41f4293, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Apr 15 18:15:36.737 INFO request completed, latency_us: 278, response_code: 200, uri: /state/cid/0, method: GET, req_id: e57b3856-143c-460d-aec7-281509095cc0, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Apr 15 18:15:37.145 INFO request completed, latency_us: 219, response_code: 204, uri: /start/cid/0, method: POST, req_id: d7fdca88-47fb-4c0d-832d-34120fa8112d, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:15:37.146 INFO request completed, latency_us: 46, response_code: 200, uri: /state/cid/0, method: GET, req_id: e1a76fe6-f1ed-4e17-8857-19602601b1a3, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 [8810] got start action [8810] I am going to restart Starting downstairs at port 8810 Make output file at "/tmp/test_up-build/dsc/downstairs-8810.txt" [8810][0] reports Starting Downstairs /var/tmp/test_up-build/8810 port 8810 PID:Some(1235) [8810][0] reports Running Apr 15 18:15:41.149 INFO request completed, latency_us: 278, response_code: 200, uri: /state/cid/0, method: GET, req_id: c3a4ca59-5dcf-4d28-ab20-eaa61a7f94df, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Apr 15 18:15:43.541 INFO request completed, [8810] Got stop action so:false kr:false latency_us: 243, response_code: 204, uri: /stop/cid/0, method: POST, req_id: c98129fe-6561-4dc3-b5d5-0ca6e23859e2, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:15:43.542 INFO request completed, latency_us: 174, response_code: 200, uri: /state/cid/0, method: GET, req_id: aef8d6f9-fa49-43d1-9fe3-a968d143a715, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Apr 15 18:15:47.545 INFO request completed, latency_us: 273, response_code: 200, uri: /state/cid/0, method: GET, req_id: 32749537-cccc-4c7d-a145-7a58874f3f36, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Apr 15 18:15:47.951 INFO request completed, latency_us: 222, response_code: 204, uri: /start/cid/0, method: POST, req_id: 56d934c6-849f-4d3f-acc0-2a92b38a5754, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:15:47.951 INFO request completed, latency_us: 50, response_code: 200, uri: /state/cid/0, method: GET, req_id: bb17cb06-2fbd-4380-8ecb-71357262cf00, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 [8810] got start action [8810] I am going to restart Starting downstairs at port 8810 Make output file at "/tmp/test_up-build/dsc/downstairs-8810.txt" [8810][0] reports Starting Downstairs /var/tmp/test_up-build/8810 port 8810 PID:Some(1236) [8810][0] reports Running Apr 15 18:15:51.953 INFO request completed, latency_us: 227, response_code: 200, uri: /state/cid/0, method: GET, req_id: 189ae8b9-f821-4c08-8a20-c160adff4f5e, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(1) stop 1 Apr 15 18:16:19.444 INFO request completed, [8820] Got stop action so:false kr:false latency_us: 225, response_code: 204, uri: /stop/cid/1, method: POST, req_id: 83a50388-6214-462c-99e9-15f6df6dc4fd, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:16:19.444 INFO request completed, latency_us: 148, response_code: 200, uri: /state/cid/1, method: GET, req_id: ab6e4341-1638-4031-bf9b-eb5bb7232c39, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] reports Exit Apr 15 18:16:23.447 INFO request completed, latency_us: 313, response_code: 200, uri: /state/cid/1, method: GET, req_id: 7c03141c-cbc8-436a-8561-3a4497fa46e8, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(1) start 1 Apr 15 18:16:23.855 INFO request completed, latency_us: 226, response_code: 204, uri: /start/cid/1, method: POST, req_id: 7a0befca-d02d-4abb-be7c-e5109412568a, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:16:23.855 INFO request completed, latency_us: 69, response_code: 200, uri: /state/cid/1, method: GET, req_id: b9f94d69-28f9-4f74-b055-a66ed99eb898, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 [8820] got start action [8820] I am going to restart Starting downstairs at port 8820 Make output file at "/tmp/test_up-build/dsc/downstairs-8820.txt" [8820][1] reports Starting Downstairs /var/tmp/test_up-build/8820 port 8820 PID:Some(1237) [8820][1] reports Running Apr 15 18:16:27.857 INFO request completed, latency_us: 236, response_code: 200, uri: /state/cid/1, method: GET, req_id: c8f4b88b-95ab-47fa-9a33-780e3360e6c1, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(2) stop 2 Apr 15 18:16:55.472 INFO request completed[8830] Got stop action so:false kr:false , latency_us: 231, response_code: 204, uri: /stop/cid/2, method: POST, req_id: de6c500c-d7e2-44e1-9451-17a6d2ae9712, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:16:55.472 INFO request completed, latency_us: 71, response_code: 200, uri: /state/cid/2, method: GET, req_id: fb111dc2-7e9c-40eb-9889-aca0e1664f43, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit Apr 15 18:16:59.475 INFO request completed, latency_us: 302, response_code: 200, uri: /state/cid/2, method: GET, req_id: 5caf3251-fa34-44cb-8b54-f4abc3afb56f, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(2) start 2 Apr 15 18:16:59.881 INFO request completed, latency_us: 218, response_code: 204, uri: /start/cid/2, method: POST, req_id: b7026dce-c0cf-4c42-8e87-69bb76700913, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:16:59.881 INFO request completed, latency_us: 84, response_code: 200, uri: /state/cid/2, method: GET, req_id: a80168b2-1cd3-4f72-a37c-0e107460c454, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 [8830] got start action [8830] I am going to restart Starting downstairs at port 8830 Make output file at "/tmp/test_up-build/dsc/downstairs-8830.txt" [8830][2] reports Starting Downstairs /var/tmp/test_up-build/8830 port 8830 PID:Some(1238) [8830][2] reports Running Apr 15 18:17:03.883 INFO request completed, latency_us: 248, response_code: 200, uri: /state/cid/2, method: GET, req_id: b4f6016a-1407-4045-90f0-e7da4a062e4f, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(3) stop 3 Apr 15 18:17:31.708[8840] Got stop action so:false kr:false INFO request completed, latency_us: 263, response_code: 204, uri: /stop/cid/3, method: POST, req_id: 2624af71-9a2e-4da4-ae98-5627e961b244, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:17:31.709 INFO request completed, latency_us: 72, response_code: 200, uri: /state/cid/3, method: GET, req_id: 38cb9319-68cd-4ff3-bb95-8296b123af14, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 [8840] Exited with: ExitStatus(unix_wait_status(9)) [8840][3] reports Exit Apr 15 18:17:35.710 INFO request completed, latency_us: 301, response_code: 200, uri: /state/cid/3, method: GET, req_id: 91fd5245-9b2d-41ea-8b18-60df5fc947bc, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(3) start 3 Apr 15 18:17:36.123 INFO request completed, latency_us: 225, response_code: 204, uri: /start/cid/3, method: POST, req_id: bcf1e775-338d-405f-bbb2-a3cccab8c59d, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:17:36.124 INFO request completed, latency_us: 51, response_code: 200, uri: /state/cid/3, method: GET, req_id: f84268d2-6efd-4e14-8e8e-69dd5199b56c, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 [8840] got start action [8840] I am going to restart Starting downstairs at port 8840 Make output file at "/tmp/test_up-build/dsc/downstairs-8840.txt" [8840][3] reports Starting Downstairs /var/tmp/test_up-build/8840 port 8840 PID:Some(1239) [8840][3] reports Running Apr 15 18:17:40.125 INFO request completed, latency_us: 267, response_code: 200, uri: /state/cid/3, method: GET, req_id: 2150e1a7-8d87-4c26-baa8-dd7b899bbf23, remote_addr: 127.0.0.1:36246, local_addr: 127.0.0.1:9998 Apr 15 18:18:07.820 INFO accepted connection, remote_addr: 127.0.0.1:55388, local_addr: 127.0.0.1:9998 Apr 15 18:18:07.820 INFO request completed, latency_us: 160, response_code: 200, uri: /regioninfo, method: GET, req_id: f8c12c78-e3b5-404c-9def-0cbd9abf841c, remote_addr: 127.0.0.1:55388, local_addr: 127.0.0.1:9998 Apr 15 18:18:07.821 INFO request completed, latency_us: 71, response_code: 200, uri: /regioncount, method: GET, req_id: fc3e1f6d-32bb-4210-8f4c-e803c8405039, remote_addr: 127.0.0.1:55388, local_addr: 127.0.0.1:9998 Apr 15 18:18:07.821 INFO request completed, latency_us: 64, response_code: 200, uri: /port/cid/0, method: GET, req_id: 6e338ebf-0525-4d3b-b772-dacfbae83496, remote_addr: 127.0.0.1:55388, local_addr: 127.0.0.1:9998 Apr 15 18:18:07.821 INFO request completed, latency_us: 33, response_code: 200, uri: /port/cid/1, method: GET, req_id: 06254534-d8d6-4e73-bfd0-c57892979f7c, remote_addr: 127.0.0.1:55388, local_addr: 127.0.0.1:9998 Apr 15 18:18:07.822 INFO request completed, latency_us: 38, response_code: 200, uri: /port/cid/2, method: GET, req_id: 92bc148e-dbe5-417c-9715-f02fbe9601a0, remote_addr: 127.0.0.1:55388, local_addr: 127.0.0.1:9998 Apr 15 18:18:07.823 INFO request completed, latency_us: 48, response_code: 200, uri: /port/cid/3, method: GET, req_id: 08431a86-863b-4e0c-bb84-a463cd12d1f4, remote_addr: 127.0.0.1:55388, local_addr: 127.0.0.1:9998 Apr 15 18:18:07.823 INFO request completed, latency_us: 31, response_code: 200, uri: /port/cid/4, method: GET, req_id: 7a94ea9f-2c59-4910-804a-3aaf908726cc, remote_addr: 127.0.0.1:55388, local_addr: 127.0.0.1:9998 Apr 15 18:18:07.823 INFO request completed, latency_us: 29, response_code: 200, uri: /port/cid/5, method: GET, req_id: e25ccb77-9003-49b9-942f-e35aaa1dd5d4, remote_addr: 127.0.0.1:55388, local_addr: 127.0.0.1:9998 Apr 15 18:18:08.008 INFO accepted connection, remote_addr: 127.0.0.1:36677, local_addr: 127.0.0.1:9998 Apr 15 18:18:08.008 INFO request completed, latency_us: 132, response_code: 200, uri: /regioncount, method: GET, req_id: 0971adee-cee8-4769-baed-ff3f449ad653, remote_addr: 127.0.0.1:36677, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Apr 15 18:18:13.708 INFO request completed, [8810] Got stop action so:false kr:false latency_us: 225, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 2761f47a-ad6b-4a46-ab6d-af1be568fdc8, remote_addr: 127.0.0.1:36677, local_addr: 127.0.0.1:9998 Apr 15 18:18:13.709 INFO request completed, latency_us: 104, response_code: 200, uri: /state/cid/0, method: GET, req_id: 5d7a95f2-b683-44c0-ada0-04e3596dae87, remote_addr: 127.0.0.1:36677, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Apr 15 18:18:17.711 INFO request completed, latency_us: 240, response_code: 200, uri: /state/cid/0, method: GET, req_id: acea9eda-4864-44ca-8bc2-d4739e7966a3, remote_addr: 127.0.0.1:36677, local_addr: 127.0.0.1:9998 Apr 15 18:18:55.073 INFO accepted connection, remote_addr: 127.0.0.1:40067, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Apr 15 18:18:55.074 INFO request completed, latency_us: 177, response_code: 204, uri: /start/cid/0, method: POST, req_id: bef889cb-e011-4fcb-8ea3-ab93f4e94d04, remote_addr: 127.0.0.1:40067, local_addr: 127.0.0.1:9998 [8810] got start action [8810] I am going to restart Starting downstairs at port 8810 Make output file at "/tmp/test_up-build/dsc/downstairs-8810.txt" [8810][0] reports Starting Downstairs /var/tmp/test_up-build/8810 port 8810 PID:Some(1241) [8810][0] reports Running Main task has work to do, go find it got dsc Stop(1) stop 1 Apr 15 18:18:59.473 INFO [8820] Got stop action so:false kr:false request completed, latency_us: 221, response_code: 204, uri: /stop/cid/1, method: POST, req_id: 9f780f14-ac64-4fb2-8889-b70cb1d568a3, remote_addr: 127.0.0.1:40067, local_addr: 127.0.0.1:9998 Apr 15 18:18:59.473 INFO request completed, latency_us: 55, response_code: 200, uri: /state/cid/1, method: GET, req_id: 832fc8c3-868d-4bd1-8c4f-f99c34272bcd, remote_addr: 127.0.0.1:40067, local_addr: 127.0.0.1:9998 [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] reports Exit Apr 15 18:19:03.476 INFO request completed, latency_us: 254, response_code: 200, uri: /state/cid/1, method: GET, req_id: 64f417c5-396f-481f-9ca1-9a45c7d9558c, remote_addr: 127.0.0.1:40067, local_addr: 127.0.0.1:9998 Apr 15 18:19:40.815 INFO accepted connection, remote_addr: 127.0.0.1:35473, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(1) Apr 15 18:19:40.815start 1 INFO request completed, latency_us: 171, response_code: 204, uri: /start/cid/1, method: POST, req_id: cf4379b0-b98f-47f3-ab53-35d3cdd627e4, remote_addr: 127.0.0.1:35473, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(2) stop 2 Apr 15 18:19:45.219 INFO request completed[8830] Got stop action so:false kr:false , latency_us: 216, response_code: 204, uri: /stop/cid/2, method: POST, req_id: 13b66ea0-eeac-4bd8-b840-3858f75f72fe, remote_addr: 127.0.0.1:35473, local_addr: 127.0.0.1:9998 Apr 15 18:19:45.219 INFO request completed, latency_us: 53, response_code: 200, uri: /state/cid/2, method: GET, req_id: b8d67dd1-9a68-4197-85a2-acce1a41cfc5, remote_addr: 127.0.0.1:35473, local_addr: 127.0.0.1:9998 [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit [8820] got start action [8820] I am going to restart Starting downstairs at port 8820 Make output file at "/tmp/test_up-build/dsc/downstairs-8820.txt" [8820][1] reports Starting Downstairs /var/tmp/test_up-build/8820 port 8820 PID:Some(1242) [8820][1] reports Running Apr 15 18:19:49.222 INFO request completed, latency_us: 214, response_code: 200, uri: /state/cid/2, method: GET, req_id: fb7139b4-067e-44ec-98fb-1e19d17a4f05, remote_addr: 127.0.0.1:35473, local_addr: 127.0.0.1:9998 Apr 15 18:20:26.614 INFO accepted connection, remote_addr: 127.0.0.1:64230, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(2) Apr 15 18:20:26.614 INFO request completed, latency_us: 176start 2 , response_code: 204, uri: /start/cid/2, method: POST, req_id: fadb6bdb-8020-451f-b5a3-094836ae501d, remote_addr: 127.0.0.1:64230, local_addr: 127.0.0.1:9998 [8830] got start action [8830] I am going to restart Starting downstairs at port 8830 Make output file at "/tmp/test_up-build/dsc/downstairs-8830.txt" [8830][2] reports Starting Downstairs /var/tmp/test_up-build/8830 port 8830 PID:Some(1243) [8830][2] reports Running Main task has work to do, go find it got dsc Stop(3) stop 3 Apr 15 18:20:31.028 [8840] Got stop action so:false kr:false INFO request completed, latency_us: 229, response_code: 204, uri: /stop/cid/3, method: POST, req_id: e0cae673-dd4c-42ae-80ad-6b430fa6c7a1, remote_addr: 127.0.0.1:64230, local_addr: 127.0.0.1:9998 Apr 15 18:20:31.028 INFO request completed, latency_us: 85, response_code: 200, uri: /state/cid/3, method: GET, req_id: 3afda28d-ae27-48b7-a8b8-05386aaa05f3, remote_addr: 127.0.0.1:64230, local_addr: 127.0.0.1:9998 [8840] Exited with: ExitStatus(unix_wait_status(9)) [8840][3] reports Exit Apr 15 18:20:35.030 INFO request completed, latency_us: 291, response_code: 200, uri: /state/cid/3, method: GET, req_id: 8eb3fb0d-54a5-4e09-9f2c-e344d903e389, remote_addr: 127.0.0.1:64230, local_addr: 127.0.0.1:9998 Apr 15 18:21:12.408 INFO accepted connection, remote_addr: 127.0.0.1:56646, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(3) start 3 Apr 15 18:21:12.408 INFO request completed, latency_us: 253, response_code: 204, uri: /start/cid/3, method: POST, req_id: d5dedce5-382a-4f71-8de5-60c57c6128ce, remote_addr: 127.0.0.1:56646, local_addr: 127.0.0.1:9998 [8840] got start action [8840] I am going to restart Starting downstairs at port 8840 Make output file at "/tmp/test_up-build/dsc/downstairs-8840.txt" [8840][3] reports Starting Downstairs /var/tmp/test_up-build/8840 port 8840 PID:Some(1244) [8840][3] reports Running Main task has work to do, go find it got dsc Stop(4) stop 4 Apr 15 18:21:16.977 INFO[8850] Got stop action so:false kr:false request completed, latency_us: 216, response_code: 204, uri: /stop/cid/4, method: POST, req_id: 1553fda1-a42b-42cc-aaa6-26f2e55e1196, remote_addr: 127.0.0.1:56646, local_addr: 127.0.0.1:9998 Apr 15 18:21:16.977 INFO request completed, latency_us: 86, response_code: 200, uri: /state/cid/4, method: GET, req_id: ff0e280c-5529-436c-8333-3d7fd4c5ab32, remote_addr: 127.0.0.1:56646, local_addr: 127.0.0.1:9998 [8850] Exited with: ExitStatus(unix_wait_status(9)) [8850][4] reports Exit Apr 15 18:21:20.980 INFO request completed, latency_us: 236, response_code: 200, uri: /state/cid/4, method: GET, req_id: 761e23f2-ceae-4c76-bb61-273d4dad1ead, remote_addr: 127.0.0.1:56646, local_addr: 127.0.0.1:9998 Apr 15 18:21:58.338 INFO accepted connection, remote_addr: 127.0.0.1:63658, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(4) start 4 Apr 15 18:21:58.339 INFO request completed, latency_us: 172, response_code: 204, uri: /start/cid/4, method: POST, req_id: b898e365-d910-4d99-b9b6-1e9b93ab45d6, remote_addr: 127.0.0.1:63658, local_addr: 127.0.0.1:9998 [8850] got start action [8850] I am going to restart Starting downstairs at port 8850 Make output file at "/tmp/test_up-build/dsc/downstairs-8850.txt" [8850][4] reports Starting Downstairs /var/tmp/test_up-build/8850 port 8850 PID:Some(1245) [8850][4] reports Running Apr 15 18:22:02.384 INFO accepted connection, remote_addr: 127.0.0.1:61007, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Shutdown Shutdown Shut it down Apr 15 18:22:02.384 INFO request completed, latency_us: 140, response_code: 204, uri: /shutdown[8820] Disable keep_running , method[8810] Disable keep_running :[8820] Got stop action so:false kr:false POST, req_id: b6d51f88-dd44-47e3-bf7a-4fdc88b66303, remote_addr: 127.0.0.[8830] Disable keep_running 1:61007, local_addr: 127.[8830] Got stop action so:false kr:false 0[8840] Disable keep_running .[8840] Got stop action so:false kr:false 0.1:9998 [8870] Disable keep_running [8870] Got stop action so:false kr:false [8850] Disable keep_running [8850] Got stop action so:false kr:false [8860] Disable keep_running [8860] Got stop action so:false kr:false [8810] Got stop action so:false kr:false [8850] Exited with: ExitStatus(unix_wait_status(9)) [8850][4] reports Exit [8840] Exited with: ExitStatus(unix_wait_status(9)) [8840][3] reports Exit [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit [8860] Exited with: ExitStatus(unix_wait_status(9)) [8860][5] reports Exit [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] reports Exit [8870] Exited with: ExitStatus(unix_wait_status(9)) [8870][6] reports Exit