Dec 13 02:57:46.348 INFO testing skyscribe/pr-transfer-analyzer against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 13 02:57:46.348 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:46.871 INFO [stdout] 75262c32142d0c925b28082c3defaafad46060dd6b707ee37057c28f56985a56
Dec 13 02:57:46.872 INFO running `"docker" "start" "-a" "75262c32142d0c925b28082c3defaafad46060dd6b707ee37057c28f56985a56"`
Dec 13 02:57:47.816 INFO [stderr] usermod: no changes
Dec 13 02:57:47.951 INFO [stderr]    Compiling proc-macro2 v0.4.23
Dec 13 02:57:47.951 INFO [stderr]    Compiling regex-syntax v0.6.3
Dec 13 02:57:47.951 INFO [stderr]    Compiling csv-core v0.1.4
Dec 13 02:57:49.503 INFO [stderr]    Compiling csv v1.0.2
Dec 13 02:57:52.147 INFO [stderr]    Compiling quote v0.6.10
Dec 13 02:57:54.398 INFO [stderr]    Compiling syn v0.15.20
Dec 13 02:58:05.124 INFO [stderr]    Compiling regex v1.0.6
Dec 13 02:58:13.111 INFO [stderr]    Compiling serde_derive v1.0.80
Dec 13 02:58:21.939 INFO [stderr]    Compiling flexi_logger v0.9.3
Dec 13 02:58:36.763 INFO [stderr]    Compiling analyzer v0.1.0 (/source)
Dec 13 02:58:44.524 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 56.70s
Dec 13 02:58:44.532 INFO [stderr] su: No module specific data is present
Dec 13 02:58:46.452 INFO running `"docker" "inspect" "75262c32142d0c925b28082c3defaafad46060dd6b707ee37057c28f56985a56"`
Dec 13 02:58:46.761 INFO running `"docker" "rm" "-f" "75262c32142d0c925b28082c3defaafad46060dd6b707ee37057c28f56985a56"`
Dec 13 02:58:47.183 INFO [stdout] 75262c32142d0c925b28082c3defaafad46060dd6b707ee37057c28f56985a56
Dec 13 02:58:47.189 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:47.688 INFO [stdout] 705e1a7c26cdb2d92a122cb034d592d64c66dca1d5c1f1f6b6d0de5da6c46d71
Dec 13 02:58:47.690 INFO running `"docker" "start" "-a" "705e1a7c26cdb2d92a122cb034d592d64c66dca1d5c1f1f6b6d0de5da6c46d71"`
Dec 13 02:58:49.077 INFO [stderr] usermod: no changes
Dec 13 02:58:49.235 INFO [stderr]    Compiling analyzer v0.1.0 (/source)
Dec 13 02:58:54.623 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.50s
Dec 13 02:58:54.624 INFO [stderr] su: No module specific data is present
Dec 13 02:58:55.854 INFO running `"docker" "inspect" "705e1a7c26cdb2d92a122cb034d592d64c66dca1d5c1f1f6b6d0de5da6c46d71"`
Dec 13 02:58:56.257 INFO running `"docker" "rm" "-f" "705e1a7c26cdb2d92a122cb034d592d64c66dca1d5c1f1f6b6d0de5da6c46d71"`
Dec 13 02:58:56.699 INFO [stdout] 705e1a7c26cdb2d92a122cb034d592d64c66dca1d5c1f1f6b6d0de5da6c46d71
Dec 13 02:58:56.701 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:57.172 INFO [stdout] 56382d430548215e2b6bb13c5b5a775dca99b2f100bcd73dce483e6b6284fe79
Dec 13 02:58:57.174 INFO running `"docker" "start" "-a" "56382d430548215e2b6bb13c5b5a775dca99b2f100bcd73dce483e6b6284fe79"`
Dec 13 02:58:58.958 INFO [stderr] usermod: no changes
Dec 13 02:58:59.116 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.13s
Dec 13 02:58:59.125 INFO [stderr]      Running /target/debug/deps/analyzer-28688bbc435a1b76
Dec 13 02:58:59.128 INFO [stdout] 
Dec 13 02:58:59.128 INFO [stdout] running 37 tests
Dec 13 02:58:59.136 INFO [stdout] test parser::attachcheck::tests::should_parse_attached_list_for_chained ... ok
Dec 13 02:58:59.136 INFO [stdout] test parser::field::group::tests::should_found_group_in_category ... ok
Dec 13 02:58:59.137 INFO [stdout] test parser::field::group::tests::should_check_in_same_pool_as_same_group ... ok
Dec 13 02:58:59.137 INFO [stdout] test parser::field::group::tests::should_check_differnt_groups_in_same_pool_as_in_different_groups ... ok
Dec 13 02:58:59.138 INFO [stdout] test parser::field::group::tests::should_check_one_as_pool_group_as_in_same_group ... ok
Dec 13 02:58:59.138 INFO [stdout] test parser::attachcheck::tests::should_set_interested_as_main_when_first_as_not_intersted ... ok
Dec 13 02:58:59.138 INFO [stdout] test parser::attachcheck::tests::should_parse_attached_list_for_non_paired_attach ... ok
Dec 13 02:58:59.139 INFO [stdout] test parser::field::rdinfo::tests::should_prune_malformed_author ... ok
Dec 13 02:58:59.203 INFO [stdout] test parser::field::rdinfo::tests::should_parse_checker_info_pattern3 ... ok
Dec 13 02:58:59.204 INFO [stdout] test parser::field::rdinfo::tests::should_parse_checker_info_pattern1_1 ... ok
Dec 13 02:58:59.204 INFO [stdout] test parser::field::rdinfo::tests::should_parse_checker_info_pattern1 ... ok
Dec 13 02:58:59.205 INFO [stdout] test parser::field::rdinfo::tests::should_parse_checker_info_pattern2 ... ok
Dec 13 02:58:59.247 INFO [stdout] test parser::field::revisionitem::test::should_parse_attach_changes ... ok
Dec 13 02:58:59.247 INFO [stdout] test parser::field::revisionitem::test::should_parse_reopen_as_state_changed ... ok
Dec 13 02:58:59.248 INFO [stdout] test parser::field::revisionitem::test::should_parse_manual_state_change_than_transfer ... ok
Dec 13 02:58:59.248 INFO [stdout] test parser::field::revisionitem::test::should_parse_postpone_and_reactivate_pairs ... ok
Dec 13 02:58:59.250 INFO [stdout] test parser::field::revisions::tests::get_last_final_record_should_return_none_for_ongoing ... ok
Dec 13 02:58:59.255 INFO [stdout] test parser::field::revisions::tests::get_transfer_by_author_should_return_earliest ... ok
Dec 13 02:58:59.272 INFO [stdout] test parser::field::revisions::tests::get_transfer_by_author_should_skip_pool_transfer ... ok
Dec 13 02:58:59.287 INFO [stdout] test parser::field::revisionitem::test::should_parse_attach_and_detach_pairs ... ok
Dec 13 02:58:59.289 INFO [stdout] test parser::field::revisions::tests::should_get_correct_transfer_count ... ok
Dec 13 02:58:59.295 INFO [stdout] test parser::field::revisions::tests::should_check_normal_group_transfer ... ok
Dec 13 02:58:59.297 INFO [stdout] test parser::field::revisions::tests::get_transfer_by_grp_should_return_latest_item ... ok
Dec 13 02:58:59.298 INFO [stdout] test parser::field::revisions::tests::should_get_last_closed_record_for_finally_closed ... ok
Dec 13 02:58:59.328 INFO [stdout] test parser::field::revisions::tests::should_get_empty_first_when_no_group_change ... ok
Dec 13 02:58:59.329 INFO [stdout] test parser::field::revisions::tests::should_join_normal_transfer_path ... ok
Dec 13 02:58:59.335 INFO [stdout] test parser::record::tests::should_parse_action_type_as_actual_for_finalized_in_ours_without_attaching ... ok
Dec 13 02:58:59.345 INFO [stdout] test parser::field::revisions::tests::should_get_first_valid_transfer_when_there_are_pool_transfers ... ok
Dec 13 02:58:59.348 INFO [stdout] test parser::record::tests::should_parse_action_type_as_ongoing_for_not_finalized ... ok
Dec 13 02:58:59.353 INFO [stdout] test parser::field::revisions::tests::should_get_last_transfer_from_our_group_for_complex_pingpong ... ok
Dec 13 02:58:59.355 INFO [stdout] test parser::field::revisions::tests::should_join_trunctated_transfer_path ... ok
Dec 13 02:58:59.357 INFO [stdout] test parser::field::revisions::tests::should_parse_first_group ... ok
Dec 13 02:58:59.378 INFO [stdout] test parser::field::revisions::tests::should_trim_ping_pong_when_join_transfer_paths ... ok
Dec 13 02:58:59.379 INFO [stdout] test parser::record::tests::should_parse_action_type_as_transfer_for_finalized_in_other_groups ... ok
Dec 13 02:58:59.379 INFO [stdout] test parser::record::tests::should_parse_action_type_as_attached_for_finalized_in_ours_and_attached ... ok
Dec 13 02:58:59.385 INFO [stdout] test parser::field::revisions::tests::should_get_last_closed_record_for_finally_cnn ... ok
Dec 13 02:58:59.395 INFO [stdout] test parser::field::revisionitem::test::should_parse_transfer_records ... ok
Dec 13 02:58:59.395 INFO [stdout] 
Dec 13 02:58:59.395 INFO [stdout] test result: ok. 37 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 02:58:59.395 INFO [stdout] 
Dec 13 02:58:59.397 INFO [stderr]      Running /target/debug/deps/analyzer-c544c4e6779d7fb5
Dec 13 02:58:59.399 INFO [stdout] 
Dec 13 02:58:59.399 INFO [stdout] running 0 tests
Dec 13 02:58:59.399 INFO [stdout] 
Dec 13 02:58:59.399 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 02:58:59.399 INFO [stdout] 
Dec 13 02:58:59.400 INFO [stderr]    Doc-tests analyzer
Dec 13 02:59:00.044 INFO [stdout] 
Dec 13 02:59:00.044 INFO [stdout] running 0 tests
Dec 13 02:59:00.044 INFO [stdout] 
Dec 13 02:59:00.045 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 02:59:00.045 INFO [stdout] 
Dec 13 02:59:00.055 INFO [stderr] su: No module specific data is present
Dec 13 02:59:01.419 INFO running `"docker" "inspect" "56382d430548215e2b6bb13c5b5a775dca99b2f100bcd73dce483e6b6284fe79"`
Dec 13 02:59:01.791 INFO running `"docker" "rm" "-f" "56382d430548215e2b6bb13c5b5a775dca99b2f100bcd73dce483e6b6284fe79"`
Dec 13 02:59:02.159 INFO [stdout] 56382d430548215e2b6bb13c5b5a775dca99b2f100bcd73dce483e6b6284fe79