/var/tmp/bins/dsc create --cleanup --region-count 3 --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.3070652 Downstairs region 1 created at /var/tmp/test_up-build/8820 in 0.028875545 Downstairs region 2 created at /var/tmp/test_up-build/8830 in 0.0274741 Created 3 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 3 Using existing output directory "/tmp/test_up-build/dsc" Update our region info with: Some(RegionExtentInfo { block_size: 4096, blocks_per_extent: 10, extent_count: 5 }) start ds: 8810 start ds: 8820 start ds: 8830 start access at:127.0.0.1:9998 Starting downstairs at port 8810 Make output file at "/tmp/test_up-build/dsc/downstairs-8810.txt" Starting downstairs at port 8820 Make output file at "/tmp/test_up-build/dsc/downstairs-8820.txt" [8810][0] initial start wait reports Starting [8820][1] initial start wait reports Starting Starting downstairs at port 8830 Make output file at "/tmp/test_up-build/dsc/downstairs-8830.txt" [8830][2] initial start wait reports Starting Downstairs /var/tmp/test_up-build/8810 port 8810 PID:Some(1192) [8810][0] initial start wait reports Running Downstairs /var/tmp/test_up-build/8830 port 8830 PID:Some(1193) [8830][2] initial start wait reports Running Downstairs /var/tmp/test_up-build/8820 port 8820 PID:Some(1194) [8820][1] initial start wait reports Running All downstairs are running Nov 15 06:43:04.760 INFO listening, local_addr: 127.0.0.1:9998 Control access at:127.0.0.1:9998 Nov 15 06:43:09.811 INFO accepted connection, remote_addr: 127.0.0.1:32773, local_addr: 127.0.0.1:9998 Nov 15 06:43:09.815 INFO request completed, latency_us: 854, response_code: 200, uri: /state/cid/0, method: GET, req_id: f782aa7b-e6f6-4fbe-bfc3-b3fd73c0a512, remote_addr: 127.0.0.1:32773, local_addr: 127.0.0.1:9998 Nov 15 06:43:09.884 INFO accepted connection, remote_addr: 127.0.0.1:34801, local_addr: 127.0.0.1:9998 Nov 15 06:43:09.885 INFO request completed, latency_us: 154, response_code: 200, uri: /state/cid/1, method: GET, req_id: 5b268b1e-6788-4185-91b5-54dc274315da, remote_addr: 127.0.0.1:34801, local_addr: 127.0.0.1:9998 Nov 15 06:43:09.952 INFO accepted connection, remote_addr: 127.0.0.1:47378, local_addr: 127.0.0.1:9998 Nov 15 06:43:09.953 INFO request completed, latency_us: 199, response_code: 200, uri: /state/cid/2, method: GET, req_id: 8627d882-8e4e-42e6-afa5-2a5432681ce3, remote_addr: 127.0.0.1:47378, local_addr: 127.0.0.1:9998 Nov 15 06:43:10.027 INFO accepted connection, remote_addr: 127.0.0.1:49654, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc DisableRestartAll disable restart on all: 3 Nov 15 06:43:10.028 INFO request completed, latency_us: 176, response_code: 204, uri: /disablerestart/all, method: POST, req_id: efa57762-a433-415c-a3e3-0386c38ca780, remote_addr[8810] Disable keep_running : 127.0.0.1:49654, local_addr: 127.0.0[8830] Disable keep_running .1:9998 [8820] Disable keep_running Nov 15 06:43:10.193 INFO accepted connection, remote_addr: 127.0.0.1:36725, local_addr: 127.0.0.1:9998 Nov 15 06:43:10.195 INFO request completed, latency_us: 375, response_code: 200, uri: /regioninfo, method: GET, req_id: 4dec5b46-9f12-4e2a-aa11-1e7459a0a113, remote_addr: 127.0.0.1:36725, local_addr: 127.0.0.1:9998 Nov 15 06:43:10.199 INFO request completed, latency_us: 190, response_code: 200, uri: /regioncount, method: GET, req_id: 2ea94002-689c-4691-909d-c5b7b169abb6, remote_addr: 127.0.0.1:36725, local_addr: 127.0.0.1:9998 Nov 15 06:43:10.200 INFO request completed, latency_us: 142, response_code: 200, uri: /port/cid/0, method: GET, req_id: 2949092a-f3a9-4c6f-93fc-21f024c34172, remote_addr: 127.0.0.1:36725, local_addr: 127.0.0.1:9998 Nov 15 06:43:10.201 INFO request completed, latency_us: 123, response_code: 200, uri: /port/cid/1, method: GET, req_id: 43d4dbca-946d-4dc7-899e-6f740b2fb61f, remote_addr: 127.0.0.1:36725, local_addr: 127.0.0.1:9998 Nov 15 06:43:10.202 INFO request completed, latency_us: 122, response_code: 200, uri: /port/cid/2, method: GET, req_id: d946351f-1eba-4930-9ca2-82571e01434f, remote_addr: 127.0.0.1:36725, local_addr: 127.0.0.1:9998 Nov 15 06:43:19.374 INFO accepted connection, remote_addr: 127.0.0.1:37603, local_addr: 127.0.0.1:9998 Nov 15 06:43:19.376 INFO request completed, latency_us: 236, response_code: 200, uri: /regioninfo, method: GET, req_id: 50a54635-c004-4cad-b452-c75571698f71, remote_addr: 127.0.0.1:37603, local_addr: 127.0.0.1:9998 Nov 15 06:43:19.377 INFO request completed, latency_us: 127, response_code: 200, uri: /regioncount, method: GET, req_id: 6afe89d3-7ab2-41b9-9bec-798ac854d98e, remote_addr: 127.0.0.1:37603, local_addr: 127.0.0.1:9998 Nov 15 06:43:19.378 INFO request completed, latency_us: 139, response_code: 200, uri: /port/cid/0, method: GET, req_id: 4ccbc5de-0548-484a-a624-872f1e6ef745, remote_addr: 127.0.0.1:37603, local_addr: 127.0.0.1:9998 Nov 15 06:43:19.379 INFO request completed, latency_us: 142, response_code: 200, uri: /port/cid/1, method: GET, req_id: 3835f959-d5ca-4a4b-bde7-52066608c95f, remote_addr: 127.0.0.1:37603, local_addr: 127.0.0.1:9998 Nov 15 06:43:19.380 INFO request completed, latency_us: 119, response_code: 200, uri: /port/cid/2, method: GET, req_id: a9b7d869-b802-4551-b27b-7684ae37ef2e, remote_addr: 127.0.0.1:37603, local_addr: 127.0.0.1:9998 Nov 15 06:43:28.616 INFO accepted connection, remote_addr: 127.0.0.1:46609, local_addr: 127.0.0.1:9998 Nov 15 06:43:28.618 INFO request completed, latency_us: 209, response_code: 200, uri: /regioninfo, method: GET, req_id: 599d9efd-57f5-49c6-8e92-8b22d99ec273, remote_addr: 127.0.0.1:46609, local_addr: 127.0.0.1:9998 Nov 15 06:43:28.619 INFO request completed, latency_us: 120, response_code: 200, uri: /regioncount, method: GET, req_id: 5e6aaf13-8094-47bb-9ed8-49ee0f8fdd6d, remote_addr: 127.0.0.1:46609, local_addr: 127.0.0.1:9998 Nov 15 06:43:28.620 INFO request completed, latency_us: 144, response_code: 200, uri: /port/cid/0, method: GET, req_id: 18c288e8-7fe9-4478-88f4-74bfc9a21b4d, remote_addr: 127.0.0.1:46609, local_addr: 127.0.0.1:9998 Nov 15 06:43:28.621 INFO request completed, latency_us: 125, response_code: 200, uri: /port/cid/1, method: GET, req_id: 7222df24-b1ba-4d2c-b605-0a1682e3decb, remote_addr: 127.0.0.1:46609, local_addr: 127.0.0.1:9998 Nov 15 06:43:28.622 INFO request completed, latency_us: 146, response_code: 200, uri: /port/cid/2, method: GET, req_id: 3cdd9695-e3c0-4ffe-b72c-2d04c545b8d9, remote_addr: 127.0.0.1:46609, local_addr: 127.0.0.1:9998 Nov 15 06:43:46.335 INFO accepted connection, remote_addr: 127.0.0.1:54181, local_addr: 127.0.0.1:9998 Nov 15 06:43:46.336 INFO request completed, latency_us: 209, response_code: 200, uri: /regioninfo, method: GET, req_id: d51c5246-34d3-4ff9-9c47-e3f9b0d0218a, remote_addr: 127.0.0.1:54181, local_addr: 127.0.0.1:9998 Nov 15 06:43:46.337 INFO request completed, latency_us: 135, response_code: 200, uri: /regioncount, method: GET, req_id: 01716f4b-cabb-42c2-a0bc-7f776d382fef, remote_addr: 127.0.0.1:54181, local_addr: 127.0.0.1:9998 Nov 15 06:43:46.338 INFO request completed, latency_us: 145, response_code: 200, uri: /port/cid/0, method: GET, req_id: 4d51191f-21e4-453c-b8c1-5d7f5ef671c9, remote_addr: 127.0.0.1:54181, local_addr: 127.0.0.1:9998 Nov 15 06:43:46.339 INFO request completed, latency_us: 126, response_code: 200, uri: /port/cid/1, method: GET, req_id: 13caba33-dee0-470a-ab24-ea004fe4524c, remote_addr: 127.0.0.1:54181, local_addr: 127.0.0.1:9998 Nov 15 06:43:46.340 INFO request completed, latency_us: 137, response_code: 200, uri: /port/cid/2, method: GET, req_id: ad71d19c-4cb2-4b19-87b0-b824decae51d, remote_addr: 127.0.0.1:54181, local_addr: 127.0.0.1:9998 Nov 15 06:44:02.379 INFO accepted connection, remote_addr: 127.0.0.1:36418, local_addr: 127.0.0.1:9998 Nov 15 06:44:02.380 INFO request completed, latency_us: 207, response_code: 200, uri: /regioninfo, method: GET, req_id: 241525c9-f66a-4f7b-a780-9957b770c374, remote_addr: 127.0.0.1:36418, local_addr: 127.0.0.1:9998 Nov 15 06:44:02.382 INFO request completed, latency_us: 119, response_code: 200, uri: /regioncount, method: GET, req_id: 995b5aaf-25ae-4a1d-8b02-fb093d57cb10, remote_addr: 127.0.0.1:36418, local_addr: 127.0.0.1:9998 Nov 15 06:44:02.383 INFO request completed, latency_us: 154, response_code: 200, uri: /port/cid/0, method: GET, req_id: d7010a7e-43e6-4007-b907-ddedf59b36f6, remote_addr: 127.0.0.1:36418, local_addr: 127.0.0.1:9998 Nov 15 06:44:02.384 INFO request completed, latency_us: 148, response_code: 200, uri: /port/cid/1, method: GET, req_id: 801e4c82-66e7-48d7-af6e-7d3a36554ab3, remote_addr: 127.0.0.1:36418, local_addr: 127.0.0.1:9998 Nov 15 06:44:02.384 INFO request completed, latency_us: 122, response_code: 200, uri: /port/cid/2, method: GET, req_id: 2c4d650e-ed06-4f17-8c3a-cf9685fae81c, remote_addr: 127.0.0.1:36418, local_addr: 127.0.0.1:9998 Nov 15 06:45:05.618 INFO accepted connection, remote_addr: 127.0.0.1:46987, local_addr: 127.0.0.1:9998 Nov 15 06:45:05.619 INFO request completed, latency_us: 199, response_code: 200, uri: /regioninfo, method: GET, req_id: 8f038ba4-4485-4d29-9cc9-77fbad0f5cb3, remote_addr: 127.0.0.1:46987, local_addr: 127.0.0.1:9998 Nov 15 06:45:05.621 INFO request completed, latency_us: 118, response_code: 200, uri: /regioncount, method: GET, req_id: 8124a891-28b0-4516-be92-5b35d207aec9, remote_addr: 127.0.0.1:46987, local_addr: 127.0.0.1:9998 Nov 15 06:45:05.622 INFO request completed, latency_us: 152, response_code: 200, uri: /port/cid/0, method: GET, req_id: 4c86dfb5-205d-4f78-b760-544d9566f66c, remote_addr: 127.0.0.1:46987, local_addr: 127.0.0.1:9998 Nov 15 06:45:05.622 INFO request completed, latency_us: 121, response_code: 200, uri: /port/cid/1, method: GET, req_id: c5ab04fd-483f-4784-b31f-95e66c645b11, remote_addr: 127.0.0.1:46987, local_addr: 127.0.0.1:9998 Nov 15 06:45:05.623 INFO request completed, latency_us: 119, response_code: 200, uri: /port/cid/2, method: GET, req_id: 8a786a79-9b2b-4e87-b136-2a637305231c, remote_addr: 127.0.0.1:46987, local_addr: 127.0.0.1:9998 Nov 15 06:45:09.782 INFO accepted connection, remote_addr: 127.0.0.1:56205, local_addr: 127.0.0.1:9998 Nov 15 06:45:09.783 INFO request completed, latency_us: 206, response_code: 200, uri: /regioninfo, method: GET, req_id: d007cf93-adf7-4365-8be6-365c0ca5702e, remote_addr: 127.0.0.1:56205, local_addr: 127.0.0.1:9998 Nov 15 06:45:09.784 INFO request completed, latency_us: 119, response_code: 200, uri: /regioncount, method: GET, req_id: bf3f4879-7ff4-4543-9e1c-25847325259a, remote_addr: 127.0.0.1:56205, local_addr: 127.0.0.1:9998 Nov 15 06:45:09.785 INFO request completed, latency_us: 139, response_code: 200, uri: /port/cid/0, method: GET, req_id: c210e4a4-4b25-43a2-9bf4-e2bc9e1f8eb3, remote_addr: 127.0.0.1:56205, local_addr: 127.0.0.1:9998 Nov 15 06:45:09.786 INFO request completed, latency_us: 137, response_code: 200, uri: /port/cid/1, method: GET, req_id: 49c25fa2-a454-4cf9-9ad5-ae75f00fb109, remote_addr: 127.0.0.1:56205, local_addr: 127.0.0.1:9998 Nov 15 06:45:09.786 INFO request completed, latency_us: 116, response_code: 200, uri: /port/cid/2, method: GET, req_id: 9a912617-1385-4b57-a7ca-45895fcc92db, remote_addr: 127.0.0.1:56205, local_addr: 127.0.0.1:9998 Nov 15 06:45:13.920 INFO accepted connection, remote_addr: 127.0.0.1:57173, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc DisableRestartAll disable restart on all: 3 Nov 15 06:45:13.921 INFO request completed, latency_us: 236, response_code: 204, uri: /disablerestart/all, method: POST, req_id: 0a1dc36a-6a43-49d1-9c7e-c71d10f8cce2, remote_addr: 127.0.0.1:57173, local_addr: 127.0.0.1:9998 [8820] Disable keep_running [8810] Disable keep_running [8830] Disable keep_running Nov 15 06:45:13.989 INFO accepted connection, remote_addr: 127.0.0.1:58178, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(2) stop 2 Nov 15 06:45:13.990 INFO request completed, latency_us: 281, response_code: 204, uri: /stop/cid/2, method: POST, req_id: 65b10cb4-2814-4409-a5e3-571d19034009, remote_addr: 127.0.0.1:58178, local_addr: 127.0.0.1:9998 [8830] Got stop action so:false kr:false [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit Nov 15 06:45:14.057 INFO accepted connection, remote_addr: 127.0.0.1:45779, local_addr: 127.0.0.1:9998 Nov 15 06:45:14.058 INFO request completed, latency_us: 213, response_code: 200, uri: /state/cid/2, method: GET, req_id: 1063e624-e493-411c-bac4-93082104be1b, remote_addr: 127.0.0.1:45779, local_addr: 127.0.0.1:9998 Nov 15 06:45:14.135 INFO accepted connection, remote_addr: 127.0.0.1:59432, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(2) start 2 Nov 15 06:45:14.136 INFO request completed, latency_us: 208, response_code: 204, uri: /start/cid/2, method: POST, req_id: 0a13c632-586c-4e34-901f-3b3f6be6f9c6, remote_addr: 127.0.0.1:59432, local_addr: 127.0.0.1:9998 Nov 15 06:45:14.245 INFO accepted connection, remote_addr: 127.0.0.1:53826, local_addr: 127.0.0.1:9998 Nov 15 06:45:14.246 INFO request completed, latency_us: 215, response_code: 200, uri: /regioninfo, method: GET, req_id: a5274548-42c2-4fab-8a81-dbfedda27693, remote_addr: 127.0.0.1:53826, local_addr: 127.0.0.1:9998 Nov 15 06:45:14.248 INFO request completed, latency_us: 137, response_code: 200, uri: /regioncount, method: GET, req_id: 90d9533b-2da9-412c-ae85-615fa946a036, remote_addr: 127.0.0.1:53826, local_addr: 127.0.0.1:9998 Nov 15 06:45:14.249 INFO request completed, latency_us: 221, response_code: 200, uri: /port/cid/0, method: GET, req_id: d8eaa926-09b8-4892-97bd-a2e2763cbbf1, remote_addr: 127.0.0.1:53826, local_addr: 127.0.0.1:9998 Nov 15 06:45:14.250 INFO request completed, latency_us: 120, response_code: 200, uri: /port/cid/1, method: GET, req_id: 07caa7e1-7fa0-4bfc-895c-3ba55f4ef9a5, remote_addr: 127.0.0.1:53826, local_addr: 127.0.0.1:9998 Nov 15 06:45:14.250 INFO request completed, latency_us: 120, response_code: 200, uri: /port/cid/2, method: GET, req_id: f37b92d8-e891-4c08-98ea-6f6e2fe2274e, remote_addr: 127.0.0.1:53826, 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 Nov 15 06:45:28.521 INFO accepted connection, remote_addr: 127.0.0.1:58375, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Shutdown Shutdown Nov 15 06:45:28.522 INFO request completed, latency_us: 240, response_code: 204, uri: /shutdown, method: POST, req_id: a9f74443-f9d2-4c8b-95f5-6fbc91631110, remote_addr: 127.0.0.1:58375, local_addr: 127.0.0.1:9998 Shut it down [8810] Disable keep_running [8810] Got stop action so:false kr:false [8820] Disable keep_running [8830] Disable keep_running [8830] Got stop action so:false kr:false [8820] Got stop action so:false kr:false [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] reports Exit /var/tmp/bins/dsc create --cleanup --region-count 4 --extent-count 300 --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.037350003 Downstairs region 1 created at /var/tmp/test_up-build/8820 in 0.03700552 Downstairs region 2 created at /var/tmp/test_up-build/8830 in 0.03573608 Downstairs region 3 created at /var/tmp/test_up-build/8840 in 0.03647757 Created 4 regions /var/tmp/bins/dsc start --region-count 4 --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: 100 }) start ds: 8810 start ds: 8820 start ds: 8830 start ds: 8840 start access at:127.0.0.1:9998 Starting downstairs at port 8810 Make output file at "/tmp/test_up-build/dsc/downstairs-8810.txt" [8810][0] initial start wait reports Starting Starting downstairs at port 8820 Make output file at "/tmp/test_up-build/dsc/downstairs-8820.txt" Starting downstairs at port 8830 Starting downstairs at port 8840 [8820][1] initial start wait reports Starting Make output file at "/tmp/test_up-build/dsc/downstairs-8830.txt" [8830][2] initial start wait reports Starting Make output file at "/tmp/test_up-build/dsc/downstairs-8840.txt" [8840][3] initial start wait reports Starting Downstairs /var/tmp/test_up-build/8810 port 8810 PID:Some(1256) [8810][0] initial start wait reports Running Downstairs /var/tmp/test_up-build/8820 port 8820 PID:Some(1257) [8820][1] initial start wait reports Running Downstairs /var/tmp/test_up-build/8830 port 8830 PID:Some(1258) [8830][2] initial start wait reports Running Downstairs /var/tmp/test_up-build/8840 port 8840 PID:Some(1259) [8840][3] initial start wait reports Running All downstairs are running Nov 15 06:45:33.735 INFO listening, local_addr: 127.0.0.1:9998 Control access at:127.0.0.1:9998 Nov 15 06:45:38.783 INFO accepted connection, remote_addr: 127.0.0.1:43864, local_addr: 127.0.0.1:9998 Nov 15 06:45:38.785 INFO request completed, latency_us: 460, response_code: 200, uri: /state/cid/0, method: GET, req_id: e98f6a72-c773-46aa-b685-1f6841ae0725, remote_addr: 127.0.0.1:43864, local_addr: 127.0.0.1:9998 Nov 15 06:45:38.853 INFO accepted connection, remote_addr: 127.0.0.1:63151, local_addr: 127.0.0.1:9998 Nov 15 06:45:38.854 INFO request completed, latency_us: 132, response_code: 200, uri: /state/cid/1, method: GET, req_id: 196ab8b0-9e8b-45b9-9b76-d8a9c8296cab, remote_addr: 127.0.0.1:63151, local_addr: 127.0.0.1:9998 Nov 15 06:45:38.921 INFO accepted connection, remote_addr: 127.0.0.1:64317, local_addr: 127.0.0.1:9998 Nov 15 06:45:38.921 INFO request completed, latency_us: 129, response_code: 200, uri: /state/cid/2, method: GET, req_id: c334bc3c-5727-40dd-98d5-eef3aef8c7fc, remote_addr: 127.0.0.1:64317, local_addr: 127.0.0.1:9998 Nov 15 06:45:38.995 INFO accepted connection, remote_addr: 127.0.0.1:63078, local_addr: 127.0.0.1:9998 Nov 15 06:45:38.996 INFO request completed, latency_us: 145, response_code: 200, uri: /state/cid/3, method: GET, req_id: e0789cfe-89a4-4c65-82f0-8432ae21e07d, remote_addr: 127.0.0.1:63078, local_addr: 127.0.0.1:9998 Nov 15 06:45:39.069 INFO accepted connection, remote_addr: 127.0.0.1:64550, local_addr: 127.0.0.1:9998 Nov 15 06:45:39.070 INFO request completed, latency_us: 153, response_code: 200, uri: /port/cid/3, method: GET, req_id: d228abc5-9045-49dd-a4eb-4b59e82f93ba, remote_addr: 127.0.0.1:64550, local_addr: 127.0.0.1:9998 Nov 15 06:45:39.147 INFO accepted connection, remote_addr: 127.0.0.1:34679, local_addr: 127.0.0.1:9998 Nov 15 06:45:39.149 INFO request completed, latency_us: 214, response_code: 200, uri: /regioninfo, method: GET, req_id: 6dbf064a-2e23-4a6f-8938-92a1768fbbd5, remote_addr: 127.0.0.1:34679, local_addr: 127.0.0.1:9998 Nov 15 06:45:39.150 INFO request completed, latency_us: 131, response_code: 200, uri: /regioncount, method: GET, req_id: 80225851-737e-45dd-803e-f322e9c77a8e, remote_addr: 127.0.0.1:34679, local_addr: 127.0.0.1:9998 Nov 15 06:45:39.151 INFO request completed, latency_us: 123, response_code: 200, uri: /port/cid/0, method: GET, req_id: af2934fa-3b5e-406f-98ce-33ce5d8f5890, remote_addr: 127.0.0.1:34679, local_addr: 127.0.0.1:9998 Nov 15 06:45:39.152 INFO request completed, latency_us: 115, response_code: 200, uri: /port/cid/1, method: GET, req_id: 126155bc-9d72-4fe4-83b5-1e1c1336c5d4, remote_addr: 127.0.0.1:34679, local_addr: 127.0.0.1:9998 Nov 15 06:45:39.152 INFO request completed, latency_us: 120, response_code: 200, uri: /port/cid/2, method: GET, req_id: ac03959c-2ddc-4de4-a008-9cff76b3c949, remote_addr: 127.0.0.1:34679, local_addr: 127.0.0.1:9998 Nov 15 06:45:39.236 INFO accepted connection, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 Nov 15 06:45:39.237 INFO request completed, latency_us: 172, response_code: 200, uri: /regioncount, method: GET, req_id: 4ab1b567-44e4-4759-ac69-7ceae9c5fb48, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Nov 15 06:45:39.261 INFO request completed, latency_us: 198, response_code: 204, uri: [8810] Got stop action so:false kr:false /stop/cid/0, method: POST, req_id: ebbe5b41-cead-4384-b7f3-87add7edef16, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 Nov 15 06:45:39.262 INFO request completed, latency_us: 289, response_code: 200, uri: /state/cid/0, method: GET, req_id: ee3f4143-1900-4247-bbb0-bad421b10e6a, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Nov 15 06:45:43.264 INFO request completed, latency_us: 194, response_code: 200, uri: /state/cid/0, method: GET, req_id: 44bcd882-8c08-4096-ac3d-62c2c3c9bf70, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Nov 15 06:45:43.288 INFO request completed, latency_us: 263, response_code: 204, uri: /start/cid/0, method: POST, req_id: 0b2b9ff7-4c4c-46be-9632-3f3f059a9ff0, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 Nov 15 06:45:43.289 INFO request completed, latency_us: 233, response_code: 200, uri: /state/cid/0, method: GET, req_id: 84f7df0c-9f01-4a37-805b-5052ea9f377e, remote_addr: 127.0.0.1:48058, 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(1267) [8810][0] reports Running Nov 15 06:45:47.292 INFO request completed, latency_us: 499, response_code: 200, uri: /state/cid/0, method: GET, req_id: f8e28f9f-a1ca-4d28-9196-e228da6f0bb8, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(1) stop 1 Nov 15 06:46:17.549 INFO request completed, [8820] Got stop action so:false kr:false latency_us: 275, response_code: 204, uri: /stop/cid/1, method: POST, req_id: 354756e3-c770-45c4-bce6-4fc09bedd0a1, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 Nov 15 06:46:17.552 INFO request completed, latency_us: 213, response_code: 200, uri: /state/cid/1, method: GET, req_id: 2bfe39e5-0f9b-44db-8af7-e41294751de6, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] reports Exit Nov 15 06:46:21.554 INFO request completed, latency_us: 198, response_code: 200, uri: /state/cid/1, method: GET, req_id: 24d9ac03-164b-4957-8761-b1bfc8cd5f97, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(1) start 1 Nov 15 06:46:21.580 INFO request completed, latency_us: 288, response_code: 204, uri: /start/cid/1, method: POST, req_id: 87109605-c3d3-4367-93c0-5e6d1be2b561, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 Nov 15 06:46:21.581 INFO request completed, latency_us: 144, response_code: 200, uri: /state/cid/1, method: GET, req_id: 751ca5d2-88b0-4445-8722-e012e3979c5b, remote_addr: 127.0.0.1:48058, 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(1268) [8820][1] reports Running Nov 15 06:46:25.584 INFO request completed, latency_us: 349, response_code: 200, uri: /state/cid/1, method: GET, req_id: 5f100c0d-d59a-4224-a31d-161da63763ba, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(2) stop 2 Nov 15 06:46:55.831 INFO request completed[8830] Got stop action so:false kr:false , latency_us: 313, response_code: 204, uri: /stop/cid/2, method: POST, req_id: fde117cd-0838-41c0-b57d-ebb8abe3d205, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 Nov 15 06:46:55.832 INFO request completed, latency_us: 222, response_code: 200, uri: /state/cid/2, method: GET, req_id: cd70e8a0-3a66-4ef2-be51-e355204789ed, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit Nov 15 06:46:59.836 INFO request completed, latency_us: 255, response_code: 200, uri: /state/cid/2, method: GET, req_id: 61fa060a-55b7-40ec-9395-8c6c004e4565, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(2) Nov 15 06:46:59.863 INFO request completed, latency_us: 298, response_code: 204, uri: /start/cid/2, method: POST, req_idstart 2 : 15c21cdd-2e26-4e27-8e1c-babcd7717211, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 Nov 15 06:46:59.864 INFO request completed, latency_us: 332, response_code: 200, uri: /state/cid/2, method: GET, req_id: 6022c37f-9255-4582-9e70-04ae3e6f945a, remote_addr: 127.0.0.1:48058, 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(1269) [8830][2] reports Running Nov 15 06:47:03.867 INFO request completed, latency_us: 451, response_code: 200, uri: /state/cid/2, method: GET, req_id: aa84347c-34c4-4ba7-a4c5-f2e987b8fe4b, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(3) stop 3 Nov 15 06:47:34.119 INFO request completed, latency_us: 286, response_code: 204, uri: /stop/cid/3, method: POST, req_id: b2af6cd7-4aba-4683-87e9-0a2be1ab99d1, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 [8840] Got stop action so:false kr:false Nov 15 06:47:34.121 INFO request completed, latency_us: 283, response_code: 200, uri: /state/cid/3, method: GET, req_id: 0d7cfe33-0fc7-4e28-b088-068fe5be7961, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 [8840] Exited with: ExitStatus(unix_wait_status(9)) [8840][3] reports Exit Nov 15 06:47:38.124 INFO request completed, latency_us: 284, response_code: 200, uri: /state/cid/3, method: GET, req_id: 6d5784a2-970f-44e6-89e2-581e1559f75b, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(3) start 3 Nov 15 06:47:38.150 INFO request completed, latency_us: 309, response_code: 204, uri: /start/cid/3, method: POST, req_id: 25aa4297-3677-4eef-9970-fae4697d7319, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 Nov 15 06:47:38.151 INFO request completed, latency_us: 140, response_code: 200, uri: /state/cid/3, method: GET, req_id: 40cb3ec7-91dd-4799-8c12-5482ee95f95b, remote_addr: 127.0.0.1:48058, 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(1270) [8840][3] reports Running Nov 15 06:47:42.154 INFO request completed, latency_us: 523, response_code: 200, uri: /state/cid/3, method: GET, req_id: 8083aa72-804e-46bd-a6fc-e3b9f4a0a3bb, remote_addr: 127.0.0.1:48058, local_addr: 127.0.0.1:9998 Nov 15 06:48:12.473 INFO accepted connection, remote_addr: 127.0.0.1:59288, local_addr: 127.0.0.1:9998 Nov 15 06:48:12.475 INFO request completed, latency_us: 250, response_code: 200, uri: /regioninfo, method: GET, req_id: 2241b4b7-73f4-4592-984f-3199469f6ef8, remote_addr: 127.0.0.1:59288, local_addr: 127.0.0.1:9998 Nov 15 06:48:12.476 INFO request completed, latency_us: 117, response_code: 200, uri: /regioncount, method: GET, req_id: 8ad89878-c4dd-4089-af5d-b117c426cdb1, remote_addr: 127.0.0.1:59288, local_addr: 127.0.0.1:9998 Nov 15 06:48:12.477 INFO request completed, latency_us: 142, response_code: 200, uri: /port/cid/0, method: GET, req_id: 5b8b7b2a-6e24-40ff-94c4-4ceea4118e95, remote_addr: 127.0.0.1:59288, local_addr: 127.0.0.1:9998 Nov 15 06:48:12.478 INFO request completed, latency_us: 123, response_code: 200, uri: /port/cid/1, method: GET, req_id: cd7c90cd-1175-472e-8755-8cefd573e2c7, remote_addr: 127.0.0.1:59288, local_addr: 127.0.0.1:9998 Nov 15 06:48:12.479 INFO request completed, latency_us: 121, response_code: 200, uri: /port/cid/2, method: GET, req_id: af77d8e0-f776-4ef0-8ac4-541ea3f927e0, remote_addr: 127.0.0.1:59288, local_addr: 127.0.0.1:9998 Nov 15 06:48:12.561 INFO accepted connection, remote_addr: 127.0.0.1:63010, local_addr: 127.0.0.1:9998 Nov 15 06:48:12.562 INFO request completed, latency_us: 239, response_code: 200, uri: /regioncount, method: GET, req_id: 9e3b159a-323f-474e-bb1c-44182c30eac6, remote_addr: 127.0.0.1:63010, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Nov 15 06:48:12.949 INFO request completed, latency_us: 284, response_code: 204, uri: /stop/cid/0, method: POST, req_id: e9e63da2-399c-40ab-82a6-ffa4468ee46b, remote_addr: 127.0.0.1:63010, local_addr: 127.0.0.1:9998 [8810] Got stop action so:false kr:false Nov 15 06:48:12.951 INFO request completed, latency_us: 289, response_code: 200, uri: /state/cid/0, method: GET, req_id: def368c2-996b-49e4-94a4-73c828d178c6, remote_addr: 127.0.0.1:63010, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Nov 15 06:48:16.954 INFO request completed, latency_us: 335, response_code: 200, uri: /state/cid/0, method: GET, req_id: 4a1ffeee-dd62-4aa1-8eb4-219a0a48f0cf, remote_addr: 127.0.0.1:63010, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Nov 15 06:48:41.858 INFO request completed, latency_us: 373, response_code: 204, uri: /start/cid/0, method: POST, req_id: bf6df63e-ff9f-494c-b7b4-d75a112619b8, remote_addr: 127.0.0.1:63010, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(1) stop 1 [8820] Got stop action so:false kr:false Nov 15 06:48:45.885 INFO request completed, latency_us: 275, response_code: 204, uri: /stop/cid/1, method: POST, req_id: 7b172651-f1b7-4984-a22c-2dafbc9bfd54, remote_addr: 127.0.0.1:63010, local_addr: 127.0.0.1:9998 Nov 15 06:48:45.887 INFO request completed, latency_us: 145, response_code: 200, uri: /state/cid/1, method: GET, req_id: 5c8f4ee3-1f89-4e1b-8be3-f84218851987, remote_addr: 127.0.0.1:63010, local_addr: 127.0.0.1:9998 [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] reports Exit [8810] got start action [8810] I am going to restart Starting downstairs at port 8810 Make output file at "/tmp/test_up-build/dsc/downstairs-8810.txt" [8810][0] reports Starting Downstairs /var/tmp/test_up-build/8810 port 8810 PID:Some(1272) [8810][0] reports Running Nov 15 06:48:49.890 INFO request completed, latency_us: 351, response_code: 200, uri: /state/cid/1, method: GET, req_id: 7038fb5e-6cb4-47ba-bcf6-90082ef8f9a0, remote_addr: 127.0.0.1:63010, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(1) start 1 Nov 15 06:49:14.813 INFO request completed, latency_us: 379, response_code: 204, uri: /start/cid/1, method: POST, req_id: caeb7ff2-72df-4ea4-a7ef-b0c440c4c380, remote_addr: 127.0.0.1:63010, 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(1273) [8820][1] reports Running Main task has work to do, go find it got dsc Stop(2) stop 2 Nov 15 06:49:18.844 INFO request completed, latency_us: 496, response_code: 204, uri: /stop/cid/2, method: POST, req_id: 9b96b413-f482-4532-9f3e-c9dfe74dfa49, remote_addr: 127.0.0.1:63010, local_addr: 127.0.0.1:9998 [8830] Got stop action so:false kr:false Nov 15 06:49:18.845 INFO request completed, latency_us: 264, response_code: 200, uri: /state/cid/2, method: GET, req_id: 6eb7973f-df55-4cd1-b557-522d1aab18b8, remote_addr: 127.0.0.1:63010, local_addr: 127.0.0.1:9998 [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit Nov 15 06:49:22.848 INFO request completed, latency_us: 339, response_code: 200, uri: /state/cid/2, method: GET, req_id: 37a25e5e-641b-4b58-8154-a7e79d9d5fd9, remote_addr: 127.0.0.1:63010, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(2) start 2 Nov 15 06:49:47.756 INFO request completed, latency_us: 441, response_code: 204, uri: /start/cid/2, method: POST, req_id: d462302a-b448-4554-bf33-7c73d352dbf9, remote_addr: 127.0.0.1:63010, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(3) stop 3 [8840] Got stop action so:false kr:false Nov 15 06:49:51.785 INFO request completed, latency_us: 271, response_code: 204, uri: /stop/cid/3, method: POST, req_id: 2a431521-7134-4b9c-9675-4d3c2525f761, remote_addr: 127.0.0.1:63010, local_addr: 127.0.0.1:9998 Nov 15 06:49:51.787 INFO request completed, latency_us: 203, response_code: 200, uri: /state/cid/3, method: GET, req_id: b4acc6b8-516d-4f4e-a3f3-13f56d110efe, remote_addr: 127.0.0.1:63010, 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(1274) [8830][2] reports Running Nov 15 06:49:55.789 INFO request completed, latency_us: 537, response_code: 200, uri: /state/cid/3, method: GET, req_id: 79930f3c-8443-42f8-89c6-028b8ef3e418, remote_addr: 127.0.0.1:63010, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(3) start 3 Nov 15 06:50:20.709 INFO request completed, latency_us: 394, response_code: 204, uri: /start/cid/3, method: POST, req_id: ee92b339-5da9-4f6d-830f-375c663a5714, remote_addr: 127.0.0.1:63010, 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(1275) [8840][3] reports Running Main task has work to do, go find it got dsc Stop(0) stop 0 Nov 15 06:50:24.749 INFO request completed, latency_us: 402, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 5fd4dc70-f5db-4961-8d5c-844539a06549, remote_addr: 127.0.0.1:63010, local_addr: 127.0.0.1:9998 [8810] Got stop action so:false kr:false Nov 15 06:50:24.751 INFO request completed, latency_us: 206, response_code: 200, uri: /state/cid/0, method: GET, req_id: 68591792-4fe3-4ebf-b8ff-ff1c3740d844, remote_addr: 127.0.0.1:63010, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Nov 15 06:50:28.754 INFO request completed, latency_us: 398, response_code: 200, uri: /state/cid/0, method: GET, req_id: ce5e66cf-4146-45a9-9f6c-acf1cc28638f, remote_addr: 127.0.0.1:63010, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Nov 15 06:50:53.675 INFO request completed, latency_us: 422, response_code: 204, uri: /start/cid/0, method: POST, req_id: c50569fe-4031-471b-a4cd-28a962172ae1, remote_addr: 127.0.0.1:63010, 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(1276) [8810][0] reports Running Nov 15 06:50:57.764 INFO accepted connection, remote_addr: 127.0.0.1:42759, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Shutdown Shutdown Nov 15 06:50:57.765 INFO request completed, latency_us: 223, response_code: 204, uri: /shutdown, method: POST, req_id: 613fb5e4-2563-4212-95f4-fdac3129b4de, remote_addr: 127.0.0.1:42759, [8810] Disable keep_running local_addrShut it down : 127.0.0.1:9998 [8810] Got stop action so:false kr:false [8820] Disable keep_running [8820] Got stop action so:false kr:false [8830] Disable keep_running [8830] Got stop action so:false kr:false [8840] Disable keep_running [8840] Got stop action so:false kr:false [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit [8830] Exited with: ExitStatus(unix_wait_status(9)) [8820] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit [8820][1] reports Exit [8840] Exited with: ExitStatus(unix_wait_status(9)) [8840][3] reports Exit