Dec 06 04:15:01.209 INFO testing ccheek21/topd against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 06 04:15:01.209 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 04:15:01.366 INFO [stdout] 6b114106538530a62ae283d88afc9122fa49cad0d23e1bf89ba0a98e35712099
Dec 06 04:15:01.370 INFO running `"docker" "start" "-a" "6b114106538530a62ae283d88afc9122fa49cad0d23e1bf89ba0a98e35712099"`
Dec 06 04:15:02.288 INFO [stderr] usermod: no changes
Dec 06 04:15:02.568 INFO [stderr]    Compiling serde v1.0.80
Dec 06 04:15:02.596 INFO [stderr]    Compiling path-dedot v1.1.6
Dec 06 04:15:02.600 INFO [stderr]    Compiling float-cmp v0.4.0
Dec 06 04:15:03.732 INFO [stderr]    Compiling predicates v1.0.0
Dec 06 04:15:03.844 INFO [stderr]    Compiling path-absolutize v1.1.1
Dec 06 04:15:15.950 INFO [stderr]    Compiling serde_json v1.0.33
Dec 06 04:15:15.956 INFO [stderr]    Compiling chrono v0.4.6
Dec 06 04:15:22.311 INFO [stderr]    Compiling escargot v0.3.1
Dec 06 04:15:24.714 INFO [stderr]    Compiling assert_cmd v0.10.1
Dec 06 04:15:27.156 INFO [stderr]    Compiling fe v0.1.0 (/source)
Dec 06 04:15:34.046 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 31.73s
Dec 06 04:15:34.062 INFO [stderr] su: No module specific data is present
Dec 06 04:15:34.648 INFO running `"docker" "inspect" "6b114106538530a62ae283d88afc9122fa49cad0d23e1bf89ba0a98e35712099"`
Dec 06 04:15:34.775 INFO running `"docker" "rm" "-f" "6b114106538530a62ae283d88afc9122fa49cad0d23e1bf89ba0a98e35712099"`
Dec 06 04:15:34.871 INFO [stdout] 6b114106538530a62ae283d88afc9122fa49cad0d23e1bf89ba0a98e35712099
Dec 06 04:15:34.877 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 04:15:35.147 INFO [stdout] 575f21ceb87892e447f52ac533c7cecfb7a883bab04344f3658bd1c6d15a34c1
Dec 06 04:15:35.150 INFO running `"docker" "start" "-a" "575f21ceb87892e447f52ac533c7cecfb7a883bab04344f3658bd1c6d15a34c1"`
Dec 06 04:15:35.614 INFO [stderr] usermod: no changes
Dec 06 04:15:35.773 INFO [stderr]    Compiling fe v0.1.0 (/source)
Dec 06 04:15:39.966 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 4.33s
Dec 06 04:15:39.975 INFO [stderr] su: No module specific data is present
Dec 06 04:15:40.650 INFO running `"docker" "inspect" "575f21ceb87892e447f52ac533c7cecfb7a883bab04344f3658bd1c6d15a34c1"`
Dec 06 04:15:40.926 INFO running `"docker" "rm" "-f" "575f21ceb87892e447f52ac533c7cecfb7a883bab04344f3658bd1c6d15a34c1"`
Dec 06 04:15:41.208 INFO [stdout] 575f21ceb87892e447f52ac533c7cecfb7a883bab04344f3658bd1c6d15a34c1
Dec 06 04:15:41.214 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 04:15:41.570 INFO [stdout] 58b036cf9d9ec53be32083c5175294aac0f80ffa759777ab90e9f85bb57e66e2
Dec 06 04:15:41.575 INFO running `"docker" "start" "-a" "58b036cf9d9ec53be32083c5175294aac0f80ffa759777ab90e9f85bb57e66e2"`
Dec 06 04:15:42.480 INFO [stderr] usermod: no changes
Dec 06 04:15:42.656 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.14s
Dec 06 04:15:42.672 INFO [stderr]      Running /target/debug/deps/fe-ef7fb0bd3b595a69
Dec 06 04:15:42.681 INFO [stdout] 
Dec 06 04:15:42.681 INFO [stdout] running 32 tests
Dec 06 04:15:42.701 INFO [stdout] test stats::tests::compare_with_func ... ok
Dec 06 04:15:42.701 INFO [stdout] test stats::tests::compare_with_enum ... ok
Dec 06 04:15:42.701 INFO [stdout] test args::tests::get_store_path_file ... ok
Dec 06 04:15:42.703 INFO [stdout] test args::tests::get_store_path_full ... ok
Dec 06 04:15:42.703 INFO [stdout] test stats::tests::get_frecency_one_half_life ... ok
Dec 06 04:15:42.703 INFO [stdout] test stats::tests::get_frecency_two_half_lives ... ok
Dec 06 04:15:42.703 INFO [stdout] test stats::tests::new_path_stats ... ok
Dec 06 04:15:42.703 INFO [stdout] test stats::tests::update_last_access ... ok
Dec 06 04:15:42.703 INFO [stdout] test stats::tests::to_string_no_stats ... ok
Dec 06 04:15:42.703 INFO [stdout] test stats::tests::set_half_life ... ok
Dec 06 04:15:42.703 INFO [stdout] test stats::tests::to_string_stats ... ok
Dec 06 04:15:42.703 INFO [stdout] test stats::tests::update_num_accesses ... ok
Dec 06 04:15:42.703 INFO [stdout] test stats::tests::update_score ... ok
Dec 06 04:15:42.703 INFO [stdout] test store::tests::add_existing ... ok
Dec 06 04:15:42.703 INFO [stdout] test store::tests::get_exists ... ok
Dec 06 04:15:42.703 INFO [stdout] test store::tests::adjust_existing ... ok
Dec 06 04:15:42.703 INFO [stdout] test store::tests::add_new ... ok
Dec 06 04:15:42.703 INFO [stdout] test store::tests::adjust_new ... ok
Dec 06 04:15:42.703 INFO [stdout] test store::tests::get_not_exists ... ok
Dec 06 04:15:42.704 INFO [stdout] test stats::tests::secs_elapsed_one_second ... ok
Dec 06 04:15:42.704 INFO [stdout] test store::tests::purge_exists ... ok
Dec 06 04:15:42.704 INFO [stdout] test store::tests::purge_not_exists ... ok
Dec 06 04:15:42.704 INFO [stdout] test store::tests::reset_time ... ok
Dec 06 04:15:42.704 INFO [stdout] test store::tests::set_halflife ... ok
Dec 06 04:15:42.704 INFO [stdout] test store::tests::sorted_frecent ... ok
Dec 06 04:15:42.704 INFO [stdout] test stats::tests::secs_since_access ... ok
Dec 06 04:15:42.704 INFO [stdout] test store::tests::sorted_frecent2 ... ok
Dec 06 04:15:42.704 INFO [stdout] test stats::tests::reset_time ... ok
Dec 06 04:15:42.712 INFO [stdout] test store::tests::sorted_recent ... ok
Dec 06 04:15:42.712 INFO [stdout] test store::tests::truncate_greater ... ok
Dec 06 04:15:42.712 INFO [stdout] test store::tests::truncate_less ... ok
Dec 06 04:15:42.712 INFO [stdout] test store::tests::sorted_frequent ... ok
Dec 06 04:15:42.712 INFO [stdout] 
Dec 06 04:15:42.712 INFO [stdout] test result: ok. 32 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 04:15:42.712 INFO [stdout] 
Dec 06 04:15:42.712 INFO [stderr]      Running /target/debug/deps/fe-0f25c242849bfe81
Dec 06 04:15:42.720 INFO [stdout] 
Dec 06 04:15:42.720 INFO [stdout] running 0 tests
Dec 06 04:15:42.720 INFO [stdout] 
Dec 06 04:15:42.720 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 04:15:42.720 INFO [stdout] 
Dec 06 04:15:42.720 INFO [stderr]      Running /target/debug/deps/lib-b0f35ccd463d27ee
Dec 06 04:15:42.727 INFO [stdout] 
Dec 06 04:15:42.728 INFO [stdout] running 23 tests
Dec 06 04:16:42.728 INFO [stdout] test integration::errors::invalid_store ... test integration::errors::invalid_store has been running for over 60 seconds
Dec 06 04:16:42.728 INFO [stdout] test integration::errors::non_writable ... test integration::errors::non_writable has been running for over 60 seconds
Dec 06 04:16:42.728 INFO [stdout] test integration::flags::version_long ... test integration::flags::version_long has been running for over 60 seconds
Dec 06 04:16:42.728 INFO [stdout] test integration::flags::purge ... test integration::flags::purge has been running for over 60 seconds
Dec 06 04:16:42.728 INFO [stdout] test integration::flags::version_short ... test integration::flags::version_short has been running for over 60 seconds
Dec 06 04:16:42.728 INFO [stdout] test integration::sort::change_half_life_new_decay ... test integration::sort::change_half_life_new_decay has been running for over 60 seconds
Dec 06 04:16:42.728 INFO [stdout] test integration::sort::change_half_life_maintain_frecency ... test integration::sort::change_half_life_maintain_frecency has been running for over 60 seconds
Dec 06 04:16:42.728 INFO [stdout] test integration::sort::limit ... test integration::sort::limit has been running for over 60 seconds
Dec 06 04:17:05.151 INFO [stdout] test integration::errors::non_writable ... ok
Dec 06 04:17:05.308 INFO [stdout] test integration::errors::invalid_store ... ok
Dec 06 04:17:05.393 INFO [stdout] test integration::flags::version_long ... ok
Dec 06 04:17:05.648 INFO [stdout] test integration::sort::limit ... ok
Dec 06 04:17:05.984 INFO [stdout] test integration::flags::version_short ... ok
Dec 06 04:17:06.296 INFO [stdout] test integration::sort::limit_too_many ... ok
Dec 06 04:17:06.348 INFO [stdout] test integration::sort::sorted_frecent ... ok
Dec 06 04:17:06.545 INFO [stdout] test integration::sort::sorted_frequent ... ok
Dec 06 04:17:06.716 INFO [stdout] test integration::sort::sorted_invalid ... ok
Dec 06 04:17:06.840 INFO [stdout] test integration::sort::sorted_recent ... ok
Dec 06 04:17:06.947 INFO [stdout] test integration::sort::change_half_life_new_decay ... ok
Dec 06 04:17:07.000 INFO [stdout] test integration::flags::purge ... ok
Dec 06 04:17:07.112 INFO [stdout] test integration::sort::change_half_life_maintain_frecency ... ok
Dec 06 04:17:07.138 INFO [stdout] test integration::sort::sorted_stats ... ok
Dec 06 04:17:08.048 INFO [stdout] test integration::weight::decrease_accesses ... ok
Dec 06 04:17:08.180 INFO [stdout] test integration::sort::truncate ... ok
Dec 06 04:17:08.332 INFO [stdout] test integration::weight::add_existing_exists ... ok
Dec 06 04:17:08.421 INFO [stdout] test integration::weight::add_existing_increases ... ok
Dec 06 04:17:08.760 INFO [stdout] test integration::weight::decrease_score ... ok
Dec 06 04:17:08.831 INFO [stdout] test integration::weight::add_relative ... ok
Dec 06 04:17:08.973 INFO [stdout] test integration::weight::increase_accesses ... ok
Dec 06 04:17:09.044 INFO [stdout] test integration::weight::add_create ... ok
Dec 06 04:17:09.076 INFO [stdout] test integration::weight::increase_score ... ok
Dec 06 04:17:09.076 INFO [stdout] 
Dec 06 04:17:09.076 INFO [stdout] test result: ok. 23 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 04:17:09.076 INFO [stdout] 
Dec 06 04:17:09.084 INFO [stderr]    Doc-tests fe
Dec 06 04:17:10.600 INFO [stdout] 
Dec 06 04:17:10.600 INFO [stdout] running 0 tests
Dec 06 04:17:10.600 INFO [stdout] 
Dec 06 04:17:10.600 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 04:17:10.600 INFO [stdout] 
Dec 06 04:17:10.616 INFO [stderr] su: No module specific data is present
Dec 06 04:17:11.106 INFO running `"docker" "inspect" "58b036cf9d9ec53be32083c5175294aac0f80ffa759777ab90e9f85bb57e66e2"`
Dec 06 04:17:11.398 INFO running `"docker" "rm" "-f" "58b036cf9d9ec53be32083c5175294aac0f80ffa759777ab90e9f85bb57e66e2"`
Dec 06 04:17:11.751 INFO [stdout] 58b036cf9d9ec53be32083c5175294aac0f80ffa759777ab90e9f85bb57e66e2