Nov 16 19:36:11.280 INFO testing mike-engel/jwt-cli against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 16 19:36:11.280 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 16 19:36:11.755 INFO [stdout] 5b9784e67e0048bf75abb7c4c277d362f0dd8738f5e1a74ccf31a3f1b8f2212f
Nov 16 19:36:11.757 INFO running `"docker" "start" "-a" "5b9784e67e0048bf75abb7c4c277d362f0dd8738f5e1a74ccf31a3f1b8f2212f"`
Nov 16 19:36:13.514 INFO [stderr] usermod: no changes
Nov 16 19:36:13.733 INFO [stderr]    Compiling syn v0.15.18
Nov 16 19:36:23.365 INFO [stderr]    Compiling serde_derive v1.0.80
Nov 16 19:36:39.963 INFO [stderr]    Compiling jsonwebtoken v5.0.1
Nov 16 19:36:42.176 INFO [stderr]    Compiling jwt-cli v2.1.0 (/source)
Nov 16 19:36:47.509 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 33.96s
Nov 16 19:36:47.519 INFO [stderr] su: No module specific data is present
Nov 16 19:36:47.840 INFO running `"docker" "inspect" "5b9784e67e0048bf75abb7c4c277d362f0dd8738f5e1a74ccf31a3f1b8f2212f"`
Nov 16 19:36:47.940 INFO running `"docker" "rm" "-f" "5b9784e67e0048bf75abb7c4c277d362f0dd8738f5e1a74ccf31a3f1b8f2212f"`
Nov 16 19:36:48.037 INFO [stdout] 5b9784e67e0048bf75abb7c4c277d362f0dd8738f5e1a74ccf31a3f1b8f2212f
Nov 16 19:36:48.038 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 16 19:36:48.194 INFO [stdout] 1b7526e3ea47a21ed642f290ce248eb8c50a4a589fd1270f1771d198f33125bf
Nov 16 19:36:48.196 INFO running `"docker" "start" "-a" "1b7526e3ea47a21ed642f290ce248eb8c50a4a589fd1270f1771d198f33125bf"`
Nov 16 19:36:48.859 INFO [stderr] usermod: no changes
Nov 16 19:36:48.943 INFO [stderr]    Compiling jwt-cli v2.1.0 (/source)
Nov 16 19:36:52.782 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.91s
Nov 16 19:36:52.790 INFO [stderr] su: No module specific data is present
Nov 16 19:36:53.449 INFO running `"docker" "inspect" "1b7526e3ea47a21ed642f290ce248eb8c50a4a589fd1270f1771d198f33125bf"`
Nov 16 19:36:53.564 INFO running `"docker" "rm" "-f" "1b7526e3ea47a21ed642f290ce248eb8c50a4a589fd1270f1771d198f33125bf"`
Nov 16 19:36:53.690 INFO [stdout] 1b7526e3ea47a21ed642f290ce248eb8c50a4a589fd1270f1771d198f33125bf
Nov 16 19:36:53.693 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 16 19:36:53.927 INFO [stdout] 1b9904e2dba848785e73186aea193b0b6ae1ffa6e83f040e1d2dbac8e2b821eb
Nov 16 19:36:53.932 INFO running `"docker" "start" "-a" "1b9904e2dba848785e73186aea193b0b6ae1ffa6e83f040e1d2dbac8e2b821eb"`
Nov 16 19:36:54.494 INFO [stderr] usermod: no changes
Nov 16 19:36:54.583 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.07s
Nov 16 19:36:54.595 INFO [stderr]      Running /target/debug/deps/jwt-b228d8346e39b598
Nov 16 19:36:54.598 INFO [stdout] 
Nov 16 19:36:54.598 INFO [stdout] running 0 tests
Nov 16 19:36:54.598 INFO [stdout] 
Nov 16 19:36:54.598 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 19:36:54.598 INFO [stdout] 
Nov 16 19:36:54.600 INFO [stderr]      Running /target/debug/deps/jwt_cli-f5624ebb6b4d06ed
Nov 16 19:36:54.600 INFO [stdout] 
Nov 16 19:36:54.600 INFO [stdout] running 23 tests
Nov 16 19:36:54.607 INFO [stdout] test tests::creates_jwt_header_with_kid ... ok
Nov 16 19:36:54.607 INFO [stdout] test tests::creates_jwt_header_without_kid ... ok
Nov 16 19:36:54.618 INFO [stdout] test tests::decodes_a_token_without_a_secret ... ok
Nov 16 19:36:54.618 INFO [stdout] test tests::adds_iat_exp_automatically ... ok
Nov 16 19:36:54.618 INFO [stdout] test tests::decodes_a_token_with_invalid_secret ... ok
Nov 16 19:36:54.618 INFO [stdout] test tests::decodes_a_token_without_a_typ ... ok
Nov 16 19:36:54.618 INFO [stdout] test tests::decodes_a_token_without_an_alg ... ok
Nov 16 19:36:54.618 INFO [stdout] test tests::is_valid_payload_item ... ok
Nov 16 19:36:54.618 INFO [stdout] test tests::payload_from_payload_items ... ok
Nov 16 19:36:54.618 INFO [stdout] test tests::payload_item_from_none ... ok
Nov 16 19:36:54.618 INFO [stdout] test tests::payload_item_from_none_with_name ... ok
Nov 16 19:36:54.621 INFO [stdout] test tests::payload_item_from_string ... ok
Nov 16 19:36:54.621 INFO [stdout] test tests::decodes_a_token_as_json ... ok
Nov 16 19:36:54.621 INFO [stdout] test tests::payload_item_from_string_with_name ... ok
Nov 16 19:36:54.630 INFO [stdout] test tests::split_payload_item ... ok
Nov 16 19:36:54.630 INFO [stdout] test tests::is_invalid_num ... ok
Nov 16 19:36:54.630 INFO [stdout] test tests::is_invalid_payload_item ... ok
Nov 16 19:36:54.630 INFO [stdout] test tests::is_valid_num ... ok
Nov 16 19:36:54.632 INFO [stdout] test tests::translates_algorithm ... ok
Nov 16 19:36:54.632 INFO [stdout] test tests::supported_algorithm_from_string ... ok
Nov 16 19:36:54.635 INFO [stdout] test tests::encodes_a_token ... ok
Nov 16 19:36:54.639 INFO [stdout] test tests::allows_for_a_custom_exp ... ok
Nov 16 19:36:54.691 INFO [stdout] test tests::decodes_a_token ... FAILED
Nov 16 19:36:54.691 INFO [stdout] 
Nov 16 19:36:54.691 INFO [stdout] failures:
Nov 16 19:36:54.691 INFO [stdout] 
Nov 16 19:36:54.691 INFO [stdout] ---- tests::decodes_a_token stdout ----
Nov 16 19:36:54.691 INFO [stdout] thread 'tests::decodes_a_token' panicked at 'assertion failed: result.is_ok()', tests/jwt-cli.rs:309:9
Nov 16 19:36:54.691 INFO [stdout] stack backtrace:
Nov 16 19:36:54.691 INFO [stdout]    0:     0x55c9835b50d3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h26df0f6fdb27c709
Nov 16 19:36:54.691 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 16 19:36:54.691 INFO [stdout]    1:     0x55c9835af938 - std::sys_common::backtrace::_print::h84c40fecda6d638e
Nov 16 19:36:54.691 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Nov 16 19:36:54.691 INFO [stdout]    2:     0x55c9835b4044 - std::panicking::default_hook::{{closure}}::h77e370c0567fc0a7
Nov 16 19:36:54.691 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Nov 16 19:36:54.691 INFO [stdout]                                at libstd/panicking.rs:211
Nov 16 19:36:54.691 INFO [stdout]    3:     0x55c9835b3d2c - std::panicking::default_hook::hb9a45c11a33bbcb2
Nov 16 19:36:54.691 INFO [stdout]                                at libstd/panicking.rs:221
Nov 16 19:36:54.691 INFO [stdout]    4:     0x55c9835b4750 - std::panicking::rust_panic_with_hook::ha8d95048d940f754
Nov 16 19:36:54.691 INFO [stdout]                                at libstd/panicking.rs:476
Nov 16 19:36:54.691 INFO [stdout]    5:     0x55c983591697 - std::panicking::begin_panic::hdff646dce40e8acd
Nov 16 19:36:54.691 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:410
Nov 16 19:36:54.691 INFO [stdout]    6:     0x55c9832d62a7 - jwt_cli::tests::decodes_a_token::hc97e569e87f30059
Nov 16 19:36:54.691 INFO [stdout]                                at tests/jwt-cli.rs:309
Nov 16 19:36:54.691 INFO [stdout]    7:     0x55c9832d6079 - jwt_cli::tests::decodes_a_token::{{closure}}::h71333183cf36e049
Nov 16 19:36:54.691 INFO [stdout]                                at tests/jwt-cli.rs:294
Nov 16 19:36:54.691 INFO [stdout]    8:     0x55c983302bed - core::ops::function::FnOnce::call_once::hfff721a9fbd845c9
Nov 16 19:36:54.691 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libcore/ops/function.rs:238
Nov 16 19:36:54.691 INFO [stdout]    9:     0x55c98331998e - <F as alloc::boxed::FnBox<A>>::call_box::hae1ea457a0ccd8d5
Nov 16 19:36:54.691 INFO [stdout]                                at libtest/lib.rs:1471
Nov 16 19:36:54.691 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libcore/ops/function.rs:238
Nov 16 19:36:54.691 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:673
Nov 16 19:36:54.691 INFO [stdout]   10:     0x55c9835c2fb9 - __rust_maybe_catch_panic
Nov 16 19:36:54.691 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 16 19:36:54.691 INFO [stdout]   11:     0x55c9833117e3 - std::sys_common::backtrace::__rust_begin_short_backtrace::hd0ded23d435d70d0
Nov 16 19:36:54.691 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:289
Nov 16 19:36:54.691 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:398
Nov 16 19:36:54.691 INFO [stdout]                                at libtest/lib.rs:1426
Nov 16 19:36:54.691 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/sys_common/backtrace.rs:136
Nov 16 19:36:54.691 INFO [stdout]   12:     0x55c983312684 - std::panicking::try::do_call::h031514fb401b7159
Nov 16 19:36:54.691 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/thread/mod.rs:477
Nov 16 19:36:54.692 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:319
Nov 16 19:36:54.692 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:310
Nov 16 19:36:54.692 INFO [stdout]   13:     0x55c9835c2fb9 - __rust_maybe_catch_panic
Nov 16 19:36:54.692 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 16 19:36:54.692 INFO [stdout]   14:     0x55c98331986c - <F as alloc::boxed::FnBox<A>>::call_box::h44700b7a2ec6fd33
Nov 16 19:36:54.692 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:289
Nov 16 19:36:54.692 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:398
Nov 16 19:36:54.692 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/thread/mod.rs:476
Nov 16 19:36:54.692 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:673
Nov 16 19:36:54.692 INFO [stdout]   15:     0x55c9835b6e3d - std::sys::unix::thread::Thread::new::thread_start::h8526625243d2f13b
Nov 16 19:36:54.692 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:683
Nov 16 19:36:54.692 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Nov 16 19:36:54.692 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Nov 16 19:36:54.692 INFO [stdout]   16:     0x7f826d5c96b9 - start_thread
Nov 16 19:36:54.692 INFO [stdout]   17:     0x7f826d0e941c - clone
Nov 16 19:36:54.692 INFO [stdout]   18:                0x0 - <unknown>
Nov 16 19:36:54.692 INFO [stdout] 
Nov 16 19:36:54.692 INFO [stdout] 
Nov 16 19:36:54.692 INFO [stdout] failures:
Nov 16 19:36:54.692 INFO [stdout]     tests::decodes_a_token
Nov 16 19:36:54.692 INFO [stdout] 
Nov 16 19:36:54.692 INFO [stdout] test result: FAILED. 22 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 19:36:54.692 INFO [stdout] 
Nov 16 19:36:54.702 INFO [stderr] error: test failed, to rerun pass '--test jwt-cli'
Nov 16 19:36:54.703 INFO [stderr] su: No module specific data is present
Nov 16 19:36:54.985 INFO running `"docker" "inspect" "1b9904e2dba848785e73186aea193b0b6ae1ffa6e83f040e1d2dbac8e2b821eb"`
Nov 16 19:36:55.084 INFO running `"docker" "rm" "-f" "1b9904e2dba848785e73186aea193b0b6ae1ffa6e83f040e1d2dbac8e2b821eb"`
Nov 16 19:36:55.241 INFO [stdout] 1b9904e2dba848785e73186aea193b0b6ae1ffa6e83f040e1d2dbac8e2b821eb