[INFO] fetching crate spf-milter 0.6.0... [INFO] testing spf-milter-0.6.0 against beta-2023-10-04 for beta-1.74-1 [INFO] extracting crate spf-milter 0.6.0 into /workspace/builds/worker-7-tc2/source [INFO] validating manifest of crates.io crate spf-milter 0.6.0 on toolchain beta-2023-10-04 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2023-10-04" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate spf-milter 0.6.0 [INFO] finished tweaking crates.io crate spf-milter 0.6.0 [INFO] tweaked toml for crates.io crate spf-milter 0.6.0 written to /workspace/builds/worker-7-tc2/source/Cargo.toml [INFO] crate crates.io crate spf-milter 0.6.0 already has a lockfile, it will not be regenerated [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2023-10-04" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:4f8da3c744d2da974e54ce020a79f4ad54313e914ebeaab8fc086f07e8d8b066" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2023-10-04" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] cfe32d10dcd2cbd791eb906d65ef8ce7f1ac579af303300b3368697609e9568a [INFO] running `Command { std: "docker" "start" "-a" "cfe32d10dcd2cbd791eb906d65ef8ce7f1ac579af303300b3368697609e9568a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "cfe32d10dcd2cbd791eb906d65ef8ce7f1ac579af303300b3368697609e9568a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "cfe32d10dcd2cbd791eb906d65ef8ce7f1ac579af303300b3368697609e9568a", kill_on_drop: false }` [INFO] [stdout] cfe32d10dcd2cbd791eb906d65ef8ce7f1ac579af303300b3368697609e9568a [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:4f8da3c744d2da974e54ce020a79f4ad54313e914ebeaab8fc086f07e8d8b066" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2023-10-04" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] d841a90d096d9c1702d227ccf8330c1aabefffb8d6045601d45f4a484e4eac8a [INFO] running `Command { std: "docker" "start" "-a" "d841a90d096d9c1702d227ccf8330c1aabefffb8d6045601d45f4a484e4eac8a", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.147 [INFO] [stderr] Compiling proc-macro2 v1.0.66 [INFO] [stderr] Compiling unicode-ident v1.0.11 [INFO] [stderr] Compiling pin-project-lite v0.2.13 [INFO] [stderr] Compiling futures-core v0.3.28 [INFO] [stderr] Compiling cfg-if v1.0.0 [INFO] [stderr] Compiling autocfg v1.1.0 [INFO] [stderr] Compiling bytes v1.4.0 [INFO] [stderr] Compiling futures-task v0.3.28 [INFO] [stderr] Compiling futures-channel v0.3.28 [INFO] [stderr] Compiling memchr v2.5.0 [INFO] [stderr] Compiling futures-util v0.3.28 [INFO] [stderr] Compiling slab v0.4.9 [INFO] [stderr] Compiling futures-sink v0.3.28 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling version_check v0.9.4 [INFO] [stderr] Compiling async-trait v0.1.73 [INFO] [stderr] Compiling quote v1.0.33 [INFO] [stderr] Compiling tinyvec_macros v0.1.1 [INFO] [stderr] Compiling futures-io v0.3.28 [INFO] [stderr] Compiling error-chain v0.12.4 [INFO] [stderr] Compiling tinyvec v1.6.0 [INFO] [stderr] Compiling syn v2.0.29 [INFO] [stderr] Compiling ppv-lite86 v0.2.17 [INFO] [stderr] Compiling num_threads v0.1.6 [INFO] [stderr] Compiling itoa v1.0.9 [INFO] [stderr] Compiling time-core v0.1.1 [INFO] [stderr] Compiling once_cell v1.18.0 [INFO] [stderr] Compiling signal-hook v0.3.17 [INFO] [stderr] Compiling socket2 v0.5.3 [INFO] [stderr] Compiling num_cpus v1.16.0 [INFO] [stderr] Compiling mio v0.8.8 [INFO] [stderr] Compiling getrandom v0.2.10 [INFO] [stderr] Compiling tracing-core v0.1.31 [INFO] [stderr] Compiling signal-hook-registry v1.4.1 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling time v0.3.23 [INFO] [stderr] Compiling smallvec v1.11.0 [INFO] [stderr] Compiling match_cfg v0.1.0 [INFO] [stderr] Compiling unicode-bidi v0.3.13 [INFO] [stderr] Compiling unicode-normalization v0.1.22 [INFO] [stderr] Compiling hostname v0.3.1 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling octseq v0.2.0 [INFO] [stderr] Compiling tracing v0.1.37 [INFO] [stderr] Compiling idna v0.4.0 [INFO] [stderr] Compiling log v0.4.20 [INFO] [stderr] Compiling bitflags v1.3.2 [INFO] [stderr] Compiling viaspf-record v0.5.0 [INFO] [stderr] Compiling ipnet v2.8.0 [INFO] [stderr] Compiling syslog v6.1.0 [INFO] [stderr] Compiling tokio-macros v2.1.0 [INFO] [stderr] Compiling futures-macro v0.3.28 [INFO] [stderr] Compiling byte-strings-proc_macros v0.3.1 [INFO] [stderr] Compiling byte-strings v0.3.1 [INFO] [stderr] Compiling tokio v1.32.0 [INFO] [stderr] Compiling futures-executor v0.3.28 [INFO] [stderr] Compiling futures v0.3.28 [INFO] [stderr] Compiling signal-hook-tokio v0.3.1 [INFO] [stderr] Compiling viaspf v0.6.0 [INFO] [stderr] Compiling indymilter v0.2.0 [INFO] [stderr] Compiling domain v0.8.0 [INFO] [stderr] Compiling spf-milter v0.6.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 34.01s [INFO] running `Command { std: "docker" "inspect" "d841a90d096d9c1702d227ccf8330c1aabefffb8d6045601d45f4a484e4eac8a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "d841a90d096d9c1702d227ccf8330c1aabefffb8d6045601d45f4a484e4eac8a", kill_on_drop: false }` [INFO] [stdout] d841a90d096d9c1702d227ccf8330c1aabefffb8d6045601d45f4a484e4eac8a [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:4f8da3c744d2da974e54ce020a79f4ad54313e914ebeaab8fc086f07e8d8b066" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2023-10-04" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 6e8ec123ef893eb89b861151795e7e3d2577bd6890835c6d0729eb60caaec96a [INFO] running `Command { std: "docker" "start" "-a" "6e8ec123ef893eb89b861151795e7e3d2577bd6890835c6d0729eb60caaec96a", kill_on_drop: false }` [INFO] [stderr] Compiling tokio v1.32.0 [INFO] [stderr] Compiling indymilter v0.2.0 [INFO] [stderr] Compiling viaspf v0.6.0 [INFO] [stderr] Compiling domain v0.8.0 [INFO] [stderr] Compiling signal-hook-tokio v0.3.1 [INFO] [stderr] Compiling indymilter-test v0.0.3 [INFO] [stderr] Compiling spf-milter v0.6.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 51.60s [INFO] running `Command { std: "docker" "inspect" "6e8ec123ef893eb89b861151795e7e3d2577bd6890835c6d0729eb60caaec96a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "6e8ec123ef893eb89b861151795e7e3d2577bd6890835c6d0729eb60caaec96a", kill_on_drop: false }` [INFO] [stdout] 6e8ec123ef893eb89b861151795e7e3d2577bd6890835c6d0729eb60caaec96a [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:4f8da3c744d2da974e54ce020a79f4ad54313e914ebeaab8fc086f07e8d8b066" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2023-10-04" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] f72aba5a3bee4572a5c57efd04c3520c7bf17643021dd9124cd83e3491744e71 [INFO] running `Command { std: "docker" "start" "-a" "f72aba5a3bee4572a5c57efd04c3520c7bf17643021dd9124cd83e3491744e71", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.08s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/spf_milter-79ebe64cada1f23b) [INFO] [stdout] [INFO] [stdout] running 48 tests [INFO] [stdout] test auth::tests::add_headers_for_all_results ... ok [INFO] [stdout] test auth::tests::escape_reply_text_ok ... ok [INFO] [stdout] test auth::tests::add_header_default_no_spf ... ok [INFO] [stdout] test auth::tests::reject_failure_with_i18n_explanation_from_dns ... ok [INFO] [stdout] test auth::tests::reject_unauthorized_sender ... ok [INFO] [stdout] test auth::tests::eq_authserv_ids_ok ... ok [INFO] [stdout] test auth::tests::skip_sender_matching_mailfrom_identity ... ok [INFO] [stdout] test auth::tests::dry_run_accepts_message ... ok [INFO] [stdout] test auth::tests::add_header_with_unusable_helo_identity ... ok [INFO] [stdout] test auth::tests::reject_helo_timeout_with_escaped_reply_text ... ok [INFO] [stdout] test auth::tests::reject_null_sender_with_invalid_helo_record ... ok [INFO] [stdout] test config::model::tests::enhanced_status_code_parse_ok ... ok [INFO] [stdout] test config::cli_opts::tests::cli_options_builder_ok ... ok [INFO] [stdout] test config::model::tests::reply_code_parse_ok ... ok [INFO] [stdout] test config::read::tests::parse_exp_explain_string_ok ... ok [INFO] [stdout] test config::model::tests::skip_senders_ok ... ok [INFO] [stdout] test config::model::tests::trusted_networks_loopback_ok ... ok [INFO] [stdout] test config::read::tests::parse_results_invalid_result ... ok [INFO] [stdout] test auth::tests::delete_forged_authentication_results_headers ... ok [INFO] [stdout] test config::read::tests::parse_skip_senders_ok ... ok [INFO] [stdout] test config::model::tests::trusted_networks_subnet_ok ... ok [INFO] [stdout] test config::tests::delete_incoming_authentication_results_default_ok ... ok [INFO] [stdout] test auth::tests::add_header_for_definitive_helo_result ... ok [INFO] [stdout] test config::read::tests::split_param_values_ok ... ok [INFO] [stdout] test config::read::tests::parse_skip_senders_file_content_ok ... ok [INFO] [stdout] test config::read::tests::socket_with_cli_options_override ... ok [INFO] [stdout] test header::auth_results::tests::extract_authserv_id_ok ... ok [INFO] [stdout] test header::format::tests::decode_quoted_string_ok ... ok [INFO] [stdout] test config::read::tests::parse_results_ok ... ok [INFO] [stdout] test header::format::tests::encode_mime_value_ok ... ok [INFO] [stdout] test header::auth_results::tests::auth_results_header_display_error ... ok [INFO] [stdout] test header::format::tests::strip_ctext_ok ... ok [INFO] [stdout] test header::format::tests::strip_comment_ok ... ok [INFO] [stdout] test header::format::tests::strip_fws_ok ... ok [INFO] [stdout] test header::format::tests::strip_mime_value_ok ... ok [INFO] [stdout] test header::auth_results::tests::encode_sender_ok ... ok [INFO] [stdout] test header::format::tests::escape_comment_word_ok ... ok [INFO] [stdout] test header::format::tests::strip_quoted_pair_ok ... ok [INFO] [stdout] test header::format::tests::is_dot_atom_ok ... ok [INFO] [stdout] test header::auth_results::tests::auth_results_header_display_all_results ... ok [INFO] [stdout] test resolver::tests::domain_resolver_lookup_ok ... ignored, depends on live DNS records [INFO] [stdout] test header::received_spf::tests::format_header_value_ok ... ok [INFO] [stdout] test header::received_spf::tests::format_pass_parts_ok ... ok [INFO] [stdout] test header::received_spf::tests::format_none_parts_with_unusual_sender ... ok [INFO] [stdout] test header::received_spf::tests::received_spf_header_display_error ... ok [INFO] [stdout] test header::received_spf::tests::received_spf_header_display_default_neutral_result ... ok [INFO] [stdout] test header::received_spf::tests::received_spf_header_display_pass ... ok [INFO] [stderr] Running unittests src/main.rs (/opt/rustwide/target/debug/deps/spf_milter-711f83ee9efb1b3f) [INFO] [stdout] test verify::tests::to_verifier_config_exp_explain_string ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 47 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.07s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 0 tests [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running tests/config_reload.rs (/opt/rustwide/target/debug/deps/config_reload-b0181ca71d5da0c5) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stderr] SPF Milter 0.6.0 starting [INFO] [stderr] from@example.org (mailfrom): pass [INFO] [stderr] configuration reloaded from /opt/rustwide/target/debug/config_reload.conf [INFO] [stderr] parameter "socket" changed, restart needed [INFO] [stderr] from@example.org (mailfrom): pass [INFO] [stderr] failed to reload configuration from /opt/rustwide/target/debug/config_reload.conf: line 5: unknown parameter "invalid_key" [INFO] [stderr] from@example.org (mailfrom): pass [INFO] [stderr] SPF Milter 0.6.0 shut down [INFO] [stdout] test config_reload ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.62s [INFO] [stdout] [INFO] [stderr] Running tests/delete_auth_results.rs (/opt/rustwide/target/debug/deps/delete_auth_results-18dbbcf9dd894d76) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stderr] SPF Milter 0.6.0 starting [INFO] [stderr] from@example.org (mailfrom): softfail [INFO] [stderr] SPF Milter 0.6.0 shut down [INFO] [stdout] test delete_auth_results ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.14s [INFO] [stdout] [INFO] [stderr] Running tests/live.rs (/opt/rustwide/target/debug/deps/live-09175a63ba7d333a) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test live ... ignored, depends on live DNS records [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running tests/trusted_clients.rs (/opt/rustwide/target/debug/deps/trusted_clients-b83e424855938c5a) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stderr] SPF Milter 0.6.0 starting [INFO] [stderr] SPF Milter 0.6.0 shut down [INFO] [stdout] test trusted_clients ... ok [INFO] [stderr] Running tests/verify_sender.rs (/opt/rustwide/target/debug/deps/verify_sender-93e59b32e7575e70) [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.10s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stderr] SPF Milter 0.6.0 starting [INFO] [stderr] mail.amy.org (helo): pass [INFO] [stderr] mail.example.com (helo): none [INFO] [stderr] from@amy.org (mailfrom): pass [INFO] [stderr] mail.mallory.org (helo): fail [INFO] [stderr] mail.example.com (helo): none [INFO] [stderr] from@mallory.org (mailfrom): fail [INFO] [stderr] mail.example.com (helo): none [INFO] [stderr] mail.example.com (helo): none [INFO] [stderr] from@error.mallory.org (mailfrom): permerror (more than one SPF record found) [INFO] [stderr] mail.example.com (helo): none [INFO] [stderr] from@amy.org (mailfrom): pass [INFO] [stderr] from@amy.org (mailfrom): pass [INFO] [stderr] from@mallory.org (mailfrom): fail [INFO] [stderr] SPF Milter 0.6.0 shut down [INFO] [stdout] test verify_sender ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.69s [INFO] [stdout] [INFO] [stderr] Doc-tests spf-milter [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test src/lib.rs - run (line 131) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.49s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "f72aba5a3bee4572a5c57efd04c3520c7bf17643021dd9124cd83e3491744e71", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f72aba5a3bee4572a5c57efd04c3520c7bf17643021dd9124cd83e3491744e71", kill_on_drop: false }` [INFO] [stdout] f72aba5a3bee4572a5c57efd04c3520c7bf17643021dd9124cd83e3491744e71