[INFO] cloning repository https://github.com/collabora/obs-gitlab-runner [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/collabora/obs-gitlab-runner" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fcollabora%2Fobs-gitlab-runner", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fcollabora%2Fobs-gitlab-runner'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 6a91199fa1b08c81f33dd58b6126cad3ef33c69d [INFO] testing collabora/obs-gitlab-runner against try#d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6 for pr-146237 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fcollabora%2Fobs-gitlab-runner" "/workspace/builds/worker-4-tc2/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc2/source'... [INFO] [stderr] done. [INFO] started tweaking git repo https://github.com/collabora/obs-gitlab-runner [INFO] finished tweaking git repo https://github.com/collabora/obs-gitlab-runner [INFO] tweaked toml for git repo https://github.com/collabora/obs-gitlab-runner written to /workspace/builds/worker-4-tc2/source/Cargo.toml [INFO] validating manifest of git repo https://github.com/collabora/obs-gitlab-runner on toolchain d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate git repo https://github.com/collabora/obs-gitlab-runner 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" "+d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 2359efa24589848de606e87fb580f2ab2e969f56937bea0634f0454dbcd04a0a [INFO] running `Command { std: "docker" "start" "-a" "2359efa24589848de606e87fb580f2ab2e969f56937bea0634f0454dbcd04a0a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "2359efa24589848de606e87fb580f2ab2e969f56937bea0634f0454dbcd04a0a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "2359efa24589848de606e87fb580f2ab2e969f56937bea0634f0454dbcd04a0a", kill_on_drop: false }` [INFO] [stdout] 2359efa24589848de606e87fb580f2ab2e969f56937bea0634f0454dbcd04a0a [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=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" "+d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 3093286b394c3cbb9ab0930a7d155ad435d374fd7d802cee52eb88b3bbe308b0 [INFO] running `Command { std: "docker" "start" "-a" "3093286b394c3cbb9ab0930a7d155ad435d374fd7d802cee52eb88b3bbe308b0", kill_on_drop: false }` [INFO] [stderr] Compiling unicode-ident v1.0.18 [INFO] [stderr] Compiling libc v0.2.174 [INFO] [stderr] Compiling smallvec v1.15.1 [INFO] [stderr] Compiling itoa v1.0.1 [INFO] [stderr] Compiling bytes v1.10.1 [INFO] [stderr] Compiling lock_api v0.4.13 [INFO] [stderr] Compiling typenum v1.15.0 [INFO] [stderr] Compiling slab v0.4.5 [INFO] [stderr] Compiling proc-macro2 v1.0.95 [INFO] [stderr] Compiling generic-array v0.14.5 [INFO] [stderr] Compiling hashbrown v0.15.4 [INFO] [stderr] Compiling httparse v1.10.1 [INFO] [stderr] Compiling ryu v1.0.9 [INFO] [stderr] Compiling try-lock v0.2.3 [INFO] [stderr] Compiling openssl v0.10.73 [INFO] [stderr] Compiling quote v1.0.40 [INFO] [stderr] Compiling want v0.3.0 [INFO] [stderr] Compiling native-tls v0.2.14 [INFO] [stderr] Compiling syn v2.0.103 [INFO] [stderr] Compiling http v1.3.1 [INFO] [stderr] Compiling syn v1.0.86 [INFO] [stderr] Compiling indexmap v2.9.0 [INFO] [stderr] Compiling serde_json v1.0.140 [INFO] [stderr] Compiling anyhow v1.0.98 [INFO] [stderr] Compiling jobserver v0.1.33 [INFO] [stderr] Compiling parking_lot_core v0.9.11 [INFO] [stderr] Compiling mio v1.0.4 [INFO] [stderr] Compiling socket2 v0.5.10 [INFO] [stderr] Compiling cc v1.2.27 [INFO] [stderr] Compiling parking_lot v0.12.4 [INFO] [stderr] Compiling signal-hook-registry v1.4.0 [INFO] [stderr] Compiling getrandom v0.3.3 [INFO] [stderr] Compiling deranged v0.4.0 [INFO] [stderr] Compiling http-body v1.0.1 [INFO] [stderr] Compiling vergen-lib v0.1.6 [INFO] [stderr] Compiling time-core v0.1.4 [INFO] [stderr] Compiling http v0.2.6 [INFO] [stderr] Compiling vergen v9.0.6 [INFO] [stderr] Compiling ipnet v2.11.0 [INFO] [stderr] Compiling encoding_rs v0.8.30 [INFO] [stderr] Compiling crypto-common v0.1.3 [INFO] [stderr] Compiling block-buffer v0.10.2 [INFO] [stderr] Compiling atomic-waker v1.0.0 [INFO] [stderr] Compiling digest v0.10.7 [INFO] [stderr] Compiling vergen-gitcl v1.0.8 [INFO] [stderr] Compiling tracing-log v0.2.0 [INFO] [stderr] Compiling thread_local v1.1.4 [INFO] [stderr] Compiling num-traits v0.2.14 [INFO] [stderr] Compiling zstd-safe v7.2.4 [INFO] [stderr] Compiling openssl-sys v0.9.109 [INFO] [stderr] Compiling zstd-sys v2.0.15+zstd.1.5.7 [INFO] [stderr] Compiling bzip2-sys v0.1.13+1.0.8 [INFO] [stderr] Compiling liblzma-sys v0.4.4 [INFO] [stderr] Compiling nu-ansi-term v0.50.1 [INFO] [stderr] Compiling num_threads v0.1.6 [INFO] [stderr] Compiling mime v0.3.16 [INFO] [stderr] Compiling cpufeatures v0.2.1 [INFO] [stderr] Compiling time v0.3.41 [INFO] [stderr] Compiling http-body v0.4.4 [INFO] [stderr] Compiling mime_guess v2.0.3 [INFO] [stderr] Compiling inout v0.1.4 [INFO] [stderr] Compiling rand_core v0.9.3 [INFO] [stderr] Compiling socket2 v0.4.4 [INFO] [stderr] Compiling sync_wrapper v1.0.2 [INFO] [stderr] Compiling miniz_oxide v0.4.4 [INFO] [stderr] Compiling num-integer v0.1.44 [INFO] [stderr] Compiling zlib-rs v0.5.1 [INFO] [stderr] Compiling httpdate v1.0.2 [INFO] [stderr] Compiling ppv-lite86 v0.2.16 [INFO] [stderr] Compiling synstructure v0.13.2 [INFO] [stderr] Compiling darling_core v0.20.11 [INFO] [stderr] Compiling unicode-segmentation v1.9.0 [INFO] [stderr] Compiling rustix v1.0.7 [INFO] [stderr] Compiling anstyle-parse v0.2.7 [INFO] [stderr] Compiling rand_chacha v0.9.0 [INFO] [stderr] Compiling thiserror-impl v1.0.30 [INFO] [stderr] Compiling cipher v0.4.3 [INFO] [stderr] Compiling backtrace v0.3.64 [INFO] [stderr] Compiling hmac v0.12.1 [INFO] [stderr] Compiling tokio-macros v2.5.0 [INFO] [stderr] Compiling zerofrom-derive v0.1.6 [INFO] [stderr] Compiling futures-macro v0.3.31 [INFO] [stderr] Compiling yoke-derive v0.8.0 [INFO] [stderr] Compiling tokio v1.45.1 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling zerofrom v0.1.6 [INFO] [stderr] Compiling serde_derive v1.0.219 [INFO] [stderr] Compiling yoke v0.8.0 [INFO] [stderr] Compiling tracing-attributes v0.1.30 [INFO] [stderr] Compiling zerovec-derive v0.11.1 [INFO] [stderr] Compiling displaydoc v0.2.5 [INFO] [stderr] Compiling zerotrie v0.2.2 [INFO] [stderr] Compiling darling_macro v0.20.11 [INFO] [stderr] Compiling zerovec v0.11.2 [INFO] [stderr] Compiling openssl-macros v0.1.1 [INFO] [stderr] Compiling darling v0.20.11 [INFO] [stderr] Compiling derive_builder_core v0.20.2 [INFO] [stderr] Compiling tracing v0.1.41 [INFO] [stderr] Compiling zeroize_derive v1.4.2 [INFO] [stderr] Compiling zeroize v1.8.1 [INFO] [stderr] Compiling tinystr v0.8.1 [INFO] [stderr] Compiling potential_utf v0.1.2 [INFO] [stderr] Compiling icu_collections v2.0.0 [INFO] [stderr] Compiling derive_builder_macro v0.20.2 [INFO] [stderr] Compiling icu_locale_core v2.0.0 [INFO] [stderr] Compiling derive_builder v0.20.2 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling libz-rs-sys v0.5.1 [INFO] [stderr] Compiling icu_provider v2.0.0 [INFO] [stderr] Compiling serde v1.0.219 [INFO] [stderr] Compiling pin-project-internal v1.1.10 [INFO] [stderr] Compiling icu_normalizer v2.0.0 [INFO] [stderr] Compiling icu_properties v2.0.1 [INFO] [stderr] Compiling http-body-util v0.1.3 [INFO] [stderr] Compiling linux-raw-sys v0.9.4 [INFO] [stderr] Compiling tokio-util v0.7.15 [INFO] [stderr] Compiling tower v0.5.2 [INFO] [stderr] Compiling simd-adler32 v0.3.7 [INFO] [stderr] Compiling eyre v0.6.6 [INFO] [stderr] Compiling tokio-native-tls v0.3.0 [INFO] [stderr] Compiling anstyle-query v1.1.3 [INFO] [stderr] Compiling colorchoice v1.0.4 [INFO] [stderr] Compiling bumpalo v3.18.1 [INFO] [stderr] Compiling idna_adapter v1.2.1 [INFO] [stderr] Compiling gimli v0.26.1 [INFO] [stderr] Compiling idna v1.0.3 [INFO] [stderr] Compiling h2 v0.4.10 [INFO] [stderr] Compiling h2 v0.3.27 [INFO] [stderr] Compiling url v2.5.4 [INFO] [stderr] Compiling anstyle v1.0.11 [INFO] [stderr] Compiling iri-string v0.7.8 [INFO] [stderr] Compiling anstream v0.6.19 [INFO] [stderr] Compiling zopfli v0.8.2 [INFO] [stderr] Compiling dirs-sys v0.5.0 [INFO] [stderr] Compiling tracing-serde v0.2.0 [INFO] [stderr] Compiling serde_urlencoded v0.7.1 [INFO] [stderr] Compiling addr2line v0.17.0 [INFO] [stderr] Compiling pin-project v1.1.10 [INFO] [stderr] Compiling zstd v0.13.3 [INFO] [stderr] Compiling tokio-util v0.6.9 [INFO] [stderr] Compiling tracing-subscriber v0.3.20 [INFO] [stderr] Compiling tower-http v0.6.6 [INFO] [stderr] Compiling hyper v1.6.0 [INFO] [stderr] Compiling gitlab-runner v0.3.0-rc1 [INFO] [stderr] Compiling liblzma v0.4.2 [INFO] [stderr] Compiling flate2 v1.1.2 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling rustls-pki-types v1.12.0 [INFO] [stderr] Compiling thiserror v1.0.30 [INFO] [stderr] Compiling pbkdf2 v0.12.2 [INFO] [stderr] Compiling thiserror-impl v2.0.12 [INFO] [stderr] Compiling bzip2 v0.5.2 [INFO] [stderr] Compiling aes v0.8.4 [INFO] [stderr] Compiling hyper-util v0.1.14 [INFO] [stderr] Compiling tracing-error v0.2.0 [INFO] [stderr] Compiling hyper v0.14.17 [INFO] [stderr] Compiling rand v0.9.1 [INFO] [stderr] Compiling heck v0.3.3 [INFO] [stderr] Compiling sha1 v0.10.1 [INFO] [stderr] Compiling time v0.1.43 [INFO] [stderr] Compiling object v0.27.1 [INFO] [stderr] Compiling rustc-demangle v0.1.21 [INFO] [stderr] Compiling clap_lex v0.7.5 [INFO] [stderr] Compiling deflate64 v0.1.9 [INFO] [stderr] Compiling camino v1.1.10 [INFO] [stderr] Compiling owo-colors v3.2.0 [INFO] [stderr] Compiling base64 v0.13.0 [INFO] [stderr] Compiling tempfile v3.20.0 [INFO] [stderr] Compiling hyper-tls v0.6.0 [INFO] [stderr] Compiling zip v4.1.0 [INFO] [stderr] Compiling clap_builder v4.5.40 [INFO] [stderr] Compiling reqwest v0.12.20 [INFO] [stderr] Compiling tokio-retry2 v0.5.7 [INFO] [stderr] Compiling chrono v0.4.19 [INFO] [stderr] Compiling thiserror v2.0.12 [INFO] [stderr] Compiling strum_macros v0.23.1 [INFO] [stderr] Compiling color-spantrace v0.2.0 [INFO] [stderr] Compiling dirs v6.0.0 [INFO] [stderr] Compiling quick-xml v0.22.0 [INFO] [stderr] Compiling strum_macros v0.27.1 [INFO] [stderr] Compiling async-trait v0.1.88 [INFO] [stderr] Compiling clap_derive v4.5.40 [INFO] [stderr] Compiling sha2 v0.10.9 [INFO] [stderr] Compiling hyper-tls v0.5.0 [INFO] [stderr] Compiling reqwest v0.11.9 [INFO] [stderr] Compiling md-5 v0.10.0 [INFO] [stderr] Compiling fmt2io v0.2.0 [INFO] [stderr] Compiling base16ct v0.1.1 [INFO] [stderr] Compiling glob v0.3.2 [INFO] [stderr] Compiling serde_yaml v0.9.34+deprecated [INFO] [stderr] Compiling clap v4.5.40 [INFO] [stderr] Compiling rfc822-like v0.2.1 [INFO] [stderr] Compiling color-eyre v0.6.0 [INFO] [stderr] Compiling strum v0.27.1 [INFO] [stderr] Compiling open-build-service-api v0.1.0 (https://github.com/collabora/open-build-service-rs#1bb9a29e) [INFO] [stderr] Compiling shellexpand v3.1.1 [INFO] [stderr] Compiling derivative v2.2.0 [INFO] [stderr] Compiling base16ct v0.2.0 [INFO] [stderr] Compiling obs-gitlab-runner v0.1.8 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 4m 03s [INFO] [stderr] warning: the following packages contain code that will be rejected by a future version of Rust: quick-xml v0.22.0 [INFO] [stderr] note: to see what the problems were, use the option `--future-incompat-report`, or run `cargo report future-incompatibilities --id 2` [INFO] running `Command { std: "docker" "inspect" "3093286b394c3cbb9ab0930a7d155ad435d374fd7d802cee52eb88b3bbe308b0", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3093286b394c3cbb9ab0930a7d155ad435d374fd7d802cee52eb88b3bbe308b0", kill_on_drop: false }` [INFO] [stdout] 3093286b394c3cbb9ab0930a7d155ad435d374fd7d802cee52eb88b3bbe308b0 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=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" "+d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 87973dd3bc6587523dbb405b0e850d0bdb2facdbe6195504d17926e7f3451c50 [INFO] running `Command { std: "docker" "start" "-a" "87973dd3bc6587523dbb405b0e850d0bdb2facdbe6195504d17926e7f3451c50", kill_on_drop: false }` [INFO] [stderr] Compiling memchr v2.7.5 [INFO] [stderr] Compiling value-bag v1.11.1 [INFO] [stderr] Compiling socket2 v0.4.4 [INFO] [stderr] Compiling cache-padded v1.2.0 [INFO] [stderr] Compiling url v2.5.4 [INFO] [stderr] Compiling anyhow v1.0.98 [INFO] [stderr] Compiling num_cpus v1.13.1 [INFO] [stderr] Compiling standback v0.2.17 [INFO] [stderr] Compiling concurrent-queue v1.2.2 [INFO] [stderr] Compiling event-listener v2.5.2 [INFO] [stderr] Compiling log v0.4.27 [INFO] [stderr] Compiling parking v2.0.0 [INFO] [stderr] Compiling fastrand v1.7.0 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling serde_json v1.0.140 [INFO] [stderr] Compiling cipher v0.2.5 [INFO] [stderr] Compiling crossbeam-utils v0.8.8 [INFO] [stderr] Compiling want v0.3.0 [INFO] [stderr] Compiling futures-lite v1.12.0 [INFO] [stderr] Compiling native-tls v0.2.14 [INFO] [stderr] Compiling universal-hash v0.4.0 [INFO] [stderr] Compiling digest v0.9.0 [INFO] [stderr] Compiling getrandom v0.2.16 [INFO] [stderr] Compiling lexical-core v0.7.6 [INFO] [stderr] Compiling const_fn v0.4.9 [INFO] [stderr] Compiling cpuid-bool v0.2.0 [INFO] [stderr] Compiling async-task v4.1.0 [INFO] [stderr] Compiling semver v1.0.6 [INFO] [stderr] Compiling polyval v0.4.5 [INFO] [stderr] Compiling rand_core v0.6.3 [INFO] [stderr] Compiling tokio-native-tls v0.3.0 [INFO] [stderr] Compiling time-macros-impl v0.1.2 [INFO] [stderr] Compiling aes-soft v0.6.4 [INFO] [stderr] Compiling getrandom v0.1.16 [INFO] [stderr] Compiling polling v2.2.0 [INFO] [stderr] Compiling aho-corasick v1.1.3 [INFO] [stderr] Compiling async-channel v1.6.1 [INFO] [stderr] Compiling crypto-mac v0.10.0 [INFO] [stderr] Compiling time v0.2.27 [INFO] [stderr] Compiling nom v5.1.2 [INFO] [stderr] Compiling arrayvec v0.5.2 [INFO] [stderr] Compiling blocking v1.1.0 [INFO] [stderr] Compiling async-io v1.6.0 [INFO] [stderr] Compiling time-macros v0.1.1 [INFO] [stderr] Compiling hmac v0.10.1 [INFO] [stderr] Compiling rand_core v0.5.1 [INFO] [stderr] Compiling aes v0.6.0 [INFO] [stderr] Compiling async-executor v1.4.1 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling vergen-lib v0.1.6 [INFO] [stderr] Compiling ghash v0.3.1 [INFO] [stderr] Compiling regex-automata v0.4.9 [INFO] [stderr] Compiling ctr v0.6.0 [INFO] [stderr] Compiling tracing-log v0.2.0 [INFO] [stderr] Compiling async-mutex v1.4.0 [INFO] [stderr] Compiling block-buffer v0.9.0 [INFO] [stderr] Compiling aead v0.3.2 [INFO] [stderr] Compiling cookie v0.14.4 [INFO] [stderr] Compiling crossbeam-queue v0.3.4 [INFO] [stderr] Compiling tracing-subscriber v0.3.20 [INFO] [stderr] Compiling aes-gcm v0.8.0 [INFO] [stderr] Compiling sha2 v0.9.9 [INFO] [stderr] Compiling rustc_version v0.4.1 [INFO] [stderr] Compiling tokio-util v0.7.15 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling async-global-executor v2.0.2 [INFO] [stderr] Compiling vergen v9.0.6 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling h2 v0.4.10 [INFO] [stderr] Compiling h2 v0.3.27 [INFO] [stderr] Compiling regex v1.11.1 [INFO] [stderr] Compiling rand_chacha v0.2.2 [INFO] [stderr] Compiling hkdf v0.10.0 [INFO] [stderr] Compiling assert-json-diff v2.0.1 [INFO] [stderr] Compiling kv-log-macro v1.0.7 [INFO] [stderr] Compiling async-lock v2.4.0 [INFO] [stderr] Compiling http-types v2.12.0 [INFO] [stderr] Compiling winnow v0.7.11 [INFO] [stderr] Compiling async-std v1.10.0 [INFO] [stderr] Compiling tower v0.5.2 [INFO] [stderr] Compiling rand v0.7.3 [INFO] [stderr] Compiling config v0.10.1 [INFO] [stderr] Compiling vergen-gitcl v1.0.8 [INFO] [stderr] Compiling hyper v1.6.0 [INFO] [stderr] Compiling toml_edit v0.22.27 [INFO] [stderr] Compiling rstest_macros v0.26.1 [INFO] [stderr] Compiling serde_qs v0.8.5 [INFO] [stderr] Compiling multer v3.1.0 [INFO] [stderr] Compiling futures-timer v3.0.3 [INFO] [stderr] Compiling infer v0.2.3 [INFO] [stderr] Compiling deadpool-runtime v0.1.4 [INFO] [stderr] Compiling zopfli v0.8.2 [INFO] [stderr] Compiling deadpool v0.10.0 [INFO] [stderr] Compiling hyper-util v0.1.14 [INFO] [stderr] Compiling tower-http v0.6.6 [INFO] [stderr] Compiling hyper v0.14.17 [INFO] [stderr] Compiling proc-macro-crate v3.3.0 [INFO] [stderr] Compiling deadpool v0.7.0 [INFO] [stderr] Compiling gitlab-runner v0.3.0-rc1 [INFO] [stderr] Compiling tracing-error v0.2.0 [INFO] [stderr] Compiling tokio-util v0.6.9 [INFO] [stderr] Compiling object v0.27.1 [INFO] [stderr] Compiling quick-xml v0.22.0 [INFO] [stderr] Compiling hyper-tls v0.6.0 [INFO] [stderr] Compiling relative-path v1.9.3 [INFO] [stderr] Compiling spin v0.9.8 [INFO] [stderr] Compiling color-spantrace v0.2.0 [INFO] [stderr] Compiling zip v4.1.0 [INFO] [stderr] Compiling reqwest v0.12.20 [INFO] [stderr] Compiling wiremock v0.6.4 [INFO] [stderr] Compiling strum v0.23.0 [INFO] [stderr] Compiling xml-builder v0.5.0 [INFO] [stderr] Compiling claims v0.8.0 [INFO] [stderr] Compiling gitlab-runner-mock v0.2.1 [INFO] [stderr] Compiling backtrace v0.3.64 [INFO] [stderr] Compiling color-eyre v0.6.0 [INFO] [stderr] Compiling hyper-tls v0.5.0 [INFO] [stderr] Compiling wiremock v0.5.10 [INFO] [stderr] Compiling reqwest v0.11.9 [INFO] [stderr] Compiling rstest v0.26.1 [INFO] [stderr] Compiling open-build-service-mock v0.1.0 (https://github.com/collabora/open-build-service-rs#1bb9a29e) [INFO] [stderr] Compiling open-build-service-api v0.1.0 (https://github.com/collabora/open-build-service-rs#1bb9a29e) [INFO] [stderr] Compiling obs-gitlab-runner v0.1.8 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 2m 48s [INFO] [stderr] warning: the following packages contain code that will be rejected by a future version of Rust: nom v5.1.2, quick-xml v0.22.0 [INFO] [stderr] note: to see what the problems were, use the option `--future-incompat-report`, or run `cargo report future-incompatibilities --id 3` [INFO] running `Command { std: "docker" "inspect" "87973dd3bc6587523dbb405b0e850d0bdb2facdbe6195504d17926e7f3451c50", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "87973dd3bc6587523dbb405b0e850d0bdb2facdbe6195504d17926e7f3451c50", kill_on_drop: false }` [INFO] [stdout] 87973dd3bc6587523dbb405b0e850d0bdb2facdbe6195504d17926e7f3451c50 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=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" "+d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 8a68a6092420cfed96777af598807f0f6fea0eaaa9ec25dbe6c1bc3981d037e2 [INFO] running `Command { std: "docker" "start" "-a" "8a68a6092420cfed96777af598807f0f6fea0eaaa9ec25dbe6c1bc3981d037e2", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.95s [INFO] [stderr] warning: the following packages contain code that will be rejected by a future version of Rust: nom v5.1.2, quick-xml v0.22.0 [INFO] [stderr] note: to see what the problems were, use the option `--future-incompat-report`, or run `cargo report future-incompatibilities --id 3` [INFO] [stderr] Running unittests src/main.rs (/opt/rustwide/target/debug/deps/obs_gitlab_runner-409b7d560d157acd) [INFO] [stdout] [INFO] [stdout] running 122 tests [INFO] [stdout] test dsc::tests::test_de ... ok [INFO] [stdout] test binaries::tests::test_build_results ... ok [INFO] [stdout] test build_meta::tests::test_build_meta_repos ... ok [INFO] [stdout] test build_meta::tests::test_build_meta_ignores_empty::skip_early_1_false::dirty_1_false ... ok [INFO] [stdout] test handler::tests::test_generate_monitor_timeouts::test_2_Some_GenerateMonitorTimeoutLocation__HandlerOption_ ... ok [INFO] [stdout] test build_meta::tests::test_build_meta_ignores_empty::skip_early_1_false::dirty_2_true ... ok [INFO] [stdout] test build_meta::tests::test_build_meta_ignores_empty::skip_early_2_true::dirty_1_false ... ok [INFO] [stdout] test build_meta::tests::test_build_meta_ignores_empty::skip_early_2_true::dirty_2_true ... ok [INFO] [stdout] test handler::tests::test_generate_monitor_timeouts::test_1_None ... ok [INFO] [stdout] test handler::tests::test_generate_monitor_timeouts::test_3_Some_GenerateMonitorTimeoutLocation__Argument_ ... ok [INFO] [stdout] test handler::tests::test_flag_parsing ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_1_true::log_test_1_MonitorLogTest__Long::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_1_true::log_test_2_MonitorLogTest__Short::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_1_true::log_test_2_MonitorLogTest__Short::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_1_true::log_test_1_MonitorLogTest__Long::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_1_true::log_test_2_MonitorLogTest__Short::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_1_true::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_1_true::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_1_true::log_test_2_MonitorLogTest__Short::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_1_true::log_test_3_MonitorLogTest__Unavailable::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_1_true::log_test_3_MonitorLogTest__Unavailable::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_1_true::log_test_3_MonitorLogTest__Unavailable::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_1_true::log_test_3_MonitorLogTest__Unavailable::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_2_false::log_test_2_MonitorLogTest__Short::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_2_false::log_test_2_MonitorLogTest__Short::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_2_false::log_test_2_MonitorLogTest__Short::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_2_false::log_test_3_MonitorLogTest__Unavailable::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_2_false::log_test_2_MonitorLogTest__Short::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_2_false::log_test_3_MonitorLogTest__Unavailable::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_2_false::log_test_3_MonitorLogTest__Unavailable::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1_DputTest__Basic::build_success_2_false::log_test_3_MonitorLogTest__Unavailable::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_1_true::log_test_1_MonitorLogTest__Long::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_1_true::log_test_2_MonitorLogTest__Short::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_1_true::log_test_1_MonitorLogTest__Long::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_1_true::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_1_true::log_test_2_MonitorLogTest__Short::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_1_true::log_test_2_MonitorLogTest__Short::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_1_true::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_1_true::log_test_2_MonitorLogTest__Short::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_1_true::log_test_3_MonitorLogTest__Unavailable::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_1_true::log_test_3_MonitorLogTest__Unavailable::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_1_true::log_test_3_MonitorLogTest__Unavailable::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_1_true::log_test_3_MonitorLogTest__Unavailable::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... FAILED [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_2_false::log_test_2_MonitorLogTest__Short::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_2_false::log_test_2_MonitorLogTest__Short::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_2_false::log_test_2_MonitorLogTest__Short::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_2_false::log_test_3_MonitorLogTest__Unavailable::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_2_false::log_test_2_MonitorLogTest__Short::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_2_false::log_test_3_MonitorLogTest__Unavailable::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_2_false::log_test_3_MonitorLogTest__Unavailable::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_2_false::log_test_3_MonitorLogTest__Unavailable::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_1_true::log_test_1_MonitorLogTest__Long::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_1_true::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_1_true::log_test_1_MonitorLogTest__Long::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_1_true::log_test_2_MonitorLogTest__Short::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_1_true::log_test_2_MonitorLogTest__Short::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_1_true::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_1_true::log_test_2_MonitorLogTest__Short::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_1_true::log_test_2_MonitorLogTest__Short::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_1_true::log_test_3_MonitorLogTest__Unavailable::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_1_true::log_test_3_MonitorLogTest__Unavailable::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_1_true::log_test_3_MonitorLogTest__Unavailable::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_1_true::log_test_3_MonitorLogTest__Unavailable::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_2_false::log_test_2_MonitorLogTest__Short::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_2_false::log_test_2_MonitorLogTest__Short::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_2_false::log_test_2_MonitorLogTest__Short::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_2_false::log_test_3_MonitorLogTest__Unavailable::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_2_false::log_test_2_MonitorLogTest__Short::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_2_false::log_test_3_MonitorLogTest__Unavailable::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_2_false::log_test_3_MonitorLogTest__Unavailable::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3_DputTest__ReusePreviousBuild::build_success_2_false::log_test_3_MonitorLogTest__Unavailable::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_1_true::log_test_1_MonitorLogTest__Long::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_1_true::log_test_1_MonitorLogTest__Long::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_1_true::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_1_true::log_test_2_MonitorLogTest__Short::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_1_true::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_1_true::log_test_2_MonitorLogTest__Short::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_1_true::log_test_2_MonitorLogTest__Short::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_1_true::log_test_2_MonitorLogTest__Short::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_1_true::log_test_3_MonitorLogTest__Unavailable::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_1_true::log_test_3_MonitorLogTest__Unavailable::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_1_true::log_test_3_MonitorLogTest__Unavailable::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_1_true::log_test_3_MonitorLogTest__Unavailable::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_2_false::log_test_2_MonitorLogTest__Short::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_2_false::log_test_2_MonitorLogTest__Short::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test monitor::tests::test_download_log ... ok [INFO] [stdout] test handler::tests::test_variable_expansion ... ok [INFO] [stdout] test monitor::tests::test_fails_after_repeated_duplicate_endtimes ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_2_false::log_test_2_MonitorLogTest__Short::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test monitor::tests::test_handles_old_build_status ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_2_false::log_test_2_MonitorLogTest__Short::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test monitor::tests::test_handles_missing_status ... ok [INFO] [stdout] test monitor::tests::test_latest_state ... ok [INFO] [stdout] test monitor::tests::test_srcmd5_checks ... ok [INFO] [stdout] test retry::tests::test_no_retry_on_client_errors ... ok [INFO] [stdout] test retry::tests::test_no_retry_on_nested_client_errors ... ok [INFO] [stdout] test prune::tests::test_prune ... ok [INFO] [stdout] test upload::tests::test_commit ... ok [INFO] [stdout] test upload::tests::test_create_list_package ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_2_false::log_test_3_MonitorLogTest__Unavailable::download_binaries_1_true::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test upload::tests::test_upload ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_2_false::log_test_3_MonitorLogTest__Unavailable::download_binaries_1_true::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_2_false::log_test_3_MonitorLogTest__Unavailable::download_binaries_2_false::prune_only_if_job_unsuccessful_1_true ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4_DputTest__Branch::build_success_2_false::log_test_3_MonitorLogTest__Unavailable::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false ... ok [INFO] [stdout] test retry::tests::test_retry_on_nested_non_client_errors ... ok [INFO] [stdout] test retry::tests::test_retry_on_non_client_errors ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false stdout ---- [INFO] [stdout] 2025-09-10T13:25:11.061820Z TRACE command{cmdline="dput foo test1-bad.dsc"}: obs_gitlab_runner::handler: > dput foo test1-bad.dsc gitlab.output=true [INFO] [stdout] 2025-09-10T13:25:11.085108Z DEBUG command{cmdline="dput foo test1-bad.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1-bad.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::handler: Saving initial build info: ObsBuildInfo { project: "foo", package: "bar", rev: None, srcmd5: None, is_branched: false, enabled_repos: {} } [INFO] [stdout] 2025-09-10T13:25:11.103137Z DEBUG command{cmdline="dput foo test1-bad.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1-bad.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:get_if_package_exists{project="foo" package="bar" options=BuildMetaOptions { history_retrieval: Full, disabled_repos: Keep }}:get{project="foo" package="bar" options=BuildMetaOptions { history_retrieval: Full, disabled_repos: Keep }}: obs_gitlab_runner::build_meta: project_meta=ProjectMeta { name: "foo", repositories: [RepositoryMeta { name: "repo", rebuild: Transitive, block: All, arches: ["x86_64", "aarch64"] }] } [INFO] [stdout] 2025-09-10T13:25:11.119977Z DEBUG command{cmdline="dput foo test1-bad.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1-bad.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::handler: initial_build_meta=Some(BuildMeta { client: Client { base: Url { scheme: "http", cannot_be_a_base: false, username: "", password: None, host: Some(Ipv4(127.0.0.1)), port: Some(39967), path: "/", query: None, fragment: None }, user: "user", pass: "[redacted]", client: Client { accepts: Accepts, proxies: [Proxy(System({}), None)], referer: true, default_headers: {"accept": "*/*"} } }, project: "foo", package: "bar", repos: {RepoArch { repo: "repo", arch: "aarch64" }: JobHistList { jobhist: [] }, RepoArch { repo: "repo", arch: "x86_64" }: JobHistList { jobhist: [] }} }) [INFO] [stdout] 2025-09-10T13:25:11.120087Z TRACE command{cmdline="dput foo test1-bad.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1-bad.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1-bad.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1.missing" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}: obs_gitlab_runner::upload: Uploading test1-bad.dsc to foo/bar... gitlab.output=true [INFO] [stdout] 2025-09-10T13:25:11.131256Z TRACE command{cmdline="dput foo test1-bad.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1-bad.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1-bad.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1.missing" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}: obs_gitlab_runner::upload: files_to_commit={"test1-bad.dsc": "c09a28e451b8586a98f614f9fe0742de", "_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1.missing": "202cb962ac59075b964b07152d234b70"} present_files={} [INFO] [stdout] 2025-09-10T13:25:11.143753Z TRACE command{cmdline="dput foo test1-bad.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1-bad.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1-bad.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1.missing" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}:commit{commit_message="test1-bad.dsc" root="" files={"test1-bad.dsc": "c09a28e451b8586a98f614f9fe0742de", "_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1.missing": "202cb962ac59075b964b07152d234b70"}}: obs_gitlab_runner::upload: Retry commit, missing 2 entries [INFO] [stdout] 2025-09-10T13:25:11.143822Z DEBUG command{cmdline="dput foo test1-bad.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1-bad.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1-bad.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1.missing" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}:commit{commit_message="test1-bad.dsc" root="" files={"test1-bad.dsc": "c09a28e451b8586a98f614f9fe0742de", "_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1.missing": "202cb962ac59075b964b07152d234b70"}}: obs_gitlab_runner::upload: missing=MissingEntries { entries: [CommitEntry { name: "test1-bad.dsc", md5: "c09a28e451b8586a98f614f9fe0742de" }, CommitEntry { name: "test1.missing", md5: "202cb962ac59075b964b07152d234b70" }] } [INFO] [stdout] 2025-09-10T13:25:11.143890Z DEBUG command{cmdline="dput foo test1-bad.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1-bad.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1-bad.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1.missing" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}:commit{commit_message="test1-bad.dsc" root="" files={"test1-bad.dsc": "c09a28e451b8586a98f614f9fe0742de", "_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1.missing": "202cb962ac59075b964b07152d234b70"}}:upload_file{root="" filename="test1-bad.dsc"}: obs_gitlab_runner::upload: Uploading file [INFO] [stdout] 2025-09-10T13:25:11.188041Z DEBUG command{cmdline="dput foo test1-bad.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1-bad.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1-bad.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1.missing" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}:commit{commit_message="test1-bad.dsc" root="" files={"test1-bad.dsc": "c09a28e451b8586a98f614f9fe0742de", "_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1.missing": "202cb962ac59075b964b07152d234b70"}}:upload_file{root="" filename="test1.missing"}: obs_gitlab_runner::upload: Uploading file [INFO] [stdout] 2025-09-10T13:25:11.192211Z ERROR obs_gitlab_runner::handler: Error running command: [INFO] [stdout] 0: \x1b[91mCould not find artifact 'test1.missing'\x1b[0m [INFO] [stdout] [INFO] [stdout] Location: [INFO] [stdout] \x1b[35msrc/artifacts.rs\x1b[0m:\x1b[35m34\x1b[0m [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ SPANTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout] [INFO] [stdout] 0: \x1b[91mobs_gitlab_runner::upload\x1b[0m\x1b[91m::\x1b[0m\x1b[91mupload_file\x1b[0m with \x1b[96m\x1b[3mroot\x1b[0m\x1b[2m=\x1b[0m"" \x1b[3mfilename\x1b[0m\x1b[2m=\x1b[0m"test1.missing"\x1b[0m [INFO] [stdout] at \x1b[35msrc/upload.rs\x1b[0m:\x1b[35m220\x1b[0m [INFO] [stdout] 218 │ } [INFO] [stdout] 219 │ [INFO] [stdout] \x1b[37;1m 220 > #[instrument(skip(self, artifacts))]\x1b[0m [INFO] [stdout] 221 │ async fn upload_file( [INFO] [stdout] 222 │ &self, [INFO] [stdout] 1: \x1b[91mobs_gitlab_runner::upload\x1b[0m\x1b[91m::\x1b[0m\x1b[91mcommit\x1b[0m with \x1b[96m\x1b[3mcommit_message\x1b[0m\x1b[2m=\x1b[0m"test1-bad.dsc" \x1b[3mroot\x1b[0m\x1b[2m=\x1b[0m"" \x1b[3mfiles\x1b[0m\x1b[2m=\x1b[0m{"test1-bad.dsc": "c09a28e451b8586a98f614f9fe0742de", "_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1.missing": "202cb962ac59075b964b07152d234b70"}\x1b[0m [INFO] [stdout] at \x1b[35msrc/upload.rs\x1b[0m:\x1b[35m243\x1b[0m [INFO] [stdout] 241 │ } [INFO] [stdout] 242 │ [INFO] [stdout] \x1b[37;1m 243 > #[instrument(skip(self, artifacts))]\x1b[0m [INFO] [stdout] 244 │ async fn commit( [INFO] [stdout] 245 │ &self, [INFO] [stdout] 2: \x1b[91mobs_gitlab_runner::upload\x1b[0m\x1b[91m::\x1b[0m\x1b[91mupload_package\x1b[0m with \x1b[96m\x1b[3mself\x1b[0m\x1b[2m=\x1b[0mObsDscUploader { project: "foo", package: "bar", dsc_path: "test1-bad.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1.missing" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }\x1b[0m [INFO] [stdout] at \x1b[35msrc/upload.rs\x1b[0m:\x1b[35m298\x1b[0m [INFO] [stdout] 296 │ } [INFO] [stdout] 297 │ [INFO] [stdout] \x1b[37;1m 298 > #[instrument(skip(artifacts))]\x1b[0m [INFO] [stdout] 299 │ pub async fn upload_package(&self, artifacts: &impl ArtifactDirectory) -> Result { [INFO] [stdout] 300 │ let dsc_parent = self [INFO] [stdout] 3: \x1b[91mobs_gitlab_runner::handler\x1b[0m\x1b[91m::\x1b[0m\x1b[91mrun_dput\x1b[0m with \x1b[96m\x1b[3margs\x1b[0m\x1b[2m=\x1b[0mDputAction { project: "foo", dsc: "test1-bad.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }\x1b[0m [INFO] [stdout] at \x1b[35msrc/handler.rs\x1b[0m:\x1b[35m278\x1b[0m [INFO] [stdout] 276 │ } [INFO] [stdout] 277 │ [INFO] [stdout] \x1b[37;1m 278 > #[instrument(skip(self))]\x1b[0m [INFO] [stdout] 279 │ async fn run_dput(&mut self, args: DputAction) -> Result<()> { [INFO] [stdout] 280 │ let branch_to = if !args.branch_to.is_empty() { [INFO] [stdout] 4: \x1b[91mobs_gitlab_runner::handler\x1b[0m\x1b[91m::\x1b[0m\x1b[91mcommand\x1b[0m with \x1b[96m\x1b[3mcmdline\x1b[0m\x1b[2m=\x1b[0m"dput foo test1-bad.dsc"\x1b[0m [INFO] [stdout] at \x1b[35msrc/handler.rs\x1b[0m:\x1b[35m578\x1b[0m [INFO] [stdout] 576 │ } [INFO] [stdout] 577 │ [INFO] [stdout] \x1b[37;1m 578 > #[instrument(skip(self))]\x1b[0m [INFO] [stdout] 579 │ async fn command(&mut self, cmdline: &str) -> Result<()> { [INFO] [stdout] 580 │ // TODO: inject user? [INFO] [stdout] 5: \x1b[91mgitlab_runner::gitlab::job\x1b[0m\x1b[91m::\x1b[0m\x1b[91mrun\x1b[0m with \x1b[96mgitlab.job=267\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/gitlab-runner-0.3.0-rc1/src/run.rs\x1b[0m:\x1b[35m252\x1b[0m [INFO] [stdout] 250 │ // the gitlab_runner::gitlab::job target is specified for crate users to depend on; Also it's [INFO] [stdout] 251 │ // at max verbosity (error), so it always shows up regardless of level. [INFO] [stdout] \x1b[37;1m 252 > #[tracing::instrument(target = "gitlab_runner::gitlab::job",\x1b[0m [INFO] [stdout] 253 │ level = "error", [INFO] [stdout] 254 │ skip_all, [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout] \x1b[96m ⋮ 5 frames hidden ⋮ \x1b[0m [INFO] [stdout] 6: \x1b[91mobs_gitlab_runner::artifacts::ArtifactDirectory::get::{{closure}}::{{closure}}\x1b[0m\x1b[90m::ha06f8e7ff1bdb763\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/workdir/src/artifacts.rs\x1b[0m:\x1b[35m34\x1b[0m [INFO] [stdout] 32 │ self.get_or_none(filename) [INFO] [stdout] 33 │ .await? [INFO] [stdout] \x1b[37;1m 34\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m .ok_or_else(|| eyre!("Could not find artifact '{}'", filename))\x1b[0m [INFO] [stdout] 35 │ } [INFO] [stdout] 36 │ [INFO] [stdout] 7: \x1b[32mcore::option::Option::ok_or_else\x1b[0m\x1b[90m::h0e530dafe5d76f68\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/option.rs\x1b[0m:\x1b[35m1362\x1b[0m [INFO] [stdout] 8: \x1b[91mobs_gitlab_runner::artifacts::ArtifactDirectory::get::{{closure}}\x1b[0m\x1b[90m::hf82db53b08358aab\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/workdir/src/artifacts.rs\x1b[0m:\x1b[35m34\x1b[0m [INFO] [stdout] 32 │ self.get_or_none(filename) [INFO] [stdout] 33 │ .await? [INFO] [stdout] \x1b[37;1m 34\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m .ok_or_else(|| eyre!("Could not find artifact '{}'", filename))\x1b[0m [INFO] [stdout] 35 │ } [INFO] [stdout] 36 │ [INFO] [stdout] 9: \x1b[32m as core::future::future::Future>::poll\x1b[0m\x1b[90m::h5917c22d00ae36c8\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/future/future.rs\x1b[0m:\x1b[35m133\x1b[0m [INFO] [stdout] 10: \x1b[91mobs_gitlab_runner::artifacts::ArtifactDirectory::get_file::{{closure}}\x1b[0m\x1b[90m::hebecf932c775044e\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/workdir/src/artifacts.rs\x1b[0m:\x1b[35m47\x1b[0m [INFO] [stdout] 45 │ [INFO] [stdout] 46 │ async fn get_file(&self, filename: &str) -> Result { [INFO] [stdout] \x1b[37;1m 47\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m let reader = self.get(filename).await?;\x1b[0m [INFO] [stdout] 48 │ async_save_to_tempfile(reader).await [INFO] [stdout] 49 │ } [INFO] [stdout] 11: \x1b[32m as core::future::future::Future>::poll\x1b[0m\x1b[90m::h3bad161537346b91\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/future/future.rs\x1b[0m:\x1b[35m133\x1b[0m [INFO] [stdout] 12: \x1b[91mobs_gitlab_runner::upload::ObsDscUploader::upload_file::{{closure}}::{{closure}}\x1b[0m\x1b[90m::h1db7adeb2a77a3b4\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/workdir/src/upload.rs\x1b[0m:\x1b[35m228\x1b[0m [INFO] [stdout] 226 │ ) -> Result<()> { [INFO] [stdout] 227 │ debug!("Uploading file"); [INFO] [stdout] \x1b[37;1m 228\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m let file = artifacts.get_file(root.join(filename).as_str()).await?;\x1b[0m [INFO] [stdout] 229 │ [INFO] [stdout] 230 │ retry_request!({ [INFO] [stdout] 13: \x1b[91m as core::future::future::Future>::poll\x1b[0m\x1b[90m::h8ef7c75efc2b0b59\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-0.1.41/src/instrument.rs\x1b[0m:\x1b[35m321\x1b[0m [INFO] [stdout] 319 │ let (span, inner) = self.project().span_and_inner_pin_mut(); [INFO] [stdout] 320 │ let _enter = span.enter(); [INFO] [stdout] \x1b[37;1m 321\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m inner.poll(cx)\x1b[0m [INFO] [stdout] 322 │ } [INFO] [stdout] 323 │ } [INFO] [stdout] 14: \x1b[91mobs_gitlab_runner::upload::ObsDscUploader::upload_file::{{closure}}\x1b[0m\x1b[90m::hf4a31866a3bc4cfa\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/workdir/src/upload.rs\x1b[0m:\x1b[35m220\x1b[0m [INFO] [stdout] 218 │ } [INFO] [stdout] 219 │ [INFO] [stdout] \x1b[37;1m 220\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m #[instrument(skip(self, artifacts))]\x1b[0m [INFO] [stdout] 221 │ async fn upload_file( [INFO] [stdout] 222 │ &self, [INFO] [stdout] 15: \x1b[91mobs_gitlab_runner::upload::ObsDscUploader::commit::{{closure}}::{{closure}}\x1b[0m\x1b[90m::hba15843d81128813\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/workdir/src/upload.rs\x1b[0m:\x1b[35m291\x1b[0m [INFO] [stdout] 289 │ [INFO] [stdout] 290 │ for entry in missing.entries { [INFO] [stdout] \x1b[37;1m 291\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m self.upload_file(root, &entry.name, artifacts).await?;\x1b[0m [INFO] [stdout] 292 │ } [INFO] [stdout] 293 │ } [INFO] [stdout] 16: \x1b[91m as core::future::future::Future>::poll\x1b[0m\x1b[90m::ha766c5bfccdaf38f\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-0.1.41/src/instrument.rs\x1b[0m:\x1b[35m321\x1b[0m [INFO] [stdout] 319 │ let (span, inner) = self.project().span_and_inner_pin_mut(); [INFO] [stdout] 320 │ let _enter = span.enter(); [INFO] [stdout] \x1b[37;1m 321\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m inner.poll(cx)\x1b[0m [INFO] [stdout] 322 │ } [INFO] [stdout] 323 │ } [INFO] [stdout] 17: \x1b[91mobs_gitlab_runner::upload::ObsDscUploader::commit::{{closure}}\x1b[0m\x1b[90m::h0890f48a4673aa58\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/workdir/src/upload.rs\x1b[0m:\x1b[35m243\x1b[0m [INFO] [stdout] 241 │ } [INFO] [stdout] 242 │ [INFO] [stdout] \x1b[37;1m 243\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m #[instrument(skip(self, artifacts))]\x1b[0m [INFO] [stdout] 244 │ async fn commit( [INFO] [stdout] 245 │ &self, [INFO] [stdout] 18: \x1b[91mobs_gitlab_runner::upload::ObsDscUploader::upload_package::{{closure}}::{{closure}}\x1b[0m\x1b[90m::h2b52433af5553d86\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/workdir/src/upload.rs\x1b[0m:\x1b[35m338\x1b[0m [INFO] [stdout] 336 │ ( [INFO] [stdout] 337 │ self.commit(dsc_filename, dsc_parent, files_to_commit, artifacts) [INFO] [stdout] \x1b[37;1m 338\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m .await?,\x1b[0m [INFO] [stdout] 339 │ false, [INFO] [stdout] 340 │ ) [INFO] [stdout] 19: \x1b[91m as core::future::future::Future>::poll\x1b[0m\x1b[90m::hbea4ec03ce71db27\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-0.1.41/src/instrument.rs\x1b[0m:\x1b[35m321\x1b[0m [INFO] [stdout] 319 │ let (span, inner) = self.project().span_and_inner_pin_mut(); [INFO] [stdout] 320 │ let _enter = span.enter(); [INFO] [stdout] \x1b[37;1m 321\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m inner.poll(cx)\x1b[0m [INFO] [stdout] 322 │ } [INFO] [stdout] 323 │ } [INFO] [stdout] 20: \x1b[91mobs_gitlab_runner::upload::ObsDscUploader::upload_package::{{closure}}\x1b[0m\x1b[90m::h66d68db0258da416\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/workdir/src/upload.rs\x1b[0m:\x1b[35m298\x1b[0m [INFO] [stdout] 296 │ } [INFO] [stdout] 297 │ [INFO] [stdout] \x1b[37;1m 298\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m #[instrument(skip(artifacts))]\x1b[0m [INFO] [stdout] 299 │ pub async fn upload_package(&self, artifacts: &impl ArtifactDirectory) -> Result { [INFO] [stdout] 300 │ let dsc_parent = self [INFO] [stdout] 21: \x1b[91mobs_gitlab_runner::handler::ObsJobHandler::run_dput::{{closure}}::{{closure}}\x1b[0m\x1b[90m::h4a1f02766dd887b2\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/workdir/src/handler.rs\x1b[0m:\x1b[35m332\x1b[0m [INFO] [stdout] 330 │ debug!(?initial_build_meta); [INFO] [stdout] 331 │ [INFO] [stdout] \x1b[37;1m 332\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m let result = uploader.upload_package(self).await?;\x1b[0m [INFO] [stdout] 333 │ [INFO] [stdout] 334 │ // If we couldn't get the metadata before because the package didn't [INFO] [stdout] 22: \x1b[91m as core::future::future::Future>::poll\x1b[0m\x1b[90m::h8d97a2c304120e83\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-0.1.41/src/instrument.rs\x1b[0m:\x1b[35m321\x1b[0m [INFO] [stdout] 319 │ let (span, inner) = self.project().span_and_inner_pin_mut(); [INFO] [stdout] 320 │ let _enter = span.enter(); [INFO] [stdout] \x1b[37;1m 321\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m inner.poll(cx)\x1b[0m [INFO] [stdout] 322 │ } [INFO] [stdout] 323 │ } [INFO] [stdout] 23: \x1b[91mobs_gitlab_runner::handler::ObsJobHandler::run_dput::{{closure}}\x1b[0m\x1b[90m::hd0c637221da1b6d8\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/workdir/src/handler.rs\x1b[0m:\x1b[35m278\x1b[0m [INFO] [stdout] 276 │ } [INFO] [stdout] 277 │ [INFO] [stdout] \x1b[37;1m 278\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m #[instrument(skip(self))]\x1b[0m [INFO] [stdout] 279 │ async fn run_dput(&mut self, args: DputAction) -> Result<()> { [INFO] [stdout] 280 │ let branch_to = if !args.branch_to.is_empty() { [INFO] [stdout] 24: \x1b[91mobs_gitlab_runner::handler::ObsJobHandler::command::{{closure}}::{{closure}}\x1b[0m\x1b[90m::h4badadd93a179b68\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/workdir/src/handler.rs\x1b[0m:\x1b[35m589\x1b[0m [INFO] [stdout] 587 │ [INFO] [stdout] 588 │ match command.action { [INFO] [stdout] \x1b[37;1m 589\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m Action::Dput(args) => self.run_dput(args).await?,\x1b[0m [INFO] [stdout] 590 │ Action::GenerateMonitor(args) => self.run_generate_monitor(args).await?, [INFO] [stdout] 591 │ Action::Monitor(args) => self.run_monitor(args).await?, [INFO] [stdout] 25: \x1b[91m as core::future::future::Future>::poll\x1b[0m\x1b[90m::hef32c27fef28df72\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-0.1.41/src/instrument.rs\x1b[0m:\x1b[35m321\x1b[0m [INFO] [stdout] 319 │ let (span, inner) = self.project().span_and_inner_pin_mut(); [INFO] [stdout] 320 │ let _enter = span.enter(); [INFO] [stdout] \x1b[37;1m 321\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m inner.poll(cx)\x1b[0m [INFO] [stdout] 322 │ } [INFO] [stdout] 323 │ } [INFO] [stdout] 26: \x1b[91mobs_gitlab_runner::handler::ObsJobHandler::command::{{closure}}\x1b[0m\x1b[90m::h3c0e29412fbc4406\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/workdir/src/handler.rs\x1b[0m:\x1b[35m578\x1b[0m [INFO] [stdout] 576 │ } [INFO] [stdout] 577 │ [INFO] [stdout] \x1b[37;1m 578\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m #[instrument(skip(self))]\x1b[0m [INFO] [stdout] 579 │ async fn command(&mut self, cmdline: &str) -> Result<()> { [INFO] [stdout] 580 │ // TODO: inject user? [INFO] [stdout] 27: \x1b[91m>::step::{{closure}}\x1b[0m\x1b[90m::h621b33597b94abdd\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/workdir/src/handler.rs\x1b[0m:\x1b[35m640\x1b[0m [INFO] [stdout] 638 │ async fn step(&mut self, script: &[String], _phase: Phase) -> JobResult { [INFO] [stdout] 639 │ for command in script { [INFO] [stdout] \x1b[37;1m 640\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m if let Err(err) = self.command(command).await {\x1b[0m [INFO] [stdout] 641 │ // Failed builds would already have information on them printed [INFO] [stdout] 642 │ // above, so don't print anything on them again. [INFO] [stdout] 28: \x1b[32m as core::future::future::Future>::poll\x1b[0m\x1b[90m::hfa1bf70bf0537e9c\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/future/future.rs\x1b[0m:\x1b[35m133\x1b[0m [INFO] [stdout] 29: \x1b[91m>::step::{{closure}}::{{closure}}\x1b[0m\x1b[90m::h3e28d0a902be6bf1\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/macros/select.rs\x1b[0m:\x1b[35m708\x1b[0m [INFO] [stdout] 706 │ [INFO] [stdout] 707 │ // Try polling it [INFO] [stdout] \x1b[37;1m 708\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m let out = match Future::poll(fut, cx) {\x1b[0m [INFO] [stdout] 709 │ Ready(out) => out, [INFO] [stdout] 710 │ Pending => { [INFO] [stdout] 30: \x1b[32m as core::future::future::Future>::poll\x1b[0m\x1b[90m::h8eb50ebd1aa55a89\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/future/poll_fn.rs\x1b[0m:\x1b[35m151\x1b[0m [INFO] [stdout] 31: \x1b[91m>::step::{{closure}}\x1b[0m\x1b[90m::h55225c6a90147160\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/gitlab-runner-0.3.0-rc1/src/lib.rs\x1b[0m:\x1b[35m198\x1b[0m [INFO] [stdout] 196 │ cancel_token: &CancellationToken, [INFO] [stdout] 197 │ ) -> JobResult { [INFO] [stdout] \x1b[37;1m 198\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m tokio::select! {\x1b[0m [INFO] [stdout] 199 │ r = self.step(script, phase) => r, [INFO] [stdout] 200 │ _ = cancel_token.cancelled() => Ok(()), [INFO] [stdout] 32: \x1b[32m as core::future::future::Future>::poll\x1b[0m\x1b[90m::hfa1bf70bf0537e9c\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/future/future.rs\x1b[0m:\x1b[35m133\x1b[0m [INFO] [stdout] 33: \x1b[91mgitlab_runner::run::run::{{closure}}\x1b[0m\x1b[90m::h5ffe67afea742496\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/gitlab-runner-0.3.0-rc1/src/run.rs\x1b[0m:\x1b[35m42\x1b[0m [INFO] [stdout] 40 │ let script_result = handler [INFO] [stdout] 41 │ .step(&script.script, Phase::Script, &cancel_token) [INFO] [stdout] \x1b[37;1m 42\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m .await;\x1b[0m [INFO] [stdout] 43 │ [INFO] [stdout] 44 │ if !cancel_token.is_cancelled() { [INFO] [stdout] 34: \x1b[91m as core::future::future::Future>::poll\x1b[0m\x1b[90m::h696126a2bab9ead5\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-0.1.41/src/instrument.rs\x1b[0m:\x1b[35m321\x1b[0m [INFO] [stdout] 319 │ let (span, inner) = self.project().span_and_inner_pin_mut(); [INFO] [stdout] 320 │ let _enter = span.enter(); [INFO] [stdout] \x1b[37;1m 321\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m inner.poll(cx)\x1b[0m [INFO] [stdout] 322 │ } [INFO] [stdout] 323 │ } [INFO] [stdout] 35: \x1b[91m as core::future::future::Future>::poll\x1b[0m\x1b[90m::hcabe335adab54b37\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-0.1.41/src/instrument.rs\x1b[0m:\x1b[35m388\x1b[0m [INFO] [stdout] 386 │ let future = this.inner; [INFO] [stdout] 387 │ let _default = dispatcher::set_default(dispatcher); [INFO] [stdout] \x1b[37;1m 388\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m future.poll(cx)\x1b[0m [INFO] [stdout] 389 │ } [INFO] [stdout] 390 │ } [INFO] [stdout] 36: \x1b[32m as core::future::future::Future>::poll\x1b[0m\x1b[90m::h578b916e144d771d\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/future/future.rs\x1b[0m:\x1b[35m133\x1b[0m [INFO] [stdout] 37: \x1b[91mtokio::runtime::task::core::Core::poll::{{closure}}\x1b[0m\x1b[90m::h6f4acfa140acd576\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/task/core.rs\x1b[0m:\x1b[35m331\x1b[0m [INFO] [stdout] 329 │ [INFO] [stdout] 330 │ let _guard = TaskIdGuard::enter(self.task_id); [INFO] [stdout] \x1b[37;1m 331\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m future.poll(&mut cx)\x1b[0m [INFO] [stdout] 332 │ }) [INFO] [stdout] 333 │ }; [INFO] [stdout] 38: \x1b[91mtokio::loom::std::unsafe_cell::UnsafeCell::with_mut\x1b[0m\x1b[90m::h777d99cd2f6394bb\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/loom/std/unsafe_cell.rs\x1b[0m:\x1b[35m16\x1b[0m [INFO] [stdout] 14 │ #[inline(always)] [INFO] [stdout] 15 │ pub(crate) fn with_mut(&self, f: impl FnOnce(*mut T) -> R) -> R { [INFO] [stdout] \x1b[37;1m 16\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m f(self.0.get())\x1b[0m [INFO] [stdout] 17 │ } [INFO] [stdout] 18 │ } [INFO] [stdout] 39: \x1b[91mtokio::runtime::task::core::Core::poll\x1b[0m\x1b[90m::hf447ae4a2e0f8bde\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/task/core.rs\x1b[0m:\x1b[35m320\x1b[0m [INFO] [stdout] 318 │ pub(super) fn poll(&self, mut cx: Context<'_>) -> Poll { [INFO] [stdout] 319 │ let res = { [INFO] [stdout] \x1b[37;1m 320\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m self.stage.stage.with_mut(|ptr| {\x1b[0m [INFO] [stdout] 321 │ // Safety: The caller ensures mutual exclusion to the field. [INFO] [stdout] 322 │ let future = match unsafe { &mut *ptr } { [INFO] [stdout] 40: \x1b[91mtokio::runtime::task::harness::poll_future::{{closure}}\x1b[0m\x1b[90m::h86b055a44017753b\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/task/harness.rs\x1b[0m:\x1b[35m532\x1b[0m [INFO] [stdout] 530 │ } [INFO] [stdout] 531 │ let guard = Guard { core }; [INFO] [stdout] \x1b[37;1m 532\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m let res = guard.core.poll(cx);\x1b[0m [INFO] [stdout] 533 │ mem::forget(guard); [INFO] [stdout] 534 │ res [INFO] [stdout] 41: \x1b[32m as core::ops::function::FnOnce<()>>::call_once\x1b[0m\x1b[90m::hc2080fce84d1609e\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/panic/unwind_safe.rs\x1b[0m:\x1b[35m274\x1b[0m [INFO] [stdout] 42: \x1b[32mstd::panicking::catch_unwind::do_call\x1b[0m\x1b[90m::h3a2dad973ae396d7\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/panicking.rs\x1b[0m:\x1b[35m590\x1b[0m [INFO] [stdout] 43: \x1b[32m__rust_try\x1b[0m\x1b[90m\x1b[0m [INFO] [stdout] at \x1b[35m\x1b[0m:\x1b[35m\x1b[0m [INFO] [stdout] 44: \x1b[32mstd::panicking::catch_unwind\x1b[0m\x1b[90m::h55c63dea7ff28b21\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/panicking.rs\x1b[0m:\x1b[35m553\x1b[0m [INFO] [stdout] 45: \x1b[32mstd::panic::catch_unwind\x1b[0m\x1b[90m::hc1651bb819dc2ef7\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/panic.rs\x1b[0m:\x1b[35m359\x1b[0m [INFO] [stdout] 46: \x1b[91mtokio::runtime::task::harness::poll_future\x1b[0m\x1b[90m::he0a89dc82d4a00ec\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/task/harness.rs\x1b[0m:\x1b[35m520\x1b[0m [INFO] [stdout] 518 │ fn poll_future(core: &Core, cx: Context<'_>) -> Poll<()> { [INFO] [stdout] 519 │ // Poll the future. [INFO] [stdout] \x1b[37;1m 520\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m let output = panic::catch_unwind(panic::AssertUnwindSafe(|| {\x1b[0m [INFO] [stdout] 521 │ struct Guard<'a, T: Future, S: Schedule> { [INFO] [stdout] 522 │ core: &'a Core, [INFO] [stdout] 47: \x1b[91mtokio::runtime::task::harness::Harness::poll_inner\x1b[0m\x1b[90m::h72f177bfb0508d1c\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/task/harness.rs\x1b[0m:\x1b[35m209\x1b[0m [INFO] [stdout] 207 │ let waker_ref = waker_ref::(&header_ptr); [INFO] [stdout] 208 │ let cx = Context::from_waker(&waker_ref); [INFO] [stdout] \x1b[37;1m 209\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m let res = poll_future(self.core(), cx);\x1b[0m [INFO] [stdout] 210 │ [INFO] [stdout] 211 │ if res == Poll::Ready(()) { [INFO] [stdout] 48: \x1b[91mtokio::runtime::task::harness::Harness::poll\x1b[0m\x1b[90m::h3eaa8f34b17dae3f\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/task/harness.rs\x1b[0m:\x1b[35m154\x1b[0m [INFO] [stdout] 152 │ pub(super) fn poll(self) { [INFO] [stdout] 153 │ // We pass our ref-count to `poll_inner`. [INFO] [stdout] \x1b[37;1m 154\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m match self.poll_inner() {\x1b[0m [INFO] [stdout] 155 │ PollFuture::Notified => { [INFO] [stdout] 156 │ // The `poll_inner` call has given us two ref-counts back. [INFO] [stdout] 49: \x1b[91mtokio::runtime::task::raw::poll\x1b[0m\x1b[90m::h1f8d4a5c25199ad3\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/task/raw.rs\x1b[0m:\x1b[35m271\x1b[0m [INFO] [stdout] 269 │ unsafe fn poll(ptr: NonNull
) { [INFO] [stdout] 270 │ let harness = Harness::::from_raw(ptr); [INFO] [stdout] \x1b[37;1m 271\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m harness.poll();\x1b[0m [INFO] [stdout] 272 │ } [INFO] [stdout] 273 │ [INFO] [stdout] 50: \x1b[91mtokio::runtime::task::raw::RawTask::poll\x1b[0m\x1b[90m::hd6737340f5e784e3\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/task/raw.rs\x1b[0m:\x1b[35m201\x1b[0m [INFO] [stdout] 199 │ pub(crate) fn poll(self) { [INFO] [stdout] 200 │ let vtable = self.header().vtable; [INFO] [stdout] \x1b[37;1m 201\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m unsafe { (vtable.poll)(self.ptr) }\x1b[0m [INFO] [stdout] 202 │ } [INFO] [stdout] 203 │ [INFO] [stdout] 51: \x1b[91mtokio::runtime::task::LocalNotified::run\x1b[0m\x1b[90m::ha09ae575dd4dfb14\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/task/mod.rs\x1b[0m:\x1b[35m463\x1b[0m [INFO] [stdout] 461 │ let raw = self.task.raw; [INFO] [stdout] 462 │ mem::forget(self); [INFO] [stdout] \x1b[37;1m 463\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m raw.poll();\x1b[0m [INFO] [stdout] 464 │ } [INFO] [stdout] 465 │ } [INFO] [stdout] 52: \x1b[91mtokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}\x1b[0m\x1b[90m::h2df0f66f91d150ec\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs\x1b[0m:\x1b[35m780\x1b[0m [INFO] [stdout] 778 │ context.handle.task_hooks.poll_start_callback(task_id); [INFO] [stdout] 779 │ [INFO] [stdout] \x1b[37;1m 780\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m task.run();\x1b[0m [INFO] [stdout] 781 │ [INFO] [stdout] 782 │ #[cfg(tokio_unstable)] [INFO] [stdout] 53: \x1b[91mtokio::task::coop::with_budget\x1b[0m\x1b[90m::h001ced96b22eb698\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/task/coop/mod.rs\x1b[0m:\x1b[35m167\x1b[0m [INFO] [stdout] 165 │ // The function is called regardless even if the budget is not successfully [INFO] [stdout] 166 │ // set due to the thread-local being destroyed. [INFO] [stdout] \x1b[37;1m 167\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m f()\x1b[0m [INFO] [stdout] 168 │ } [INFO] [stdout] 169 │ [INFO] [stdout] 54: \x1b[91mtokio::task::coop::budget\x1b[0m\x1b[90m::hf24acd21b59b24bf\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/task/coop/mod.rs\x1b[0m:\x1b[35m133\x1b[0m [INFO] [stdout] 131 │ #[inline(always)] [INFO] [stdout] 132 │ pub(crate) fn budget(f: impl FnOnce() -> R) -> R { [INFO] [stdout] \x1b[37;1m 133\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m with_budget(Budget::initial(), f)\x1b[0m [INFO] [stdout] 134 │ } [INFO] [stdout] 135 │ [INFO] [stdout] 55: \x1b[91mtokio::runtime::scheduler::current_thread::Context::run_task::{{closure}}\x1b[0m\x1b[90m::h6fa01513caf25a6a\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs\x1b[0m:\x1b[35m368\x1b[0m [INFO] [stdout] 366 │ fn run_task(&self, mut core: Box, f: impl FnOnce() -> R) -> (Box, R) { [INFO] [stdout] 367 │ core.metrics.start_poll(); [INFO] [stdout] \x1b[37;1m 368\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m let mut ret = self.enter(core, || crate::task::coop::budget(f));\x1b[0m [INFO] [stdout] 369 │ ret.0.metrics.end_poll(); [INFO] [stdout] 370 │ ret [INFO] [stdout] 56: \x1b[91mtokio::runtime::scheduler::current_thread::Context::enter\x1b[0m\x1b[90m::h3aa238526aa8176d\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs\x1b[0m:\x1b[35m432\x1b[0m [INFO] [stdout] 430 │ [INFO] [stdout] 431 │ // Execute the closure while tracking the execution budget [INFO] [stdout] \x1b[37;1m 432\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m let ret = f();\x1b[0m [INFO] [stdout] 433 │ [INFO] [stdout] 434 │ // Take the scheduler core back [INFO] [stdout] 57: \x1b[91mtokio::runtime::scheduler::current_thread::Context::run_task\x1b[0m\x1b[90m::h098b990c34b0e1bf\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs\x1b[0m:\x1b[35m368\x1b[0m [INFO] [stdout] 366 │ fn run_task(&self, mut core: Box, f: impl FnOnce() -> R) -> (Box, R) { [INFO] [stdout] 367 │ core.metrics.start_poll(); [INFO] [stdout] \x1b[37;1m 368\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m let mut ret = self.enter(core, || crate::task::coop::budget(f));\x1b[0m [INFO] [stdout] 369 │ ret.0.metrics.end_poll(); [INFO] [stdout] 370 │ ret [INFO] [stdout] 58: \x1b[91mtokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}\x1b[0m\x1b[90m::h8cb4cea3eb3454cb\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs\x1b[0m:\x1b[35m776\x1b[0m [INFO] [stdout] 774 │ let task_id = task.task_id(); [INFO] [stdout] 775 │ [INFO] [stdout] \x1b[37;1m 776\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m let (c, ()) = context.run_task(core, || {\x1b[0m [INFO] [stdout] 777 │ #[cfg(tokio_unstable)] [INFO] [stdout] 778 │ context.handle.task_hooks.poll_start_callback(task_id); [INFO] [stdout] 59: \x1b[91mtokio::runtime::scheduler::current_thread::CoreGuard::enter::{{closure}}\x1b[0m\x1b[90m::hb66af98cd9be45a2\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs\x1b[0m:\x1b[35m820\x1b[0m [INFO] [stdout] 818 │ [INFO] [stdout] 819 │ // Call the closure and place `core` back [INFO] [stdout] \x1b[37;1m 820\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m let (core, ret) = context::set_scheduler(&self.context, || f(core, context));\x1b[0m [INFO] [stdout] 821 │ [INFO] [stdout] 822 │ *context.core.borrow_mut() = Some(core); [INFO] [stdout] 60: \x1b[91mtokio::runtime::context::scoped::Scoped::set\x1b[0m\x1b[90m::h7397be9bebccfdf1\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/context/scoped.rs\x1b[0m:\x1b[35m40\x1b[0m [INFO] [stdout] 38 │ }; [INFO] [stdout] 39 │ [INFO] [stdout] \x1b[37;1m 40\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m f()\x1b[0m [INFO] [stdout] 41 │ } [INFO] [stdout] 42 │ [INFO] [stdout] 61: \x1b[91mtokio::runtime::context::set_scheduler::{{closure}}\x1b[0m\x1b[90m::h38856e221efabd1c\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/context.rs\x1b[0m:\x1b[35m176\x1b[0m [INFO] [stdout] 174 │ [INFO] [stdout] 175 │ pub(super) fn set_scheduler(v: &scheduler::Context, f: impl FnOnce() -> R) -> R { [INFO] [stdout] \x1b[37;1m 176\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m CONTEXT.with(|c| c.scheduler.set(v, f))\x1b[0m [INFO] [stdout] 177 │ } [INFO] [stdout] 178 │ [INFO] [stdout] 62: \x1b[32mstd::thread::local::LocalKey::try_with\x1b[0m\x1b[90m::h6c066c5ca866cfaa\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/thread/local.rs\x1b[0m:\x1b[35m315\x1b[0m [INFO] [stdout] 63: \x1b[32mstd::thread::local::LocalKey::with\x1b[0m\x1b[90m::h960a58c9a659dea0\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/thread/local.rs\x1b[0m:\x1b[35m279\x1b[0m [INFO] [stdout] 64: \x1b[91mtokio::runtime::context::set_scheduler\x1b[0m\x1b[90m::h55d3eeaaa96a30f8\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/context.rs\x1b[0m:\x1b[35m176\x1b[0m [INFO] [stdout] 174 │ [INFO] [stdout] 175 │ pub(super) fn set_scheduler(v: &scheduler::Context, f: impl FnOnce() -> R) -> R { [INFO] [stdout] \x1b[37;1m 176\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m CONTEXT.with(|c| c.scheduler.set(v, f))\x1b[0m [INFO] [stdout] 177 │ } [INFO] [stdout] 178 │ [INFO] [stdout] 65: \x1b[91mtokio::runtime::scheduler::current_thread::CoreGuard::enter\x1b[0m\x1b[90m::hdf1d264b594aff6d\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs\x1b[0m:\x1b[35m820\x1b[0m [INFO] [stdout] 818 │ [INFO] [stdout] 819 │ // Call the closure and place `core` back [INFO] [stdout] \x1b[37;1m 820\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m let (core, ret) = context::set_scheduler(&self.context, || f(core, context));\x1b[0m [INFO] [stdout] 821 │ [INFO] [stdout] 822 │ *context.core.borrow_mut() = Some(core); [INFO] [stdout] 66: \x1b[91mtokio::runtime::scheduler::current_thread::CoreGuard::block_on\x1b[0m\x1b[90m::h764198b1b79b7da3\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs\x1b[0m:\x1b[35m720\x1b[0m [INFO] [stdout] 718 │ #[track_caller] [INFO] [stdout] 719 │ fn block_on(self, future: F) -> F::Output { [INFO] [stdout] \x1b[37;1m 720\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m let ret = self.enter(|mut core, context| {\x1b[0m [INFO] [stdout] 721 │ let waker = Handle::waker_ref(&context.handle); [INFO] [stdout] 722 │ let mut cx = std::task::Context::from_waker(&waker); [INFO] [stdout] 67: \x1b[91mtokio::runtime::scheduler::current_thread::CurrentThread::block_on::{{closure}}\x1b[0m\x1b[90m::h6814c816b8008647\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs\x1b[0m:\x1b[35m200\x1b[0m [INFO] [stdout] 198 │ .worker_metrics [INFO] [stdout] 199 │ .set_thread_id(thread::current().id()); [INFO] [stdout] \x1b[37;1m 200\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m return core.block_on(future);\x1b[0m [INFO] [stdout] 201 │ } else { [INFO] [stdout] 202 │ let notified = self.notify.notified(); [INFO] [stdout] 68: \x1b[91mtokio::runtime::context::runtime::enter_runtime\x1b[0m\x1b[90m::h28e302dc58913760\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/context/runtime.rs\x1b[0m:\x1b[35m65\x1b[0m [INFO] [stdout] 63 │ [INFO] [stdout] 64 │ if let Some(mut guard) = maybe_guard { [INFO] [stdout] \x1b[37;1m 65\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m return f(&mut guard.blocking);\x1b[0m [INFO] [stdout] 66 │ } [INFO] [stdout] 67 │ [INFO] [stdout] 69: \x1b[91mtokio::runtime::scheduler::current_thread::CurrentThread::block_on\x1b[0m\x1b[90m::h8e7aa5c98bbcfec2\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs\x1b[0m:\x1b[35m188\x1b[0m [INFO] [stdout] 186 │ pin!(future); [INFO] [stdout] 187 │ [INFO] [stdout] \x1b[37;1m 188\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m crate::runtime::context::enter_runtime(handle, false, |blocking| {\x1b[0m [INFO] [stdout] 189 │ let handle = handle.as_current_thread(); [INFO] [stdout] 190 │ [INFO] [stdout] 70: \x1b[91mtokio::runtime::runtime::Runtime::block_on_inner\x1b[0m\x1b[90m::h84a0e260c467446d\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/runtime.rs\x1b[0m:\x1b[35m356\x1b[0m [INFO] [stdout] 354 │ [INFO] [stdout] 355 │ match &self.scheduler { [INFO] [stdout] \x1b[37;1m 356\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m Scheduler::CurrentThread(exec) => exec.block_on(&self.handle.inner, future),\x1b[0m [INFO] [stdout] 357 │ #[cfg(feature = "rt-multi-thread")] [INFO] [stdout] 358 │ Scheduler::MultiThread(exec) => exec.block_on(&self.handle.inner, future), [INFO] [stdout] 71: \x1b[91mtokio::runtime::runtime::Runtime::block_on\x1b[0m\x1b[90m::hcb274c9a98ea0eee\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/runtime.rs\x1b[0m:\x1b[35m330\x1b[0m [INFO] [stdout] 328 │ self.block_on_inner(Box::pin(future), SpawnMeta::new_unnamed(fut_size)) [INFO] [stdout] 329 │ } else { [INFO] [stdout] \x1b[37;1m 330\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m self.block_on_inner(future, SpawnMeta::new_unnamed(fut_size))\x1b[0m [INFO] [stdout] 331 │ } [INFO] [stdout] 332 │ } [INFO] [stdout] 72: \x1b[91mobs_gitlab_runner::handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false\x1b[0m\x1b[90m::hc858a9a2016cdce4\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/workdir/src/handler.rs\x1b[0m:\x1b[35m1716\x1b[0m [INFO] [stdout] 1714 │ } [INFO] [stdout] 1715 │ [INFO] [stdout] \x1b[37;1m 1716\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m #[rstest]\x1b[0m [INFO] [stdout] 1717 │ #[tokio::test] [INFO] [stdout] 1718 │ async fn test_handler_flow( [INFO] [stdout] 73: \x1b[91mobs_gitlab_runner::handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false::{{closure}}\x1b[0m\x1b[90m::h7c51ecac5da4c899\x1b[0m [INFO] [stdout] at \x1b[35m/opt/rustwide/workdir/src/handler.rs\x1b[0m:\x1b[35m1716\x1b[0m [INFO] [stdout] 1714 │ } [INFO] [stdout] 1715 │ [INFO] [stdout] \x1b[37;1m 1716\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m #[rstest]\x1b[0m [INFO] [stdout] 1717 │ #[tokio::test] [INFO] [stdout] 1718 │ async fn test_handler_flow( [INFO] [stdout] 74: \x1b[32mcore::ops::function::FnOnce::call_once\x1b[0m\x1b[90m::h551c8a7ebde95699\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/ops/function.rs\x1b[0m:\x1b[35m253\x1b[0m [INFO] [stdout] 75: \x1b[32mcore::ops::function::FnOnce::call_once\x1b[0m\x1b[90m::h6784bd5bdcff97de\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/ops/function.rs\x1b[0m:\x1b[35m253\x1b[0m [INFO] [stdout] 76: \x1b[32mtest::__rust_begin_short_backtrace\x1b[0m\x1b[90m::h798bd19dd09654cd\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/test/src/lib.rs\x1b[0m:\x1b[35m663\x1b[0m [INFO] [stdout] 77: \x1b[32mtest::run_test_in_process::{{closure}}\x1b[0m\x1b[90m::heb6e1d5908538a47\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/test/src/lib.rs\x1b[0m:\x1b[35m686\x1b[0m [INFO] [stdout] 78: \x1b[32m as core::ops::function::FnOnce<()>>::call_once\x1b[0m\x1b[90m::h9431628be07bec43\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/panic/unwind_safe.rs\x1b[0m:\x1b[35m274\x1b[0m [INFO] [stdout] 79: \x1b[32mstd::panicking::catch_unwind::do_call\x1b[0m\x1b[90m::h8c5784c3e5739874\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/panicking.rs\x1b[0m:\x1b[35m590\x1b[0m [INFO] [stdout] 80: \x1b[32mstd::panicking::catch_unwind\x1b[0m\x1b[90m::hd47261f607ec5959\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/panicking.rs\x1b[0m:\x1b[35m553\x1b[0m [INFO] [stdout] 81: \x1b[32mstd::panic::catch_unwind\x1b[0m\x1b[90m::h75de848a5faf1178\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/panic.rs\x1b[0m:\x1b[35m359\x1b[0m [INFO] [stdout] 82: \x1b[32mtest::run_test_in_process\x1b[0m\x1b[90m::h0c2306062242e954\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/test/src/lib.rs\x1b[0m:\x1b[35m686\x1b[0m [INFO] [stdout] 83: \x1b[32mtest::run_test::{{closure}}\x1b[0m\x1b[90m::h1d068b692146486f\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/test/src/lib.rs\x1b[0m:\x1b[35m607\x1b[0m [INFO] [stdout] 84: \x1b[32mtest::run_test::{{closure}}\x1b[0m\x1b[90m::h9b4e49b84b6af8d5\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/test/src/lib.rs\x1b[0m:\x1b[35m637\x1b[0m [INFO] [stdout] 85: \x1b[32mstd::sys::backtrace::__rust_begin_short_backtrace\x1b[0m\x1b[90m::h91a1650e239bfc6e\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/sys/backtrace.rs\x1b[0m:\x1b[35m158\x1b[0m [INFO] [stdout] 86: \x1b[32mstd::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}\x1b[0m\x1b[90m::he53e60d6e45d96c0\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/thread/mod.rs\x1b[0m:\x1b[35m559\x1b[0m [INFO] [stdout] 87: \x1b[32m as core::ops::function::FnOnce<()>>::call_once\x1b[0m\x1b[90m::h387e1a11696f00c7\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/panic/unwind_safe.rs\x1b[0m:\x1b[35m274\x1b[0m [INFO] [stdout] 88: \x1b[32mstd::panicking::catch_unwind::do_call\x1b[0m\x1b[90m::h40236a17a6d8fbeb\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/panicking.rs\x1b[0m:\x1b[35m590\x1b[0m [INFO] [stdout] 89: \x1b[32mstd::panicking::catch_unwind\x1b[0m\x1b[90m::h2bcea238e4fbd369\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/panicking.rs\x1b[0m:\x1b[35m553\x1b[0m [INFO] [stdout] 90: \x1b[32mstd::panic::catch_unwind\x1b[0m\x1b[90m::heaa1daa6f035f00e\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/panic.rs\x1b[0m:\x1b[35m359\x1b[0m [INFO] [stdout] 91: \x1b[32mstd::thread::Builder::spawn_unchecked_::{{closure}}\x1b[0m\x1b[90m::hd02bdac90423c966\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/thread/mod.rs\x1b[0m:\x1b[35m557\x1b[0m [INFO] [stdout] 92: \x1b[32mcore::ops::function::FnOnce::call_once{{vtable.shim}}\x1b[0m\x1b[90m::h4bf9f1a93d331696\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/ops/function.rs\x1b[0m:\x1b[35m253\x1b[0m [INFO] [stdout] 93: \x1b[32m as core::ops::function::FnOnce>::call_once\x1b[0m\x1b[90m::h182634db7b0cf35c\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/alloc/src/boxed.rs\x1b[0m:\x1b[35m1985\x1b[0m [INFO] [stdout] 94: \x1b[32mstd::sys::pal::unix::thread::Thread::new::thread_start\x1b[0m\x1b[90m::h74991452e4c34e7b\x1b[0m [INFO] [stdout] at \x1b[35m/rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/sys/pal/unix/thread.rs\x1b[0m:\x1b[35m118\x1b[0m [INFO] [stdout] 95: \x1b[91mclone\x1b[0m\x1b[90m\x1b[0m [INFO] [stdout] at \x1b[35m\x1b[0m:\x1b[35m\x1b[0m [INFO] [stdout] [INFO] [stdout] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering. gitlab.output=true [INFO] [stdout] 2025-09-10T13:25:11.686793Z TRACE command{cmdline="dput foo test1.dsc"}: obs_gitlab_runner::handler: > dput foo test1.dsc gitlab.output=true [INFO] [stdout] 2025-09-10T13:25:11.693830Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::handler: Saving initial build info: ObsBuildInfo { project: "foo", package: "bar", rev: None, srcmd5: None, is_branched: false, enabled_repos: {} } [INFO] [stdout] 2025-09-10T13:25:11.695972Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:get_if_package_exists{project="foo" package="bar" options=BuildMetaOptions { history_retrieval: Full, disabled_repos: Keep }}:get{project="foo" package="bar" options=BuildMetaOptions { history_retrieval: Full, disabled_repos: Keep }}: obs_gitlab_runner::build_meta: project_meta=ProjectMeta { name: "foo", repositories: [RepositoryMeta { name: "repo", rebuild: Transitive, block: All, arches: ["x86_64", "aarch64"] }] } [INFO] [stdout] 2025-09-10T13:25:11.700949Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::handler: initial_build_meta=Some(BuildMeta { client: Client { base: Url { scheme: "http", cannot_be_a_base: false, username: "", password: None, host: Some(Ipv4(127.0.0.1)), port: Some(39967), path: "/", query: None, fragment: None }, user: "user", pass: "[redacted]", client: Client { accepts: Accepts, proxies: [Proxy(System({}), None)], referer: true, default_headers: {"accept": "*/*"} } }, project: "foo", package: "bar", repos: {RepoArch { repo: "repo", arch: "aarch64" }: JobHistList { jobhist: [] }, RepoArch { repo: "repo", arch: "x86_64" }: JobHistList { jobhist: [] }} }) [INFO] [stdout] 2025-09-10T13:25:11.701053Z TRACE command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}: obs_gitlab_runner::upload: Uploading test1.dsc to foo/bar... gitlab.output=true [INFO] [stdout] 2025-09-10T13:25:11.702842Z TRACE command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}: obs_gitlab_runner::upload: files_to_commit={"_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1": "202cb962ac59075b964b07152d234b70", "test1.dsc": "4c85fb7ae65cc69676f0d043082779e7"} present_files={} [INFO] [stdout] 2025-09-10T13:25:11.703848Z TRACE command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}:commit{commit_message="test1.dsc" root="" files={"_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1": "202cb962ac59075b964b07152d234b70", "test1.dsc": "4c85fb7ae65cc69676f0d043082779e7"}}: obs_gitlab_runner::upload: Retry commit, missing 2 entries [INFO] [stdout] 2025-09-10T13:25:11.703895Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}:commit{commit_message="test1.dsc" root="" files={"_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1": "202cb962ac59075b964b07152d234b70", "test1.dsc": "4c85fb7ae65cc69676f0d043082779e7"}}: obs_gitlab_runner::upload: missing=MissingEntries { entries: [CommitEntry { name: "test1", md5: "202cb962ac59075b964b07152d234b70" }, CommitEntry { name: "test1.dsc", md5: "4c85fb7ae65cc69676f0d043082779e7" }] } [INFO] [stdout] 2025-09-10T13:25:11.703948Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}:commit{commit_message="test1.dsc" root="" files={"_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1": "202cb962ac59075b964b07152d234b70", "test1.dsc": "4c85fb7ae65cc69676f0d043082779e7"}}:upload_file{root="" filename="test1"}: obs_gitlab_runner::upload: Uploading file [INFO] [stdout] 2025-09-10T13:25:11.749425Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}:commit{commit_message="test1.dsc" root="" files={"_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1": "202cb962ac59075b964b07152d234b70", "test1.dsc": "4c85fb7ae65cc69676f0d043082779e7"}}:upload_file{root="" filename="test1.dsc"}: obs_gitlab_runner::upload: Uploading file [INFO] [stdout] 2025-09-10T13:25:11.864197Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:get_status_when_ready{project="foo" package="bar" repo="repo" arch="aarch64" options=BuildMetaWaitOptions { sleep_until_ready: 15s }}:get_status{project="foo" package="bar" repo="repo" arch="aarch64"}: obs_gitlab_runner::build_meta: status=BuildStatus { package: "bar", code: Unknown, dirty: false, details: Some("") } [INFO] [stdout] 2025-09-10T13:25:11.881046Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:get_status_when_ready{project="foo" package="bar" repo="repo" arch="x86_64" options=BuildMetaWaitOptions { sleep_until_ready: 15s }}:get_status{project="foo" package="bar" repo="repo" arch="x86_64"}: obs_gitlab_runner::build_meta: status=BuildStatus { package: "bar", code: Disabled, dirty: false, details: Some("") } [INFO] [stdout] 2025-09-10T13:25:11.881149Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::build_meta: Disabling repo=repo repo_arch.arch=x86_64 [INFO] [stdout] 2025-09-10T13:25:11.881215Z TRACE command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::handler: Package uploaded with revision 1. gitlab.output=true [INFO] [stdout] 2025-09-10T13:25:11.881280Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::handler: Saving complete build info: ObsBuildInfo { project: "foo", package: "bar", rev: Some("1"), srcmd5: Some("c938a6103ec80eba4d79cb60c9880a9d"), is_branched: false, enabled_repos: {RepoArch { repo: "repo", arch: "aarch64" }: CommitBuildInfo { prev_endtime_for_commit: None }} } [INFO] [stdout] 2025-09-10T13:25:12.421680Z TRACE command{cmdline="dput foo test1.dsc"}: obs_gitlab_runner::handler: > dput foo test1.dsc gitlab.output=true [INFO] [stdout] 2025-09-10T13:25:12.475890Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::handler: Saving initial build info: ObsBuildInfo { project: "foo", package: "bar", rev: None, srcmd5: None, is_branched: false, enabled_repos: {} } [INFO] [stdout] 2025-09-10T13:25:12.508234Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:get_if_package_exists{project="foo" package="bar" options=BuildMetaOptions { history_retrieval: Full, disabled_repos: Keep }}:get{project="foo" package="bar" options=BuildMetaOptions { history_retrieval: Full, disabled_repos: Keep }}: obs_gitlab_runner::build_meta: project_meta=ProjectMeta { name: "foo", repositories: [RepositoryMeta { name: "repo", rebuild: Transitive, block: All, arches: ["x86_64", "aarch64"] }] } [INFO] [stdout] 2025-09-10T13:25:12.522079Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::handler: initial_build_meta=Some(BuildMeta { client: Client { base: Url { scheme: "http", cannot_be_a_base: false, username: "", password: None, host: Some(Ipv4(127.0.0.1)), port: Some(39967), path: "/", query: None, fragment: None }, user: "user", pass: "[redacted]", client: Client { accepts: Accepts, proxies: [Proxy(System({}), None)], referer: true, default_headers: {"accept": "*/*"} } }, project: "foo", package: "bar", repos: {RepoArch { repo: "repo", arch: "x86_64" }: JobHistList { jobhist: [] }, RepoArch { repo: "repo", arch: "aarch64" }: JobHistList { jobhist: [JobHist { package: "bar", rev: "1", srcmd5: "c938a6103ec80eba4d79cb60c9880a9d", versrel: "1.0-1", bcnt: "1", readytime: 1757510711, starttime: 1757510711, endtime: 1757510711, code: Succeeded, uri: "http://127.0.0.1:9000", workerid: "worker:1", hostarch: "x86_64", reason: "source change", verifymd5: "ce40f389975c1572b98b9b25939b02ba" }] }} }) [INFO] [stdout] 2025-09-10T13:25:12.522188Z TRACE command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}: obs_gitlab_runner::upload: Uploading test1.dsc to foo/bar... gitlab.output=true [INFO] [stdout] 2025-09-10T13:25:12.565426Z TRACE command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}: obs_gitlab_runner::upload: files_to_commit={"test1.dsc": "4c85fb7ae65cc69676f0d043082779e7", "_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1": "202cb962ac59075b964b07152d234b70"} present_files={"test1.dsc": "4c85fb7ae65cc69676f0d043082779e7", "_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1": "202cb962ac59075b964b07152d234b70"} [INFO] [stdout] 2025-09-10T13:25:12.576892Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:get_status_when_ready{project="foo" package="bar" repo="repo" arch="x86_64" options=BuildMetaWaitOptions { sleep_until_ready: 15s }}:get_status{project="foo" package="bar" repo="repo" arch="x86_64"}: obs_gitlab_runner::build_meta: status=BuildStatus { package: "bar", code: Disabled, dirty: false, details: Some("") } [INFO] [stdout] 2025-09-10T13:25:12.576973Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::build_meta: Disabling repo=repo repo_arch.arch=x86_64 [INFO] [stdout] 2025-09-10T13:25:12.587109Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:get_status_when_ready{project="foo" package="bar" repo="repo" arch="aarch64" options=BuildMetaWaitOptions { sleep_until_ready: 15s }}:get_status{project="foo" package="bar" repo="repo" arch="aarch64"}: obs_gitlab_runner::build_meta: status=BuildStatus { package: "bar", code: Failed, dirty: false, details: Some("") } [INFO] [stdout] 2025-09-10T13:25:12.587226Z TRACE command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::handler: Package unchanged at revision 1. gitlab.output=true [INFO] [stdout] 2025-09-10T13:25:12.587315Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::handler: Saving complete build info: ObsBuildInfo { project: "foo", package: "bar", rev: Some("1"), srcmd5: Some("c938a6103ec80eba4d79cb60c9880a9d"), is_branched: false, enabled_repos: {RepoArch { repo: "repo", arch: "aarch64" }: CommitBuildInfo { prev_endtime_for_commit: None }} } [INFO] [stdout] 2025-09-10T13:25:13.591009Z TRACE command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}: obs_gitlab_runner::handler: > dput foo test1.dsc --rebuild-if-unchanged gitlab.output=true [INFO] [stdout] 2025-09-10T13:25:13.611523Z DEBUG command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: true }}: obs_gitlab_runner::handler: Saving initial build info: ObsBuildInfo { project: "foo", package: "bar", rev: None, srcmd5: None, is_branched: false, enabled_repos: {} } [INFO] [stdout] 2025-09-10T13:25:13.622093Z DEBUG command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: true }}:get_if_package_exists{project="foo" package="bar" options=BuildMetaOptions { history_retrieval: Full, disabled_repos: Keep }}:get{project="foo" package="bar" options=BuildMetaOptions { history_retrieval: Full, disabled_repos: Keep }}: obs_gitlab_runner::build_meta: project_meta=ProjectMeta { name: "foo", repositories: [RepositoryMeta { name: "repo", rebuild: Transitive, block: All, arches: ["x86_64", "aarch64"] }] } [INFO] [stdout] 2025-09-10T13:25:13.635133Z DEBUG command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: true }}: obs_gitlab_runner::handler: initial_build_meta=Some(BuildMeta { client: Client { base: Url { scheme: "http", cannot_be_a_base: false, username: "", password: None, host: Some(Ipv4(127.0.0.1)), port: Some(39967), path: "/", query: None, fragment: None }, user: "user", pass: "[redacted]", client: Client { accepts: Accepts, proxies: [Proxy(System({}), None)], referer: true, default_headers: {"accept": "*/*"} } }, project: "foo", package: "bar", repos: {RepoArch { repo: "repo", arch: "aarch64" }: JobHistList { jobhist: [JobHist { package: "bar", rev: "1", srcmd5: "c938a6103ec80eba4d79cb60c9880a9d", versrel: "1.0-1", bcnt: "1", readytime: 1757510711, starttime: 1757510711, endtime: 1757510711, code: Succeeded, uri: "http://127.0.0.1:9000", workerid: "worker:1", hostarch: "x86_64", reason: "source change", verifymd5: "ce40f389975c1572b98b9b25939b02ba" }] }, RepoArch { repo: "repo", arch: "x86_64" }: JobHistList { jobhist: [] }} }) [INFO] [stdout] 2025-09-10T13:25:13.635246Z TRACE command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: true }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}: obs_gitlab_runner::upload: Uploading test1.dsc to foo/bar... gitlab.output=true [INFO] [stdout] 2025-09-10T13:25:13.651711Z TRACE command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: true }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}: obs_gitlab_runner::upload: files_to_commit={"_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1": "202cb962ac59075b964b07152d234b70", "test1.dsc": "4c85fb7ae65cc69676f0d043082779e7"} present_files={"_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1": "202cb962ac59075b964b07152d234b70", "test1.dsc": "4c85fb7ae65cc69676f0d043082779e7"} [INFO] [stdout] 2025-09-10T13:25:13.663233Z DEBUG command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: true }}:get_status_when_ready{project="foo" package="bar" repo="repo" arch="aarch64" options=BuildMetaWaitOptions { sleep_until_ready: 15s }}:get_status{project="foo" package="bar" repo="repo" arch="aarch64"}: obs_gitlab_runner::build_meta: status=BuildStatus { package: "bar", code: Failed, dirty: false, details: Some("") } [INFO] [stdout] 2025-09-10T13:25:13.668983Z DEBUG command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: true }}:get_status_when_ready{project="foo" package="bar" repo="repo" arch="x86_64" options=BuildMetaWaitOptions { sleep_until_ready: 15s }}:get_status{project="foo" package="bar" repo="repo" arch="x86_64"}: obs_gitlab_runner::build_meta: status=BuildStatus { package: "bar", code: Disabled, dirty: false, details: Some("") } [INFO] [stdout] 2025-09-10T13:25:13.669072Z DEBUG command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: true }}: obs_gitlab_runner::build_meta: Disabling repo=repo repo_arch.arch=x86_64 [INFO] [stdout] 2025-09-10T13:25:13.669107Z TRACE command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: true }}: obs_gitlab_runner::handler: Package unchanged at revision 1. gitlab.output=true [INFO] [stdout] 2025-09-10T13:25:13.694672Z DEBUG command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: true }}: obs_gitlab_runner::handler: Saving complete build info: ObsBuildInfo { project: "foo", package: "bar", rev: Some("1"), srcmd5: Some("c938a6103ec80eba4d79cb60c9880a9d"), is_branched: false, enabled_repos: {RepoArch { repo: "repo", arch: "aarch64" }: CommitBuildInfo { prev_endtime_for_commit: Some(1757510711) }} } [INFO] [stdout] 2025-09-10T13:25:14.160856Z TRACE command{cmdline="generate-monitor test-tag --job-timeout '1 day' --rules '[{a: 1}, {b: 2}]'"}: obs_gitlab_runner::handler: > generate-monitor test-tag --job-timeout '1 day' --rules '[{a: 1}, {b: 2}]' gitlab.output=true [INFO] [stdout] 2025-09-10T13:25:14.185756Z TRACE command{cmdline="generate-monitor test-tag --job-timeout '1 day' --rules '[{a: 1}, {b: 2}]'"}:run_generate_monitor{args=GenerateMonitorAction { tag: "test-tag", rules: Some("[{a: 1}, {b: 2}]"), build_results_dir: None, build_info: "build-info.yml", pipeline_out: "obs.yml", job_prefix: "obs", job_timeout: Some("1 day"), artifact_expiration: "3 days", build_log_out: "build.log" }}: obs_gitlab_runner::handler: Wrote pipeline file 'obs.yml'. gitlab.output=true [INFO] [stdout] 2025-09-10T13:25:15.061045Z TRACE command{cmdline="monitor --rev 1 --srcmd5 c938a6103ec80eba4d79cb60c9880a9d --build-log-out build.log --prev-endtime-for-commit 1757510711 --project foo --package bar --repository repo --arch aarch64"}: obs_gitlab_runner::handler: > monitor --rev 1 --srcmd5 c938a6103ec80eba4d79cb60c9880a9d --build-log-out build.log --prev-endtime-for-commit 1757510711 --project foo --package bar --repository repo --arch aarch64 gitlab.output=true [INFO] [stdout] 2025-09-10T13:25:15.094915Z TRACE command{cmdline="monitor --rev 1 --srcmd5 c938a6103ec80eba4d79cb60c9880a9d --build-log-out build.log --prev-endtime-for-commit 1757510711 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "c938a6103ec80eba4d79cb60c9880a9d", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1757510711), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "c938a6103ec80eba4d79cb60c9880a9d", prev_endtime_for_commit: Some(1757510711) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_old_status: 100ms, max_old_status_retries: 99 }}: obs_gitlab_runner::monitor: Live build log: http://127.0.0.1:39967/package/live_build_log/foo/bar/repo/aarch64 gitlab.output=true [INFO] [stdout] 2025-09-10T13:25:15.222755Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 c938a6103ec80eba4d79cb60c9880a9d --build-log-out build.log --prev-endtime-for-commit 1757510711 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "c938a6103ec80eba4d79cb60c9880a9d", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1757510711), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "c938a6103ec80eba4d79cb60c9880a9d", prev_endtime_for_commit: Some(1757510711) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_old_status: 100ms, max_old_status_retries: 99 }}:get_latest_state: obs_gitlab_runner::monitor: jobhist.jobhist=[JobHist { package: "bar", rev: "1", srcmd5: "c938a6103ec80eba4d79cb60c9880a9d", versrel: "1.0-1", bcnt: "1", readytime: 1757510711, starttime: 1757510711, endtime: 1757510711, code: Succeeded, uri: "http://127.0.0.1:9000", workerid: "worker:1", hostarch: "x86_64", reason: "source change", verifymd5: "ce40f389975c1572b98b9b25939b02ba" }, JobHist { package: "bar", rev: "1", srcmd5: "c938a6103ec80eba4d79cb60c9880a9d", versrel: "1.0-1", bcnt: "1", readytime: 1757510715, starttime: 1757510715, endtime: 999, code: Succeeded, uri: "http://127.0.0.1:9000", workerid: "worker:1", hostarch: "x86_64", reason: "source change", verifymd5: "70632f8a4d6fb6ba58b20c8681b17677" }] [INFO] [stdout] 2025-09-10T13:25:15.222827Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 c938a6103ec80eba4d79cb60c9880a9d --build-log-out build.log --prev-endtime-for-commit 1757510711 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "c938a6103ec80eba4d79cb60c9880a9d", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1757510711), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "c938a6103ec80eba4d79cb60c9880a9d", prev_endtime_for_commit: Some(1757510711) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_old_status: 100ms, max_old_status_retries: 99 }}:get_latest_state: obs_gitlab_runner::monitor: prev_endtime_for_commit=Some(999) [INFO] [stdout] 2025-09-10T13:25:15.222908Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 c938a6103ec80eba4d79cb60c9880a9d --build-log-out build.log --prev-endtime-for-commit 1757510711 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "c938a6103ec80eba4d79cb60c9880a9d", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1757510711), build_log_out: "build.log" }}: obs_gitlab_runner::handler: Completed with: Failed(Failed) [INFO] [stdout] 2025-09-10T13:25:15.230498Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 c938a6103ec80eba4d79cb60c9880a9d --build-log-out build.log --prev-endtime-for-commit 1757510711 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "c938a6103ec80eba4d79cb60c9880a9d", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1757510711), build_log_out: "build.log" }}:download_build_log{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "c938a6103ec80eba4d79cb60c9880a9d", prev_endtime_for_commit: Some(1757510711) } }}:check_log_md5: obs_gitlab_runner::monitor: needle=srcmd5 'c938a6103ec80eba4d79cb60c9880a9d' content=srcmd5 'c938a6103ec80eba4d79cb60c9880a9d' this is a long log that will need to be trimmed when printed [INFO] [stdout] 2025-09-10T13:25:15.232935Z TRACE command{cmdline="monitor --rev 1 --srcmd5 c938a6103ec80eba4d79cb60c9880a9d --build-log-out build.log --prev-endtime-for-commit 1757510711 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "c938a6103ec80eba4d79cb60c9880a9d", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1757510711), build_log_out: "build.log" }}: obs_gitlab_runner::handler: ================================================================ gitlab.output=true [INFO] [stdout] 2025-09-10T13:25:15.233005Z TRACE command{cmdline="monitor --rev 1 --srcmd5 c938a6103ec80eba4d79cb60c9880a9d --build-log-out build.log --prev-endtime-for-commit 1757510711 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "c938a6103ec80eba4d79cb60c9880a9d", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1757510711), build_log_out: "build.log" }}: obs_gitlab_runner::handler: Build failed with reason 'failed'. gitlab.output=true [INFO] [stdout] 2025-09-10T13:25:15.233035Z TRACE command{cmdline="monitor --rev 1 --srcmd5 c938a6103ec80eba4d79cb60c9880a9d --build-log-out build.log --prev-endtime-for-commit 1757510711 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "c938a6103ec80eba4d79cb60c9880a9d", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1757510711), build_log_out: "build.log" }}: obs_gitlab_runner::handler: The last 2MB of the build log is printed above. gitlab.output=true [INFO] [stdout] 2025-09-10T13:25:15.233068Z TRACE command{cmdline="monitor --rev 1 --srcmd5 c938a6103ec80eba4d79cb60c9880a9d --build-log-out build.log --prev-endtime-for-commit 1757510711 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "c938a6103ec80eba4d79cb60c9880a9d", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1757510711), build_log_out: "build.log" }}: obs_gitlab_runner::handler: (Full logs are available in the build artifact 'build.log'.) gitlab.output=true [INFO] [stdout] The application panicked (crashed). [INFO] [stdout] Message: assertion `left == right` failed [INFO] [stdout] left: false [INFO] [stdout] right: true [INFO] [stdout] Location: src/handler.rs:1561 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 8 frames hidden ⋮  [INFO] [stdout] 9: core::panicking::assert_failed_inner::h1d3594dadcac678c [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/panicking.rs:439 [INFO] [stdout] 10: core::panicking::assert_failed::hd396eab4d36bebc4 [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/panicking.rs:394 [INFO] [stdout] 11: obs_gitlab_runner::handler::tests::test_monitoring::{{closure}}::hbda76ffbb6e542f6 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1561 [INFO] [stdout] 1559 │ // so don't check for an old build status. [INFO] [stdout] 1560 │ let build_actually_occurred = dput_test != DputTest::ReusePreviousBuild; [INFO] [stdout]  1561 >  assert_eq!( [INFO] [stdout] 1562 │ job_log.contains("Waiting for build status"), [INFO] [stdout] 1563 │ build_actually_occurred [INFO] [stdout] 12: obs_gitlab_runner::handler::tests::test_handler_flow::{{closure}}::{{closure}}::{{closure}}::hacb4aa69fc5dab32 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1748 [INFO] [stdout] 1746 │ download_binaries, [INFO] [stdout] 1747 │ ) [INFO] [stdout]  1748 >  .await; [INFO] [stdout] 1749 │ [INFO] [stdout] 1750 │ test_prune( [INFO] [stdout] 13:  as core::future::future::Future>::poll::h342a6845f0785f37 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-0.1.41/src/instrument.rs:388 [INFO] [stdout] 386 │ let future = this.inner; [INFO] [stdout] 387 │ let _default = dispatcher::set_default(dispatcher); [INFO] [stdout]  388 >  future.poll(cx) [INFO] [stdout] 389 │ } [INFO] [stdout] 390 │ } [INFO] [stdout] 14: obs_gitlab_runner::handler::tests::with_context::{{closure}}::h0e838a092f82edf4 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:827 [INFO] [stdout] 825 │ .with(layer), [INFO] [stdout] 826 │ ) [INFO] [stdout]  827 >  .await [INFO] [stdout] 828 │ } [INFO] [stdout] 829 │ [INFO] [stdout] 15: obs_gitlab_runner::handler::tests::test_handler_flow::{{closure}}::h176b204294a4be84 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1758 [INFO] [stdout] 1756 │ .await; [INFO] [stdout] 1757 │ }) [INFO] [stdout]  1758 >  .await; [INFO] [stdout] 1759 │ } [INFO] [stdout] 1760 │ [INFO] [stdout] 16: obs_gitlab_runner::handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false::{{closure}}::h83e77d735936b66d [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1716 [INFO] [stdout] 1714 │ } [INFO] [stdout] 1715 │ [INFO] [stdout]  1716 >  #[rstest] [INFO] [stdout] 1717 │ #[tokio::test] [INFO] [stdout] 1718 │ async fn test_handler_flow( [INFO] [stdout] 17:  as core::future::future::Future>::poll::hce49b8926c882ddb [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/future/future.rs:133 [INFO] [stdout] 18:  as core::future::future::Future>::poll::h327d0f52a96bb5aa [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/future/future.rs:133 [INFO] [stdout] 19: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::{{closure}}::h7998b2b2ea730a75 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs:733 [INFO] [stdout] 731 │ if handle.reset_woken() { [INFO] [stdout] 732 │ let (c, res) = context.enter(core, || { [INFO] [stdout]  733 >  crate::task::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stdout] 734 │ }); [INFO] [stdout] 735 │ [INFO] [stdout] 20: tokio::task::coop::with_budget::h6d552cc4e7010b46 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/task/coop/mod.rs:167 [INFO] [stdout] 165 │ // The function is called regardless even if the budget is not successfully [INFO] [stdout] 166 │ // set due to the thread-local being destroyed. [INFO] [stdout]  167 >  f() [INFO] [stdout] 168 │ } [INFO] [stdout] 169 │ [INFO] [stdout] 21: tokio::task::coop::budget::h5a913bc5be20195f [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/task/coop/mod.rs:133 [INFO] [stdout] 131 │ #[inline(always)] [INFO] [stdout] 132 │ pub(crate) fn budget(f: impl FnOnce() -> R) -> R { [INFO] [stdout]  133 >  with_budget(Budget::initial(), f) [INFO] [stdout] 134 │ } [INFO] [stdout] 135 │ [INFO] [stdout] 22: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::h61add551f9be2598 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs:733 [INFO] [stdout] 731 │ if handle.reset_woken() { [INFO] [stdout] 732 │ let (c, res) = context.enter(core, || { [INFO] [stdout]  733 >  crate::task::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stdout] 734 │ }); [INFO] [stdout] 735 │ [INFO] [stdout] 23: tokio::runtime::scheduler::current_thread::Context::enter::hce7fc0e3762d5a50 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs:432 [INFO] [stdout] 430 │ [INFO] [stdout] 431 │ // Execute the closure while tracking the execution budget [INFO] [stdout]  432 >  let ret = f(); [INFO] [stdout] 433 │ [INFO] [stdout] 434 │ // Take the scheduler core back [INFO] [stdout] 24: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::h8cb4cea3eb3454cb [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs:732 [INFO] [stdout] 730 │ [INFO] [stdout] 731 │ if handle.reset_woken() { [INFO] [stdout]  732 >  let (c, res) = context.enter(core, || { [INFO] [stdout] 733 │ crate::task::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stdout] 734 │ }); [INFO] [stdout] 25: tokio::runtime::scheduler::current_thread::CoreGuard::enter::{{closure}}::hb66af98cd9be45a2 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs:820 [INFO] [stdout] 818 │ [INFO] [stdout] 819 │ // Call the closure and place `core` back [INFO] [stdout]  820 >  let (core, ret) = context::set_scheduler(&self.context, || f(core, context)); [INFO] [stdout] 821 │ [INFO] [stdout] 822 │ *context.core.borrow_mut() = Some(core); [INFO] [stdout] 26: tokio::runtime::context::scoped::Scoped::set::h7397be9bebccfdf1 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/context/scoped.rs:40 [INFO] [stdout] 38 │ }; [INFO] [stdout] 39 │ [INFO] [stdout]  40 >  f() [INFO] [stdout] 41 │ } [INFO] [stdout] 42 │ [INFO] [stdout] 27: tokio::runtime::context::set_scheduler::{{closure}}::h38856e221efabd1c [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/context.rs:176 [INFO] [stdout] 174 │ [INFO] [stdout] 175 │ pub(super) fn set_scheduler(v: &scheduler::Context, f: impl FnOnce() -> R) -> R { [INFO] [stdout]  176 >  CONTEXT.with(|c| c.scheduler.set(v, f)) [INFO] [stdout] 177 │ } [INFO] [stdout] 178 │ [INFO] [stdout] 28: std::thread::local::LocalKey::try_with::h6c066c5ca866cfaa [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/thread/local.rs:315 [INFO] [stdout] 29: std::thread::local::LocalKey::with::h960a58c9a659dea0 [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/thread/local.rs:279 [INFO] [stdout] 30: tokio::runtime::context::set_scheduler::h55d3eeaaa96a30f8 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/context.rs:176 [INFO] [stdout] 174 │ [INFO] [stdout] 175 │ pub(super) fn set_scheduler(v: &scheduler::Context, f: impl FnOnce() -> R) -> R { [INFO] [stdout]  176 >  CONTEXT.with(|c| c.scheduler.set(v, f)) [INFO] [stdout] 177 │ } [INFO] [stdout] 178 │ [INFO] [stdout] 31: tokio::runtime::scheduler::current_thread::CoreGuard::enter::hdf1d264b594aff6d [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs:820 [INFO] [stdout] 818 │ [INFO] [stdout] 819 │ // Call the closure and place `core` back [INFO] [stdout]  820 >  let (core, ret) = context::set_scheduler(&self.context, || f(core, context)); [INFO] [stdout] 821 │ [INFO] [stdout] 822 │ *context.core.borrow_mut() = Some(core); [INFO] [stdout] 32: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::h764198b1b79b7da3 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs:720 [INFO] [stdout] 718 │ #[track_caller] [INFO] [stdout] 719 │ fn block_on(self, future: F) -> F::Output { [INFO] [stdout]  720 >  let ret = self.enter(|mut core, context| { [INFO] [stdout] 721 │ let waker = Handle::waker_ref(&context.handle); [INFO] [stdout] 722 │ let mut cx = std::task::Context::from_waker(&waker); [INFO] [stdout] 33: tokio::runtime::scheduler::current_thread::CurrentThread::block_on::{{closure}}::h6814c816b8008647 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs:200 [INFO] [stdout] 198 │ .worker_metrics [INFO] [stdout] 199 │ .set_thread_id(thread::current().id()); [INFO] [stdout]  200 >  return core.block_on(future); [INFO] [stdout] 201 │ } else { [INFO] [stdout] 202 │ let notified = self.notify.notified(); [INFO] [stdout] 34: tokio::runtime::context::runtime::enter_runtime::h28e302dc58913760 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/context/runtime.rs:65 [INFO] [stdout] 63 │ [INFO] [stdout] 64 │ if let Some(mut guard) = maybe_guard { [INFO] [stdout]  65 >  return f(&mut guard.blocking); [INFO] [stdout] 66 │ } [INFO] [stdout] 67 │ [INFO] [stdout] 35: tokio::runtime::scheduler::current_thread::CurrentThread::block_on::h8e7aa5c98bbcfec2 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs:188 [INFO] [stdout] 186 │ pin!(future); [INFO] [stdout] 187 │ [INFO] [stdout]  188 >  crate::runtime::context::enter_runtime(handle, false, |blocking| { [INFO] [stdout] 189 │ let handle = handle.as_current_thread(); [INFO] [stdout] 190 │ [INFO] [stdout] 36: tokio::runtime::runtime::Runtime::block_on_inner::h84a0e260c467446d [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/runtime.rs:356 [INFO] [stdout] 354 │ [INFO] [stdout] 355 │ match &self.scheduler { [INFO] [stdout]  356 >  Scheduler::CurrentThread(exec) => exec.block_on(&self.handle.inner, future), [INFO] [stdout] 357 │ #[cfg(feature = "rt-multi-thread")] [INFO] [stdout] 358 │ Scheduler::MultiThread(exec) => exec.block_on(&self.handle.inner, future), [INFO] [stdout] 37: tokio::runtime::runtime::Runtime::block_on::hcb274c9a98ea0eee [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/runtime.rs:330 [INFO] [stdout] 328 │ self.block_on_inner(Box::pin(future), SpawnMeta::new_unnamed(fut_size)) [INFO] [stdout] 329 │ } else { [INFO] [stdout]  330 >  self.block_on_inner(future, SpawnMeta::new_unnamed(fut_size)) [INFO] [stdout] 331 │ } [INFO] [stdout] 332 │ } [INFO] [stdout] 38: obs_gitlab_runner::handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false::hc858a9a2016cdce4 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1716 [INFO] [stdout] 1714 │ } [INFO] [stdout] 1715 │ [INFO] [stdout]  1716 >  #[rstest] [INFO] [stdout] 1717 │ #[tokio::test] [INFO] [stdout] 1718 │ async fn test_handler_flow( [INFO] [stdout] 39: obs_gitlab_runner::handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false::{{closure}}::h7c51ecac5da4c899 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1716 [INFO] [stdout] 1714 │ } [INFO] [stdout] 1715 │ [INFO] [stdout]  1716 >  #[rstest] [INFO] [stdout] 1717 │ #[tokio::test] [INFO] [stdout] 1718 │ async fn test_handler_flow( [INFO] [stdout] 40: core::ops::function::FnOnce::call_once::h551c8a7ebde95699 [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/ops/function.rs:253 [INFO] [stdout] 41: core::ops::function::FnOnce::call_once::h6784bd5bdcff97de [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/ops/function.rs:253 [INFO] [stdout] 42: test::__rust_begin_short_backtrace::h798bd19dd09654cd [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/test/src/lib.rs:663 [INFO] [stdout] 43: test::run_test_in_process::{{closure}}::heb6e1d5908538a47 [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/test/src/lib.rs:686 [INFO] [stdout] 44:  as core::ops::function::FnOnce<()>>::call_once::h9431628be07bec43 [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/panic/unwind_safe.rs:274 [INFO] [stdout] 45: std::panicking::catch_unwind::do_call::h8c5784c3e5739874 [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/panicking.rs:590 [INFO] [stdout] 46: std::panicking::catch_unwind::hd47261f607ec5959 [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/panicking.rs:553 [INFO] [stdout] 47: std::panic::catch_unwind::h75de848a5faf1178 [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/panic.rs:359 [INFO] [stdout] 48: test::run_test_in_process::h0c2306062242e954 [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/test/src/lib.rs:686 [INFO] [stdout] 49: test::run_test::{{closure}}::h1d068b692146486f [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/test/src/lib.rs:607 [INFO] [stdout] 50: test::run_test::{{closure}}::h9b4e49b84b6af8d5 [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/test/src/lib.rs:637 [INFO] [stdout] 51: std::sys::backtrace::__rust_begin_short_backtrace::h91a1650e239bfc6e [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/sys/backtrace.rs:158 [INFO] [stdout] 52: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::he53e60d6e45d96c0 [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/thread/mod.rs:559 [INFO] [stdout] 53:  as core::ops::function::FnOnce<()>>::call_once::h387e1a11696f00c7 [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/panic/unwind_safe.rs:274 [INFO] [stdout] 54: std::panicking::catch_unwind::do_call::h40236a17a6d8fbeb [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/panicking.rs:590 [INFO] [stdout] 55: std::panicking::catch_unwind::h2bcea238e4fbd369 [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/panicking.rs:553 [INFO] [stdout] 56: std::panic::catch_unwind::heaa1daa6f035f00e [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/panic.rs:359 [INFO] [stdout] 57: std::thread::Builder::spawn_unchecked_::{{closure}}::hd02bdac90423c966 [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/thread/mod.rs:557 [INFO] [stdout] 58: core::ops::function::FnOnce::call_once{{vtable.shim}}::h4bf9f1a93d331696 [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/core/src/ops/function.rs:253 [INFO] [stdout] 59:  as core::ops::function::FnOnce>::call_once::h182634db7b0cf35c [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/alloc/src/boxed.rs:1985 [INFO] [stdout] 60: std::sys::pal::unix::thread::Thread::new::thread_start::h74991452e4c34e7b [INFO] [stdout] at /rustc/d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6/library/std/src/sys/pal/unix/thread.rs:118 [INFO] [stdout] 61: clone [INFO] [stdout] at : [INFO] [stdout] [INFO] [stdout] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering. [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] handler::tests::test_handler_flow::dput_test_2_DputTest__Rebuild::build_success_2_false::log_test_1_MonitorLogTest__Long::download_binaries_2_false::prune_only_if_job_unsuccessful_2_false [INFO] [stdout] [INFO] [stdout] test result: FAILED. 121 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 99.05s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--bin obs-gitlab-runner` [INFO] running `Command { std: "docker" "inspect" "8a68a6092420cfed96777af598807f0f6fea0eaaa9ec25dbe6c1bc3981d037e2", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "8a68a6092420cfed96777af598807f0f6fea0eaaa9ec25dbe6c1bc3981d037e2", kill_on_drop: false }` [INFO] [stdout] 8a68a6092420cfed96777af598807f0f6fea0eaaa9ec25dbe6c1bc3981d037e2