[INFO] fetching crate gitlab-runner 0.3.0-rc1... [INFO] testing gitlab-runner-0.3.0-rc1 against master#ad85bc524b1ad696e42061ad8338d382dffbdbe5 for pr-146237 [INFO] extracting crate gitlab-runner 0.3.0-rc1 into /workspace/builds/worker-6-tc1/source [INFO] started tweaking crates.io crate gitlab-runner 0.3.0-rc1 [INFO] removed 0 missing examples [INFO] removed 0 missing tests [INFO] finished tweaking crates.io crate gitlab-runner 0.3.0-rc1 [INFO] tweaked toml for crates.io crate gitlab-runner 0.3.0-rc1 written to /workspace/builds/worker-6-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate gitlab-runner 0.3.0-rc1 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 crates.io crate gitlab-runner 0.3.0-rc1 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] Updating crates.io index [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded zstd-safe v7.2.3 [INFO] [stderr] Downloaded log v0.4.26 [INFO] [stderr] Downloaded lockfree-object-pool v0.1.6 [INFO] [stderr] Downloaded once_cell v1.21.1 [INFO] [stderr] Downloaded tempfile v3.19.0 [INFO] [stderr] Downloaded assert-json-diff v2.0.2 [INFO] [stderr] Downloaded vergen-lib v0.1.6 [INFO] [stderr] Downloaded deadpool-runtime v0.1.4 [INFO] [stderr] Downloaded redox_syscall v0.5.10 [INFO] [stderr] Downloaded wiremock v0.6.3 [INFO] [stderr] Downloaded clap v4.5.32 [INFO] [stderr] Downloaded cc v1.2.16 [INFO] [stderr] Downloaded zip v4.1.0 [INFO] [stderr] Downloaded gitlab-runner-mock v0.2.1 [INFO] [stderr] Downloaded time v0.3.40 [INFO] [stderr] Downloaded zerocopy-derive v0.8.23 [INFO] [stderr] Downloaded rustls-webpki v0.103.0 [INFO] [stderr] Downloaded zerocopy v0.8.23 [INFO] [stderr] Downloaded zstd-sys v2.0.14+zstd.1.5.7 [INFO] [stderr] Downloaded rustix v1.0.3 [INFO] [stderr] Downloaded rand v0.9.0 [INFO] [stderr] Downloaded vergen v9.0.6 [INFO] [stderr] Downloaded time-macros v0.2.21 [INFO] [stderr] Downloaded deadpool v0.10.0 [INFO] [stderr] Downloaded arbitrary v1.4.1 [INFO] [stderr] Downloaded bzip2 v0.5.2 [INFO] [stderr] Downloaded deflate64 v0.1.9 [INFO] [stderr] Downloaded libz-rs-sys v0.5.1 [INFO] [stderr] Downloaded tokio-retry2 v0.5.7 [INFO] [stderr] Downloaded vergen-gitcl v1.0.8 [INFO] [stderr] Downloaded derive_arbitrary v1.4.1 [INFO] [stderr] Downloaded liblzma v0.4.1 [INFO] [stderr] Downloaded zopfli v0.8.1 [INFO] [stderr] Downloaded h2 v0.4.8 [INFO] [stderr] Downloaded liblzma-sys v0.4.4 [INFO] [stderr] Downloaded clap_builder v4.5.32 [INFO] [stderr] Downloaded zlib-rs v0.5.1 [INFO] [stderr] Downloaded bzip2-sys v0.1.13+1.0.8 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] b9ada0344c3db9f5b5f1addc30c06d495e28dbdc58b0085b76b00e2bcca81893 [INFO] running `Command { std: "docker" "start" "-a" "b9ada0344c3db9f5b5f1addc30c06d495e28dbdc58b0085b76b00e2bcca81893", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "b9ada0344c3db9f5b5f1addc30c06d495e28dbdc58b0085b76b00e2bcca81893", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "b9ada0344c3db9f5b5f1addc30c06d495e28dbdc58b0085b76b00e2bcca81893", kill_on_drop: false }` [INFO] [stdout] b9ada0344c3db9f5b5f1addc30c06d495e28dbdc58b0085b76b00e2bcca81893 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 6ef33c1db03e8de6e287e344176c67724587f0f714352d4dfc3e88446f466620 [INFO] running `Command { std: "docker" "start" "-a" "6ef33c1db03e8de6e287e344176c67724587f0f714352d4dfc3e88446f466620", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.94 [INFO] [stderr] Compiling libc v0.2.171 [INFO] [stderr] Compiling autocfg v1.4.0 [INFO] [stderr] Compiling smallvec v1.14.0 [INFO] [stderr] Compiling once_cell v1.21.1 [INFO] [stderr] Compiling rustversion v1.0.20 [INFO] [stderr] Compiling parking_lot_core v0.9.10 [INFO] [stderr] Compiling strsim v0.11.1 [INFO] [stderr] Compiling memchr v2.7.4 [INFO] [stderr] Compiling litemap v0.7.5 [INFO] [stderr] Compiling writeable v0.5.5 [INFO] [stderr] Compiling getrandom v0.3.2 [INFO] [stderr] Compiling log v0.4.26 [INFO] [stderr] Compiling bitflags v2.9.0 [INFO] [stderr] Compiling anyhow v1.0.97 [INFO] [stderr] Compiling icu_locid_transform_data v1.5.0 [INFO] [stderr] Compiling tracing-core v0.1.33 [INFO] [stderr] Compiling hashbrown v0.15.2 [INFO] [stderr] Compiling deranged v0.4.0 [INFO] [stderr] Compiling foreign-types-shared v0.1.1 [INFO] [stderr] Compiling zerocopy v0.8.23 [INFO] [stderr] Compiling openssl v0.10.73 [INFO] [stderr] Compiling lock_api v0.4.12 [INFO] [stderr] Compiling slab v0.4.9 [INFO] [stderr] Compiling icu_properties_data v1.5.0 [INFO] [stderr] Compiling time-core v0.1.4 [INFO] [stderr] Compiling foreign-types v0.3.2 [INFO] [stderr] Compiling native-tls v0.2.14 [INFO] [stderr] Compiling utf16_iter v1.0.5 [INFO] [stderr] Compiling write16 v1.0.0 [INFO] [stderr] Compiling icu_normalizer_data v1.5.0 [INFO] [stderr] Compiling zstd-safe v7.2.3 [INFO] [stderr] Compiling openssl-probe v0.1.6 [INFO] [stderr] Compiling percent-encoding v2.3.1 [INFO] [stderr] Compiling num_threads v0.1.7 [INFO] [stderr] Compiling unicase v2.8.1 [INFO] [stderr] Compiling crc32fast v1.4.2 [INFO] [stderr] Compiling vergen-lib v0.1.6 [INFO] [stderr] Compiling vergen v9.0.6 [INFO] [stderr] Compiling vergen-gitcl v1.0.8 [INFO] [stderr] Compiling zlib-rs v0.5.1 [INFO] [stderr] Compiling serde_json v1.0.140 [INFO] [stderr] Compiling quote v1.0.40 [INFO] [stderr] Compiling form_urlencoded v1.2.1 [INFO] [stderr] Compiling mime_guess v2.0.5 [INFO] [stderr] Compiling adler2 v2.0.0 [INFO] [stderr] Compiling rustix v1.0.3 [INFO] [stderr] Compiling hmac v0.12.1 [INFO] [stderr] Compiling sync_wrapper v1.0.2 [INFO] [stderr] Compiling linux-raw-sys v0.9.3 [INFO] [stderr] Compiling indexmap v2.8.0 [INFO] [stderr] Compiling miniz_oxide v0.8.5 [INFO] [stderr] Compiling rustls-pki-types v1.11.0 [INFO] [stderr] Compiling overload v0.1.1 [INFO] [stderr] Compiling bumpalo v3.17.0 [INFO] [stderr] Compiling doc-comment v0.3.3 [INFO] [stderr] Compiling lockfree-object-pool v0.1.6 [INFO] [stderr] Compiling thiserror v2.0.12 [INFO] [stderr] Compiling simd-adler32 v0.3.7 [INFO] [stderr] Compiling syn v2.0.100 [INFO] [stderr] Compiling pbkdf2 v0.12.2 [INFO] [stderr] Compiling zopfli v0.8.1 [INFO] [stderr] Compiling rustls-pemfile v2.2.0 [INFO] [stderr] Compiling nu-ansi-term v0.46.0 [INFO] [stderr] Compiling sharded-slab v0.1.7 [INFO] [stderr] Compiling aes v0.8.4 [INFO] [stderr] Compiling time v0.3.40 [INFO] [stderr] Compiling tracing-log v0.2.0 [INFO] [stderr] Compiling sha1 v0.10.6 [INFO] [stderr] Compiling jobserver v0.1.32 [INFO] [stderr] Compiling mio v1.0.3 [INFO] [stderr] Compiling socket2 v0.5.8 [INFO] [stderr] Compiling signal-hook-registry v1.4.2 [INFO] [stderr] Compiling parking_lot v0.12.3 [INFO] [stderr] Compiling cc v1.2.16 [INFO] [stderr] Compiling rand_core v0.9.3 [INFO] [stderr] Compiling thread_local v1.1.8 [INFO] [stderr] Compiling encoding_rs v0.8.35 [INFO] [stderr] Compiling constant_time_eq v0.3.1 [INFO] [stderr] Compiling deflate64 v0.1.9 [INFO] [stderr] Compiling sha2 v0.10.8 [INFO] [stderr] Compiling glob v0.3.2 [INFO] [stderr] Compiling tracing-subscriber v0.3.19 [INFO] [stderr] Compiling ppv-lite86 v0.2.21 [INFO] [stderr] Compiling openssl-sys v0.9.109 [INFO] [stderr] Compiling zstd-sys v2.0.14+zstd.1.5.7 [INFO] [stderr] Compiling liblzma-sys v0.4.4 [INFO] [stderr] Compiling bzip2-sys v0.1.13+1.0.8 [INFO] [stderr] Compiling rand_chacha v0.9.0 [INFO] [stderr] Compiling rand v0.9.0 [INFO] [stderr] Compiling bzip2 v0.5.2 [INFO] [stderr] Compiling tempfile v3.19.0 [INFO] [stderr] Compiling liblzma v0.4.1 [INFO] [stderr] Compiling libz-rs-sys v0.5.1 [INFO] [stderr] Compiling flate2 v1.1.2 [INFO] [stderr] Compiling synstructure v0.13.1 [INFO] [stderr] Compiling darling_core v0.20.11 [INFO] [stderr] Compiling zerofrom-derive v0.1.6 [INFO] [stderr] Compiling yoke-derive v0.7.5 [INFO] [stderr] Compiling zerovec-derive v0.10.3 [INFO] [stderr] Compiling displaydoc v0.2.5 [INFO] [stderr] Compiling tokio-macros v2.5.0 [INFO] [stderr] Compiling icu_provider_macros v1.5.0 [INFO] [stderr] Compiling futures-macro v0.3.31 [INFO] [stderr] Compiling tracing-attributes v0.1.28 [INFO] [stderr] Compiling openssl-macros v0.1.1 [INFO] [stderr] Compiling serde_derive v1.0.219 [INFO] [stderr] Compiling pin-project-internal v1.1.10 [INFO] [stderr] Compiling zeroize_derive v1.4.2 [INFO] [stderr] Compiling thiserror-impl v2.0.12 [INFO] [stderr] Compiling async-trait v0.1.88 [INFO] [stderr] Compiling zeroize v1.8.1 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling tokio v1.44.2 [INFO] [stderr] Compiling tracing v0.1.41 [INFO] [stderr] Compiling pin-project v1.1.10 [INFO] [stderr] Compiling zerofrom v0.1.6 [INFO] [stderr] Compiling zstd v0.13.3 [INFO] [stderr] Compiling darling_macro v0.20.11 [INFO] [stderr] Compiling zip v4.1.0 [INFO] [stderr] Compiling yoke v0.7.5 [INFO] [stderr] Compiling zerovec v0.10.4 [INFO] [stderr] Compiling darling v0.20.11 [INFO] [stderr] Compiling derive_builder_core v0.20.2 [INFO] [stderr] Compiling derive_builder_macro v0.20.2 [INFO] [stderr] Compiling tinystr v0.7.6 [INFO] [stderr] Compiling icu_collections v1.5.0 [INFO] [stderr] Compiling serde v1.0.219 [INFO] [stderr] Compiling icu_locid v1.5.0 [INFO] [stderr] Compiling derive_builder v0.20.2 [INFO] [stderr] Compiling gitlab-runner v0.3.0-rc1 (/opt/rustwide/workdir) [INFO] [stderr] warning: gitlab-runner@0.3.0-rc1: not within a suitable 'git' worktree! [INFO] [stderr] warning: gitlab-runner@0.3.0-rc1: VERGEN_GIT_SHA set to default [INFO] [stderr] warning: gitlab-runner@0.3.0-rc1: VERGEN_GIT_DIRTY set to default [INFO] [stderr] Compiling icu_provider v1.5.0 [INFO] [stderr] Compiling icu_locid_transform v1.5.0 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling icu_properties v1.5.1 [INFO] [stderr] Compiling icu_normalizer v1.5.0 [INFO] [stderr] Compiling tokio-util v0.7.14 [INFO] [stderr] Compiling tokio-native-tls v0.3.1 [INFO] [stderr] Compiling tower v0.5.2 [INFO] [stderr] Compiling tokio-retry2 v0.5.7 [INFO] [stderr] Compiling serde_urlencoded v0.7.1 [INFO] [stderr] Compiling idna_adapter v1.2.0 [INFO] [stderr] Compiling idna v1.0.3 [INFO] [stderr] Compiling h2 v0.4.8 [INFO] [stderr] Compiling url v2.5.4 [INFO] [stderr] Compiling hyper v1.6.0 [INFO] [stderr] Compiling hyper-util v0.1.10 [INFO] [stderr] Compiling hyper-tls v0.6.0 [INFO] [stderr] Compiling reqwest v0.12.15 [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/artifact.rs:60:17 [INFO] [stdout] | [INFO] [stdout] 60 | pub fn file(&mut self, name: &str) -> Option { [INFO] [stdout] | ^^^^^^^^^ ^^^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 60 | pub fn file(&mut self, name: &str) -> Option> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/artifact.rs:65:21 [INFO] [stdout] | [INFO] [stdout] 65 | pub fn by_index(&mut self, i: usize) -> Option { [INFO] [stdout] | ^^^^^^^^^ ^^^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 65 | pub fn by_index(&mut self, i: usize) -> Option> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/job.rs:316:21 [INFO] [stdout] | [INFO] [stdout] 316 | pub fn variable(&self, key: &str) -> Option { [INFO] [stdout] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 316 | pub fn variable(&self, key: &str) -> Option> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/job.rs:321:22 [INFO] [stdout] | [INFO] [stdout] 321 | pub fn variables(&self) -> impl Iterator { [INFO] [stdout] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 321 | pub fn variables(&self) -> impl Iterator> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/job.rs:326:25 [INFO] [stdout] | [INFO] [stdout] 326 | pub fn dependencies(&self) -> impl Iterator { [INFO] [stdout] | ^^^^^ ^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 326 | pub fn dependencies(&self) -> impl Iterator> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 13s [INFO] running `Command { std: "docker" "inspect" "6ef33c1db03e8de6e287e344176c67724587f0f714352d4dfc3e88446f466620", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "6ef33c1db03e8de6e287e344176c67724587f0f714352d4dfc3e88446f466620", kill_on_drop: false }` [INFO] [stdout] 6ef33c1db03e8de6e287e344176c67724587f0f714352d4dfc3e88446f466620 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 8c9601db796d0ef092fe5cebf4953da31b20bd43e81630934405cd751367f131 [INFO] running `Command { std: "docker" "start" "-a" "8c9601db796d0ef092fe5cebf4953da31b20bd43e81630934405cd751367f131", kill_on_drop: false }` [INFO] [stderr] Compiling darling_core v0.20.11 [INFO] [stderr] Compiling tokio v1.44.2 [INFO] [stderr] Compiling anyhow v1.0.97 [INFO] [stderr] Compiling aho-corasick v1.1.3 [INFO] [stderr] Compiling regex-syntax v0.8.5 [INFO] [stderr] Compiling regex-syntax v0.6.29 [INFO] [stderr] Compiling anstyle-parse v0.2.6 [INFO] [stderr] Compiling num_cpus v1.16.0 [INFO] [stderr] Compiling multer v3.1.0 [INFO] [stderr] Compiling deadpool-runtime v0.1.4 [INFO] [stderr] Compiling anstyle v1.0.10 [INFO] [stderr] Compiling anstyle-query v1.1.2 [INFO] [stderr] Compiling colorchoice v1.0.3 [INFO] [stderr] Compiling assert-json-diff v2.0.2 [INFO] [stderr] Compiling tracing-serde v0.2.0 [INFO] [stderr] Compiling clap_lex v0.7.4 [INFO] [stderr] Compiling spin v0.9.8 [INFO] [stderr] Compiling clap_derive v4.5.32 [INFO] [stderr] Compiling anstream v0.6.18 [INFO] [stderr] Compiling clap_builder v4.5.32 [INFO] [stderr] Compiling darling_macro v0.20.11 [INFO] [stderr] Compiling regex-automata v0.1.10 [INFO] [stderr] Compiling darling v0.20.11 [INFO] [stderr] Compiling derive_builder_core v0.20.2 [INFO] [stderr] Compiling regex-automata v0.4.9 [INFO] [stderr] Compiling matchers v0.1.0 [INFO] [stderr] Compiling derive_builder_macro v0.20.2 [INFO] [stderr] Compiling clap v4.5.32 [INFO] [stderr] Compiling derive_builder v0.20.2 [INFO] [stderr] Compiling vergen-lib v0.1.6 [INFO] [stderr] Compiling vergen v9.0.6 [INFO] [stderr] Compiling vergen-gitcl v1.0.8 [INFO] [stderr] Compiling gitlab-runner v0.3.0-rc1 (/opt/rustwide/workdir) [INFO] [stderr] warning: gitlab-runner@0.3.0-rc1: not within a suitable 'git' worktree! [INFO] [stderr] warning: gitlab-runner@0.3.0-rc1: VERGEN_GIT_SHA set to default [INFO] [stderr] warning: gitlab-runner@0.3.0-rc1: VERGEN_GIT_DIRTY set to default [INFO] [stderr] Compiling tokio-util v0.7.14 [INFO] [stderr] Compiling tokio-native-tls v0.3.1 [INFO] [stderr] Compiling deadpool v0.10.0 [INFO] [stderr] Compiling tower v0.5.2 [INFO] [stderr] Compiling tokio-retry2 v0.5.7 [INFO] [stderr] Compiling regex v1.11.1 [INFO] [stderr] Compiling h2 v0.4.8 [INFO] [stderr] Compiling tracing-subscriber v0.3.19 [INFO] [stderr] Compiling hyper v1.6.0 [INFO] [stderr] Compiling hyper-util v0.1.10 [INFO] [stderr] Compiling hyper-tls v0.6.0 [INFO] [stderr] Compiling wiremock v0.6.3 [INFO] [stderr] Compiling reqwest v0.12.15 [INFO] [stderr] Compiling gitlab-runner-mock v0.2.1 [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/artifact.rs:60:17 [INFO] [stdout] | [INFO] [stdout] 60 | pub fn file(&mut self, name: &str) -> Option { [INFO] [stdout] | ^^^^^^^^^ ^^^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 60 | pub fn file(&mut self, name: &str) -> Option> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/artifact.rs:65:21 [INFO] [stdout] | [INFO] [stdout] 65 | pub fn by_index(&mut self, i: usize) -> Option { [INFO] [stdout] | ^^^^^^^^^ ^^^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 65 | pub fn by_index(&mut self, i: usize) -> Option> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/job.rs:316:21 [INFO] [stdout] | [INFO] [stdout] 316 | pub fn variable(&self, key: &str) -> Option { [INFO] [stdout] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 316 | pub fn variable(&self, key: &str) -> Option> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/job.rs:321:22 [INFO] [stdout] | [INFO] [stdout] 321 | pub fn variables(&self) -> impl Iterator { [INFO] [stdout] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 321 | pub fn variables(&self) -> impl Iterator> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/job.rs:326:25 [INFO] [stdout] | [INFO] [stdout] 326 | pub fn dependencies(&self) -> impl Iterator { [INFO] [stdout] | ^^^^^ ^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 326 | pub fn dependencies(&self) -> impl Iterator> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/artifact.rs:60:17 [INFO] [stdout] | [INFO] [stdout] 60 | pub fn file(&mut self, name: &str) -> Option { [INFO] [stdout] | ^^^^^^^^^ ^^^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 60 | pub fn file(&mut self, name: &str) -> Option> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/artifact.rs:65:21 [INFO] [stdout] | [INFO] [stdout] 65 | pub fn by_index(&mut self, i: usize) -> Option { [INFO] [stdout] | ^^^^^^^^^ ^^^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 65 | pub fn by_index(&mut self, i: usize) -> Option> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/job.rs:316:21 [INFO] [stdout] | [INFO] [stdout] 316 | pub fn variable(&self, key: &str) -> Option { [INFO] [stdout] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 316 | pub fn variable(&self, key: &str) -> Option> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/job.rs:321:22 [INFO] [stdout] | [INFO] [stdout] 321 | pub fn variables(&self) -> impl Iterator { [INFO] [stdout] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 321 | pub fn variables(&self) -> impl Iterator> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/job.rs:326:25 [INFO] [stdout] | [INFO] [stdout] 326 | pub fn dependencies(&self) -> impl Iterator { [INFO] [stdout] | ^^^^^ ^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 326 | pub fn dependencies(&self) -> impl Iterator> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 47.85s [INFO] running `Command { std: "docker" "inspect" "8c9601db796d0ef092fe5cebf4953da31b20bd43e81630934405cd751367f131", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "8c9601db796d0ef092fe5cebf4953da31b20bd43e81630934405cd751367f131", kill_on_drop: false }` [INFO] [stdout] 8c9601db796d0ef092fe5cebf4953da31b20bd43e81630934405cd751367f131 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 3572acc0728dcf179c6cb15715386e163c72b9c0730c71f5c29107b0afcef6b3 [INFO] running `Command { std: "docker" "start" "-a" "3572acc0728dcf179c6cb15715386e163c72b9c0730c71f5c29107b0afcef6b3", kill_on_drop: false }` [INFO] [stderr] warning: gitlab-runner@0.3.0-rc1: not within a suitable 'git' worktree! [INFO] [stderr] warning: gitlab-runner@0.3.0-rc1: VERGEN_GIT_SHA set to default [INFO] [stderr] warning: gitlab-runner@0.3.0-rc1: VERGEN_GIT_DIRTY set to default [INFO] [stderr] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stderr] --> src/artifact.rs:60:17 [INFO] [stderr] | [INFO] [stderr] 60 | pub fn file(&mut self, name: &str) -> Option { [INFO] [stderr] | ^^^^^^^^^ ^^^^^^^^^^^^ the same lifetime is hidden here [INFO] [stderr] | | [INFO] [stderr] | the lifetime is elided here [INFO] [stderr] | [INFO] [stderr] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stderr] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stderr] help: use `'_` for type paths [INFO] [stderr] | [INFO] [stderr] 60 | pub fn file(&mut self, name: &str) -> Option> { [INFO] [stderr] | ++++ [INFO] [stderr] [INFO] [stderr] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stderr] --> src/artifact.rs:65:21 [INFO] [stderr] | [INFO] [stderr] 65 | pub fn by_index(&mut self, i: usize) -> Option { [INFO] [stderr] | ^^^^^^^^^ ^^^^^^^^^^^^ the same lifetime is hidden here [INFO] [stderr] | | [INFO] [stderr] | the lifetime is elided here [INFO] [stderr] | [INFO] [stderr] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stderr] help: use `'_` for type paths [INFO] [stderr] | [INFO] [stderr] 65 | pub fn by_index(&mut self, i: usize) -> Option> { [INFO] [stderr] | ++++ [INFO] [stderr] [INFO] [stderr] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stderr] --> src/job.rs:316:21 [INFO] [stderr] | [INFO] [stderr] 316 | pub fn variable(&self, key: &str) -> Option { [INFO] [stderr] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stderr] | | [INFO] [stderr] | the lifetime is elided here [INFO] [stderr] | [INFO] [stderr] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stderr] help: use `'_` for type paths [INFO] [stderr] | [INFO] [stderr] 316 | pub fn variable(&self, key: &str) -> Option> { [INFO] [stderr] | ++++ [INFO] [stderr] [INFO] [stderr] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stderr] --> src/job.rs:321:22 [INFO] [stderr] | [INFO] [stderr] 321 | pub fn variables(&self) -> impl Iterator { [INFO] [stderr] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stderr] | | [INFO] [stderr] | the lifetime is elided here [INFO] [stderr] | [INFO] [stderr] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stderr] help: use `'_` for type paths [INFO] [stderr] | [INFO] [stderr] 321 | pub fn variables(&self) -> impl Iterator> { [INFO] [stderr] | ++++ [INFO] [stderr] [INFO] [stderr] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stderr] --> src/job.rs:326:25 [INFO] [stderr] | [INFO] [stderr] 326 | pub fn dependencies(&self) -> impl Iterator { [INFO] [stderr] | ^^^^^ ^^^^^^^^^^ the same lifetime is hidden here [INFO] [stderr] | | [INFO] [stderr] | the lifetime is elided here [INFO] [stderr] | [INFO] [stderr] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stderr] help: use `'_` for type paths [INFO] [stderr] | [INFO] [stderr] 326 | pub fn dependencies(&self) -> impl Iterator> { [INFO] [stderr] | ++++ [INFO] [stderr] [INFO] [stderr] warning: `gitlab-runner` (lib) generated 5 warnings (run `cargo fix --lib -p gitlab-runner` to apply 5 suggestions) [INFO] [stderr] warning: `gitlab-runner` (lib test) generated 5 warnings (5 duplicates) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.44s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/gitlab_runner-6cfaf19342c24026) [INFO] [stdout] [INFO] [stdout] running 4 tests [INFO] [stdout] test client::test::deserialize_variables ... ok [INFO] [stdout] test runlist::test::runlist ... ok [INFO] [stdout] test client::test::no_job ... ok [INFO] [stdout] test client::test::process_job ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.22s [INFO] [stdout] [INFO] [stderr] Running tests/artifacts.rs (/opt/rustwide/target/debug/deps/artifacts-25dc839350648628) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] test multiple_upload ... ok [INFO] [stderr] Running tests/integration.rs (/opt/rustwide/target/debug/deps/integration-372a2ab4c6989323) [INFO] [stdout] test upload_download ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.41s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 13 tests [INFO] [stdout] 2025-09-11T06:00:52.869030Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:39405) [INFO] [stdout] 2025-09-11T06:00:52.869595Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(39405)) [INFO] [stdout] 2025-09-11T06:00:52.869723Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:39405 [INFO] [stdout] 2025-09-11T06:00:52.870110Z DEBUG hyper_util::client::legacy::connect::http: connected to 127.0.0.1:39405 [INFO] [stdout] 2025-09-11T06:00:52.870445Z TRACE hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-11T06:00:52.870698Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:39405) [INFO] [stdout] 2025-09-11T06:00:52.891411Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:46639) [INFO] [stdout] 2025-09-11T06:00:52.896930Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(46639)) [INFO] [stdout] 2025-09-11T06:00:52.897067Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:46639 [INFO] [stdout] 2025-09-11T06:00:52.897340Z DEBUG hyper_util::client::legacy::connect::http: connected to 127.0.0.1:46639 [INFO] [stdout] 2025-09-11T06:00:52.897470Z TRACE hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-11T06:00:52.897643Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:46639) [INFO] [stdout] 2025-09-11T06:00:52.899394Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:46639) [INFO] [stdout] 2025-09-11T06:00:52.899442Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:46639) [INFO] [stdout] 2025-09-11T06:00:52.902378Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:46639): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:52.902447Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:46639) [INFO] [stdout] 2025-09-11T06:00:52.903671Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:46639) [INFO] [stdout] 2025-09-11T06:00:52.903719Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:46639) [INFO] [stdout] test job_fail ... ok [INFO] [stdout] 2025-09-11T06:00:52.988240Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:39405) [INFO] [stdout] 2025-09-11T06:00:52.988316Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:39405) [INFO] [stdout] 2025-09-11T06:00:52.994263Z  INFO integration: TEST [INFO] [stdout] 2025-09-11T06:00:52.995379Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:35213) [INFO] [stdout] 2025-09-11T06:00:52.995585Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:52.995805Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(35213)) [INFO] [stdout] 2025-09-11T06:00:52.995864Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:35213 [INFO] [stdout] 2025-09-11T06:00:52.996110Z DEBUG hyper_util::client::legacy::connect::http: connected to 127.0.0.1:35213 [INFO] [stdout] 2025-09-11T06:00:52.996298Z TRACE hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-11T06:00:52.997001Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:35213) [INFO] [stdout] 2025-09-11T06:00:52.998117Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:37693) [INFO] [stdout] 2025-09-11T06:00:52.998587Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(37693)) [INFO] [stdout] 2025-09-11T06:00:52.998687Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:37693 [INFO] [stdout] 2025-09-11T06:00:52.998987Z DEBUG hyper_util::client::legacy::connect::http: connected to 127.0.0.1:37693 [INFO] [stdout] 2025-09-11T06:00:53.000820Z TRACE hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-11T06:00:53.001018Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:37693) [INFO] [stdout] 2025-09-11T06:00:53.007333Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:35213) [INFO] [stdout] 2025-09-11T06:00:53.007452Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:35213) [INFO] [stdout] 2025-09-11T06:00:53.009960Z  INFO run{gitlab.job=266}: integration: TEST1234 [INFO] [stdout] 2025-09-11T06:00:53.010092Z TRACE run{gitlab.job=266}: integration: aa gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:53.010220Z TRACE run{gitlab.job=266}: integration: cc gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:53.013272Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:35213): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.013332Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:35213) [INFO] [stdout] 2025-09-11T06:00:53.014123Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:37693) [INFO] [stdout] 2025-09-11T06:00:53.014184Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:37693) [INFO] [stdout] 2025-09-11T06:00:53.015799Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:35213) [INFO] [stdout] 2025-09-11T06:00:53.015855Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:37693): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.015882Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:35213) [INFO] [stdout] 2025-09-11T06:00:53.017979Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:35213): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.018061Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:35213) [INFO] [stdout] 2025-09-11T06:00:53.018324Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:37693) [INFO] [stdout] 2025-09-11T06:00:53.020321Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:43577) [INFO] [stdout] 2025-09-11T06:00:53.022869Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(43577)) [INFO] [stdout] 2025-09-11T06:00:53.023975Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:43577 [INFO] [stdout] 2025-09-11T06:00:53.024238Z DEBUG hyper_util::client::legacy::connect::http: connected to 127.0.0.1:43577 [INFO] [stdout] 2025-09-11T06:00:53.024345Z TRACE hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-11T06:00:53.024480Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:43577) [INFO] [stdout] 2025-09-11T06:00:53.022888Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:35213) [INFO] [stdout] 2025-09-11T06:00:53.024782Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:35213) [INFO] [stdout] 2025-09-11T06:00:53.024946Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:37693) [INFO] [stdout] 2025-09-11T06:00:53.025049Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:37693) [INFO] [stdout] 2025-09-11T06:00:53.034021Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:43577) [INFO] [stdout] 2025-09-11T06:00:53.034078Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:43577) [INFO] [stdout] test job_metadata ... ok [INFO] [stdout] 2025-09-11T06:00:53.043025Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.043182Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(39267)) [INFO] [stdout] 2025-09-11T06:00:53.043226Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:39267 [INFO] [stdout] 2025-09-11T06:00:53.044292Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:43577): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.044380Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:43577) [INFO] [stdout] test job_log ... ok [INFO] [stdout] 2025-09-11T06:00:53.045909Z DEBUG hyper_util::client::legacy::connect::http: connected to 127.0.0.1:39267 [INFO] [stdout] 2025-09-11T06:00:53.045998Z TRACE hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-11T06:00:53.046186Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.047969Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:43577) [INFO] [stdout] 2025-09-11T06:00:53.048020Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:43577) [INFO] [stdout] test job_variables ... ok [INFO] [stdout] 2025-09-11T06:00:53.068115Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.068222Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(39267)) [INFO] [stdout] 2025-09-11T06:00:53.068259Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:39267 [INFO] [stdout] 2025-09-11T06:00:53.068939Z DEBUG hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.078604Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.078666Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.079593Z TRACE hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:39267): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.079619Z DEBUG hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.080813Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.080962Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(38819)) [INFO] [stdout] 2025-09-11T06:00:53.081007Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:38819 [INFO] [stdout] 2025-09-11T06:00:53.081195Z DEBUG hyper_util::client::legacy::connect::http: connected to 127.0.0.1:38819 [INFO] [stdout] 2025-09-11T06:00:53.081264Z TRACE hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-11T06:00:53.081364Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.083983Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.084017Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.084832Z TRACE hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:38819): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.084864Z DEBUG hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.085367Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.085431Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.086486Z TRACE hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:39267): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.086517Z DEBUG hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.088048Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.088087Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.088895Z TRACE hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:38819): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.088926Z DEBUG hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.090207Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.090246Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.091138Z TRACE hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:38819): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.091174Z DEBUG hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.092842Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.092876Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.093619Z TRACE hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:38819): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.093651Z DEBUG hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.094128Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.094169Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.095305Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.095333Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.096187Z TRACE hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:38819): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.096228Z DEBUG hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.097036Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.097062Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.097600Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:53.098205Z TRACE run{gitlab.job=270}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:38819): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.098254Z DEBUG run{gitlab.job=270}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.098391Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.098469Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(38819)) [INFO] [stdout] 2025-09-11T06:00:53.098530Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:38819 [INFO] [stdout] 2025-09-11T06:00:53.098929Z TRACE run{gitlab.job=268}: hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.098994Z TRACE run{gitlab.job=268}: hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(38819)) [INFO] [stdout] 2025-09-11T06:00:53.099033Z DEBUG run{gitlab.job=268}: hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:38819 [INFO] [stdout] 2025-09-11T06:00:53.099225Z TRACE hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:39267): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.099252Z DEBUG hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.099342Z TRACE run{gitlab.job=267}: hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.099408Z TRACE run{gitlab.job=267}: hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(38819)) [INFO] [stdout] 2025-09-11T06:00:53.099448Z DEBUG run{gitlab.job=267}: hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:38819 [INFO] [stdout] 2025-09-11T06:00:53.099794Z TRACE run{gitlab.job=269}: hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.099857Z TRACE run{gitlab.job=269}: hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(38819)) [INFO] [stdout] 2025-09-11T06:00:53.099892Z DEBUG run{gitlab.job=269}: hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:38819 [INFO] [stdout] 2025-09-11T06:00:53.100946Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.100982Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.101035Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::connect::http: connected to 127.0.0.1:38819 [INFO] [stdout] 2025-09-11T06:00:53.101111Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-11T06:00:53.101181Z DEBUG run{gitlab.job=268}: hyper_util::client::legacy::connect::http: connected to 127.0.0.1:38819 [INFO] [stdout] 2025-09-11T06:00:53.101230Z TRACE run{gitlab.job=268}: hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-11T06:00:53.101289Z DEBUG run{gitlab.job=267}: hyper_util::client::legacy::connect::http: connected to 127.0.0.1:38819 [INFO] [stdout] 2025-09-11T06:00:53.101337Z TRACE run{gitlab.job=267}: hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-11T06:00:53.101396Z DEBUG run{gitlab.job=269}: hyper_util::client::legacy::connect::http: connected to 127.0.0.1:38819 [INFO] [stdout] 2025-09-11T06:00:53.101443Z TRACE run{gitlab.job=269}: hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-11T06:00:53.101637Z TRACE run{gitlab.job=270}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.101678Z TRACE run{gitlab.job=270}: hyper_util::client::legacy::pool: put; found waiter for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.101705Z TRACE hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:39267): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.101726Z DEBUG hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.101874Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.101991Z TRACE run{gitlab.job=268}: hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.102083Z TRACE run{gitlab.job=267}: hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.102175Z TRACE run{gitlab.job=269}: hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.102863Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.102900Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.103011Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.103058Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.104413Z TRACE run{gitlab.job=268}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.104450Z DEBUG run{gitlab.job=268}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.104629Z TRACE run{gitlab.job=267}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.104666Z DEBUG run{gitlab.job=267}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.104850Z TRACE run{gitlab.job=269}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.104886Z DEBUG run{gitlab.job=269}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:38819) [INFO] [stdout] 2025-09-11T06:00:53.104980Z TRACE run{gitlab.job=270}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:39267): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.105027Z DEBUG run{gitlab.job=270}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.106104Z TRACE run{gitlab.job=270}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.106146Z DEBUG run{gitlab.job=270}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.106716Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:39267): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.106788Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.107584Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:36107) [INFO] [stdout] 2025-09-11T06:00:53.107676Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(36107)) [INFO] [stdout] 2025-09-11T06:00:53.107716Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:36107 [INFO] [stdout] 2025-09-11T06:00:53.107983Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:36829) [INFO] [stdout] 2025-09-11T06:00:53.108048Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(36829)) [INFO] [stdout] 2025-09-11T06:00:53.108076Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:36829 [INFO] [stdout] 2025-09-11T06:00:53.108223Z DEBUG hyper_util::client::legacy::connect::http: connected to 127.0.0.1:36829 [INFO] [stdout] 2025-09-11T06:00:53.108274Z TRACE hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-11T06:00:53.108283Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.108321Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.108356Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:36829) [INFO] [stdout] test job_drain ... ok [INFO] [stdout] 2025-09-11T06:00:53.109145Z TRACE run{gitlab.job=268}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:39267): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.109198Z DEBUG run{gitlab.job=268}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.109709Z DEBUG hyper_util::client::legacy::connect::http: connected to 127.0.0.1:36107 [INFO] [stdout] 2025-09-11T06:00:53.109796Z TRACE hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-11T06:00:53.109882Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:36107) [INFO] [stdout] 2025-09-11T06:00:53.110158Z TRACE run{gitlab.job=268}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.110201Z DEBUG run{gitlab.job=268}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.111872Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:36107) [INFO] [stdout] 2025-09-11T06:00:53.111900Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:36107) [INFO] [stdout] 2025-09-11T06:00:53.113026Z TRACE run{gitlab.job=267}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:39267): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.113081Z DEBUG run{gitlab.job=267}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.113729Z TRACE run{gitlab.job=267}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.113807Z DEBUG run{gitlab.job=267}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.114435Z TRACE run{gitlab.job=269}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:39267): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.114487Z DEBUG run{gitlab.job=269}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.114695Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:36107): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.115159Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:36107) [INFO] [stdout] 2025-09-11T06:00:53.115445Z TRACE run{gitlab.job=269}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.115491Z DEBUG run{gitlab.job=269}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:39267) [INFO] [stdout] 2025-09-11T06:00:53.115923Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:36829) [INFO] [stdout] 2025-09-11T06:00:53.115965Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:36829) [INFO] [stdout] 2025-09-11T06:00:53.117412Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:36829): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.117473Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:36829) [INFO] [stdout] 2025-09-11T06:00:53.119277Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:36107) [INFO] [stdout] 2025-09-11T06:00:53.119329Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:36107) [INFO] [stdout] 2025-09-11T06:00:53.120533Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:36829) [INFO] [stdout] 2025-09-11T06:00:53.120583Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:36829) [INFO] [stdout] test job_parallel ... ok [INFO] [stdout] test job_success ... ok [INFO] [stdout] test job_steps ... ok [INFO] [stdout] 2025-09-11T06:00:53.135414Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:45233) [INFO] [stdout] 2025-09-11T06:00:53.135578Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(45233)) [INFO] [stdout] 2025-09-11T06:00:53.135640Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:45233 [INFO] [stdout] 2025-09-11T06:00:53.135947Z DEBUG hyper_util::client::legacy::connect::http: connected to 127.0.0.1:45233 [INFO] [stdout] 2025-09-11T06:00:53.136033Z TRACE hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-11T06:00:53.136183Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:45233) [INFO] [stdout] 2025-09-11T06:00:53.141009Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:45233) [INFO] [stdout] 2025-09-11T06:00:53.141072Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:45233) [INFO] [stdout] 2025-09-11T06:00:53.199367Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] test runner_run ... ok [INFO] [stdout] 2025-09-11T06:00:53.255901Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:45233): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:53.256001Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:45233) [INFO] [stdout] 2025-09-11T06:00:53.257682Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:45233) [INFO] [stdout] 2025-09-11T06:00:53.257753Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:45233) [INFO] [stdout] test job_panic ... ok [INFO] [stdout] 2025-09-11T06:00:53.302996Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:53.404605Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:53.506046Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:53.608978Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:53.710577Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:53.813189Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:53.959999Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] test runner_delay ... ok [INFO] [stdout] 2025-09-11T06:00:54.061096Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:54.162147Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:54.264179Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:54.364728Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] test runner_limit ... ok [INFO] [stdout] 2025-09-11T06:00:54.468414Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:54.570119Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:54.670895Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:54.772603Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:54.879306Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:54.979902Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:55.081569Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:55.183392Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:55.285258Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:55.390015Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:55.491626Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:55.593203Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:55.694968Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:55.796640Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:55.898185Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:55.992941Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:39405): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:55.993119Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:39405) [INFO] [stdout] 2025-09-11T06:00:55.998778Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-11T06:00:56.021135Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:39405) [INFO] [stdout] 2025-09-11T06:00:56.021302Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:39405) [INFO] [stdout] 2025-09-11T06:00:56.021956Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:39405): expiration = Some(90s) [INFO] [stdout] 2025-09-11T06:00:56.022059Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:39405) [INFO] [stdout] 2025-09-11T06:00:56.025462Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:39405) [INFO] [stdout] 2025-09-11T06:00:56.025608Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:39405) [INFO] [stdout] test job_cancel_step ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.88s [INFO] [stdout] [INFO] [stderr] Running tests/runhandler.rs (/opt/rustwide/target/debug/deps/runhandler-d5f8f199f8ec661c) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test update_interval ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.54s [INFO] [stdout] [INFO] [stderr] Doc-tests gitlab_runner [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] test src/lib.rs - (line 16) - compile ... ok [INFO] [stdout] test src/logging.rs - logging::GitlabLayer::new (line 72) ... ok [INFO] [stdout] test src/lib.rs - RunnerBuilder::new (line 231) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.66s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "3572acc0728dcf179c6cb15715386e163c72b9c0730c71f5c29107b0afcef6b3", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3572acc0728dcf179c6cb15715386e163c72b9c0730c71f5c29107b0afcef6b3", kill_on_drop: false }` [INFO] [stdout] 3572acc0728dcf179c6cb15715386e163c72b9c0730c71f5c29107b0afcef6b3