/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.17908725 Downstairs region 1 created at /var/tmp/test_up-build/8820 in 0.011510474 Downstairs region 2 created at /var/tmp/test_up-build/8830 in 0.010989859 Downstairs region 3 created at /var/tmp/test_up-build/8840 in 0.010774178 Downstairs region 4 created at /var/tmp/test_up-build/8850 in 0.010812014 Downstairs region 5 created at /var/tmp/test_up-build/8860 in 0.010840331 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 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 Starting downstairs at port 8830 Starting downstairs at port 8840 Make output file at "/tmp/test_up-build/dsc/downstairs-8840.txt" [8810][0] initial start wait reports Starting Make output file at "/tmp/test_up-build/dsc/downstairs-8820.txt" Make output file at "/tmp/test_up-build/dsc/downstairs-8830.txt" [8820][1] initial start wait reports Starting [8830][2] initial start wait reports Starting 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 8860 Make output file at "/tmp/test_up-build/dsc/downstairs-8860.txt" [8850][4] initial start wait reports Starting Make output file at "/tmp/test_up-build/dsc/downstairs-8810.txt" [8860][5] initial start wait reports Starting Downstairs /var/tmp/test_up-build/8810 port 8810 PID:Some(1140) Downstairs /var/tmp/test_up-build/8840 port 8840 PID:Some(1143) [8810][0] initial start wait reports Running [8840][3] initial start wait reports Running Downstairs /var/tmp/test_up-build/8830 port 8830 PID:Some(1142) Downstairs /var/tmp/test_up-build/8820 port 8820 PID:Some(1141) [8830][2] initial start wait reports Running [8820][1] initial start wait reports Running Downstairs /var/tmp/test_up-build/8860 port 8860 PID:Some(1139) [8860][5] initial start wait reports Running Downstairs /var/tmp/test_up-build/8850 port 8850 PID:Some(1138) [8850][4] initial start wait reports Running All downstairs are running Control access at:127.0.0.1:9998 Sep 09 17:47:44.694 INFO listening, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.699 INFO accepted connection, remote_addr: 127.0.0.1:65207, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.701 INFO request completed, latency_us: 428, response_code: 200, uri: /state/cid/0, method: GET, req_id: 08f77749-304d-4b2e-937e-f5540c6202b5, remote_addr: 127.0.0.1:65207, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.714 INFO accepted connection, remote_addr: 127.0.0.1:45694, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.715 INFO request completed, latency_us: 62, response_code: 200, uri: /state/cid/1, method: GET, req_id: e84bf971-5013-4b44-a5c8-9e1dc2672e6b, remote_addr: 127.0.0.1:45694, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.728 INFO accepted connection, remote_addr: 127.0.0.1:35272, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.728 INFO request completed, latency_us: 74, response_code: 200, uri: /state/cid/2, method: GET, req_id: 56814595-6a65-4861-bf87-3ee419de0638, remote_addr: 127.0.0.1:35272, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.741 INFO accepted connection, remote_addr: 127.0.0.1:41230, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.742 INFO request completed, latency_us: 66, response_code: 200, uri: /state/cid/3, method: GET, req_id: 252e3588-4caa-492b-ac48-20d07d494378, remote_addr: 127.0.0.1:41230, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.755 INFO accepted connection, remote_addr: 127.0.0.1:36416, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.755 INFO request completed, latency_us: 57, response_code: 200, uri: /state/cid/4, method: GET, req_id: f984a9cb-91e7-4900-9d4c-73b6366dda81, remote_addr: 127.0.0.1:36416, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.768 INFO accepted connection, remote_addr: 127.0.0.1:60364, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.769 INFO request completed, latency_us: 61, response_code: 200, uri: /state/cid/5, method: GET, req_id: 5176e26b-e58e-4d60-ad08-369f8f8a4a21, remote_addr: 127.0.0.1:60364, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.781 INFO accepted connection, remote_addr: 127.0.0.1:37795, 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 09 17:47:49.782 INFO request completed, latency_us: 80, response_code: 204, uri: /disablerestart/all, method: POST, req_id: 889cf84a-514f-4bbf-981d-6c8a6c34c409, remote_addr: 127.0.0.1:37795, local_addr: 127.0.0.1:9998 [8810] Disable keep_running [8830] Disable keep_running [8840] Disable keep_running [8820] Disable keep_running [8850] Disable keep_running [8860] Disable keep_running Sep 09 17:47:49.849 INFO accepted connection, remote_addr: 127.0.0.1:62438, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.850 INFO request completed, latency_us: 252, response_code: 200, uri: /regioninfo, method: GET, req_id: 762d28e0-ad0c-4928-b882-3b6d4a4b2cce, remote_addr: 127.0.0.1:62438, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.852 INFO request completed, latency_us: 37, response_code: 200, uri: /regioncount, method: GET, req_id: 72ca14d6-673d-4d7f-8eab-f7453f435ef9, remote_addr: 127.0.0.1:62438, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.853 INFO request completed, latency_us: 85, response_code: 200, uri: /port/cid/0, method: GET, req_id: 97d3d01a-3eac-4d32-9919-1fea55681401, remote_addr: 127.0.0.1:62438, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.853 INFO request completed, latency_us: 30, response_code: 200, uri: /port/cid/1, method: GET, req_id: 11a6cd3c-4ad5-4f7c-9c1a-748c38f77242, remote_addr: 127.0.0.1:62438, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.853 INFO request completed, latency_us: 26, response_code: 200, uri: /port/cid/2, method: GET, req_id: 157e97c8-5f54-406d-b870-c05be4031444, remote_addr: 127.0.0.1:62438, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.858 INFO request completed, latency_us: 40, response_code: 200, uri: /port/cid/3, method: GET, req_id: 5fd0940e-93b6-4d89-955b-a8825ff540ba, remote_addr: 127.0.0.1:62438, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.858 INFO request completed, latency_us: 35, response_code: 200, uri: /port/cid/4, method: GET, req_id: 08ff23af-d2c3-4fe7-9bae-772c63700490, remote_addr: 127.0.0.1:62438, local_addr: 127.0.0.1:9998 Sep 09 17:47:49.859 INFO request completed, latency_us: 26, response_code: 200, uri: /port/cid/5, method: GET, req_id: 79d857ff-be74-4058-b450-036ddf78eb27, remote_addr: 127.0.0.1:62438, local_addr: 127.0.0.1:9998 Sep 09 17:47:58.918 INFO accepted connection, remote_addr: 127.0.0.1:35003, local_addr: 127.0.0.1:9998 Sep 09 17:47:58.918 INFO request completed, latency_us: 149, response_code: 200, uri: /regioninfo, method: GET, req_id: 6e5f674b-0faf-4400-b187-f20de45d196e, remote_addr: 127.0.0.1:35003, local_addr: 127.0.0.1:9998 Sep 09 17:47:58.919 INFO request completed, latency_us: 38, response_code: 200, uri: /regioncount, method: GET, req_id: d94215ba-a34e-42b1-a46a-f52f124786a0, remote_addr: 127.0.0.1:35003, local_addr: 127.0.0.1:9998 Sep 09 17:47:58.919 INFO request completed, latency_us: 57, response_code: 200, uri: /port/cid/0, method: GET, req_id: f7b4809f-bd6c-4f71-8f8e-9cd845ed0d3d, remote_addr: 127.0.0.1:35003, local_addr: 127.0.0.1:9998 Sep 09 17:47:58.920 INFO request completed, latency_us: 31, response_code: 200, uri: /port/cid/1, method: GET, req_id: 7a4e60f9-cdd6-4f2d-beea-dfe98921a906, remote_addr: 127.0.0.1:35003, local_addr: 127.0.0.1:9998 Sep 09 17:47:58.920 INFO request completed, latency_us: 39, response_code: 200, uri: /port/cid/2, method: GET, req_id: 75e936c2-ab1a-4348-b872-aabaed5fe6a0, remote_addr: 127.0.0.1:35003, local_addr: 127.0.0.1:9998 Sep 09 17:47:58.921 INFO request completed, latency_us: 52, response_code: 200, uri: /port/cid/3, method: GET, req_id: 235ce17e-4f01-4867-bfba-2ec3f9993dca, remote_addr: 127.0.0.1:35003, local_addr: 127.0.0.1:9998 Sep 09 17:47:58.922 INFO request completed, latency_us: 33, response_code: 200, uri: /port/cid/4, method: GET, req_id: c53d79f5-6ac5-4340-9e09-2f263ffc884c, remote_addr: 127.0.0.1:35003, local_addr: 127.0.0.1:9998 Sep 09 17:47:58.922 INFO request completed, latency_us: 29, response_code: 200, uri: /port/cid/5, method: GET, req_id: 6ff2aa40-47a3-4ede-bdaa-9135c210f055, remote_addr: 127.0.0.1:35003, local_addr: 127.0.0.1:9998 Sep 09 17:48:08.216 INFO accepted connection, remote_addr: 127.0.0.1:63679, local_addr: 127.0.0.1:9998 Sep 09 17:48:08.217 INFO request completed, latency_us: 154, response_code: 200, uri: /regioninfo, method: GET, req_id: 7dc46481-f589-465d-b27c-ab196042f356, remote_addr: 127.0.0.1:63679, local_addr: 127.0.0.1:9998 Sep 09 17:48:08.217 INFO request completed, latency_us: 38, response_code: 200, uri: /regioncount, method: GET, req_id: a63d7a6a-8377-45df-9437-dd4a4cfb79dd, remote_addr: 127.0.0.1:63679, local_addr: 127.0.0.1:9998 Sep 09 17:48:08.218 INFO request completed, latency_us: 68, response_code: 200, uri: /port/cid/0, method: GET, req_id: 041a6f75-ab6d-4416-8ad0-ca4c4e4e2ceb, remote_addr: 127.0.0.1:63679, local_addr: 127.0.0.1:9998 Sep 09 17:48:08.218 INFO request completed, latency_us: 31, response_code: 200, uri: /port/cid/1, method: GET, req_id: c034fbe5-2d1a-406d-ac6b-ad02d20b1dfe, remote_addr: 127.0.0.1:63679, local_addr: 127.0.0.1:9998 Sep 09 17:48:08.218 INFO request completed, latency_us: 29, response_code: 200, uri: /port/cid/2, method: GET, req_id: 3f13af7c-d441-4a1b-8f93-2cebfe0b3e05, remote_addr: 127.0.0.1:63679, local_addr: 127.0.0.1:9998 Sep 09 17:48:08.220 INFO request completed, latency_us: 79, response_code: 200, uri: /port/cid/3, method: GET, req_id: 012d78db-ad4b-4f8f-9a57-22f468aa4459, remote_addr: 127.0.0.1:63679, local_addr: 127.0.0.1:9998 Sep 09 17:48:08.220 INFO request completed, latency_us: 75, response_code: 200, uri: /port/cid/4, method: GET, req_id: e6ef3dc0-e0a3-4060-87aa-52232316555b, remote_addr: 127.0.0.1:63679, local_addr: 127.0.0.1:9998 Sep 09 17:48:08.220 INFO request completed, latency_us: 38, response_code: 200, uri: /port/cid/5, method: GET, req_id: 217f64ea-5dd2-4b77-9349-2de316c36eee, remote_addr: 127.0.0.1:63679, local_addr: 127.0.0.1:9998 Sep 09 17:48:31.970 INFO accepted connection, remote_addr: 127.0.0.1:60406, local_addr: 127.0.0.1:9998 Sep 09 17:48:31.970 INFO request completed, latency_us: 155, response_code: 200, uri: /regioninfo, method: GET, req_id: 95fec562-0b78-4159-839e-da5d7bb73585, remote_addr: 127.0.0.1:60406, local_addr: 127.0.0.1:9998 Sep 09 17:48:31.971 INFO request completed, latency_us: 39, response_code: 200, uri: /regioncount, method: GET, req_id: 567fa711-3722-4e62-a16b-4daa389e7cae, remote_addr: 127.0.0.1:60406, local_addr: 127.0.0.1:9998 Sep 09 17:48:31.971 INFO request completed, latency_us: 57, response_code: 200, uri: /port/cid/0, method: GET, req_id: 81a5e72f-c2b2-4b04-b0dd-e8fbc41db8be, remote_addr: 127.0.0.1:60406, local_addr: 127.0.0.1:9998 Sep 09 17:48:31.971 INFO request completed, latency_us: 30, response_code: 200, uri: /port/cid/1, method: GET, req_id: ed2a10d1-4e92-402b-b682-054b95656f46, remote_addr: 127.0.0.1:60406, local_addr: 127.0.0.1:9998 Sep 09 17:48:31.972 INFO request completed, latency_us: 31, response_code: 200, uri: /port/cid/2, method: GET, req_id: 35cbaa17-8861-42fb-8081-e89dbbd1a009, remote_addr: 127.0.0.1:60406, local_addr: 127.0.0.1:9998 Sep 09 17:48:31.973 INFO request completed, latency_us: 67, response_code: 200, uri: /port/cid/3, method: GET, req_id: 815a38c9-7064-4244-ac0a-7aa0f827ee5d, remote_addr: 127.0.0.1:60406, local_addr: 127.0.0.1:9998 Sep 09 17:48:31.974 INFO request completed, latency_us: 62, response_code: 200, uri: /port/cid/4, method: GET, req_id: ab04d571-0e88-4bed-b4e7-f6ef7653e9dd, remote_addr: 127.0.0.1:60406, local_addr: 127.0.0.1:9998 Sep 09 17:48:31.974 INFO request completed, latency_us: 61, response_code: 200, uri: /port/cid/5, method: GET, req_id: 7d731d0b-467b-4700-95f2-996467fd2507, remote_addr: 127.0.0.1:60406, local_addr: 127.0.0.1:9998 Sep 09 17:50:54.867 INFO accepted connection, remote_addr: 127.0.0.1:45889, local_addr: 127.0.0.1:9998 Sep 09 17:50:54.868 INFO request completed, latency_us: 152, response_code: 200, uri: /regioninfo, method: GET, req_id: f5e78965-2321-49ac-83bf-00c5652f585d, remote_addr: 127.0.0.1:45889, local_addr: 127.0.0.1:9998 Sep 09 17:50:54.868 INFO request completed, latency_us: 37, response_code: 200, uri: /regioncount, method: GET, req_id: 07a799b8-e749-40ab-b0ab-5a409b36b103, remote_addr: 127.0.0.1:45889, local_addr: 127.0.0.1:9998 Sep 09 17:50:54.869 INFO request completed, latency_us: 68, response_code: 200, uri: /port/cid/0, method: GET, req_id: 31519f2e-aca5-4106-b923-22b74e8524b8, remote_addr: 127.0.0.1:45889, local_addr: 127.0.0.1:9998 Sep 09 17:50:54.869 INFO request completed, latency_us: 32, response_code: 200, uri: /port/cid/1, method: GET, req_id: 1de5a103-6f00-47ef-ad71-6ec9b37e1fa2, remote_addr: 127.0.0.1:45889, local_addr: 127.0.0.1:9998 Sep 09 17:50:54.869 INFO request completed, latency_us: 41, response_code: 200, uri: /port/cid/2, method: GET, req_id: d973ef5a-cc3c-4fc4-80f2-71dddcce4930, remote_addr: 127.0.0.1:45889, local_addr: 127.0.0.1:9998 Sep 09 17:50:54.871 INFO request completed, latency_us: 50, response_code: 200, uri: /port/cid/3, method: GET, req_id: 18b4fc45-1a31-4f81-a56b-ddd3ffc9eff7, remote_addr: 127.0.0.1:45889, local_addr: 127.0.0.1:9998 Sep 09 17:50:54.871 INFO request completed, latency_us: 32, response_code: 200, uri: /port/cid/4, method: GET, req_id: 298e4865-90d3-44ad-a23d-f23f9506ecc2, remote_addr: 127.0.0.1:45889, local_addr: 127.0.0.1:9998 Sep 09 17:50:54.871 INFO request completed, latency_us: 29, response_code: 200, uri: /port/cid/5, method: GET, req_id: 5ee03f93-70b9-4012-ae73-5c9008dd0f23, remote_addr: 127.0.0.1:45889, local_addr: 127.0.0.1:9998 Sep 09 17:51:11.020 INFO accepted connection, remote_addr: 127.0.0.1:39148, local_addr: 127.0.0.1:9998 Sep 09 17:51:11.021 INFO request completed, latency_us: 148, response_code: 200, uri: /regioninfo, method: GET, req_id: 78995471-3971-4726-9cbf-704779b31ef5, remote_addr: 127.0.0.1:39148, local_addr: 127.0.0.1:9998 Sep 09 17:51:11.021 INFO request completed, latency_us: 54, response_code: 200, uri: /regioncount, method: GET, req_id: 04a5c082-0c22-4043-92fe-1c3d5927cb80, remote_addr: 127.0.0.1:39148, local_addr: 127.0.0.1:9998 Sep 09 17:51:11.022 INFO request completed, latency_us: 62, response_code: 200, uri: /port/cid/0, method: GET, req_id: f5ecca51-cf93-42bb-a45f-428c0619c3ff, remote_addr: 127.0.0.1:39148, local_addr: 127.0.0.1:9998 Sep 09 17:51:11.022 INFO request completed, latency_us: 42, response_code: 200, uri: /port/cid/1, method: GET, req_id: de719492-1cca-4b4c-9920-bf4bc0234aef, remote_addr: 127.0.0.1:39148, local_addr: 127.0.0.1:9998 Sep 09 17:51:11.022 INFO request completed, latency_us: 26, response_code: 200, uri: /port/cid/2, method: GET, req_id: a9b21993-a184-462f-94ec-20b91abb0bae, remote_addr: 127.0.0.1:39148, local_addr: 127.0.0.1:9998 Sep 09 17:51:11.023 INFO request completed, latency_us: 67, response_code: 200, uri: /port/cid/3, method: GET, req_id: e428cd7b-460c-4b0a-8aed-dc039c2ea3c2, remote_addr: 127.0.0.1:39148, local_addr: 127.0.0.1:9998 Sep 09 17:51:11.024 INFO request completed, latency_us: 60, response_code: 200, uri: /port/cid/4, method: GET, req_id: 0dcece6f-ffda-4ba0-945e-74c65214580b, remote_addr: 127.0.0.1:39148, local_addr: 127.0.0.1:9998 Sep 09 17:51:11.024 INFO request completed, latency_us: 58, response_code: 200, uri: /port/cid/5, method: GET, req_id: bcf1f3b2-e09a-4848-8927-42283907aefe, remote_addr: 127.0.0.1:39148, local_addr: 127.0.0.1:9998 Sep 09 17:51:15.126 INFO accepted connection, remote_addr: 127.0.0.1:52297, local_addr: 127.0.0.1:9998 Sep 09 17:51:15.126 INFO request completed, latency_us: 144, response_code: 200, uri: /regioninfo, method: GET, req_id: b47850e6-9928-4302-b611-90e696634af4, remote_addr: 127.0.0.1:52297, local_addr: 127.0.0.1:9998 Sep 09 17:51:15.127 INFO request completed, latency_us: 48, response_code: 200, uri: /regioncount, method: GET, req_id: 4741ec54-8506-4957-99fc-1fcd0d9a012e, remote_addr: 127.0.0.1:52297, local_addr: 127.0.0.1:9998 Sep 09 17:51:15.127 INFO request completed, latency_us: 69, response_code: 200, uri: /port/cid/0, method: GET, req_id: cb4c424c-ff3a-4641-988a-9864d94f47b3, remote_addr: 127.0.0.1:52297, local_addr: 127.0.0.1:9998 Sep 09 17:51:15.127 INFO request completed, latency_us: 41, response_code: 200, uri: /port/cid/1, method: GET, req_id: 5264abc3-b87c-464f-beb5-598bae8ad268, remote_addr: 127.0.0.1:52297, local_addr: 127.0.0.1:9998 Sep 09 17:51:15.128 INFO request completed, latency_us: 31, response_code: 200, uri: /port/cid/2, method: GET, req_id: ca9aacc5-082a-4a47-a841-c1085ca83073, remote_addr: 127.0.0.1:52297, local_addr: 127.0.0.1:9998 Sep 09 17:51:15.129 INFO request completed, latency_us: 57, response_code: 200, uri: /port/cid/3, method: GET, req_id: 8198d3da-9185-4190-b5cc-1550536d7b4a, remote_addr: 127.0.0.1:52297, local_addr: 127.0.0.1:9998 Sep 09 17:51:15.129 INFO request completed, latency_us: 33, response_code: 200, uri: /port/cid/4, method: GET, req_id: 8a1d1297-968c-471a-9d4f-20d7a3b581aa, remote_addr: 127.0.0.1:52297, local_addr: 127.0.0.1:9998 Sep 09 17:51:15.129 INFO request completed, latency_us: 28, response_code: 200, uri: /port/cid/5, method: GET, req_id: f4903bee-4b18-4d7e-968a-1318cc39faa0, remote_addr: 127.0.0.1:52297, local_addr: 127.0.0.1:9998 Sep 09 17:51:19.222 INFO accepted connection, remote_addr: 127.0.0.1:63562, 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 09 17:51:19.222 INFO request completed, latency_us: 126, response_code: 204, uri: /disablerestart/all, method: POST, req_id: 82a6b7b0-c802-4775-b7c0-73f5f6fd3d4b, remote_addr: 127.0.0.1:63562, local_addr: 127.[8810] Disable keep_running 0[8820] Disable keep_running .[8830] Disable keep_running 0[8840] Disable keep_running .[8860] Disable keep_running 1:9998 [8850] Disable keep_running Sep 09 17:51:19.236 INFO accepted connection, remote_addr: 127.0.0.1:50760, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(2) stop 2 Sep 09 17:51:19.236 INFO request completed, latency_us: 82, response_code: 204, uri: /stop/cid/2, method: POST, req_id: 02d1adf6-4528-4623-99aa-0d309ba171c6, remote_addr: [8830] Got stop action so:false kr:false 127.0.0.1:50760, local_addr: 127.0.0.1:9998 [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit Sep 09 17:51:19.249 INFO accepted connection, remote_addr: 127.0.0.1:56931, local_addr: 127.0.0.1:9998 Sep 09 17:51:19.250 INFO request completed, latency_us: 87, response_code: 200, uri: /state/cid/2, method: GET, req_id: eafba3d8-9e5b-42eb-bc8d-0bc786b972f4, remote_addr: 127.0.0.1:56931, local_addr: 127.0.0.1:9998 Sep 09 17:51:19.266 INFO accepted connection, remote_addr: 127.0.0.1:60470, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(2) start 2 Sep 09 17:51:19.266 INFO request completed, latency_us: 77, response_code: 204, uri: /start/cid/2, method: POST, req_id: c21ae538-4a05-409e-b77f-bfb4f95b878b, remote_addr: 127.0.0.1:60470, local_addr: 127.0.0.1:9998 Sep 09 17:51:19.299 INFO accepted connection, remote_addr: 127.0.0.1:43859, local_addr: 127.0.0.1:9998 Sep 09 17:51:19.300 INFO request completed, latency_us: 115, response_code: 200, uri: /regioninfo, method: GET, req_id: 04fb8cc6-4dcc-4bc6-bedf-be4a8a3f5535, remote_addr: 127.0.0.1:43859, local_addr: 127.0.0.1:9998 Sep 09 17:51:19.301 INFO request completed, latency_us: 88, response_code: 200, uri: /regioncount, method: GET, req_id: 7bc8e9a9-7552-4935-81c7-ce138f575c7d, remote_addr: 127.0.0.1:43859, local_addr: 127.0.0.1:9998 Sep 09 17:51:19.301 INFO request completed, latency_us: 66, response_code: 200, uri: /port/cid/0, method: GET, req_id: d49d2374-b86e-4687-abc2-bc97393153b3, remote_addr: 127.0.0.1:43859, local_addr: 127.0.0.1:9998 Sep 09 17:51:19.301 INFO request completed, latency_us: 32, response_code: 200, uri: /port/cid/1, method: GET, req_id: fb07daa4-b870-471e-8c82-4976a322013c, remote_addr: 127.0.0.1:43859, local_addr: 127.0.0.1:9998 Sep 09 17:51:19.301 INFO request completed, latency_us: 29, response_code: 200, uri: /port/cid/2, method: GET, req_id: 74bb75c2-2de2-4c11-81b5-829f9791b354, remote_addr: 127.0.0.1:43859, local_addr: 127.0.0.1:9998 Sep 09 17:51:19.303 INFO request completed, latency_us: 76, response_code: 200, uri: /port/cid/3, method: GET, req_id: 719e6ce3-c876-45f0-bdee-407800ce4620, remote_addr: 127.0.0.1:43859, local_addr: 127.0.0.1:9998 Sep 09 17:51:19.303 INFO request completed, latency_us: 74, response_code: 200, uri: /port/cid/4, method: GET, req_id: 6270c399-fedf-4e8c-9b93-9f6db4aae33c, remote_addr: 127.0.0.1:43859, local_addr: 127.0.0.1:9998 Sep 09 17:51:19.303 INFO request completed, latency_us: 71, response_code: 200, uri: /port/cid/5, method: GET, req_id: 9e6bb7d4-288b-49a5-a174-44ab0e51c142, remote_addr: 127.0.0.1:43859, 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(1194) [8830][2] reports Running Sep 09 17:51:33.435 INFO accepted connection, remote_addr: 127.0.0.1:56307, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Shutdown Shutdown Shut it down Sep 09 17:51:33.435 INFO request completed, latency_us: 130, response_code: 204, uri: /shutdown, method: POST, req_id: 6d00411f-1f06-4bd6-b1c4-d8a9a53c4216, remote_addr: [8810] Disable keep_running 127[8810] Got stop action so:false kr:false .0.0.1:56307, local_addr: 127.0.0.1:9998 [8840] Disable keep_running [8840] Got stop action so:false kr:false [8830] Disable keep_running [8820] Disable keep_running [8830] Got stop action so:false kr:false [8820] Got stop action so:false kr:false [8860] Disable keep_running [8860] Got stop action so:false kr:false [8850] Disable keep_running [8850] Got stop action so:false kr:false [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit [8860] Exited with: ExitStatus(unix_wait_status(9)) [8860][5] reports Exit [8840] Exited with: ExitStatus(unix_wait_status(9)) [8840][3] reports Exit [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] reports Exit [8850] Exited with: ExitStatus(unix_wait_status(9)) [8850][4] 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.029377494 Downstairs region 1 created at /var/tmp/test_up-build/8820 in 0.02731753 Downstairs region 2 created at /var/tmp/test_up-build/8830 in 0.0268378 Downstairs region 3 created at /var/tmp/test_up-build/8840 in 0.026122972 Downstairs region 4 created at /var/tmp/test_up-build/8850 in 0.027012616 Downstairs region 5 created at /var/tmp/test_up-build/8860 in 0.03398461 Downstairs region 6 created at /var/tmp/test_up-build/8870 in 0.03907451 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 start ds: 8870 Starting downstairs at port 8810 Starting downstairs at port 8820 Starting downstairs at port 8830 Make output file at "/tmp/test_up-build/dsc/downstairs-8820.txt" Make output file at "/tmp/test_up-build/dsc/downstairs-8810.txt" Make output file at "/tmp/test_up-build/dsc/downstairs-8830.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 Starting downstairs at port 8850 [8820][1] initial start wait reports Starting [8830][2] initial start wait reports Starting [8840][3] initial start wait reports Starting Make output file at "/tmp/test_up-build/dsc/downstairs-8850.txt" [8850][4] initial start wait reports Starting Starting downstairs at port 8860 Make output file at "/tmp/test_up-build/dsc/downstairs-8860.txt" Starting downstairs at port 8870 Make output file at "/tmp/test_up-build/dsc/downstairs-8870.txt" [8860][5] initial start wait reports Starting [8870][6] initial start wait reports Starting Downstairs /var/tmp/test_up-build/8840 port 8840 PID:Some(1217) Control access at:127.0.0.1:9998 Downstairs /var/tmp/test_up-build/8810 port 8810 PID:Some(1214) Downstairs /var/tmp/test_up-build/8820 port 8820 PID:Some(1213) Downstairs /var/tmp/test_up-build/8850 port 8850 PID:Some(1212) Downstairs /var/tmp/test_up-build/8870 port 8870 PID:Some(1211) [8840][3] initial start wait reports Running [8810][0] initial start wait reports Running [8820][1] initial start wait reports Running [8850][4] initial start wait reports Running [8870][6] initial start wait reports Running Sep 09 17:51:38.669 INFO listening, local_addr: 127.0.0.1:9998 Downstairs /var/tmp/test_up-build/8830 port 8830 PID:Some(1215) [8830][2] initial start wait reports Running Downstairs /var/tmp/test_up-build/8860 port 8860 PID:Some(1216) [8860][5] initial start wait reports Running All downstairs are running Sep 09 17:51:43.680 INFO accepted connection, remote_addr: 127.0.0.1:37921, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.681 INFO request completed, latency_us: 298, response_code: 200, uri: /state/cid/0, method: GET, req_id: 792e3cc4-ab58-4e4e-a1d6-e4a1f726745f, remote_addr: 127.0.0.1:37921, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.696 INFO accepted connection, remote_addr: 127.0.0.1:45563, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.697 INFO request completed, latency_us: 79, response_code: 200, uri: /state/cid/1, method: GET, req_id: a8f8ce47-6e66-4a6c-9631-7f92151c4d3f, remote_addr: 127.0.0.1:45563, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.711 INFO accepted connection, remote_addr: 127.0.0.1:63617, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.712 INFO request completed, latency_us: 68, response_code: 200, uri: /state/cid/2, method: GET, req_id: 884fe597-47c6-4e60-a91e-be77deaeac0c, remote_addr: 127.0.0.1:63617, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.726 INFO accepted connection, remote_addr: 127.0.0.1:46838, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.726 INFO request completed, latency_us: 62, response_code: 200, uri: /state/cid/3, method: GET, req_id: 18b676d8-33e4-4b61-9532-8e7754b9b180, remote_addr: 127.0.0.1:46838, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.740 INFO accepted connection, remote_addr: 127.0.0.1:57049, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.740 INFO request completed, latency_us: 67, response_code: 200, uri: /state/cid/4, method: GET, req_id: ac4a9760-ca3a-40fc-8e3f-c5a4d1370754, remote_addr: 127.0.0.1:57049, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.753 INFO accepted connection, remote_addr: 127.0.0.1:39451, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.754 INFO request completed, latency_us: 66, response_code: 200, uri: /state/cid/5, method: GET, req_id: aacda05e-3862-441a-8f70-29e775406d9c, remote_addr: 127.0.0.1:39451, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.766 INFO accepted connection, remote_addr: 127.0.0.1:64595, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.767 INFO request completed, latency_us: 61, response_code: 200, uri: /state/cid/6, method: GET, req_id: 9da4e7f2-cfcb-4696-8a25-9dabb513443f, remote_addr: 127.0.0.1:64595, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.780 INFO accepted connection, remote_addr: 127.0.0.1:62561, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.780 INFO request completed, latency_us: 77, response_code: 200, uri: /port/cid/6, method: GET, req_id: eea86df5-61d5-4d3e-8d0d-747d6cc694e2, remote_addr: 127.0.0.1:62561, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.799 INFO accepted connection, remote_addr: 127.0.0.1:42536, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.799 INFO request completed, latency_us: 111, response_code: 200, uri: /regioninfo, method: GET, req_id: e7abf86a-038f-4606-b071-60b438422b2a, remote_addr: 127.0.0.1:42536, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.800 INFO request completed, latency_us: 55, response_code: 200, uri: /regioncount, method: GET, req_id: c3631ebd-043b-44c7-b6cf-654fade05a06, remote_addr: 127.0.0.1:42536, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.800 INFO request completed, latency_us: 39, response_code: 200, uri: /port/cid/0, method: GET, req_id: 988e1f6c-43f7-4e8d-a70b-3cca4a645169, remote_addr: 127.0.0.1:42536, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.800 INFO request completed, latency_us: 26, response_code: 200, uri: /port/cid/1, method: GET, req_id: 2d1b5528-344b-45cb-b81c-b744ef55ef21, remote_addr: 127.0.0.1:42536, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.800 INFO request completed, latency_us: 25, response_code: 200, uri: /port/cid/2, method: GET, req_id: c4c2bb36-9199-4e96-88aa-ea6e35b54ca8, remote_addr: 127.0.0.1:42536, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.801 INFO request completed, latency_us: 50, response_code: 200, uri: /port/cid/3, method: GET, req_id: d0044639-c34b-4772-8f5a-e06c6f7a83f3, remote_addr: 127.0.0.1:42536, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.802 INFO request completed, latency_us: 30, response_code: 200, uri: /port/cid/4, method: GET, req_id: 485fd564-e1a0-4164-a114-bea8019cf7e1, remote_addr: 127.0.0.1:42536, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.802 INFO request completed, latency_us: 61, response_code: 200, uri: /port/cid/5, method: GET, req_id: a8e68617-6028-422f-a0d7-58ecd0d52112, remote_addr: 127.0.0.1:42536, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.824 INFO accepted connection, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:51:43.825 INFO request completed, latency_us: 131, response_code: 200, uri: /regioncount, method: GET, req_id: c10c2856-e1d0-4414-ad37-e6fd758080a1, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Sep 09 17:51:44.190 INFO request completed[8810] Got stop action so:false kr:false , latency_us: 231, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 3d8f04f2-7f2b-4a19-955c-543f9938a0a4, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:51:44.191 INFO request completed, latency_us: 77, response_code: 200, uri: /state/cid/0, method: GET, req_id: 9842a9ca-6d4c-4da0-9b8a-d0834c1e0843, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 09 17:51:48.193 INFO request completed, latency_us: 204, response_code: 200, uri: /state/cid/0, method: GET, req_id: cb1a15e1-ae90-4622-a46c-74a4e3f1f566, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 09 17:51:48.564 INFO request completed, latency_us: 232, response_code: 204, uri: /start/cid/0, method: POST, req_id: 2cd8de3f-fb22-4c35-9cb0-473043918007, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:51:48.564 INFO request completed, latency_us: 58, response_code: 200, uri: /state/cid/0, method: GET, req_id: 2984beee-f8b6-454d-947e-f99ef8aee584, remote_addr: 127.0.0.1:51024, 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(1228) [8810][0] reports Running Sep 09 17:51:52.566 INFO request completed, latency_us: 185, response_code: 200, uri: /state/cid/0, method: GET, req_id: e43c3161-a31d-45a2-9695-dbbc70301c5f, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Sep 09 17:51:58.931 INFO request completed[8810] Got stop action so:false kr:false , latency_us: 227, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 307e69bd-254c-457a-9b8c-3e548501122a, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:51:58.932 INFO request completed, latency_us: 222, response_code: 200, uri: /state/cid/0, method: GET, req_id: 952537b7-17b3-415a-ab3e-c3586297ff8b, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 09 17:52:02.934 INFO request completed, latency_us: 221, response_code: 200, uri: /state/cid/0, method: GET, req_id: 8590ac45-2b69-4bdc-9feb-766d50c062a6, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 09 17:52:03.311 INFO request completed, latency_us: 222, response_code: 204, uri: /start/cid/0, method: POST, req_id: 6896eacc-657c-435f-974d-f1327cd25e59, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:52:03.311 INFO request completed, latency_us: 86, response_code: 200, uri: /state/cid/0, method: GET, req_id: e67f7415-b589-44f3-b1b8-a292c47244a8, remote_addr: 127.0.0.1:51024, 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(1229) [8810][0] reports Running Sep 09 17:52:07.313 INFO request completed, latency_us: 205, response_code: 200, uri: /state/cid/0, method: GET, req_id: 9b6199d4-57eb-49c4-a61d-9c648117b251, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Sep 09 17:52:12.678 [8810] Got stop action so:false kr:false INFO request completed, latency_us: 216, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 4410008c-33a7-4367-b78e-9f29b065fa8c, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:52:12.678 INFO request completed, latency_us: 61, response_code: 200, uri: /state/cid/0, method: GET, req_id: 62e4de2a-727a-4f93-8767-ceb750edba7b, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 09 17:52:16.680 INFO request completed, latency_us: 198, response_code: 200, uri: /state/cid/0, method: GET, req_id: 4a3eccc8-7092-48eb-97b4-6a9939cf568f, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 09 17:52:17.061 INFO request completed, latency_us: 212, response_code: 204, uri: /start/cid/0, method: POST, req_id: 5cd69277-b583-4892-8a15-9344d1c6e750, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:52:17.061 INFO request completed, latency_us: 70, response_code: 200, uri: /state/cid/0, method: GET, req_id: db834178-f5fb-45e3-9447-10f78a9c6045, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:52:21.063 INFO request completed, latency_us: 200, response_code: 200, uri: /state/cid/0, method: GET, req_id: a71dcac8-ead5-4f90-ac3b-30639ceb2c7a, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:52:25.065 INFO request completed, latency_us: 211, response_code: 200, uri: /state/cid/0, method: GET, req_id: b47b43b1-735f-43ab-8e0b-c68fa518253c, remote_addr: 127.0.0.1:51024, 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(1230) [8810][0] reports Running Sep 09 17:52:29.066 INFO request completed, latency_us: 134, response_code: 200, uri: /state/cid/0, method: GET, req_id: e010aa73-1400-4e6a-b9a2-2625ac9bd9b4, remote_addr: 127.0.0.1:51024, 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 09 17:52:33.436 INFO request completed, latency_us: 221, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 720d7de6-2b00-4314-a196-0877c7377762, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:52:33.436 INFO request completed, latency_us: 82, response_code: 200, uri: /state/cid/0, method: GET, req_id: cc8702f7-1e77-4d4d-9132-6493bfd8a80d, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 09 17:52:37.438 INFO request completed, latency_us: 226, response_code: 200, uri: /state/cid/0, method: GET, req_id: e6f6915e-86f1-4217-8d9e-ff18a49e0eed, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 09 17:52:37.819 INFO request completed, latency_us: 224, response_code: 204, uri: /start/cid/0, method: POST, req_id: cfba78b0-e488-4733-bcbb-e4af9c4e9d59, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:52:37.819 INFO request completed, latency_us: 48, response_code: 200, uri: /state/cid/0, method: GET, req_id: 1177c1b2-d2b6-438b-ba47-d7964a389c7d, remote_addr: 127.0.0.1:51024, 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 09 17:52:41.821 INFO request completed, latency_us: 192, response_code: 200, uri: /state/cid/0, method: GET, req_id: 0cbcd9e4-075c-4a83-84c6-2f02e2382d16, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Sep 09 17:52:45.186[8810] Got stop action so:false kr:false INFO request completed, latency_us: 232, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 6d3402c0-42cc-41a2-86a4-223b21e23558, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:52:45.187 INFO request completed, latency_us: 86, response_code: 200, uri: /state/cid/0, method: GET, req_id: 38514e4b-1199-40ed-bbdb-0fd7793a29c7, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 09 17:52:49.189 INFO request completed, latency_us: 225, response_code: 200, uri: /state/cid/0, method: GET, req_id: 80a973af-ab89-425d-9696-08797227c937, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 09 17:52:49.573 INFO request completed, latency_us: 213, response_code: 204, uri: /start/cid/0, method: POST, req_id: 5ee5d1ea-6373-4218-836e-c5ae87859e78, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:52:49.573 INFO request completed, latency_us: 74, response_code: 200, uri: /state/cid/0, method: GET, req_id: a6f97eab-8b69-4572-bdb7-dd005911227d, remote_addr: 127.0.0.1:51024, 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 09 17:52:53.575 INFO request completed, latency_us: 186, response_code: 200, uri: /state/cid/0, method: GET, req_id: 6f2b4e83-7083-40be-9c1a-246ae3d9b754, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Sep 09 17:52:55.943 INFO request completed[8810] Got stop action so:false kr:false , latency_us: 219, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 75a6975b-0ea7-48cd-ae2a-b70bc004e5ce, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:52:55.943 INFO request completed, latency_us: 147, response_code: 200, uri: /state/cid/0, method: GET, req_id: 5fb80a9e-d43b-4da9-a533-6b49e10f39e7, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 09 17:52:59.946 INFO request completed, latency_us: 204, response_code: 200, uri: /state/cid/0, method: GET, req_id: 357d0aec-39a1-4c8d-b007-019ed10b54dd, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 09 17:53:00.330 INFO request completed, latency_us: 218, response_code: 204, uri: /start/cid/0, method: POST, req_id: bc3baf95-e647-4548-ba58-62b913281cbf, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:53:00.330 INFO request completed, latency_us: 52, response_code: 200, uri: /state/cid/0, method: GET, req_id: e18b3e4c-c64e-42a9-be05-441625608657, remote_addr: 127.0.0.1:51024, 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 09 17:53:04.332 INFO request completed, latency_us: 188, response_code: 200, uri: /state/cid/0, method: GET, req_id: 793a5561-eded-4a1c-b8b3-81c18ed6a0ce, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(1) stop 1 Sep 09 17:53:31.547 [8820] Got stop action so:false kr:false INFO request completed, latency_us: 225, response_code: 204, uri: /stop/cid/1, method: POST, req_id: 795dac13-9745-4aef-bb80-eb6e58b21925, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:53:31.548 INFO request completed, latency_us: 88, response_code: 200, uri: /state/cid/1, method: GET, req_id: 4970d625-4e8a-4423-8627-d773f76bf364, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] reports Exit Sep 09 17:53:35.549 INFO request completed, latency_us: 203, response_code: 200, uri: /state/cid/1, method: GET, req_id: 75cf7764-3182-4dac-a924-f76748830f1d, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(1) start 1 Sep 09 17:53:35.930 INFO request completed, latency_us: 230, response_code: 204, uri: /start/cid/1, method: POST, req_id: 3ed52623-96a8-4ec2-894f-1fb7595cea2e, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:53:35.930 INFO request completed, latency_us: 51, response_code: 200, uri: /state/cid/1, method: GET, req_id: d3ff3fdf-0610-4fa7-801e-744581ac63ae, remote_addr: 127.0.0.1:51024, 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(1234) [8820][1] reports Running Sep 09 17:53:39.932 INFO request completed, latency_us: 182, response_code: 200, uri: /state/cid/1, method: GET, req_id: 387d2afa-680a-4a16-9202-b26e20cb2594, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(2) stop 2 Sep 09 17:54:07.242 INFO request completed[8830] Got stop action so:false kr:false , latency_us: 225, response_code: 204, uri: /stop/cid/2, method: POST, req_id: 94b9ce8b-04d8-4923-bb1a-8d5e271b1471, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:54:07.242 INFO request completed, latency_us: 134, response_code: 200, uri: /state/cid/2, method: GET, req_id: 0b595d8b-aa17-4bf6-ac07-5f1cbe043a71, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit Sep 09 17:54:11.244 INFO request completed, latency_us: 206, response_code: 200, uri: /state/cid/2, method: GET, req_id: bd024de0-c095-400d-bc3e-b8380585ab1c, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(2) start 2 Sep 09 17:54:11.626 INFO request completed, latency_us: 220, response_code: 204, uri: /start/cid/2, method: POST, req_id: 296252a7-fc2d-4e0c-b773-016213cfd9b6, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:54:11.626 INFO request completed, latency_us: 74, response_code: 200, uri: /state/cid/2, method: GET, req_id: 6fdb3a59-ecc7-48e2-9cd0-6d269cffe477, remote_addr: 127.0.0.1:51024, 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(1235) [8830][2] reports Running Sep 09 17:54:15.628 INFO request completed, latency_us: 187, response_code: 200, uri: /state/cid/2, method: GET, req_id: a3ece508-b7d6-4a0f-845b-8fe71c49d728, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(3) stop 3 Sep 09 17:54:43.164 INFO request completed, latency_us: 224, response_code[8840] Got stop action so:false kr:false : 204, uri: /stop/cid/3, method: POST, req_id: 397f9c90-a79f-416b-974f-0bebebc5dcee, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:54:43.164 INFO request completed, latency_us: 165, response_code: 200, uri: /state/cid/3, method: GET, req_id: 84b423b4-6124-40fc-9028-985730ac80b0, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 [8840] Exited with: ExitStatus(unix_wait_status(9)) [8840][3] reports Exit Sep 09 17:54:47.166 INFO request completed, latency_us: 219, response_code: 200, uri: /state/cid/3, method: GET, req_id: be249c75-f188-4583-a949-16b08d740068, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(3) start 3 Sep 09 17:54:47.554 INFO request completed, latency_us: 217, response_code: 204, uri: /start/cid/3, method: POST, req_id: 9069d935-4b4d-4212-a991-4a8713f2e270, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:54:47.555 INFO request completed, latency_us: 48, response_code: 200, uri: /state/cid/3, method: GET, req_id: 6d57d7c5-4300-4307-a4cf-eaf26b07bb32, remote_addr: 127.0.0.1:51024, 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(1236) [8840][3] reports Running Sep 09 17:54:51.557 INFO request completed, latency_us: 189, response_code: 200, uri: /state/cid/3, method: GET, req_id: 8e69145b-bc12-4ba2-8bd1-bb5969d89902, remote_addr: 127.0.0.1:51024, local_addr: 127.0.0.1:9998 Sep 09 17:55:18.978 INFO accepted connection, remote_addr: 127.0.0.1:41156, local_addr: 127.0.0.1:9998 Sep 09 17:55:18.979 INFO request completed, latency_us: 158, response_code: 200, uri: /regioninfo, method: GET, req_id: 6f9ce5b1-3750-4f32-abfd-7371d1b488ce, remote_addr: 127.0.0.1:41156, local_addr: 127.0.0.1:9998 Sep 09 17:55:18.980 INFO request completed, latency_us: 70, response_code: 200, uri: /regioncount, method: GET, req_id: af11ed70-b97d-4332-9e38-f86d4a051cb0, remote_addr: 127.0.0.1:41156, local_addr: 127.0.0.1:9998 Sep 09 17:55:18.980 INFO request completed, latency_us: 63, response_code: 200, uri: /port/cid/0, method: GET, req_id: 10fa3833-dfb6-4881-9854-bf53d4c930e4, remote_addr: 127.0.0.1:41156, local_addr: 127.0.0.1:9998 Sep 09 17:55:18.980 INFO request completed, latency_us: 33, response_code: 200, uri: /port/cid/1, method: GET, req_id: 4c04c3fb-c538-490d-ab2b-e7c4e3633f7e, remote_addr: 127.0.0.1:41156, local_addr: 127.0.0.1:9998 Sep 09 17:55:18.980 INFO request completed, latency_us: 41, response_code: 200, uri: /port/cid/2, method: GET, req_id: 76ed1990-62d9-4d10-b0f0-fbcf27d046da, remote_addr: 127.0.0.1:41156, local_addr: 127.0.0.1:9998 Sep 09 17:55:18.982 INFO request completed, latency_us: 60, response_code: 200, uri: /port/cid/3, method: GET, req_id: 9b97c5ec-8aca-4fe2-a008-2bd1a0ae90cc, remote_addr: 127.0.0.1:41156, local_addr: 127.0.0.1:9998 Sep 09 17:55:18.982 INFO request completed, latency_us: 33, response_code: 200, uri: /port/cid/4, method: GET, req_id: e66108cc-1261-48d7-8b4f-f67076fbfe32, remote_addr: 127.0.0.1:41156, local_addr: 127.0.0.1:9998 Sep 09 17:55:18.982 INFO request completed, latency_us: 29, response_code: 200, uri: /port/cid/5, method: GET, req_id: 670f68a8-5b06-4d95-8904-6c41a936210f, remote_addr: 127.0.0.1:41156, local_addr: 127.0.0.1:9998 Sep 09 17:55:19.170 INFO accepted connection, remote_addr: 127.0.0.1:43941, local_addr: 127.0.0.1:9998 Sep 09 17:55:19.170 INFO request completed, latency_us: 140, response_code: 200, uri: /regioncount, method: GET, req_id: f0996e0f-cca4-4f0a-a63d-aa7c3f121f92, remote_addr: 127.0.0.1:43941, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Sep 09 17:55:24.441[8810] Got stop action so:false kr:false INFO request completed, latency_us: 220, response_code: 204, uri: /stop/cid/0, method: POST, req_id: c364d4cc-fe13-4216-85a6-96f062790e4b, remote_addr: 127.0.0.1:43941, local_addr: 127.0.0.1:9998 Sep 09 17:55:24.441 INFO request completed, latency_us: 123, response_code: 200, uri: /state/cid/0, method: GET, req_id: ee4b3516-07eb-4664-9495-325486785781, remote_addr: 127.0.0.1:43941, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 09 17:55:28.444 INFO request completed, latency_us: 222, response_code: 200, uri: /state/cid/0, method: GET, req_id: 7528c061-8ee2-4a04-9990-afc5643f85c0, remote_addr: 127.0.0.1:43941, local_addr: 127.0.0.1:9998 Sep 09 17:56:05.093 INFO accepted connection, remote_addr: 127.0.0.1:34283, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 09 17:56:05.093 INFO request completed, latency_us: 184, response_code: 204, uri: /start/cid/0, method: POST, req_id: 9acd0c45-5525-4956-a34c-c261d3b36050, remote_addr: 127.0.0.1:34283, 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(1238) [8810][0] reports Running Main task has work to do, go find it got dsc Stop(1) stop 1 [8820] Got stop action so:false kr:false Sep 09 17:56:09.468 INFO request completed, latency_us: 268, response_code: 204, uri: /stop/cid/1, method: POST, req_id: e016f46e-27dc-4ba8-bf93-cec8f8fcfed1, remote_addr: 127.0.0.1:34283, local_addr: 127.0.0.1:9998 Sep 09 17:56:09.469 INFO request completed, latency_us: 92, response_code: 200, uri: /state/cid/1, method: GET, req_id: 12a2699e-915d-4daf-9d2c-de04d99fcd5d, remote_addr: 127.0.0.1:34283, local_addr: 127.0.0.1:9998 [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] reports Exit Sep 09 17:56:13.471 INFO request completed, latency_us: 230, response_code: 200, uri: /state/cid/1, method: GET, req_id: fe9adf37-6a91-4c33-97a5-e1619cf484ba, remote_addr: 127.0.0.1:34283, local_addr: 127.0.0.1:9998 Sep 09 17:56:50.192 INFO accepted connection, remote_addr: 127.0.0.1:54850, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(1) start 1 Sep 09 17:56:50.193 INFO request completed, latency_us: 179, response_code: 204, uri: /start/cid/1, method: POST, req_id: 0e5788bf-24eb-4917-bcd2-fcabaa925510, remote_addr: 127.0.0.1:54850, 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(1239) [8820][1] reports Running Main task has work to do, go find it got dsc Stop(2) stop 2 Sep 09 17:56:54.576 INFO [8830] Got stop action so:false kr:false request completed, latency_us: 218, response_code: 204, uri: /stop/cid/2, method: POST, req_id: 1656eb9f-5e52-4036-82b8-82376645c92d, remote_addr: 127.0.0.1:54850, local_addr: 127.0.0.1:9998 Sep 09 17:56:54.577 INFO request completed, latency_us: 82, response_code: 200, uri: /state/cid/2, method: GET, req_id: d89be80d-8a14-4bae-b274-43ebde09092d, remote_addr: 127.0.0.1:54850, local_addr: 127.0.0.1:9998 [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit Sep 09 17:56:58.579 INFO request completed, latency_us: 206, response_code: 200, uri: /state/cid/2, method: GET, req_id: 00690b03-635b-40e0-8702-827aafc31645, remote_addr: 127.0.0.1:54850, local_addr: 127.0.0.1:9998 Sep 09 17:57:35.222 INFO accepted connection, remote_addr: 127.0.0.1:39456, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(2) Sep 09 17:57:35.222 INFO request completed, latency_us: 165, response_code: 204, uri: /start/cid/2, method: POST, req_id: e3aeaf36-fe72-4170-a59c-b0f358914e7f, remote_addr: 127.0.0.1:39456, local_addr: 127.0.0.1:9998 start 2 Main task has work to do, go find it got dsc Stop(3) stop 3 Sep 09 17:57:39.612 INFO [8840] Got stop action so:false kr:false request completed, latency_us: 219, response_code: 204, uri: /stop/cid/3, method: POST, req_id: 815b9cf2-291e-46fd-a7de-97d19faa0c5e, remote_addr: 127.0.0.1:39456, local_addr: 127.0.0.1:9998 Sep 09 17:57:39.613 INFO request completed, latency_us: 72, response_code: 200, uri: /state/cid/3, method: GET, req_id: 78c98261-e4e3-4e3a-ada4-7c5b060c56c6, remote_addr: 127.0.0.1:39456, local_addr: 127.0.0.1:9998 [8840] Exited with: ExitStatus(unix_wait_status(9)) [8840][3] reports Exit [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(1240) [8830][2] reports Running Sep 09 17:57:43.615 INFO request completed, latency_us: 214, response_code: 200, uri: /state/cid/3, method: GET, req_id: 338a50c3-ad33-41ff-bc8f-372366df24fb, remote_addr: 127.0.0.1:39456, local_addr: 127.0.0.1:9998 Sep 09 17:58:20.265 INFO accepted connection, remote_addr: 127.0.0.1:61975, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(3) start 3 Sep 09 17:58:20.265 INFO request completed, latency_us: 165, response_code: 204, uri: /start/cid/3, method: POST, req_id: 487c62b3-b255-47b3-a8d8-e2a7a39eb98e, remote_addr: 127.0.0.1:61975, 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(1241) [8840][3] reports Running Main task has work to do, go find it got dsc Stop(4) stop 4 [8850] Got stop action so:false kr:false Sep 09 17:58:24.792 INFO request completed, latency_us: 276, response_code: 204, uri: /stop/cid/4, method: POST, req_id: eac923b5-1efb-4d63-9e51-682671e5194e, remote_addr: 127.0.0.1:61975, local_addr: 127.0.0.1:9998 Sep 09 17:58:24.793 INFO request completed, latency_us: 82, response_code: 200, uri: /state/cid/4, method: GET, req_id: beb5e3c8-3db0-4d81-982a-028607f9e9db, remote_addr: 127.0.0.1:61975, local_addr: 127.0.0.1:9998 [8850] Exited with: ExitStatus(unix_wait_status(9)) [8850][4] reports Exit Sep 09 17:58:28.794 INFO request completed, latency_us: 210, response_code: 200, uri: /state/cid/4, method: GET, req_id: 2b27ab7d-0768-40cb-983f-51f72e59a48d, remote_addr: 127.0.0.1:61975, local_addr: 127.0.0.1:9998 Sep 09 17:59:05.458 INFO accepted connection, remote_addr: 127.0.0.1:53520, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(4) start 4 Sep 09 17:59:05.459 INFO request completed, latency_us: 187, response_code: 204, uri: /start/cid/4, method: POST, req_id: c0d3121a-db72-4602-a76b-80b115f60755, remote_addr: 127.0.0.1:53520, 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(1242) [8850][4] reports Running Sep 09 17:59:09.506 INFO accepted connection, remote_addr: 127.0.0.1:33635, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Shutdown Shutdown Shut it down Sep 09 17:59:09.507 INFO request completed, latency_us: 139, response_code: 204, uri: /shutdown, method: POST, req_id: 2125e3e6-9d50-4c23-af82-3a60a356477d, remote_addr: 127.0.0[8820] Disable keep_running .[8810] Disable keep_running 1:33635[8810] Got stop action so:false kr:false , local_addr: 127.0.0.1:9998 [8820] Got stop action so:false kr:false [8840] Disable keep_running [8850] Disable keep_running [8860] Disable keep_running [8840] Got stop action so:false kr:false [8830] Disable keep_running [8850] Got stop action so:false kr:false [8860] Got stop action so:false kr:false [8830] Got stop action so:false kr:false [8870] Disable keep_running [8870] 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 [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] reports Exit [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit [8870] Exited with: ExitStatus(unix_wait_status(9)) [8870][6] reports Exit