Dec 13 02:57:44.417 INFO testing skyscribe/pr-transfer-analyzer against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 13 02:57:44.417 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 13 02:57:44.924 INFO [stdout] 535e01f8c4af78a6331bc99fc540500cc963901fd1ec39ec2f96a6bf8a53b8c7
Dec 13 02:57:44.926 INFO running `"docker" "start" "-a" "535e01f8c4af78a6331bc99fc540500cc963901fd1ec39ec2f96a6bf8a53b8c7"`
Dec 13 02:57:45.994 INFO [stderr] usermod: no changes
Dec 13 02:57:46.125 INFO [stderr]    Compiling regex-syntax v0.6.3
Dec 13 02:57:46.160 INFO [stderr]    Compiling csv-core v0.1.4
Dec 13 02:57:46.160 INFO [stderr]    Compiling syn v0.15.20
Dec 13 02:57:47.520 INFO [stderr]    Compiling csv v1.0.2
Dec 13 02:58:02.207 INFO [stderr]    Compiling serde_derive v1.0.80
Dec 13 02:58:03.386 INFO [stderr]    Compiling regex v1.0.6
Dec 13 02:58:15.712 INFO [stderr]    Compiling flexi_logger v0.9.3
Dec 13 02:58:28.192 INFO [stderr]    Compiling analyzer v0.1.0 (/source)
Dec 13 02:58:35.768 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 49.73s
Dec 13 02:58:35.774 INFO [stderr] su: No module specific data is present
Dec 13 02:58:37.734 INFO running `"docker" "inspect" "535e01f8c4af78a6331bc99fc540500cc963901fd1ec39ec2f96a6bf8a53b8c7"`
Dec 13 02:58:38.119 INFO running `"docker" "rm" "-f" "535e01f8c4af78a6331bc99fc540500cc963901fd1ec39ec2f96a6bf8a53b8c7"`
Dec 13 02:58:38.463 INFO [stdout] 535e01f8c4af78a6331bc99fc540500cc963901fd1ec39ec2f96a6bf8a53b8c7
Dec 13 02:58:38.465 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 13 02:58:38.996 INFO [stdout] b43d09e71a0485127a6f51c0e731e2d307bebca168f8625ac6a4df16c99df946
Dec 13 02:58:38.998 INFO running `"docker" "start" "-a" "b43d09e71a0485127a6f51c0e731e2d307bebca168f8625ac6a4df16c99df946"`
Dec 13 02:58:41.147 INFO [stderr] usermod: no changes
Dec 13 02:58:41.318 INFO [stderr]    Compiling analyzer v0.1.0 (/source)
Dec 13 02:58:46.987 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.80s
Dec 13 02:58:46.990 INFO [stderr] su: No module specific data is present
Dec 13 02:58:48.137 INFO running `"docker" "inspect" "b43d09e71a0485127a6f51c0e731e2d307bebca168f8625ac6a4df16c99df946"`
Dec 13 02:58:48.387 INFO running `"docker" "rm" "-f" "b43d09e71a0485127a6f51c0e731e2d307bebca168f8625ac6a4df16c99df946"`
Dec 13 02:58:48.735 INFO [stdout] b43d09e71a0485127a6f51c0e731e2d307bebca168f8625ac6a4df16c99df946
Dec 13 02:58:48.737 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 13 02:58:49.249 INFO [stdout] 9ce0853751075ee65f68ce44206da43fbdb4ead38a370b4e526ba79725d0e4a6
Dec 13 02:58:49.251 INFO running `"docker" "start" "-a" "9ce0853751075ee65f68ce44206da43fbdb4ead38a370b4e526ba79725d0e4a6"`
Dec 13 02:58:51.587 INFO [stderr] usermod: no changes
Dec 13 02:58:51.747 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.12s
Dec 13 02:58:51.755 INFO [stderr]      Running /target/debug/deps/analyzer-c2e29b1eca81544c
Dec 13 02:58:51.759 INFO [stdout] 
Dec 13 02:58:51.759 INFO [stdout] running 37 tests
Dec 13 02:58:51.759 INFO [stdout] test parser::field::group::tests::should_found_group_in_category ... ok
Dec 13 02:58:51.763 INFO [stdout] test parser::field::group::tests::should_check_one_as_pool_group_as_in_same_group ... ok
Dec 13 02:58:51.763 INFO [stdout] test parser::field::group::tests::should_check_in_same_pool_as_same_group ... ok
Dec 13 02:58:51.763 INFO [stdout] test parser::field::group::tests::should_check_differnt_groups_in_same_pool_as_in_different_groups ... ok
Dec 13 02:58:51.763 INFO [stdout] test parser::attachcheck::tests::should_set_interested_as_main_when_first_as_not_intersted ... ok
Dec 13 02:58:51.771 INFO [stdout] test parser::attachcheck::tests::should_parse_attached_list_for_non_paired_attach ... ok
Dec 13 02:58:51.771 INFO [stdout] test parser::field::rdinfo::tests::should_prune_malformed_author ... ok
Dec 13 02:58:51.784 INFO [stdout] test parser::attachcheck::tests::should_parse_attached_list_for_chained ... ok
Dec 13 02:58:51.795 INFO [stdout] test parser::field::rdinfo::tests::should_parse_checker_info_pattern3 ... ok
Dec 13 02:58:51.795 INFO [stdout] test parser::field::rdinfo::tests::should_parse_checker_info_pattern2 ... ok
Dec 13 02:58:51.795 INFO [stdout] test parser::field::rdinfo::tests::should_parse_checker_info_pattern1 ... ok
Dec 13 02:58:51.815 INFO [stdout] test parser::field::rdinfo::tests::should_parse_checker_info_pattern1_1 ... ok
Dec 13 02:58:51.895 INFO [stdout] test parser::field::revisionitem::test::should_parse_attach_changes ... ok
Dec 13 02:58:51.895 INFO [stdout] test parser::field::revisionitem::test::should_parse_postpone_and_reactivate_pairs ... ok
Dec 13 02:58:51.912 INFO [stdout] test parser::field::revisions::tests::get_transfer_by_author_should_return_earliest ... ok
Dec 13 02:58:51.927 INFO [stdout] test parser::field::revisions::tests::should_check_normal_group_transfer ... ok
Dec 13 02:58:51.936 INFO [stdout] test parser::field::revisionitem::test::should_parse_attach_and_detach_pairs ... ok
Dec 13 02:58:51.949 INFO [stdout] test parser::field::revisionitem::test::should_parse_manual_state_change_than_transfer ... ok
Dec 13 02:58:51.974 INFO [stdout] test parser::field::revisions::tests::get_transfer_by_author_should_skip_pool_transfer ... ok
Dec 13 02:58:51.995 INFO [stdout] test parser::field::revisions::tests::get_transfer_by_grp_should_return_latest_item ... ok
Dec 13 02:58:52.027 INFO [stdout] test parser::field::revisions::tests::should_get_correct_transfer_count ... ok
Dec 13 02:58:52.044 INFO [stdout] test parser::field::revisions::tests::should_get_first_valid_transfer_when_there_are_pool_transfers ... ok
Dec 13 02:58:52.059 INFO [stdout] test parser::field::revisions::tests::should_get_empty_first_when_no_group_change ... ok
Dec 13 02:58:52.059 INFO [stdout] test parser::field::revisionitem::test::should_parse_reopen_as_state_changed ... ok
Dec 13 02:58:52.087 INFO [stdout] test parser::field::revisions::tests::get_last_final_record_should_return_none_for_ongoing ... ok
Dec 13 02:58:52.127 INFO [stdout] test parser::field::revisions::tests::should_get_last_closed_record_for_finally_cnn ... ok
Dec 13 02:58:52.143 INFO [stdout] test parser::field::revisions::tests::should_join_normal_transfer_path ... ok
Dec 13 02:58:52.143 INFO [stdout] test parser::field::revisions::tests::should_join_trunctated_transfer_path ... ok
Dec 13 02:58:52.143 INFO [stdout] test parser::field::revisions::tests::should_parse_first_group ... ok
Dec 13 02:58:52.143 INFO [stdout] test parser::field::revisions::tests::should_trim_ping_pong_when_join_transfer_paths ... ok
Dec 13 02:58:52.151 INFO [stdout] test parser::record::tests::should_parse_action_type_as_ongoing_for_not_finalized ... ok
Dec 13 02:58:52.151 INFO [stdout] test parser::record::tests::should_parse_action_type_as_attached_for_finalized_in_ours_and_attached ... ok
Dec 13 02:58:52.151 INFO [stdout] test parser::field::revisions::tests::should_get_last_transfer_from_our_group_for_complex_pingpong ... ok
Dec 13 02:58:52.151 INFO [stdout] test parser::record::tests::should_parse_action_type_as_actual_for_finalized_in_ours_without_attaching ... ok
Dec 13 02:58:52.163 INFO [stdout] test parser::record::tests::should_parse_action_type_as_transfer_for_finalized_in_other_groups ... ok
Dec 13 02:58:52.170 INFO [stdout] test parser::field::revisions::tests::should_get_last_closed_record_for_finally_closed ... ok
Dec 13 02:58:52.176 INFO [stdout] test parser::field::revisionitem::test::should_parse_transfer_records ... ok
Dec 13 02:58:52.176 INFO [stderr]      Running /target/debug/deps/analyzer-69dfc507965d8ba5
Dec 13 02:58:52.176 INFO [stdout] 
Dec 13 02:58:52.176 INFO [stderr]    Doc-tests analyzer
Dec 13 02:58:52.176 INFO [stdout] test result: ok. 37 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 02:58:52.176 INFO [stdout] 
Dec 13 02:58:52.176 INFO [stdout] 
Dec 13 02:58:52.176 INFO [stdout] running 0 tests
Dec 13 02:58:52.176 INFO [stdout] 
Dec 13 02:58:52.176 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 02:58:52.176 INFO [stdout] 
Dec 13 02:58:52.969 INFO [stdout] 
Dec 13 02:58:52.969 INFO [stdout] running 0 tests
Dec 13 02:58:52.969 INFO [stdout] 
Dec 13 02:58:52.969 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 02:58:52.969 INFO [stdout] 
Dec 13 02:58:52.983 INFO [stderr] su: No module specific data is present
Dec 13 02:58:53.888 INFO running `"docker" "inspect" "9ce0853751075ee65f68ce44206da43fbdb4ead38a370b4e526ba79725d0e4a6"`
Dec 13 02:58:54.277 INFO running `"docker" "rm" "-f" "9ce0853751075ee65f68ce44206da43fbdb4ead38a370b4e526ba79725d0e4a6"`
Dec 13 02:58:54.635 INFO [stdout] 9ce0853751075ee65f68ce44206da43fbdb4ead38a370b4e526ba79725d0e4a6