/var/tmp/bins/dsc create --encrypted --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.2034327 Downstairs region 1 created at /var/tmp/test_up-build/8820 in 0.017487433 Downstairs region 2 created at /var/tmp/test_up-build/8830 in 0.017216794 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 8820 Starting downstairs at port 8810 Make output file at "/tmp/test_up-build/dsc/downstairs-8810.txt" Make output file at "/tmp/test_up-build/dsc/downstairs-8820.txt" Starting downstairs at port 8830 Make output file at "/tmp/test_up-build/dsc/downstairs-8830.txt" [8820][1] initial start wait reports Starting [8810][0] initial start wait reports Starting [8830][2] initial start wait reports Starting Downstairs /var/tmp/test_up-build/8810 port 8810 PID:Some(1189) [8810][0] initial start wait reports Running Downstairs /var/tmp/test_up-build/8830 port 8830 PID:Some(1190) [8830][2] initial start wait reports Running Downstairs /var/tmp/test_up-build/8820 port 8820 PID:Some(1191) [8820][1] initial start wait reports Running All downstairs are running Nov 26 22:04:30.916 INFO listening, local_addr: 127.0.0.1:9998 Control access at:127.0.0.1:9998 Nov 26 22:04:35.959 INFO accepted connection, remote_addr: 127.0.0.1:39102, local_addr: 127.0.0.1:9998 Nov 26 22:04:35.961 INFO request completed, latency_us: 502, response_code: 200, uri: /state/cid/0, method: GET, req_id: a2479da1-c470-463d-98d2-18366abad8c5, remote_addr: 127.0.0.1:39102, local_addr: 127.0.0.1:9998 Nov 26 22:04:36.015 INFO accepted connection, remote_addr: 127.0.0.1:50628, local_addr: 127.0.0.1:9998 Nov 26 22:04:36.016 INFO request completed, latency_us: 138, response_code: 200, uri: /state/cid/1, method: GET, req_id: 42782962-91ef-4653-9c61-fa5778a10b04, remote_addr: 127.0.0.1:50628, local_addr: 127.0.0.1:9998 Nov 26 22:04:36.066 INFO accepted connection, remote_addr: 127.0.0.1:43567, local_addr: 127.0.0.1:9998 Nov 26 22:04:36.067 INFO request completed, latency_us: 99, response_code: 200, uri: /state/cid/2, method: GET, req_id: 3ae8b43b-76ca-4a64-951e-9c840c616cac, remote_addr: 127.0.0.1:43567, local_addr: 127.0.0.1:9998 Nov 26 22:04:36.118 INFO accepted connection, remote_addr: 127.0.0.1:43693, 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 26 22:04:36.118 INFO request completed, latency_us: 143, response_code: 204, uri: /disablerestart/all, method:[8810] Disable keep_running POST, req_id: 156f1eef-697f-4fc6-8c1f-9432ea3d9601, remote_addr: 127.0[8830] Disable keep_running .[8820] Disable keep_running 0.1:43693, local_addr: 127.0.0.1:9998 Nov 26 22:04:36.201 INFO accepted connection, remote_addr: 127.0.0.1:54730, local_addr: 127.0.0.1:9998 Nov 26 22:04:36.202 INFO request completed, latency_us: 274, response_code: 200, uri: /regioninfo, method: GET, req_id: 68e5dd33-4b34-4eac-a2b8-b3f14bad1662, remote_addr: 127.0.0.1:54730, local_addr: 127.0.0.1:9998 Nov 26 22:04:36.203 INFO request completed, latency_us: 94, response_code: 200, uri: /regioncount, method: GET, req_id: d061858a-768b-417d-baf6-90a73b89e0ed, remote_addr: 127.0.0.1:54730, local_addr: 127.0.0.1:9998 Nov 26 22:04:36.204 INFO request completed, latency_us: 117, response_code: 200, uri: /port/cid/0, method: GET, req_id: 47821f59-8d25-4777-89dc-32d254e6270d, remote_addr: 127.0.0.1:54730, local_addr: 127.0.0.1:9998 Nov 26 22:04:36.204 INFO request completed, latency_us: 94, response_code: 200, uri: /port/cid/1, method: GET, req_id: c9f25d86-fd5f-4240-bd1c-929e0e72a2a8, remote_addr: 127.0.0.1:54730, local_addr: 127.0.0.1:9998 Nov 26 22:04:36.205 INFO request completed, latency_us: 94, response_code: 200, uri: /port/cid/2, method: GET, req_id: 50f8d132-8076-43de-95dd-be005a1a43db, remote_addr: 127.0.0.1:54730, local_addr: 127.0.0.1:9998 Nov 26 22:04:45.337 INFO accepted connection, remote_addr: 127.0.0.1:64780, local_addr: 127.0.0.1:9998 Nov 26 22:04:45.338 INFO request completed, latency_us: 170, response_code: 200, uri: /regioninfo, method: GET, req_id: 81bdd230-d2fb-4187-a26e-e1d76f76e481, remote_addr: 127.0.0.1:64780, local_addr: 127.0.0.1:9998 Nov 26 22:04:45.339 INFO request completed, latency_us: 114, response_code: 200, uri: /regioncount, method: GET, req_id: 3c6eb725-87ed-4496-9d14-06506f1be9e9, remote_addr: 127.0.0.1:64780, local_addr: 127.0.0.1:9998 Nov 26 22:04:45.339 INFO request completed, latency_us: 106, response_code: 200, uri: /port/cid/0, method: GET, req_id: 0560b923-babf-4c12-a822-5b230d382f00, remote_addr: 127.0.0.1:64780, local_addr: 127.0.0.1:9998 Nov 26 22:04:45.340 INFO request completed, latency_us: 101, response_code: 200, uri: /port/cid/1, method: GET, req_id: 6ccdd753-eb0f-40d6-a78e-620d0803122c, remote_addr: 127.0.0.1:64780, local_addr: 127.0.0.1:9998 Nov 26 22:04:45.340 INFO request completed, latency_us: 93, response_code: 200, uri: /port/cid/2, method: GET, req_id: ddb896ad-0c8c-4bf5-93bc-4bcbb70535ca, remote_addr: 127.0.0.1:64780, local_addr: 127.0.0.1:9998 Nov 26 22:04:54.683 INFO accepted connection, remote_addr: 127.0.0.1:56068, local_addr: 127.0.0.1:9998 Nov 26 22:04:54.683 INFO request completed, latency_us: 157, response_code: 200, uri: /regioninfo, method: GET, req_id: ead959a5-d4dc-482d-8824-61e7e9590cc3, remote_addr: 127.0.0.1:56068, local_addr: 127.0.0.1:9998 Nov 26 22:04:54.684 INFO request completed, latency_us: 92, response_code: 200, uri: /regioncount, method: GET, req_id: c04c5561-d48f-4330-b5a1-0034c1845c82, remote_addr: 127.0.0.1:56068, local_addr: 127.0.0.1:9998 Nov 26 22:04:54.685 INFO request completed, latency_us: 109, response_code: 200, uri: /port/cid/0, method: GET, req_id: 66a91f20-f0ce-4593-8892-ff8ad067bdb2, remote_addr: 127.0.0.1:56068, local_addr: 127.0.0.1:9998 Nov 26 22:04:54.685 INFO request completed, latency_us: 112, response_code: 200, uri: /port/cid/1, method: GET, req_id: dfd47a50-e936-4a26-b998-911b44c8b485, remote_addr: 127.0.0.1:56068, local_addr: 127.0.0.1:9998 Nov 26 22:04:54.686 INFO request completed, latency_us: 96, response_code: 200, uri: /port/cid/2, method: GET, req_id: 1ed6bdd3-5d02-463c-aa58-5ecb1f34c1a2, remote_addr: 127.0.0.1:56068, local_addr: 127.0.0.1:9998 Nov 26 22:05:51.279 INFO accepted connection, remote_addr: 127.0.0.1:59993, local_addr: 127.0.0.1:9998 Nov 26 22:05:51.280 INFO request completed, latency_us: 154, response_code: 200, uri: /regioninfo, method: GET, req_id: f03ea382-5bf4-4185-b4d4-ac3b2a16f874, remote_addr: 127.0.0.1:59993, local_addr: 127.0.0.1:9998 Nov 26 22:05:51.281 INFO request completed, latency_us: 107, response_code: 200, uri: /regioncount, method: GET, req_id: 76b74d47-4878-4baf-abb0-e7883088a7de, remote_addr: 127.0.0.1:59993, local_addr: 127.0.0.1:9998 Nov 26 22:05:51.281 INFO request completed, latency_us: 105, response_code: 200, uri: /port/cid/0, method: GET, req_id: 3fd91207-4be8-4865-b129-fb1e77b676be, remote_addr: 127.0.0.1:59993, local_addr: 127.0.0.1:9998 Nov 26 22:05:51.282 INFO request completed, latency_us: 96, response_code: 200, uri: /port/cid/1, method: GET, req_id: b5b28a0d-d2a0-400c-9982-366c34a479f7, remote_addr: 127.0.0.1:59993, local_addr: 127.0.0.1:9998 Nov 26 22:05:51.282 INFO request completed, latency_us: 91, response_code: 200, uri: /port/cid/2, method: GET, req_id: f39bb430-dc48-41fd-bd01-dee48b191810, remote_addr: 127.0.0.1:59993, local_addr: 127.0.0.1:9998 Nov 26 22:06:36.021 INFO accepted connection, remote_addr: 127.0.0.1:40645, local_addr: 127.0.0.1:9998 Nov 26 22:06:36.021 INFO request completed, latency_us: 188, response_code: 200, uri: /regioninfo, method: GET, req_id: 5b075c38-6e9d-4101-9d8b-6a5554bcaadc, remote_addr: 127.0.0.1:40645, local_addr: 127.0.0.1:9998 Nov 26 22:06:36.022 INFO request completed, latency_us: 88, response_code: 200, uri: /regioncount, method: GET, req_id: 30d5d364-0237-4913-884e-9b1137a3da93, remote_addr: 127.0.0.1:40645, local_addr: 127.0.0.1:9998 Nov 26 22:06:36.023 INFO request completed, latency_us: 119, response_code: 200, uri: /port/cid/0, method: GET, req_id: f6695914-0d29-4582-84c2-3f66c1d593f6, remote_addr: 127.0.0.1:40645, local_addr: 127.0.0.1:9998 Nov 26 22:06:36.024 INFO request completed, latency_us: 130, response_code: 200, uri: /port/cid/1, method: GET, req_id: 19a5a149-a30b-4719-a394-fceb3d20eaf2, remote_addr: 127.0.0.1:40645, local_addr: 127.0.0.1:9998 Nov 26 22:06:36.024 INFO request completed, latency_us: 91, response_code: 200, uri: /port/cid/2, method: GET, req_id: 38f6f743-39ac-4e8d-8936-c504948239ed, remote_addr: 127.0.0.1:40645, local_addr: 127.0.0.1:9998 Nov 26 22:07:53.356 INFO accepted connection, remote_addr: 127.0.0.1:65231, local_addr: 127.0.0.1:9998 Nov 26 22:07:53.357 INFO request completed, latency_us: 159, response_code: 200, uri: /regioninfo, method: GET, req_id: 8a80d195-9e67-44b1-a237-ca5f0a238130, remote_addr: 127.0.0.1:65231, local_addr: 127.0.0.1:9998 Nov 26 22:07:53.358 INFO request completed, latency_us: 96, response_code: 200, uri: /regioncount, method: GET, req_id: feffacb3-75f4-4a5c-b6c7-24b2c93a83d9, remote_addr: 127.0.0.1:65231, local_addr: 127.0.0.1:9998 Nov 26 22:07:53.358 INFO request completed, latency_us: 130, response_code: 200, uri: /port/cid/0, method: GET, req_id: ace2246a-b42f-4be4-b9ea-a6078cb8e42d, remote_addr: 127.0.0.1:65231, local_addr: 127.0.0.1:9998 Nov 26 22:07:53.359 INFO request completed, latency_us: 95, response_code: 200, uri: /port/cid/1, method: GET, req_id: d44f9de3-6320-488b-9789-c9b25390223f, remote_addr: 127.0.0.1:65231, local_addr: 127.0.0.1:9998 Nov 26 22:07:53.360 INFO request completed, latency_us: 93, response_code: 200, uri: /port/cid/2, method: GET, req_id: f57ec492-ea65-4170-92ae-0c374f84fdd7, remote_addr: 127.0.0.1:65231, local_addr: 127.0.0.1:9998 Nov 26 22:07:57.571 INFO accepted connection, remote_addr: 127.0.0.1:59834, local_addr: 127.0.0.1:9998 Nov 26 22:07:57.571 INFO request completed, latency_us: 194, response_code: 200, uri: /regioninfo, method: GET, req_id: 5add856b-62c5-4229-aef1-0684f7e78d28, remote_addr: 127.0.0.1:59834, local_addr: 127.0.0.1:9998 Nov 26 22:07:57.572 INFO request completed, latency_us: 120, response_code: 200, uri: /regioncount, method: GET, req_id: 6bf0b07e-8d8b-438f-9090-af04898a77f1, remote_addr: 127.0.0.1:59834, local_addr: 127.0.0.1:9998 Nov 26 22:07:57.573 INFO request completed, latency_us: 109, response_code: 200, uri: /port/cid/0, method: GET, req_id: 26c41c8b-4705-49c8-bf3b-d6ec32158b53, remote_addr: 127.0.0.1:59834, local_addr: 127.0.0.1:9998 Nov 26 22:07:57.574 INFO request completed, latency_us: 93, response_code: 200, uri: /port/cid/1, method: GET, req_id: a28cd3a8-8210-4703-98ff-cc3801c48685, remote_addr: 127.0.0.1:59834, local_addr: 127.0.0.1:9998 Nov 26 22:07:57.574 INFO request completed, latency_us: 92, response_code: 200, uri: /port/cid/2, method: GET, req_id: 90287034-4d89-40f9-9a7e-c3c9d54b3041, remote_addr: 127.0.0.1:59834, local_addr: 127.0.0.1:9998 Nov 26 22:08:01.748 INFO accepted connection, remote_addr: 127.0.0.1:64323, 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 26 22:08:01.749 INFO request completed, latency_us: 147, response_code: 204, uri: /disablerestart/all[8810] Disable keep_running , [8820] Disable keep_running method: POST, req_id: 260bbcab-df1f-40a4-9732-ecd59b0bc2eb, remote_addr: 127[8830] Disable keep_running .0.0.1:64323, local_addr: 127.0.0.1:9998 Nov 26 22:08:01.803 INFO accepted connection, remote_addr: 127.0.0.1:43125, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(2) stop 2 Nov 26 22:08:01.804[8830] Got stop action so:false kr:false INFO request completed, latency_us: 136, response_code: 204, uri: /stop/cid/2, method: POST, req_id: 6597dd7c-4f4b-4d43-a0fb-be9a48f3ee28, remote_addr: 127.0.0.1:43125, local_addr: 127.0.0.1:9998 [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit Nov 26 22:08:01.857 INFO accepted connection, remote_addr: 127.0.0.1:35923, local_addr: 127.0.0.1:9998 Nov 26 22:08:01.858 INFO request completed, latency_us: 136, response_code: 200, uri: /state/cid/2, method: GET, req_id: fdc15674-5045-4c77-b446-bf00c78ac19d, remote_addr: 127.0.0.1:35923, local_addr: 127.0.0.1:9998 Nov 26 22:08:01.915 INFO accepted connection, remote_addr: 127.0.0.1:35668, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(2) start 2 Nov 26 22:08:01.916 INFO request completed, latency_us: 164, response_code: 204, uri: /start/cid/2, method: POST, req_id: 491fa3a1-3ad2-4102-b06f-9ad6c97cce99, remote_addr: 127.0.0.1:35668, local_addr: 127.0.0.1:9998 Nov 26 22:08:01.995 INFO accepted connection, remote_addr: 127.0.0.1:53199, local_addr: 127.0.0.1:9998 Nov 26 22:08:01.996 INFO request completed, latency_us: 154, response_code: 200, uri: /regioninfo, method: GET, req_id: 73607cac-1303-4e3f-bd4a-99130b683488, remote_addr: 127.0.0.1:53199, local_addr: 127.0.0.1:9998 Nov 26 22:08:01.997 INFO request completed, latency_us: 112, response_code: 200, uri: /regioncount, method: GET, req_id: 5d0f2abe-9ea6-427e-8ae9-b91370fcfb6d, remote_addr: 127.0.0.1:53199, local_addr: 127.0.0.1:9998 Nov 26 22:08:01.997 INFO request completed, latency_us: 108, response_code: 200, uri: /port/cid/0, method: GET, req_id: 8ae31777-1fc7-4062-bee6-eafb255d7f3d, remote_addr: 127.0.0.1:53199, local_addr: 127.0.0.1:9998 Nov 26 22:08:01.998 INFO request completed, latency_us: 94, response_code: 200, uri: /port/cid/1, method: GET, req_id: 637d2b67-f95c-4697-a3b3-1c40b6de872a, remote_addr: 127.0.0.1:53199, local_addr: 127.0.0.1:9998 Nov 26 22:08:01.998 INFO request completed, latency_us: 91, response_code: 200, uri: /port/cid/2, method: GET, req_id: e80fc5d2-f7fd-489e-b506-f20bb009dff9, remote_addr: 127.0.0.1:53199, 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(1239) [8830][2] reports Running Nov 26 22:08:16.233 INFO accepted connection, remote_addr: 127.0.0.1:37037, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Shutdown Shutdown Shut it down Nov 26 22:08:16.234 INFO request completed, latency_us: 214, response_code: 204, uri: /shutdown, method: [8810] Disable keep_running POST[8810] Got stop action so:false kr:false , req_id: 5225df84-ec50-4f9b-a728-70e62f318872, remote_addr: 127.0.0.1:37037, local_addr: 127.0.0.1:9998 [8830] Disable keep_running [8830] Got stop action so:false kr:false [8820] Disable keep_running [8820] 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 [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] reports Exit /var/tmp/bins/dsc create --encrypted --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.027221007 Downstairs region 1 created at /var/tmp/test_up-build/8820 in 0.024320848 Downstairs region 2 created at /var/tmp/test_up-build/8830 in 0.023661217 Downstairs region 3 created at /var/tmp/test_up-build/8840 in 0.023001224 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 Starting downstairs at port 8820 Make output file at "/tmp/test_up-build/dsc/downstairs-8810.txt" Make output file at "/tmp/test_up-build/dsc/downstairs-8820.txt" Starting downstairs at port 8830 Make output file at "/tmp/test_up-build/dsc/downstairs-8830.txt" [8810][0] initial start wait reports Starting Starting downstairs at port 8840 [8820][1] initial start wait reports Starting Make output file at "/tmp/test_up-build/dsc/downstairs-8840.txt" [8830][2] initial start wait reports Starting [8840][3] initial start wait reports Starting Downstairs /var/tmp/test_up-build/8810 port 8810 PID:Some(1253) [8810][0] initial start wait reports Running Downstairs /var/tmp/test_up-build/8830 port 8830 PID:Some(1254) [8830][2] initial start wait reports Running Downstairs /var/tmp/test_up-build/8840 port 8840 PID:Some(1255) [8840][3] initial start wait reports Running Downstairs /var/tmp/test_up-build/8820 port 8820 PID:Some(1256) [8820][1] initial start wait reports Running All downstairs are running Nov 26 22:08:21.379 INFO listening, local_addr: 127.0.0.1:9998 Control access at:127.0.0.1:9998 Nov 26 22:08:26.418 INFO accepted connection, remote_addr: 127.0.0.1:43140, local_addr: 127.0.0.1:9998 Nov 26 22:08:26.420 INFO request completed, latency_us: 332, response_code: 200, uri: /state/cid/0, method: GET, req_id: a2b48b93-53dd-4ea9-8748-19807868a7e2, remote_addr: 127.0.0.1:43140, local_addr: 127.0.0.1:9998 Nov 26 22:08:26.475 INFO accepted connection, remote_addr: 127.0.0.1:36197, local_addr: 127.0.0.1:9998 Nov 26 22:08:26.476 INFO request completed, latency_us: 134, response_code: 200, uri: /state/cid/1, method: GET, req_id: 04efa0fc-1701-43d1-ab5d-8d9111b58019, remote_addr: 127.0.0.1:36197, local_addr: 127.0.0.1:9998 Nov 26 22:08:26.529 INFO accepted connection, remote_addr: 127.0.0.1:47659, local_addr: 127.0.0.1:9998 Nov 26 22:08:26.529 INFO request completed, latency_us: 134, response_code: 200, uri: /state/cid/2, method: GET, req_id: f74a1919-f7c0-4667-9fc6-c522db6d7e6b, remote_addr: 127.0.0.1:47659, local_addr: 127.0.0.1:9998 Nov 26 22:08:26.582 INFO accepted connection, remote_addr: 127.0.0.1:52501, local_addr: 127.0.0.1:9998 Nov 26 22:08:26.583 INFO request completed, latency_us: 115, response_code: 200, uri: /state/cid/3, method: GET, req_id: f9a90fa9-cb8f-4c7b-88e1-2b7c7ac86d30, remote_addr: 127.0.0.1:52501, local_addr: 127.0.0.1:9998 Nov 26 22:08:26.636 INFO accepted connection, remote_addr: 127.0.0.1:35321, local_addr: 127.0.0.1:9998 Nov 26 22:08:26.637 INFO request completed, latency_us: 157, response_code: 200, uri: /port/cid/3, method: GET, req_id: 8f2667ce-bcd1-4d03-8600-65ab7f39a12a, remote_addr: 127.0.0.1:35321, local_addr: 127.0.0.1:9998 Nov 26 22:08:26.697 INFO accepted connection, remote_addr: 127.0.0.1:38872, local_addr: 127.0.0.1:9998 Nov 26 22:08:26.697 INFO request completed, latency_us: 165, response_code: 200, uri: /regioninfo, method: GET, req_id: 5dd88bc6-f601-4e90-862c-e329298c2291, remote_addr: 127.0.0.1:38872, local_addr: 127.0.0.1:9998 Nov 26 22:08:26.698 INFO request completed, latency_us: 114, response_code: 200, uri: /regioncount, method: GET, req_id: 8c4006d5-3c26-452b-8605-dccd09aa430d, remote_addr: 127.0.0.1:38872, local_addr: 127.0.0.1:9998 Nov 26 22:08:26.699 INFO request completed, latency_us: 103, response_code: 200, uri: /port/cid/0, method: GET, req_id: feb2b85b-2cfe-4c68-817b-729869a7350a, remote_addr: 127.0.0.1:38872, local_addr: 127.0.0.1:9998 Nov 26 22:08:26.699 INFO request completed, latency_us: 94, response_code: 200, uri: /port/cid/1, method: GET, req_id: df93afec-05e5-4964-95b4-698d5537dfd0, remote_addr: 127.0.0.1:38872, local_addr: 127.0.0.1:9998 Nov 26 22:08:26.700 INFO request completed, latency_us: 94, response_code: 200, uri: /port/cid/2, method: GET, req_id: 6d37d03c-f865-4f7e-b81b-12aad639dac3, remote_addr: 127.0.0.1:38872, local_addr: 127.0.0.1:9998 Nov 26 22:08:26.770 INFO accepted connection, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Nov 26 22:08:26.770 INFO request completed, latency_us: 106, response_code: 200, uri: /regioncount, method: GET, req_id: 0deb0124-c414-42c3-b1d5-a51776c74269, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Nov 26 22:08:26.860 INFO request completed[8810] Got stop action so:false kr:false , latency_us: 241, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 13ab1d1f-88ac-40d9-9ec3-4c381c726e6b, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Nov 26 22:08:26.861 INFO request completed, latency_us: 151, response_code: 200, uri: /state/cid/0, method: GET, req_id: 3372b249-e8f4-46e1-9d38-ed0cb1512dac, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Nov 26 22:08:30.864 INFO request completed, latency_us: 277, response_code: 200, uri: /state/cid/0, method: GET, req_id: 855f2d4c-252e-4990-bd91-94aba26744ee, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Nov 26 22:08:30.946 INFO request completed, latency_us: 180, response_code: 204, uri: /start/cid/0, method: POST, req_id: 64bf76b6-a420-4da0-ae32-3d52242406e1, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Nov 26 22:08:30.947 INFO request completed, latency_us: 117, response_code: 200, uri: /state/cid/0, method: GET, req_id: fc08956d-80f1-40c9-9b44-bbf21b66364a, remote_addr: 127.0.0.1:39426, 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(1264) [8810][0] reports Running Nov 26 22:08:34.949 INFO request completed, latency_us: 314, response_code: 200, uri: /state/cid/0, method: GET, req_id: 17a7ef58-d49e-4752-a9e1-72231fbe6f09, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(1) stop 1 Nov 26 22:09:05.397 INFO request completed[8820] Got stop action so:false kr:false , latency_us: 219, response_code: 204, uri: /stop/cid/1, method: POST, req_id: ddcad194-c276-4a90-9a99-e97aac29a0eb, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Nov 26 22:09:05.397 INFO request completed, latency_us: 136, response_code: 200, uri: /state/cid/1, method: GET, req_id: 638a9df8-d466-4c07-9a8a-dff27eb29f0f, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] reports Exit Nov 26 22:09:09.400 INFO request completed, latency_us: 217, response_code: 200, uri: /state/cid/1, method: GET, req_id: 917ea281-bdf1-44db-9de7-83727cf14928, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(1) start 1 Nov 26 22:09:09.483 INFO request completed, latency_us: 187, response_code: 204, uri: /start/cid/1, method: POST, req_id: c10f0798-f85d-4e57-8485-d5e073409771, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Nov 26 22:09:09.484 INFO request completed, latency_us: 122, response_code: 200, uri: /state/cid/1, method: GET, req_id: 20028ffa-9373-4af3-80c4-807a8076c122, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Nov 26 22:09:13.485 INFO request completed, latency_us: 211, response_code: 200, uri: /state/cid/1, method: GET, req_id: 7753f13c-baf4-4292-9b5e-4a7d3b5e0864, remote_addr: 127.0.0.1:39426, 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(1265) [8820][1] reports Running Nov 26 22:09:17.487 INFO request completed, latency_us: 438, response_code: 200, uri: /state/cid/1, method: GET, req_id: 591befa2-2b74-424d-abc7-a2c5e14a8e6a, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(1) stop 1 Nov 26 22:09:23.579 INFO request completed, latency_us[8820] Got stop action so:false kr:false : 222, response_code: 204, uri: /stop/cid/1, method: POST, req_id: d1c52ba2-4b3c-49ea-9cb1-7ac8f78c813f, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Nov 26 22:09:23.580 INFO request completed, latency_us: 134, response_code: 200, uri: /state/cid/1, method: GET, req_id: f68681fd-fea6-437c-9bbc-49b1be15c5e1, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] reports Exit Nov 26 22:09:27.581 INFO request completed, latency_us: 199, response_code: 200, uri: /state/cid/1, method: GET, req_id: 80c2ffbb-3919-4af3-bac8-378ac4458f87, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(1) start 1 Nov 26 22:09:27.665 INFO request completed, latency_us: 162, response_code: 204, uri: /start/cid/1, method: POST, req_id: ea19fa71-8554-4172-bfd4-f3f609b9b944, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Nov 26 22:09:27.666 INFO request completed, latency_us: 100, response_code: 200, uri: /state/cid/1, method: GET, req_id: 834de6f2-eaca-4345-a2c0-30fa5e2fe3f2, remote_addr: 127.0.0.1:39426, 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(1266) [8820][1] reports Running Nov 26 22:09:31.667 INFO request completed, latency_us: 317, response_code: 200, uri: /state/cid/1, method: GET, req_id: 276accee-eafb-4042-91a6-9fe709e588bf, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(2) stop 2 Nov 26 22:10:01.273 INFO request completed, latency_us: 259, response_code: 204, uri: [8830] Got stop action so:false kr:false /stop/cid/2, method: POST, req_id: 29ea1f93-56fa-4fdf-bd26-7918b0e8a03a, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Nov 26 22:10:01.274 INFO request completed, latency_us: 186, response_code: 200, uri: /state/cid/2, method: GET, req_id: c6e76d86-1d2a-4763-b478-a3dedb664c03, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit Nov 26 22:10:05.276 INFO request completed, latency_us: 232, response_code: 200, uri: /state/cid/2, method: GET, req_id: b3247909-f26c-4b65-bccd-3fd811fd0000, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(2) start 2 Nov 26 22:10:05.360 INFO request completed, latency_us: 141, response_code: 204, uri: /start/cid/2, method: POST, req_id: 9195027e-987a-401b-8a3c-9d2029d31e25, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Nov 26 22:10:05.361 INFO request completed, latency_us: 100, response_code: 200, uri: /state/cid/2, method: GET, req_id: 891d31d9-c43a-4e6f-b306-a1381ed366bc, remote_addr: 127.0.0.1:39426, 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(1267) [8830][2] reports Running Nov 26 22:10:09.363 INFO request completed, latency_us: 338, response_code: 200, uri: /state/cid/2, method: GET, req_id: 8875c4fe-49b9-4ecc-8248-d1edab5d7bbc, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(3) stop 3 Nov 26 22:10:39.054 INFO request completed, latency_us: 230, response_code: 204, uri:[8840] Got stop action so:false kr:false /stop/cid/3, method: POST, req_id: 029ac1ab-b83a-4bdd-8c24-4bc0e524053c, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Nov 26 22:10:39.054 INFO request completed, latency_us: 150, response_code: 200, uri: /state/cid/3, method: GET, req_id: c1096660-d4db-49a3-9eea-83ee14b780a4, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 [8840] Exited with: ExitStatus(unix_wait_status(9)) [8840][3] reports Exit Nov 26 22:10:43.056 INFO request completed, latency_us: 208, response_code: 200, uri: /state/cid/3, method: GET, req_id: 54deb54c-b2c2-4fcd-aa4a-69d3398ff361, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(3) start 3 Nov 26 22:10:43.143 INFO request completed, latency_us: 141, response_code: 204, uri: /start/cid/3, method: POST, req_id: b0381ec4-81ec-4acb-9ac4-ef4ecb242f85, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Nov 26 22:10:43.143 INFO request completed, latency_us: 101, response_code: 200, uri: /state/cid/3, method: GET, req_id: 5f4a1669-552f-4430-a6f6-00d688f52dc3, remote_addr: 127.0.0.1:39426, 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(1268) [8840][3] reports Running Nov 26 22:10:47.146 INFO request completed, latency_us: 310, response_code: 200, uri: /state/cid/3, method: GET, req_id: d7c33a24-777c-428d-b241-88e1dcf76918, remote_addr: 127.0.0.1:39426, local_addr: 127.0.0.1:9998 Nov 26 22:11:16.895 INFO accepted connection, remote_addr: 127.0.0.1:55391, local_addr: 127.0.0.1:9998 Nov 26 22:11:16.896 INFO request completed, latency_us: 194, response_code: 200, uri: /regioninfo, method: GET, req_id: cb95b789-8248-4f91-96c6-37dbcdd9b2e9, remote_addr: 127.0.0.1:55391, local_addr: 127.0.0.1:9998 Nov 26 22:11:16.897 INFO request completed, latency_us: 91, response_code: 200, uri: /regioncount, method: GET, req_id: ac30d430-b3b1-471a-b056-ab019ae58723, remote_addr: 127.0.0.1:55391, local_addr: 127.0.0.1:9998 Nov 26 22:11:16.898 INFO request completed, latency_us: 109, response_code: 200, uri: /port/cid/0, method: GET, req_id: 5fc62a1f-a81a-4e39-affc-93bab79f8b81, remote_addr: 127.0.0.1:55391, local_addr: 127.0.0.1:9998 Nov 26 22:11:16.898 INFO request completed, latency_us: 95, response_code: 200, uri: /port/cid/1, method: GET, req_id: 3b06e0b7-92f3-450e-b7f9-9a3aa167c1d9, remote_addr: 127.0.0.1:55391, local_addr: 127.0.0.1:9998 Nov 26 22:11:16.899 INFO request completed, latency_us: 93, response_code: 200, uri: /port/cid/2, method: GET, req_id: 5ece7735-55a4-4d2b-b87e-899429794659, remote_addr: 127.0.0.1:55391, local_addr: 127.0.0.1:9998 Nov 26 22:11:16.968 INFO accepted connection, remote_addr: 127.0.0.1:43765, local_addr: 127.0.0.1:9998 Nov 26 22:11:16.968 INFO request completed, latency_us: 108, response_code: 200, uri: /regioncount, method: GET, req_id: fc835c9c-6a61-4042-8c80-29cb699aa054, remote_addr: 127.0.0.1:43765, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Stop(0) stop 0 Nov 26 22:11:18.897 INFO request completed, latency_us: 225, response_code[8810] Got stop action so:false kr:false : 204, uri: /stop/cid/0, method: POST, req_id: 8a2919c4-be46-4520-8c15-53d30faf1f64, remote_addr: 127.0.0.1:43765, local_addr: 127.0.0.1:9998 Nov 26 22:11:18.898 INFO request completed, latency_us: 152, response_code: 200, uri: /state/cid/0, method: GET, req_id: 9b7c7f9e-14ab-40d4-a222-79f28ef3b26a, remote_addr: 127.0.0.1:43765, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Nov 26 22:11:22.901 INFO request completed, latency_us: 226, response_code: 200, uri: /state/cid/0, method: GET, req_id: d892e31d-c6cf-4620-ab69-f43d686fe1c1, remote_addr: 127.0.0.1:43765, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Nov 26 22:11:51.655 INFO request completed, latency_us: 207, response_code: 204, uri: /start/cid/0, method: POST, req_id: f3d0fc3a-5695-4cd4-9141-2155d4b07319, remote_addr: 127.0.0.1:43765, 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(1270) [8810][0] reports Running Main task has work to do, go find it got dsc Stop(1) stop 1 Nov 26 22:11:55.746 INFO [8820] Got stop action so:false kr:false request completed, latency_us: 312, response_code: 204, uri: /stop/cid/1, method: POST, req_id: 172a2645-9e5a-4663-9dea-93bf82fbfb43, remote_addr: 127.0.0.1:43765, local_addr: 127.0.0.1:9998 Nov 26 22:11:55.747 INFO request completed, latency_us: 137, response_code: 200, uri: /state/cid/1, method: GET, req_id: 9612d3bd-a5c6-4995-abe5-8bcc434d0d56, remote_addr: 127.0.0.1:43765, local_addr: 127.0.0.1:9998 [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] reports Exit Nov 26 22:11:59.749 INFO request completed, latency_us: 200, response_code: 200, uri: /state/cid/1, method: GET, req_id: 202ca13c-16db-4b18-a5e4-9df3848f203c, remote_addr: 127.0.0.1:43765, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(1) start 1 Nov 26 22:12:28.535 INFO request completed, latency_us: 214, response_code: 204, uri: /start/cid/1, method: POST, req_id: ac86c10e-629b-4b54-baff-70475a720c49, remote_addr: 127.0.0.1:43765, 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(1271) [8820][1] reports Running Main task has work to do, go find it got dsc Stop(2) stop 2 [8830] Got stop action so:false kr:false Nov 26 22:12:32.623 INFO request completed, latency_us: 319, response_code: 204, uri: /stop/cid/2, method: POST, req_id: 8bc30ec6-8be7-4d35-baf8-4b6d9ac6becc, remote_addr: 127.0.0.1:43765, local_addr: 127.0.0.1:9998 Nov 26 22:12:32.624 INFO request completed, latency_us: 160, response_code: 200, uri: /state/cid/2, method: GET, req_id: c50897ab-2b82-4587-9419-e9543f1c24f9, remote_addr: 127.0.0.1:43765, local_addr: 127.0.0.1:9998 [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit Nov 26 22:12:36.626 INFO request completed, latency_us: 195, response_code: 200, uri: /state/cid/2, method: GET, req_id: cf91c6fd-7dd0-477b-929d-7025bb0ba3f0, remote_addr: 127.0.0.1:43765, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(2) start 2 Nov 26 22:13:05.390 INFO request completed, latency_us: 234, response_code: 204, uri: /start/cid/2, method: POST, req_id: 2353d131-f5c3-49df-93cf-403e2047da8b, remote_addr: 127.0.0.1:43765, 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(1272) [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 Nov 26 22:13:09.487 INFO request completed, latency_us: 343, response_code: 204, uri: /stop/cid/3, method: POST, req_id: bd9ea41d-f29a-47ba-a5fa-48af7c4a11b5, remote_addr: 127.0.0.1:43765, local_addr: 127.0.0.1:9998 Nov 26 22:13:09.488 INFO request completed, latency_us: 111, response_code: 200, uri: /state/cid/3, method: GET, req_id: 55ea8a13-1f5e-43a7-ad61-0348156a9ec5, remote_addr: 127.0.0.1:43765, local_addr: 127.0.0.1:9998 [8840] Exited with: ExitStatus(unix_wait_status(9)) [8840][3] reports Exit Nov 26 22:13:13.490 INFO request completed, latency_us: 211, response_code: 200, uri: /state/cid/3, method: GET, req_id: 581850a9-0d9f-4189-8464-0a71f3b30b5c, remote_addr: 127.0.0.1:43765, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(3) start 3 Nov 26 22:13:42.264 INFO request completed, latency_us: 204, response_code: 204, uri: /start/cid/3, method: POST, req_id: 01104e76-08c9-49fd-a147-f4414ceef166, remote_addr: 127.0.0.1:43765, 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(1273) [8840][3] reports Running Main task has work to do, go find it got dsc Stop(0) stop 0 [8810] Got stop action so:false kr:false Nov 26 22:13:46.393 INFO request completed, latency_us: 316, response_code: 204, uri: /stop/cid/0, method: POST, req_id: 30787e05-a8d8-4259-b8fe-2322e2202adb, remote_addr: 127.0.0.1:43765, local_addr: 127.0.0.1:9998 Nov 26 22:13:46.394 INFO request completed, latency_us: 147, response_code: 200, uri: /state/cid/0, method: GET, req_id: 8014d5ce-a846-47c6-b957-8c570a0b062a, remote_addr: 127.0.0.1:43765, local_addr: 127.0.0.1:9998 [8810] Exited with: ExitStatus(unix_wait_status(9)) [8810][0] reports Exit Nov 26 22:13:50.396 INFO request completed, latency_us: 201, response_code: 200, uri: /state/cid/0, method: GET, req_id: 31505cee-fd34-4567-ba01-52b0be31dacf, remote_addr: 127.0.0.1:43765, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Start(0) start 0 Nov 26 22:14:19.169 INFO request completed, latency_us: 231, response_code: 204, uri: /start/cid/0, method: POST, req_id: ccd17603-821c-42b6-b37f-a780f9fc5ccb, remote_addr: 127.0.0.1:43765, local_addr: 127.0.0.1:9998 Nov 26 22:14:23.240 INFO accepted connection, remote_addr: 127.0.0.1:54640, local_addr: 127.0.0.1:9998 Main task has work to do, go find it got dsc Shutdown Shutdown Shut it down Nov 26 22:14:23.241 INFO request completed[8830] Disable keep_running , [8820] Disable keep_running latency_us[8840] Disable keep_running : 144, response_code[8840] Got stop action so:false kr:false :[8830] Got stop action so:false kr:false [8820] Got stop action so:false kr:false 204, uri: /shutdown, method: POST, req_id: c86d307e-1499-430e-ad57-d7e84d9de409, remote_addr: 127.0.0.1:54640, local_addr: 127.0.0.1:9998 [8820] Exited with: ExitStatus(unix_wait_status(9)) [8820][1] reports Exit [8840] Exited with: ExitStatus(unix_wait_status(9)) [8840][3] reports Exit [8830] Exited with: ExitStatus(unix_wait_status(9)) [8830][2] reports Exit