Dec 06 19:14:28.674 INFO testing tsc-timer-0.1.5 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 06 19:14:28.674 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 06 19:14:28.920 INFO [stdout] 972834ccdb5ff4c99d0bc3765e2515766cbeea9823035308058b25ddb75e95d0
Dec 06 19:14:28.922 INFO running `"docker" "start" "-a" "972834ccdb5ff4c99d0bc3765e2515766cbeea9823035308058b25ddb75e95d0"`
Dec 06 19:14:29.330 INFO [stderr] usermod: no changes
Dec 06 19:14:29.358 INFO [stderr]    Compiling tsc-timer v0.1.5 (/source)
Dec 06 19:14:29.755 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.41s
Dec 06 19:14:29.757 INFO [stderr] su: No module specific data is present
Dec 06 19:14:30.118 INFO running `"docker" "inspect" "972834ccdb5ff4c99d0bc3765e2515766cbeea9823035308058b25ddb75e95d0"`
Dec 06 19:14:30.222 INFO running `"docker" "rm" "-f" "972834ccdb5ff4c99d0bc3765e2515766cbeea9823035308058b25ddb75e95d0"`
Dec 06 19:14:30.331 INFO [stdout] 972834ccdb5ff4c99d0bc3765e2515766cbeea9823035308058b25ddb75e95d0
Dec 06 19:14:30.335 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 06 19:14:30.547 INFO [stdout] fbbcdc10e58e44c5f2bb49dd0e568b8e5f96af8e25ebe59cd6521039fa2203ea
Dec 06 19:14:30.549 INFO running `"docker" "start" "-a" "fbbcdc10e58e44c5f2bb49dd0e568b8e5f96af8e25ebe59cd6521039fa2203ea"`
Dec 06 19:14:30.984 INFO [stderr] usermod: no changes
Dec 06 19:14:31.010 INFO [stderr]    Compiling tsc-timer v0.1.5 (/source)
Dec 06 19:14:31.767 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.77s
Dec 06 19:14:31.769 INFO [stderr] su: No module specific data is present
Dec 06 19:14:32.259 INFO running `"docker" "inspect" "fbbcdc10e58e44c5f2bb49dd0e568b8e5f96af8e25ebe59cd6521039fa2203ea"`
Dec 06 19:14:32.390 INFO running `"docker" "rm" "-f" "fbbcdc10e58e44c5f2bb49dd0e568b8e5f96af8e25ebe59cd6521039fa2203ea"`
Dec 06 19:14:32.515 INFO [stdout] fbbcdc10e58e44c5f2bb49dd0e568b8e5f96af8e25ebe59cd6521039fa2203ea
Dec 06 19:14:32.520 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 06 19:14:32.723 INFO [stdout] 0248890ba81769a5d9bed3be7f1aae348a70b8a6783704c539e061688911b760
Dec 06 19:14:32.732 INFO running `"docker" "start" "-a" "0248890ba81769a5d9bed3be7f1aae348a70b8a6783704c539e061688911b760"`
Dec 06 19:14:33.396 INFO [stderr] usermod: no changes
Dec 06 19:14:33.428 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.01s
Dec 06 19:14:33.428 INFO [stderr]      Running /target/debug/deps/tsc_timer-4f5004516f45ac2d
Dec 06 19:14:33.434 INFO [stdout] 
Dec 06 19:14:33.434 INFO [stdout] running 3 tests
Dec 06 19:14:33.434 INFO [stdout] test tests::invariant_tsc ... ok
Dec 06 19:14:33.434 INFO [stderr]    Doc-tests tsc-timer
Dec 06 19:14:33.434 INFO [stdout] test tests::print_span_overhead ... ok
Dec 06 19:14:33.434 INFO [stdout] test tests::start_stop ... ok
Dec 06 19:14:33.434 INFO [stdout] 
Dec 06 19:14:33.434 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 19:14:33.434 INFO [stdout] 
Dec 06 19:14:33.971 INFO [stdout] 
Dec 06 19:14:33.972 INFO [stdout] running 1 test
Dec 06 19:14:34.867 INFO [stdout] test src/lib.rs -  (line 6) ... ok
Dec 06 19:14:34.867 INFO [stdout] 
Dec 06 19:14:34.867 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 19:14:34.868 INFO [stdout] 
Dec 06 19:14:34.880 INFO [stderr] su: No module specific data is present
Dec 06 19:14:35.363 INFO running `"docker" "inspect" "0248890ba81769a5d9bed3be7f1aae348a70b8a6783704c539e061688911b760"`
Dec 06 19:14:35.450 INFO running `"docker" "rm" "-f" "0248890ba81769a5d9bed3be7f1aae348a70b8a6783704c539e061688911b760"`
Dec 06 19:14:35.575 INFO [stdout] 0248890ba81769a5d9bed3be7f1aae348a70b8a6783704c539e061688911b760