Nov 18 11:05:10.202 INFO testing google-jwt-verify-0.1.0 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 18 11:05:10.207 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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 11:05:10.608 INFO [stdout] 218d3535cc04c0e70d2c705c120f217c981702b6afd08dfd4d42efa8e8ddcaa6
Nov 18 11:05:10.610 INFO running `"docker" "start" "-a" "218d3535cc04c0e70d2c705c120f217c981702b6afd08dfd4d42efa8e8ddcaa6"`
Nov 18 11:05:12.439 INFO [stderr] usermod: no changes
Nov 18 11:05:12.891 INFO [stderr]    Compiling google-jwt-verify v0.1.0 (/source)
Nov 18 11:05:23.111 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.65s
Nov 18 11:05:23.133 INFO [stderr] su: No module specific data is present
Nov 18 11:05:24.332 INFO running `"docker" "inspect" "218d3535cc04c0e70d2c705c120f217c981702b6afd08dfd4d42efa8e8ddcaa6"`
Nov 18 11:05:24.689 INFO running `"docker" "rm" "-f" "218d3535cc04c0e70d2c705c120f217c981702b6afd08dfd4d42efa8e8ddcaa6"`
Nov 18 11:05:25.019 INFO [stdout] 218d3535cc04c0e70d2c705c120f217c981702b6afd08dfd4d42efa8e8ddcaa6
Nov 18 11:05:25.028 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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 11:05:25.435 INFO [stdout] 687b73fba72d8de1ac9072814ec2ea1c0448ff609bd20eed10f049a2b54a79cc
Nov 18 11:05:25.441 INFO running `"docker" "start" "-a" "687b73fba72d8de1ac9072814ec2ea1c0448ff609bd20eed10f049a2b54a79cc"`
Nov 18 11:05:27.099 INFO [stderr] usermod: no changes
Nov 18 11:05:27.495 INFO [stderr]    Compiling google-jwt-verify v0.1.0 (/source)
Nov 18 11:05:39.575 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 12.45s
Nov 18 11:05:39.599 INFO [stderr] su: No module specific data is present
Nov 18 11:05:40.618 INFO running `"docker" "inspect" "687b73fba72d8de1ac9072814ec2ea1c0448ff609bd20eed10f049a2b54a79cc"`
Nov 18 11:05:40.945 INFO running `"docker" "rm" "-f" "687b73fba72d8de1ac9072814ec2ea1c0448ff609bd20eed10f049a2b54a79cc"`
Nov 18 11:05:41.255 INFO [stdout] 687b73fba72d8de1ac9072814ec2ea1c0448ff609bd20eed10f049a2b54a79cc
Nov 18 11:05:41.262 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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 11:05:41.671 INFO [stdout] 68e35728e2b308fbaa14adb21d37db721dd76c05329a992d4140e2b891709361
Nov 18 11:05:41.677 INFO running `"docker" "start" "-a" "68e35728e2b308fbaa14adb21d37db721dd76c05329a992d4140e2b891709361"`
Nov 18 11:05:43.271 INFO [stderr] usermod: no changes
Nov 18 11:05:43.523 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.23s
Nov 18 11:05:43.539 INFO [stderr]      Running /target/debug/deps/google_jwt_verify-a16d7f4d95158dae
Nov 18 11:05:43.559 INFO [stdout] 
Nov 18 11:05:43.559 INFO [stdout] running 5 tests
Nov 18 11:05:43.559 INFO [stdout] test test::decode_keys ... ok
Nov 18 11:05:43.671 INFO [stdout] test test::test_client_invalid_client_id ... ok
Nov 18 11:05:43.671 INFO [stdout] test test::test_id_token ... ok
Nov 18 11:05:43.671 INFO [stdout] test test::test_client ... ok
Nov 18 11:05:43.899 INFO [stdout] test key_provider::test_google_provider ... FAILED
Nov 18 11:05:43.899 INFO [stdout] 
Nov 18 11:05:43.899 INFO [stdout] failures:
Nov 18 11:05:43.899 INFO [stdout] 
Nov 18 11:05:43.899 INFO [stdout] ---- key_provider::test_google_provider stdout ----
Nov 18 11:05:43.899 INFO [stdout] thread 'key_provider::test_google_provider' panicked at 'assertion failed: provider.get_key("test").is_ok()', src/key_provider.rs:60:5
Nov 18 11:05:43.899 INFO [stdout] stack backtrace:
Nov 18 11:05:43.899 INFO [stdout]    0:     0x559ac5d51573 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h26df0f6fdb27c709
Nov 18 11:05:43.899 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 18 11:05:43.899 INFO [stdout]    1:     0x559ac5d4b8e8 - std::sys_common::backtrace::_print::h84c40fecda6d638e
Nov 18 11:05:43.899 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Nov 18 11:05:43.899 INFO [stdout]    2:     0x559ac5d50004 - std::panicking::default_hook::{{closure}}::h77e370c0567fc0a7
Nov 18 11:05:43.899 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Nov 18 11:05:43.899 INFO [stdout]                                at libstd/panicking.rs:211
Nov 18 11:05:43.899 INFO [stdout]    3:     0x559ac5d4fcec - std::panicking::default_hook::hb9a45c11a33bbcb2
Nov 18 11:05:43.899 INFO [stdout]                                at libstd/panicking.rs:221
Nov 18 11:05:43.899 INFO [stdout]    4:     0x559ac5d50710 - std::panicking::rust_panic_with_hook::ha8d95048d940f754
Nov 18 11:05:43.899 INFO [stdout]                                at libstd/panicking.rs:476
Nov 18 11:05:43.899 INFO [stdout]    5:     0x559ac5d134a7 - std::panicking::begin_panic::ha9cd2193c8a08742
Nov 18 11:05:43.899 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:410
Nov 18 11:05:43.899 INFO [stdout]    6:     0x559ac5874c82 - google_jwt_verify::key_provider::test_google_provider::hd77e77d498a59f59
Nov 18 11:05:43.899 INFO [stdout]                                at src/key_provider.rs:60
Nov 18 11:05:43.899 INFO [stdout]    7:     0x559ac5874bc9 - google_jwt_verify::key_provider::test_google_provider::{{closure}}::h0ef21b4d7c2342e1
Nov 18 11:05:43.899 INFO [stdout]                                at src/key_provider.rs:58
Nov 18 11:05:43.899 INFO [stdout]    8:     0x559ac588b1ed - core::ops::function::FnOnce::call_once::habd8966203513f8e
Nov 18 11:05:43.899 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libcore/ops/function.rs:238
Nov 18 11:05:43.899 INFO [stdout]    9:     0x559ac589e4de - <F as alloc::boxed::FnBox<A>>::call_box::hae1ea457a0ccd8d5
Nov 18 11:05:43.899 INFO [stdout]                                at libtest/lib.rs:1471
Nov 18 11:05:43.899 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libcore/ops/function.rs:238
Nov 18 11:05:43.899 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:673
Nov 18 11:05:43.899 INFO [stdout]   10:     0x559ac5d5f979 - __rust_maybe_catch_panic
Nov 18 11:05:43.899 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 18 11:05:43.899 INFO [stdout]   11:     0x559ac5896333 - std::sys_common::backtrace::__rust_begin_short_backtrace::hd0ded23d435d70d0
Nov 18 11:05:43.899 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:289
Nov 18 11:05:43.899 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:398
Nov 18 11:05:43.899 INFO [stdout]                                at libtest/lib.rs:1426
Nov 18 11:05:43.899 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/sys_common/backtrace.rs:136
Nov 18 11:05:43.899 INFO [stdout]   12:     0x559ac58971d4 - std::panicking::try::do_call::h031514fb401b7159
Nov 18 11:05:43.899 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/thread/mod.rs:477
Nov 18 11:05:43.899 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:319
Nov 18 11:05:43.900 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:310
Nov 18 11:05:43.900 INFO [stdout]   13:     0x559ac5d5f979 - __rust_maybe_catch_panic
Nov 18 11:05:43.900 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 18 11:05:43.900 INFO [stdout]   14:     0x559ac589e3bc - <F as alloc::boxed::FnBox<A>>::call_box::h44700b7a2ec6fd33
Nov 18 11:05:43.900 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:289
Nov 18 11:05:43.900 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:398
Nov 18 11:05:43.900 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/thread/mod.rs:476
Nov 18 11:05:43.900 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:673
Nov 18 11:05:43.900 INFO [stdout]   15:     0x559ac5d5349d - std::sys::unix::thread::Thread::new::thread_start::h8526625243d2f13b
Nov 18 11:05:43.900 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:683
Nov 18 11:05:43.900 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Nov 18 11:05:43.900 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Nov 18 11:05:43.900 INFO [stdout]   16:     0x7f38b1c226b9 - start_thread
Nov 18 11:05:43.900 INFO [stdout]   17:     0x7f38b174241c - clone
Nov 18 11:05:43.900 INFO [stdout]   18:                0x0 - <unknown>
Nov 18 11:05:43.900 INFO [stdout] 
Nov 18 11:05:43.900 INFO [stdout] 
Nov 18 11:05:43.900 INFO [stdout] failures:
Nov 18 11:05:43.900 INFO [stdout]     key_provider::test_google_provider
Nov 18 11:05:43.900 INFO [stdout] 
Nov 18 11:05:43.900 INFO [stdout] test result: FAILED. 4 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 11:05:43.900 INFO [stdout] 
Nov 18 11:05:43.911 INFO [stderr] error: test failed, to rerun pass '--lib'
Nov 18 11:05:43.914 INFO [stderr] su: No module specific data is present
Nov 18 11:05:44.999 INFO running `"docker" "inspect" "68e35728e2b308fbaa14adb21d37db721dd76c05329a992d4140e2b891709361"`
Nov 18 11:05:45.309 INFO running `"docker" "rm" "-f" "68e35728e2b308fbaa14adb21d37db721dd76c05329a992d4140e2b891709361"`
Nov 18 11:05:45.575 INFO [stdout] 68e35728e2b308fbaa14adb21d37db721dd76c05329a992d4140e2b891709361