/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.18379983 Downstairs region 1 created at /var/tmp/test_up-build/8820 in 0.011940907 Downstairs region 2 created at /var/tmp/test_up-build/8830 in 0.011498522 Downstairs region 3 created at /var/tmp/test_up-build/8840 in 0.011055148 Downstairs region 4 created at /var/tmp/test_up-build/8850 in 0.010951961 Downstairs region 5 created at /var/tmp/test_up-build/8860 in 0.010886864 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 Make output file at "/tmp/test_up-build/dsc/downstairs-8810.txt" Make output file at "/tmp/test_up-build/dsc/downstairs-8820.txt" Make output file at "/tmp/test_up-build/dsc/downstairs-8830.txt" [8810][0] initial start wait reports Starting [8820][1] initial start wait reports Starting Starting downstairs at port 8840 [8830][2] initial start wait reports Starting Starting downstairs at port 8850 Make output file at "/tmp/test_up-build/dsc/downstairs-8850.txt" [8840][3] initial start wait reports Starting Make output file at "/tmp/test_up-build/dsc/downstairs-8840.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" [8860][5] initial start wait reports Starting Control access at:127.0.0.1:9998 Downstairs /var/tmp/test_up-build/8820 port 8820 PID:Some(1140) Downstairs /var/tmp/test_up-build/8860 port 8860 PID:Some(1145) [8820][1] initial start wait reports Running [8860][5] initial start wait reports Running Downstairs /var/tmp/test_up-build/8850 port 8850 PID:Some(1142) [8850][4] initial start wait reports Running Downstairs /var/tmp/test_up-build/8840 port 8840 PID:Some(1143) [8840][3] initial start wait reports Running Downstairs /var/tmp/test_up-build/8810 port 8810 PID:Some(1144) [8810][0] initial start wait reports Running Sep 04 05:05:45.846 INFO listening, local_addr: 127.0.0.1:9998 Downstairs /var/tmp/test_up-build/8830 port 8830 PID:Some(1141) [8830][2] initial start wait reports Running All downstairs are running Sep 04 05:05:50.856 INFO accepted connection, remote_addr: 127.0.0.1:38857, local_addr: 127.0.0.1:9998 Sep 04 05:05:50.858 INFO request completed, latency_us: 512, response_code: 200, uri: /state/cid/0, method: GET, req_id: 27eda3c0-4653-4241-be45-d795d9bb5523, remote_addr: 127.0.0.1:38857, local_addr: 127.0.0.1:9998 Sep 04 05:05:50.872 INFO accepted connection, remote_addr: 127.0.0.1:49663, local_addr: 127.0.0.1:9998 Sep 04 05:05:50.872 INFO request completed, latency_us: 111, response_code: 200, uri: /state/cid/1, method: GET, req_id: afed6d68-67ca-4b86-8720-0165298ce868, remote_addr: 127.0.0.1:49663, local_addr: 127.0.0.1:9998 Sep 04 05:05:50.885 INFO accepted connection, remote_addr: 127.0.0.1:37166, local_addr: 127.0.0.1:9998 Sep 04 05:05:50.886 INFO request completed, latency_us: 101, response_code: 200, uri: /state/cid/2, method: GET, req_id: eaa20984-8fc4-46a1-bd8c-1e42a47ecfbb, remote_addr: 127.0.0.1:37166, local_addr: 127.0.0.1:9998 Sep 04 05:05:50.899 INFO accepted connection, remote_addr: 127.0.0.1:65322, local_addr: 127.0.0.1:9998 Sep 04 05:05:50.900 INFO request completed, latency_us: 70, response_code: 200, uri: /state/cid/3, method: GET, req_id: 8435c9b2-9943-4683-a1ae-cbc3bd758299, remote_addr: 127.0.0.1:65322, local_addr: 127.0.0.1:9998 Sep 04 05:05:50.912 INFO accepted connection, remote_addr: 127.0.0.1:36722, local_addr: 127.0.0.1:9998 Sep 04 05:05:50.913 INFO request completed, latency_us: 67, response_code: 200, uri: /state/cid/4, method: GET, req_id: 09e72da1-60e1-4d09-8072-0b461ad7c732, remote_addr: 127.0.0.1:36722, local_addr: 127.0.0.1:9998 Sep 04 05:05:50.926 INFO accepted connection, remote_addr: 127.0.0.1:59291, local_addr: 127.0.0.1:9998 Sep 04 05:05:50.926 INFO request completed, latency_us: 59, response_code: 200, uri: /state/cid/5, method: GET, req_id: f2dca921-3768-458c-be3e-90bdf6e30ef3, remote_addr: 127.0.0.1:59291, local_addr: 127.0.0.1:9998 Sep 04 05:05:50.939 INFO accepted connection, remote_addr: 127.0.0.1:36963, 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 04 05:05:50.939 INFO request completed, latency_us: 83, response_code: 204, uri: /disablerestart/all, method: POST, req_id: 9d7608be-7515-4499-841d-4534a004becf, remote_addr: 127.0.0.1[8810] Disable keep_running :[8820] Disable keep_running 36963[8830] Disable keep_running , [8850] Disable keep_running local_addr: 127.0.[8860] Disable keep_running 0[8840] Disable keep_running .1:9998 Sep 04 05:05:51.001 INFO accepted connection, remote_addr: 127.0.0.1:50146, local_addr: 127.0.0.1:9998 Sep 04 05:05:51.002 INFO request completed, latency_us: 255, response_code: 200, uri: /regioninfo, method: GET, req_id: 8e2fd5f4-f904-479e-91cf-603271dafead, remote_addr: 127.0.0.1:50146, local_addr: 127.0.0.1:9998 Sep 04 05:05:51.004 INFO request completed, latency_us: 34, response_code: 200, uri: /regioncount, method: GET, req_id: 13c9a2a2-9b70-45fd-a670-ef709a9573a2, remote_addr: 127.0.0.1:50146, local_addr: 127.0.0.1:9998 Sep 04 05:05:51.005 INFO request completed, latency_us: 73, response_code: 200, uri: /port/cid/0, method: GET, req_id: e0ebac6d-6291-4d58-9722-e4d7d9b29a4e, remote_addr: 127.0.0.1:50146, local_addr: 127.0.0.1:9998 Sep 04 05:05:51.005 INFO request completed, latency_us: 38, response_code: 200, uri: /port/cid/1, method: GET, req_id: d7b28753-84a9-44db-b0e7-af37e7a6f92f, remote_addr: 127.0.0.1:50146, local_addr: 127.0.0.1:9998 Sep 04 05:05:51.005 INFO request completed, latency_us: 26, response_code: 200, uri: /port/cid/2, method: GET, req_id: afbc65a1-bfe4-4fc8-b4ed-a23f0826d70d, remote_addr: 127.0.0.1:50146, local_addr: 127.0.0.1:9998 Sep 04 05:05:51.009 INFO request completed, latency_us: 43, response_code: 200, uri: /port/cid/3, method: GET, req_id: 304fe8e1-0374-46de-9ce1-7ee4f4f13d0b, remote_addr: 127.0.0.1:50146, local_addr: 127.0.0.1:9998 Sep 04 05:05:51.009 INFO request completed, latency_us: 34, response_code: 200, uri: /port/cid/4, method: GET, req_id: 7b120ad3-8b12-4fcb-8e83-01c5426a216f, remote_addr: 127.0.0.1:50146, local_addr: 127.0.0.1:9998 Sep 04 05:05:51.009 INFO request completed, latency_us: 24, response_code: 200, uri: /port/cid/5, method: GET, req_id: 4d4603e6-5e7a-47b1-8061-8e5ca30d05f4, remote_addr: 127.0.0.1:50146, local_addr: 127.0.0.1:9998 Sep 04 05:06:00.066 INFO accepted connection, remote_addr: 127.0.0.1:35296, local_addr: 127.0.0.1:9998 Sep 04 05:06:00.067 INFO request completed, latency_us: 152, response_code: 200, uri: /regioninfo, method: GET, req_id: 49bfc184-89ec-48a8-b5ba-401cdac9df97, remote_addr: 127.0.0.1:35296, local_addr: 127.0.0.1:9998 Sep 04 05:06:00.067 INFO request completed, latency_us: 70, response_code: 200, uri: /regioncount, method: GET, req_id: f85201ef-c7c3-4679-becd-df0f13a2d403, remote_addr: 127.0.0.1:35296, local_addr: 127.0.0.1:9998 Sep 04 05:06:00.068 INFO request completed, latency_us: 87, response_code: 200, uri: /port/cid/0, method: GET, req_id: 18a695af-254c-4257-8773-ea3547923b5f, remote_addr: 127.0.0.1:35296, local_addr: 127.0.0.1:9998 Sep 04 05:06:00.068 INFO request completed, latency_us: 66, response_code: 200, uri: /port/cid/1, method: GET, req_id: 3dbf0675-0518-4ade-b7e3-6dda95e0f931, remote_addr: 127.0.0.1:35296, local_addr: 127.0.0.1:9998 Sep 04 05:06:00.069 INFO request completed, latency_us: 74, response_code: 200, uri: /port/cid/2, method: GET, req_id: cd6d520b-5b35-4e49-b9d8-3111d03d985a, remote_addr: 127.0.0.1:35296, local_addr: 127.0.0.1:9998 Sep 04 05:06:00.070 INFO request completed, latency_us: 71, response_code: 200, uri: /port/cid/3, method: GET, req_id: a132b049-0cc9-45e7-9b33-489569e70a7d, remote_addr: 127.0.0.1:35296, local_addr: 127.0.0.1:9998 Sep 04 05:06:00.071 INFO request completed, latency_us: 76, response_code: 200, uri: /port/cid/4, method: GET, req_id: e54c267e-7189-4754-98d1-dc666bff4e08, remote_addr: 127.0.0.1:35296, local_addr: 127.0.0.1:9998 Sep 04 05:06:00.071 INFO request completed, latency_us: 66, response_code: 200, uri: /port/cid/5, method: GET, req_id: 2b90d488-6a68-4b26-845d-ed0edd744b28, remote_addr: 127.0.0.1:35296, local_addr: 127.0.0.1:9998 Sep 04 05:06:09.366 INFO accepted connection, remote_addr: 127.0.0.1:53835, local_addr: 127.0.0.1:9998 Sep 04 05:06:09.367 INFO request completed, latency_us: 156, response_code: 200, uri: /regioninfo, method: GET, req_id: f8062543-b835-463e-8a24-309e801f4657, remote_addr: 127.0.0.1:53835, local_addr: 127.0.0.1:9998 Sep 04 05:06:09.367 INFO request completed, latency_us: 70, response_code: 200, uri: /regioncount, method: GET, req_id: 94ee1c55-3f82-4e4f-af36-87c4a5c5d6b8, remote_addr: 127.0.0.1:53835, local_addr: 127.0.0.1:9998 Sep 04 05:06:09.368 INFO request completed, latency_us: 73, response_code: 200, uri: /port/cid/0, method: GET, req_id: 18a01580-419b-4501-a978-220351b6c164, remote_addr: 127.0.0.1:53835, local_addr: 127.0.0.1:9998 Sep 04 05:06:09.368 INFO request completed, latency_us: 34, response_code: 200, uri: /port/cid/1, method: GET, req_id: 8ee35def-88ba-479a-a466-4eef89c28a88, remote_addr: 127.0.0.1:53835, local_addr: 127.0.0.1:9998 Sep 04 05:06:09.368 INFO request completed, latency_us: 30, response_code: 200, uri: /port/cid/2, method: GET, req_id: 390bb34d-16e1-401c-b948-09c6aec1fcaa, remote_addr: 127.0.0.1:53835, local_addr: 127.0.0.1:9998 Sep 04 05:06:09.370 INFO request completed, latency_us: 67, response_code: 200, uri: /port/cid/3, method: GET, req_id: 21c2addc-c8ab-48d1-a7ea-2d836467d48a, remote_addr: 127.0.0.1:53835, local_addr: 127.0.0.1:9998 Sep 04 05:06:09.370 INFO request completed, latency_us: 35, response_code: 200, uri: /port/cid/4, method: GET, req_id: aab90f53-f673-40eb-95f4-25ac19cc2eed, remote_addr: 127.0.0.1:53835, local_addr: 127.0.0.1:9998 Sep 04 05:06:09.370 INFO request completed, latency_us: 29, response_code: 200, uri: /port/cid/5, method: GET, req_id: bce31014-f2fa-4d0a-a12c-af1de0e1777a, remote_addr: 127.0.0.1:53835, local_addr: 127.0.0.1:9998 Sep 04 05:06:33.356 INFO accepted connection, remote_addr: 127.0.0.1:58961, local_addr: 127.0.0.1:9998 Sep 04 05:06:33.357 INFO request completed, latency_us: 159, response_code: 200, uri: /regioninfo, method: GET, req_id: b832d8ae-59c9-4afa-8656-591e53fd2d78, remote_addr: 127.0.0.1:58961, local_addr: 127.0.0.1:9998 Sep 04 05:06:33.358 INFO request completed, latency_us: 65, response_code: 200, uri: /regioncount, method: GET, req_id: ed8bfcf5-d15a-49a5-a949-236b9e3edc6a, remote_addr: 127.0.0.1:58961, local_addr: 127.0.0.1:9998 Sep 04 05:06:33.358 INFO request completed, latency_us: 62, response_code: 200, uri: /port/cid/0, method: GET, req_id: 2a976c5c-0ec0-4197-a6a3-b1d419863e46, remote_addr: 127.0.0.1:58961, local_addr: 127.0.0.1:9998 Sep 04 05:06:33.358 INFO request completed, latency_us: 33, response_code: 200, uri: /port/cid/1, method: GET, req_id: 7b34b5a4-cec2-4f11-85bf-2084cfbf39d5, remote_addr: 127.0.0.1:58961, local_addr: 127.0.0.1:9998 Sep 04 05:06:33.358 INFO request completed, latency_us: 47, response_code: 200, uri: /port/cid/2, method: GET, req_id: 14f30a7d-a607-4e69-90a3-9ee97b709acf, remote_addr: 127.0.0.1:58961, local_addr: 127.0.0.1:9998 Sep 04 05:06:33.360 INFO request completed, latency_us: 61, response_code: 200, uri: /port/cid/3, method: GET, req_id: ed8f762a-88e6-484c-a423-2cfb56b28dab, remote_addr: 127.0.0.1:58961, local_addr: 127.0.0.1:9998 Sep 04 05:06:33.360 INFO request completed, latency_us: 65, response_code: 200, uri: /port/cid/4, method: GET, req_id: 58b85d49-891b-4619-a272-27b3df8a96a7, remote_addr: 127.0.0.1:58961, local_addr: 127.0.0.1:9998 Sep 04 05:06:33.361 INFO request completed, latency_us: 76, response_code: 200, uri: /port/cid/5, method: GET, req_id: 2d6f1760-a0fa-4348-881b-adedc2aff528, remote_addr: 127.0.0.1:58961, local_addr: 127.0.0.1:9998 Sep 04 05:08:58.008 INFO accepted connection, remote_addr: 127.0.0.1:33285, local_addr: 127.0.0.1:9998 Sep 04 05:08:58.008 INFO request completed, latency_us: 155, response_code: 200, uri: /regioninfo, method: GET, req_id: a0fca66c-5bad-4564-9ce6-4385e0e8d142, remote_addr: 127.0.0.1:33285, local_addr: 127.0.0.1:9998 Sep 04 05:08:58.009 INFO request completed, latency_us: 39, response_code: 200, uri: /regioncount, method: GET, req_id: 0079e28a-ed36-4a5b-aa51-158e961c492e, remote_addr: 127.0.0.1:33285, local_addr: 127.0.0.1:9998 Sep 04 05:08:58.009 INFO request completed, latency_us: 58, response_code: 200, uri: /port/cid/0, method: GET, req_id: 401d7de5-e80a-4225-9402-96c6b9460200, remote_addr: 127.0.0.1:33285, local_addr: 127.0.0.1:9998 Sep 04 05:08:58.010 INFO request completed, latency_us: 31, response_code: 200, uri: /port/cid/1, method: GET, req_id: 33d6e33c-f97c-4de4-aaed-890204360c0f, remote_addr: 127.0.0.1:33285, local_addr: 127.0.0.1:9998 Sep 04 05:08:58.010 INFO request completed, latency_us: 42, response_code: 200, uri: /port/cid/2, method: GET, req_id: d0c2f388-5761-4ca4-be9d-a3582ae5b015, remote_addr: 127.0.0.1:33285, local_addr: 127.0.0.1:9998 Sep 04 05:08:58.011 INFO request completed, latency_us: 49, response_code: 200, uri: /port/cid/3, method: GET, req_id: 51eeb6ad-b809-4a05-b8a7-5107e3c8d75f, remote_addr: 127.0.0.1:33285, local_addr: 127.0.0.1:9998 Sep 04 05:08:58.011 INFO request completed, latency_us: 34, response_code: 200, uri: /port/cid/4, method: GET, req_id: 6e2a5e88-2fce-498b-8cee-c3f9d9b5fc7f, remote_addr: 127.0.0.1:33285, local_addr: 127.0.0.1:9998 Sep 04 05:08:58.012 INFO request completed, latency_us: 29, response_code: 200, uri: /port/cid/5, method: GET, req_id: f500b889-c35b-4a86-a22a-5e9da44cbfca, remote_addr: 127.0.0.1:33285, local_addr: 127.0.0.1:9998 Sep 04 05:09:14.103 INFO accepted connection, remote_addr: 127.0.0.1:55305, local_addr: 127.0.0.1:9998 Sep 04 05:09:14.104 INFO request completed, latency_us: 150, response_code: 200, uri: /regioninfo, method: GET, req_id: 7e963e93-f09d-4381-acea-7d3dc00ec9be, remote_addr: 127.0.0.1:55305, local_addr: 127.0.0.1:9998 Sep 04 05:09:14.104 INFO request completed, latency_us: 38, response_code: 200, uri: /regioncount, method: GET, req_id: 88176180-b9c3-49e6-aecf-b037e26c461d, remote_addr: 127.0.0.1:55305, local_addr: 127.0.0.1:9998 Sep 04 05:09:14.104 INFO request completed, latency_us: 57, response_code: 200, uri: /port/cid/0, method: GET, req_id: 43f24106-c986-4828-b267-cd359971771d, remote_addr: 127.0.0.1:55305, local_addr: 127.0.0.1:9998 Sep 04 05:09:14.105 INFO request completed, latency_us: 42, response_code: 200, uri: /port/cid/1, method: GET, req_id: 76ae9a0f-b72a-45a8-9501-0f6f6a161988, remote_addr: 127.0.0.1:55305, local_addr: 127.0.0.1:9998 Sep 04 05:09:14.105 INFO request completed, latency_us: 30, response_code: 200, uri: /port/cid/2, method: GET, req_id: 310fc890-1ff3-4eaf-b588-b08ff4388a3f, remote_addr: 127.0.0.1:55305, local_addr: 127.0.0.1:9998 Sep 04 05:09:14.106 INFO request completed, latency_us: 74, response_code: 200, uri: /port/cid/3, method: GET, req_id: 52b075d6-2afb-4ddc-a096-9ffed26e0497, remote_addr: 127.0.0.1:55305, local_addr: 127.0.0.1:9998 Sep 04 05:09:14.106 INFO request completed, latency_us: 36, response_code: 200, uri: /port/cid/4, method: GET, req_id: bd622914-1f22-4778-95b1-83274d8aaa0d, remote_addr: 127.0.0.1:55305, local_addr: 127.0.0.1:9998 Sep 04 05:09:14.107 INFO request completed, latency_us: 31, response_code: 200, uri: /port/cid/5, method: GET, req_id: f70b2599-b1cb-46f7-b60e-31701b8fb422, remote_addr: 127.0.0.1:55305, local_addr: 127.0.0.1:9998 Sep 04 05:09:18.210 INFO accepted connection, remote_addr: 127.0.0.1:36944, local_addr: 127.0.0.1:9998 Sep 04 05:09:18.211 INFO request completed, latency_us: 151, response_code: 200, uri: /regioninfo, method: GET, req_id: 93eb16c1-4358-426c-b85d-396346463597, remote_addr: 127.0.0.1:36944, local_addr: 127.0.0.1:9998 Sep 04 05:09:18.211 INFO request completed, latency_us: 48, response_code: 200, uri: /regioncount, method: GET, req_id: 298eb17c-0b50-4412-b81c-5d87e3dd117a, remote_addr: 127.0.0.1:36944, local_addr: 127.0.0.1:9998 Sep 04 05:09:18.212 INFO request completed, latency_us: 58, response_code: 200, uri: /port/cid/0, method: GET, req_id: 3e59a2a0-f68f-4b7d-8088-a4b832ce0a6d, remote_addr: 127.0.0.1:36944, local_addr: 127.0.0.1:9998 Sep 04 05:09:18.212 INFO request completed, latency_us: 38, response_code: 200, uri: /port/cid/1, method: GET, req_id: 73c44d19-5844-4294-b9b0-2040a994c8d3, remote_addr: 127.0.0.1:36944, local_addr: 127.0.0.1:9998 Sep 04 05:09:18.212 INFO request completed, latency_us: 30, response_code: 200, uri: /port/cid/2, method: GET, req_id: 3d8628b6-9de8-4975-bad5-a25ecb2eb10e, remote_addr: 127.0.0.1:36944, local_addr: 127.0.0.1:9998 Sep 04 05:09:18.213 INFO request completed, latency_us: 74, response_code: 200, uri: /port/cid/3, method: GET, req_id: 284c1991-4ee6-4988-91a8-268a10ccf045, remote_addr: 127.0.0.1:36944, local_addr: 127.0.0.1:9998 Sep 04 05:09:18.214 INFO request completed, latency_us: 37, response_code: 200, uri: /port/cid/4, method: GET, req_id: b27cae0b-13c7-4ab7-a0b7-4e8e0582b9d8, remote_addr: 127.0.0.1:36944, local_addr: 127.0.0.1:9998 Sep 04 05:09:18.214 INFO request completed, latency_us: 31, response_code: 200, uri: /port/cid/5, method: GET, req_id: ea91ff1e-3ba0-4ce3-9f61-574af8be214e, remote_addr: 127.0.0.1:36944, local_addr: 127.0.0.1:9998 Sep 04 05:09:22.306 INFO accepted connection, remote_addr: 127.0.0.1:33767, 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 04 05:09:22.307 INFO request completed, latency_us: 128, response_code: 204, uri: /disablerestart/all, method: POST, req_id: 9f6084aa-2a7c-4039-9d2a-714134da1765, remote_addr: 127.0.0.1:33767, local_addr: [8810] Disable keep_running 127[8820] Disable keep_running .[8830] Disable keep_running 0[8850] Disable keep_running .0.1:9998 [8860] Disable keep_running [8840] Disable keep_running Sep 04 05:09:22.320 INFO accepted connection, remote_addr: 127.0.0.1:53169, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(2) stop 2 Sep 04 05:09:22.321 INFO request completed, latency_us: 103, response_code: 204, uri: /stop/cid/2, method: [8830] Got stop action so:false kr:false POST, req_id: d40a7ab6-8404-4013-91c0-d947e4f0a25d, remote_addr: 127.0.0.1:53169, local_addr: 127.0.0.1:9998 [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit Sep 04 05:09:22.334 INFO accepted connection, remote_addr: 127.0.0.1:57343, local_addr: 127.0.0.1:9998 Sep 04 05:09:22.334 INFO request completed, latency_us: 76, response_code: 200, uri: /state/cid/2, method: GET, req_id: 6c14943f-5f00-47b9-b68c-4b85ea27366a, remote_addr: 127.0.0.1:57343, local_addr: 127.0.0.1:9998 Sep 04 05:09:22.350 INFO accepted connection, remote_addr: 127.0.0.1:56795, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(2) start 2 Sep 04 05:09:22.351 INFO request completed, latency_us: 73, response_code: 204, uri: /start/cid/2, method: POST, req_id: 781beb13-b70f-4d4e-898d-c1044a6563df, remote_addr: 127.0.0.1:56795, local_addr: 127.0.0.1:9998 Sep 04 05:09:22.385 INFO accepted connection, remote_addr: 127.0.0.1:37546, local_addr: 127.0.0.1:9998 Sep 04 05:09:22.385 INFO request completed, latency_us: 109, response_code: 200, uri: /regioninfo, method: GET, req_id: ec395302-0493-4acb-b4b6-a01a80f833d8, remote_addr: 127.0.0.1:37546, local_addr: 127.0.0.1:9998 Sep 04 05:09:22.386 INFO request completed, latency_us: 33, response_code: 200, uri: /regioncount, method: GET, req_id: 4fe22152-0931-402e-b355-be3ce98c88f5, remote_addr: 127.0.0.1:37546, local_addr: 127.0.0.1:9998 Sep 04 05:09:22.387 INFO request completed, latency_us: 89, response_code: 200, uri: /port/cid/0, method: GET, req_id: 27ced340-c5a9-47c0-9c61-63b4f846cb4e, remote_addr: 127.0.0.1:37546, local_addr: 127.0.0.1:9998 Sep 04 05:09:22.387 INFO request completed, latency_us: 59, response_code: 200, uri: /port/cid/1, method: GET, req_id: 617c7a16-9aea-4afd-a1f2-0e197d5f6d95, remote_addr: 127.0.0.1:37546, local_addr: 127.0.0.1:9998 Sep 04 05:09:22.387 INFO request completed, latency_us: 58, response_code: 200, uri: /port/cid/2, method: GET, req_id: 06067959-ffb6-42ea-84c4-acdd7aba08b3, remote_addr: 127.0.0.1:37546, local_addr: 127.0.0.1:9998 Sep 04 05:09:22.389 INFO request completed, latency_us: 63, response_code: 200, uri: /port/cid/3, method: GET, req_id: 3dc5e76c-9bbc-41b3-bc39-bcf09d98390b, remote_addr: 127.0.0.1:37546, local_addr: 127.0.0.1:9998 Sep 04 05:09:22.389 INFO request completed, latency_us: 38, response_code: 200, uri: /port/cid/4, method: GET, req_id: b2100f86-47a8-49ef-b80d-ad9b341e8284, remote_addr: 127.0.0.1:37546, local_addr: 127.0.0.1:9998 Sep 04 05:09:22.389 INFO request completed, latency_us: 31, response_code: 200, uri: /port/cid/5, method: GET, req_id: efca1ad6-c996-441d-b30f-68a1df9cf13e, remote_addr: 127.0.0.1:37546, 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(1196) [8830][2] reports Running Sep 04 05:09:36.535 INFO accepted connection, remote_addr: 127.0.0.1:63814, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Shutdown Shutdown Sep 04 05:09:36.535 Shut it down INFO request completed, latency_us: 129, response_code: 204, uri: /shutdown, method: POST, req_id: e4e5d05b-b311-45b5-b8f7-d1c4e5a52eb1, remote_addr: 127.0.0.1:63814, local_addr: 127[8810] Disable keep_running .[8830] Disable keep_running 0[8840] Disable keep_running .[8850] Disable keep_running 0.1:9998 [8850] Got stop action so:false kr:false [8840] Got stop action so:false kr:false [8820] Disable keep_running [8860] Disable keep_running [8860] Got stop action so:false kr:false [8820] Got stop action so:false kr:false [8830] Got stop action so:false kr:false [8810] Got stop action so:false kr:false [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit [8860] Exited with: ExitStatus(unix_wait_status(9)) [8840] Exited with: ExitStatus(unix_wait_status(9)) [8860][5] reports Exit [8840][3] reports Exit [8850] Exited with: ExitStatus(unix_wait_status(9)) [8850][4] reports Exit [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] 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.029587487 Downstairs region 1 created at /var/tmp/test_up-build/8820 in 0.026804583 Downstairs region 2 created at /var/tmp/test_up-build/8830 in 0.026068565 Downstairs region 3 created at /var/tmp/test_up-build/8840 in 0.02552916 Downstairs region 4 created at /var/tmp/test_up-build/8850 in 0.026286028 Downstairs region 5 created at /var/tmp/test_up-build/8860 in 0.025919016 Downstairs region 6 created at /var/tmp/test_up-build/8870 in 0.034129888 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 8820 Starting downstairs at port 8810 Starting downstairs at port 8830 Make output file at "/tmp/test_up-build/dsc/downstairs-8810.txt" [8820][1] initial start wait reports Starting Make output file at "/tmp/test_up-build/dsc/downstairs-8830.txt" Make output file at "/tmp/test_up-build/dsc/downstairs-8820.txt" [8810][0] initial start wait reports Starting 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 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 Starting downstairs at port 8870 Make output file at "/tmp/test_up-build/dsc/downstairs-8870.txt" [8870][6] initial start wait reports Starting Control access at:127.0.0.1:9998 Sep 04 05:09:41.753 INFO listening, local_addr: 127.0.0.1:9998 Downstairs /var/tmp/test_up-build/8820 port 8820 PID:Some(1213) Downstairs /var/tmp/test_up-build/8810 port 8810 PID:Some(1219) Downstairs /var/tmp/test_up-build/8830 port 8830 PID:Some(1218) Downstairs /var/tmp/test_up-build/8860 port 8860 PID:Some(1215) Downstairs /var/tmp/test_up-build/8850 port 8850 PID:Some(1214) Downstairs /var/tmp/test_up-build/8840 port 8840 PID:Some(1216) Downstairs /var/tmp/test_up-build/8870 port 8870 PID:Some(1217) [8820][1] initial start wait reports Running [8810][0] initial start wait reports Running [8830][2] initial start wait reports Running [8860][5] initial start wait reports Running [8850][4] initial start wait reports Running [8840][3] initial start wait reports Running [8870][6] initial start wait reports Running All downstairs are running Sep 04 05:09:46.765 INFO accepted connection, remote_addr: 127.0.0.1:60587, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.766 INFO request completed, latency_us: 305, response_code: 200, uri: /state/cid/0, method: GET, req_id: 60618d11-0cc9-4dc6-b807-8636cbfc2939, remote_addr: 127.0.0.1:60587, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.781 INFO accepted connection, remote_addr: 127.0.0.1:62508, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.782 INFO request completed, latency_us: 65, response_code: 200, uri: /state/cid/1, method: GET, req_id: d81ce4cb-2dee-45ad-82bc-4ecf286dab3c, remote_addr: 127.0.0.1:62508, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.795 INFO accepted connection, remote_addr: 127.0.0.1:37076, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.796 INFO request completed, latency_us: 60, response_code: 200, uri: /state/cid/2, method: GET, req_id: 1f78fb8e-5c82-4ee6-b65e-5536e9728061, remote_addr: 127.0.0.1:37076, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.809 INFO accepted connection, remote_addr: 127.0.0.1:55334, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.810 INFO request completed, latency_us: 61, response_code: 200, uri: /state/cid/3, method: GET, req_id: 92921810-f6f0-4d2e-a5e7-f90c531da195, remote_addr: 127.0.0.1:55334, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.823 INFO accepted connection, remote_addr: 127.0.0.1:33696, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.823 INFO request completed, latency_us: 73, response_code: 200, uri: /state/cid/4, method: GET, req_id: 2e56581f-dc0b-42a0-b178-16fbafef5642, remote_addr: 127.0.0.1:33696, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.836 INFO accepted connection, remote_addr: 127.0.0.1:52882, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.837 INFO request completed, latency_us: 66, response_code: 200, uri: /state/cid/5, method: GET, req_id: c77884ca-a616-43da-ad14-111d39ab53a7, remote_addr: 127.0.0.1:52882, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.850 INFO accepted connection, remote_addr: 127.0.0.1:61372, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.850 INFO request completed, latency_us: 60, response_code: 200, uri: /state/cid/6, method: GET, req_id: ce843a14-2602-4dd6-a15e-03bd066ce874, remote_addr: 127.0.0.1:61372, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.863 INFO accepted connection, remote_addr: 127.0.0.1:32783, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.863 INFO request completed, latency_us: 76, response_code: 200, uri: /port/cid/6, method: GET, req_id: 8e52fc62-903e-4a83-8981-94916e8a78ee, remote_addr: 127.0.0.1:32783, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.882 INFO accepted connection, remote_addr: 127.0.0.1:45893, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.883 INFO request completed, latency_us: 117, response_code: 200, uri: /regioninfo, method: GET, req_id: 1872e271-e104-4803-aa00-c115e09adc8a, remote_addr: 127.0.0.1:45893, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.884 INFO request completed, latency_us: 69, response_code: 200, uri: /regioncount, method: GET, req_id: c8708909-7520-4404-aa2a-da2426fc5140, remote_addr: 127.0.0.1:45893, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.884 INFO request completed, latency_us: 65, response_code: 200, uri: /port/cid/0, method: GET, req_id: 7fa90e35-b4b7-42f8-9858-0ee0cc4148e2, remote_addr: 127.0.0.1:45893, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.884 INFO request completed, latency_us: 63, response_code: 200, uri: /port/cid/1, method: GET, req_id: 8cdac51a-d69a-4b9f-b65c-77f0f9529fd0, remote_addr: 127.0.0.1:45893, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.885 INFO request completed, latency_us: 57, response_code: 200, uri: /port/cid/2, method: GET, req_id: a0f49e66-da3e-4a92-bb5e-e9a2ae71f14d, remote_addr: 127.0.0.1:45893, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.886 INFO request completed, latency_us: 48, response_code: 200, uri: /port/cid/3, method: GET, req_id: 0945b891-a2f0-4192-9bc0-b47f8066d497, remote_addr: 127.0.0.1:45893, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.886 INFO request completed, latency_us: 27, response_code: 200, uri: /port/cid/4, method: GET, req_id: b7f136ce-a6aa-409d-af70-2c260b1ace06, remote_addr: 127.0.0.1:45893, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.886 INFO request completed, latency_us: 24, response_code: 200, uri: /port/cid/5, method: GET, req_id: dad11405-e41a-49ec-a754-4ef392a8d18c, remote_addr: 127.0.0.1:45893, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.909 INFO accepted connection, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:09:46.909 INFO request completed, latency_us: 130, response_code: 200, uri: /regioncount, method: GET, req_id: c851bb6c-7af1-4dee-8509-2aff314815a0, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Sep 04 05:09:47.280 INFO request completed, [8810] Got stop action so:false kr:false latency_us: 240, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 5eaaca03-a9cf-493a-91c7-30571b796fb4, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:09:47.281 INFO request completed, latency_us: 536, response_code: 200, uri: /state/cid/0, method: GET, req_id: 54d2472e-bf22-4764-9df6-11297f69f397, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 04 05:09:51.283 INFO request completed, latency_us: 244, response_code: 200, uri: /state/cid/0, method: GET, req_id: 23db4016-4609-4c0b-bbc8-b73ecbbd75d2, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 04 05:09:51.657 INFO request completed, latency_us: 226, response_code: 204, uri: /start/cid/0, method: POST, req_id: 7ed49c24-f399-4494-9129-ae1a8490b283, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:09:51.657 INFO request completed, latency_us: 49, response_code: 200, uri: /state/cid/0, method: GET, req_id: 51e1d85f-aeb2-4106-b7fe-7521df17af3f, remote_addr: 127.0.0.1:43771, 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 04 05:09:55.660 INFO request completed, latency_us: 196, response_code: 200, uri: /state/cid/0, method: GET, req_id: 8e5b31c0-33e9-49fb-8da5-2caf5ca67094, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Sep 04 05:10:02.028 INFO request completed, [8810] Got stop action so:false kr:false latency_us: 224, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 84fe62af-0007-4f15-8ac2-76199d16a3f3, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:10:02.029 INFO request completed, latency_us: 55, response_code: 200, uri: /state/cid/0, method: GET, req_id: 282b9c1a-84ab-4a59-bed1-978fea2a4ea2, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 04 05:10:06.030 INFO request completed, latency_us: 234, response_code: 200, uri: /state/cid/0, method: GET, req_id: fa15b442-80a5-4cab-b1b3-7347df483d27, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 04 05:10:06.411 INFO request completed, latency_us: 218, response_code: 204, uri: /start/cid/0, method: POST, req_id: c2f37bf5-2baf-443f-88e9-640a2ee2a37e, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:10:06.411 INFO request completed, latency_us: 73, response_code: 200, uri: /state/cid/0, method: GET, req_id: 8a279bf3-15b3-40ac-a6c5-140f432920d3, remote_addr: 127.0.0.1:43771, 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 04 05:10:10.413 INFO request completed, latency_us: 230, response_code: 200, uri: /state/cid/0, method: GET, req_id: 00bf5c73-ee62-4c65-aae5-51faa1e6102e, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Sep 04 05:10:15.782 [8810] Got stop action so:false kr:false INFO request completed, latency_us: 215, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 2e1755a4-4a05-44f9-b4c8-e5604e1f5b15, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:10:15.783 INFO request completed, latency_us: 109, response_code: 200, uri: /state/cid/0, method: GET, req_id: 56705b2a-0d94-4309-80a2-45890c0f0efb, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 04 05:10:19.785 INFO request completed, latency_us: 212, response_code: 200, uri: /state/cid/0, method: GET, req_id: c3291431-6d3a-4ec1-bda8-0a35a8681a29, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 04 05:10:20.168 INFO request completed, latency_us: 217, response_code: 204, uri: /start/cid/0, method: POST, req_id: b0906a71-d2a4-4f77-8edb-998fb40b9ad6, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:10:20.168 INFO request completed, latency_us: 51, response_code: 200, uri: /state/cid/0, method: GET, req_id: e8ebd583-1b8d-49e7-a736-6ce51a4a07cc, remote_addr: 127.0.0.1:43771, 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 04 05:10:24.170 INFO request completed, latency_us: 192, response_code: 200, uri: /state/cid/0, method: GET, req_id: b447e8f6-e540-4bf0-8271-83d1bb88bf48, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Sep 04 05:10:28.543 [8810] Got stop action so:false kr:false INFO request completed, latency_us: 219, response_code: 204, uri: /stop/cid/0, method: POST, req_id: a316d729-175c-45ca-af9f-52d7212e4056, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:10:28.543 INFO request completed, latency_us: 89, response_code: 200, uri: /state/cid/0, method: GET, req_id: d891b6d5-c175-4517-a8bb-87460c82101b, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 04 05:10:32.546 INFO request completed, latency_us: 202, response_code: 200, uri: /state/cid/0, method: GET, req_id: e3d2a766-81e1-4c17-b622-481809da54fb, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 04 05:10:32.933 INFO request completed, latency_us: 226, response_code: 204, uri: /start/cid/0, method: POST, req_id: 1d4c7d18-57be-4c49-a08f-3d10b0d97400, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:10:32.934 INFO request completed, latency_us: 91, response_code: 200, uri: /state/cid/0, method: GET, req_id: 57145233-9877-43fa-ac42-63d1b6881da7, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:10:36.936 INFO request completed, latency_us: 186, response_code: 200, uri: /state/cid/0, method: GET, req_id: f09828db-6139-4beb-82dd-f7e817905827, remote_addr: 127.0.0.1:43771, 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 04 05:10:40.938 INFO request completed, latency_us: 190, response_code: 200, uri: /state/cid/0, method: GET, req_id: 663d378e-c6ca-4a33-9c3c-5d98203f6850, remote_addr: 127.0.0.1:43771, 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 04 05:10:44.311 INFO request completed, latency_us: 269, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 1e1061cf-9c0b-4e03-965e-c76a4c0a841d, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:10:44.312 INFO request completed, latency_us: 95, response_code: 200, uri: /state/cid/0, method: GET, req_id: 655396b7-0ff9-4aec-8809-7140ed02aed8, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 04 05:10:48.313 INFO request completed, latency_us: 209, response_code: 200, uri: /state/cid/0, method: GET, req_id: 486ffc52-be20-40c9-8f22-dc38268d28d3, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 04 05:10:48.698 INFO request completed, latency_us: 225, response_code: 204, uri: /start/cid/0, method: POST, req_id: 685c1af7-c320-4632-987d-be351693836b, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:10:48.699 INFO request completed, latency_us: 52, response_code: 200, uri: /state/cid/0, method: GET, req_id: 4347e4cf-6e4c-4b57-aaf6-0b9146c643fb, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:10:52.701 INFO request completed, latency_us: 208, response_code: 200, uri: /state/cid/0, method: GET, req_id: 3ade03b8-9118-4569-9c30-a3d8eb72957a, remote_addr: 127.0.0.1:43771, 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 04 05:10:56.703 INFO request completed, latency_us: 185, response_code: 200, uri: /state/cid/0, method: GET, req_id: 6107801d-ef4d-45ca-9743-7143a6427d9d, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Sep 04 05:10:59.080 INFO request completed, latency_us: 230, response_code: 204[8810] Got stop action so:false kr:false , uri: /stop/cid/0, method: POST, req_id: 408b7389-9d11-4d6c-b064-bc2786056792, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:10:59.081 INFO request completed, latency_us: 69, response_code: 200, uri: /state/cid/0, method: GET, req_id: 2125e4bf-935a-4e29-8a9b-b5412e46df96, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 04 05:11:03.083 INFO request completed, latency_us: 210, response_code: 200, uri: /state/cid/0, method: GET, req_id: 0939ef72-63f0-43ae-8816-0419e5b13447, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 04 05:11:03.468 INFO request completed, latency_us: 219, response_code: 204, uri: /start/cid/0, method: POST, req_id: 8577d5da-c3ac-4013-becb-394d6922aedc, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:11:03.468 INFO request completed, latency_us: 82, response_code: 200, uri: /state/cid/0, method: GET, req_id: 352420ec-4926-46fc-a3b9-ab7667c522e0, remote_addr: 127.0.0.1:43771, 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 04 05:11:07.470 INFO request completed, latency_us: 195, response_code: 200, uri: /state/cid/0, method: GET, req_id: 3b230f91-131b-483d-8c93-e320cb0e25a7, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(1) stop 1 Sep 04 05:11:34.686 INFO [8820] Got stop action so:false kr:false request completed, latency_us: 231, response_code: 204, uri: /stop/cid/1, method: POST, req_id: e5b2bca9-2c66-4a4f-b895-54920c890cbe, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:11:34.686 INFO request completed, latency_us: 82, response_code: 200, uri: /state/cid/1, method: GET, req_id: fbaa5010-426d-42ab-ab11-751aa7b56af8, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] reports Exit Sep 04 05:11:38.689 INFO request completed, latency_us: 208, response_code: 200, uri: /state/cid/1, method: GET, req_id: 49490dbe-da00-40b6-8cf8-ea3950b3512a, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(1) start 1 Sep 04 05:11:39.076 INFO request completed, latency_us: 227, response_code: 204, uri: /start/cid/1, method: POST, req_id: 4b6a7959-b352-423f-8197-4f9ff9801822, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:11:39.076 INFO request completed, latency_us: 72, response_code: 200, uri: /state/cid/1, method: GET, req_id: 3f9fb002-d6a5-476e-8923-0e079c8b134f, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:11:43.079 INFO request completed, latency_us: 199, response_code: 200, uri: /state/cid/1, method: GET, req_id: 0223eb2b-2718-46a0-b677-58756fdeb99c, remote_addr: 127.0.0.1:43771, 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(1236) [8820][1] reports Running Sep 04 05:11:47.081 INFO request completed, latency_us: 201, response_code: 200, uri: /state/cid/1, method: GET, req_id: 3f7daa0f-9319-4c6d-9067-31b770920d1a, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(2) stop 2 Sep 04 05:12:14.420 INFO request completed[8830] Got stop action so:false kr:false , latency_us: 219, response_code: 204, uri: /stop/cid/2, method: POST, req_id: 021d7623-2cd2-48d3-aad1-2d529d2b9a7f, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:12:14.421 INFO request completed, latency_us: 76, response_code: 200, uri: /state/cid/2, method: GET, req_id: 10c1d669-f5cc-412f-8b65-5d791cee7f04, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit Sep 04 05:12:18.423 INFO request completed, latency_us: 205, response_code: 200, uri: /state/cid/2, method: GET, req_id: 10a84861-3090-4aff-9a1e-2a745beb4b96, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(2) start 2 Sep 04 05:12:18.809 INFO request completed, latency_us: 221, response_code: 204, uri: /start/cid/2, method: POST, req_id: 307cd9b1-c89d-42ff-ae89-7286aeccbe97, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:12:18.809 INFO request completed, latency_us: 83, response_code: 200, uri: /state/cid/2, method: GET, req_id: b9a675ef-d2eb-4837-bd2c-d991d9ffe3a4, remote_addr: 127.0.0.1:43771, 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(1237) [8830][2] reports Running Sep 04 05:12:22.811 INFO request completed, latency_us: 190, response_code: 200, uri: /state/cid/2, method: GET, req_id: f9a09f67-5b6d-4c67-b5f4-d56d9200ead4, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(3) stop 3 Sep 04 05:12:50.347 INFO request completed, [8840] Got stop action so:false kr:false latency_us: 219, response_code: 204, uri: /stop/cid/3, method: POST, req_id: 4f386bd9-a99f-456b-ab1f-2d64fd7c3bfa, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:12:50.348 INFO request completed, latency_us: 107, response_code: 200, uri: /state/cid/3, method: GET, req_id: 128c2965-236f-4908-aff9-8ae970aa773e, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 [8840] Exited with: ExitStatus(unix_wait_status(9)) [8840][3] reports Exit Sep 04 05:12:54.349 INFO request completed, latency_us: 199, response_code: 200, uri: /state/cid/3, method: GET, req_id: 0e02f48b-bcb7-4b43-9c22-9666ed2cbff9, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(3) start 3 Sep 04 05:12:54.743 INFO request completed, latency_us: 220, response_code: 204, uri: /start/cid/3, method: POST, req_id: 761418c8-266d-4c18-a728-54eeaba50fec, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:12:54.743 INFO request completed, latency_us: 85, response_code: 200, uri: /state/cid/3, method: GET, req_id: d1b10abd-64ed-4883-ba85-cc675f4111e4, remote_addr: 127.0.0.1:43771, 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(1238) [8840][3] reports Running Sep 04 05:12:58.746 INFO request completed, latency_us: 195, response_code: 200, uri: /state/cid/3, method: GET, req_id: e968d414-f492-430e-a5ac-c383e4118083, remote_addr: 127.0.0.1:43771, local_addr: 127.0.0.1:9998 Sep 04 05:13:26.163 INFO accepted connection, remote_addr: 127.0.0.1:63756, local_addr: 127.0.0.1:9998 Sep 04 05:13:26.164 INFO request completed, latency_us: 157, response_code: 200, uri: /regioninfo, method: GET, req_id: 90afda7a-57f7-4874-8a02-3eb7b7ab0c4e, remote_addr: 127.0.0.1:63756, local_addr: 127.0.0.1:9998 Sep 04 05:13:26.165 INFO request completed, latency_us: 71, response_code: 200, uri: /regioncount, method: GET, req_id: d5888c66-5af9-443a-9f5a-99f58fbbde8c, remote_addr: 127.0.0.1:63756, local_addr: 127.0.0.1:9998 Sep 04 05:13:26.165 INFO request completed, latency_us: 64, response_code: 200, uri: /port/cid/0, method: GET, req_id: 827ab5cf-dd58-4e42-bf64-a7bd9f3b9711, remote_addr: 127.0.0.1:63756, local_addr: 127.0.0.1:9998 Sep 04 05:13:26.165 INFO request completed, latency_us: 45, response_code: 200, uri: /port/cid/1, method: GET, req_id: e7dcb9ad-7948-4641-87e8-977715ef07c6, remote_addr: 127.0.0.1:63756, local_addr: 127.0.0.1:9998 Sep 04 05:13:26.165 INFO request completed, latency_us: 32, response_code: 200, uri: /port/cid/2, method: GET, req_id: 71eec401-f275-4dfd-9414-91d130fbc4c7, remote_addr: 127.0.0.1:63756, local_addr: 127.0.0.1:9998 Sep 04 05:13:26.167 INFO request completed, latency_us: 43, response_code: 200, uri: /port/cid/3, method: GET, req_id: 38ac5d3f-2b8d-434c-8f42-4951e623e68f, remote_addr: 127.0.0.1:63756, local_addr: 127.0.0.1:9998 Sep 04 05:13:26.167 INFO request completed, latency_us: 53, response_code: 200, uri: /port/cid/4, method: GET, req_id: 943528a3-6cc7-41bb-b726-f6a18e2e4ee5, remote_addr: 127.0.0.1:63756, local_addr: 127.0.0.1:9998 Sep 04 05:13:26.167 INFO request completed, latency_us: 33, response_code: 200, uri: /port/cid/5, method: GET, req_id: a4d95410-ee81-4c1e-ac93-f5c8a3e84eea, remote_addr: 127.0.0.1:63756, local_addr: 127.0.0.1:9998 Sep 04 05:13:26.351 INFO accepted connection, remote_addr: 127.0.0.1:51936, local_addr: 127.0.0.1:9998 Sep 04 05:13:26.352 INFO request completed, latency_us: 132, response_code: 200, uri: /regioncount, method: GET, req_id: 45e30b80-7b65-4d3f-a1a7-3a3c713edde4, remote_addr: 127.0.0.1:51936, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Sep 04 05:13:31.616 INFO [8810] Got stop action so:false kr:false request completed, latency_us: 225, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 76bf7e5d-aec7-4fc5-a192-4c2affcb2608, remote_addr: 127.0.0.1:51936, local_addr: 127.0.0.1:9998 Sep 04 05:13:31.617 INFO request completed, latency_us: 87, response_code: 200, uri: /state/cid/0, method: GET, req_id: 2bd6137d-23e5-4bb8-b463-fb38918c85df, remote_addr: 127.0.0.1:51936, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Sep 04 05:13:35.619 INFO request completed, latency_us: 222, response_code: 200, uri: /state/cid/0, method: GET, req_id: 83d2af01-3627-45fa-8d49-a0cc38f0879a, remote_addr: 127.0.0.1:51936, local_addr: 127.0.0.1:9998 Sep 04 05:14:12.305 INFO accepted connection, remote_addr: 127.0.0.1:57804, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Sep 04 05:14:12.305 INFO request completed, latency_us: 177, response_code: 204, uri: /start/cid/0, method: POST, req_id: 551727c2-d614-4b2b-9b11-b5247144ca01, remote_addr: 127.0.0.1:57804, 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(1240) [8810][0] reports Running Main task has work to do, go find it got dsc Stop(1) stop 1 Sep 04 05:14:16.686 INFO request completed[8820] Got stop action so:false kr:false , latency_us: 220, response_code: 204, uri: /stop/cid/1, method: POST, req_id: 2d09c403-74f4-4181-a742-d665a82053cb, remote_addr: 127.0.0.1:57804, local_addr: 127.0.0.1:9998 Sep 04 05:14:16.687 INFO request completed, latency_us: 102, response_code: 200, uri: /state/cid/1, method: GET, req_id: d934ca24-4c46-4748-ae06-16194f171606, remote_addr: 127.0.0.1:57804, local_addr: 127.0.0.1:9998 [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] reports Exit Sep 04 05:14:20.689 INFO request completed, latency_us: 220, response_code: 200, uri: /state/cid/1, method: GET, req_id: b5326aa5-b2e7-4e3d-b994-9d9698f45556, remote_addr: 127.0.0.1:57804, local_addr: 127.0.0.1:9998 Sep 04 05:14:57.409 INFO accepted connection, remote_addr: 127.0.0.1:45647, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(1) Sep 04 05:14:57.409 INFO request completedstart 1 , latency_us: 167, response_code: 204, uri: /start/cid/1, method: POST, req_id: abeacd02-4104-404a-8a7b-80cf75af718b, remote_addr: 127.0.0.1:45647, 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(1241) [8820][1] reports Running Main task has work to do, go find it got dsc Stop(2) stop 2 Sep 04 05:15:01.791 [8830] Got stop action so:false kr:false INFO request completed, latency_us: 218, response_code: 204, uri: /stop/cid/2, method: POST, req_id: 5913f8d6-3ad5-47fa-b085-11a2dd7098cd, remote_addr: 127.0.0.1:45647, local_addr: 127.0.0.1:9998 Sep 04 05:15:01.792 INFO request completed, latency_us: 100, response_code: 200, uri: /state/cid/2, method: GET, req_id: 815967dc-4762-435d-8862-cf4026f35038, remote_addr: 127.0.0.1:45647, local_addr: 127.0.0.1:9998 [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit Sep 04 05:15:05.794 INFO request completed, latency_us: 216, response_code: 200, uri: /state/cid/2, method: GET, req_id: 871c81c8-b01e-4174-98b9-2257690dad95, remote_addr: 127.0.0.1:45647, local_addr: 127.0.0.1:9998 Sep 04 05:15:42.489 INFO accepted connection, remote_addr: 127.0.0.1:35683, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(2) Sep 04 05:15:42.489 INFOstart 2 request completed, latency_us: 172, response_code: 204, uri: /start/cid/2, method: POST, req_id: 3135613d-3fe4-4edc-b87b-b5fd9379beda, remote_addr: 127.0.0.1:35683, 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(1242) [8830][2] reports Running Main task has work to do, go find it got dsc Stop(3) stop 3 [8840] Got stop action so:false kr:false Sep 04 05:15:46.885 INFO request completed, latency_us: 216, response_code: 204, uri: /stop/cid/3, method: POST, req_id: 79d4498e-11ef-4119-a56f-b6e6d425284f, remote_addr: 127.0.0.1:35683, local_addr: 127.0.0.1:9998 Sep 04 05:15:46.886 INFO request completed, latency_us: 61, response_code: 200, uri: /state/cid/3, method: GET, req_id: 12e0751c-c741-4f83-9bd4-737f583bd177, remote_addr: 127.0.0.1:35683, local_addr: 127.0.0.1:9998 [8840] Exited with: ExitStatus(unix_wait_status(9)) [8840][3] reports Exit Sep 04 05:15:50.888 INFO request completed, latency_us: 205, response_code: 200, uri: /state/cid/3, method: GET, req_id: 70d9c007-8a41-465e-933c-9f650d0d6e0d, remote_addr: 127.0.0.1:35683, local_addr: 127.0.0.1:9998 Sep 04 05:16:27.643 INFO accepted connection, remote_addr: 127.0.0.1:38657, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(3) Sep 04 05:16:27.643 INFO request completedstart 3 , latency_us: 179, response_code: 204, uri: /start/cid/3, method: POST, req_id: 09e765e1-0143-494d-8569-235067cc9e8f, remote_addr: 127.0.0.1:38657, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(4) stop 4 Sep 04 05:16:32.171[8850] Got stop action so:false kr:false INFO request completed, latency_us: 233, response_code: 204, uri: /stop/cid/4, method: POST, req_id: 12377a02-10c7-4e84-8826-bddb29e211c8, remote_addr: 127.0.0.1:38657, local_addr: 127.0.0.1:9998 Sep 04 05:16:32.172 INFO request completed, latency_us: 78, response_code: 200, uri: /state/cid/4, method: GET, req_id: aae9698b-f3f7-4efd-8180-6a42934636da, remote_addr: 127.0.0.1:38657, local_addr: 127.0.0.1:9998 [8850] Exited with: ExitStatus(unix_wait_status(9)) [8850][4] reports Exit [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(1243) [8840][3] reports Running Sep 04 05:16:36.174 INFO request completed, latency_us: 225, response_code: 200, uri: /state/cid/4, method: GET, req_id: 51dc5b58-4776-475f-92fc-24b796372927, remote_addr: 127.0.0.1:38657, local_addr: 127.0.0.1:9998 Sep 04 05:17:12.884 INFO accepted connection, remote_addr: 127.0.0.1:63099, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(4) start 4 Sep 04 05:17:12.884 INFO request completed, latency_us: 172, response_code: 204, uri: /start/cid/4, method: POST, req_id: f70ff2a1-3d84-4f93-bad0-49c26597db7f, remote_addr: 127.0.0.1:63099, local_addr: 127.0.0.1:9998 Sep 04 05:17:16.931 INFO accepted connection, remote_addr: 127.0.0.1:44617, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Shutdown Shutdown Shut it down Sep 04 05:17:16.932 INFO request completed, latency_us: 143, [8810] Disable keep_running response_code[8810] Got stop action so:false kr:false :[8830] Disable keep_running [8830] Got stop action so:false kr:false 204[8860] Disable keep_running , [8870] Disable keep_running uri: /shutdown[8860] Got stop action so:false kr:false , method:[8870] Got stop action so:false kr:false POST, req_id: c3ef3cbb-911d-4890-917f-b3825156e87c, remote_addr: 127.0.0.1:44617, local_addr: 127.0.0.1:9998 [8820] Disable keep_running [8820] Got stop action so:false kr:false [8840] Disable keep_running [8840] Got stop action so:false kr:false [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