Nov 18 00:11:02.994 INFO testing rustwt-1.0.1 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982 Nov 18 00:11:03.000 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 18 00:11:03.477 INFO [stdout] 461e5f938033d82dd2daa9bd9f2be7ff8163dac53fdb0d54ba3e5be95ab95f5c Nov 18 00:11:03.480 INFO running `"docker" "start" "-a" "461e5f938033d82dd2daa9bd9f2be7ff8163dac53fdb0d54ba3e5be95ab95f5c"` Nov 18 00:11:05.071 INFO [stderr] usermod: no changes Nov 18 00:11:05.195 INFO [stderr] Compiling uuid v0.4.0 Nov 18 00:11:06.427 INFO [stderr] Compiling rustwt v1.0.1 (/source) Nov 18 00:11:07.371 INFO [stderr] warning: use of deprecated item 'openssl::sign::Verifier::finish': renamed to `verify` Nov 18 00:11:07.371 INFO [stderr] --> src/lib.rs:316:26 Nov 18 00:11:07.372 INFO [stderr] | Nov 18 00:11:07.372 INFO [stderr] 316 | verifier.finish(&signature).map_err( Nov 18 00:11:07.372 INFO [stderr] | ^^^^^^ Nov 18 00:11:07.372 INFO [stderr] | Nov 18 00:11:07.373 INFO [stderr] = note: #[warn(deprecated)] on by default Nov 18 00:11:07.373 INFO [stderr] Nov 18 00:11:07.373 INFO [stderr] warning: use of deprecated item 'openssl::sign::Signer::finish': renamed to sign_to_vec Nov 18 00:11:07.374 INFO [stderr] --> src/lib.rs:343:12 Nov 18 00:11:07.374 INFO [stderr] | Nov 18 00:11:07.374 INFO [stderr] 343 | signer.finish() Nov 18 00:11:07.374 INFO [stderr] | ^^^^^^ Nov 18 00:11:07.375 INFO [stderr] Nov 18 00:11:08.951 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 3.85s Nov 18 00:11:08.955 INFO [stderr] su: No module specific data is present Nov 18 00:11:09.887 INFO running `"docker" "inspect" "461e5f938033d82dd2daa9bd9f2be7ff8163dac53fdb0d54ba3e5be95ab95f5c"` Nov 18 00:11:10.157 INFO running `"docker" "rm" "-f" "461e5f938033d82dd2daa9bd9f2be7ff8163dac53fdb0d54ba3e5be95ab95f5c"` Nov 18 00:11:10.636 INFO [stdout] 461e5f938033d82dd2daa9bd9f2be7ff8163dac53fdb0d54ba3e5be95ab95f5c Nov 18 00:11:10.646 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 18 00:11:11.223 INFO [stdout] 8eb42f5c55e6b03639d5de14507c6aa5c6527150688b20267666819a4848935a Nov 18 00:11:11.225 INFO running `"docker" "start" "-a" "8eb42f5c55e6b03639d5de14507c6aa5c6527150688b20267666819a4848935a"` Nov 18 00:11:12.918 INFO [stderr] usermod: no changes Nov 18 00:11:13.075 INFO [stderr] Compiling rustwt v1.0.1 (/source) Nov 18 00:11:14.083 INFO [stderr] warning: use of deprecated item 'openssl::sign::Verifier::finish': renamed to `verify` Nov 18 00:11:14.084 INFO [stderr] --> src/lib.rs:316:26 Nov 18 00:11:14.084 INFO [stderr] | Nov 18 00:11:14.085 INFO [stderr] 316 | verifier.finish(&signature).map_err( Nov 18 00:11:14.086 INFO [stderr] | ^^^^^^ Nov 18 00:11:14.086 INFO [stderr] | Nov 18 00:11:14.087 INFO [stderr] = note: #[warn(deprecated)] on by default Nov 18 00:11:14.087 INFO [stderr] Nov 18 00:11:14.095 INFO [stderr] warning: use of deprecated item 'openssl::sign::Signer::finish': renamed to sign_to_vec Nov 18 00:11:14.096 INFO [stderr] --> src/lib.rs:343:12 Nov 18 00:11:14.096 INFO [stderr] | Nov 18 00:11:14.097 INFO [stderr] 343 | signer.finish() Nov 18 00:11:14.098 INFO [stderr] | ^^^^^^ Nov 18 00:11:14.098 INFO [stderr] Nov 18 00:11:17.244 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 4.29s Nov 18 00:11:17.251 INFO [stderr] su: No module specific data is present Nov 18 00:11:18.341 INFO running `"docker" "inspect" "8eb42f5c55e6b03639d5de14507c6aa5c6527150688b20267666819a4848935a"` Nov 18 00:11:18.617 INFO running `"docker" "rm" "-f" "8eb42f5c55e6b03639d5de14507c6aa5c6527150688b20267666819a4848935a"` Nov 18 00:11:18.955 INFO [stdout] 8eb42f5c55e6b03639d5de14507c6aa5c6527150688b20267666819a4848935a Nov 18 00:11:18.965 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 18 00:11:19.544 INFO [stdout] ad702c9b374c3e53700c019f0ab2b1015b01259f841ae587f0bbbcd281101712 Nov 18 00:11:19.546 INFO running `"docker" "start" "-a" "ad702c9b374c3e53700c019f0ab2b1015b01259f841ae587f0bbbcd281101712"` Nov 18 00:11:21.751 INFO [stderr] usermod: no changes Nov 18 00:11:21.965 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.20s Nov 18 00:11:21.983 INFO [stderr] Running /target/debug/deps/rustwt-ccf1822aed36ab1f Nov 18 00:11:21.983 INFO [stdout] Nov 18 00:11:21.983 INFO [stdout] running 23 tests Nov 18 00:11:21.991 INFO [stdout] test id_token::tests::expires_should_be_relative_to_nbf ... ok Nov 18 00:11:21.991 INFO [stdout] test id_token::tests::fail_on_expired_id_token ... ok Nov 18 00:11:21.995 INFO [stdout] test id_token::tests::fail_on_if_token_used_before_nbf ... ok Nov 18 00:11:21.995 INFO [stdout] test id_token::tests::fail_on_incorrect_azp ... ok Nov 18 00:11:21.995 INFO [stdout] test id_token::tests::fail_on_invalid_id_token ... ok Nov 18 00:11:21.995 INFO [stdout] test id_token::tests::encode_and_decode_id_token ... ok Nov 18 00:11:21.995 INFO [stdout] test id_token::tests::fail_on_incorrect_audience ... ok Nov 18 00:11:21.995 INFO [stdout] test id_token::tests::pass_on_correct_azp ... ok Nov 18 00:11:21.996 INFO [stdout] test id_token::tests::fail_on_missing_azp_if_expected ... ok Nov 18 00:11:22.007 INFO [stdout] test id_token::tests::pass_on_correct_acr ... ok Nov 18 00:11:22.007 INFO [stdout] test tests::test_decode_valid_jwt_rs256_and_check_deeply ... ok Nov 18 00:11:22.007 INFO [stdout] test id_token::tests::pass_if_nbf_is_valid ... ok Nov 18 00:11:22.007 INFO [stdout] test tests::test_decode_valid_jwt_hs256 ... ok Nov 18 00:11:22.007 INFO [stdout] test tests::it_should_fail_if_the_rsa_signature_is_invalid ... ok Nov 18 00:11:22.007 INFO [stdout] test id_token::tests::fail_on_wrong_acr ... ok Nov 18 00:11:22.008 INFO [stdout] test id_token::tests::fail_on_missing_acr_if_expected ... ok Nov 18 00:11:22.011 INFO [stdout] test tests::test_encode_and_decode_jwt_ec ... ok Nov 18 00:11:22.015 INFO [stdout] test tests::test_encode_and_decode_jwt_rs256 ... ok Nov 18 00:11:22.027 INFO [stdout] test tests::test_encode_and_decode_jwt_hs512 ... ok Nov 18 00:11:22.027 INFO [stdout] test tests::test_encode_and_decode_jwt_hs384 ... ok Nov 18 00:11:22.031 INFO [stdout] test tests::test_encode_and_decode_jwt_hs256 ... ok Nov 18 00:11:22.031 INFO [stdout] test tests::test_encode_valid_jwt_rs256 ... ok Nov 18 00:11:22.127 INFO [stdout] test id_token::tests::it_should_create_a_valid_id_token ... FAILED Nov 18 00:11:22.127 INFO [stdout] Nov 18 00:11:22.127 INFO [stdout] failures: Nov 18 00:11:22.127 INFO [stdout] Nov 18 00:11:22.127 INFO [stdout] ---- id_token::tests::it_should_create_a_valid_id_token stdout ---- Nov 18 00:11:22.127 INFO [stdout] thread 'id_token::tests::it_should_create_a_valid_id_token' panicked at 'assertion failed: `(left == right)` Nov 18 00:11:22.127 INFO [stdout] left: `1542499881`, Nov 18 00:11:22.127 INFO [stdout] right: `1542499882`', src/id_token.rs:475:9 Nov 18 00:11:22.127 INFO [stdout] stack backtrace: Nov 18 00:11:22.127 INFO [stdout] 0: 0x562a8bab5d03 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h1a944b80a560daa8 Nov 18 00:11:22.127 INFO [stdout] at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Nov 18 00:11:22.127 INFO [stdout] 1: 0x562a8bab03d8 - std::sys_common::backtrace::_print::h18f735b4c3a49bdb Nov 18 00:11:22.127 INFO [stdout] at libstd/sys_common/backtrace.rs:71 Nov 18 00:11:22.127 INFO [stdout] 2: 0x562a8bab4c24 - std::panicking::default_hook::{{closure}}::h97ab3548a215c097 Nov 18 00:11:22.127 INFO [stdout] at libstd/sys_common/backtrace.rs:59 Nov 18 00:11:22.127 INFO [stdout] at libstd/panicking.rs:211 Nov 18 00:11:22.127 INFO [stdout] 3: 0x562a8bab490c - std::panicking::default_hook::h98c2a01ef734e4b0 Nov 18 00:11:22.127 INFO [stdout] at libstd/panicking.rs:221 Nov 18 00:11:22.127 INFO [stdout] 4: 0x562a8bab5330 - std::panicking::rust_panic_with_hook::hb4b3f28eb79ea752 Nov 18 00:11:22.127 INFO [stdout] at libstd/panicking.rs:476 Nov 18 00:11:22.127 INFO [stdout] 5: 0x562a8bab4eb1 - std::panicking::continue_panic_fmt::h61fd5a7b1a268752 Nov 18 00:11:22.127 INFO [stdout] at libstd/panicking.rs:390 Nov 18 00:11:22.127 INFO [stdout] 6: 0x562a8bab4dfe - std::panicking::begin_panic_fmt::hfa6f19d29682fcb6 Nov 18 00:11:22.127 INFO [stdout] at libstd/panicking.rs:345 Nov 18 00:11:22.127 INFO [stdout] 7: 0x562a8b9f3c50 - rustwt::id_token::tests::it_should_create_a_valid_id_token::hac5faf222c830536 Nov 18 00:11:22.127 INFO [stdout] at src/id_token.rs:475 Nov 18 00:11:22.127 INFO [stdout] 8: 0x562a8b9f34d9 - rustwt::id_token::tests::it_should_create_a_valid_id_token::{{closure}}::h6cd701d7d732eb36 Nov 18 00:11:22.127 INFO [stdout] at src/id_token.rs:463 Nov 18 00:11:22.127 INFO [stdout] 9: 0x562a8b9e29cd - core::ops::function::FnOnce::call_once::hd16c15cca103eab9 Nov 18 00:11:22.127 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/ops/function.rs:238 Nov 18 00:11:22.127 INFO [stdout] 10: 0x562a8ba17b3e - >::call_box::h9dcb7fc9b8bb4240 Nov 18 00:11:22.127 INFO [stdout] at libtest/lib.rs:1471 Nov 18 00:11:22.127 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/ops/function.rs:238 Nov 18 00:11:22.127 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673 Nov 18 00:11:22.127 INFO [stdout] 11: 0x562a8bac3a99 - __rust_maybe_catch_panic Nov 18 00:11:22.127 INFO [stdout] at libpanic_unwind/lib.rs:102 Nov 18 00:11:22.127 INFO [stdout] 12: 0x562a8ba0fba3 - std::sys_common::backtrace::__rust_begin_short_backtrace::h89ea8afe71e36f86 Nov 18 00:11:22.127 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289 Nov 18 00:11:22.127 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398 Nov 18 00:11:22.127 INFO [stdout] at libtest/lib.rs:1426 Nov 18 00:11:22.127 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/sys_common/backtrace.rs:136 Nov 18 00:11:22.127 INFO [stdout] 13: 0x562a8ba10a54 - std::panicking::try::do_call::h4132042e0cbea3ae Nov 18 00:11:22.127 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:477 Nov 18 00:11:22.127 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:319 Nov 18 00:11:22.127 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:310 Nov 18 00:11:22.127 INFO [stdout] 14: 0x562a8bac3a99 - __rust_maybe_catch_panic Nov 18 00:11:22.127 INFO [stdout] at libpanic_unwind/lib.rs:102 Nov 18 00:11:22.127 INFO [stdout] 15: 0x562a8ba17c6c - >::call_box::hab7f616f05880bf6 Nov 18 00:11:22.127 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289 Nov 18 00:11:22.127 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398 Nov 18 00:11:22.128 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:476 Nov 18 00:11:22.128 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673 Nov 18 00:11:22.128 INFO [stdout] 16: 0x562a8bab797d - std::sys::unix::thread::Thread::new::thread_start::h5f8d67158a1ca038 Nov 18 00:11:22.128 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:683 Nov 18 00:11:22.128 INFO [stdout] at libstd/sys_common/thread.rs:24 Nov 18 00:11:22.128 INFO [stdout] at libstd/sys/unix/thread.rs:90 Nov 18 00:11:22.128 INFO [stdout] 17: 0x7f3e1130a6b9 - start_thread Nov 18 00:11:22.128 INFO [stdout] 18: 0x7f3e10e2a41c - clone Nov 18 00:11:22.128 INFO [stdout] 19: 0x0 - Nov 18 00:11:22.128 INFO [stdout] Nov 18 00:11:22.128 INFO [stdout] Nov 18 00:11:22.128 INFO [stdout] failures: Nov 18 00:11:22.128 INFO [stdout] id_token::tests::it_should_create_a_valid_id_token Nov 18 00:11:22.128 INFO [stdout] Nov 18 00:11:22.128 INFO [stdout] test result: FAILED. 22 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out Nov 18 00:11:22.128 INFO [stdout] Nov 18 00:11:22.131 INFO [stderr] error: test failed, to rerun pass '--lib' Nov 18 00:11:22.133 INFO [stderr] su: No module specific data is present Nov 18 00:11:23.469 INFO running `"docker" "inspect" "ad702c9b374c3e53700c019f0ab2b1015b01259f841ae587f0bbbcd281101712"` Nov 18 00:11:23.879 INFO running `"docker" "rm" "-f" "ad702c9b374c3e53700c019f0ab2b1015b01259f841ae587f0bbbcd281101712"` Nov 18 00:11:24.275 INFO [stdout] ad702c9b374c3e53700c019f0ab2b1015b01259f841ae587f0bbbcd281101712