[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] 75d3bf5a3a94f23d7246268eb1771bef92ced47d [INFO] testing collabora/obs-gitlab-runner against try#015dbcea68b7642e2acc268b5c8f3e9086fc02ff for pr-102570 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fcollabora%2Fobs-gitlab-runner" "/workspace/builds/worker-0/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-0/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/collabora/obs-gitlab-runner on toolchain 015dbcea68b7642e2acc268b5c8f3e9086fc02ff [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+015dbcea68b7642e2acc268b5c8f3e9086fc02ff" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [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-0/source/Cargo.toml [INFO] crate git repo https://github.com/collabora/obs-gitlab-runner already has a lockfile, it will not be regenerated [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+015dbcea68b7642e2acc268b5c8f3e9086fc02ff" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/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:bb5b0feb4f87116a4ea905b7ff5bf5b85913db05e9f0fbc1abdd3168928d9075" "/opt/rustwide/cargo-home/bin/cargo" "+015dbcea68b7642e2acc268b5c8f3e9086fc02ff" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] db1b7fc87ce61b1859d5243b58dfcd396f389501593ff3423937b24e7e6c69a5 [INFO] running `Command { std: "docker" "start" "-a" "db1b7fc87ce61b1859d5243b58dfcd396f389501593ff3423937b24e7e6c69a5", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "db1b7fc87ce61b1859d5243b58dfcd396f389501593ff3423937b24e7e6c69a5", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "db1b7fc87ce61b1859d5243b58dfcd396f389501593ff3423937b24e7e6c69a5", kill_on_drop: false }` [INFO] [stdout] db1b7fc87ce61b1859d5243b58dfcd396f389501593ff3423937b24e7e6c69a5 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/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:bb5b0feb4f87116a4ea905b7ff5bf5b85913db05e9f0fbc1abdd3168928d9075" "/opt/rustwide/cargo-home/bin/cargo" "+015dbcea68b7642e2acc268b5c8f3e9086fc02ff" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] bf6b78b37bf1c8ae0ee7eee23eba034aa100fe3bfcb77027009f19b5f3b8fbda [INFO] running `Command { std: "docker" "start" "-a" "bf6b78b37bf1c8ae0ee7eee23eba034aa100fe3bfcb77027009f19b5f3b8fbda", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.117 [INFO] [stderr] Compiling proc-macro2 v1.0.36 [INFO] [stderr] Compiling unicode-xid v0.2.2 [INFO] [stderr] Compiling syn v1.0.86 [INFO] [stderr] Compiling cfg-if v1.0.0 [INFO] [stderr] Compiling autocfg v1.0.1 [INFO] [stderr] Compiling version_check v0.9.4 [INFO] [stderr] Compiling memchr v2.4.1 [INFO] [stderr] Compiling log v0.4.14 [INFO] [stderr] Compiling once_cell v1.9.0 [INFO] [stderr] Compiling smallvec v1.8.0 [INFO] [stderr] Compiling pin-project-lite v0.2.8 [INFO] [stderr] Compiling pkg-config v0.3.24 [INFO] [stderr] Compiling serde_derive v1.0.136 [INFO] [stderr] Compiling typenum v1.15.0 [INFO] [stderr] Compiling itoa v1.0.1 [INFO] [stderr] Compiling serde v1.0.136 [INFO] [stderr] Compiling lazy_static v1.4.0 [INFO] [stderr] Compiling generic-array v0.14.5 [INFO] [stderr] Compiling futures-core v0.3.21 [INFO] [stderr] Compiling quote v1.0.15 [INFO] [stderr] Compiling scopeguard v1.1.0 [INFO] [stderr] Compiling parking_lot_core v0.8.5 [INFO] [stderr] Compiling jobserver v0.1.24 [INFO] [stderr] Compiling lock_api v0.4.6 [INFO] [stderr] Compiling tracing-core v0.1.22 [INFO] [stderr] Compiling instant v0.1.12 [INFO] [stderr] Compiling bytes v1.1.0 [INFO] [stderr] Compiling futures-channel v0.3.21 [INFO] [stderr] Compiling cc v1.0.72 [INFO] [stderr] Compiling futures-sink v0.3.21 [INFO] [stderr] Compiling futures-task v0.3.21 [INFO] [stderr] Compiling parking_lot v0.11.2 [INFO] [stderr] Compiling signal-hook-registry v1.4.0 [INFO] [stderr] Compiling mio v0.7.14 [INFO] [stderr] Compiling num_cpus v1.13.1 [INFO] [stderr] Compiling futures-util v0.3.21 [INFO] [stderr] Compiling indexmap v1.8.0 [INFO] [stderr] Compiling bitflags v1.2.1 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling subtle v2.4.1 [INFO] [stderr] Compiling slab v0.4.5 [INFO] [stderr] Compiling ryu v1.0.9 [INFO] [stderr] Compiling futures-io v0.3.21 [INFO] [stderr] Compiling block-buffer v0.10.2 [INFO] [stderr] Compiling crypto-common v0.1.3 [INFO] [stderr] Compiling serde_json v1.0.79 [INFO] [stderr] Compiling hashbrown v0.11.2 [INFO] [stderr] Compiling digest v0.10.3 [INFO] [stderr] Compiling getrandom v0.2.4 [INFO] [stderr] Compiling unicase v2.6.0 [INFO] [stderr] Compiling foreign-types-shared v0.1.1 [INFO] [stderr] Compiling fnv v1.0.7 [INFO] [stderr] Compiling openssl v0.10.38 [INFO] [stderr] Compiling rand_core v0.6.3 [INFO] [stderr] Compiling http v0.2.6 [INFO] [stderr] Compiling openssl-sys v0.9.72 [INFO] [stderr] Compiling zstd-sys v1.6.3+zstd.1.5.2 [INFO] [stderr] Compiling foreign-types v0.3.2 [INFO] [stderr] Compiling miniz_oxide v0.4.4 [INFO] [stderr] Compiling tinyvec_macros v0.1.0 [INFO] [stderr] Compiling httparse v1.6.0 [INFO] [stderr] Compiling native-tls v0.2.8 [INFO] [stderr] Compiling matches v0.1.9 [INFO] [stderr] Compiling tinyvec v1.5.1 [INFO] [stderr] Compiling rustversion v1.0.6 [INFO] [stderr] Compiling percent-encoding v2.1.0 [INFO] [stderr] Compiling adler v1.0.2 [INFO] [stderr] Compiling try-lock v0.2.3 [INFO] [stderr] Compiling openssl-probe v0.1.5 [INFO] [stderr] Compiling cpufeatures v0.2.1 [INFO] [stderr] Compiling want v0.3.0 [INFO] [stderr] Compiling mime_guess v2.0.3 [INFO] [stderr] Compiling form_urlencoded v1.0.1 [INFO] [stderr] Compiling unicode-normalization v0.1.19 [INFO] [stderr] Compiling bzip2-sys v0.1.11+1.0.8 [INFO] [stderr] Compiling tracing-log v0.1.2 [INFO] [stderr] Compiling socket2 v0.4.4 [INFO] [stderr] Compiling sharded-slab v0.1.4 [INFO] [stderr] Compiling num-traits v0.2.14 [INFO] [stderr] Compiling proc-macro-error-attr v1.0.4 [INFO] [stderr] Compiling thread_local v1.1.4 [INFO] [stderr] Compiling ansi_term v0.12.1 [INFO] [stderr] Compiling http-body v0.4.4 [INFO] [stderr] Compiling httpdate v1.0.2 [INFO] [stderr] Compiling unicode-bidi v0.3.7 [INFO] [stderr] Compiling zstd-safe v4.1.6+zstd.1.5.2 [INFO] [stderr] Compiling crc32fast v1.3.2 [INFO] [stderr] Compiling tower-service v0.3.1 [INFO] [stderr] Compiling encoding_rs v0.8.30 [INFO] [stderr] Compiling idna v0.2.3 [INFO] [stderr] Compiling num-integer v0.1.44 [INFO] [stderr] Compiling proc-macro-error v1.0.4 [INFO] [stderr] Compiling unicode-segmentation v1.9.0 [INFO] [stderr] Compiling heck v0.4.0 [INFO] [stderr] Compiling mime v0.3.16 [INFO] [stderr] Compiling base64ct v1.0.1 [INFO] [stderr] Compiling parking_lot_core v0.9.1 [INFO] [stderr] Compiling url v2.2.2 [INFO] [stderr] Compiling password-hash v0.3.2 [INFO] [stderr] Compiling sha2 v0.10.2 [INFO] [stderr] Compiling backtrace v0.3.64 [INFO] [stderr] Compiling hmac v0.12.1 [INFO] [stderr] Compiling cipher v0.3.0 [INFO] [stderr] Compiling doc-comment v0.3.3 [INFO] [stderr] Compiling base64 v0.13.0 [INFO] [stderr] Compiling num_threads v0.1.6 [INFO] [stderr] Compiling time-macros v0.2.4 [INFO] [stderr] Compiling async-trait v0.1.52 [INFO] [stderr] Compiling eyre v0.6.6 [INFO] [stderr] Compiling gimli v0.26.1 [INFO] [stderr] Compiling ppv-lite86 v0.2.16 [INFO] [stderr] Compiling opaque-debug v0.3.0 [INFO] [stderr] Compiling ipnet v2.3.1 [INFO] [stderr] Compiling aes v0.7.5 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling pbkdf2 v0.10.1 [INFO] [stderr] Compiling flate2 v1.0.22 [INFO] [stderr] Compiling heck v0.3.3 [INFO] [stderr] Compiling sha1 v0.10.1 [INFO] [stderr] Compiling time v0.1.43 [INFO] [stderr] Compiling dirs-sys-next v0.1.2 [INFO] [stderr] Compiling object v0.27.1 [INFO] [stderr] Compiling camino v1.0.7 [INFO] [stderr] Compiling fastrand v1.7.0 [INFO] [stderr] Compiling time v0.3.9 [INFO] [stderr] Compiling rustc-demangle v0.1.21 [INFO] [stderr] Compiling owo-colors v3.2.0 [INFO] [stderr] Compiling indenter v0.3.3 [INFO] [stderr] Compiling constant_time_eq v0.1.5 [INFO] [stderr] Compiling linked-hash-map v0.5.4 [INFO] [stderr] Compiling remove_dir_all v0.5.3 [INFO] [stderr] Compiling byteorder v1.4.3 [INFO] [stderr] Compiling addr2line v0.17.0 [INFO] [stderr] Compiling yaml-rust v0.4.5 [INFO] [stderr] Compiling tempfile v3.3.0 [INFO] [stderr] Compiling tokio-macros v1.7.0 [INFO] [stderr] Compiling tracing-attributes v0.1.19 [INFO] [stderr] Compiling futures-macro v0.3.21 [INFO] [stderr] Compiling thiserror-impl v1.0.30 [INFO] [stderr] Compiling pin-project-internal v1.0.10 [INFO] [stderr] Compiling tokio v1.16.1 [INFO] [stderr] Compiling tracing v0.1.30 [INFO] [stderr] Compiling strum_macros v0.23.1 [INFO] [stderr] Compiling clap_derive v3.1.0 [INFO] [stderr] Compiling pin-project v1.0.10 [INFO] [stderr] Compiling strum_macros v0.24.0 [INFO] [stderr] Compiling thiserror v1.0.30 [INFO] [stderr] Compiling dirs-next v2.0.0 [INFO] [stderr] Compiling parking_lot v0.12.0 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling md-5 v0.10.0 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling os_str_bytes v6.0.0 [INFO] [stderr] Compiling futures-executor v0.3.21 [INFO] [stderr] Compiling strsim v0.10.0 [INFO] [stderr] Compiling futures v0.3.21 [INFO] [stderr] Compiling tokio-util v0.6.9 [INFO] [stderr] Compiling tokio-native-tls v0.3.0 [INFO] [stderr] Compiling tokio-util v0.7.0 [INFO] [stderr] Compiling termcolor v1.1.2 [INFO] [stderr] Compiling fmt2io v0.2.0 [INFO] [stderr] Compiling h2 v0.3.11 [INFO] [stderr] Compiling base16ct v0.1.1 [INFO] [stderr] Compiling textwrap v0.14.2 [INFO] [stderr] Compiling strum v0.24.0 [INFO] [stderr] Compiling backoff v0.4.0 [INFO] [stderr] Compiling shellexpand-fork v2.1.1 [INFO] [stderr] Compiling derivative v2.2.0 [INFO] [stderr] Compiling clap v3.1.0 [INFO] [stderr] Compiling shell-words v1.1.0 [INFO] [stderr] Compiling bzip2 v0.4.3 [INFO] [stderr] Compiling tracing-serde v0.1.3 [INFO] [stderr] Compiling serde_urlencoded v0.7.1 [INFO] [stderr] Compiling quick-xml v0.22.0 [INFO] [stderr] Compiling chrono v0.4.19 [INFO] [stderr] Compiling hyper v0.14.17 [INFO] [stderr] Compiling tracing-subscriber v0.3.8 [INFO] [stderr] Compiling serde_yaml v0.8.23 [INFO] [stderr] Compiling rfc822-like v0.2.1 [INFO] [stderr] Compiling tracing-error v0.2.0 [INFO] [stderr] Compiling color-spantrace v0.2.0 [INFO] [stderr] Compiling color-eyre v0.6.0 [INFO] [stderr] Compiling hyper-tls v0.5.0 [INFO] [stderr] Compiling reqwest v0.11.9 [INFO] [stderr] Compiling open-build-service-api v0.1.0 (https://github.com/collabora/open-build-service-rs#e89ee96c) [INFO] [stderr] Compiling zstd v0.10.2+zstd.1.5.2 [INFO] [stderr] Compiling zip v0.6.2 [INFO] [stderr] Compiling gitlab-runner v0.0.5 [INFO] [stderr] Compiling obs-gitlab-runner v0.1.3 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 3m 30s [INFO] running `Command { std: "docker" "inspect" "bf6b78b37bf1c8ae0ee7eee23eba034aa100fe3bfcb77027009f19b5f3b8fbda", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "bf6b78b37bf1c8ae0ee7eee23eba034aa100fe3bfcb77027009f19b5f3b8fbda", kill_on_drop: false }` [INFO] [stdout] bf6b78b37bf1c8ae0ee7eee23eba034aa100fe3bfcb77027009f19b5f3b8fbda [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/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:bb5b0feb4f87116a4ea905b7ff5bf5b85913db05e9f0fbc1abdd3168928d9075" "/opt/rustwide/cargo-home/bin/cargo" "+015dbcea68b7642e2acc268b5c8f3e9086fc02ff" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] ccc7ce002585c9ca01a466b8136434fe7e29812e457788bf2edcf1d2c07a4b48 [INFO] running `Command { std: "docker" "start" "-a" "ccc7ce002585c9ca01a466b8136434fe7e29812e457788bf2edcf1d2c07a4b48", kill_on_drop: false }` [INFO] [stderr] Compiling value-bag v1.0.0-alpha.8 [INFO] [stderr] Compiling memchr v2.4.1 [INFO] [stderr] Compiling log v0.4.14 [INFO] [stderr] Compiling ctor v0.1.21 [INFO] [stderr] Compiling autocfg v0.1.7 [INFO] [stderr] Compiling rand_core v0.4.2 [INFO] [stderr] Compiling version_check v0.1.5 [INFO] [stderr] Compiling socket2 v0.4.4 [INFO] [stderr] Compiling rand_core v0.3.1 [INFO] [stderr] Compiling cache-padded v1.2.0 [INFO] [stderr] Compiling concurrent-queue v1.2.2 [INFO] [stderr] Compiling standback v0.2.17 [INFO] [stderr] Compiling parking v2.0.0 [INFO] [stderr] Compiling proc-macro-hack v0.5.19 [INFO] [stderr] Compiling unicase v1.4.2 [INFO] [stderr] Compiling event-listener v2.5.2 [INFO] [stderr] Compiling rand_chacha v0.1.1 [INFO] [stderr] Compiling rand_pcg v0.1.2 [INFO] [stderr] Compiling rand v0.6.5 [INFO] [stderr] Compiling waker-fn v1.1.0 [INFO] [stderr] Compiling siphasher v0.2.3 [INFO] [stderr] Compiling futures-util v0.3.21 [INFO] [stderr] Compiling futures-lite v1.12.0 [INFO] [stderr] Compiling phf_shared v0.7.24 [INFO] [stderr] Compiling rand_xorshift v0.1.1 [INFO] [stderr] Compiling rand_hc v0.1.0 [INFO] [stderr] Compiling rand_isaac v0.1.1 [INFO] [stderr] Compiling rand_os v0.1.3 [INFO] [stderr] Compiling rand_jitter v0.1.4 [INFO] [stderr] Compiling cipher v0.2.5 [INFO] [stderr] Compiling crossbeam-utils v0.8.8 [INFO] [stderr] Compiling getrandom v0.1.16 [INFO] [stderr] Compiling universal-hash v0.4.1 [INFO] [stderr] Compiling digest v0.9.0 [INFO] [stderr] Compiling async-task v4.1.0 [INFO] [stderr] Compiling traitobject v0.1.0 [INFO] [stderr] Compiling cpuid-bool v0.2.0 [INFO] [stderr] Compiling const_fn v0.4.9 [INFO] [stderr] Compiling mio v0.7.14 [INFO] [stderr] Compiling want v0.3.0 [INFO] [stderr] Compiling log v0.3.9 [INFO] [stderr] Compiling lexical-core v0.7.6 [INFO] [stderr] Compiling polling v2.2.0 [INFO] [stderr] Compiling polyval v0.4.5 [INFO] [stderr] Compiling phf_generator v0.7.24 [INFO] [stderr] Compiling url v2.2.2 [INFO] [stderr] Compiling aes-soft v0.6.4 [INFO] [stderr] Compiling tokio v1.16.1 [INFO] [stderr] Compiling mime_guess v2.0.3 [INFO] [stderr] Compiling time-macros-impl v0.1.2 [INFO] [stderr] Compiling async-channel v1.6.1 [INFO] [stderr] Compiling idna v0.1.5 [INFO] [stderr] Compiling crypto-mac v0.10.1 [INFO] [stderr] Compiling nom v5.1.2 [INFO] [stderr] Compiling time v0.2.27 [INFO] [stderr] Compiling static_assertions v1.1.0 [INFO] [stderr] Compiling percent-encoding v1.0.1 [INFO] [stderr] Compiling safemem v0.3.3 [INFO] [stderr] Compiling arrayvec v0.5.2 [INFO] [stderr] Compiling atomic-waker v1.0.0 [INFO] [stderr] Compiling blocking v1.1.0 [INFO] [stderr] Compiling url v1.7.2 [INFO] [stderr] Compiling hmac v0.10.1 [INFO] [stderr] Compiling time-macros v0.1.1 [INFO] [stderr] Compiling aes v0.6.0 [INFO] [stderr] Compiling phf_codegen v0.7.24 [INFO] [stderr] Compiling ghash v0.3.1 [INFO] [stderr] Compiling async-io v1.6.0 [INFO] [stderr] Compiling rand_core v0.5.1 [INFO] [stderr] Compiling mime v0.2.6 [INFO] [stderr] Compiling async-executor v1.4.1 [INFO] [stderr] Compiling futures-executor v0.3.21 [INFO] [stderr] Compiling unsafe-any v0.4.2 [INFO] [stderr] Compiling ctr v0.6.0 [INFO] [stderr] Compiling phf v0.7.24 [INFO] [stderr] Compiling aho-corasick v0.7.18 [INFO] [stderr] Compiling async-mutex v1.4.0 [INFO] [stderr] Compiling aead v0.3.2 [INFO] [stderr] Compiling block-buffer v0.9.0 [INFO] [stderr] Compiling cookie v0.14.4 [INFO] [stderr] Compiling crossbeam-queue v0.3.4 [INFO] [stderr] Compiling anyhow v1.0.53 [INFO] [stderr] Compiling semver v1.0.6 [INFO] [stderr] Compiling regex-syntax v0.6.25 [INFO] [stderr] Compiling native-tls v0.2.8 [INFO] [stderr] Compiling aes-gcm v0.8.0 [INFO] [stderr] Compiling sha2 v0.9.9 [INFO] [stderr] Compiling mime_guess v1.8.8 [INFO] [stderr] Compiling async-global-executor v2.0.2 [INFO] [stderr] Compiling futures v0.3.21 [INFO] [stderr] Compiling typemap v0.3.3 [INFO] [stderr] Compiling rand_chacha v0.2.2 [INFO] [stderr] Compiling hkdf v0.10.0 [INFO] [stderr] Compiling base64 v0.9.3 [INFO] [stderr] Compiling tracing-log v0.1.2 [INFO] [stderr] Compiling kv-log-macro v1.0.7 [INFO] [stderr] Compiling async-lock v2.4.0 [INFO] [stderr] Compiling language-tags v0.2.2 [INFO] [stderr] Compiling http-types v2.12.0 [INFO] [stderr] Compiling typeable v0.1.2 [INFO] [stderr] Compiling hyper v0.10.16 [INFO] [stderr] Compiling tracing-subscriber v0.3.8 [INFO] [stderr] Compiling async-std v1.10.0 [INFO] [stderr] Compiling regex v1.5.4 [INFO] [stderr] Compiling tokio-util v0.6.9 [INFO] [stderr] Compiling h2 v0.3.11 [INFO] [stderr] Compiling config v0.10.1 [INFO] [stderr] Compiling tokio-native-tls v0.3.0 [INFO] [stderr] Compiling rand v0.7.3 [INFO] [stderr] Compiling plugin v0.2.6 [INFO] [stderr] Compiling serde_qs v0.8.5 [INFO] [stderr] Compiling modifier v0.1.0 [INFO] [stderr] Compiling infer v0.2.3 [INFO] [stderr] Compiling rustc_version v0.4.0 [INFO] [stderr] Compiling deadpool v0.7.0 [INFO] [stderr] Compiling mustache v0.9.0 [INFO] [stderr] Compiling assert-json-diff v2.0.1 [INFO] [stderr] Compiling ascii v0.8.7 [INFO] [stderr] Compiling chunked_transfer v0.3.1 [INFO] [stderr] Compiling futures-timer v3.0.2 [INFO] [stderr] Compiling groupable v0.2.0 [INFO] [stderr] Compiling nickel v0.11.0 [INFO] [stderr] Compiling tiny_http v0.6.4 [INFO] [stderr] Compiling iron v0.6.1 [INFO] [stderr] Compiling rstest v0.12.0 [INFO] [stderr] Compiling tracing-error v0.2.0 [INFO] [stderr] Compiling hyper v0.14.17 [INFO] [stderr] Compiling buf_redux v0.8.4 [INFO] [stderr] Compiling twoway v0.1.8 [INFO] [stderr] Compiling quick-xml v0.22.0 [INFO] [stderr] Compiling object v0.27.1 [INFO] [stderr] Compiling claim v0.5.0 [INFO] [stderr] Compiling quick-error v1.2.3 [INFO] [stderr] Compiling multipart v0.18.0 [INFO] [stderr] Compiling color-spantrace v0.2.0 [INFO] [stderr] Compiling tokio-util v0.7.0 [INFO] [stderr] Compiling os_str_bytes v6.0.0 [INFO] [stderr] Compiling xml-builder v0.5.0 [INFO] [stderr] Compiling strum v0.23.0 [INFO] [stderr] Compiling clap v3.1.0 [INFO] [stderr] Compiling zip v0.5.13 [INFO] [stderr] Compiling backoff v0.4.0 [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 open-build-service-mock v0.1.0 (https://github.com/collabora/open-build-service-rs#e89ee96c) [INFO] [stderr] Compiling gitlab-runner-mock v0.0.5 [INFO] [stderr] Compiling open-build-service-api v0.1.0 (https://github.com/collabora/open-build-service-rs#e89ee96c) [INFO] [stderr] Compiling gitlab-runner v0.0.5 [INFO] [stderr] Compiling obs-gitlab-runner v0.1.3 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 3m 16s [INFO] [stderr] warning: the following packages contain code that will be rejected by a future version of Rust: traitobject v0.1.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" "ccc7ce002585c9ca01a466b8136434fe7e29812e457788bf2edcf1d2c07a4b48", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "ccc7ce002585c9ca01a466b8136434fe7e29812e457788bf2edcf1d2c07a4b48", kill_on_drop: false }` [INFO] [stdout] ccc7ce002585c9ca01a466b8136434fe7e29812e457788bf2edcf1d2c07a4b48 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/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:bb5b0feb4f87116a4ea905b7ff5bf5b85913db05e9f0fbc1abdd3168928d9075" "/opt/rustwide/cargo-home/bin/cargo" "+015dbcea68b7642e2acc268b5c8f3e9086fc02ff" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 19084db352649b847bb731d99431de4793098486a21cc2730d6a07897c4d1034 [INFO] running `Command { std: "docker" "start" "-a" "19084db352649b847bb731d99431de4793098486a21cc2730d6a07897c4d1034", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.83s [INFO] [stderr] warning: the following packages contain code that will be rejected by a future version of Rust: traitobject v0.1.0 [INFO] [stderr] note: to see what the problems were, use the option `--future-incompat-report`, or run `cargo report future-incompatibilities --id 4` [INFO] [stderr] Running unittests src/main.rs (/opt/rustwide/target/debug/deps/obs_gitlab_runner-d58063dcfd116b46) [INFO] [stdout] [INFO] [stdout] running 112 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 handler::tests::test_flag_parsing ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2 ... FAILED [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_variable_expansion ... ok [INFO] [stdout] test monitor::tests::test_download_log ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test monitor::tests::test_handles_missing_status ... ok [INFO] [stdout] test monitor::tests::test_fails_after_repeated_duplicate_endtimes ... ok [INFO] [stdout] test monitor::tests::test_latest_state ... ok [INFO] [stdout] test monitor::tests::test_handles_old_build_status ... ok [INFO] [stdout] test monitor::tests::test_srcmd5_checks ... 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::build_success_2::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test upload::tests::test_upload ... ok [INFO] [stdout] test retry::tests::test_retry_on_non_client_errors ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2 stdout ---- [INFO] [stdout] -------------- TEST START -------------- [INFO] [stdout] 2022-11-09T17:41:08.452646Z TRACE command{cmdline="dput foo test1-bad.dsc"}: obs_gitlab_runner::handler: > dput foo test1-bad.dsc gitlab.output=true [INFO] [stdout] 2022-11-09T17:41:08.477827Z 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] 2022-11-09T17:41:08.519049Z 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" history_retrieval=Full}:get{project="foo" package="bar" history_retrieval=Full}: obs_gitlab_runner::build_meta: project_meta=ProjectMeta { name: "foo", repositories: [RepositoryMeta { name: "repo", rebuild: Transitive, block: All, arches: ["aarch64", "x86_64"] }] } [INFO] [stdout] 2022-11-09T17:41:08.542899Z 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" history_retrieval=Full}:get{project="foo" package="bar" history_retrieval=Full}: obs_gitlab_runner::build_meta: status=BuildStatus { package: "bar", code: Unknown, dirty: false, details: None } [INFO] [stdout] 2022-11-09T17:41:08.554102Z 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" history_retrieval=Full}:get{project="foo" package="bar" history_retrieval=Full}: obs_gitlab_runner::build_meta: status=BuildStatus { package: "bar", code: Disabled, dirty: false, details: None } [INFO] [stdout] 2022-11-09T17:41:08.574437Z 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" history_retrieval=Full}:get{project="foo" package="bar" history_retrieval=Full}: obs_gitlab_runner::build_meta: Disabling repo=repo arch=x86_64 [INFO] [stdout] 2022-11-09T17:41:08.574591Z 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 { enabled_repos: {RepoArch { repo: "repo", arch: "aarch64" }: JobHistList { jobhist: [] }} }) [INFO] [stdout] 2022-11-09T17:41:08.574731Z 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] 2022-11-09T17:41:08.586037Z 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.missing": "202cb962ac59075b964b07152d234b70", "_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1-bad.dsc": "c09a28e451b8586a98f614f9fe0742de"} present_files={} [INFO] [stdout] 2022-11-09T17:41:08.606799Z 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.missing": "202cb962ac59075b964b07152d234b70", "_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1-bad.dsc": "c09a28e451b8586a98f614f9fe0742de"}}: obs_gitlab_runner::upload: Retry commit, missing 2 entries [INFO] [stdout] 2022-11-09T17:41:08.606889Z 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.missing": "202cb962ac59075b964b07152d234b70", "_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1-bad.dsc": "c09a28e451b8586a98f614f9fe0742de"}}: obs_gitlab_runner::upload: missing=MissingEntries { entries: [CommitEntry { name: "test1.missing", md5: "202cb962ac59075b964b07152d234b70" }, CommitEntry { name: "test1-bad.dsc", md5: "c09a28e451b8586a98f614f9fe0742de" }] } [INFO] [stdout] 2022-11-09T17:41:08.607018Z 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.missing": "202cb962ac59075b964b07152d234b70", "_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1-bad.dsc": "c09a28e451b8586a98f614f9fe0742de"}}:upload_file{root="" filename="test1.missing"}: obs_gitlab_runner::upload: Uploading file [INFO] [stdout] 2022-11-09T17:41:08.630491Z ERROR obs_gitlab_runner::handler: Error running command: [INFO] [stdout] 0: Could not find artifact 'test1.missing' [INFO] [stdout] [INFO] [stdout] Location: [INFO] [stdout] src/artifacts.rs:34 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ SPANTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout] [INFO] [stdout] 0: obs_gitlab_runner::upload::upload_file with root="" filename="test1.missing" [INFO] [stdout] at src/upload.rs:227 [INFO] [stdout] 225 │ } [INFO] [stdout] 226 │ [INFO] [stdout]  227 > #[instrument(skip(self, artifacts))] [INFO] [stdout] 228 │ async fn upload_file( [INFO] [stdout] 229 │ &self, [INFO] [stdout] 1: obs_gitlab_runner::upload::commit with commit_message="test1-bad.dsc" root="" files={"test1.missing": "202cb962ac59075b964b07152d234b70", "_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1-bad.dsc": "c09a28e451b8586a98f614f9fe0742de"} [INFO] [stdout] at src/upload.rs:254 [INFO] [stdout] 252 │ } [INFO] [stdout] 253 │ [INFO] [stdout]  254 > #[instrument(skip(self, artifacts))] [INFO] [stdout] 255 │ async fn commit( [INFO] [stdout] 256 │ &self, [INFO] [stdout] 2: obs_gitlab_runner::upload::upload_package with 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 } } [INFO] [stdout] at src/upload.rs:311 [INFO] [stdout] 309 │ } [INFO] [stdout] 310 │ [INFO] [stdout]  311 > #[instrument(skip(artifacts))] [INFO] [stdout] 312 │ pub async fn upload_package(&self, artifacts: &impl ArtifactDirectory) -> Result { [INFO] [stdout] 313 │ let dsc_parent = self [INFO] [stdout] 3: obs_gitlab_runner::handler::run_dput with args=DputAction { project: "foo", dsc: "test1-bad.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false } [INFO] [stdout] at src/handler.rs:269 [INFO] [stdout] 267 │ } [INFO] [stdout] 268 │ [INFO] [stdout]  269 > #[instrument(skip(self))] [INFO] [stdout] 270 │ async fn run_dput(&mut self, args: DputAction) -> Result<()> { [INFO] [stdout] 271 │ let branch_to = if !args.branch_to.is_empty() { [INFO] [stdout] 4: obs_gitlab_runner::handler::command with cmdline="dput foo test1-bad.dsc" [INFO] [stdout] at src/handler.rs:554 [INFO] [stdout] 552 │ } [INFO] [stdout] 553 │ [INFO] [stdout]  554 > #[instrument(skip(self))] [INFO] [stdout] 555 │ async fn command(&mut self, cmdline: &str) -> Result<()> { [INFO] [stdout] 556 │ // TODO: inject user? [INFO] [stdout] 5: gitlab_runner::run::run with gitlab.job=267 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/gitlab-runner-0.0.5/src/run.rs:137 [INFO] [stdout] 135 │ } [INFO] [stdout] 136 │ [INFO] [stdout]  137 > #[tracing::instrument(skip(self, process,build_dir),fields(gitlab.job=self.response.id))] [INFO] [stdout] 138 │ pub(crate) async fn run(&mut self, process: F, build_dir: PathBuf) [INFO] [stdout] 139 │ where [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 5 frames hidden ⋮  [INFO] [stdout] 6: obs_gitlab_runner::artifacts::ArtifactDirectory::get::{{closure}}::{{closure}}::h3eac9094d3cf0b88 [INFO] [stdout] at /opt/rustwide/workdir/src/artifacts.rs:34 [INFO] [stdout] 32 │ self.get_or_none(filename) [INFO] [stdout] 33 │ .await? [INFO] [stdout]  34 >  .ok_or_else(|| eyre!("Could not find artifact '{}'", filename)) [INFO] [stdout] 35 │ } [INFO] [stdout] 36 │ [INFO] [stdout] 7: core::option::Option::ok_or_else::hadb071e0125ab477 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/option.rs:1083 [INFO] [stdout] 8: obs_gitlab_runner::artifacts::ArtifactDirectory::get::{{closure}}::h0665bbc4de486fcc [INFO] [stdout] at /opt/rustwide/workdir/src/artifacts.rs:32 [INFO] [stdout] 30 │ [INFO] [stdout] 31 │ async fn get(&self, filename: &str) -> Result { [INFO] [stdout]  32 >  self.get_or_none(filename) [INFO] [stdout] 33 │ .await? [INFO] [stdout] 34 │ .ok_or_else(|| eyre!("Could not find artifact '{}'", filename)) [INFO] [stdout] 9:  as core::future::future::Future>::poll::h9d6ed62fa905bb97 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 10:  as core::future::future::Future>::poll::h8d3ad4e14489f1c6 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/future.rs:124 [INFO] [stdout] 11: obs_gitlab_runner::artifacts::ArtifactDirectory::get_file::{{closure}}::h28db3b8f5864c9ef [INFO] [stdout] at /opt/rustwide/workdir/src/artifacts.rs:47 [INFO] [stdout] 45 │ [INFO] [stdout] 46 │ async fn get_file(&self, filename: &str) -> Result { [INFO] [stdout]  47 >  let reader = self.get(filename).await?; [INFO] [stdout] 48 │ async_save_to_tempfile(reader).await [INFO] [stdout] 49 │ } [INFO] [stdout] 12:  as core::future::future::Future>::poll::hdbda0644624f56ee [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 13:  as core::future::future::Future>::poll::hd4cbf1a00f69191e [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/future.rs:124 [INFO] [stdout] 14: obs_gitlab_runner::upload::ObsDscUploader::upload_file::{{closure}}::{{closure}}::h22af4bdcda985087 [INFO] [stdout] at /opt/rustwide/workdir/src/upload.rs:235 [INFO] [stdout] 233 │ ) -> Result<()> { [INFO] [stdout] 234 │ debug!("Uploading file"); [INFO] [stdout]  235 >  let file = artifacts.get_file(root.join(filename).as_str()).await?; [INFO] [stdout] 236 │ [INFO] [stdout] 237 │ retry_large_request(|| { [INFO] [stdout] 15:  as core::future::future::Future>::poll::h6c24e25f567ae118 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 16:  as core::future::future::Future>::poll::h68ba4dc7649f001b [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tracing-0.1.30/src/instrument.rs:272 [INFO] [stdout] 270 │ let this = self.project(); [INFO] [stdout] 271 │ let _enter = this.span.enter(); [INFO] [stdout]  272 >  this.inner.poll(cx) [INFO] [stdout] 273 │ } [INFO] [stdout] 274 │ } [INFO] [stdout] 17: obs_gitlab_runner::upload::ObsDscUploader::upload_file::{{closure}}::ha8bbb5d805e6f097 [INFO] [stdout] at /opt/rustwide/workdir/src/upload.rs:227 [INFO] [stdout] 225 │ } [INFO] [stdout] 226 │ [INFO] [stdout]  227 >  #[instrument(skip(self, artifacts))] [INFO] [stdout] 228 │ async fn upload_file( [INFO] [stdout] 229 │ &self, [INFO] [stdout] 18:  as core::future::future::Future>::poll::he5c22b2b61790b10 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 19: obs_gitlab_runner::upload::ObsDscUploader::commit::{{closure}}::{{closure}}::h893dc9d88cb48cbf [INFO] [stdout] at /opt/rustwide/workdir/src/upload.rs:304 [INFO] [stdout] 302 │ [INFO] [stdout] 303 │ for entry in missing.entries { [INFO] [stdout]  304 >  self.upload_file(root, &entry.name, artifacts).await?; [INFO] [stdout] 305 │ } [INFO] [stdout] 306 │ } [INFO] [stdout] 20:  as core::future::future::Future>::poll::h9f685780e182278a [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 21:  as core::future::future::Future>::poll::he48eb4aa7b44871e [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tracing-0.1.30/src/instrument.rs:272 [INFO] [stdout] 270 │ let this = self.project(); [INFO] [stdout] 271 │ let _enter = this.span.enter(); [INFO] [stdout]  272 >  this.inner.poll(cx) [INFO] [stdout] 273 │ } [INFO] [stdout] 274 │ } [INFO] [stdout] 22: obs_gitlab_runner::upload::ObsDscUploader::commit::{{closure}}::h2297daf1e975741c [INFO] [stdout] at /opt/rustwide/workdir/src/upload.rs:254 [INFO] [stdout] 252 │ } [INFO] [stdout] 253 │ [INFO] [stdout]  254 >  #[instrument(skip(self, artifacts))] [INFO] [stdout] 255 │ async fn commit( [INFO] [stdout] 256 │ &self, [INFO] [stdout] 23:  as core::future::future::Future>::poll::h407491181672042e [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 24: obs_gitlab_runner::upload::ObsDscUploader::upload_package::{{closure}}::{{closure}}::h5421ba5b4dc047a5 [INFO] [stdout] at /opt/rustwide/workdir/src/upload.rs:351 [INFO] [stdout] 349 │ ( [INFO] [stdout] 350 │ self.commit(dsc_filename, dsc_parent, files_to_commit, artifacts) [INFO] [stdout]  351 >  .await?, [INFO] [stdout] 352 │ false, [INFO] [stdout] 353 │ ) [INFO] [stdout] 25:  as core::future::future::Future>::poll::h2296085e5f007bda [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 26:  as core::future::future::Future>::poll::h9455fae2de2a1fed [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tracing-0.1.30/src/instrument.rs:272 [INFO] [stdout] 270 │ let this = self.project(); [INFO] [stdout] 271 │ let _enter = this.span.enter(); [INFO] [stdout]  272 >  this.inner.poll(cx) [INFO] [stdout] 273 │ } [INFO] [stdout] 274 │ } [INFO] [stdout] 27: obs_gitlab_runner::upload::ObsDscUploader::upload_package::{{closure}}::hc9a3f0868af2033f [INFO] [stdout] at /opt/rustwide/workdir/src/upload.rs:311 [INFO] [stdout] 309 │ } [INFO] [stdout] 310 │ [INFO] [stdout]  311 >  #[instrument(skip(artifacts))] [INFO] [stdout] 312 │ pub async fn upload_package(&self, artifacts: &impl ArtifactDirectory) -> Result { [INFO] [stdout] 313 │ let dsc_parent = self [INFO] [stdout] 28:  as core::future::future::Future>::poll::h1c380895d7ec76d2 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 29: obs_gitlab_runner::handler::ObsJobHandler::run_dput::{{closure}}::{{closure}}::h7b85aa3aa0d57390 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:318 [INFO] [stdout] 316 │ debug!(?initial_build_meta); [INFO] [stdout] 317 │ [INFO] [stdout]  318 >  let result = uploader.upload_package(self).await?; [INFO] [stdout] 319 │ [INFO] [stdout] 320 │ // If we couldn't get the metadata before because the package didn't [INFO] [stdout] 30:  as core::future::future::Future>::poll::hc05474279a488d8b [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 31:  as core::future::future::Future>::poll::hd6b995cb341e8a41 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tracing-0.1.30/src/instrument.rs:272 [INFO] [stdout] 270 │ let this = self.project(); [INFO] [stdout] 271 │ let _enter = this.span.enter(); [INFO] [stdout]  272 >  this.inner.poll(cx) [INFO] [stdout] 273 │ } [INFO] [stdout] 274 │ } [INFO] [stdout] 32: obs_gitlab_runner::handler::ObsJobHandler::run_dput::{{closure}}::h9f885a3a471dae03 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:269 [INFO] [stdout] 267 │ } [INFO] [stdout] 268 │ [INFO] [stdout]  269 >  #[instrument(skip(self))] [INFO] [stdout] 270 │ async fn run_dput(&mut self, args: DputAction) -> Result<()> { [INFO] [stdout] 271 │ let branch_to = if !args.branch_to.is_empty() { [INFO] [stdout] 33:  as core::future::future::Future>::poll::h246ff462dfad6f33 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 34: obs_gitlab_runner::handler::ObsJobHandler::command::{{closure}}::{{closure}}::hd7e3ff7fddd04723 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:565 [INFO] [stdout] 563 │ [INFO] [stdout] 564 │ match command.action { [INFO] [stdout]  565 >  Action::Dput(args) => self.run_dput(args).await?, [INFO] [stdout] 566 │ Action::GenerateMonitor(args) => self.run_generate_monitor(args).await?, [INFO] [stdout] 567 │ Action::Monitor(args) => self.run_monitor(args).await?, [INFO] [stdout] 35:  as core::future::future::Future>::poll::hb593554c207b0bfd [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 36:  as core::future::future::Future>::poll::h31fbcd2a111ba92c [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tracing-0.1.30/src/instrument.rs:272 [INFO] [stdout] 270 │ let this = self.project(); [INFO] [stdout] 271 │ let _enter = this.span.enter(); [INFO] [stdout]  272 >  this.inner.poll(cx) [INFO] [stdout] 273 │ } [INFO] [stdout] 274 │ } [INFO] [stdout] 37: obs_gitlab_runner::handler::ObsJobHandler::command::{{closure}}::h097b85bb67758fbb [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:554 [INFO] [stdout] 552 │ } [INFO] [stdout] 553 │ [INFO] [stdout]  554 >  #[instrument(skip(self))] [INFO] [stdout] 555 │ async fn command(&mut self, cmdline: &str) -> Result<()> { [INFO] [stdout] 556 │ // TODO: inject user? [INFO] [stdout] 38:  as core::future::future::Future>::poll::h85aa22f367196203 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 39: ::step::{{closure}}::h6a315c9c83fb7c64 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:608 [INFO] [stdout] 606 │ async fn step(&mut self, script: &[String], _phase: Phase) -> JobResult { [INFO] [stdout] 607 │ for command in script { [INFO] [stdout]  608 >  if let Err(err) = self.command(command).await { [INFO] [stdout] 609 │ // Failed builds would already have information on them printed [INFO] [stdout] 610 │ // above, so don't print anything on them again. [INFO] [stdout] 40:  as core::future::future::Future>::poll::h690644c312777350 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 41:  as core::future::future::Future>::poll::h4c1db1c28f1d0856 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/future.rs:124 [INFO] [stdout] 42: gitlab_runner::run::run::{{closure}}::hfaf498fd530243ae [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/gitlab-runner-0.0.5/src/run.rs:36 [INFO] [stdout] 34 │ let script = response.step(Phase::Script).ok_or(())?; [INFO] [stdout] 35 │ // TODO handle timeout [INFO] [stdout]  36 >  let script_result = handler.step(&script.script, Phase::Script).await; [INFO] [stdout] 37 │ [INFO] [stdout] 38 │ if let Some(after) = response.step(Phase::AfterScript) { [INFO] [stdout] 43:  as core::future::future::Future>::poll::h2f277727bb02c74f [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 44:  as core::future::future::Future>::poll::hc26cd5b5ec5a5531 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tracing-0.1.30/src/instrument.rs:272 [INFO] [stdout] 270 │ let this = self.project(); [INFO] [stdout] 271 │ let _enter = this.span.enter(); [INFO] [stdout]  272 >  this.inner.poll(cx) [INFO] [stdout] 273 │ } [INFO] [stdout] 274 │ } [INFO] [stdout] 45:  as core::future::future::Future>::poll::h1a7bb08cc6b5cfb8 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tracing-0.1.30/src/instrument.rs:329 [INFO] [stdout] 327 │ let future = this.inner; [INFO] [stdout] 328 │ let _default = dispatcher::set_default(dispatcher); [INFO] [stdout]  329 >  future.poll(cx) [INFO] [stdout] 330 │ } [INFO] [stdout] 331 │ } [INFO] [stdout] 46:  as core::future::future::Future>::poll::hda0d90283490c93a [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/future.rs:124 [INFO] [stdout] 47: tokio::runtime::task::core::CoreStage::poll::{{closure}}::hd1598bf8cc957547 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/task/core.rs:161 [INFO] [stdout] 159 │ let future = unsafe { Pin::new_unchecked(future) }; [INFO] [stdout] 160 │ [INFO] [stdout]  161 >  future.poll(&mut cx) [INFO] [stdout] 162 │ }) [INFO] [stdout] 163 │ }; [INFO] [stdout] 48: tokio::loom::std::unsafe_cell::UnsafeCell::with_mut::hb6ff899641f7dfe6 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/loom/std/unsafe_cell.rs:14 [INFO] [stdout] 12 │ [INFO] [stdout] 13 │ pub(crate) fn with_mut(&self, f: impl FnOnce(*mut T) -> R) -> R { [INFO] [stdout]  14 >  f(self.0.get()) [INFO] [stdout] 15 │ } [INFO] [stdout] 16 │ } [INFO] [stdout] 49: tokio::runtime::task::core::CoreStage::poll::hf776e1c4665eea5c [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/task/core.rs:151 [INFO] [stdout] 149 │ pub(super) fn poll(&self, mut cx: Context<'_>) -> Poll { [INFO] [stdout] 150 │ let res = { [INFO] [stdout]  151 >  self.stage.with_mut(|ptr| { [INFO] [stdout] 152 │ // Safety: The caller ensures mutual exclusion to the field. [INFO] [stdout] 153 │ let future = match unsafe { &mut *ptr } { [INFO] [stdout] 50: tokio::runtime::task::harness::poll_future::{{closure}}::h0862f324521ad0b9 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/task/harness.rs:461 [INFO] [stdout] 459 │ } [INFO] [stdout] 460 │ let guard = Guard { core }; [INFO] [stdout]  461 >  let res = guard.core.poll(cx); [INFO] [stdout] 462 │ mem::forget(guard); [INFO] [stdout] 463 │ res [INFO] [stdout] 51:  as core::ops::function::FnOnce<()>>::call_once::h1584cd1644766ed3 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/panic/unwind_safe.rs:271 [INFO] [stdout] 52: std::panicking::try::do_call::h8853eea18f1e4eea [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panicking.rs:483 [INFO] [stdout] 53: __rust_try [INFO] [stdout] at : [INFO] [stdout] 54: std::panicking::try::h0b1d85887b7af6b1 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panicking.rs:447 [INFO] [stdout] 55: std::panic::catch_unwind::hc989e3cc71abdf6a [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panic.rs:137 [INFO] [stdout] 56: tokio::runtime::task::harness::poll_future::hc96903c1bffc47cd [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/task/harness.rs:449 [INFO] [stdout] 447 │ fn poll_future(core: &CoreStage, cx: Context<'_>) -> Poll<()> { [INFO] [stdout] 448 │ // Poll the future. [INFO] [stdout]  449 >  let output = panic::catch_unwind(panic::AssertUnwindSafe(|| { [INFO] [stdout] 450 │ struct Guard<'a, T: Future> { [INFO] [stdout] 451 │ core: &'a CoreStage, [INFO] [stdout] 57: tokio::runtime::task::harness::Harness::poll_inner::h05666da0a41a02a8 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/task/harness.rs:98 [INFO] [stdout] 96 │ let waker_ref = waker_ref::(self.header()); [INFO] [stdout] 97 │ let cx = Context::from_waker(&*waker_ref); [INFO] [stdout]  98 >  let res = poll_future(&self.core().stage, cx); [INFO] [stdout] 99 │ [INFO] [stdout] 100 │ if res == Poll::Ready(()) { [INFO] [stdout] 58: tokio::runtime::task::harness::Harness::poll::h2b3170d97302c3b2 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/task/harness.rs:53 [INFO] [stdout] 51 │ pub(super) fn poll(self) { [INFO] [stdout] 52 │ // We pass our ref-count to `poll_inner`. [INFO] [stdout]  53 >  match self.poll_inner() { [INFO] [stdout] 54 │ PollFuture::Notified => { [INFO] [stdout] 55 │ // The `poll_inner` call has given us two ref-counts back. [INFO] [stdout] 59: tokio::runtime::task::raw::poll::hc9c4a2c04094b9f9 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/task/raw.rs:113 [INFO] [stdout] 111 │ unsafe fn poll(ptr: NonNull
) { [INFO] [stdout] 112 │ let harness = Harness::::from_raw(ptr); [INFO] [stdout]  113 >  harness.poll(); [INFO] [stdout] 114 │ } [INFO] [stdout] 115 │ [INFO] [stdout] 60: tokio::runtime::task::raw::RawTask::poll::h577199a6baa7abb2 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/task/raw.rs:70 [INFO] [stdout] 68 │ pub(super) fn poll(self) { [INFO] [stdout] 69 │ let vtable = self.header().vtable; [INFO] [stdout]  70 >  unsafe { (vtable.poll)(self.ptr) } [INFO] [stdout] 71 │ } [INFO] [stdout] 72 │ [INFO] [stdout] 61: tokio::runtime::task::LocalNotified::run::h8a0c26bd74063749 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/task/mod.rs:343 [INFO] [stdout] 341 │ let raw = self.task.raw; [INFO] [stdout] 342 │ mem::forget(self); [INFO] [stdout]  343 >  raw.poll(); [INFO] [stdout] 344 │ } [INFO] [stdout] 345 │ } [INFO] [stdout] 62: tokio::runtime::basic_scheduler::CoreGuard::block_on::{{closure}}::{{closure}}::h519534bec4576e5e [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:557 [INFO] [stdout] 555 │ [INFO] [stdout] 556 │ let (c, _) = context.run_task(core, || { [INFO] [stdout]  557 >  task.run(); [INFO] [stdout] 558 │ }); [INFO] [stdout] 559 │ [INFO] [stdout] 63: tokio::coop::with_budget::{{closure}}::h0685771f205d9f47 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/coop.rs:102 [INFO] [stdout] 100 │ let _guard = ResetGuard { cell, prev }; [INFO] [stdout] 101 │ [INFO] [stdout]  102 >  f() [INFO] [stdout] 103 │ }) [INFO] [stdout] 104 │ } [INFO] [stdout] 64: std::thread::local::LocalKey::try_with::h7abbe10988de466a [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/thread/local.rs:446 [INFO] [stdout] 65: std::thread::local::LocalKey::with::h426b661fd715f278 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/thread/local.rs:422 [INFO] [stdout] 66: tokio::coop::with_budget::h0c65d075ec915ee0 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/coop.rs:95 [INFO] [stdout] 93 │ } [INFO] [stdout] 94 │ [INFO] [stdout]  95 >  CURRENT.with(move |cell| { [INFO] [stdout] 96 │ let prev = cell.get(); [INFO] [stdout] 97 │ [INFO] [stdout] 67: tokio::coop::budget::hb468962713e0efd6 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/coop.rs:72 [INFO] [stdout] 70 │ #[inline(always)] [INFO] [stdout] 71 │ pub(crate) fn budget(f: impl FnOnce() -> R) -> R { [INFO] [stdout]  72 >  with_budget(Budget::initial(), f) [INFO] [stdout] 73 │ } [INFO] [stdout] 74 │ [INFO] [stdout] 68: tokio::runtime::basic_scheduler::Context::run_task::{{closure}}::h3b5dda510110b411 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:309 [INFO] [stdout] 307 │ fn run_task(&self, mut core: Box, f: impl FnOnce() -> R) -> (Box, R) { [INFO] [stdout] 308 │ core.metrics.incr_poll_count(); [INFO] [stdout]  309 >  self.enter(core, || crate::coop::budget(f)) [INFO] [stdout] 310 │ } [INFO] [stdout] 311 │ [INFO] [stdout] 69: tokio::runtime::basic_scheduler::Context::enter::hfc60e1c9a7f238e8 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:374 [INFO] [stdout] 372 │ [INFO] [stdout] 373 │ // Execute the closure while tracking the execution budget [INFO] [stdout]  374 >  let ret = f(); [INFO] [stdout] 375 │ [INFO] [stdout] 376 │ // Take the scheduler core back [INFO] [stdout] 70: tokio::runtime::basic_scheduler::Context::run_task::h3586ca73a8ae5a7d [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:309 [INFO] [stdout] 307 │ fn run_task(&self, mut core: Box, f: impl FnOnce() -> R) -> (Box, R) { [INFO] [stdout] 308 │ core.metrics.incr_poll_count(); [INFO] [stdout]  309 >  self.enter(core, || crate::coop::budget(f)) [INFO] [stdout] 310 │ } [INFO] [stdout] 311 │ [INFO] [stdout] 71: tokio::runtime::basic_scheduler::CoreGuard::block_on::{{closure}}::h5b65bbb9b1b91471 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:556 [INFO] [stdout] 554 │ let task = context.spawner.shared.owned.assert_owner(task); [INFO] [stdout] 555 │ [INFO] [stdout]  556 >  let (c, _) = context.run_task(core, || { [INFO] [stdout] 557 │ task.run(); [INFO] [stdout] 558 │ }); [INFO] [stdout] 72: tokio::runtime::basic_scheduler::CoreGuard::enter::{{closure}}::h4531dc96313cbb38 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:582 [INFO] [stdout] 580 │ [INFO] [stdout] 581 │ // Call the closure and place `core` back [INFO] [stdout]  582 >  let (core, ret) = CURRENT.set(&self.context, || f(core, &self.context)); [INFO] [stdout] 583 │ [INFO] [stdout] 584 │ *self.context.core.borrow_mut() = Some(core); [INFO] [stdout] 73: tokio::macros::scoped_tls::ScopedKey::set::hfff667e5b48d6002 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/macros/scoped_tls.rs:61 [INFO] [stdout] 59 │ }; [INFO] [stdout] 60 │ [INFO] [stdout]  61 >  f() [INFO] [stdout] 62 │ } [INFO] [stdout] 63 │ [INFO] [stdout] 74: tokio::runtime::basic_scheduler::CoreGuard::enter::h0635d3bf1e089ef1 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:582 [INFO] [stdout] 580 │ [INFO] [stdout] 581 │ // Call the closure and place `core` back [INFO] [stdout]  582 >  let (core, ret) = CURRENT.set(&self.context, || f(core, &self.context)); [INFO] [stdout] 583 │ [INFO] [stdout] 584 │ *self.context.core.borrow_mut() = Some(core); [INFO] [stdout] 75: tokio::runtime::basic_scheduler::CoreGuard::block_on::h354cf198f5227a7d [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:506 [INFO] [stdout] 504 │ impl CoreGuard<'_> { [INFO] [stdout] 505 │ fn block_on(self, future: F) -> F::Output { [INFO] [stdout]  506 >  self.enter(|mut core, context| { [INFO] [stdout] 507 │ let _enter = crate::runtime::enter(false); [INFO] [stdout] 508 │ let waker = context.spawner.waker_ref(); [INFO] [stdout] 76: tokio::runtime::basic_scheduler::BasicScheduler::block_on::hc7771ae8e8b6652d [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:182 [INFO] [stdout] 180 │ loop { [INFO] [stdout] 181 │ if let Some(core) = self.take_core() { [INFO] [stdout]  182 >  return core.block_on(future); [INFO] [stdout] 183 │ } else { [INFO] [stdout] 184 │ let mut enter = crate::runtime::enter(false); [INFO] [stdout] 77: tokio::runtime::Runtime::block_on::h2ff4c1ee1cb1c467 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/mod.rs:475 [INFO] [stdout] 473 │ [INFO] [stdout] 474 │ match &self.kind { [INFO] [stdout]  475 >  Kind::CurrentThread(exec) => exec.block_on(future), [INFO] [stdout] 476 │ #[cfg(feature = "rt-multi-thread")] [INFO] [stdout] 477 │ Kind::ThreadPool(exec) => exec.block_on(future), [INFO] [stdout] 78: obs_gitlab_runner::handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2::he7a082e9a66deed8 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1661 [INFO] [stdout] 1659 │ } [INFO] [stdout] 1660 │ [INFO] [stdout]  1661 >  #[rstest] [INFO] [stdout] 1662 │ #[tokio::test] [INFO] [stdout] 1663 │ async fn test_handler_flow( [INFO] [stdout] 79: obs_gitlab_runner::handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2::{{closure}}::h4050e1cc3ee69778 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1661 [INFO] [stdout] 1659 │ } [INFO] [stdout] 1660 │ [INFO] [stdout]  1661 >  #[rstest] [INFO] [stdout] 1662 │ #[tokio::test] [INFO] [stdout] 1663 │ async fn test_handler_flow( [INFO] [stdout] 80: core::ops::function::FnOnce::call_once::ha39b005752ce8763 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/ops/function.rs:510 [INFO] [stdout] 81: core::ops::function::FnOnce::call_once::h2f93ceb28cb0c61c [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/ops/function.rs:510 [INFO] [stdout] 82: test::__rust_begin_short_backtrace::hdd721a0e8ee25a0e [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/test/src/lib.rs:637 [INFO] [stdout] 83: test::run_test::{{closure}}::h95fe25ef5b9cf584 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/test/src/lib.rs:628 [INFO] [stdout] 84: core::ops::function::FnOnce::call_once{{vtable.shim}}::he3685b1dad984a5a [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/ops/function.rs:510 [INFO] [stdout] 85:  as core::ops::function::FnOnce>::call_once::h301cae15faf66265 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/alloc/src/boxed.rs:2000 [INFO] [stdout] 86:  as core::ops::function::FnOnce<()>>::call_once::hb7be0f46463c4091 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/panic/unwind_safe.rs:271 [INFO] [stdout] 87: std::panicking::try::do_call::he80e1dd13c6c4b2c [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panicking.rs:483 [INFO] [stdout] 88: std::panicking::try::h88563fd695098a44 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panicking.rs:447 [INFO] [stdout] 89: std::panic::catch_unwind::h6a5b06a8b67964f8 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panic.rs:137 [INFO] [stdout] 90: test::run_test_in_process::h3bfa2ac051e33645 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/test/src/lib.rs:660 [INFO] [stdout]  ⋮ 15 frames hidden ⋮  [INFO] [stdout] [INFO] [stdout] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering. gitlab.output=true [INFO] [stdout] 2022-11-09T17:41:09.519755Z TRACE command{cmdline="dput foo test1.dsc"}: obs_gitlab_runner::handler: > dput foo test1.dsc gitlab.output=true [INFO] [stdout] 2022-11-09T17:41:09.523001Z 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] 2022-11-09T17:41:09.543069Z 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" history_retrieval=Full}:get{project="foo" package="bar" history_retrieval=Full}: obs_gitlab_runner::build_meta: project_meta=ProjectMeta { name: "foo", repositories: [RepositoryMeta { name: "repo", rebuild: Transitive, block: All, arches: ["aarch64", "x86_64"] }] } [INFO] [stdout] 2022-11-09T17:41:09.544547Z 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" history_retrieval=Full}:get{project="foo" package="bar" history_retrieval=Full}: obs_gitlab_runner::build_meta: status=BuildStatus { package: "bar", code: Unknown, dirty: false, details: None } [INFO] [stdout] 2022-11-09T17:41:09.548644Z 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" history_retrieval=Full}:get{project="foo" package="bar" history_retrieval=Full}: obs_gitlab_runner::build_meta: status=BuildStatus { package: "bar", code: Disabled, dirty: false, details: None } [INFO] [stdout] 2022-11-09T17:41:09.548727Z 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" history_retrieval=Full}:get{project="foo" package="bar" history_retrieval=Full}: obs_gitlab_runner::build_meta: Disabling repo=repo arch=x86_64 [INFO] [stdout] 2022-11-09T17:41:09.548851Z 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 { enabled_repos: {RepoArch { repo: "repo", arch: "aarch64" }: JobHistList { jobhist: [] }} }) [INFO] [stdout] 2022-11-09T17:41:09.548994Z 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] 2022-11-09T17:41:09.565250Z 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] 2022-11-09T17:41:09.602731Z 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] 2022-11-09T17:41:09.602805Z 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] 2022-11-09T17:41:09.602927Z 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] 2022-11-09T17:41:09.614639Z 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] 2022-11-09T17:41:09.643572Z 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] 2022-11-09T17:41:09.643707Z 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("192b40707694f0350f9b609d41624349"), is_branched: false, enabled_repos: {RepoArch { repo: "repo", arch: "aarch64" }: CommitBuildInfo { prev_endtime_for_commit: None }} } [INFO] [stdout] 2022-11-09T17:41:09.767913Z TRACE command{cmdline="dput foo test1.dsc"}: obs_gitlab_runner::handler: > dput foo test1.dsc gitlab.output=true [INFO] [stdout] 2022-11-09T17:41:09.783669Z 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] 2022-11-09T17:41:09.786427Z 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" history_retrieval=Full}:get{project="foo" package="bar" history_retrieval=Full}: obs_gitlab_runner::build_meta: project_meta=ProjectMeta { name: "foo", repositories: [RepositoryMeta { name: "repo", rebuild: Transitive, block: All, arches: ["aarch64", "x86_64"] }] } [INFO] [stdout] 2022-11-09T17:41:09.787864Z 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" history_retrieval=Full}:get{project="foo" package="bar" history_retrieval=Full}: obs_gitlab_runner::build_meta: status=BuildStatus { package: "bar", code: Failed, dirty: false, details: None } [INFO] [stdout] 2022-11-09T17:41:09.815027Z 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" history_retrieval=Full}:get{project="foo" package="bar" history_retrieval=Full}: obs_gitlab_runner::build_meta: status=BuildStatus { package: "bar", code: Disabled, dirty: false, details: None } [INFO] [stdout] 2022-11-09T17:41:09.815142Z 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" history_retrieval=Full}:get{project="foo" package="bar" history_retrieval=Full}: obs_gitlab_runner::build_meta: Disabling repo=repo arch=x86_64 [INFO] [stdout] 2022-11-09T17:41:09.815293Z 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 { enabled_repos: {RepoArch { repo: "repo", arch: "aarch64" }: JobHistList { jobhist: [JobHist { package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", versrel: "1.0-1", bcnt: "1", readytime: 1668015669, starttime: 1668015669, endtime: 1668015669, code: Succeeded, uri: "http://127.0.0.1:9000", workerid: "worker:1", hostarch: "x86_64", reason: "source change", verifymd5: "6b6dddfc4f6bca139e330bbb52a78410" }] }} }) [INFO] [stdout] 2022-11-09T17:41:09.816640Z 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] 2022-11-09T17:41:09.827753Z 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.dsc": "4c85fb7ae65cc69676f0d043082779e7", "test1": "202cb962ac59075b964b07152d234b70"} present_files={"_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1.dsc": "4c85fb7ae65cc69676f0d043082779e7", "test1": "202cb962ac59075b964b07152d234b70"} [INFO] [stdout] 2022-11-09T17:41:09.836081Z 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] 2022-11-09T17:41:09.836252Z 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("192b40707694f0350f9b609d41624349"), is_branched: false, enabled_repos: {RepoArch { repo: "repo", arch: "aarch64" }: CommitBuildInfo { prev_endtime_for_commit: None }} } [INFO] [stdout] 2022-11-09T17:41:10.010859Z 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] 2022-11-09T17:41:10.027112Z 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] 2022-11-09T17:41:10.029986Z 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" history_retrieval=Full}:get{project="foo" package="bar" history_retrieval=Full}: obs_gitlab_runner::build_meta: project_meta=ProjectMeta { name: "foo", repositories: [RepositoryMeta { name: "repo", rebuild: Transitive, block: All, arches: ["aarch64", "x86_64"] }] } [INFO] [stdout] 2022-11-09T17:41:10.031380Z 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" history_retrieval=Full}:get{project="foo" package="bar" history_retrieval=Full}: obs_gitlab_runner::build_meta: status=BuildStatus { package: "bar", code: Failed, dirty: false, details: None } [INFO] [stdout] 2022-11-09T17:41:10.042900Z 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" history_retrieval=Full}:get{project="foo" package="bar" history_retrieval=Full}: obs_gitlab_runner::build_meta: status=BuildStatus { package: "bar", code: Disabled, dirty: false, details: None } [INFO] [stdout] 2022-11-09T17:41:10.043004Z 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" history_retrieval=Full}:get{project="foo" package="bar" history_retrieval=Full}: obs_gitlab_runner::build_meta: Disabling repo=repo arch=x86_64 [INFO] [stdout] 2022-11-09T17:41:10.043133Z 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 { enabled_repos: {RepoArch { repo: "repo", arch: "aarch64" }: JobHistList { jobhist: [JobHist { package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", versrel: "1.0-1", bcnt: "1", readytime: 1668015669, starttime: 1668015669, endtime: 1668015669, code: Succeeded, uri: "http://127.0.0.1:9000", workerid: "worker:1", hostarch: "x86_64", reason: "source change", verifymd5: "6b6dddfc4f6bca139e330bbb52a78410" }] }} }) [INFO] [stdout] 2022-11-09T17:41:10.043298Z 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] 2022-11-09T17:41:10.095348Z 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={"test1.dsc": "4c85fb7ae65cc69676f0d043082779e7", "test1": "202cb962ac59075b964b07152d234b70", "_meta": "f4f75daf7cd22556e6bef525a11ed94f"} present_files={"test1.dsc": "4c85fb7ae65cc69676f0d043082779e7", "test1": "202cb962ac59075b964b07152d234b70", "_meta": "f4f75daf7cd22556e6bef525a11ed94f"} [INFO] [stdout] 2022-11-09T17:41:10.097178Z 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] 2022-11-09T17:41:10.123070Z 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("192b40707694f0350f9b609d41624349"), is_branched: false, enabled_repos: {RepoArch { repo: "repo", arch: "aarch64" }: CommitBuildInfo { prev_endtime_for_commit: Some(1668015669) }} } [INFO] [stdout] 2022-11-09T17:41:10.259412Z TRACE command{cmdline="generate-monitor test-tag --rules '[{a: 1}, {b: 2}]' --download-build-results-to results"}: obs_gitlab_runner::handler: > generate-monitor test-tag --rules '[{a: 1}, {b: 2}]' --download-build-results-to results gitlab.output=true [INFO] [stdout] 2022-11-09T17:41:10.277069Z TRACE command{cmdline="generate-monitor test-tag --rules '[{a: 1}, {b: 2}]' --download-build-results-to results"}:run_generate_monitor{args=GenerateMonitorAction { tag: "test-tag", rules: Some("[{a: 1}, {b: 2}]"), build_results_dir: Some("results"), build_info: "build-info.yml", pipeline_out: "obs.yml", job_prefix: "obs", artifact_expiration: "3 days", build_log_out: "build.log" }}: obs_gitlab_runner::handler: Wrote pipeline file 'obs.yml'. gitlab.output=true [INFO] [stdout] 2022-11-09T17:41:10.357926Z TRACE command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}: obs_gitlab_runner::handler: > monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64 gitlab.output=true [INFO] [stdout] 2022-11-09T17:41:10.399430Z TRACE command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_dirty: 0ns, sleep_on_old_status: 100ms, max_old_status_retries: 99 }}: obs_gitlab_runner::monitor: Live build log: http://127.0.0.1:43439/package/live_build_log/foo/bar/repo/aarch64 gitlab.output=true [INFO] [stdout] 2022-11-09T17:41:10.438873Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_dirty: 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: "192b40707694f0350f9b609d41624349", versrel: "1.0-1", bcnt: "1", readytime: 1668015669, starttime: 1668015669, endtime: 1668015669, code: Succeeded, uri: "http://127.0.0.1:9000", workerid: "worker:1", hostarch: "x86_64", reason: "source change", verifymd5: "6b6dddfc4f6bca139e330bbb52a78410" }] [INFO] [stdout] 2022-11-09T17:41:10.438954Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_dirty: 0ns, sleep_on_old_status: 100ms, max_old_status_retries: 99 }}:get_latest_state: obs_gitlab_runner::monitor: prev_endtime_for_commit=Some(1668015669) [INFO] [stdout] 2022-11-09T17:41:10.439010Z TRACE command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_dirty: 0ns, sleep_on_old_status: 100ms, max_old_status_retries: 99 }}: obs_gitlab_runner::monitor: Waiting for build status to be available... gitlab.output=true [INFO] [stdout] 2022-11-09T17:41:10.562776Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_dirty: 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: "192b40707694f0350f9b609d41624349", versrel: "1.0-1", bcnt: "1", readytime: 1668015669, starttime: 1668015669, endtime: 1668015669, code: Succeeded, uri: "http://127.0.0.1:9000", workerid: "worker:1", hostarch: "x86_64", reason: "source change", verifymd5: "6b6dddfc4f6bca139e330bbb52a78410" }] [INFO] [stdout] 2022-11-09T17:41:10.562858Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_dirty: 0ns, sleep_on_old_status: 100ms, max_old_status_retries: 99 }}:get_latest_state: obs_gitlab_runner::monitor: prev_endtime_for_commit=Some(1668015669) [INFO] [stdout] 2022-11-09T17:41:10.670073Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_dirty: 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: "192b40707694f0350f9b609d41624349", versrel: "1.0-1", bcnt: "1", readytime: 1668015669, starttime: 1668015669, endtime: 1668015669, code: Succeeded, uri: "http://127.0.0.1:9000", workerid: "worker:1", hostarch: "x86_64", reason: "source change", verifymd5: "6b6dddfc4f6bca139e330bbb52a78410" }] [INFO] [stdout] 2022-11-09T17:41:10.670143Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_dirty: 0ns, sleep_on_old_status: 100ms, max_old_status_retries: 99 }}:get_latest_state: obs_gitlab_runner::monitor: prev_endtime_for_commit=Some(1668015669) [INFO] [stdout] 2022-11-09T17:41:10.780420Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_dirty: 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: "192b40707694f0350f9b609d41624349", versrel: "1.0-1", bcnt: "1", readytime: 1668015669, starttime: 1668015669, endtime: 1668015669, code: Succeeded, uri: "http://127.0.0.1:9000", workerid: "worker:1", hostarch: "x86_64", reason: "source change", verifymd5: "6b6dddfc4f6bca139e330bbb52a78410" }] [INFO] [stdout] 2022-11-09T17:41:10.780499Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_dirty: 0ns, sleep_on_old_status: 100ms, max_old_status_retries: 99 }}:get_latest_state: obs_gitlab_runner::monitor: prev_endtime_for_commit=Some(1668015669) [INFO] [stdout] 2022-11-09T17:41:10.889335Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_dirty: 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: "192b40707694f0350f9b609d41624349", versrel: "1.0-1", bcnt: "1", readytime: 1668015669, starttime: 1668015669, endtime: 1668015669, code: Succeeded, uri: "http://127.0.0.1:9000", workerid: "worker:1", hostarch: "x86_64", reason: "source change", verifymd5: "6b6dddfc4f6bca139e330bbb52a78410" }] [INFO] [stdout] 2022-11-09T17:41:10.889414Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_dirty: 0ns, sleep_on_old_status: 100ms, max_old_status_retries: 99 }}:get_latest_state: obs_gitlab_runner::monitor: prev_endtime_for_commit=Some(1668015669) [INFO] [stdout] 2022-11-09T17:41:11.050673Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_dirty: 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: "192b40707694f0350f9b609d41624349", versrel: "1.0-1", bcnt: "1", readytime: 1668015669, starttime: 1668015669, endtime: 1668015669, code: Succeeded, uri: "http://127.0.0.1:9000", workerid: "worker:1", hostarch: "x86_64", reason: "source change", verifymd5: "6b6dddfc4f6bca139e330bbb52a78410" }] [INFO] [stdout] 2022-11-09T17:41:11.050800Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_dirty: 0ns, sleep_on_old_status: 100ms, max_old_status_retries: 99 }}:get_latest_state: obs_gitlab_runner::monitor: prev_endtime_for_commit=Some(1668015669) [INFO] [stdout] 2022-11-09T17:41:11.199621Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_dirty: 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: "192b40707694f0350f9b609d41624349", versrel: "1.0-1", bcnt: "1", readytime: 1668015669, starttime: 1668015669, endtime: 1668015669, code: Succeeded, uri: "http://127.0.0.1:9000", workerid: "worker:1", hostarch: "x86_64", reason: "source change", verifymd5: "6b6dddfc4f6bca139e330bbb52a78410" }] [INFO] [stdout] 2022-11-09T17:41:11.199729Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_dirty: 0ns, sleep_on_old_status: 100ms, max_old_status_retries: 99 }}:get_latest_state: obs_gitlab_runner::monitor: prev_endtime_for_commit=Some(1668015669) [INFO] [stdout] 2022-11-09T17:41:11.328203Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_dirty: 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: "192b40707694f0350f9b609d41624349", versrel: "1.0-1", bcnt: "1", readytime: 1668015669, starttime: 1668015669, endtime: 1668015669, code: Succeeded, uri: "http://127.0.0.1:9000", workerid: "worker:1", hostarch: "x86_64", reason: "source change", verifymd5: "6b6dddfc4f6bca139e330bbb52a78410" }] [INFO] [stdout] 2022-11-09T17:41:11.328337Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_dirty: 0ns, sleep_on_old_status: 100ms, max_old_status_retries: 99 }}:get_latest_state: obs_gitlab_runner::monitor: prev_endtime_for_commit=Some(1668015669) [INFO] [stdout] 2022-11-09T17:41:11.464329Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_dirty: 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: "192b40707694f0350f9b609d41624349", versrel: "1.0-1", bcnt: "1", readytime: 1668015669, starttime: 1668015669, endtime: 1668015669, code: Succeeded, uri: "http://127.0.0.1:9000", workerid: "worker:1", hostarch: "x86_64", reason: "source change", verifymd5: "6b6dddfc4f6bca139e330bbb52a78410" }, JobHist { package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", versrel: "1.0-1", bcnt: "1", readytime: 1668015671, starttime: 1668015671, endtime: 999, code: Succeeded, uri: "http://127.0.0.1:9000", workerid: "worker:1", hostarch: "x86_64", reason: "source change", verifymd5: "575c05cca6ac3a96efcb719bffd4dc33" }] [INFO] [stdout] 2022-11-09T17:41:11.464475Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_dirty: 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] 2022-11-09T17:41:11.464613Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}: obs_gitlab_runner::handler: Completed with: Succeeded [INFO] [stdout] 2022-11-09T17:41:11.511786Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" }}:download_build_log{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } }}:check_log_md5: obs_gitlab_runner::monitor: needle=srcmd5 '192b40707694f0350f9b609d41624349' content=srcmd5 'd41d8cd98f00b204e9800998ecf8427e' this is a long log that will need to be trimmed when printed [INFO] [stdout] 2022-11-09T17:41:11.586515Z ERROR obs_gitlab_runner::handler: Error running command: [INFO] [stdout] 0: Build logs are unavailable (overwritten by a later build revision?) [INFO] [stdout] [INFO] [stdout] Location: [INFO] [stdout] /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/eyre-0.6.6/src/lib.rs:1164 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ SPANTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout] [INFO] [stdout] 0: obs_gitlab_runner::monitor::check_log_md5 [INFO] [stdout] at src/monitor.rs:177 [INFO] [stdout] 175 │ } [INFO] [stdout] 176 │ [INFO] [stdout]  177 > #[instrument(skip(self, content))] [INFO] [stdout] 178 │ fn check_log_md5(&self, content: &str) -> Result<()> { [INFO] [stdout] 179 │ let needle = format!("srcmd5 '{}'", self.package.srcmd5); [INFO] [stdout] 1: obs_gitlab_runner::monitor::download_build_log with self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", prev_endtime_for_commit: Some(1668015669) } } [INFO] [stdout] at src/monitor.rs:264 [INFO] [stdout] 262 │ } [INFO] [stdout] 263 │ [INFO] [stdout]  264 > #[instrument] [INFO] [stdout] 265 │ pub async fn download_build_log(&self) -> Result { [INFO] [stdout] 266 │ const LOG_LEN_TO_CHECK_FOR_MD5: u64 = 2500; [INFO] [stdout] 2: obs_gitlab_runner::handler::run_monitor with args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "192b40707694f0350f9b609d41624349", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1668015669), build_log_out: "build.log" } [INFO] [stdout] at src/handler.rs:416 [INFO] [stdout] 414 │ } [INFO] [stdout] 415 │ [INFO] [stdout]  416 > #[instrument(skip(self))] [INFO] [stdout] 417 │ async fn run_monitor(&mut self, args: MonitorAction) -> Result<()> { [INFO] [stdout] 418 │ let monitor = ObsMonitor::new( [INFO] [stdout] 3: obs_gitlab_runner::handler::command with cmdline="monitor --rev 1 --srcmd5 192b40707694f0350f9b609d41624349 --build-log-out build.log --prev-endtime-for-commit 1668015669 --project foo --package bar --repository repo --arch aarch64" [INFO] [stdout] at src/handler.rs:554 [INFO] [stdout] 552 │ } [INFO] [stdout] 553 │ [INFO] [stdout]  554 > #[instrument(skip(self))] [INFO] [stdout] 555 │ async fn command(&mut self, cmdline: &str) -> Result<()> { [INFO] [stdout] 556 │ // TODO: inject user? [INFO] [stdout] 4: gitlab_runner::run::run with gitlab.job=272 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/gitlab-runner-0.0.5/src/run.rs:137 [INFO] [stdout] 135 │ } [INFO] [stdout] 136 │ [INFO] [stdout]  137 > #[tracing::instrument(skip(self, process,build_dir),fields(gitlab.job=self.response.id))] [INFO] [stdout] 138 │ pub(crate) async fn run(&mut self, process: F, build_dir: PathBuf) [INFO] [stdout] 139 │ where [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 6 frames hidden ⋮  [INFO] [stdout] 7: obs_gitlab_runner::monitor::ObsMonitor::check_log_md5::hc4146600f2e542a4 [INFO] [stdout] at /opt/rustwide/workdir/src/monitor.rs:181 [INFO] [stdout] 179 │ let needle = format!("srcmd5 '{}'", self.package.srcmd5); [INFO] [stdout] 180 │ debug!(%needle, %content); [INFO] [stdout]  181 >  ensure!( [INFO] [stdout] 182 │ content.contains(&needle), [INFO] [stdout] 183 │ "Build logs are unavailable (overwritten by a later build revision?)" [INFO] [stdout] 8: obs_gitlab_runner::monitor::ObsMonitor::download_build_log::{{closure}}::{{closure}}::h2f09fc90a2802fb4 [INFO] [stdout] at /opt/rustwide/workdir/src/monitor.rs:300 [INFO] [stdout] 298 │ .await [INFO] [stdout] 299 │ .wrap_err("Failed to read start of logs")?; [INFO] [stdout]  300 >  self.check_log_md5(&String::from_utf8_lossy(&buf))?; [INFO] [stdout] 301 │ [INFO] [stdout] 302 │ file.rewind().await.wrap_err("Failed to rewind file")?; [INFO] [stdout] 9:  as core::future::future::Future>::poll::h64145896b5595d05 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 10:  as core::future::future::Future>::poll::h0cda80c698264608 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tracing-0.1.30/src/instrument.rs:272 [INFO] [stdout] 270 │ let this = self.project(); [INFO] [stdout] 271 │ let _enter = this.span.enter(); [INFO] [stdout]  272 >  this.inner.poll(cx) [INFO] [stdout] 273 │ } [INFO] [stdout] 274 │ } [INFO] [stdout] 11: obs_gitlab_runner::monitor::ObsMonitor::download_build_log::{{closure}}::heb90a0cac49ca0d2 [INFO] [stdout] at /opt/rustwide/workdir/src/monitor.rs:264 [INFO] [stdout] 262 │ } [INFO] [stdout] 263 │ [INFO] [stdout]  264 >  #[instrument] [INFO] [stdout] 265 │ pub async fn download_build_log(&self) -> Result { [INFO] [stdout] 266 │ const LOG_LEN_TO_CHECK_FOR_MD5: u64 = 2500; [INFO] [stdout] 12:  as core::future::future::Future>::poll::ha19d8c484e77bbe2 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 13: obs_gitlab_runner::handler::ObsJobHandler::run_monitor::{{closure}}::{{closure}}::h1dc676f2101718e9 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:436 [INFO] [stdout] 434 │ debug!("Completed with: {:?}", completion); [INFO] [stdout] 435 │ [INFO] [stdout]  436 >  let mut log_file = monitor.download_build_log().await?; [INFO] [stdout] 437 │ self.artifacts.insert( [INFO] [stdout] 438 │ args.build_log_out.clone(), [INFO] [stdout] 14:  as core::future::future::Future>::poll::h0c32199c08757977 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 15:  as core::future::future::Future>::poll::h2814a820e7265819 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tracing-0.1.30/src/instrument.rs:272 [INFO] [stdout] 270 │ let this = self.project(); [INFO] [stdout] 271 │ let _enter = this.span.enter(); [INFO] [stdout]  272 >  this.inner.poll(cx) [INFO] [stdout] 273 │ } [INFO] [stdout] 274 │ } [INFO] [stdout] 16: obs_gitlab_runner::handler::ObsJobHandler::run_monitor::{{closure}}::h8cd680d504b9276b [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:416 [INFO] [stdout] 414 │ } [INFO] [stdout] 415 │ [INFO] [stdout]  416 >  #[instrument(skip(self))] [INFO] [stdout] 417 │ async fn run_monitor(&mut self, args: MonitorAction) -> Result<()> { [INFO] [stdout] 418 │ let monitor = ObsMonitor::new( [INFO] [stdout] 17:  as core::future::future::Future>::poll::h0c66c3dc2c8b24b3 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 18: obs_gitlab_runner::handler::ObsJobHandler::command::{{closure}}::{{closure}}::hd7e3ff7fddd04723 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:567 [INFO] [stdout] 565 │ Action::Dput(args) => self.run_dput(args).await?, [INFO] [stdout] 566 │ Action::GenerateMonitor(args) => self.run_generate_monitor(args).await?, [INFO] [stdout]  567 >  Action::Monitor(args) => self.run_monitor(args).await?, [INFO] [stdout] 568 │ Action::DownloadBinaries(args) => self.run_download_binaries(args).await?, [INFO] [stdout] 569 │ Action::Prune(args) => self.run_prune(args).await?, [INFO] [stdout] 19:  as core::future::future::Future>::poll::hb593554c207b0bfd [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 20:  as core::future::future::Future>::poll::h31fbcd2a111ba92c [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tracing-0.1.30/src/instrument.rs:272 [INFO] [stdout] 270 │ let this = self.project(); [INFO] [stdout] 271 │ let _enter = this.span.enter(); [INFO] [stdout]  272 >  this.inner.poll(cx) [INFO] [stdout] 273 │ } [INFO] [stdout] 274 │ } [INFO] [stdout] 21: obs_gitlab_runner::handler::ObsJobHandler::command::{{closure}}::h097b85bb67758fbb [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:554 [INFO] [stdout] 552 │ } [INFO] [stdout] 553 │ [INFO] [stdout]  554 >  #[instrument(skip(self))] [INFO] [stdout] 555 │ async fn command(&mut self, cmdline: &str) -> Result<()> { [INFO] [stdout] 556 │ // TODO: inject user? [INFO] [stdout] 22:  as core::future::future::Future>::poll::h85aa22f367196203 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 23: ::step::{{closure}}::h6a315c9c83fb7c64 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:608 [INFO] [stdout] 606 │ async fn step(&mut self, script: &[String], _phase: Phase) -> JobResult { [INFO] [stdout] 607 │ for command in script { [INFO] [stdout]  608 >  if let Err(err) = self.command(command).await { [INFO] [stdout] 609 │ // Failed builds would already have information on them printed [INFO] [stdout] 610 │ // above, so don't print anything on them again. [INFO] [stdout] 24:  as core::future::future::Future>::poll::h690644c312777350 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 25:  as core::future::future::Future>::poll::h4c1db1c28f1d0856 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/future.rs:124 [INFO] [stdout] 26: gitlab_runner::run::run::{{closure}}::hfaf498fd530243ae [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/gitlab-runner-0.0.5/src/run.rs:36 [INFO] [stdout] 34 │ let script = response.step(Phase::Script).ok_or(())?; [INFO] [stdout] 35 │ // TODO handle timeout [INFO] [stdout]  36 >  let script_result = handler.step(&script.script, Phase::Script).await; [INFO] [stdout] 37 │ [INFO] [stdout] 38 │ if let Some(after) = response.step(Phase::AfterScript) { [INFO] [stdout] 27:  as core::future::future::Future>::poll::h2f277727bb02c74f [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 28:  as core::future::future::Future>::poll::hc26cd5b5ec5a5531 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tracing-0.1.30/src/instrument.rs:272 [INFO] [stdout] 270 │ let this = self.project(); [INFO] [stdout] 271 │ let _enter = this.span.enter(); [INFO] [stdout]  272 >  this.inner.poll(cx) [INFO] [stdout] 273 │ } [INFO] [stdout] 274 │ } [INFO] [stdout] 29:  as core::future::future::Future>::poll::h1a7bb08cc6b5cfb8 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tracing-0.1.30/src/instrument.rs:329 [INFO] [stdout] 327 │ let future = this.inner; [INFO] [stdout] 328 │ let _default = dispatcher::set_default(dispatcher); [INFO] [stdout]  329 >  future.poll(cx) [INFO] [stdout] 330 │ } [INFO] [stdout] 331 │ } [INFO] [stdout] 30:  as core::future::future::Future>::poll::hda0d90283490c93a [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/future.rs:124 [INFO] [stdout] 31: tokio::runtime::task::core::CoreStage::poll::{{closure}}::hd1598bf8cc957547 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/task/core.rs:161 [INFO] [stdout] 159 │ let future = unsafe { Pin::new_unchecked(future) }; [INFO] [stdout] 160 │ [INFO] [stdout]  161 >  future.poll(&mut cx) [INFO] [stdout] 162 │ }) [INFO] [stdout] 163 │ }; [INFO] [stdout] 32: tokio::loom::std::unsafe_cell::UnsafeCell::with_mut::hb6ff899641f7dfe6 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/loom/std/unsafe_cell.rs:14 [INFO] [stdout] 12 │ [INFO] [stdout] 13 │ pub(crate) fn with_mut(&self, f: impl FnOnce(*mut T) -> R) -> R { [INFO] [stdout]  14 >  f(self.0.get()) [INFO] [stdout] 15 │ } [INFO] [stdout] 16 │ } [INFO] [stdout] 33: tokio::runtime::task::core::CoreStage::poll::hf776e1c4665eea5c [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/task/core.rs:151 [INFO] [stdout] 149 │ pub(super) fn poll(&self, mut cx: Context<'_>) -> Poll { [INFO] [stdout] 150 │ let res = { [INFO] [stdout]  151 >  self.stage.with_mut(|ptr| { [INFO] [stdout] 152 │ // Safety: The caller ensures mutual exclusion to the field. [INFO] [stdout] 153 │ let future = match unsafe { &mut *ptr } { [INFO] [stdout] 34: tokio::runtime::task::harness::poll_future::{{closure}}::h0862f324521ad0b9 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/task/harness.rs:461 [INFO] [stdout] 459 │ } [INFO] [stdout] 460 │ let guard = Guard { core }; [INFO] [stdout]  461 >  let res = guard.core.poll(cx); [INFO] [stdout] 462 │ mem::forget(guard); [INFO] [stdout] 463 │ res [INFO] [stdout] 35:  as core::ops::function::FnOnce<()>>::call_once::h1584cd1644766ed3 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/panic/unwind_safe.rs:271 [INFO] [stdout] 36: std::panicking::try::do_call::h8853eea18f1e4eea [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panicking.rs:483 [INFO] [stdout] 37: __rust_try [INFO] [stdout] at : [INFO] [stdout] 38: std::panicking::try::h0b1d85887b7af6b1 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panicking.rs:447 [INFO] [stdout] 39: std::panic::catch_unwind::hc989e3cc71abdf6a [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panic.rs:137 [INFO] [stdout] 40: tokio::runtime::task::harness::poll_future::hc96903c1bffc47cd [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/task/harness.rs:449 [INFO] [stdout] 447 │ fn poll_future(core: &CoreStage, cx: Context<'_>) -> Poll<()> { [INFO] [stdout] 448 │ // Poll the future. [INFO] [stdout]  449 >  let output = panic::catch_unwind(panic::AssertUnwindSafe(|| { [INFO] [stdout] 450 │ struct Guard<'a, T: Future> { [INFO] [stdout] 451 │ core: &'a CoreStage, [INFO] [stdout] 41: tokio::runtime::task::harness::Harness::poll_inner::h05666da0a41a02a8 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/task/harness.rs:98 [INFO] [stdout] 96 │ let waker_ref = waker_ref::(self.header()); [INFO] [stdout] 97 │ let cx = Context::from_waker(&*waker_ref); [INFO] [stdout]  98 >  let res = poll_future(&self.core().stage, cx); [INFO] [stdout] 99 │ [INFO] [stdout] 100 │ if res == Poll::Ready(()) { [INFO] [stdout] 42: tokio::runtime::task::harness::Harness::poll::h2b3170d97302c3b2 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/task/harness.rs:53 [INFO] [stdout] 51 │ pub(super) fn poll(self) { [INFO] [stdout] 52 │ // We pass our ref-count to `poll_inner`. [INFO] [stdout]  53 >  match self.poll_inner() { [INFO] [stdout] 54 │ PollFuture::Notified => { [INFO] [stdout] 55 │ // The `poll_inner` call has given us two ref-counts back. [INFO] [stdout] 43: tokio::runtime::task::raw::poll::hc9c4a2c04094b9f9 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/task/raw.rs:113 [INFO] [stdout] 111 │ unsafe fn poll(ptr: NonNull
) { [INFO] [stdout] 112 │ let harness = Harness::::from_raw(ptr); [INFO] [stdout]  113 >  harness.poll(); [INFO] [stdout] 114 │ } [INFO] [stdout] 115 │ [INFO] [stdout] 44: tokio::runtime::task::raw::RawTask::poll::h577199a6baa7abb2 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/task/raw.rs:70 [INFO] [stdout] 68 │ pub(super) fn poll(self) { [INFO] [stdout] 69 │ let vtable = self.header().vtable; [INFO] [stdout]  70 >  unsafe { (vtable.poll)(self.ptr) } [INFO] [stdout] 71 │ } [INFO] [stdout] 72 │ [INFO] [stdout] 45: tokio::runtime::task::LocalNotified::run::h8a0c26bd74063749 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/task/mod.rs:343 [INFO] [stdout] 341 │ let raw = self.task.raw; [INFO] [stdout] 342 │ mem::forget(self); [INFO] [stdout]  343 >  raw.poll(); [INFO] [stdout] 344 │ } [INFO] [stdout] 345 │ } [INFO] [stdout] 46: tokio::runtime::basic_scheduler::CoreGuard::block_on::{{closure}}::{{closure}}::h519534bec4576e5e [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:557 [INFO] [stdout] 555 │ [INFO] [stdout] 556 │ let (c, _) = context.run_task(core, || { [INFO] [stdout]  557 >  task.run(); [INFO] [stdout] 558 │ }); [INFO] [stdout] 559 │ [INFO] [stdout] 47: tokio::coop::with_budget::{{closure}}::h0685771f205d9f47 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/coop.rs:102 [INFO] [stdout] 100 │ let _guard = ResetGuard { cell, prev }; [INFO] [stdout] 101 │ [INFO] [stdout]  102 >  f() [INFO] [stdout] 103 │ }) [INFO] [stdout] 104 │ } [INFO] [stdout] 48: std::thread::local::LocalKey::try_with::h7abbe10988de466a [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/thread/local.rs:446 [INFO] [stdout] 49: std::thread::local::LocalKey::with::h426b661fd715f278 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/thread/local.rs:422 [INFO] [stdout] 50: tokio::coop::with_budget::h0c65d075ec915ee0 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/coop.rs:95 [INFO] [stdout] 93 │ } [INFO] [stdout] 94 │ [INFO] [stdout]  95 >  CURRENT.with(move |cell| { [INFO] [stdout] 96 │ let prev = cell.get(); [INFO] [stdout] 97 │ [INFO] [stdout] 51: tokio::coop::budget::hb468962713e0efd6 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/coop.rs:72 [INFO] [stdout] 70 │ #[inline(always)] [INFO] [stdout] 71 │ pub(crate) fn budget(f: impl FnOnce() -> R) -> R { [INFO] [stdout]  72 >  with_budget(Budget::initial(), f) [INFO] [stdout] 73 │ } [INFO] [stdout] 74 │ [INFO] [stdout] 52: tokio::runtime::basic_scheduler::Context::run_task::{{closure}}::h3b5dda510110b411 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:309 [INFO] [stdout] 307 │ fn run_task(&self, mut core: Box, f: impl FnOnce() -> R) -> (Box, R) { [INFO] [stdout] 308 │ core.metrics.incr_poll_count(); [INFO] [stdout]  309 >  self.enter(core, || crate::coop::budget(f)) [INFO] [stdout] 310 │ } [INFO] [stdout] 311 │ [INFO] [stdout] 53: tokio::runtime::basic_scheduler::Context::enter::hfc60e1c9a7f238e8 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:374 [INFO] [stdout] 372 │ [INFO] [stdout] 373 │ // Execute the closure while tracking the execution budget [INFO] [stdout]  374 >  let ret = f(); [INFO] [stdout] 375 │ [INFO] [stdout] 376 │ // Take the scheduler core back [INFO] [stdout] 54: tokio::runtime::basic_scheduler::Context::run_task::h3586ca73a8ae5a7d [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:309 [INFO] [stdout] 307 │ fn run_task(&self, mut core: Box, f: impl FnOnce() -> R) -> (Box, R) { [INFO] [stdout] 308 │ core.metrics.incr_poll_count(); [INFO] [stdout]  309 >  self.enter(core, || crate::coop::budget(f)) [INFO] [stdout] 310 │ } [INFO] [stdout] 311 │ [INFO] [stdout] 55: tokio::runtime::basic_scheduler::CoreGuard::block_on::{{closure}}::h5b65bbb9b1b91471 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:556 [INFO] [stdout] 554 │ let task = context.spawner.shared.owned.assert_owner(task); [INFO] [stdout] 555 │ [INFO] [stdout]  556 >  let (c, _) = context.run_task(core, || { [INFO] [stdout] 557 │ task.run(); [INFO] [stdout] 558 │ }); [INFO] [stdout] 56: tokio::runtime::basic_scheduler::CoreGuard::enter::{{closure}}::h4531dc96313cbb38 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:582 [INFO] [stdout] 580 │ [INFO] [stdout] 581 │ // Call the closure and place `core` back [INFO] [stdout]  582 >  let (core, ret) = CURRENT.set(&self.context, || f(core, &self.context)); [INFO] [stdout] 583 │ [INFO] [stdout] 584 │ *self.context.core.borrow_mut() = Some(core); [INFO] [stdout] 57: tokio::macros::scoped_tls::ScopedKey::set::hfff667e5b48d6002 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/macros/scoped_tls.rs:61 [INFO] [stdout] 59 │ }; [INFO] [stdout] 60 │ [INFO] [stdout]  61 >  f() [INFO] [stdout] 62 │ } [INFO] [stdout] 63 │ [INFO] [stdout] 58: tokio::runtime::basic_scheduler::CoreGuard::enter::h0635d3bf1e089ef1 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:582 [INFO] [stdout] 580 │ [INFO] [stdout] 581 │ // Call the closure and place `core` back [INFO] [stdout]  582 >  let (core, ret) = CURRENT.set(&self.context, || f(core, &self.context)); [INFO] [stdout] 583 │ [INFO] [stdout] 584 │ *self.context.core.borrow_mut() = Some(core); [INFO] [stdout] 59: tokio::runtime::basic_scheduler::CoreGuard::block_on::h354cf198f5227a7d [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:506 [INFO] [stdout] 504 │ impl CoreGuard<'_> { [INFO] [stdout] 505 │ fn block_on(self, future: F) -> F::Output { [INFO] [stdout]  506 >  self.enter(|mut core, context| { [INFO] [stdout] 507 │ let _enter = crate::runtime::enter(false); [INFO] [stdout] 508 │ let waker = context.spawner.waker_ref(); [INFO] [stdout] 60: tokio::runtime::basic_scheduler::BasicScheduler::block_on::hc7771ae8e8b6652d [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:182 [INFO] [stdout] 180 │ loop { [INFO] [stdout] 181 │ if let Some(core) = self.take_core() { [INFO] [stdout]  182 >  return core.block_on(future); [INFO] [stdout] 183 │ } else { [INFO] [stdout] 184 │ let mut enter = crate::runtime::enter(false); [INFO] [stdout] 61: tokio::runtime::Runtime::block_on::h2ff4c1ee1cb1c467 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/mod.rs:475 [INFO] [stdout] 473 │ [INFO] [stdout] 474 │ match &self.kind { [INFO] [stdout]  475 >  Kind::CurrentThread(exec) => exec.block_on(future), [INFO] [stdout] 476 │ #[cfg(feature = "rt-multi-thread")] [INFO] [stdout] 477 │ Kind::ThreadPool(exec) => exec.block_on(future), [INFO] [stdout] 62: obs_gitlab_runner::handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2::he7a082e9a66deed8 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1661 [INFO] [stdout] 1659 │ } [INFO] [stdout] 1660 │ [INFO] [stdout]  1661 >  #[rstest] [INFO] [stdout] 1662 │ #[tokio::test] [INFO] [stdout] 1663 │ async fn test_handler_flow( [INFO] [stdout] 63: obs_gitlab_runner::handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2::{{closure}}::h4050e1cc3ee69778 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1661 [INFO] [stdout] 1659 │ } [INFO] [stdout] 1660 │ [INFO] [stdout]  1661 >  #[rstest] [INFO] [stdout] 1662 │ #[tokio::test] [INFO] [stdout] 1663 │ async fn test_handler_flow( [INFO] [stdout] 64: core::ops::function::FnOnce::call_once::ha39b005752ce8763 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/ops/function.rs:510 [INFO] [stdout] 65: core::ops::function::FnOnce::call_once::h2f93ceb28cb0c61c [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/ops/function.rs:510 [INFO] [stdout] 66: test::__rust_begin_short_backtrace::hdd721a0e8ee25a0e [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/test/src/lib.rs:637 [INFO] [stdout] 67: test::run_test::{{closure}}::h95fe25ef5b9cf584 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/test/src/lib.rs:628 [INFO] [stdout] 68: core::ops::function::FnOnce::call_once{{vtable.shim}}::he3685b1dad984a5a [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/ops/function.rs:510 [INFO] [stdout] 69:  as core::ops::function::FnOnce>::call_once::h301cae15faf66265 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/alloc/src/boxed.rs:2000 [INFO] [stdout] 70:  as core::ops::function::FnOnce<()>>::call_once::hb7be0f46463c4091 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/panic/unwind_safe.rs:271 [INFO] [stdout] 71: std::panicking::try::do_call::he80e1dd13c6c4b2c [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panicking.rs:483 [INFO] [stdout] 72: std::panicking::try::h88563fd695098a44 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panicking.rs:447 [INFO] [stdout] 73: std::panic::catch_unwind::h6a5b06a8b67964f8 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panic.rs:137 [INFO] [stdout] 74: test::run_test_in_process::h3bfa2ac051e33645 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/test/src/lib.rs:660 [INFO] [stdout]  ⋮ 15 frames hidden ⋮  [INFO] [stdout] [INFO] [stdout] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering. gitlab.output=true [INFO] [stdout] The application panicked (crashed). [INFO] [stdout] Message: assertion failed, expected Ok(..), got Err(Elapsed(())) [INFO] [stdout] Location: src/handler.rs:1483 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 8 frames hidden ⋮  [INFO] [stdout] 9: obs_gitlab_runner::handler::tests::test_monitoring::{{closure}}::h3131ee5050299ecf [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1483 [INFO] [stdout] 1481 │ } [INFO] [stdout] 1482 │ [INFO] [stdout]  1483 >  assert_ok!( [INFO] [stdout] 1484 │ tokio::time::timeout(OLD_STATUS_SLEEP_DURATION * 20, run_obs_handler(context)) [INFO] [stdout] 1485 │ .await [INFO] [stdout] 10:  as core::future::future::Future>::poll::h38fcc020fde9270d [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 11: obs_gitlab_runner::handler::tests::test_handler_flow::{{closure}}::{{closure}}::h10292d96db9cd3cc [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1695 [INFO] [stdout] 1693 │ download_binaries, [INFO] [stdout] 1694 │ ) [INFO] [stdout]  1695 >  .await; [INFO] [stdout] 1696 │ [INFO] [stdout] 1697 │ test_prune( [INFO] [stdout] 12:  as core::future::future::Future>::poll::h1875cffe4ac3ef7c [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 13:  as core::future::future::Future>::poll::head20c7bd5c14d93 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tracing-0.1.30/src/instrument.rs:329 [INFO] [stdout] 327 │ let future = this.inner; [INFO] [stdout] 328 │ let _default = dispatcher::set_default(dispatcher); [INFO] [stdout]  329 >  future.poll(cx) [INFO] [stdout] 330 │ } [INFO] [stdout] 331 │ } [INFO] [stdout] 14: obs_gitlab_runner::handler::tests::with_tracing::{{closure}}::h64109b3e2fbaa1dd [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:782 [INFO] [stdout] 780 │ .with(layer), [INFO] [stdout] 781 │ ) [INFO] [stdout]  782 >  .await [INFO] [stdout] 783 │ } [INFO] [stdout] 784 │ [INFO] [stdout] 15:  as core::future::future::Future>::poll::h83ffbd3634bec551 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 16: obs_gitlab_runner::handler::tests::test_handler_flow::{{closure}}::hd1529a18500b5514 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1705 [INFO] [stdout] 1703 │ .await; [INFO] [stdout] 1704 │ }) [INFO] [stdout]  1705 >  .await; [INFO] [stdout] 1706 │ } [INFO] [stdout] 1707 │ [INFO] [stdout] 17:  as core::future::future::Future>::poll::h2a3fc3d254a5fda9 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 18: obs_gitlab_runner::handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2::{{closure}}::h85ed714c265fb0dc [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1661 [INFO] [stdout] 1659 │ } [INFO] [stdout] 1660 │ [INFO] [stdout]  1661 >  #[rstest] [INFO] [stdout] 1662 │ #[tokio::test] [INFO] [stdout] 1663 │ async fn test_handler_flow( [INFO] [stdout] 19:  as core::future::future::Future>::poll::hf1b4cfde52c3e6d0 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91 [INFO] [stdout] 20:  as core::future::future::Future>::poll::heaeae5a4eadd0e7f [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/future.rs:124 [INFO] [stdout] 21: tokio::runtime::basic_scheduler::CoreGuard::block_on::{{closure}}::{{closure}}::{{closure}}::hed5c098857efa5cf [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:516 [INFO] [stdout] 514 │ if core.spawner.reset_woken() { [INFO] [stdout] 515 │ let (c, res) = context.enter(core, || { [INFO] [stdout]  516 >  crate::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stdout] 517 │ }); [INFO] [stdout] 518 │ [INFO] [stdout] 22: tokio::coop::with_budget::{{closure}}::h1a3d9ab91ecbdabf [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/coop.rs:102 [INFO] [stdout] 100 │ let _guard = ResetGuard { cell, prev }; [INFO] [stdout] 101 │ [INFO] [stdout]  102 >  f() [INFO] [stdout] 103 │ }) [INFO] [stdout] 104 │ } [INFO] [stdout] 23: std::thread::local::LocalKey::try_with::hb6649fd2a04d81b9 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/thread/local.rs:446 [INFO] [stdout] 24: std::thread::local::LocalKey::with::hc30e8c0c66c37a24 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/thread/local.rs:422 [INFO] [stdout] 25: tokio::coop::with_budget::h8505dfcd0a91b67d [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/coop.rs:95 [INFO] [stdout] 93 │ } [INFO] [stdout] 94 │ [INFO] [stdout]  95 >  CURRENT.with(move |cell| { [INFO] [stdout] 96 │ let prev = cell.get(); [INFO] [stdout] 97 │ [INFO] [stdout] 26: tokio::coop::budget::h71bdd3689c7357f3 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/coop.rs:72 [INFO] [stdout] 70 │ #[inline(always)] [INFO] [stdout] 71 │ pub(crate) fn budget(f: impl FnOnce() -> R) -> R { [INFO] [stdout]  72 >  with_budget(Budget::initial(), f) [INFO] [stdout] 73 │ } [INFO] [stdout] 74 │ [INFO] [stdout] 27: tokio::runtime::basic_scheduler::CoreGuard::block_on::{{closure}}::{{closure}}::hd95858c35939504b [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:516 [INFO] [stdout] 514 │ if core.spawner.reset_woken() { [INFO] [stdout] 515 │ let (c, res) = context.enter(core, || { [INFO] [stdout]  516 >  crate::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stdout] 517 │ }); [INFO] [stdout] 518 │ [INFO] [stdout] 28: tokio::runtime::basic_scheduler::Context::enter::hf144be18fb169f4e [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:374 [INFO] [stdout] 372 │ [INFO] [stdout] 373 │ // Execute the closure while tracking the execution budget [INFO] [stdout]  374 >  let ret = f(); [INFO] [stdout] 375 │ [INFO] [stdout] 376 │ // Take the scheduler core back [INFO] [stdout] 29: tokio::runtime::basic_scheduler::CoreGuard::block_on::{{closure}}::h5b65bbb9b1b91471 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:515 [INFO] [stdout] 513 │ 'outer: loop { [INFO] [stdout] 514 │ if core.spawner.reset_woken() { [INFO] [stdout]  515 >  let (c, res) = context.enter(core, || { [INFO] [stdout] 516 │ crate::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stdout] 517 │ }); [INFO] [stdout] 30: tokio::runtime::basic_scheduler::CoreGuard::enter::{{closure}}::h4531dc96313cbb38 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:582 [INFO] [stdout] 580 │ [INFO] [stdout] 581 │ // Call the closure and place `core` back [INFO] [stdout]  582 >  let (core, ret) = CURRENT.set(&self.context, || f(core, &self.context)); [INFO] [stdout] 583 │ [INFO] [stdout] 584 │ *self.context.core.borrow_mut() = Some(core); [INFO] [stdout] 31: tokio::macros::scoped_tls::ScopedKey::set::hfff667e5b48d6002 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/macros/scoped_tls.rs:61 [INFO] [stdout] 59 │ }; [INFO] [stdout] 60 │ [INFO] [stdout]  61 >  f() [INFO] [stdout] 62 │ } [INFO] [stdout] 63 │ [INFO] [stdout] 32: tokio::runtime::basic_scheduler::CoreGuard::enter::h0635d3bf1e089ef1 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:582 [INFO] [stdout] 580 │ [INFO] [stdout] 581 │ // Call the closure and place `core` back [INFO] [stdout]  582 >  let (core, ret) = CURRENT.set(&self.context, || f(core, &self.context)); [INFO] [stdout] 583 │ [INFO] [stdout] 584 │ *self.context.core.borrow_mut() = Some(core); [INFO] [stdout] 33: tokio::runtime::basic_scheduler::CoreGuard::block_on::h354cf198f5227a7d [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:506 [INFO] [stdout] 504 │ impl CoreGuard<'_> { [INFO] [stdout] 505 │ fn block_on(self, future: F) -> F::Output { [INFO] [stdout]  506 >  self.enter(|mut core, context| { [INFO] [stdout] 507 │ let _enter = crate::runtime::enter(false); [INFO] [stdout] 508 │ let waker = context.spawner.waker_ref(); [INFO] [stdout] 34: tokio::runtime::basic_scheduler::BasicScheduler::block_on::hc7771ae8e8b6652d [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/basic_scheduler.rs:182 [INFO] [stdout] 180 │ loop { [INFO] [stdout] 181 │ if let Some(core) = self.take_core() { [INFO] [stdout]  182 >  return core.block_on(future); [INFO] [stdout] 183 │ } else { [INFO] [stdout] 184 │ let mut enter = crate::runtime::enter(false); [INFO] [stdout] 35: tokio::runtime::Runtime::block_on::h2ff4c1ee1cb1c467 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.16.1/src/runtime/mod.rs:475 [INFO] [stdout] 473 │ [INFO] [stdout] 474 │ match &self.kind { [INFO] [stdout]  475 >  Kind::CurrentThread(exec) => exec.block_on(future), [INFO] [stdout] 476 │ #[cfg(feature = "rt-multi-thread")] [INFO] [stdout] 477 │ Kind::ThreadPool(exec) => exec.block_on(future), [INFO] [stdout] 36: obs_gitlab_runner::handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2::he7a082e9a66deed8 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1661 [INFO] [stdout] 1659 │ } [INFO] [stdout] 1660 │ [INFO] [stdout]  1661 >  #[rstest] [INFO] [stdout] 1662 │ #[tokio::test] [INFO] [stdout] 1663 │ async fn test_handler_flow( [INFO] [stdout] 37: obs_gitlab_runner::handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2::{{closure}}::h4050e1cc3ee69778 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1661 [INFO] [stdout] 1659 │ } [INFO] [stdout] 1660 │ [INFO] [stdout]  1661 >  #[rstest] [INFO] [stdout] 1662 │ #[tokio::test] [INFO] [stdout] 1663 │ async fn test_handler_flow( [INFO] [stdout] 38: core::ops::function::FnOnce::call_once::ha39b005752ce8763 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/ops/function.rs:510 [INFO] [stdout] 39: core::ops::function::FnOnce::call_once::h2f93ceb28cb0c61c [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/ops/function.rs:510 [INFO] [stdout] 40: test::__rust_begin_short_backtrace::hdd721a0e8ee25a0e [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/test/src/lib.rs:637 [INFO] [stdout] 41: test::run_test::{{closure}}::h95fe25ef5b9cf584 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/test/src/lib.rs:628 [INFO] [stdout] 42: core::ops::function::FnOnce::call_once{{vtable.shim}}::he3685b1dad984a5a [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/ops/function.rs:510 [INFO] [stdout] 43:  as core::ops::function::FnOnce>::call_once::h301cae15faf66265 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/alloc/src/boxed.rs:2000 [INFO] [stdout] 44:  as core::ops::function::FnOnce<()>>::call_once::hb7be0f46463c4091 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/panic/unwind_safe.rs:271 [INFO] [stdout] 45: std::panicking::try::do_call::he80e1dd13c6c4b2c [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panicking.rs:483 [INFO] [stdout] 46: std::panicking::try::h88563fd695098a44 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panicking.rs:447 [INFO] [stdout] 47: std::panic::catch_unwind::h6a5b06a8b67964f8 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panic.rs:137 [INFO] [stdout] 48: test::run_test_in_process::h3bfa2ac051e33645 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/test/src/lib.rs:660 [INFO] [stdout]  ⋮ 15 frames hidden ⋮  [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::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2 [INFO] [stdout] [INFO] [stdout] test result: FAILED. 111 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 60.84s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--bin obs-gitlab-runner` [INFO] running `Command { std: "docker" "inspect" "19084db352649b847bb731d99431de4793098486a21cc2730d6a07897c4d1034", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "19084db352649b847bb731d99431de4793098486a21cc2730d6a07897c4d1034", kill_on_drop: false }` [INFO] [stdout] 19084db352649b847bb731d99431de4793098486a21cc2730d6a07897c4d1034