[INFO] cloning repository https://github.com/nullren/email-server
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/nullren/email-server" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fnullren%2Femail-server", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fnullren%2Femail-server'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] d4d364f75a6687faf1d3fa602236f187bf67d4d0
[INFO] testing nullren/email-server against master#ad85bc524b1ad696e42061ad8338d382dffbdbe5 for pr-146237
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fnullren%2Femail-server" "/workspace/builds/worker-3-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/nullren/email-server
[INFO] finished tweaking git repo https://github.com/nullren/email-server
[INFO] tweaked toml for git repo https://github.com/nullren/email-server written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/nullren/email-server on toolchain ad85bc524b1ad696e42061ad8338d382dffbdbe5
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/nullren/email-server 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" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded derive_builder_core v0.20.2
[INFO] [stderr]   Downloaded derive_builder_macro v0.20.2
[INFO] [stderr]   Downloaded derive_builder v0.20.2
[INFO] [stderr]   Downloaded hickory-resolver v0.24.4
[INFO] [stderr]   Downloaded hickory-proto v0.24.4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 36c75a7ae487497bb7548b20c203e391a29df7120973a8270286b8ca2f2ece0e
[INFO] running `Command { std: "docker" "start" "-a" "36c75a7ae487497bb7548b20c203e391a29df7120973a8270286b8ca2f2ece0e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "36c75a7ae487497bb7548b20c203e391a29df7120973a8270286b8ca2f2ece0e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "36c75a7ae487497bb7548b20c203e391a29df7120973a8270286b8ca2f2ece0e", kill_on_drop: false }`
[INFO] [stdout] 36c75a7ae487497bb7548b20c203e391a29df7120973a8270286b8ca2f2ece0e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2a2280cbcf38dd684c7354aa8dcfa4f79ba4d7082fe9bc93b70a2db5a06a7023
[INFO] running `Command { std: "docker" "start" "-a" "2a2280cbcf38dd684c7354aa8dcfa4f79ba4d7082fe9bc93b70a2db5a06a7023", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.93
[INFO] [stderr]    Compiling unicode-ident v1.0.16
[INFO] [stderr]    Compiling litemap v0.7.4
[INFO] [stderr]    Compiling once_cell v1.20.3
[INFO] [stderr]    Compiling bytes v1.10.0
[INFO] [stderr]    Compiling serde v1.0.217
[INFO] [stderr]    Compiling parking_lot_core v0.9.10
[INFO] [stderr]    Compiling serde_json v1.0.138
[INFO] [stderr]    Compiling thiserror v2.0.11
[INFO] [stderr]    Compiling ryu v1.0.19
[INFO] [stderr]    Compiling cc v1.2.14
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling log v0.4.25
[INFO] [stderr]    Compiling event-listener v5.4.0
[INFO] [stderr]    Compiling indexmap v2.7.1
[INFO] [stderr]    Compiling crossbeam-queue v0.3.12
[INFO] [stderr]    Compiling hashlink v0.10.0
[INFO] [stderr]    Compiling tracing-core v0.1.33
[INFO] [stderr]    Compiling crypto-common v0.1.6
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling parking_lot v0.12.3
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling byteorder v1.5.0
[INFO] [stderr]    Compiling rustix v0.38.44
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling sha2 v0.10.8
[INFO] [stderr]    Compiling getrandom v0.3.1
[INFO] [stderr]    Compiling getrandom v0.2.15
[INFO] [stderr]    Compiling linux-raw-sys v0.4.15
[INFO] [stderr]    Compiling bitflags v2.8.0
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling futures-intrusive v0.5.0
[INFO] [stderr]    Compiling tokio v1.43.0
[INFO] [stderr]    Compiling dotenvy v0.15.7
[INFO] [stderr]    Compiling regex-syntax v0.8.5
[INFO] [stderr]    Compiling hex v0.4.3
[INFO] [stderr]    Compiling tinyvec v1.8.1
[INFO] [stderr]    Compiling hostname v0.3.1
[INFO] [stderr]    Compiling flume v0.11.1
[INFO] [stderr]    Compiling quote v1.0.38
[INFO] [stderr]    Compiling linked-hash-map v0.5.6
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling resolv-conf v0.7.0
[INFO] [stderr]    Compiling thread_local v1.1.8
[INFO] [stderr]    Compiling clap_builder v4.5.31
[INFO] [stderr]    Compiling uuid v1.15.1
[INFO] [stderr]    Compiling syn v2.0.98
[INFO] [stderr]    Compiling lru-cache v0.1.2
[INFO] [stderr]    Compiling libsqlite3-sys v0.30.1
[INFO] [stderr]    Compiling regex-automata v0.4.9
[INFO] [stderr]    Compiling tempfile v3.17.1
[INFO] [stderr]    Compiling futures-executor v0.3.31
[INFO] [stderr]    Compiling regex v1.11.1
[INFO] [stderr]    Compiling tokio-stream v0.1.17
[INFO] [stderr]    Compiling synstructure v0.13.1
[INFO] [stderr]    Compiling darling_core v0.20.10
[INFO] [stderr]    Compiling zerovec-derive v0.10.3
[INFO] [stderr]    Compiling displaydoc v0.2.5
[INFO] [stderr]    Compiling icu_provider_macros v1.5.0
[INFO] [stderr]    Compiling tracing-attributes v0.1.28
[INFO] [stderr]    Compiling serde_derive v1.0.217
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling thiserror-impl v2.0.11
[INFO] [stderr]    Compiling tokio-macros v2.5.0
[INFO] [stderr]    Compiling zerocopy-derive v0.7.35
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling enum-as-inner v0.6.1
[INFO] [stderr]    Compiling async-trait v0.1.87
[INFO] [stderr]    Compiling clap_derive v4.5.28
[INFO] [stderr]    Compiling zerofrom-derive v0.1.5
[INFO] [stderr]    Compiling yoke-derive v0.7.5
[INFO] [stderr]    Compiling zerocopy v0.7.35
[INFO] [stderr]    Compiling zerofrom v0.1.5
[INFO] [stderr]    Compiling yoke v0.7.5
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling tracing v0.1.41
[INFO] [stderr]    Compiling zerovec v0.10.4
[INFO] [stderr]    Compiling clap v4.5.31
[INFO] [stderr]    Compiling ppv-lite86 v0.2.20
[INFO] [stderr]    Compiling tracing-subscriber v0.3.19
[INFO] [stderr]    Compiling darling_macro v0.20.10
[INFO] [stderr]    Compiling darling v0.20.10
[INFO] [stderr]    Compiling derive_builder_core v0.20.2
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling tinystr v0.7.6
[INFO] [stderr]    Compiling icu_collections v1.5.0
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling icu_locid v1.5.0
[INFO] [stderr]    Compiling derive_builder_macro v0.20.2
[INFO] [stderr]    Compiling icu_provider v1.5.0
[INFO] [stderr]    Compiling derive_builder v0.20.2
[INFO] [stderr]    Compiling icu_locid_transform v1.5.0
[INFO] [stderr]    Compiling icu_properties v1.5.1
[INFO] [stderr]    Compiling futures v0.3.31
[INFO] [stderr]    Compiling either v1.13.0
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling icu_normalizer v1.5.0
[INFO] [stderr]    Compiling idna_adapter v1.2.0
[INFO] [stderr]    Compiling idna v1.0.3
[INFO] [stderr]    Compiling tokio-util v0.7.13
[INFO] [stderr]    Compiling url v2.5.4
[INFO] [stderr]    Compiling hickory-proto v0.24.4
[INFO] [stderr]    Compiling sqlx-core v0.8.3
[INFO] [stderr]    Compiling sqlx-sqlite v0.8.3
[INFO] [stderr]    Compiling hickory-resolver v0.24.4
[INFO] [stderr]    Compiling sqlx-macros-core v0.8.3
[INFO] [stderr]    Compiling sqlx-macros v0.8.3
[INFO] [stderr]    Compiling sqlx v0.8.3
[INFO] [stderr]    Compiling email-server-core v0.1.0 (/opt/rustwide/workdir/crates/email-server-core)
[INFO] [stderr]    Compiling email-server v0.1.0 (/opt/rustwide/workdir/crates/email-server)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 21s
[INFO] running `Command { std: "docker" "inspect" "2a2280cbcf38dd684c7354aa8dcfa4f79ba4d7082fe9bc93b70a2db5a06a7023", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2a2280cbcf38dd684c7354aa8dcfa4f79ba4d7082fe9bc93b70a2db5a06a7023", kill_on_drop: false }`
[INFO] [stdout] 2a2280cbcf38dd684c7354aa8dcfa4f79ba4d7082fe9bc93b70a2db5a06a7023
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 931a0d514167d4b59c1890cc3ee6a7d28861a8de299163de6233ef7869b98b9a
[INFO] running `Command { std: "docker" "start" "-a" "931a0d514167d4b59c1890cc3ee6a7d28861a8de299163de6233ef7869b98b9a", kill_on_drop: false }`
[INFO] [stderr]    Compiling email-server-core v0.1.0 (/opt/rustwide/workdir/crates/email-server-core)
[INFO] [stderr]    Compiling email-server v0.1.0 (/opt/rustwide/workdir/crates/email-server)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 3.70s
[INFO] running `Command { std: "docker" "inspect" "931a0d514167d4b59c1890cc3ee6a7d28861a8de299163de6233ef7869b98b9a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "931a0d514167d4b59c1890cc3ee6a7d28861a8de299163de6233ef7869b98b9a", kill_on_drop: false }`
[INFO] [stdout] 931a0d514167d4b59c1890cc3ee6a7d28861a8de299163de6233ef7869b98b9a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] ac85addc004d738a19a43c7d96c6c7b1d3b25dbc498e33284b964467d6fd0d3d
[INFO] running `Command { std: "docker" "start" "-a" "ac85addc004d738a19a43c7d96c6c7b1d3b25dbc498e33284b964467d6fd0d3d", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.25s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/email_server-48d3f9b1d43c8906)
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/email_server_core-89a0d96778e9b607)
[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] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test smtp::state::tests::test_data_collect_state ... ok
[INFO] [stdout] test smtp::state::tests::test_done_state ... ok
[INFO] [stdout] test smtp::state::tests::test_init_state_helo ... ok
[INFO] [stdout] test smtp::state::tests::test_mail_state_from ... ok
[INFO] [stdout] test smtp::state::tests::test_rcpt_state_to ... ok
[INFO] [stdout] test smtp::state::tests::test_data_state ... ok
[INFO] [stdout] test tests::test_smtp_server_helo_command ... ok
[INFO] [stdout] test tests::test_smtp_server_quit_command ... ok
[INFO] [stdout] test tests::test_smtp_server_initial_response ... ok
[INFO] [stdout] test storage::message::tests::test_create_message ... ok
[INFO] [stderr] 2025-09-10T20:25:03.636319Z  INFO email_server_core::tests: This should appear in test output!
[INFO] [stdout] test tests::test_logging ... ok
[INFO] [stderr] 2025-09-10T20:25:03.643398Z  INFO email_server_core::socket: SMTP Server listening on 127.0.0.1:33073
[INFO] [stderr] 2025-09-10T20:25:03.643517Z  INFO email_server_core::socket: New connection: 127.0.0.1:58858
[INFO] [stderr] 2025-09-10T20:25:03.643697Z  INFO email_server_core::socket: New connection: 127.0.0.1:58862
[INFO] [stderr] 2025-09-10T20:25:03.643986Z  INFO socket{id=0dbecdaf-ee42-47af-ad17-3d2ec4c1237f peer=127.0.0.1:58858}: email_server_core::socket: Closing after 0 ms
[INFO] [stderr] 2025-09-10T20:25:03.649135Z  INFO email_server_core::socket: Closing after 5 ms
[INFO] [stdout] test tests::test_pipeline_message ... ok
[INFO] [stderr] 2025-09-10T20:25:03.694612Z  INFO email_server_core::socket: SMTP Server listening on 127.0.0.1:46787
[INFO] [stderr] 2025-09-10T20:25:03.694724Z  INFO email_server_core::socket: New connection: 127.0.0.1:52918
[INFO] [stderr] 2025-09-10T20:25:03.694773Z  INFO email_server_core::socket: New connection: 127.0.0.1:52920
[INFO] [stderr] 2025-09-10T20:25:03.695317Z  INFO socket{id=94f2fd03-05ad-407b-95ae-d7cb19ec359d peer=127.0.0.1:58862}:socket{id=56aaf51b-cb94-41b1-b447-c1dc29fe42d3 peer=127.0.0.1:52918}: email_server_core::socket: Closing after 0 ms
[INFO] [stdout] test tests::test_full_message ... ok
[INFO] [stderr] 2025-09-10T20:25:03.707112Z  INFO socket{id=a767d844-56e0-4d37-a32c-454cdd7cd7a3 peer=127.0.0.1:52920}: email_server_core::socket: Closing after 12 ms
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.10s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests email_server_core
[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] running `Command { std: "docker" "inspect" "ac85addc004d738a19a43c7d96c6c7b1d3b25dbc498e33284b964467d6fd0d3d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ac85addc004d738a19a43c7d96c6c7b1d3b25dbc498e33284b964467d6fd0d3d", kill_on_drop: false }`
[INFO] [stdout] ac85addc004d738a19a43c7d96c6c7b1d3b25dbc498e33284b964467d6fd0d3d