Dec 15 22:57:21.381 INFO testing aio-limited-0.1.0 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 15 22:57:21.382 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +bd47d6825bf4090517549d33cfef10d3300b4a75-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 15 22:57:21.860 INFO [stdout] f0179c00c02b80a7cb9b67210ef7d6798c9b417818408d8f271e88269f1470f1
Dec 15 22:57:21.862 INFO running `"docker" "start" "-a" "f0179c00c02b80a7cb9b67210ef7d6798c9b417818408d8f271e88269f1470f1"`
Dec 15 22:57:24.150 INFO [stderr] usermod: no changes
Dec 15 22:57:24.419 INFO [stderr]    Compiling aio-limited v0.1.0 (/source)
Dec 15 22:57:26.997 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.82s
Dec 15 22:57:27.004 INFO [stderr] su: No module specific data is present
Dec 15 22:57:27.525 INFO running `"docker" "inspect" "f0179c00c02b80a7cb9b67210ef7d6798c9b417818408d8f271e88269f1470f1"`
Dec 15 22:57:27.681 INFO running `"docker" "rm" "-f" "f0179c00c02b80a7cb9b67210ef7d6798c9b417818408d8f271e88269f1470f1"`
Dec 15 22:57:27.884 INFO [stdout] f0179c00c02b80a7cb9b67210ef7d6798c9b417818408d8f271e88269f1470f1
Dec 15 22:57:27.889 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +bd47d6825bf4090517549d33cfef10d3300b4a75-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 15 22:57:28.129 INFO [stdout] 3def409727e7898c3c253f49dd757caed3b261a618d49cc894335b538e54dc87
Dec 15 22:57:28.132 INFO running `"docker" "start" "-a" "3def409727e7898c3c253f49dd757caed3b261a618d49cc894335b538e54dc87"`
Dec 15 22:57:28.798 INFO [stderr] usermod: no changes
Dec 15 22:57:29.037 INFO [stderr]    Compiling aio-limited v0.1.0 (/source)
Dec 15 22:57:39.327 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.49s
Dec 15 22:57:39.330 INFO [stderr] su: No module specific data is present
Dec 15 22:57:40.452 INFO running `"docker" "inspect" "3def409727e7898c3c253f49dd757caed3b261a618d49cc894335b538e54dc87"`
Dec 15 22:57:40.736 INFO running `"docker" "rm" "-f" "3def409727e7898c3c253f49dd757caed3b261a618d49cc894335b538e54dc87"`
Dec 15 22:57:41.018 INFO [stdout] 3def409727e7898c3c253f49dd757caed3b261a618d49cc894335b538e54dc87
Dec 15 22:57:41.021 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +bd47d6825bf4090517549d33cfef10d3300b4a75-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 15 22:57:41.435 INFO [stdout] 04bc0da26dc8950a5bb5e13ba082d228960e2700819667ecba2d4b9a7efa28cd
Dec 15 22:57:41.443 INFO running `"docker" "start" "-a" "04bc0da26dc8950a5bb5e13ba082d228960e2700819667ecba2d4b9a7efa28cd"`
Dec 15 22:57:42.983 INFO [stderr] usermod: no changes
Dec 15 22:57:43.151 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.15s
Dec 15 22:57:43.171 INFO [stderr]      Running /target/debug/deps/aio_limited-08f597674192b2e6
Dec 15 22:57:43.175 INFO [stdout] 
Dec 15 22:57:43.175 INFO [stdout] running 2 tests
Dec 15 22:57:44.197 INFO [stderr]  INFO 2018-12-15T22:57:44Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:44.281 INFO [stderr]  INFO 2018-12-15T22:57:44Z: aio_limited::limiter::tests: spawning 30 clients, data size = 10000 b, duration = 30 s
Dec 15 22:57:45.179 INFO [stderr]  INFO 2018-12-15T22:57:45Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:45.182 INFO [stderr]  INFO 2018-12-15T22:57:45Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:45.182 INFO [stderr]  INFO 2018-12-15T22:57:45Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:45.184 INFO [stderr]  INFO 2018-12-15T22:57:45Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:45.185 INFO [stderr]  INFO 2018-12-15T22:57:45Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:45.186 INFO [stderr]  INFO 2018-12-15T22:57:45Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:45.187 INFO [stderr]  INFO 2018-12-15T22:57:45Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:46.191 INFO [stderr]  INFO 2018-12-15T22:57:46Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:46.191 INFO [stderr]  INFO 2018-12-15T22:57:46Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:46.191 INFO [stderr]  INFO 2018-12-15T22:57:46Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:46.191 INFO [stderr]  INFO 2018-12-15T22:57:46Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:46.191 INFO [stderr]  INFO 2018-12-15T22:57:46Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:46.191 INFO [stderr]  INFO 2018-12-15T22:57:46Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:46.191 INFO [stderr]  INFO 2018-12-15T22:57:46Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:46.285 INFO [stderr]  INFO 2018-12-15T22:57:46Z: aio_limited::limiter::tests: duration = 0 s, r = 10000.000 b/s
Dec 15 22:57:47.183 INFO [stderr]  INFO 2018-12-15T22:57:47Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:47.183 INFO [stderr]  INFO 2018-12-15T22:57:47Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:47.183 INFO [stderr]  INFO 2018-12-15T22:57:47Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:47.183 INFO [stderr]  INFO 2018-12-15T22:57:47Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:47.183 INFO [stderr]  INFO 2018-12-15T22:57:47Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:47.183 INFO [stderr]  INFO 2018-12-15T22:57:47Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:47.183 INFO [stderr]  INFO 2018-12-15T22:57:47Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:47.183 INFO [stderr]  INFO 2018-12-15T22:57:47Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:47.285 INFO [stderr]  INFO 2018-12-15T22:57:47Z: aio_limited::limiter::tests: duration = 1 s, r = 10000.000 b/s
Dec 15 22:57:48.187 INFO [stderr]  INFO 2018-12-15T22:57:48Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:48.187 INFO [stderr]  INFO 2018-12-15T22:57:48Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:48.187 INFO [stderr]  INFO 2018-12-15T22:57:48Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:48.187 INFO [stderr]  INFO 2018-12-15T22:57:48Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:48.187 INFO [stderr]  INFO 2018-12-15T22:57:48Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:48.187 INFO [stderr]  INFO 2018-12-15T22:57:48Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:48.187 INFO [stderr]  INFO 2018-12-15T22:57:48Z: aio_limited::limiter::tests: Ok("0123456789")
Dec 15 22:57:48.283 INFO [stderr]  INFO 2018-12-15T22:57:48Z: aio_limited::limiter::tests: duration = 2 s, r = 5000.000 b/s
Dec 15 22:57:49.290 INFO [stderr]  INFO 2018-12-15T22:57:49Z: aio_limited::limiter::tests: duration = 3 s, r = 3333.333 b/s
Dec 15 22:57:50.250 INFO [stdout] test limiter::tests::test1 ... ok
Dec 15 22:57:50.291 INFO [stderr]  INFO 2018-12-15T22:57:50Z: aio_limited::limiter::tests: duration = 4 s, r = 2500.000 b/s
Dec 15 22:57:51.288 INFO [stderr]  INFO 2018-12-15T22:57:51Z: aio_limited::limiter::tests: duration = 5 s, r = 2000.000 b/s
Dec 15 22:57:52.286 INFO [stderr]  INFO 2018-12-15T22:57:52Z: aio_limited::limiter::tests: duration = 6 s, r = 1666.667 b/s
Dec 15 22:57:53.286 INFO [stderr]  INFO 2018-12-15T22:57:53Z: aio_limited::limiter::tests: duration = 7 s, r = 1428.571 b/s
Dec 15 22:57:54.287 INFO [stderr]  INFO 2018-12-15T22:57:54Z: aio_limited::limiter::tests: duration = 8 s, r = 1250.000 b/s
Dec 15 22:57:55.283 INFO [stderr]  INFO 2018-12-15T22:57:55Z: aio_limited::limiter::tests: duration = 9 s, r = 1111.111 b/s
Dec 15 22:57:56.323 INFO [stderr]  INFO 2018-12-15T22:57:56Z: aio_limited::limiter::tests: duration = 11 s, r = 909.091 b/s
Dec 15 22:57:57.286 INFO [stderr]  INFO 2018-12-15T22:57:57Z: aio_limited::limiter::tests: duration = 11 s, r = 909.091 b/s
Dec 15 22:57:58.287 INFO [stderr]  INFO 2018-12-15T22:57:58Z: aio_limited::limiter::tests: duration = 12 s, r = 833.333 b/s
Dec 15 22:57:59.282 INFO [stderr]  INFO 2018-12-15T22:57:59Z: aio_limited::limiter::tests: duration = 13 s, r = 769.231 b/s
Dec 15 22:58:00.284 INFO [stderr]  INFO 2018-12-15T22:58:00Z: aio_limited::limiter::tests: duration = 14 s, r = 714.286 b/s
Dec 15 22:58:01.291 INFO [stderr]  INFO 2018-12-15T22:58:01Z: aio_limited::limiter::tests: duration = 15 s, r = 666.667 b/s
Dec 15 22:58:02.283 INFO [stderr]  INFO 2018-12-15T22:58:02Z: aio_limited::limiter::tests: duration = 16 s, r = 625.000 b/s
Dec 15 22:58:03.283 INFO [stderr]  INFO 2018-12-15T22:58:03Z: aio_limited::limiter::tests: duration = 17 s, r = 588.235 b/s
Dec 15 22:58:04.284 INFO [stderr]  INFO 2018-12-15T22:58:04Z: aio_limited::limiter::tests: duration = 18 s, r = 555.556 b/s
Dec 15 22:58:04.284 INFO [stderr]  INFO 2018-12-15T22:58:04Z: aio_limited::limiter::tests: duration = 18 s, r = 555.556 b/s
Dec 15 22:58:05.284 INFO [stderr]  INFO 2018-12-15T22:58:05Z: aio_limited::limiter::tests: duration = 19 s, r = 526.316 b/s
Dec 15 22:58:06.282 INFO [stderr]  INFO 2018-12-15T22:58:06Z: aio_limited::limiter::tests: duration = 20 s, r = 500.000 b/s
Dec 15 22:58:07.283 INFO [stderr]  INFO 2018-12-15T22:58:07Z: aio_limited::limiter::tests: duration = 21 s, r = 476.190 b/s
Dec 15 22:58:08.284 INFO [stderr]  INFO 2018-12-15T22:58:08Z: aio_limited::limiter::tests: duration = 22 s, r = 454.545 b/s
Dec 15 22:58:09.282 INFO [stderr]  INFO 2018-12-15T22:58:09Z: aio_limited::limiter::tests: duration = 23 s, r = 434.783 b/s
Dec 15 22:58:10.282 INFO [stderr]  INFO 2018-12-15T22:58:10Z: aio_limited::limiter::tests: duration = 24 s, r = 416.667 b/s
Dec 15 22:58:11.283 INFO [stderr]  INFO 2018-12-15T22:58:11Z: aio_limited::limiter::tests: duration = 25 s, r = 400.000 b/s
Dec 15 22:58:12.282 INFO [stderr]  INFO 2018-12-15T22:58:12Z: aio_limited::limiter::tests: duration = 26 s, r = 384.615 b/s
Dec 15 22:58:13.282 INFO [stderr]  INFO 2018-12-15T22:58:13Z: aio_limited::limiter::tests: duration = 27 s, r = 370.370 b/s
Dec 15 22:58:14.283 INFO [stderr]  INFO 2018-12-15T22:58:14Z: aio_limited::limiter::tests: duration = 28 s, r = 357.143 b/s
Dec 15 22:58:17.311 INFO [stderr]  INFO 2018-12-15T22:58:17Z: aio_limited::limiter::tests: spawn 1 more client, data size = 10000 b, duration = 1 s
Dec 15 22:58:18.323 INFO [stderr]  INFO 2018-12-15T22:58:18Z: aio_limited::limiter::tests: duration = 0 s, r = 10000.000 b/s
Dec 15 22:58:21.311 INFO [stdout] test limiter::tests::test2 ... ok
Dec 15 22:58:21.311 INFO [stderr]    Doc-tests aio-limited
Dec 15 22:58:21.311 INFO [stdout] 
Dec 15 22:58:21.311 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 22:58:21.311 INFO [stdout] 
Dec 15 22:58:24.583 INFO [stdout] 
Dec 15 22:58:24.583 INFO [stdout] running 0 tests
Dec 15 22:58:24.583 INFO [stdout] 
Dec 15 22:58:24.583 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 22:58:24.583 INFO [stdout] 
Dec 15 22:58:24.595 INFO [stderr] su: No module specific data is present
Dec 15 22:58:25.358 INFO running `"docker" "inspect" "04bc0da26dc8950a5bb5e13ba082d228960e2700819667ecba2d4b9a7efa28cd"`
Dec 15 22:58:25.764 INFO running `"docker" "rm" "-f" "04bc0da26dc8950a5bb5e13ba082d228960e2700819667ecba2d4b9a7efa28cd"`
Dec 15 22:58:26.163 INFO [stdout] 04bc0da26dc8950a5bb5e13ba082d228960e2700819667ecba2d4b9a7efa28cd