[INFO] fetching crate sbpf-runtime 0.3.1...
[INFO] testing sbpf-runtime-0.3.1 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate sbpf-runtime 0.3.1 into /workspace/builds/worker-5-tc2/source
[INFO] started tweaking crates.io crate sbpf-runtime 0.3.1
[INFO] finished tweaking crates.io crate sbpf-runtime 0.3.1
[INFO] tweaked toml for crates.io crate sbpf-runtime 0.3.1 written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate sbpf-runtime 0.3.1 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate sbpf-runtime 0.3.1 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,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" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] a3b1dfa9907c7bd89a949d1f33a0d7090cf577260fadddbb96421633f28ff2bb
[INFO] running `Command { std: "docker" "start" "a3b1dfa9907c7bd89a949d1f33a0d7090cf577260fadddbb96421633f28ff2bb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a3b1dfa9907c7bd89a949d1f33a0d7090cf577260fadddbb96421633f28ff2bb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "a3b1dfa9907c7bd89a949d1f33a0d7090cf577260fadddbb96421633f28ff2bb" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a3b1dfa9907c7bd89a949d1f33a0d7090cf577260fadddbb96421633f28ff2bb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "a3b1dfa9907c7bd89a949d1f33a0d7090cf577260fadddbb96421633f28ff2bb" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.107
[INFO] [stderr]    Compiling unicode-ident v1.0.24
[INFO] [stderr]    Compiling typenum v1.20.1
[INFO] [stderr]    Compiling version_check v0.9.5
[INFO] [stderr]    Compiling thiserror v2.0.20
[INFO] [stderr]    Compiling strsim v0.11.1
[INFO] [stderr]    Compiling quote v1.0.47
[INFO] [stderr]    Compiling ident_case v1.0.1
[INFO] [stderr]    Compiling semver v1.0.28
[INFO] [stderr]    Compiling five8_core v1.0.0
[INFO] [stderr]    Compiling pastey v0.2.3
[INFO] [stderr]    Compiling rustc_version v0.4.1
[INFO] [stderr]    Compiling cpufeatures v0.2.17
[INFO] [stderr]    Compiling five8 v1.0.0
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling solana-program-error v3.0.1
[INFO] [stderr]    Compiling subtle v2.6.1
[INFO] [stderr]    Compiling zeroize v1.9.0
[INFO] [stderr]    Compiling curve25519-dalek v4.1.3
[INFO] [stderr]    Compiling five8_const v1.0.0
[INFO] [stderr]    Compiling solana-sanitize v3.0.1
[INFO] [stderr]    Compiling sha2-const-stable v0.1.0
[INFO] [stderr]    Compiling solana-atomic-u64 v3.0.1
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling bs58 v0.5.1
[INFO] [stderr]    Compiling crc32fast v1.5.0
[INFO] [stderr]    Compiling cmov v0.5.4
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling shlex v2.0.1
[INFO] [stderr]    Compiling ctutils v0.4.2
[INFO] [stderr]    Compiling wincode v0.6.0
[INFO] [stderr]    Compiling find-msvc-tools v0.1.10
[INFO] [stderr]    Compiling const-oid v0.10.2
[INFO] [stderr]    Compiling adler2 v2.0.1
[INFO] [stderr]    Compiling hybrid-array v0.4.14
[INFO] [stderr]    Compiling simd-adler32 v0.3.10
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling cc v1.4.2
[INFO] [stderr]    Compiling block-buffer v0.12.1
[INFO] [stderr]    Compiling crypto-common v0.2.2
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling solana-instruction-error v2.5.0
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling object v0.39.1
[INFO] [stderr]    Compiling twox-hash v2.1.3
[INFO] [stderr]    Compiling sbpf-syscall-map v0.3.1
[INFO] [stderr]    Compiling ruzstd v0.8.3
[INFO] [stderr]    Compiling flate2 v1.1.9
[INFO] [stderr]    Compiling cpufeatures v0.3.0
[INFO] [stderr]    Compiling solana-program-memory v3.1.0
[INFO] [stderr]    Compiling keccak v0.2.1
[INFO] [stderr]    Compiling solana-msg v3.1.0
[INFO] [stderr]    Compiling arrayvec v0.7.8
[INFO] [stderr]    Compiling digest v0.11.3
[INFO] [stderr]    Compiling constant_time_eq v0.4.2
[INFO] [stderr]    Compiling arrayref v0.3.9
[INFO] [stderr]    Compiling sponge-cursor v0.1.0
[INFO] [stderr]    Compiling sha3 v0.12.0
[INFO] [stderr]    Compiling blake3 v1.8.6
[INFO] [stderr]    Compiling sha2 v0.11.0
[INFO] [stderr]    Compiling base64 v0.22.1
[INFO] [stderr]    Compiling thiserror-impl v2.0.20
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling sbpf-common-tablegen v0.3.1
[INFO] [stderr]    Compiling darling_core v0.23.0
[INFO] [stderr]    Compiling curve25519-dalek-derive v0.1.1
[INFO] [stderr]    Compiling solana-sdk-macro v3.0.1
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling num-derive v0.4.2
[INFO] [stderr]    Compiling sbpf-common-derive v0.3.1
[INFO] [stderr]    Compiling either v1.17.0
[INFO] [stderr]    Compiling sbpf-common v0.3.1
[INFO] [stderr]    Compiling darling_macro v0.23.0
[INFO] [stderr]    Compiling darling v0.23.0
[INFO] [stderr]    Compiling wincode-derive v0.5.0
[INFO] [stderr]    Compiling sbpf-disassembler v0.3.1
[INFO] [stderr]    Compiling sbpf-vm v0.3.1
[INFO] [stderr]    Compiling solana-hash v4.6.0
[INFO] [stderr]    Compiling solana-sha256-hasher v3.1.0
[INFO] [stderr]    Compiling solana-address v2.7.0
[INFO] [stderr]    Compiling solana-sdk-ids v3.1.0
[INFO] [stderr]    Compiling solana-get-sysvar v1.0.0
[INFO] [stderr]    Compiling solana-pubkey v4.3.0
[INFO] [stderr]    Compiling solana-account-info v3.1.1
[INFO] [stderr]    Compiling solana-instruction v3.5.0
[INFO] [stderr]    Compiling solana-sysvar-id v3.1.0
[INFO] [stderr]    Compiling solana-system-interface v3.3.0
[INFO] [stderr]    Compiling solana-clock v3.2.0
[INFO] [stderr]    Compiling solana-last-restart-slot v3.2.0
[INFO] [stderr]    Compiling solana-epoch-schedule v3.3.0
[INFO] [stderr]    Compiling solana-slot-hashes v3.2.0
[INFO] [stderr]    Compiling solana-rent v4.4.0
[INFO] [stderr]    Compiling solana-epoch-rewards v3.2.0
[INFO] [stderr]    Compiling solana-stake-history v1.1.0
[INFO] [stderr]    Compiling solana-account v4.5.0
[INFO] [stderr]    Compiling sbpf-runtime v0.3.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 01s
[INFO] running `Command { std: "docker" "inspect" "a3b1dfa9907c7bd89a949d1f33a0d7090cf577260fadddbb96421633f28ff2bb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "a3b1dfa9907c7bd89a949d1f33a0d7090cf577260fadddbb96421633f28ff2bb" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling zeroize_derive v1.5.0
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling subtle v2.6.1
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling const-oid v0.9.6
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling bytemuck_derive v1.12.0
[INFO] [stderr]    Compiling curve25519-dalek v4.1.3
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling zerocopy v0.8.56
[INFO] [stderr]    Compiling either v1.17.0
[INFO] [stderr]    Compiling bincode v1.3.3
[INFO] [stderr]    Compiling log v0.4.33
[INFO] [stderr]    Compiling zeroize v1.9.0
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling feature-probe v0.1.1
[INFO] [stderr]    Compiling bv v0.11.1
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling paste v1.0.15
[INFO] [stderr]    Compiling ahash v0.8.12
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stderr]    Compiling solana-instruction-error v2.5.0
[INFO] [stderr]    Compiling num-integer v0.1.46
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling solana-fee-calculator v3.3.0
[INFO] [stderr]    Compiling bytemuck v1.25.2
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling solana-hash v4.6.0
[INFO] [stderr]    Compiling num-bigint v0.4.8
[INFO] [stderr]    Compiling solana-define-syscall v4.0.1
[INFO] [stderr]    Compiling radium v0.7.0
[INFO] [stderr]    Compiling lazy_static v1.5.0
[INFO] [stderr]    Compiling solana-sha256-hasher v3.1.0
[INFO] [stderr]    Compiling tap v1.0.1
[INFO] [stderr]    Compiling wyz v0.5.1
[INFO] [stderr]    Compiling serde_bytes v0.11.19
[INFO] [stderr]    Compiling funty v2.0.0
[INFO] [stderr]    Compiling enum-ordinalize-derive v4.4.2
[INFO] [stderr]    Compiling qualifier_attr v0.2.2
[INFO] [stderr]    Compiling solana-address v2.7.0
[INFO] [stderr]    Compiling solana-pubkey v4.3.0
[INFO] [stderr]    Compiling solana-sdk-ids v3.1.0
[INFO] [stderr]    Compiling solana-instruction v3.5.0
[INFO] [stderr]    Compiling solana-get-sysvar v1.0.0
[INFO] [stderr]    Compiling solana-account-info v3.1.1
[INFO] [stderr]    Compiling bitvec v1.1.1
[INFO] [stderr]    Compiling solana-sysvar-id v3.1.0
[INFO] [stderr]    Compiling solana-program-entrypoint v3.1.1
[INFO] [stderr]    Compiling solana-clock v3.2.0
[INFO] [stderr]    Compiling solana-last-restart-slot v3.2.0
[INFO] [stderr]    Compiling solana-slot-history v3.2.0
[INFO] [stderr]    Compiling solana-stake-history v1.1.0
[INFO] [stderr]    Compiling solana-epoch-schedule v3.3.0
[INFO] [stderr]    Compiling solana-epoch-rewards v3.2.0
[INFO] [stderr]    Compiling solana-slot-hashes v3.2.0
[INFO] [stderr]    Compiling solana-rent v4.4.0
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling crunchy v0.2.4
[INFO] [stderr]    Compiling solana-sysvar v4.2.0
[INFO] [stderr]    Compiling enum-ordinalize v4.4.2
[INFO] [stderr]    Compiling rand_xorshift v0.3.0
[INFO] [stderr]    Compiling num-bigint v0.2.6
[INFO] [stderr]    Compiling ark-serialize-derive v0.5.0
[INFO] [stderr]    Compiling typenum v1.20.1
[INFO] [stderr]    Compiling educe v0.6.0
[INFO] [stderr]    Compiling solana-account v4.5.0
[INFO] [stderr]    Compiling ark-ff-macros v0.5.0
[INFO] [stderr]    Compiling solana-transaction-error v3.4.0
[INFO] [stderr]    Compiling der v0.7.10
[INFO] [stderr]    Compiling itertools v0.13.0
[INFO] [stderr]    Compiling num-rational v0.2.4
[INFO] [stderr]    Compiling num-complex v0.2.4
[INFO] [stderr]    Compiling ark-ff-asm v0.5.0
[INFO] [stderr]    Compiling void v1.0.2
[INFO] [stderr]    Compiling allocator-api2 v0.2.21
[INFO] [stderr]    Compiling byteorder v1.5.0
[INFO] [stderr]    Compiling hashbrown v0.15.5
[INFO] [stderr]    Compiling unreachable v1.0.0
[INFO] [stderr]    Compiling ff v0.13.1
[INFO] [stderr]    Compiling digest v0.9.0
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling itertools v0.10.5
[INFO] [stderr]    Compiling ascii v0.9.3
[INFO] [stderr]    Compiling bitflags v2.13.1
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling combine v3.8.1
[INFO] [stderr]    Compiling libsecp256k1-core v0.3.0
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling rand v0.8.7
[INFO] [stderr]    Compiling solana-system-interface v3.3.0
[INFO] [stderr]    Compiling hashbrown v0.13.2
[INFO] [stderr]    Compiling ark-std v0.5.0
[INFO] [stderr]    Compiling group v0.13.0
[INFO] [stderr]    Compiling ark-serialize v0.5.0
[INFO] [stderr]    Compiling ark-std v0.4.0
[INFO] [stderr]    Compiling hash32 v0.3.1
[INFO] [stderr]    Compiling spki v0.7.3
[INFO] [stderr]    Compiling solana-message v4.5.0
[INFO] [stderr]    Compiling solana-serialize-utils v3.1.2
[INFO] [stderr]    Compiling num-iter v0.1.46
[INFO] [stderr]    Compiling solana-signature v3.5.1
[INFO] [stderr]    Compiling ark-ff v0.5.0
[INFO] [stderr]    Compiling enum-iterator-derive v1.5.0
[INFO] [stderr]    Compiling rustc-demangle v0.1.28
[INFO] [stderr]    Compiling base16ct v0.2.0
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling sec1 v0.7.3
[INFO] [stderr]    Compiling solana-transaction v4.2.0
[INFO] [stderr]    Compiling ark-serialize-derive v0.4.2
[INFO] [stderr]    Compiling derivative v2.2.0
[INFO] [stderr]    Compiling ark-ff-macros v0.4.2
[INFO] [stderr]    Compiling ark-ff-asm v0.4.2
[INFO] [stderr]    Compiling ark-serialize v0.4.2
[INFO] [stderr]    Compiling enum-iterator v2.3.0
[INFO] [stderr]    Compiling num v0.2.1
[INFO] [stderr]    Compiling pkcs8 v0.10.2
[INFO] [stderr]    Compiling solana-instructions-sysvar v3.0.1
[INFO] [stderr]    Compiling rand v0.9.5
[INFO] [stderr]    Compiling solana-cpi v3.1.0
[INFO] [stderr]    Compiling blst v0.3.17
[INFO] [stderr]    Compiling hmac v0.12.1
[INFO] [stderr]    Compiling crypto-bigint v0.5.5
[INFO] [stderr]    Compiling solana-precompile-error v3.0.0
[INFO] [stderr]    Compiling num_cpus v1.17.0
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling eager v0.1.0
[INFO] [stderr]    Compiling solana-svm-type-overrides v4.1.2
[INFO] [stderr]    Compiling threadpool v1.8.1
[INFO] [stderr]    Compiling solana-svm-callback v4.1.2
[INFO] [stderr]    Compiling ark-ff v0.4.2
[INFO] [stderr]    Compiling rfc6979 v0.4.0
[INFO] [stderr]    Compiling solana-stake-interface v3.1.0
[INFO] [stderr]    Compiling solana-svm-timings v4.1.2
[INFO] [stderr]    Compiling elliptic-curve v0.13.8
[INFO] [stderr]    Compiling solana-sbpf v0.21.0
[INFO] [stderr]    Compiling percentage v0.1.0
[INFO] [stderr]    Compiling solana-svm-transaction v4.1.2
[INFO] [stderr]    Compiling libsecp256k1-gen-ecmult v0.3.0
[INFO] [stderr]    Compiling libsecp256k1-gen-genmult v0.3.0
[INFO] [stderr]    Compiling solana-loader-v3-interface v7.0.0
[INFO] [stderr]    Compiling solana-stable-layout v3.0.1
[INFO] [stderr]    Compiling signature v2.2.0
[INFO] [stderr]    Compiling aho-corasick v1.1.5
[INFO] [stderr]    Compiling solana-svm-log-collector v4.1.2
[INFO] [stderr]    Compiling itertools v0.14.0
[INFO] [stderr]    Compiling solana-fee-structure v3.0.0
[INFO] [stderr]    Compiling regex-syntax v0.8.11
[INFO] [stderr]    Compiling solana-svm-feature-set v4.1.2
[INFO] [stderr]    Compiling equivalent v1.0.2
[INFO] [stderr]    Compiling winnow v1.0.4
[INFO] [stderr]    Compiling solana-svm-measure v4.1.2
[INFO] [stderr]    Compiling hashbrown v0.17.1
[INFO] [stderr]    Compiling indexmap v2.14.0
[INFO] [stderr]    Compiling toml_parser v1.1.3+spec-1.1.0
[INFO] [stderr]    Compiling regex-automata v0.4.18
[INFO] [stderr]    Compiling ark-poly v0.5.0
[INFO] [stderr]    Compiling ecdsa v0.16.9
[INFO] [stderr]    Compiling solana-transaction-context v4.1.2
[INFO] [stderr]    Compiling libsecp256k1 v0.7.2
[INFO] [stderr]    Compiling pairing v0.23.0
[INFO] [stderr]    Compiling block-buffer v0.9.0
[INFO] [stderr]    Compiling toml_datetime v1.1.1+spec-1.1.0
[INFO] [stderr]    Compiling opaque-debug v0.3.1
[INFO] [stderr]    Compiling keccak v0.1.6
[INFO] [stderr]    Compiling solana-program-runtime v4.1.2
[INFO] [stderr]    Compiling ark-ec v0.5.0
[INFO] [stderr]    Compiling byte-slice-cast v1.2.3
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling sha3 v0.10.9
[INFO] [stderr]    Compiling anstyle-parse v1.0.0
[INFO] [stderr]    Compiling blstrs v0.7.1
[INFO] [stderr]    Compiling toml_edit v0.25.13+spec-1.1.0
[INFO] [stderr]    Compiling sha2 v0.9.9
[INFO] [stderr]    Compiling k256 v0.13.4
[INFO] [stderr]    Compiling ark-poly v0.4.2
[INFO] [stderr]    Compiling regex v1.13.1
[INFO] [stderr]    Compiling ark-bn254 v0.5.0
[INFO] [stderr]    Compiling ark-ec v0.4.2
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling signal-hook v0.3.18
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.2
[INFO] [stderr]    Compiling solana-hash-512 v1.2.0
[INFO] [stderr]    Compiling jiff-core v0.1.0
[INFO] [stderr]    Compiling anstyle-query v1.1.5
[INFO] [stderr]    Compiling rustversion v1.0.23
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling anstream v1.0.0
[INFO] [stderr]    Compiling light-poseidon v0.4.0
[INFO] [stderr]    Compiling solana-bn254 v3.2.1
[INFO] [stderr]    Compiling jiff v0.2.35
[INFO] [stderr]    Compiling solana-sha512-hasher v1.0.1
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling env_filter v2.0.0
[INFO] [stderr]    Compiling solana-secp256k1-recover v3.3.0
[INFO] [stderr]    Compiling proc-macro-crate v3.5.0
[INFO] [stderr]    Compiling solana-bls12-381-syscall v0.1.1
[INFO] [stderr]    Compiling solana-keccak-hasher v3.1.0
[INFO] [stderr]    Compiling ark-bn254 v0.4.0
[INFO] [stderr]    Compiling solana-blake3-hasher v3.1.0
[INFO] [stderr]    Compiling solana-packet v4.3.0
[INFO] [stderr]    Compiling solana-big-mod-exp v3.0.0
[INFO] [stderr]    Compiling solana-nonce v3.3.0
[INFO] [stderr]    Compiling solana-address v1.1.0
[INFO] [stderr]    Compiling solana-curve25519 v4.0.1
[INFO] [stderr]    Compiling solana-bincode v3.1.0
[INFO] [stderr]    Compiling solana-pubkey v3.0.0
[INFO] [stderr]    Compiling num_enum_derive v0.7.6
[INFO] [stderr]    Compiling light-poseidon v0.2.0
[INFO] [stderr]    Compiling solana-nonce-account v4.3.0
[INFO] [stderr]    Compiling sbpf-common v0.3.1
[INFO] [stderr]    Compiling solana-system-program v4.1.2
[INFO] [stderr]    Compiling solana-compute-budget v4.1.2
[INFO] [stderr]    Compiling mollusk-svm-result v0.14.0
[INFO] [stderr]    Compiling mollusk-svm-error v0.14.0
[INFO] [stderr]    Compiling solana-program-pack v3.1.0
[INFO] [stderr]    Compiling solana-program-option v3.1.0
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling object v0.39.1
[INFO] [stderr]    Compiling num_enum v0.7.6
[INFO] [stderr]    Compiling spl-token-interface v2.0.0
[INFO] [stderr]    Compiling spl-associated-token-account-interface v2.0.0
[INFO] [stderr]    Compiling solana-poseidon v4.0.0
[INFO] [stderr]    Compiling sbpf-vm v0.3.1
[INFO] [stderr]    Compiling env_logger v0.11.11
[INFO] [stderr]    Compiling solana-logger v3.0.0
[INFO] [stderr]    Compiling solana-syscalls v4.1.2
[INFO] [stderr]    Compiling solana-bpf-loader-program v4.1.2
[INFO] [stderr]    Compiling mollusk-svm v0.14.0
[INFO] [stderr]    Compiling mollusk-svm-programs-token-2022 v0.14.0
[INFO] [stderr]    Compiling mollusk-svm-programs-token v0.14.0
[INFO] [stderr]    Compiling sbpf-disassembler v0.3.1
[INFO] [stderr]    Compiling sbpf-runtime v0.3.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2m 39s
[INFO] running `Command { std: "docker" "inspect" "a3b1dfa9907c7bd89a949d1f33a0d7090cf577260fadddbb96421633f28ff2bb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "a3b1dfa9907c7bd89a949d1f33a0d7090cf577260fadddbb96421633f28ff2bb" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.64s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/sbpf-runtime/3e3cea082e41994f/out/sbpf_runtime-3e3cea082e41994f)
[INFO] [stdout] 
[INFO] [stdout] running 146 tests
[INFO] [stdout] test cpi::builtins::system::tests::test_assign_missing_signer ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_allocate_already_in_use ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_assign_already_owned ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_allocate ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_allocate_missing_signer ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_allocate_with_seed ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_create_account ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_create_account_already_in_use ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_assign_with_seed ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_invalid_instruction_data ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_create_account_with_seed ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_create_account_with_seed_mismatch ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_insufficient_compute_units ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_transfer ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_transfer_from_carries_data ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_nonce_instruction_is_accepted_as_noop ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_allocate_exceeds_max_length ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_not_enough_accounts ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_assign ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_transfer_from_missing_account ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_transfer_insufficient_funds ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_transfer_missing_signer ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_transfer_signer_via_derived_signers ... ok
[INFO] [stdout] test cpi::builtins::tests::is_builtin_rejects_other_programs ... ok
[INFO] [stdout] test cpi::tests::execute_cpi_depth_exceeded ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_transfer_to_missing_account ... ok
[INFO] [stdout] test cpi::tests::execute_cpi_dispatches_system_transfer_builtin ... ok
[INFO] [stdout] test cpi::tests::execute_cpi_program_not_found ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_transfer_with_seed ... ok
[INFO] [stdout] test cpi::builtins::tests::is_builtin_recognizes_system_program ... ok
[INFO] [stdout] test cpi::builtins::system::tests::test_transfer_with_seed_missing_signer ... ok
[INFO] [stdout] test cpi::builtins::tests::execute_builtin_rejects_unsupported_program ... ok
[INFO] [stdout] test cpi::validate::tests::check_account_change_executable_data_rejected ... ok
[INFO] [stdout] test cpi::validate::tests::check_account_change_non_owner_debits_lamports ... ok
[INFO] [stdout] test cpi::validate::tests::check_account_change_owner_modifies_data ... ok
[INFO] [stdout] test cpi::validate::tests::check_privileges_missing_account ... ok
[INFO] [stdout] test cpi::validate::tests::check_privileges_ok ... ok
[INFO] [stdout] test cpi::validate::tests::check_privileges_signer_escalation ... ok
[INFO] [stdout] test cpi::validate::tests::check_account_change_no_modifications ... ok
[INFO] [stdout] test cpi::validate::tests::check_account_change_non_owner_changes_owner ... ok
[INFO] [stdout] test cpi::validate::tests::check_account_change_non_owner_credits_lamports ... ok
[INFO] [stdout] test cpi::validate::tests::check_privileges_signer_via_pda ... ok
[INFO] [stdout] test cpi::validate::tests::check_account_change_non_owner_modifies_data ... ok
[INFO] [stdout] test cpi::validate::tests::check_privileges_writable_escalation ... ok
[INFO] [stdout] test runtime::tests::add_program_accepts_path_and_bytes ... ok
[INFO] [stdout] test cpi::validate::tests::check_account_change_owner_changes_owner ... ok
[INFO] [stdout] test cpi::validate::tests::check_account_change_owner_debits_lamports ... ok
[INFO] [stdout] test runtime::tests::config_and_sysvar_accessors ... ok
[INFO] [stdout] test runtime::tests::new_invalid_elf_errors ... ok
[INFO] [stdout] test runtime::tests::elf_source_from_str ... ok
[INFO] [stdout] test runtime::tests::elf_source_from_bytes_slice ... ok
[INFO] [stdout] test runtime::tests::getters_before_prepare_are_defaults ... ok
[INFO] [stdout] test runtime::tests::new_bad_path_errors ... ok
[INFO] [stdout] test runtime::tests::new_from_path ... ok
[INFO] [stdout] test runtime::tests::elf_source_from_vec ... ok
[INFO] [stdout] test serialize::tests::check_instruction_data_offset ... ok
[INFO] [stdout] test runtime::tests::run_returns_ok_with_nonzero_exit_and_logs ... ok
[INFO] [stdout] test serialize::tests::check_instruction_data_offset_no_data ... ok
[INFO] [stdout] test serialize::tests::e2e_accounts_preserved ... ok
[INFO] [stdout] test runtime::tests::set_register_after_prepare_and_out_of_range ... ok
[INFO] [stdout] test serialize::tests::missing_account_errors ... ok
[INFO] [stdout] test serialize::tests::read_only_account_not_updated ... ok
[INFO] [stdout] test runtime::tests::step_before_prepare_errors ... ok
[INFO] [stdout] test runtime::tests::new_from_bytes ... ok
[INFO] [stdout] test serialize::tests::non_owner_data_change_rejected ... ok
[INFO] [stdout] test syscalls::abort::tests::test_abort_returns_abort_error ... ok
[INFO] [stdout] test syscalls::abort::tests::test_sol_panic_oob_file_ptr_returns_error ... ok
[INFO] [stdout] test runtime::tests::prepare_initializes_vm_state ... ok
[INFO] [stdout] test serialize::tests::non_owner_lamport_debit_rejected ... ok
[INFO] [stdout] test syscalls::abort::tests::test_sol_panic_returns_abort ... ok
[INFO] [stdout] test runtime::tests::set_register_before_prepare_errors ... ok
[INFO] [stdout] test syscalls::abort::tests::test_sol_panic_zero_length_file ... ok
[INFO] [stdout] test syscalls::crypto::tests::test_all_three_hashes_differ_on_same_input ... ok
[INFO] [stdout] test syscalls::crypto::tests::test_sha256_compute_exhausted ... ok
[INFO] [stdout] test syscalls::crypto::tests::test_sha256_empty_slices ... ok
[INFO] [stdout] test syscalls::crypto::tests::test_blake3_known_input ... ok
[INFO] [stdout] test syscalls::crypto::tests::test_sha256_known_input ... ok
[INFO] [stdout] test syscalls::crypto::tests::test_keccak256_empty_slices ... ok
[INFO] [stdout] test syscalls::crypto::tests::test_keccak256_known_input ... ok
[INFO] [stdout] test runtime::tests::step_drives_program_to_clean_halt ... ok
[INFO] [stdout] test syscalls::crypto::tests::test_sha256_too_many_slices ... ok
[INFO] [stdout] test syscalls::log::tests::test_sol_log_64_format ... ok
[INFO] [stdout] test syscalls::crypto::tests::test_blake3_empty_slices ... ok
[INFO] [stdout] test syscalls::log::tests::test_sol_log_compute_exhausted ... ok
[INFO] [stdout] test syscalls::log::tests::test_sol_log_64_compute_exhausted ... ok
[INFO] [stdout] test syscalls::crypto::tests::test_keccak256_differs_from_sha256 ... ok
[INFO] [stdout] test syscalls::crypto::tests::test_sha256_multiple_slices_concatenated ... ok
[INFO] [stdout] test syscalls::log::tests::test_sol_log_compute_units_logs_remaining ... ok
[INFO] [stdout] test syscalls::log::tests::test_sol_log_empty_message ... ok
[INFO] [stdout] test syscalls::log::tests::test_sol_log_invalid_utf8_is_lossy ... ok
[INFO] [stdout] test syscalls::log::tests::test_sol_log_64_zeros ... ok
[INFO] [stdout] test syscalls::log::tests::test_sol_log_basic ... ok
[INFO] [stdout] test syscalls::memory::tests::test_memcmp_equal ... ok
[INFO] [stdout] test syscalls::memory::tests::test_memcmp_greater_than ... ok
[INFO] [stdout] test syscalls::memory::tests::test_memcpy_adjacent_not_overlapping ... ok
[INFO] [stdout] test syscalls::memory::tests::test_memcmp_compute_exhausted ... ok
[INFO] [stdout] test syscalls::log::tests::test_sol_log_pubkey_formats_base58 ... ok
[INFO] [stdout] test syscalls::log::tests::test_sol_log_pubkey_nonzero ... ok
[INFO] [stdout] test syscalls::memory::tests::test_memcpy_basic ... ok
[INFO] [stdout] test syscalls::memory::tests::test_memcpy_overlapping_returns_error ... ok
[INFO] [stdout] test syscalls::memory::tests::test_memcpy_oob_dst ... ok
[INFO] [stdout] test syscalls::memory::tests::test_memmove_basic ... ok
[INFO] [stdout] test syscalls::log::tests::test_sol_remaining_compute_units_exhausted ... ok
[INFO] [stdout] test syscalls::memory::tests::test_memcpy_compute_exhausted ... ok
[INFO] [stdout] test syscalls::memory::tests::test_memcmp_less_than ... ok
[INFO] [stdout] test syscalls::memory::tests::test_memmove_compute_exhausted ... ok
[INFO] [stdout] test syscalls::memory::tests::test_memmove_overlapping_allowed ... ok
[INFO] [stdout] test syscalls::memory::tests::test_memset_zero_fill ... ok
[INFO] [stdout] test syscalls::pda::tests::test_create_program_address_compute_exhausted ... ok
[INFO] [stdout] test syscalls::pda::tests::test_create_program_address_seed_too_long ... ok
[INFO] [stdout] test syscalls::memory::tests::test_memcpy_oob_src ... ok
[INFO] [stdout] test syscalls::memory::tests::test_memcpy_zero_length ... ok
[INFO] [stdout] test syscalls::pda::tests::test_create_program_address_too_many_seeds ... ok
[INFO] [stdout] test syscalls::pda::tests::test_create_program_address_valid_pda ... ok
[INFO] [stdout] test syscalls::return_data::tests::test_get_return_data_compute_exhausted ... ok
[INFO] [stdout] test syscalls::return_data::tests::test_get_return_data_full ... ok
[INFO] [stdout] test syscalls::pda::tests::test_try_find_program_address_matches_library ... ok
[INFO] [stdout] test syscalls::return_data::tests::test_get_return_data_truncated_buf ... ok
[INFO] [stdout] test syscalls::return_data::tests::test_get_return_data_zero_buf_len ... ok
[INFO] [stdout] test syscalls::return_data::tests::test_set_return_data_basic ... ok
[INFO] [stdout] test syscalls::pda::tests::test_try_find_program_address_too_many_seeds ... ok
[INFO] [stdout] test syscalls::return_data::tests::test_get_return_data_none ... ok
[INFO] [stdout] test syscalls::sysvar::tests::test_sol_get_clock_sysvar ... ok
[INFO] [stdout] test syscalls::return_data::tests::test_set_return_data_empty ... ok
[INFO] [stdout] test syscalls::return_data::tests::test_set_return_data_compute_exhausted ... ok
[INFO] [stdout] test syscalls::sysvar::tests::test_sol_get_epoch_schedule_sysvar ... ok
[INFO] [stdout] test syscalls::sysvar::tests::test_sol_get_last_restart_slot ... ok
[INFO] [stdout] test syscalls::sysvar::tests::test_sol_get_rent_sysvar ... ok
[INFO] [stdout] test syscalls::sysvar::tests::test_sol_get_clock_sysvar_compute_exhausted ... ok
[INFO] [stdout] test syscalls::log::tests::test_sol_remaining_compute_units_returns_remaining ... ok
[INFO] [stdout] test syscalls::return_data::tests::test_set_return_data_too_large ... ok
[INFO] [stdout] test syscalls::sysvar::tests::test_sol_get_sysvar_not_found ... ok
[INFO] [stdout] test syscalls::memory::tests::test_memset_basic ... ok
[INFO] [stdout] test syscalls::sysvar::tests::test_sol_get_sysvar_offset_length_exceeds ... ok
[INFO] [stdout] test syscalls::memory::tests::test_memset_compute_exhausted ... ok
[INFO] [stdout] test syscalls::sysvar::tests::test_sol_get_epoch_rewards_sysvar ... ok
[INFO] [stdout] test syscalls::tests::consume_cpi_compute_units_dedups_and_charges_data ... ok
[INFO] [stdout] test syscalls::tests::consume_cpi_compute_units_no_caller_accounts ... ok
[INFO] [stdout] test syscalls::tests::consume_cpi_compute_units_out_of_budget ... ok
[INFO] [stdout] test syscalls::tests::handle_log_64_writes_log ... ok
[INFO] [stdout] test syscalls::tests::handle_abort_returns_abort_error ... ok
[INFO] [stdout] test syscalls::sysvar::tests::test_sol_get_sysvar ... ok
[INFO] [stdout] test syscalls::tests::handle_remaining_compute_units_reports_remaining ... ok
[INFO] [stdout] test syscalls::tests::handle_unknown_syscall_charges_base_and_returns_zero ... ok
[INFO] [stderr] [2026-10-06T18:22:57.762506925Z DEBUG solana_runtime::message_processor::stable_log] Program 22222222222222222222222222222222222222222222 invoke [1]
[INFO] [stderr] [2026-10-06T18:22:57.765554865Z DEBUG solana_runtime::message_processor::stable_log] Program log: Make invoked
[INFO] [stderr] [2026-10-06T18:22:57.765830105Z DEBUG solana_runtime::message_processor::stable_log] Program 11111111111111111111111111111111 invoke [2]
[INFO] [stderr] [2026-10-06T18:22:57.765925535Z DEBUG solana_runtime::message_processor::stable_log] Program 11111111111111111111111111111111 success
[INFO] [stderr] [2026-10-06T18:22:57.766127435Z DEBUG solana_runtime::message_processor::stable_log] Program ATokenGPvbdGVxr1b2hvZbsiqW5xWH25efTNsLJA8knL invoke [2]
[INFO] [stderr] [2026-10-06T18:22:57.766317635Z DEBUG solana_runtime::message_processor::stable_log] Program log: CreateIdempotent
[INFO] [stderr] [2026-10-06T18:22:57.766531415Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA invoke [3]
[INFO] [stderr] [2026-10-06T18:22:57.766943505Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA consumed 179 of 1388756 compute units
[INFO] [stderr] [2026-10-06T18:22:57.767010845Z DEBUG solana_runtime::message_processor::stable_log] Program return: TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA pQAAAAAAAAA=
[INFO] [stderr] [2026-10-06T18:22:57.767062885Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA success
[INFO] [stderr] [2026-10-06T18:22:57.767547105Z DEBUG solana_runtime::message_processor::stable_log] Program 11111111111111111111111111111111 invoke [3]
[INFO] [stderr] [2026-10-06T18:22:57.767683215Z DEBUG solana_runtime::message_processor::stable_log] Program 11111111111111111111111111111111 success
[INFO] [stderr] [2026-10-06T18:22:57.767782825Z DEBUG solana_runtime::message_processor::stable_log] Program log: Initialize the associated token account
[INFO] [stderr] [2026-10-06T18:22:57.767922195Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA invoke [3]
[INFO] [stderr] [2026-10-06T18:22:57.768071485Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA consumed 37 of 1383667 compute units
[INFO] [stderr] [2026-10-06T18:22:57.768175445Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA success
[INFO] [stderr] [2026-10-06T18:22:57.768350965Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA invoke [3]
[INFO] [stderr] [2026-10-06T18:22:57.768521815Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA consumed 233 of 1381204 compute units
[INFO] [stderr] [2026-10-06T18:22:57.768622915Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA success
[INFO] [stderr] [2026-10-06T18:22:57.769031705Z DEBUG solana_runtime::message_processor::stable_log] Program ATokenGPvbdGVxr1b2hvZbsiqW5xWH25efTNsLJA8knL consumed 13518 of 1394185 compute units
[INFO] [stderr] [2026-10-06T18:22:57.769135575Z DEBUG solana_runtime::message_processor::stable_log] Program ATokenGPvbdGVxr1b2hvZbsiqW5xWH25efTNsLJA8knL success
[INFO] [stderr] [2026-10-06T18:22:57.769284495Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA invoke [2]
[INFO] [stderr] [2026-10-06T18:22:57.769431955Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA consumed 76 of 1379515 compute units
[INFO] [stderr] [2026-10-06T18:22:57.769516365Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA success
[INFO] [stderr] [2026-10-06T18:22:57.769933795Z DEBUG solana_runtime::message_processor::stable_log] Program 22222222222222222222222222222222222222222222 consumed 20661 of 1400000 compute units
[INFO] [stderr] [2026-10-06T18:22:57.770053015Z DEBUG solana_runtime::message_processor::stable_log] Program 22222222222222222222222222222222222222222222 success
[INFO] [stderr] [2026-10-06T18:22:57.770522285Z DEBUG solana_runtime::message_processor::stable_log] Program 22222222222222222222222222222222222222222222 invoke [1]
[INFO] [stderr] [2026-10-06T18:22:57.770679655Z DEBUG solana_runtime::message_processor::stable_log] Program log: Refund invoked
[INFO] [stderr] [2026-10-06T18:22:57.770893305Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA invoke [2]
[INFO] [stderr] [2026-10-06T18:22:57.771053305Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA consumed 76 of 1398280 compute units
[INFO] [stderr] [2026-10-06T18:22:57.771142445Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA success
[INFO] [stderr] [2026-10-06T18:22:57.771336725Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA invoke [2]
[INFO] [stderr] [2026-10-06T18:22:57.771486805Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA consumed 118 of 1397101 compute units
[INFO] [stderr] [2026-10-06T18:22:57.771585365Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA success
[INFO] [stderr] [2026-10-06T18:22:57.771719795Z DEBUG solana_runtime::message_processor::stable_log] Program 22222222222222222222222222222222222222222222 consumed 3067 of 1400000 compute units
[INFO] [stderr] [2026-10-06T18:22:57.771822175Z DEBUG solana_runtime::message_processor::stable_log] Program 22222222222222222222222222222222222222222222 success
[INFO] [stdout] test tests::make_and_refund ... ok
[INFO] [stderr] [2026-10-06T18:22:57.928760518Z DEBUG solana_runtime::message_processor::stable_log] Program 22222222222222222222222222222222222222222222 invoke [1]
[INFO] [stderr] [2026-10-06T18:22:57.929688798Z DEBUG solana_runtime::message_processor::stable_log] Program log: Make invoked
[INFO] [stderr] [2026-10-06T18:22:57.929998248Z DEBUG solana_runtime::message_processor::stable_log] Program 11111111111111111111111111111111 invoke [2]
[INFO] [stderr] [2026-10-06T18:22:57.930115188Z DEBUG solana_runtime::message_processor::stable_log] Program 11111111111111111111111111111111 success
[INFO] [stderr] [2026-10-06T18:22:57.930365308Z DEBUG solana_runtime::message_processor::stable_log] Program ATokenGPvbdGVxr1b2hvZbsiqW5xWH25efTNsLJA8knL invoke [2]
[INFO] [stderr] [2026-10-06T18:22:57.930583048Z DEBUG solana_runtime::message_processor::stable_log] Program log: CreateIdempotent
[INFO] [stderr] [2026-10-06T18:22:57.930847088Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA invoke [3]
[INFO] [stderr] [2026-10-06T18:22:57.931161538Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA consumed 179 of 1388756 compute units
[INFO] [stderr] [2026-10-06T18:22:57.931249938Z DEBUG solana_runtime::message_processor::stable_log] Program return: TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA pQAAAAAAAAA=
[INFO] [stderr] [2026-10-06T18:22:57.931334088Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA success
[INFO] [stderr] [2026-10-06T18:22:57.931579448Z DEBUG solana_runtime::message_processor::stable_log] Program 11111111111111111111111111111111 invoke [3]
[INFO] [stderr] [2026-10-06T18:22:57.931706068Z DEBUG solana_runtime::message_processor::stable_log] Program 11111111111111111111111111111111 success
[INFO] [stderr] [2026-10-06T18:22:57.931795128Z DEBUG solana_runtime::message_processor::stable_log] Program log: Initialize the associated token account
[INFO] [stderr] [2026-10-06T18:22:57.931922308Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA invoke [3]
[INFO] [stderr] [2026-10-06T18:22:57.932058088Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA consumed 37 of 1383667 compute units
[INFO] [stderr] [2026-10-06T18:22:57.932142158Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA success
[INFO] [stderr] [2026-10-06T18:22:57.932310608Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA invoke [3]
[INFO] [stderr] [2026-10-06T18:22:57.932468558Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA consumed 233 of 1381204 compute units
[INFO] [stderr] [2026-10-06T18:22:57.934701078Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA success
[INFO] [stderr] [2026-10-06T18:22:57.935050148Z DEBUG solana_runtime::message_processor::stable_log] Program ATokenGPvbdGVxr1b2hvZbsiqW5xWH25efTNsLJA8knL consumed 13518 of 1394185 compute units
[INFO] [stderr] [2026-10-06T18:22:57.935174938Z DEBUG solana_runtime::message_processor::stable_log] Program ATokenGPvbdGVxr1b2hvZbsiqW5xWH25efTNsLJA8knL success
[INFO] [stderr] [2026-10-06T18:22:57.935305368Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA invoke [2]
[INFO] [stderr] [2026-10-06T18:22:57.935436038Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA consumed 76 of 1379515 compute units
[INFO] [stderr] [2026-10-06T18:22:57.935503518Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA success
[INFO] [stderr] [2026-10-06T18:22:57.935804418Z DEBUG solana_runtime::message_processor::stable_log] Program 22222222222222222222222222222222222222222222 consumed 20661 of 1400000 compute units
[INFO] [stderr] [2026-10-06T18:22:57.935875198Z DEBUG solana_runtime::message_processor::stable_log] Program 22222222222222222222222222222222222222222222 success
[INFO] [stderr] [2026-10-06T18:22:57.936552018Z DEBUG solana_runtime::message_processor::stable_log] Program 22222222222222222222222222222222222222222222 invoke [1]
[INFO] [stderr] [2026-10-06T18:22:57.936696188Z DEBUG solana_runtime::message_processor::stable_log] Program log: Take invoked
[INFO] [stderr] [2026-10-06T18:22:57.936855748Z DEBUG solana_runtime::message_processor::stable_log] Program ATokenGPvbdGVxr1b2hvZbsiqW5xWH25efTNsLJA8knL invoke [2]
[INFO] [stderr] [2026-10-06T18:22:57.936999788Z DEBUG solana_runtime::message_processor::stable_log] Program log: CreateIdempotent
[INFO] [stderr] [2026-10-06T18:22:57.937319898Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA invoke [3]
[INFO] [stderr] [2026-10-06T18:22:57.937434138Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA consumed 179 of 1386960 compute units
[INFO] [stderr] [2026-10-06T18:22:57.937490258Z DEBUG solana_runtime::message_processor::stable_log] Program return: TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA pQAAAAAAAAA=
[INFO] [stderr] [2026-10-06T18:22:57.937545458Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA success
[INFO] [stderr] [2026-10-06T18:22:57.937865888Z DEBUG solana_runtime::message_processor::stable_log] Program 11111111111111111111111111111111 invoke [3]
[INFO] [stderr] [2026-10-06T18:22:57.937988108Z DEBUG solana_runtime::message_processor::stable_log] Program 11111111111111111111111111111111 success
[INFO] [stderr] [2026-10-06T18:22:57.938105208Z DEBUG solana_runtime::message_processor::stable_log] Program log: Initialize the associated token account
[INFO] [stderr] [2026-10-06T18:22:57.938279018Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA invoke [3]
[INFO] [stderr] [2026-10-06T18:22:57.938452848Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA consumed 37 of 1381871 compute units
[INFO] [stderr] [2026-10-06T18:22:57.938545728Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA success
[INFO] [stderr] [2026-10-06T18:22:57.938764118Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA invoke [3]
[INFO] [stderr] [2026-10-06T18:22:57.938957928Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA consumed 233 of 1379410 compute units
[INFO] [stderr] [2026-10-06T18:22:57.939058098Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA success
[INFO] [stderr] [2026-10-06T18:22:57.939218478Z DEBUG solana_runtime::message_processor::stable_log] Program ATokenGPvbdGVxr1b2hvZbsiqW5xWH25efTNsLJA8knL consumed 17917 of 1396811 compute units
[INFO] [stderr] [2026-10-06T18:22:57.941796288Z DEBUG solana_runtime::message_processor::stable_log] Program ATokenGPvbdGVxr1b2hvZbsiqW5xWH25efTNsLJA8knL success
[INFO] [stderr] [2026-10-06T18:22:57.942068298Z DEBUG solana_runtime::message_processor::stable_log] Program ATokenGPvbdGVxr1b2hvZbsiqW5xWH25efTNsLJA8knL invoke [2]
[INFO] [stderr] [2026-10-06T18:22:57.942314878Z DEBUG solana_runtime::message_processor::stable_log] Program log: CreateIdempotent
[INFO] [stderr] [2026-10-06T18:22:57.942616818Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA invoke [3]
[INFO] [stderr] [2026-10-06T18:22:57.942834388Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA consumed 179 of 1370855 compute units
[INFO] [stderr] [2026-10-06T18:22:57.942932128Z DEBUG solana_runtime::message_processor::stable_log] Program return: TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA pQAAAAAAAAA=
[INFO] [stderr] [2026-10-06T18:22:57.943032778Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA success
[INFO] [stderr] [2026-10-06T18:22:57.943342058Z DEBUG solana_runtime::message_processor::stable_log] Program 11111111111111111111111111111111 invoke [3]
[INFO] [stderr] [2026-10-06T18:22:57.943470628Z DEBUG solana_runtime::message_processor::stable_log] Program 11111111111111111111111111111111 success
[INFO] [stderr] [2026-10-06T18:22:57.943593708Z DEBUG solana_runtime::message_processor::stable_log] Program log: Initialize the associated token account
[INFO] [stderr] [2026-10-06T18:22:57.944699748Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA invoke [3]
[INFO] [stderr] [2026-10-06T18:22:57.944881218Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA consumed 37 of 1365766 compute units
[INFO] [stderr] [2026-10-06T18:22:57.944974438Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA success
[INFO] [stderr] [2026-10-06T18:22:57.945180008Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA invoke [3]
[INFO] [stderr] [2026-10-06T18:22:57.945369688Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA consumed 233 of 1363303 compute units
[INFO] [stderr] [2026-10-06T18:22:57.945472938Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA success
[INFO] [stderr] [2026-10-06T18:22:57.945638678Z DEBUG solana_runtime::message_processor::stable_log] Program ATokenGPvbdGVxr1b2hvZbsiqW5xWH25efTNsLJA8knL consumed 13518 of 1376284 compute units
[INFO] [stderr] [2026-10-06T18:22:57.945752938Z DEBUG solana_runtime::message_processor::stable_log] Program ATokenGPvbdGVxr1b2hvZbsiqW5xWH25efTNsLJA8knL success
[INFO] [stderr] [2026-10-06T18:22:57.945928308Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA invoke [2]
[INFO] [stderr] [2026-10-06T18:22:57.946177678Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA consumed 76 of 1361635 compute units
[INFO] [stderr] [2026-10-06T18:22:57.946316388Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA success
[INFO] [stderr] [2026-10-06T18:22:57.946588448Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA invoke [2]
[INFO] [stderr] [2026-10-06T18:22:57.946791898Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA consumed 76 of 1360290 compute units
[INFO] [stderr] [2026-10-06T18:22:57.946897378Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA success
[INFO] [stderr] [2026-10-06T18:22:57.947158248Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA invoke [2]
[INFO] [stderr] [2026-10-06T18:22:57.947352488Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA consumed 118 of 1359112 compute units
[INFO] [stderr] [2026-10-06T18:22:57.947458628Z DEBUG solana_runtime::message_processor::stable_log] Program TokenkegQfeZyiNwAJbNbGKPFXCWuBvf9Ss623VQ5DA success
[INFO] [stderr] [2026-10-06T18:22:57.947626488Z DEBUG solana_runtime::message_processor::stable_log] Program 22222222222222222222222222222222222222222222 consumed 41055 of 1400000 compute units
[INFO] [stderr] [2026-10-06T18:22:57.947783728Z DEBUG solana_runtime::message_processor::stable_log] Program 22222222222222222222222222222222222222222222 success
[INFO] [stdout] test tests::make_and_take ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 146 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.38s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests sbpf_runtime
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "a3b1dfa9907c7bd89a949d1f33a0d7090cf577260fadddbb96421633f28ff2bb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a3b1dfa9907c7bd89a949d1f33a0d7090cf577260fadddbb96421633f28ff2bb", kill_on_drop: false }`
[INFO] [stdout] a3b1dfa9907c7bd89a949d1f33a0d7090cf577260fadddbb96421633f28ff2bb
