/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.14702272 Downstairs region 1 created at /var/tmp/test_up-build/8820 in 0.011470086 Downstairs region 2 created at /var/tmp/test_up-build/8830 in 0.010692377 Downstairs region 3 created at /var/tmp/test_up-build/8840 in 0.010333797 Downstairs region 4 created at /var/tmp/test_up-build/8850 in 0.010379555 Downstairs region 5 created at /var/tmp/test_up-build/8860 in 0.010529991 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 access at:127.0.0.1:9998 start ds: 8830 start ds: 8840 start ds: 8850 start ds: 8860 Starting downstairs at port 8810 Starting downstairs at port 8830 Starting downstairs at port 8820 Make output file at "/tmp/test_up-build/dsc/downstairs-8810.txt" Make output file at "/tmp/test_up-build/dsc/downstairs-8830.txt" Make output file at "/tmp/test_up-build/dsc/downstairs-8820.txt" Starting downstairs at port 8840 Make output file at "/tmp/test_up-build/dsc/downstairs-8840.txt" [8810][0] initial start wait reports Starting [8830][2] initial start wait reports Starting [8820][1] initial start wait reports Starting [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 Make output file at "/tmp/test_up-build/dsc/downstairs-8860.txt" [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(1142) Control access at:127.0.0.1:9998 Downstairs /var/tmp/test_up-build/8820 port 8820 PID:Some(1143) Downstairs /var/tmp/test_up-build/8810 port 8810 PID:Some(1146) Sep 24 22:08:39.448 INFO listening, local_addr: 127.0.0.1:9998 Downstairs /var/tmp/test_up-build/8830 port 8830 PID:Some(1145) Downstairs /var/tmp/test_up-build/8840 port 8840 PID:Some(1144) Downstairs /var/tmp/test_up-build/8850 port 8850 PID:Some(1141) [8860][5] initial start wait reports Running [8820][1] initial start wait reports Running [8810][0] initial start wait reports Running [8830][2] initial start wait reports Running [8840][3] initial start wait reports Running [8850][4] initial start wait reports Running All downstairs are running Sep 24 22:08:44.459 INFO accepted connection, remote_addr: 127.0.0.1:54333, local_addr: 127.0.0.1:9998 Sep 24 22:08:44.460 INFO request completed, latency_us: 441, response_code: 200, uri: /state/cid/0, method: GET, req_id: c0244a95-cfd4-45fc-bed3-b78a181e2c57, remote_addr: 127.0.0.1:54333, local_addr: 127.0.0.1:9998 Sep 24 22:08:44.473 INFO accepted connection, remote_addr: 127.0.0.1:33189, local_addr: 127.0.0.1:9998 Sep 24 22:08:44.474 INFO request completed, latency_us: 69, response_code: 200, uri: /state/cid/1, method: GET, req_id: 4e0ea5b1-1f6e-47e5-b2e5-d5ca8674d319, remote_addr: 127.0.0.1:33189, local_addr: 127.0.0.1:9998 Sep 24 22:08:44.488 INFO accepted connection, remote_addr: 127.0.0.1:62149, local_addr: 127.0.0.1:9998 Sep 24 22:08:44.489 INFO request completed, latency_us: 50, response_code: 200, uri: /state/cid/2, method: GET, req_id: 4e8f84e4-838d-4585-9d1e-c5686c77c16e, remote_addr: 127.0.0.1:62149, local_addr: 127.0.0.1:9998 Sep 24 22:08:44.501 INFO accepted connection, remote_addr: 127.0.0.1:54011, local_addr: 127.0.0.1:9998 Sep 24 22:08:44.502 INFO request completed, latency_us: 51, response_code: 200, uri: /state/cid/3, method: GET, req_id: 2983221c-f53d-4168-9987-c94c4b43e1b8, remote_addr: 127.0.0.1:54011, local_addr: 127.0.0.1:9998 Sep 24 22:08:44.514 INFO accepted connection, remote_addr: 127.0.0.1:35616, local_addr: 127.0.0.1:9998 Sep 24 22:08:44.515 INFO request completed, latency_us: 51, response_code: 200, uri: /state/cid/4, method: GET, req_id: 766092e6-b38e-4551-b241-668a2cf0720b, remote_addr: 127.0.0.1:35616, local_addr: 127.0.0.1:9998 Sep 24 22:08:44.527 INFO accepted connection, remote_addr: 127.0.0.1:39678, local_addr: 127.0.0.1:9998 Sep 24 22:08:44.527 INFO request completed, latency_us: 63, response_code: 200, uri: /state/cid/5, method: GET, req_id: 2fc0e95d-d85f-41a8-8d85-1c140c0d05aa, remote_addr: 127.0.0.1:39678, local_addr: 127.0.0.1:9998 Sep 24 22:08:44.539 INFO accepted connection, remote_addr: 127.0.0.1:62425, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc DisableRestartAll Sep 24 22:08:44.540 INFOdisable restart on all: 6 request completed, latency_us: 79, response_code: 204, uri: /disablerestart/all, method: POST, req_id: fc92e7fc-53a5-40e1-a713-748701214caa, remote_addr: 127.0.0[8810] Disable keep_running .[8820] Disable keep_running 1[8840] Disable keep_running :[8860] Disable keep_running 62425[8850] Disable keep_running , local_addr[8830] Disable keep_running : 127.0.0.1:9998 Sep 24 22:08:44.604 INFO accepted connection, remote_addr: 127.0.0.1:49521, local_addr: 127.0.0.1:9998 Sep 24 22:08:44.605 INFO request completed, latency_us: 227, response_code: 200, uri: /regioninfo, method: GET, req_id: 123d515e-70f5-48ab-96c9-f0c306491db1, remote_addr: 127.0.0.1:49521, local_addr: 127.0.0.1:9998 Sep 24 22:08:44.606 INFO request completed, latency_us: 37, response_code: 200, uri: /regioncount, method: GET, req_id: 784cd2aa-c8cc-44a9-9be9-961ac10e470b, remote_addr: 127.0.0.1:49521, local_addr: 127.0.0.1:9998 Sep 24 22:08:44.607 INFO request completed, latency_us: 67, response_code: 200, uri: /port/cid/0, method: GET, req_id: d6e3fd52-2180-4641-8900-d43b323ced47, remote_addr: 127.0.0.1:49521, local_addr: 127.0.0.1:9998 Sep 24 22:08:44.607 INFO request completed, latency_us: 29, response_code: 200, uri: /port/cid/1, method: GET, req_id: 701ab3ad-0865-4166-86f8-cd8d7332e805, remote_addr: 127.0.0.1:49521, local_addr: 127.0.0.1:9998 Sep 24 22:08:44.607 INFO request completed, latency_us: 24, response_code: 200, uri: /port/cid/2, method: GET, req_id: a0cf8c54-104a-4f1b-9661-6e733ac7d65c, remote_addr: 127.0.0.1:49521, local_addr: 127.0.0.1:9998 Sep 24 22:08:44.612 INFO request completed, latency_us: 60, response_code: 200, uri: /port/cid/3, method: GET, req_id: df74c39a-2e64-4a78-aafa-f54bf240602b, remote_addr: 127.0.0.1:49521, local_addr: 127.0.0.1:9998 Sep 24 22:08:44.613 INFO request completed, latency_us: 68, response_code: 200, uri: /port/cid/4, method: GET, req_id: 344f52c8-4427-46a8-a9d5-0dfad8f023b8, remote_addr: 127.0.0.1:49521, local_addr: 127.0.0.1:9998 Sep 24 22:08:44.613 INFO request completed, latency_us: 31, response_code: 200, uri: /port/cid/5, method: GET, req_id: 1300c45c-2fbf-4d00-a9b0-b83c20b1e0d7, remote_addr: 127.0.0.1:49521, local_addr: 127.0.0.1:9998 Sep 24 22:08:53.672 INFO accepted connection, remote_addr: 127.0.0.1:41397, local_addr: 127.0.0.1:9998 Sep 24 22:08:53.672 INFO request completed, latency_us: 144, response_code: 200, uri: /regioninfo, method: GET, req_id: 3dcf9c03-4028-435f-9f6e-d817b3c0aea7, remote_addr: 127.0.0.1:41397, local_addr: 127.0.0.1:9998 Sep 24 22:08:53.673 INFO request completed, latency_us: 82, response_code: 200, uri: /regioncount, method: GET, req_id: 05c232f9-c1e9-4c56-8d99-069a9dec09cc, remote_addr: 127.0.0.1:41397, local_addr: 127.0.0.1:9998 Sep 24 22:08:53.673 INFO request completed, latency_us: 82, response_code: 200, uri: /port/cid/0, method: GET, req_id: 5d95c693-7ac5-4e50-a2a3-96dd9fe24454, remote_addr: 127.0.0.1:41397, local_addr: 127.0.0.1:9998 Sep 24 22:08:53.674 INFO request completed, latency_us: 63, response_code: 200, uri: /port/cid/1, method: GET, req_id: 4c19d34c-2ee6-4205-9290-0dcdc9d68964, remote_addr: 127.0.0.1:41397, local_addr: 127.0.0.1:9998 Sep 24 22:08:53.674 INFO request completed, latency_us: 70, response_code: 200, uri: /port/cid/2, method: GET, req_id: ba1eabc8-f90f-43a3-b704-eae21484907a, remote_addr: 127.0.0.1:41397, local_addr: 127.0.0.1:9998 Sep 24 22:08:53.676 INFO request completed, latency_us: 67, response_code: 200, uri: /port/cid/3, method: GET, req_id: ecbec319-085d-4f47-a3ff-c482dae04767, remote_addr: 127.0.0.1:41397, local_addr: 127.0.0.1:9998 Sep 24 22:08:53.676 INFO request completed, latency_us: 35, response_code: 200, uri: /port/cid/4, method: GET, req_id: 5d5c9505-7af0-4f8f-b1f5-c34114281d54, remote_addr: 127.0.0.1:41397, local_addr: 127.0.0.1:9998 Sep 24 22:08:53.676 INFO request completed, latency_us: 30, response_code: 200, uri: /port/cid/5, method: GET, req_id: c6795908-ea0e-47db-b14c-2996e55de2d5, remote_addr: 127.0.0.1:41397, local_addr: 127.0.0.1:9998 Sep 24 22:09:02.983 INFO accepted connection, remote_addr: 127.0.0.1:46389, local_addr: 127.0.0.1:9998 Sep 24 22:09:02.983 INFO request completed, latency_us: 147, response_code: 200, uri: /regioninfo, method: GET, req_id: ffddb258-61be-4573-9f7d-e8d3b6e4852e, remote_addr: 127.0.0.1:46389, local_addr: 127.0.0.1:9998 Sep 24 22:09:02.984 INFO request completed, latency_us: 37, response_code: 200, uri: /regioncount, method: GET, req_id: cef74bc4-2d7c-4a1d-942d-4f8f9eda08a8, remote_addr: 127.0.0.1:46389, local_addr: 127.0.0.1:9998 Sep 24 22:09:02.984 INFO request completed, latency_us: 67, response_code: 200, uri: /port/cid/0, method: GET, req_id: 528b556a-1238-49b3-8d43-05737e053189, remote_addr: 127.0.0.1:46389, local_addr: 127.0.0.1:9998 Sep 24 22:09:02.985 INFO request completed, latency_us: 31, response_code: 200, uri: /port/cid/1, method: GET, req_id: c4449063-67e8-4c5a-9124-aab607aed4c2, remote_addr: 127.0.0.1:46389, local_addr: 127.0.0.1:9998 Sep 24 22:09:02.985 INFO request completed, latency_us: 34, response_code: 200, uri: /port/cid/2, method: GET, req_id: 24d039e1-9bd3-4834-9d40-83aa02e563fa, remote_addr: 127.0.0.1:46389, local_addr: 127.0.0.1:9998 Sep 24 22:09:02.986 INFO request completed, latency_us: 66, response_code: 200, uri: /port/cid/3, method: GET, req_id: 2be49cb4-2b66-4004-9ca0-bd52803ff7a5, remote_addr: 127.0.0.1:46389, local_addr: 127.0.0.1:9998 Sep 24 22:09:02.987 INFO request completed, latency_us: 68, response_code: 200, uri: /port/cid/4, method: GET, req_id: e0af14b6-5b9e-4e92-b65c-30e56b18150c, remote_addr: 127.0.0.1:46389, local_addr: 127.0.0.1:9998 Sep 24 22:09:02.987 INFO request completed, latency_us: 62, response_code: 200, uri: /port/cid/5, method: GET, req_id: 6a71bcad-9770-467a-b9fe-f55515ca91c7, remote_addr: 127.0.0.1:46389, local_addr: 127.0.0.1:9998 Sep 24 22:09:27.781 INFO accepted connection, remote_addr: 127.0.0.1:41687, local_addr: 127.0.0.1:9998 Sep 24 22:09:27.781 INFO request completed, latency_us: 148, response_code: 200, uri: /regioninfo, method: GET, req_id: a86af4bf-fec3-465a-87fa-e74d5f3ff9ed, remote_addr: 127.0.0.1:41687, local_addr: 127.0.0.1:9998 Sep 24 22:09:27.782 INFO request completed, latency_us: 38, response_code: 200, uri: /regioncount, method: GET, req_id: 65a27191-4776-432e-a791-2435e9a4ce6c, remote_addr: 127.0.0.1:41687, local_addr: 127.0.0.1:9998 Sep 24 22:09:27.782 INFO request completed, latency_us: 55, response_code: 200, uri: /port/cid/0, method: GET, req_id: e6c3d795-aa9f-4af9-9a3a-5e7d49f1ed2e, remote_addr: 127.0.0.1:41687, local_addr: 127.0.0.1:9998 Sep 24 22:09:27.783 INFO request completed, latency_us: 31, response_code: 200, uri: /port/cid/1, method: GET, req_id: 53c3a089-c4de-4003-8e65-9409036cd8ce, remote_addr: 127.0.0.1:41687, local_addr: 127.0.0.1:9998 Sep 24 22:09:27.783 INFO request completed, latency_us: 32, response_code: 200, uri: /port/cid/2, method: GET, req_id: 8698d284-bc7b-4919-89d6-bcdefbdf028b, remote_addr: 127.0.0.1:41687, local_addr: 127.0.0.1:9998 Sep 24 22:09:27.784 INFO request completed, latency_us: 41, response_code: 200, uri: /port/cid/3, method: GET, req_id: ad1ac4d9-b88e-4681-8b94-6f7639fdfcae, remote_addr: 127.0.0.1:41687, local_addr: 127.0.0.1:9998 Sep 24 22:09:27.784 INFO request completed, latency_us: 29, response_code: 200, uri: /port/cid/4, method: GET, req_id: 919c275e-8faa-4a1c-a765-afeda7133c73, remote_addr: 127.0.0.1:41687, local_addr: 127.0.0.1:9998 Sep 24 22:09:27.785 INFO request completed, latency_us: 28, response_code: 200, uri: /port/cid/5, method: GET, req_id: f1cbf771-b580-41c7-9e80-ab5a5fcfb06a, remote_addr: 127.0.0.1:41687, local_addr: 127.0.0.1:9998 Sep 24 22:12:00.866 INFO accepted connection, remote_addr: 127.0.0.1:47291, local_addr: 127.0.0.1:9998 Sep 24 22:12:00.866 INFO request completed, latency_us: 145, response_code: 200, uri: /regioninfo, method: GET, req_id: 5700be21-8626-4ab7-a780-26b4738270c7, remote_addr: 127.0.0.1:47291, local_addr: 127.0.0.1:9998 Sep 24 22:12:00.867 INFO request completed, latency_us: 70, response_code: 200, uri: /regioncount, method: GET, req_id: f2cae761-2f56-437a-bcb8-4caf451b7186, remote_addr: 127.0.0.1:47291, local_addr: 127.0.0.1:9998 Sep 24 22:12:00.867 INFO request completed, latency_us: 62, response_code: 200, uri: /port/cid/0, method: GET, req_id: 0491468d-633e-4ddd-9ea2-3a263e3d361d, remote_addr: 127.0.0.1:47291, local_addr: 127.0.0.1:9998 Sep 24 22:12:00.867 INFO request completed, latency_us: 37, response_code: 200, uri: /port/cid/1, method: GET, req_id: ca4da3fc-5ac7-45b6-9dc0-da31114f18a7, remote_addr: 127.0.0.1:47291, local_addr: 127.0.0.1:9998 Sep 24 22:12:00.867 INFO request completed, latency_us: 30, response_code: 200, uri: /port/cid/2, method: GET, req_id: e25ef28e-24f4-4b46-96dd-3f86cec5e074, remote_addr: 127.0.0.1:47291, local_addr: 127.0.0.1:9998 Sep 24 22:12:00.869 INFO request completed, latency_us: 45, response_code: 200, uri: /port/cid/3, method: GET, req_id: 1b475558-6a55-4005-800d-2acff5d5bc39, remote_addr: 127.0.0.1:47291, local_addr: 127.0.0.1:9998 Sep 24 22:12:00.869 INFO request completed, latency_us: 31, response_code: 200, uri: /port/cid/4, method: GET, req_id: e8767d0b-b232-4367-94e9-793f5020be80, remote_addr: 127.0.0.1:47291, local_addr: 127.0.0.1:9998 Sep 24 22:12:00.869 INFO request completed, latency_us: 28, response_code: 200, uri: /port/cid/5, method: GET, req_id: 1ca52c8f-a625-4a39-8a99-a96105996a40, remote_addr: 127.0.0.1:47291, local_addr: 127.0.0.1:9998 Sep 24 22:12:17.548 INFO accepted connection, remote_addr: 127.0.0.1:41738, local_addr: 127.0.0.1:9998 Sep 24 22:12:17.548 INFO request completed, latency_us: 144, response_code: 200, uri: /regioninfo, method: GET, req_id: ff0306a4-f5a6-4b99-ac06-bc3b75d7a2ac, remote_addr: 127.0.0.1:41738, local_addr: 127.0.0.1:9998 Sep 24 22:12:17.549 INFO request completed, latency_us: 104, response_code: 200, uri: /regioncount, method: GET, req_id: 2725cf48-f760-47f5-b097-a02e9415657f, remote_addr: 127.0.0.1:41738, local_addr: 127.0.0.1:9998 Sep 24 22:12:17.549 INFO request completed, latency_us: 73, response_code: 200, uri: /port/cid/0, method: GET, req_id: b4a69c93-92d2-452c-ad96-cd2a487aad67, remote_addr: 127.0.0.1:41738, local_addr: 127.0.0.1:9998 Sep 24 22:12:17.550 INFO request completed, latency_us: 37, response_code: 200, uri: /port/cid/1, method: GET, req_id: aae01b94-41be-4320-af2d-a473d8e2e948, remote_addr: 127.0.0.1:41738, local_addr: 127.0.0.1:9998 Sep 24 22:12:17.550 INFO request completed, latency_us: 27, response_code: 200, uri: /port/cid/2, method: GET, req_id: d4b3a4e4-2e57-4e3f-9177-2417d35ecd9d, remote_addr: 127.0.0.1:41738, local_addr: 127.0.0.1:9998 Sep 24 22:12:17.551 INFO request completed, latency_us: 62, response_code: 200, uri: /port/cid/3, method: GET, req_id: 231be4c9-39a1-4ce5-9e42-d3e3ff597e27, remote_addr: 127.0.0.1:41738, local_addr: 127.0.0.1:9998 Sep 24 22:12:17.551 INFO request completed, latency_us: 29, response_code: 200, uri: /port/cid/4, method: GET, req_id: 0735f3b7-f2ee-472c-aca1-37853cebc963, remote_addr: 127.0.0.1:41738, local_addr: 127.0.0.1:9998 Sep 24 22:12:17.551 INFO request completed, latency_us: 25, response_code: 200, uri: /port/cid/5, method: GET, req_id: f683e646-c08e-4004-9969-bf2f25a6d6c6, remote_addr: 127.0.0.1:41738, local_addr: 127.0.0.1:9998 Sep 24 22:12:21.660 INFO accepted connection, remote_addr: 127.0.0.1:44859, local_addr: 127.0.0.1:9998 Sep 24 22:12:21.660 INFO request completed, latency_us: 145, response_code: 200, uri: /regioninfo, method: GET, req_id: 48b5dfbd-74bf-478c-aaf1-881cee52ba20, remote_addr: 127.0.0.1:44859, local_addr: 127.0.0.1:9998 Sep 24 22:12:21.661 INFO request completed, latency_us: 48, response_code: 200, uri: /regioncount, method: GET, req_id: e17e83bd-4652-4043-aec4-16fda60da2a7, remote_addr: 127.0.0.1:44859, local_addr: 127.0.0.1:9998 Sep 24 22:12:21.661 INFO request completed, latency_us: 61, response_code: 200, uri: /port/cid/0, method: GET, req_id: 4f4b12f7-571b-427f-a181-61368ccbce5f, remote_addr: 127.0.0.1:44859, local_addr: 127.0.0.1:9998 Sep 24 22:12:21.661 INFO request completed, latency_us: 31, response_code: 200, uri: /port/cid/1, method: GET, req_id: 41e6c9ec-f0c2-4a1e-9a2e-b0066574bcc2, remote_addr: 127.0.0.1:44859, local_addr: 127.0.0.1:9998 Sep 24 22:12:21.662 INFO request completed, latency_us: 29, response_code: 200, uri: /port/cid/2, method: GET, req_id: 3bc6e75f-1ae7-41b5-b13d-c37681300ad2, remote_addr: 127.0.0.1:44859, local_addr: 127.0.0.1:9998 Sep 24 22:12:21.663 INFO request completed, latency_us: 33, response_code: 200, uri: /port/cid/3, method: GET, req_id: 720fedec-cff3-4c27-a739-238878f3e8d8, remote_addr: 127.0.0.1:44859, local_addr: 127.0.0.1:9998 Sep 24 22:12:21.663 INFO request completed, latency_us: 28, response_code: 200, uri: /port/cid/4, method: GET, req_id: 90c7e7f5-d924-4530-bbb1-f201c582b64f, remote_addr: 127.0.0.1:44859, local_addr: 127.0.0.1:9998 Sep 24 22:12:21.663 INFO request completed, latency_us: 28, response_code: 200, uri: /port/cid/5, method: GET, req_id: e6270518-d549-4a83-b916-0ecc52391014, remote_addr: 127.0.0.1:44859, local_addr: 127.0.0.1:9998 Sep 24 22:12:25.759 INFO accepted connection, remote_addr: 127.0.0.1:63893, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc DisableRestartAll disable restart on all: 6 Sep 24 22:12:25.759 INFO request completed, latency_us: 135, response_code: 204, uri: /disablerestart/all, method: POST, req_id: f2c6ec85-adad-4add-9f45-b0c95d53a851, remote_addr: 127.0.0.1:63893, local_addr: 127.0.0[8820] Disable keep_running .[8810] Disable keep_running 1[8840] Disable keep_running :[8850] Disable keep_running 9998 [8860] Disable keep_running [8830] Disable keep_running Sep 24 22:12:25.772 INFO accepted connection, remote_addr: 127.0.0.1:49423, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(2) Sep 24 22:12:25.773 INFO stop 2 request completed, latency_us: 100, response_code: 204, uri: /stop/cid/2, method: POST, req_id: 1f90423e-e1ce-4fd6-bd1b-18cdf6d88876, remote_addr: 127.0.0.1:49423, 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 Sep 24 22:12:25.786 INFO accepted connection, remote_addr: 127.0.0.1:56625, local_addr: 127.0.0.1:9998 Sep 24 22:12:25.786 INFO request completed, latency_us: 73, response_code: 200, uri: /state/cid/2, method: GET, req_id: 17744607-e925-4956-9ae7-899e699a7479, remote_addr: 127.0.0.1:56625, local_addr: 127.0.0.1:9998 Sep 24 22:12:25.802 INFO accepted connection, remote_addr: 127.0.0.1:44081, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(2) start 2 Sep 24 22:12:25.802 INFO request completed, latency_us: 77, response_code: 204, uri: /start/cid/2, method: POST, req_id: c2fa63e0-dea8-4121-9845-4ea2b2a6dcf8, remote_addr: 127.0.0.1:44081, local_addr: 127.0.0.1:9998 Sep 24 22:12:25.836 INFO accepted connection, remote_addr: 127.0.0.1:49769, local_addr: 127.0.0.1:9998 Sep 24 22:12:25.836 INFO request completed, latency_us: 104, response_code: 200, uri: /regioninfo, method: GET, req_id: 9af5ac81-c308-4511-bd18-c8fade1becf4, remote_addr: 127.0.0.1:49769, local_addr: 127.0.0.1:9998 Sep 24 22:12:25.837 INFO request completed, latency_us: 31, response_code: 200, uri: /regioncount, method: GET, req_id: 39ca73ea-0a1e-491a-bbfb-7710d85b2eb3, remote_addr: 127.0.0.1:49769, local_addr: 127.0.0.1:9998 Sep 24 22:12:25.837 INFO request completed, latency_us: 81, response_code: 200, uri: /port/cid/0, method: GET, req_id: 8cd78d6c-c68b-4c3e-a5d5-f5c28940ef97, remote_addr: 127.0.0.1:49769, local_addr: 127.0.0.1:9998 Sep 24 22:12:25.838 INFO request completed, latency_us: 69, response_code: 200, uri: /port/cid/1, method: GET, req_id: 6af1e2f9-428e-42ce-a66d-1f590cd8eb58, remote_addr: 127.0.0.1:49769, local_addr: 127.0.0.1:9998 Sep 24 22:12:25.838 INFO request completed, latency_us: 59, response_code: 200, uri: /port/cid/2, method: GET, req_id: d2852c6a-b0f9-483f-aef4-d336bb28629a, remote_addr: 127.0.0.1:49769, local_addr: 127.0.0.1:9998 Sep 24 22:12:25.839 INFO request completed, latency_us: 71, response_code: 200, uri: /port/cid/3, method: GET, req_id: 27f8fd99-ca18-47bd-a4e6-8193c864a447, remote_addr: 127.0.0.1:49769, local_addr: 127.0.0.1:9998 Sep 24 22:12:25.840 INFO request completed, latency_us: 60, response_code: 200, uri: /port/cid/4, method: GET, req_id: 4f57834c-93d8-4d0b-90e3-e6f693c4aae8, remote_addr: 127.0.0.1:49769, local_addr: 127.0.0.1:9998 Sep 24 22:12:25.840 INFO request completed, latency_us: 65, response_code: 200, uri: /port/cid/5, method: GET, req_id: 92d47910-7138-4cac-b0f7-aa2c1f3eee74, remote_addr: 127.0.0.1:49769, 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 Sep 24 22:12:39.974 INFO accepted connection, remote_addr: 127.0.0.1:61745, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Shutdown Shutdown Shut it down Sep 24 22:12:39.975 INFO request completed, latency_us[8810] Disable keep_running : 122[8810] Got stop action so:false kr:false , response_code: 204, uri: /shutdown, method: POST, req_id: 973c5a99-fff6-4ff7-9696-f3bda0ca7f77, remote_addr: 127.0.0.1:61745, local_addr: 127.0.0.1:9998 [8830] Disable keep_running [8840] Disable keep_running [8830] Got stop action so:false kr:false [8850] Disable keep_running [8820] Disable keep_running [8840] Got stop action so:false kr:false [8850] Got stop action so:false kr:false [8860] Disable keep_running [8820] Got stop action so:false kr:false [8860] Got stop action so:false kr:false [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit [8850] Exited with: ExitStatus(unix_wait_status(9)) [8850][4] 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 [8840] Exited with: ExitStatus(unix_wait_status(9)) [8840][3] reports Exit [8860] Exited with: ExitStatus(unix_wait_status(9)) [8860][5] 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.03013426 Downstairs region 1 created at /var/tmp/test_up-build/8820 in 0.02790296 Downstairs region 2 created at /var/tmp/test_up-build/8830 in 0.02713494 Downstairs region 3 created at /var/tmp/test_up-build/8840 in 0.026718112 Downstairs region 4 created at /var/tmp/test_up-build/8850 in 0.026533956 Downstairs region 5 created at /var/tmp/test_up-build/8860 in 0.026031 Downstairs region 6 created at /var/tmp/test_up-build/8870 in 0.033092722 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 start ds: 8870 Starting downstairs at port 8820 Starting downstairs at port 8830 Make output file at "/tmp/test_up-build/dsc/downstairs-8810.txt" [8810][0] initial start wait reports Starting Make output file at "/tmp/test_up-build/dsc/downstairs-8830.txt" [8820][1] initial start wait reports Starting Make output file at "/tmp/test_up-build/dsc/downstairs-8820.txt" Starting downstairs at port 8840 [8830][2] initial start wait reports Starting Make output file at "/tmp/test_up-build/dsc/downstairs-8840.txt" Starting downstairs at port 8850 [8840][3] initial start wait reports Starting Make output file at "/tmp/test_up-build/dsc/downstairs-8850.txt" Starting downstairs at port 8870 [8850][4] initial start wait reports Starting Make output file at "/tmp/test_up-build/dsc/downstairs-8870.txt" [8870][6] initial start wait reports Starting Starting downstairs at port 8860 Make output file at "/tmp/test_up-build/dsc/downstairs-8860.txt" [8860][5] initial start wait reports Starting Downstairs /var/tmp/test_up-build/8830 port 8830 PID:Some(1220) Downstairs /var/tmp/test_up-build/8860 port 8860 PID:Some(1214) Downstairs /var/tmp/test_up-build/8840 port 8840 PID:Some(1215) [8830][2] initial start wait reports Running [8860][5] initial start wait reports Running [8840][3] initial start wait reports Running Control access at:127.0.0.1:9998 Sep 24 22:12:45.195 INFO listening, local_addr: 127.0.0.1:9998 Downstairs /var/tmp/test_up-build/8810 port 8810 PID:Some(1217) [8810][0] initial start wait reports Running Downstairs /var/tmp/test_up-build/8820 port 8820 PID:Some(1219) [8820][1] initial start wait reports Running Downstairs /var/tmp/test_up-build/8850 port 8850 PID:Some(1216) [8850][4] initial start wait reports Running Downstairs /var/tmp/test_up-build/8870 port 8870 PID:Some(1218) [8870][6] initial start wait reports Running All downstairs are running Sep 24 22:12:50.206 INFO accepted connection, remote_addr: 127.0.0.1:41408, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.207 INFO request completed, latency_us: 301, response_code: 200, uri: /state/cid/0, method: GET, req_id: 8eb7cd74-b78e-4d07-8000-d3c34aafe186, remote_addr: 127.0.0.1:41408, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.222 INFO accepted connection, remote_addr: 127.0.0.1:62962, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.222 INFO request completed, latency_us: 74, response_code: 200, uri: /state/cid/1, method: GET, req_id: 71d55bcc-e9f9-4a4b-8724-4167c692389a, remote_addr: 127.0.0.1:62962, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.235 INFO accepted connection, remote_addr: 127.0.0.1:56037, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.236 INFO request completed, latency_us: 69, response_code: 200, uri: /state/cid/2, method: GET, req_id: be994aa2-4fec-4b58-a929-cc433c5f4e63, remote_addr: 127.0.0.1:56037, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.249 INFO accepted connection, remote_addr: 127.0.0.1:62522, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.249 INFO request completed, latency_us: 63, response_code: 200, uri: /state/cid/3, method: GET, req_id: 4d623443-13b0-476c-aa61-2be8158bb1fd, remote_addr: 127.0.0.1:62522, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.262 INFO accepted connection, remote_addr: 127.0.0.1:47247, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.262 INFO request completed, latency_us: 61, response_code: 200, uri: /state/cid/4, method: GET, req_id: a666d92d-e627-4f1d-a908-b0616d04437e, remote_addr: 127.0.0.1:47247, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.275 INFO accepted connection, remote_addr: 127.0.0.1:53095, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.275 INFO request completed, latency_us: 53, response_code: 200, uri: /state/cid/5, method: GET, req_id: 50033523-e7cd-45f1-913d-3bfd1f1d9447, remote_addr: 127.0.0.1:53095, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.288 INFO accepted connection, remote_addr: 127.0.0.1:60834, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.288 INFO request completed, latency_us: 61, response_code: 200, uri: /state/cid/6, method: GET, req_id: 2af5db4e-303b-44ed-81e4-1d427f3514d9, remote_addr: 127.0.0.1:60834, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.301 INFO accepted connection, remote_addr: 127.0.0.1:53841, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.301 INFO request completed, latency_us: 79, response_code: 200, uri: /port/cid/6, method: GET, req_id: ab7787c2-05ae-4a80-b70f-033952d4726b, remote_addr: 127.0.0.1:53841, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.321 INFO accepted connection, remote_addr: 127.0.0.1:56797, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.321 INFO request completed, latency_us: 92, response_code: 200, uri: /regioninfo, method: GET, req_id: ae329b6a-a00d-4e3f-b5a1-6903bf5950cc, remote_addr: 127.0.0.1:56797, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.322 INFO request completed, latency_us: 66, response_code: 200, uri: /regioncount, method: GET, req_id: 1000f63a-55f5-470e-9916-2ed633b86a6c, remote_addr: 127.0.0.1:56797, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.322 INFO request completed, latency_us: 38, response_code: 200, uri: /port/cid/0, method: GET, req_id: 4c0ed9f2-d9aa-42ed-b5a2-25fc2f03a53f, remote_addr: 127.0.0.1:56797, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.322 INFO request completed, latency_us: 25, response_code: 200, uri: /port/cid/1, method: GET, req_id: c245b192-8cf6-42cf-88d8-50274d53acb8, remote_addr: 127.0.0.1:56797, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.323 INFO request completed, latency_us: 36, response_code: 200, uri: /port/cid/2, method: GET, req_id: 993c2336-feb4-4cc4-bb51-005456d6a3a8, remote_addr: 127.0.0.1:56797, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.324 INFO request completed, latency_us: 62, response_code: 200, uri: /port/cid/3, method: GET, req_id: a7aac8bf-43c5-47d0-a560-03f03863d43c, remote_addr: 127.0.0.1:56797, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.324 INFO request completed, latency_us: 56, response_code: 200, uri: /port/cid/4, method: GET, req_id: 22f8dbc1-cbdf-4bf2-9f45-513d6478e14b, remote_addr: 127.0.0.1:56797, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.324 INFO request completed, latency_us: 55, response_code: 200, uri: /port/cid/5, method: GET, req_id: 723770c6-c4aa-4d20-8a4a-165f64f54755, remote_addr: 127.0.0.1:56797, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.347 INFO accepted connection, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.348 INFO request completed, latency_us: 142, response_code: 200, uri: /regioncount, method: GET, req_id: 0e4178b4-d7e1-4c61-8a5a-1e21b1652400, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Sep 24 22:12:50.743 INFO request completed[8810] Got stop action so:false kr:false , latency_us: 305, response_code: 204, uri: /stop/cid/0, method: POST, req_id: f938811e-600d-49ed-ac0c-430fb6060be0, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:12:50.743 INFO request completed, latency_us: 121, response_code: 200, uri: /state/cid/0, method: GET, req_id: be04f771-06b2-41eb-99dc-b8fbb3898543, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 24 22:12:54.745 INFO request completed, latency_us: 217, response_code: 200, uri: /state/cid/0, method: GET, req_id: 20688287-a247-4405-996b-2470d60542db, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 24 22:12:55.144 INFO request completed, latency_us: 216, response_code: 204, uri: /start/cid/0, method: POST, req_id: 9ae32394-8ebb-4084-b748-daef068d5baa, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:12:55.144 INFO request completed, latency_us: 85, response_code: 200, uri: /state/cid/0, method: GET, req_id: 8b46ef66-57b2-48c9-9025-7ab8a365cfd4, remote_addr: 127.0.0.1:34871, 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 Sep 24 22:12:59.146 INFO request completed, latency_us: 220, response_code: 200, uri: /state/cid/0, method: GET, req_id: 90e4cbab-53bb-44a9-bed3-da6b644f75c6, remote_addr: 127.0.0.1:34871, 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 Sep 24 22:13:05.542 INFO request completed, latency_us: 218, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 467fb6b9-0dd1-4df0-a408-012047c6ff03, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:13:05.542 INFO request completed, latency_us: 115, response_code: 200, uri: /state/cid/0, method: GET, req_id: 5c3da8e9-e0e1-489a-9b79-792a40a3bf6a, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 24 22:13:09.545 INFO request completed, latency_us: 207, response_code: 200, uri: /state/cid/0, method: GET, req_id: c614c640-3e25-433a-b496-ea9155f96995, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 24 22:13:09.947 INFO request completed, latency_us: 216, response_code: 204, uri: /start/cid/0, method: POST, req_id: 5224fad8-b223-453b-9277-8704d705f4ce, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:13:09.947 INFO request completed, latency_us: 62, response_code: 200, uri: /state/cid/0, method: GET, req_id: 0d1153b6-dcf2-40ea-bba0-b94a7e85b8cc, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:13:13.949 INFO request completed, latency_us: 195, response_code: 200, uri: /state/cid/0, method: GET, req_id: 1906b504-d8d0-4812-b707-3792e981694d, remote_addr: 127.0.0.1:34871, 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 Sep 24 22:13:17.951 INFO request completed, latency_us: 178, response_code: 200, uri: /state/cid/0, method: GET, req_id: 44789524-da63-46b6-982c-a62d752771b2, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Sep 24 22:13:23.346[8810] Got stop action so:false kr:false INFO request completed, latency_us: 222, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 9b5079ce-62cf-4066-94d3-68e01fdc903b, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:13:23.346 INFO request completed, latency_us: 84, response_code: 200, uri: /state/cid/0, method: GET, req_id: 28fb2018-2f27-495d-a136-0fa452bd2ff7, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 24 22:13:27.348 INFO request completed, latency_us: 210, response_code: 200, uri: /state/cid/0, method: GET, req_id: ae4bb575-f830-49bb-90d9-45d54a05267b, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 24 22:13:27.755 INFO request completed, latency_us: 226, response_code: 204, uri: /start/cid/0, method: POST, req_id: 511a6646-e08a-4e5c-a46a-e71afafa66dc, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:13:27.755 INFO request completed, latency_us: 49, response_code: 200, uri: /state/cid/0, method: GET, req_id: 25b1f914-9d52-4e57-a596-b4955bd4fb91, remote_addr: 127.0.0.1:34871, 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 Sep 24 22:13:31.757 INFO request completed, latency_us: 188, response_code: 200, uri: /state/cid/0, method: GET, req_id: cc7ecbb6-c4ad-4e06-a701-91164751a067, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Sep 24 22:13:36.151[8810] Got stop action so:false kr:false INFO request completed, latency_us: 217, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 1ffe4474-a67b-4610-8b7b-4e572f0cb322, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:13:36.152 INFO request completed, latency_us: 91, response_code: 200, uri: /state/cid/0, method: GET, req_id: 10fc960b-9a95-42dd-b83c-9fe7c330c785, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 24 22:13:40.154 INFO request completed, latency_us: 211, response_code: 200, uri: /state/cid/0, method: GET, req_id: b8bd8101-7539-4bba-b775-7cea159ed273, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 24 22:13:40.559 INFO request completed, latency_us: 220, response_code: 204, uri: /start/cid/0, method: POST, req_id: e5c408e5-9c44-4a20-a2d1-d4088c7ca041, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:13:40.559 INFO request completed, latency_us: 83, response_code: 200, uri: /state/cid/0, method: GET, req_id: e00ab1a4-dbd1-4216-9eb6-ea1a0da807ae, remote_addr: 127.0.0.1:34871, 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 Sep 24 22:13:44.561 INFO request completed, latency_us: 191, response_code: 200, uri: /state/cid/0, method: GET, req_id: c15139e0-6c3b-48d5-be6c-182e730b601e, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Sep 24 22:13:47.955 [8810] Got stop action so:false kr:false INFO request completed, latency_us: 231, response_code: 204, uri: /stop/cid/0, method: POST, req_id: ced2b304-01cc-4aa8-8fd5-d422287b4480, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:13:47.955 INFO request completed, latency_us: 289, response_code: 200, uri: /state/cid/0, method: GET, req_id: 1b333055-6c79-4e0f-b181-ddd3655fe0f5, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 24 22:13:51.957 INFO request completed, latency_us: 212, response_code: 200, uri: /state/cid/0, method: GET, req_id: 9a52355d-7b0b-42b9-a931-cca714d30f5e, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 24 22:13:52.365 INFO request completed, latency_us: 219, response_code: 204, uri: /start/cid/0, method: POST, req_id: 1996b00b-2e96-453d-902f-135d5cf1cacc, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:13:52.366 INFO request completed, latency_us: 166, response_code: 200, uri: /state/cid/0, method: GET, req_id: a95c20fa-5ca8-4563-81c8-8f54bc397c03, remote_addr: 127.0.0.1:34871, 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 Sep 24 22:13:56.368 INFO request completed, latency_us: 190, response_code: 200, uri: /state/cid/0, method: GET, req_id: cc625ae2-6367-4920-ac81-a931b96ddcdb, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Sep 24 22:13:58.762 INFO [8810] Got stop action so:false kr:false request completed, latency_us: 219, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 09734ad3-2a96-4167-b518-9012199415fc, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:13:58.763 INFO request completed, latency_us: 86, response_code: 200, uri: /state/cid/0, method: GET, req_id: 86f82842-fcc8-4de3-b4ac-a6e7d0a90c3a, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 24 22:14:02.765 INFO request completed, latency_us: 209, response_code: 200, uri: /state/cid/0, method: GET, req_id: b561aca1-1004-433e-a1a0-e1ec3e737da5, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 24 22:14:03.171 INFO request completed, latency_us: 218, response_code: 204, uri: /start/cid/0, method: POST, req_id: bd60fe98-157b-4754-ab3b-c9648bae12d1, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:14:03.172 INFO request completed, latency_us: 75, response_code: 200, uri: /state/cid/0, method: GET, req_id: 6e9ea9b4-0ca7-43a0-af01-6f4bab20ff6e, remote_addr: 127.0.0.1:34871, 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 Sep 24 22:14:07.174 INFO request completed, latency_us: 216, response_code: 200, uri: /state/cid/0, method: GET, req_id: bca60576-5468-4500-9de7-ae028a65240c, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(1) stop 1 [8820] Got stop action so:false kr:false Sep 24 22:14:34.665 INFO request completed, latency_us: 235, response_code: 204, uri: /stop/cid/1, method: POST, req_id: b3e7da34-869e-4695-b6c5-7ccff3c16925, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:14:34.666 INFO request completed, latency_us: 88, response_code: 200, uri: /state/cid/1, method: GET, req_id: 26414f4c-a4c2-4d9a-a2c9-0df6722691da, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] reports Exit Sep 24 22:14:38.668 INFO request completed, latency_us: 207, response_code: 200, uri: /state/cid/1, method: GET, req_id: aacc274d-ad9e-43fb-9352-a339eb6cdbff, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(1) start 1 Sep 24 22:14:39.075 INFO request completed, latency_us: 234, response_code: 204, uri: /start/cid/1, method: POST, req_id: 9bfada35-3ea4-4b94-a46d-9fbbf64f3c80, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:14:39.075 INFO request completed, latency_us: 75, response_code: 200, uri: /state/cid/1, method: GET, req_id: c44aa2b4-2b6f-483e-9e71-8d59802d813e, remote_addr: 127.0.0.1:34871, 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 Sep 24 22:14:43.077 INFO request completed, latency_us: 197, response_code: 200, uri: /state/cid/1, method: GET, req_id: 24fd7a9d-cf66-432b-9653-311a6ac63f3f, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(2) stop 2 Sep 24 22:15:10.680 INFO request completed, latency_us: [8830] Got stop action so:false kr:false 221, response_code: 204, uri: /stop/cid/2, method: POST, req_id: 91e7bc82-2270-498a-97c5-8058f1704576, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:15:10.681 INFO request completed, latency_us: 143, response_code: 200, uri: /state/cid/2, method: GET, req_id: 4b5d470c-adea-463f-9210-8eae40761a6a, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit Sep 24 22:15:14.683 INFO request completed, latency_us: 209, response_code: 200, uri: /state/cid/2, method: GET, req_id: b2c4fd65-8ab5-4965-ba45-b387177d2c89, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(2) start 2 Sep 24 22:15:15.089 INFO request completed, latency_us: 218, response_code: 204, uri: /start/cid/2, method: POST, req_id: 21509e07-daf0-4a83-8875-c631d3b9ea6f, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:15:15.090 INFO request completed, latency_us: 117, response_code: 200, uri: /state/cid/2, method: GET, req_id: 8bd372b1-fe4a-4a03-9787-db4329fabcb4, remote_addr: 127.0.0.1:34871, 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 Sep 24 22:15:19.092 INFO request completed, latency_us: 196, response_code: 200, uri: /state/cid/2, method: GET, req_id: 86075208-c20b-4c5c-8102-6af7672f4460, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(3) stop 3 Sep 24 22:15:46.913[8840] Got stop action so:false kr:false INFO request completed, latency_us: 215, response_code: 204, uri: /stop/cid/3, method: POST, req_id: 4edd7bcc-81c0-47c0-b405-f875bf72d5be, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:15:46.914 INFO request completed, latency_us: 74, response_code: 200, uri: /state/cid/3, method: GET, req_id: 13b219b0-bd9d-4c7f-90e2-e835106c10b9, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 [8840] Exited with: ExitStatus(unix_wait_status(9)) [8840][3] reports Exit Sep 24 22:15:50.916 INFO request completed, latency_us: 199, response_code: 200, uri: /state/cid/3, method: GET, req_id: db74d88e-5899-4271-9bf3-632444cb6106, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(3) start 3 Sep 24 22:15:51.330 INFO request completed, latency_us: 231, response_code: 204, uri: /start/cid/3, method: POST, req_id: 6b0691ca-6294-4a07-99ba-e4cb545ff8a2, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:15:51.331 INFO request completed, latency_us: 62, response_code: 200, uri: /state/cid/3, method: GET, req_id: 72c3bdee-bc3b-4ae9-9753-d1f58d76f583, remote_addr: 127.0.0.1:34871, 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 Sep 24 22:15:55.333 INFO request completed, latency_us: 189, response_code: 200, uri: /state/cid/3, method: GET, req_id: 9779d2d9-b563-4da3-a221-a1bc3b65512a, remote_addr: 127.0.0.1:34871, local_addr: 127.0.0.1:9998 Sep 24 22:16:23.036 INFO accepted connection, remote_addr: 127.0.0.1:53230, local_addr: 127.0.0.1:9998 Sep 24 22:16:23.036 INFO request completed, latency_us: 166, response_code: 200, uri: /regioninfo, method: GET, req_id: 6705c104-89d2-4bc3-abf8-74b3d62b066a, remote_addr: 127.0.0.1:53230, local_addr: 127.0.0.1:9998 Sep 24 22:16:23.037 INFO request completed, latency_us: 38, response_code: 200, uri: /regioncount, method: GET, req_id: e5370ed0-ac64-42e5-a169-99c5e39a4c64, remote_addr: 127.0.0.1:53230, local_addr: 127.0.0.1:9998 Sep 24 22:16:23.037 INFO request completed, latency_us: 55, response_code: 200, uri: /port/cid/0, method: GET, req_id: e409ac58-3c9f-449a-a117-5e870e67a647, remote_addr: 127.0.0.1:53230, local_addr: 127.0.0.1:9998 Sep 24 22:16:23.037 INFO request completed, latency_us: 30, response_code: 200, uri: /port/cid/1, method: GET, req_id: 7c39c81b-d3b4-4d21-ab68-c6476db2d574, remote_addr: 127.0.0.1:53230, local_addr: 127.0.0.1:9998 Sep 24 22:16:23.037 INFO request completed, latency_us: 28, response_code: 200, uri: /port/cid/2, method: GET, req_id: 4698d99a-0951-4890-92ad-859d01182808, remote_addr: 127.0.0.1:53230, local_addr: 127.0.0.1:9998 Sep 24 22:16:23.039 INFO request completed, latency_us: 75, response_code: 200, uri: /port/cid/3, method: GET, req_id: 3ca53648-2244-4169-8c1c-47c1868a07c2, remote_addr: 127.0.0.1:53230, local_addr: 127.0.0.1:9998 Sep 24 22:16:23.039 INFO request completed, latency_us: 63, response_code: 200, uri: /port/cid/4, method: GET, req_id: c2fe0194-c106-4f68-8d77-8dd7d3d6ea5e, remote_addr: 127.0.0.1:53230, local_addr: 127.0.0.1:9998 Sep 24 22:16:23.040 INFO request completed, latency_us: 62, response_code: 200, uri: /port/cid/5, method: GET, req_id: aa59d0dc-e4b1-4ed3-9901-5dafbe170277, remote_addr: 127.0.0.1:53230, local_addr: 127.0.0.1:9998 Sep 24 22:16:23.219 INFO accepted connection, remote_addr: 127.0.0.1:52043, local_addr: 127.0.0.1:9998 Sep 24 22:16:23.220 INFO request completed, latency_us: 130, response_code: 200, uri: /regioncount, method: GET, req_id: a8513f32-f900-4d14-9f8e-564b64df7b61, remote_addr: 127.0.0.1:52043, 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 Sep 24 22:16:28.958 INFO request completed, latency_us: 253, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 260d2d46-d96c-4aee-8662-f88d4e003e57, remote_addr: 127.0.0.1:52043, local_addr: 127.0.0.1:9998 Sep 24 22:16:28.958 INFO request completed, latency_us: 142, response_code: 200, uri: /state/cid/0, method: GET, req_id: 9751d972-520f-4258-98cf-587d031cf003, remote_addr: 127.0.0.1:52043, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 24 22:16:32.961 INFO request completed, latency_us: 220, response_code: 200, uri: /state/cid/0, method: GET, req_id: fec7723d-f2c3-44a3-be5c-abf1f89934fd, remote_addr: 127.0.0.1:52043, local_addr: 127.0.0.1:9998 Sep 24 22:17:10.438 INFO accepted connection, remote_addr: 127.0.0.1:32959, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 24 22:17:10.439 INFO request completed, latency_us: 159, response_code: 204, uri: /start/cid/0, method: POST, req_id: 3ca798af-b07e-4128-b05a-10b802c16796, remote_addr: 127.0.0.1:32959, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(1) stop 1 Sep 24 22:17:14.842 INFO request completed[8820] Got stop action so:false kr:false , latency_us: 272, response_code: 204, uri: /stop/cid/1, method: POST, req_id: 063a5736-251f-4d61-b1f1-e060e0035b89, remote_addr: 127.0.0.1:32959, local_addr: 127.0.0.1:9998 Sep 24 22:17:14.843 INFO request completed, latency_us: 60, response_code: 200, uri: /state/cid/1, method: GET, req_id: 4ce4ade7-e3da-4a28-b7a4-032f915ee560, remote_addr: 127.0.0.1:32959, local_addr: 127.0.0.1:9998 [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] reports Exit [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 Sep 24 22:17:18.845 INFO request completed, latency_us: 237, response_code: 200, uri: /state/cid/1, method: GET, req_id: 017d7e63-7233-49f4-8cb8-8d30efbdef02, remote_addr: 127.0.0.1:32959, local_addr: 127.0.0.1:9998 Sep 24 22:17:56.303 INFO accepted connection, remote_addr: 127.0.0.1:39402, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(1) start 1 Sep 24 22:17:56.303 INFO request completed, latency_us: 152, response_code: 204, uri: /start/cid/1, method: POST, req_id: 03f2ddc8-d471-49a5-a662-beaa4c109b0a, remote_addr: 127.0.0.1:39402, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(2) stop 2 Sep 24 22:18:00.709 INFO [8830] Got stop action so:false kr:false request completed, latency_us: 229, response_code: 204, uri: /stop/cid/2, method: POST, req_id: 26abcfd6-d172-40ce-915b-6b249c74e5ec, remote_addr: 127.0.0.1:39402, local_addr: 127.0.0.1:9998 Sep 24 22:18:00.709 INFO request completed, latency_us: 53, response_code: 200, uri: /state/cid/2, method: GET, req_id: 6b6e948c-b778-4cec-895c-9639f10681f6, remote_addr: 127.0.0.1:39402, local_addr: 127.0.0.1:9998 [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit Sep 24 22:18:04.712 INFO request completed, latency_us: 217, response_code: 200, uri: /state/cid/2, method: GET, req_id: 99e1e5ec-b08a-48cc-8b8a-ec3ed8b6107f, remote_addr: 127.0.0.1:39402, 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(1242) [8820][1] reports Running Sep 24 22:18:42.187 INFO accepted connection, remote_addr: 127.0.0.1:52896, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(2) start 2 Sep 24 22:18:42.187 INFO request completed, latency_us: 160, response_code: 204, uri: /start/cid/2, method: POST, req_id: 6c85fa08-3b7e-4e04-9f97-6408a3f73a65, remote_addr: 127.0.0.1:52896, 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 Sep 24 22:18:46.607 [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: 13adf42f-2fb4-4480-9b91-cdcda012ec97, remote_addr: 127.0.0.1:52896, local_addr: 127.0.0.1:9998 Sep 24 22:18:46.607 INFO request completed, latency_us: 58, response_code: 200, uri: /state/cid/3, method: GET, req_id: e7db546a-a30a-4764-acde-811d15a791c2, remote_addr: 127.0.0.1:52896, local_addr: 127.0.0.1:9998 [8840] Exited with: ExitStatus(unix_wait_status(9)) [8840][3] reports Exit Sep 24 22:18:50.609 INFO request completed, latency_us: 216, response_code: 200, uri: /state/cid/3, method: GET, req_id: f273865b-e3ea-44bd-8726-9a07d68d376e, remote_addr: 127.0.0.1:52896, local_addr: 127.0.0.1:9998 Sep 24 22:19:28.132 INFO accepted connection, remote_addr: 127.0.0.1:36052, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(3) start 3 Sep 24 22:19:28.132 INFO request completed, latency_us: 166, response_code: 204, uri: /start/cid/3, method: POST, req_id: b4af369e-4a1f-48c0-a906-7f485c3f5b22, remote_addr: 127.0.0.1:36052, 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 Sep 24 22:19:32.687 INFO request completed, [8850] Got stop action so:false kr:false latency_us: 220, response_code: 204, uri: /stop/cid/4, method: POST, req_id: 42f2e8e4-a344-4efd-b496-6aba3a4993f6, remote_addr: 127.0.0.1:36052, local_addr: 127.0.0.1:9998 Sep 24 22:19:32.688 INFO request completed, latency_us: 95, response_code: 200, uri: /state/cid/4, method: GET, req_id: 80ba27a6-8256-4ea1-aaeb-1b9b8a150647, remote_addr: 127.0.0.1:36052, local_addr: 127.0.0.1:9998 [8850] Exited with: ExitStatus(unix_wait_status(9)) [8850][4] reports Exit Sep 24 22:19:36.690 INFO request completed, latency_us: 297, response_code: 200, uri: /state/cid/4, method: GET, req_id: 3b79bdbd-7cb0-4713-a373-dc23f7ff322b, remote_addr: 127.0.0.1:36052, local_addr: 127.0.0.1:9998 Sep 24 22:20:14.158 INFO accepted connection, remote_addr: 127.0.0.1:61365, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(4) Sep 24 22:20:14.158start 4 INFO request completed, latency_us: 161, response_code: 204, uri: /start/cid/4, method: POST, req_id: b8596f62-423f-4646-9f6f-91345c9189ee, remote_addr: 127.0.0.1:61365, 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 Sep 24 22:20:18.205 INFO accepted connection, remote_addr: 127.0.0.1:48421, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Shutdown Shutdown Shut it down Sep 24 22:20:18.205 INFO request completed, latency_us: 123, response_code: 204, uri: /shutdown, method: POST, req_id: 2b92e1ab-4997-46e9-acfe-1bf9611b1e5f[8810] Disable keep_running , [8810] Got stop action so:false kr:false remote_addr[8820] Disable keep_running : 127.0.0.1:48421, local_addr: 127.0.0.1:9998 [8830] Disable keep_running [8830] Got stop action so:false kr:false [8840] Disable keep_running [8840] Got stop action so:false kr:false [8850] Disable keep_running [8860] Disable keep_running [8860] Got stop action so:false kr:false [8850] Got stop action so:false kr:false [8870] Disable keep_running [8870] Got stop action so:false kr:false [8820] 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)) [8810] Exited with: ExitStatus(unix_wait_status(9)) [8860][5] reports Exit [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