[INFO] fetching crate traq-ws-bot 0.1.2... [INFO] testing traq-ws-bot-0.1.2 against master#c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38 for pr-146098-8 [INFO] extracting crate traq-ws-bot 0.1.2 into /workspace/builds/worker-1-tc1/source [INFO] started tweaking crates.io crate traq-ws-bot 0.1.2 [INFO] finished tweaking crates.io crate traq-ws-bot 0.1.2 [INFO] tweaked toml for crates.io crate traq-ws-bot 0.1.2 written to /workspace/builds/worker-1-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate traq-ws-bot 0.1.2 on toolchain c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Locking 203 packages to latest compatible versions [INFO] [stderr] Adding generic-array v0.14.7 (available: v0.14.9) [INFO] [stderr] Adding http v0.2.12 (available: v1.3.1) [INFO] [stderr] Adding reqwest v0.11.27 (available: v0.12.24) [INFO] [stderr] Adding tokio-tungstenite v0.17.2 (available: v0.28.0) [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded webpki v0.22.4 [INFO] [stderr] Downloaded rustls v0.20.9 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] f458cb27817c50a3e7f9712a0a3eb0f811b5cca8f5dbdf42cc91f46d0d7ace3b [INFO] running `Command { std: "docker" "start" "-a" "f458cb27817c50a3e7f9712a0a3eb0f811b5cca8f5dbdf42cc91f46d0d7ace3b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "f458cb27817c50a3e7f9712a0a3eb0f811b5cca8f5dbdf42cc91f46d0d7ace3b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f458cb27817c50a3e7f9712a0a3eb0f811b5cca8f5dbdf42cc91f46d0d7ace3b", kill_on_drop: false }` [INFO] [stdout] f458cb27817c50a3e7f9712a0a3eb0f811b5cca8f5dbdf42cc91f46d0d7ace3b [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 12e7169e660f2897b31bc2582b7a4461986e857f49f0ad0f0b5ea2a9feac2eae [INFO] running `Command { std: "docker" "start" "-a" "12e7169e660f2897b31bc2582b7a4461986e857f49f0ad0f0b5ea2a9feac2eae", kill_on_drop: false }` [INFO] [stderr] Compiling ring v0.17.14 [INFO] [stderr] Compiling ring v0.16.20 [INFO] [stderr] Compiling tokio-macros v2.6.0 [INFO] [stderr] Compiling tracing-core v0.1.34 [INFO] [stderr] Compiling hashbrown v0.16.0 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling rustls v0.21.12 [INFO] [stderr] Compiling rustls v0.20.9 [INFO] [stderr] Compiling zerotrie v0.2.3 [INFO] [stderr] Compiling tinystr v0.8.2 [INFO] [stderr] Compiling socket2 v0.5.10 [INFO] [stderr] Compiling icu_collections v2.1.1 [INFO] [stderr] Compiling serde_json v1.0.145 [INFO] [stderr] Compiling encoding_rs v0.8.35 [INFO] [stderr] Compiling webpki-roots v0.25.4 [INFO] [stderr] Compiling icu_locale_core v2.1.1 [INFO] [stderr] Compiling indexmap v2.12.0 [INFO] [stderr] Compiling tracing v0.1.41 [INFO] [stderr] Compiling serde_urlencoded v0.7.1 [INFO] [stderr] Compiling chrono v0.4.42 [INFO] [stderr] Compiling tokio v1.48.0 [INFO] [stderr] Compiling icu_provider v2.1.1 [INFO] [stderr] Compiling icu_normalizer v2.1.1 [INFO] [stderr] Compiling icu_properties v2.1.1 [INFO] [stderr] Compiling idna_adapter v1.2.1 [INFO] [stderr] Compiling idna v1.1.0 [INFO] [stderr] Compiling url v2.5.7 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling sct v0.7.1 [INFO] [stderr] Compiling webpki v0.22.4 [INFO] [stderr] Compiling rustls-webpki v0.101.7 [INFO] [stderr] Compiling tokio-util v0.7.17 [INFO] [stderr] Compiling h2 v0.3.27 [INFO] [stderr] Compiling tokio-rustls v0.24.1 [INFO] [stderr] Compiling tokio-rustls v0.23.4 [INFO] [stderr] Compiling tungstenite v0.17.3 [INFO] [stderr] Compiling tokio-tungstenite v0.17.2 [INFO] [stderr] Compiling hyper v0.14.32 [INFO] [stderr] Compiling hyper-rustls v0.24.2 [INFO] [stderr] Compiling reqwest v0.11.27 [INFO] [stderr] Compiling traq-ws-bot v0.1.2 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 24.20s [INFO] running `Command { std: "docker" "inspect" "12e7169e660f2897b31bc2582b7a4461986e857f49f0ad0f0b5ea2a9feac2eae", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "12e7169e660f2897b31bc2582b7a4461986e857f49f0ad0f0b5ea2a9feac2eae", kill_on_drop: false }` [INFO] [stdout] 12e7169e660f2897b31bc2582b7a4461986e857f49f0ad0f0b5ea2a9feac2eae [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] b88e8ae3c914ef737a035c44a1e71d7e236f876962f3047ac47e9e568676eae1 [INFO] running `Command { std: "docker" "start" "-a" "b88e8ae3c914ef737a035c44a1e71d7e236f876962f3047ac47e9e568676eae1", kill_on_drop: false }` [INFO] [stderr] Compiling traq-ws-bot v0.1.2 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 2.07s [INFO] running `Command { std: "docker" "inspect" "b88e8ae3c914ef737a035c44a1e71d7e236f876962f3047ac47e9e568676eae1", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "b88e8ae3c914ef737a035c44a1e71d7e236f876962f3047ac47e9e568676eae1", kill_on_drop: false }` [INFO] [stdout] b88e8ae3c914ef737a035c44a1e71d7e236f876962f3047ac47e9e568676eae1 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 227433c83deced8352778290a74d0c8340ffa776fcd913eb25a3420136167c38 [INFO] running `Command { std: "docker" "start" "-a" "227433c83deced8352778290a74d0c8340ffa776fcd913eb25a3420136167c38", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.17s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/traq_ws_bot-0d57898c10410be6) [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] Doc-tests traq_ws_bot [INFO] [stdout] [INFO] [stdout] running 42 tests [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_direct_message_deleted_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_direct_message_deleted (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_direct_message_updated_with_resource (line 465) ... FAILED [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_direct_message_created_with_resource (line 465) ... FAILED [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::build (line 350) ... FAILED [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_channel_created_with_resource (line 465) ... FAILED [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_channel_topic_changed_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_error_with_resource (line 503) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_bot_message_stamps_updated_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::insert_resource (line 525) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_direct_message_created (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_channel_created (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_direct_message_updated (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_channel_topic_changed (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_bot_message_stamps_updated (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_error (line 483) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_message_deleted (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_event (line 423) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_message_created (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_event (line 435) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_message_created_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_left_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_left (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_joined_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_ping (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_joined (line 464) ... ok [INFO] [stdout] test src/utils.rs - utils::RateLimiter::acquire (line 73) ... FAILED [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_message_updated_with_resource (line 465) ... FAILED [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_ping_with_resource (line 465) ... FAILED [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_user_created_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_tag_removed (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_tag_added_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_stamp_created_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_user_created (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_stamp_created (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_tag_removed_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_message_updated (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_tag_added (line 464) ... ok [INFO] [stdout] test src/utils.rs - utils::RateLimiter (line 45) ... FAILED [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_message_deleted_with_resource (line 465) ... ok [INFO] [stdout] test src/utils.rs - utils::RateLimiter::try_acquire (line 97) ... FAILED [INFO] [stdout] test src/bot/mod.rs - bot::TraqBot::start (line 117) has been running for over 60 seconds [ERROR] error running command: no output for 300 seconds [INFO] running `Command { std: "docker" "inspect" "227433c83deced8352778290a74d0c8340ffa776fcd913eb25a3420136167c38", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "227433c83deced8352778290a74d0c8340ffa776fcd913eb25a3420136167c38", kill_on_drop: false }` [INFO] [stdout] 227433c83deced8352778290a74d0c8340ffa776fcd913eb25a3420136167c38