Nov 17 11:33:10.091 INFO testing TapioT/perf_msr_test against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982 Nov 17 11:33:10.093 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"` Nov 17 11:33:10.337 INFO [stdout] f854c65302286a3fa4296c06766f0870afd69dc5e4c90f2e7fad1277863dff4c Nov 17 11:33:10.341 INFO running `"docker" "start" "-a" "f854c65302286a3fa4296c06766f0870afd69dc5e4c90f2e7fad1277863dff4c"` Nov 17 11:33:10.828 INFO [stderr] usermod: no changes Nov 17 11:33:10.863 INFO [stderr] Compiling perf_msr_test v0.1.0 (/source) Nov 17 11:33:11.219 INFO [stderr] warning: function is never used: `read_msr` Nov 17 11:33:11.219 INFO [stderr] --> src/main.rs:23:1 Nov 17 11:33:11.220 INFO [stderr] | Nov 17 11:33:11.220 INFO [stderr] 23 | fn read_msr(m: u32) -> i64 { Nov 17 11:33:11.220 INFO [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^ Nov 17 11:33:11.221 INFO [stderr] | Nov 17 11:33:11.221 INFO [stderr] = note: #[warn(dead_code)] on by default Nov 17 11:33:11.221 INFO [stderr] Nov 17 11:33:11.436 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.58s Nov 17 11:33:11.438 INFO [stderr] su: No module specific data is present Nov 17 11:33:11.732 INFO running `"docker" "inspect" "f854c65302286a3fa4296c06766f0870afd69dc5e4c90f2e7fad1277863dff4c"` Nov 17 11:33:11.831 INFO running `"docker" "rm" "-f" "f854c65302286a3fa4296c06766f0870afd69dc5e4c90f2e7fad1277863dff4c"` Nov 17 11:33:11.947 INFO [stdout] f854c65302286a3fa4296c06766f0870afd69dc5e4c90f2e7fad1277863dff4c Nov 17 11:33:11.949 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"` Nov 17 11:33:12.244 INFO [stdout] fea117311b252b3951b6398da28020708a77157abcabdb0f0d54cf11ca6d1cc8 Nov 17 11:33:12.245 INFO running `"docker" "start" "-a" "fea117311b252b3951b6398da28020708a77157abcabdb0f0d54cf11ca6d1cc8"` Nov 17 11:33:12.999 INFO [stderr] usermod: no changes Nov 17 11:33:13.035 INFO [stderr] Compiling perf_msr_test v0.1.0 (/source) Nov 17 11:33:13.460 INFO [stderr] warning: function is never used: `read_msr` Nov 17 11:33:13.460 INFO [stderr] --> src/main.rs:23:1 Nov 17 11:33:13.460 INFO [stderr] | Nov 17 11:33:13.461 INFO [stderr] 23 | fn read_msr(m: u32) -> i64 { Nov 17 11:33:13.461 INFO [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^ Nov 17 11:33:13.461 INFO [stderr] | Nov 17 11:33:13.461 INFO [stderr] = note: #[warn(dead_code)] on by default Nov 17 11:33:13.461 INFO [stderr] Nov 17 11:33:13.791 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.77s Nov 17 11:33:13.794 INFO [stderr] su: No module specific data is present Nov 17 11:33:14.117 INFO running `"docker" "inspect" "fea117311b252b3951b6398da28020708a77157abcabdb0f0d54cf11ca6d1cc8"` Nov 17 11:33:14.219 INFO running `"docker" "rm" "-f" "fea117311b252b3951b6398da28020708a77157abcabdb0f0d54cf11ca6d1cc8"` Nov 17 11:33:14.328 INFO [stdout] fea117311b252b3951b6398da28020708a77157abcabdb0f0d54cf11ca6d1cc8 Nov 17 11:33:14.332 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"` Nov 17 11:33:14.583 INFO [stdout] b7d7c72a0ec96a7fd6967d68d60e6935f503119b33a19876424cae566e14c37d Nov 17 11:33:14.585 INFO running `"docker" "start" "-a" "b7d7c72a0ec96a7fd6967d68d60e6935f503119b33a19876424cae566e14c37d"` Nov 17 11:33:15.298 INFO [stderr] usermod: no changes Nov 17 11:33:15.339 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.01s Nov 17 11:33:15.340 INFO [stderr] Running /target/debug/deps/perf_msr_test-2ef640cb01c0571e Nov 17 11:33:15.346 INFO [stdout] Nov 17 11:33:15.346 INFO [stdout] running 5 tests Nov 17 11:33:15.351 INFO [stdout] test test_u8bytes2u64 ... ok Nov 17 11:33:15.351 INFO [stdout] test test_read_tsc ... ok Nov 17 11:33:15.351 INFO [stdout] test test_read_msr ... ok Nov 17 11:33:15.351 INFO [stdout] test test_cpuid_0 ... ok Nov 17 11:33:15.383 INFO [stdout] test test_read_msr2 ... FAILED Nov 17 11:33:15.383 INFO [stdout] Nov 17 11:33:15.383 INFO [stdout] failures: Nov 17 11:33:15.384 INFO [stdout] Nov 17 11:33:15.384 INFO [stdout] ---- test_read_msr2 stdout ---- Nov 17 11:33:15.384 INFO [stdout] thread 'test_read_msr2' panicked at 'Cannot open /dev/cpu/0/msr. Error entity not found', src/main.rs:78:13 Nov 17 11:33:15.386 INFO [stdout] stack backtrace: Nov 17 11:33:15.386 INFO [stdout] 0: 0x563080f67b13 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h1a944b80a560daa8 Nov 17 11:33:15.386 INFO [stdout] at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Nov 17 11:33:15.386 INFO [stdout] 1: 0x563080f622a8 - std::sys_common::backtrace::_print::h18f735b4c3a49bdb Nov 17 11:33:15.386 INFO [stdout] at libstd/sys_common/backtrace.rs:71 Nov 17 11:33:15.386 INFO [stdout] 2: 0x563080f66a84 - std::panicking::default_hook::{{closure}}::h97ab3548a215c097 Nov 17 11:33:15.386 INFO [stdout] at libstd/sys_common/backtrace.rs:59 Nov 17 11:33:15.386 INFO [stdout] at libstd/panicking.rs:211 Nov 17 11:33:15.386 INFO [stdout] 3: 0x563080f6676c - std::panicking::default_hook::h98c2a01ef734e4b0 Nov 17 11:33:15.386 INFO [stdout] at libstd/panicking.rs:221 Nov 17 11:33:15.386 INFO [stdout] 4: 0x563080f67190 - std::panicking::rust_panic_with_hook::hb4b3f28eb79ea752 Nov 17 11:33:15.386 INFO [stdout] at libstd/panicking.rs:476 Nov 17 11:33:15.386 INFO [stdout] 5: 0x563080f66d11 - std::panicking::continue_panic_fmt::h61fd5a7b1a268752 Nov 17 11:33:15.387 INFO [stdout] at libstd/panicking.rs:390 Nov 17 11:33:15.387 INFO [stdout] 6: 0x563080f66c5e - std::panicking::begin_panic_fmt::hfa6f19d29682fcb6 Nov 17 11:33:15.387 INFO [stdout] at libstd/panicking.rs:345 Nov 17 11:33:15.387 INFO [stdout] 7: 0x563080f1764b - perf_msr_test::read_msr2::hb908b830fc24f841 Nov 17 11:33:15.387 INFO [stdout] at src/main.rs:78 Nov 17 11:33:15.387 INFO [stdout] 8: 0x563080f17e60 - perf_msr_test::test_read_msr2::he12f7ac801800b48 Nov 17 11:33:15.387 INFO [stdout] at src/main.rs:119 Nov 17 11:33:15.387 INFO [stdout] 9: 0x563080f17e29 - perf_msr_test::test_read_msr2::{{closure}}::h350d94faf5688863 Nov 17 11:33:15.387 INFO [stdout] at src/main.rs:116 Nov 17 11:33:15.387 INFO [stdout] 10: 0x563080f18fad - core::ops::function::FnOnce::call_once::h5c063a0d36776755 Nov 17 11:33:15.387 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/ops/function.rs:238 Nov 17 11:33:15.387 INFO [stdout] 11: 0x563080f239ce - >::call_box::h9dcb7fc9b8bb4240 Nov 17 11:33:15.387 INFO [stdout] at libtest/lib.rs:1471 Nov 17 11:33:15.387 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/ops/function.rs:238 Nov 17 11:33:15.387 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673 Nov 17 11:33:15.387 INFO [stdout] 12: 0x563080f75869 - __rust_maybe_catch_panic Nov 17 11:33:15.387 INFO [stdout] at libpanic_unwind/lib.rs:102 Nov 17 11:33:15.387 INFO [stdout] 13: 0x563080f1ba33 - std::sys_common::backtrace::__rust_begin_short_backtrace::h89ea8afe71e36f86 Nov 17 11:33:15.387 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289 Nov 17 11:33:15.387 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398 Nov 17 11:33:15.387 INFO [stdout] at libtest/lib.rs:1426 Nov 17 11:33:15.387 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/sys_common/backtrace.rs:136 Nov 17 11:33:15.388 INFO [stdout] 14: 0x563080f1c8e4 - std::panicking::try::do_call::h4132042e0cbea3ae Nov 17 11:33:15.388 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:477 Nov 17 11:33:15.388 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:319 Nov 17 11:33:15.388 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:310 Nov 17 11:33:15.388 INFO [stdout] 15: 0x563080f75869 - __rust_maybe_catch_panic Nov 17 11:33:15.388 INFO [stdout] at libpanic_unwind/lib.rs:102 Nov 17 11:33:15.388 INFO [stdout] 16: 0x563080f23afc - >::call_box::hab7f616f05880bf6 Nov 17 11:33:15.388 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289 Nov 17 11:33:15.388 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398 Nov 17 11:33:15.388 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:476 Nov 17 11:33:15.388 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673 Nov 17 11:33:15.388 INFO [stdout] 17: 0x563080f6974d - std::sys::unix::thread::Thread::new::thread_start::h5f8d67158a1ca038 Nov 17 11:33:15.388 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:683 Nov 17 11:33:15.388 INFO [stdout] at libstd/sys_common/thread.rs:24 Nov 17 11:33:15.388 INFO [stdout] at libstd/sys/unix/thread.rs:90 Nov 17 11:33:15.388 INFO [stdout] 18: 0x7fa8bc71e6b9 - start_thread Nov 17 11:33:15.388 INFO [stdout] 19: 0x7fa8bc23e41c - clone Nov 17 11:33:15.388 INFO [stdout] 20: 0x0 - Nov 17 11:33:15.388 INFO [stdout] Nov 17 11:33:15.388 INFO [stdout] Nov 17 11:33:15.389 INFO [stdout] failures: Nov 17 11:33:15.389 INFO [stdout] test_read_msr2 Nov 17 11:33:15.389 INFO [stdout] Nov 17 11:33:15.389 INFO [stdout] test result: FAILED. 4 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out Nov 17 11:33:15.389 INFO [stdout] Nov 17 11:33:15.390 INFO [stderr] error: test failed, to rerun pass '--bin perf_msr_test' Nov 17 11:33:15.391 INFO [stderr] su: No module specific data is present Nov 17 11:33:15.773 INFO running `"docker" "inspect" "b7d7c72a0ec96a7fd6967d68d60e6935f503119b33a19876424cae566e14c37d"` Nov 17 11:33:15.895 INFO running `"docker" "rm" "-f" "b7d7c72a0ec96a7fd6967d68d60e6935f503119b33a19876424cae566e14c37d"` Nov 17 11:33:16.015 INFO [stdout] b7d7c72a0ec96a7fd6967d68d60e6935f503119b33a19876424cae566e14c37d