[INFO] cloning repository https://github.com/RadixHackathon/scrypto-code
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/RadixHackathon/scrypto-code" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FRadixHackathon%2Fscrypto-code", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FRadixHackathon%2Fscrypto-code'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] c9f2b937796aea7c00f0511a63a77a61fdf2d244
[INFO] testing RadixHackathon/scrypto-code against 1.93.0 for beta-1.94-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FRadixHackathon%2Fscrypto-code" "/workspace/builds/worker-0-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-0-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/RadixHackathon/scrypto-code
[INFO] finished tweaking git repo https://github.com/RadixHackathon/scrypto-code
[INFO] tweaked toml for git repo https://github.com/RadixHackathon/scrypto-code written to /workspace/builds/worker-0-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/RadixHackathon/scrypto-code on toolchain 1.93.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.93.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/RadixHackathon/scrypto-code 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.93.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating git repository `https://github.com/radixdlt/radixdlt-scrypto`
[INFO] [stderr]     Updating git repository `https://github.com/radixdlt/const-sha1`
[INFO] [stderr]     Updating git repository `https://github.com/bluss/indexmap`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded itoa v1.0.6
[INFO] [stderr]   Downloaded time-core v0.1.0
[INFO] [stderr]   Downloaded ident_case v1.0.1
[INFO] [stderr]   Downloaded quote v1.0.26
[INFO] [stderr]   Downloaded darling_macro v0.14.4
[INFO] [stderr]   Downloaded hex v0.4.3
[INFO] [stderr]   Downloaded memory_units v0.3.0
[INFO] [stderr]   Downloaded proc-macro2 v1.0.52
[INFO] [stderr]   Downloaded thiserror-impl v1.0.39
[INFO] [stderr]   Downloaded wasmi-validation v0.4.1
[INFO] [stderr]   Downloaded smallvec v1.10.0
[INFO] [stderr]   Downloaded tagptr v0.2.0
[INFO] [stderr]   Downloaded cmake v0.1.49
[INFO] [stderr]   Downloaded skeptic v0.13.7
[INFO] [stderr]   Downloaded rand_chacha v0.2.2
[INFO] [stderr]   Downloaded io-lifetimes v1.0.6
[INFO] [stderr]   Downloaded redox_syscall v0.2.16
[INFO] [stderr]   Downloaded wasm-instrument v0.1.1
[INFO] [stderr]   Downloaded libm v0.2.6
[INFO] [stderr]   Downloaded hermit-abi v0.2.6
[INFO] [stderr]   Downloaded scheduled-thread-pool v0.2.7
[INFO] [stderr]   Downloaded either v1.8.1
[INFO] [stderr]   Downloaded unicase v2.6.0
[INFO] [stderr]   Downloaded lock_api v0.4.9
[INFO] [stderr]   Downloaded colored v2.0.0
[INFO] [stderr]   Downloaded sized-chunks v0.6.5
[INFO] [stderr]   Downloaded camino v1.1.4
[INFO] [stderr]   Downloaded error-chain v0.12.4
[INFO] [stderr]   Downloaded im v15.1.0
[INFO] [stderr]   Downloaded itertools v0.10.5
[INFO] [stderr]   Downloaded crossbeam-channel v0.5.7
[INFO] [stderr]   Downloaded pulldown-cmark v0.9.2
[INFO] [stderr]   Downloaded moka v0.9.7
[INFO] [stderr]   Downloaded curve25519-dalek v3.2.1
[INFO] [stderr]   Downloaded chrono v0.4.24
[INFO] [stderr]   Downloaded crossbeam-epoch v0.9.14
[INFO] [stderr]   Downloaded wasm-bindgen-shared v0.2.84
[INFO] [stderr]   Downloaded semver v1.0.17
[INFO] [stderr]   Downloaded rustix v0.36.9
[INFO] [stderr]   Downloaded libc v0.2.140
[INFO] [stderr]   Downloaded bitmaps v2.1.0
[INFO] [stderr]   Downloaded crossbeam-utils v0.8.15
[INFO] [stderr]   Downloaded parking_lot_core v0.9.7
[INFO] [stderr]   Downloaded wasmi v0.11.0
[INFO] [stderr]   Downloaded time v0.3.20
[INFO] [stderr]   Downloaded memoffset v0.8.0
[INFO] [stderr]   Downloaded cargo_metadata v0.14.2
[INFO] [stderr]   Downloaded errno v0.2.8
[INFO] [stderr]   Downloaded cxx v1.0.92
[INFO] [stderr]   Downloaded wasm-bindgen v0.2.84
[INFO] [stderr]   Downloaded rustversion v1.0.12
[INFO] [stderr]   Downloaded bytecount v0.6.3
[INFO] [stderr]   Downloaded rand_xoshiro v0.6.0
[INFO] [stderr]   Downloaded memchr v2.5.0
[INFO] [stderr]   Downloaded serde_json v1.0.94
[INFO] [stderr]   Downloaded serde_with v2.3.1
[INFO] [stderr]   Downloaded num_cpus v1.15.0
[INFO] [stderr]   Downloaded cargo-platform v0.1.2
[INFO] [stderr]   Downloaded linux-raw-sys v0.1.4
[INFO] [stderr]   Downloaded js-sys v0.3.61
[INFO] [stderr]   Downloaded rand v0.7.3
[INFO] [stderr]   Downloaded bnum v0.4.0
[INFO] [stderr]   Downloaded num-bigint v0.4.3
[INFO] [stderr]   Downloaded cxx-build v1.0.92
[INFO] [stderr]   Downloaded num-traits v0.2.15
[INFO] [stderr]   Downloaded darling v0.14.4
[INFO] [stderr]   Downloaded hashbrown v0.13.2
[INFO] [stderr]   Downloaded parity-wasm v0.42.2
[INFO] [stderr]   Downloaded wasi v0.9.0+wasi-snapshot-preview1
[INFO] [stderr]   Downloaded cxxbridge-macro v1.0.92
[INFO] [stderr]   Downloaded bumpalo v3.12.0
[INFO] [stderr]   Downloaded serde_with_macros v2.3.1
[INFO] [stderr]   Downloaded sha2 v0.10.6
[INFO] [stderr]   Downloaded generic-array v0.14.6
[INFO] [stderr]   Downloaded secp256k1 v0.24.3
[INFO] [stderr]   Downloaded unicode-ident v1.0.8
[INFO] [stderr]   Downloaded errno-dragonfly v0.1.2
[INFO] [stderr]   Downloaded tempfile v3.4.0
[INFO] [stderr]   Downloaded thiserror v1.0.39
[INFO] [stderr]   Downloaded triomphe v0.1.8
[INFO] [stderr]   Downloaded getrandom v0.2.8
[INFO] [stderr]   Downloaded link-cplusplus v1.0.8
[INFO] [stderr]   Downloaded secp256k1-sys v0.6.1
[INFO] [stderr]   Downloaded uuid v1.3.0
[INFO] [stderr]   Downloaded wabt v0.10.0
[INFO] [stderr]   Downloaded byteorder v1.4.3
[INFO] [stderr]   Downloaded getrandom v0.1.16
[INFO] [stderr]   Downloaded rand_hc v0.2.0
[INFO] [stderr]   Downloaded core-foundation-sys v0.8.3
[INFO] [stderr]   Downloaded darling_core v0.14.4
[INFO] [stderr]   Downloaded time-macros v0.2.8
[INFO] [stderr]   Downloaded strum_macros v0.24.3
[INFO] [stderr]   Downloaded cc v1.0.79
[INFO] [stderr]   Downloaded ed25519 v1.5.3
[INFO] [stderr]   Downloaded wabt-sys v0.8.0
[INFO] [stderr]   Downloaded synstructure v0.12.6
[INFO] [stderr]   Downloaded ed25519-dalek v1.0.1
[INFO] [stderr]   Downloaded bech32 v0.9.1
[INFO] [stderr]   Downloaded indexmap v1.9.2
[INFO] [stderr]   Downloaded ryu v1.0.13
[INFO] [stderr]   Downloaded typenum v1.16.0
[INFO] [stderr]   Downloaded digest v0.10.6
[INFO] [stderr]   Downloaded heck v0.4.1
[INFO] [stderr]   Downloaded zeroize v1.3.0
[INFO] [stderr]   Downloaded wasm-bindgen-macro v0.2.84
[INFO] [stderr]   Downloaded keccak v0.1.3
[INFO] [stderr]   Downloaded sha3 v0.10.6
[INFO] [stderr]   Downloaded serde_derive v1.0.156
[INFO] [stderr]   Downloaded subtle v2.5.0
[INFO] [stderr]   Downloaded log v0.4.17
[INFO] [stderr]   Downloaded once_cell v1.17.1
[INFO] [stderr]   Downloaded strum v0.24.1
[INFO] [stderr]   Downloaded signature v1.6.4
[INFO] [stderr]   Downloaded rand_core v0.5.1
[INFO] [stderr]   Downloaded unicode-width v0.1.10
[INFO] [stderr]   Downloaded scratch v1.0.5
[INFO] [stderr]   Downloaded glob v0.2.11
[INFO] [stderr]   Downloaded windows-sys v0.42.0
[INFO] [stderr]   Downloaded wasm-bindgen-backend v0.2.84
[INFO] [stderr]   Downloaded paste v1.0.12
[INFO] [stderr]   Downloaded zeroize_derive v1.3.3
[INFO] [stderr]   Downloaded iana-time-zone-haiku v0.1.1
[INFO] [stderr]   Downloaded wasm-bindgen-macro-support v0.2.84
[INFO] [stderr]   Downloaded iana-time-zone v0.1.53
[INFO] [stderr]   Downloaded cpufeatures v0.2.5
[INFO] [stderr]   Downloaded cxxbridge-flags v1.0.92
[INFO] [stderr]   Downloaded termcolor v1.2.0
[INFO] [stderr]   Downloaded serde v1.0.156
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+1.93.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 4b1e82551dd99d0b33f97f89b4c07ed95adeb4c6ca7030198ea5ae8b5ba23831
[INFO] running `Command { std: "docker" "start" "-a" "4b1e82551dd99d0b33f97f89b4c07ed95adeb4c6ca7030198ea5ae8b5ba23831", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "4b1e82551dd99d0b33f97f89b4c07ed95adeb4c6ca7030198ea5ae8b5ba23831", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4b1e82551dd99d0b33f97f89b4c07ed95adeb4c6ca7030198ea5ae8b5ba23831", kill_on_drop: false }`
[INFO] [stdout] 4b1e82551dd99d0b33f97f89b4c07ed95adeb4c6ca7030198ea5ae8b5ba23831
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+1.93.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] d629bb4beac958871913b7e17fc3d3f49099c786d480d4c7b43a641ac741cab8
[INFO] running `Command { std: "docker" "start" "-a" "d629bb4beac958871913b7e17fc3d3f49099c786d480d4c7b43a641ac741cab8", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.52
[INFO] [stderr]    Compiling quote v1.0.26
[INFO] [stderr]    Compiling unicode-ident v1.0.8
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stderr]    Compiling autocfg v1.1.0
[INFO] [stderr]    Compiling const-sha1 v0.2.0 (https://github.com/radixdlt/const-sha1#5e9ae2a9)
[INFO] [stderr]    Compiling typenum v1.16.0
[INFO] [stderr]    Compiling version_check v0.9.4
[INFO] [stderr]    Compiling serde_derive v1.0.156
[INFO] [stderr]    Compiling serde v1.0.156
[INFO] [stderr]    Compiling rustversion v1.0.12
[INFO] [stderr]    Compiling heck v0.4.1
[INFO] [stderr]    Compiling paste v1.0.12
[INFO] [stderr]    Compiling num-traits v0.2.15
[INFO] [stderr]    Compiling num-integer v0.1.45
[INFO] [stderr]    Compiling generic-array v0.14.6
[INFO] [stderr]    Compiling num-bigint v0.4.3
[INFO] [stderr]    Compiling serde_json v1.0.94
[INFO] [stderr]    Compiling ryu v1.0.13
[INFO] [stderr]    Compiling cpufeatures v0.2.5
[INFO] [stderr]    Compiling itoa v1.0.6
[INFO] [stderr]    Compiling cfg-if v1.0.0
[INFO] [stderr]    Compiling keccak v0.1.3
[INFO] [stderr]    Compiling hex v0.4.3
[INFO] [stderr]    Compiling bech32 v0.9.1
[INFO] [stderr]    Compiling bnum v0.4.0
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling crypto-common v0.1.6
[INFO] [stderr]    Compiling digest v0.10.6
[INFO] [stderr]    Compiling sha2 v0.10.6
[INFO] [stderr]    Compiling sha3 v0.10.6
[INFO] [stderr]    Compiling sbor-derive-common v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling strum_macros v0.24.3
[INFO] [stderr]    Compiling sbor-derive v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling radix-engine-derive v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling sbor v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling strum v0.24.1
[INFO] [stderr]    Compiling scrypto-abi v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling utils v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling radix-engine-interface v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling scrypto-derive v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling scrypto v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling scryptoDemo v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: variable does not need to be mutable
[INFO] [stdout]    --> src/lib.rs:704:21
[INFO] [stdout]     |
[INFO] [stdout] 704 |                 let mut pool_address: ComponentAddress;
[INFO] [stdout]     |                     ----^^^^^^^^^^^^
[INFO] [stdout]     |                     |
[INFO] [stdout]     |                     help: remove this `mut`
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_mut)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: variable does not need to be mutable
[INFO] [stdout]    --> src/lib.rs:799:17
[INFO] [stdout]     |
[INFO] [stdout] 799 |             let mut pool_address: ComponentAddress;
[INFO] [stdout]     |                 ----^^^^^^^^^^^^
[INFO] [stdout]     |                 |
[INFO] [stdout]     |                 help: remove this `mut`
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: crate `scryptoDemo` should have a snake case name
[INFO] [stdout]   |
[INFO] [stdout]   = help: convert the identifier to snake case: `scrypto_demo`
[INFO] [stdout]   = note: `#[warn(non_snake_case)]` (part of `#[warn(nonstandard_style)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 51.26s
[INFO] running `Command { std: "docker" "inspect" "d629bb4beac958871913b7e17fc3d3f49099c786d480d4c7b43a641ac741cab8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d629bb4beac958871913b7e17fc3d3f49099c786d480d4c7b43a641ac741cab8", kill_on_drop: false }`
[INFO] [stdout] d629bb4beac958871913b7e17fc3d3f49099c786d480d4c7b43a641ac741cab8
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+1.93.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7851066d53c21b60d21851c4e1140bc63115b7cb3b5dceec23fd892b184cee14
[INFO] running `Command { std: "docker" "start" "-a" "7851066d53c21b60d21851c4e1140bc63115b7cb3b5dceec23fd892b184cee14", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stderr]    Compiling libc v0.2.140
[INFO] [stderr]    Compiling serde v1.0.156
[INFO] [stderr]    Compiling num-traits v0.2.15
[INFO] [stderr]    Compiling num-integer v0.1.45
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling strsim v0.10.0
[INFO] [stderr]    Compiling ident_case v1.0.1
[INFO] [stderr]    Compiling cc v1.0.79
[INFO] [stderr]    Compiling num-bigint v0.4.3
[INFO] [stderr]    Compiling getrandom v0.1.16
[INFO] [stderr]    Compiling unicode-xid v0.2.4
[INFO] [stderr]    Compiling lock_api v0.4.9
[INFO] [stderr]    Compiling crossbeam-utils v0.8.15
[INFO] [stderr]    Compiling scopeguard v1.1.0
[INFO] [stderr]    Compiling parking_lot_core v0.9.7
[INFO] [stderr]    Compiling digest v0.9.0
[INFO] [stderr]    Compiling memoffset v0.8.0
[INFO] [stderr]    Compiling smallvec v1.10.0
[INFO] [stderr]    Compiling ppv-lite86 v0.2.17
[INFO] [stderr]    Compiling itoa v1.0.6
[INFO] [stderr]    Compiling ryu v1.0.13
[INFO] [stderr]    Compiling block-buffer v0.9.0
[INFO] [stderr]    Compiling num-rational v0.4.1
[INFO] [stderr]    Compiling rand_core v0.5.1
[INFO] [stderr]    Compiling crossbeam-epoch v0.9.14
[INFO] [stderr]    Compiling libm v0.2.6
[INFO] [stderr]    Compiling secp256k1-sys v0.6.1
[INFO] [stderr]    Compiling rand_chacha v0.2.2
[INFO] [stderr]    Compiling signature v1.6.4
[INFO] [stderr]    Compiling opaque-debug v0.3.0
[INFO] [stderr]    Compiling subtle v2.5.0
[INFO] [stderr]    Compiling parity-wasm v0.42.2
[INFO] [stderr]    Compiling byteorder v1.4.3
[INFO] [stderr]    Compiling thiserror v1.0.39
[INFO] [stderr]    Compiling ed25519 v1.5.3
[INFO] [stderr]    Compiling rand v0.7.3
[INFO] [stderr]    Compiling sha2 v0.9.9
[INFO] [stderr]    Compiling parking_lot v0.12.1
[INFO] [stderr]    Compiling bnum v0.4.0
[INFO] [stderr]    Compiling getrandom v0.2.8
[INFO] [stderr]    Compiling cmake v0.1.49
[INFO] [stderr]    Compiling bitmaps v2.1.0
[INFO] [stderr]    Compiling im v15.1.0
[INFO] [stderr]    Compiling slotmap v1.0.6
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling lazy_static v1.4.0
[INFO] [stderr]    Compiling moka v0.9.7
[INFO] [stderr]    Compiling rand_xoshiro v0.6.0
[INFO] [stderr]    Compiling wabt-sys v0.8.0
[INFO] [stderr]    Compiling sized-chunks v0.6.5
[INFO] [stderr]    Compiling wasmi-validation v0.4.1
[INFO] [stderr]    Compiling uuid v1.3.0
[INFO] [stderr]    Compiling scheduled-thread-pool v0.2.7
[INFO] [stderr]    Compiling crossbeam-channel v0.5.7
[INFO] [stderr]    Compiling num_cpus v1.15.0
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling tagptr v0.2.0
[INFO] [stderr]    Compiling memory_units v0.3.0
[INFO] [stderr]    Compiling downcast-rs v1.2.0
[INFO] [stderr]    Compiling triomphe v0.1.8
[INFO] [stderr]    Compiling once_cell v1.17.1
[INFO] [stderr]    Compiling radix-engine-constants v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling serde_json v1.0.94
[INFO] [stderr]    Compiling wasmi v0.11.0
[INFO] [stderr]    Compiling colored v2.0.0
[INFO] [stderr]    Compiling wasm-instrument v0.1.1
[INFO] [stderr]    Compiling secp256k1 v0.24.3
[INFO] [stderr]    Compiling bitflags v1.3.2
[INFO] [stderr]    Compiling sbor-derive-common v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling darling_core v0.14.4
[INFO] [stderr]    Compiling synstructure v0.12.6
[INFO] [stderr]    Compiling either v1.8.1
[INFO] [stderr]    Compiling itertools v0.10.5
[INFO] [stderr]    Compiling serde_derive v1.0.156
[INFO] [stderr]    Compiling sbor-derive v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling strum_macros v0.24.3
[INFO] [stderr]    Compiling radix-engine-derive v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling zeroize_derive v1.3.3
[INFO] [stderr]    Compiling thiserror-impl v1.0.39
[INFO] [stderr]    Compiling zeroize v1.3.0
[INFO] [stderr]    Compiling curve25519-dalek v3.2.1
[INFO] [stderr]    Compiling darling_macro v0.14.4
[INFO] [stderr]    Compiling strum v0.24.1
[INFO] [stderr]    Compiling darling v0.14.4
[INFO] [stderr]    Compiling serde_with_macros v2.3.1
[INFO] [stderr]    Compiling sbor v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling hex v0.4.3
[INFO] [stderr]    Compiling serde_with v2.3.1
[INFO] [stderr]    Compiling scrypto-abi v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling utils v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling ed25519-dalek v1.0.1
[INFO] [stderr]    Compiling radix-engine-interface v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling scrypto-derive v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling transaction v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling native-sdk v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling scrypto v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling radix-engine v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling scryptoDemo v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: variable does not need to be mutable
[INFO] [stdout]    --> src/lib.rs:704:21
[INFO] [stdout]     |
[INFO] [stdout] 704 |                 let mut pool_address: ComponentAddress;
[INFO] [stdout]     |                     ----^^^^^^^^^^^^
[INFO] [stdout]     |                     |
[INFO] [stdout]     |                     help: remove this `mut`
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_mut)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: variable does not need to be mutable
[INFO] [stdout]    --> src/lib.rs:799:17
[INFO] [stdout]     |
[INFO] [stdout] 799 |             let mut pool_address: ComponentAddress;
[INFO] [stdout]     |                 ----^^^^^^^^^^^^
[INFO] [stdout]     |                 |
[INFO] [stdout]     |                 help: remove this `mut`
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: crate `scryptoDemo` should have a snake case name
[INFO] [stdout]   |
[INFO] [stdout]   = help: convert the identifier to snake case: `scrypto_demo`
[INFO] [stdout]   = note: `#[warn(non_snake_case)]` (part of `#[warn(nonstandard_style)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]    Compiling radix-engine-stores v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stderr]    Compiling wabt v0.10.0
[INFO] [stderr]    Compiling scrypto-unit v0.8.0 (https://github.com/radixdlt/radixdlt-scrypto?tag=v0.8.0#e9381b7b)
[INFO] [stdout] warning: variable does not need to be mutable
[INFO] [stdout]    --> src/lib.rs:704:21
[INFO] [stdout]     |
[INFO] [stdout] 704 |                 let mut pool_address: ComponentAddress;
[INFO] [stdout]     |                     ----^^^^^^^^^^^^
[INFO] [stdout]     |                     |
[INFO] [stdout]     |                     help: remove this `mut`
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_mut)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: variable does not need to be mutable
[INFO] [stdout]    --> src/lib.rs:799:17
[INFO] [stdout]     |
[INFO] [stdout] 799 |             let mut pool_address: ComponentAddress;
[INFO] [stdout]     |                 ----^^^^^^^^^^^^
[INFO] [stdout]     |                 |
[INFO] [stdout]     |                 help: remove this `mut`
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 4m 42s
[INFO] running `Command { std: "docker" "inspect" "7851066d53c21b60d21851c4e1140bc63115b7cb3b5dceec23fd892b184cee14", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7851066d53c21b60d21851c4e1140bc63115b7cb3b5dceec23fd892b184cee14", kill_on_drop: false }`
[INFO] [stdout] 7851066d53c21b60d21851c4e1140bc63115b7cb3b5dceec23fd892b184cee14
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+1.93.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 2b60ea01628796596198934c2e677efb51455189277dcd39a6ed8420bb038eda
[INFO] running `Command { std: "docker" "start" "-a" "2b60ea01628796596198934c2e677efb51455189277dcd39a6ed8420bb038eda", kill_on_drop: false }`
[INFO] [stderr] warning: variable does not need to be mutable
[INFO] [stderr]    --> src/lib.rs:704:21
[INFO] [stderr]     |
[INFO] [stderr] 704 |                 let mut pool_address: ComponentAddress;
[INFO] [stderr]     |                     ----^^^^^^^^^^^^
[INFO] [stderr]     |                     |
[INFO] [stderr]     |                     help: remove this `mut`
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(unused_mut)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: variable does not need to be mutable
[INFO] [stderr]    --> src/lib.rs:799:17
[INFO] [stderr]     |
[INFO] [stderr] 799 |             let mut pool_address: ComponentAddress;
[INFO] [stderr]     |                 ----^^^^^^^^^^^^
[INFO] [stderr]     |                 |
[INFO] [stderr]     |                 help: remove this `mut`
[INFO] [stderr] 
[INFO] [stderr] warning: crate `scryptoDemo` should have a snake case name
[INFO] [stderr]   |
[INFO] [stderr]   = help: convert the identifier to snake case: `scrypto_demo`
[INFO] [stderr]   = note: `#[warn(non_snake_case)]` (part of `#[warn(nonstandard_style)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `scryptoDemo` (lib) generated 3 warnings (run `cargo fix --lib -p scryptoDemo` to apply 2 suggestions)
[INFO] [stderr] warning: `scryptoDemo` (lib test) generated 2 warnings (2 duplicates)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.27s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/scryptoDemo-539b91fab77bcb90)
[INFO] [stdout] running 0 tests
[INFO] [stderr]      Running tests/lib.rs (/opt/rustwide/target/debug/deps/lib-4fc34f7810802297)
[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] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]    Compiling proc-macro2 v1.0.52
[INFO] [stderr]    Compiling quote v1.0.26
[INFO] [stderr]    Compiling unicode-ident v1.0.8
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stderr]    Compiling autocfg v1.1.0
[INFO] [stderr]    Compiling const-sha1 v0.2.0 (https://github.com/radixdlt/const-sha1#5e9ae2a9)
[INFO] [stderr]    Compiling version_check v0.9.4
[INFO] [stderr]    Compiling typenum v1.16.0
[INFO] [stderr]    Compiling serde_derive v1.0.156
[INFO] [stderr]    Compiling serde v1.0.156
[INFO] [stderr]    Compiling generic-array v0.14.6
[INFO] [stderr]    Compiling rustversion v1.0.12
[INFO] [stderr]    Compiling num-traits v0.2.15
[INFO] [stderr] error[E0463]: can't find crate for `core`
[INFO] [stderr]   |
[INFO] [stderr]   = note: the `wasm32-unknown-unknown` target may not be installed
[INFO] [stderr]   = help: consider downloading the target with `rustup target add wasm32-unknown-unknown`
[INFO] [stderr] 
[INFO] [stderr] For more information about this error, try `rustc --explain E0463`.
[INFO] [stderr] error: could not compile `const-sha1` (lib) due to 1 previous error
[INFO] [stderr] warning: build failed, waiting for other jobs to finish...
[INFO] [stdout] test test_hello ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- test_hello stdout ----
[INFO] [stdout] ------------------------------Transaction Metadata------------------------------
[INFO] [stdout] Transaction hash: 3d4aca3100d7105c85aeea8ec86d07557f17a8a8ce8aefe11720786e8f13b8bc
[INFO] [stdout] Transaction auth zone params: AuthZoneParams { initial_proofs: [], virtualizable_proofs_resource_addresses: {} }
[INFO] [stdout] Number of unique blobs: 0
[INFO] [stdout] ------------------------------Engine Execution Log------------------------------
[INFO] [stdout] [0] Creating node: node = AuthZoneStack(AuthZoneStackSubstate { auth_zones: [AuthZone { proofs: [], virtual_resources: {}, virtual_non_fungibles: {}, virtual_non_fungibles_non_extending: {}, barrier: false }] })
[INFO] [stdout] [0] Creating node: node = Logger(LoggerSubstate { logs: [] })
[INFO] [stdout] [0] Invoking: TransactionProcessor(Run)
[INFO] [stdout] [0] Reading owned nodes
[INFO] [stdout] [0] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout] [0] Lock acquired: lock_handle = 0 
[INFO] [stdout] [0] Reading substate: lock_handle = 0
[INFO] [stdout] [0] Drop Lock: lock_handle = 0
[INFO] [stdout] [0] Reading owned nodes
[INFO] [stdout] [0] Reading owned nodes
[INFO] [stdout] [0] Reading owned nodes
[INFO] [stdout] [0] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout] [0] Lock acquired: lock_handle = 1 
[INFO] [stdout] [0] Get Mut: lock_handle = 1
[INFO] [stdout] [0] Drop Lock: lock_handle = 1
[INFO] [stdout]     [1] Creating node: node = Worktop(WorktopSubstate { resources: {} })
[INFO] [stdout]     [1] Creating node: node = TransactionRuntime(TransactionRuntimeSubstate { hash: 3d4aca3100d7105c85aeea8ec86d07557f17a8a8ce8aefe11720786e8f13b8bc, next_id: 0, instruction_index: 0 })
[INFO] [stdout]     [1] Lock substate: node_id = Global(Component(NormalComponent[02337081b7b8f4a35fda75f480866499cfd7f3bcd1f21fc82364a3])) offset = Component(Info) flags = (empty)
[INFO] [stdout]     [1] Lock substate: node_id = Global(Component(NormalComponent[02337081b7b8f4a35fda75f480866499cfd7f3bcd1f21fc82364a3])) offset = Global(Global) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 0 
[INFO] [stdout]     [1] Reading substate: lock_handle = 0
[INFO] [stdout]     [1] Lock acquired: lock_handle = 1 
[INFO] [stdout]     [1] Reading substate: lock_handle = 1
[INFO] [stdout]     [1] Drop Lock: lock_handle = 1
[INFO] [stdout]     [1] Invoking: ScryptoInvocation { package_address: NormalPackage[010000000000000000000000000000000000000000000000000000], blueprint_name: "Faucet", fn_name: "lock_fee", receiver: Some(Global(NormalComponent[02337081b7b8f4a35fda75f480866499cfd7f3bcd1f21fc82364a3])), args: [92, 33, 1, 181, 0, 0, 16, 99, 45, 94, 199, 107, 5, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
[INFO] [stdout]     [1] Lock substate: node_id = Global(Component(NormalComponent[02337081b7b8f4a35fda75f480866499cfd7f3bcd1f21fc82364a3])) offset = Component(Info) flags = (empty)
[INFO] [stdout]     [1] Lock substate: node_id = Global(Component(NormalComponent[02337081b7b8f4a35fda75f480866499cfd7f3bcd1f21fc82364a3])) offset = Global(Global) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 2 
[INFO] [stdout]     [1] Reading substate: lock_handle = 2
[INFO] [stdout]     [1] Lock acquired: lock_handle = 3 
[INFO] [stdout]     [1] Reading substate: lock_handle = 3
[INFO] [stdout]     [1] Drop Lock: lock_handle = 3
[INFO] [stdout]     [1] Lock substate: node_id = Global(Component(NormalComponent[02337081b7b8f4a35fda75f480866499cfd7f3bcd1f21fc82364a3])) offset = Global(Global) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 4 
[INFO] [stdout]     [1] Reading substate: lock_handle = 4
[INFO] [stdout]     [1] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000000])) offset = Package(Info) flags = (empty)
[INFO] [stdout]     [1] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000000])) offset = Global(Global) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 5 
[INFO] [stdout]     [1] Reading substate: lock_handle = 5
[INFO] [stdout]     [1] Lock acquired: lock_handle = 6 
[INFO] [stdout]     [1] Reading substate: lock_handle = 6
[INFO] [stdout]     [1] Drop Lock: lock_handle = 6
[INFO] [stdout]     [1] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000000])) offset = Package(Info) flags = (empty)
[INFO] [stdout]     [1] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000000])) offset = Global(Global) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 7 
[INFO] [stdout]     [1] Reading substate: lock_handle = 7
[INFO] [stdout]     [1] Lock acquired: lock_handle = 8 
[INFO] [stdout]     [1] Reading substate: lock_handle = 8
[INFO] [stdout]     [1] Drop Lock: lock_handle = 8
[INFO] [stdout]     [1] Lock substate: node_id = Component([175, 85, 112, 245, 161, 129, 11, 122, 247, 140, 175, 75, 199, 10, 102, 15, 13, 245, 30, 66, 186, 249, 29, 77, 229, 178, 50, 141, 224, 232, 61, 252, 23, 0, 0, 0]) offset = Component(State) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 9 
[INFO] [stdout]     [1] Reading substate: lock_handle = 9
[INFO] [stdout]     [1] Drop Lock: lock_handle = 9
[INFO] [stdout]     [1] Lock substate: node_id = Component([175, 85, 112, 245, 161, 129, 11, 122, 247, 140, 175, 75, 199, 10, 102, 15, 13, 245, 30, 66, 186, 249, 29, 77, 229, 178, 50, 141, 224, 232, 61, 252, 23, 0, 0, 0]) offset = AccessRulesChain(AccessRulesChain) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 10 
[INFO] [stdout]     [1] Reading substate: lock_handle = 10
[INFO] [stdout]     [1] Drop Lock: lock_handle = 10
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 11 
[INFO] [stdout]     [1] Reading substate: lock_handle = 11
[INFO] [stdout]     [1] Drop Lock: lock_handle = 11
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]     [1] Lock acquired: lock_handle = 12 
[INFO] [stdout]     [1] Get Mut: lock_handle = 12
[INFO] [stdout]     [1] Drop Lock: lock_handle = 12
[INFO] [stdout]         [2] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000000])) offset = Package(Info) flags = (empty)
[INFO] [stdout]         [2] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000000])) offset = Global(Global) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 0 
[INFO] [stdout]         [2] Reading substate: lock_handle = 0
[INFO] [stdout]         [2] Lock acquired: lock_handle = 1 
[INFO] [stdout]         [2] Reading substate: lock_handle = 1
[INFO] [stdout]         [2] Drop Lock: lock_handle = 1
[INFO] [stdout]         [2] Lock substate: node_id = Component([175, 85, 112, 245, 161, 129, 11, 122, 247, 140, 175, 75, 199, 10, 102, 15, 13, 245, 30, 66, 186, 249, 29, 77, 229, 178, 50, 141, 224, 232, 61, 252, 23, 0, 0, 0]) offset = Component(State) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 2 
[INFO] [stdout]         [2] Reading substate: lock_handle = 2
[INFO] [stdout]         [2] Invoking: VaultLockFeeInvocation { receiver: [175, 85, 112, 245, 161, 129, 11, 122, 247, 140, 175, 75, 199, 10, 102, 15, 13, 245, 30, 66, 186, 249, 29, 77, 229, 178, 50, 141, 224, 232, 61, 252, 21, 0, 0, 0], amount: 100, contingent: false }
[INFO] [stdout]         [2] Lock substate: node_id = Vault([175, 85, 112, 245, 161, 129, 11, 122, 247, 140, 175, 75, 199, 10, 102, 15, 13, 245, 30, 66, 186, 249, 29, 77, 229, 178, 50, 141, 224, 232, 61, 252, 21, 0, 0, 0]) offset = Vault(Vault) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 3 
[INFO] [stdout]         [2] Reading substate: lock_handle = 3
[INFO] [stdout]         [2] Drop Lock: lock_handle = 3
[INFO] [stdout]         [2] Lock substate: node_id = Global(Resource(NormalResource[000000000000000000000000000000000000000000000000000000])) offset = VaultAccessRulesChain(AccessRulesChain) flags = (empty)
[INFO] [stdout]         [2] Lock substate: node_id = Global(Resource(NormalResource[000000000000000000000000000000000000000000000000000000])) offset = Global(Global) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 4 
[INFO] [stdout]         [2] Reading substate: lock_handle = 4
[INFO] [stdout]         [2] Lock acquired: lock_handle = 5 
[INFO] [stdout]         [2] Reading substate: lock_handle = 5
[INFO] [stdout]         [2] Drop Lock: lock_handle = 5
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 6 
[INFO] [stdout]         [2] Reading substate: lock_handle = 6
[INFO] [stdout]         [2] Drop Lock: lock_handle = 6
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 7 
[INFO] [stdout]         [2] Get Mut: lock_handle = 7
[INFO] [stdout]         [2] Drop Lock: lock_handle = 7
[INFO] [stdout]             [3] Lock substate: node_id = Vault([175, 85, 112, 245, 161, 129, 11, 122, 247, 140, 175, 75, 199, 10, 102, 15, 13, 245, 30, 66, 186, 249, 29, 77, 229, 178, 50, 141, 224, 232, 61, 252, 21, 0, 0, 0]) offset = Vault(Vault) flags = MUTABLE | UNMODIFIED_BASE | FORCE_WRITE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 0 
[INFO] [stdout]             [3] Get Mut: lock_handle = 0
[INFO] [stdout]             [3] Get Mut: lock_handle = 0
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 1 
[INFO] [stdout]             [3] Get Mut: lock_handle = 1
[INFO] [stdout]             [3] Drop Lock: lock_handle = 1
[INFO] [stdout]         [2] Exiting invoke: output = ()
[INFO] [stdout]         [2] Get Mut: lock_handle = 2
[INFO] [stdout]         [2] Drop Lock: lock_handle = 2
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 8 
[INFO] [stdout]         [2] Get Mut: lock_handle = 8
[INFO] [stdout]         [2] Drop Lock: lock_handle = 8
[INFO] [stdout]     [1] Exiting invoke: output = Tuple { fields: [] }
[INFO] [stdout]     [1] Lock substate: node_id = TransactionRuntime(1) offset = TransactionRuntime(TransactionRuntime) flags = MUTABLE
[INFO] [stdout]     [1] Lock acquired: lock_handle = 13 
[INFO] [stdout]     [1] Get Mut: lock_handle = 13
[INFO] [stdout]     [1] Drop Lock: lock_handle = 13
[INFO] [stdout]     [1] Lock substate: node_id = Global(Component(NormalComponent[02337081b7b8f4a35fda75f480866499cfd7f3bcd1f21fc82364a3])) offset = Component(Info) flags = (empty)
[INFO] [stdout]     [1] Lock substate: node_id = Global(Component(NormalComponent[02337081b7b8f4a35fda75f480866499cfd7f3bcd1f21fc82364a3])) offset = Global(Global) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 14 
[INFO] [stdout]     [1] Reading substate: lock_handle = 14
[INFO] [stdout]     [1] Lock acquired: lock_handle = 15 
[INFO] [stdout]     [1] Reading substate: lock_handle = 15
[INFO] [stdout]     [1] Drop Lock: lock_handle = 15
[INFO] [stdout]     [1] Invoking: ScryptoInvocation { package_address: NormalPackage[010000000000000000000000000000000000000000000000000000], blueprint_name: "Faucet", fn_name: "free", receiver: Some(Global(NormalComponent[02337081b7b8f4a35fda75f480866499cfd7f3bcd1f21fc82364a3])), args: [92, 33, 0] }
[INFO] [stdout]     [1] Lock substate: node_id = Global(Component(NormalComponent[02337081b7b8f4a35fda75f480866499cfd7f3bcd1f21fc82364a3])) offset = Component(Info) flags = (empty)
[INFO] [stdout]     [1] Lock substate: node_id = Global(Component(NormalComponent[02337081b7b8f4a35fda75f480866499cfd7f3bcd1f21fc82364a3])) offset = Global(Global) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 16 
[INFO] [stdout]     [1] Reading substate: lock_handle = 16
[INFO] [stdout]     [1] Lock acquired: lock_handle = 17 
[INFO] [stdout]     [1] Reading substate: lock_handle = 17
[INFO] [stdout]     [1] Drop Lock: lock_handle = 17
[INFO] [stdout]     [1] Lock substate: node_id = Global(Component(NormalComponent[02337081b7b8f4a35fda75f480866499cfd7f3bcd1f21fc82364a3])) offset = Global(Global) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 18 
[INFO] [stdout]     [1] Reading substate: lock_handle = 18
[INFO] [stdout]     [1] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000000])) offset = Package(Info) flags = (empty)
[INFO] [stdout]     [1] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000000])) offset = Global(Global) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 19 
[INFO] [stdout]     [1] Reading substate: lock_handle = 19
[INFO] [stdout]     [1] Lock acquired: lock_handle = 20 
[INFO] [stdout]     [1] Reading substate: lock_handle = 20
[INFO] [stdout]     [1] Drop Lock: lock_handle = 20
[INFO] [stdout]     [1] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000000])) offset = Package(Info) flags = (empty)
[INFO] [stdout]     [1] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000000])) offset = Global(Global) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 21 
[INFO] [stdout]     [1] Reading substate: lock_handle = 21
[INFO] [stdout]     [1] Lock acquired: lock_handle = 22 
[INFO] [stdout]     [1] Reading substate: lock_handle = 22
[INFO] [stdout]     [1] Drop Lock: lock_handle = 22
[INFO] [stdout]     [1] Lock substate: node_id = Component([175, 85, 112, 245, 161, 129, 11, 122, 247, 140, 175, 75, 199, 10, 102, 15, 13, 245, 30, 66, 186, 249, 29, 77, 229, 178, 50, 141, 224, 232, 61, 252, 23, 0, 0, 0]) offset = Component(State) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 23 
[INFO] [stdout]     [1] Reading substate: lock_handle = 23
[INFO] [stdout]     [1] Drop Lock: lock_handle = 23
[INFO] [stdout]     [1] Lock substate: node_id = Component([175, 85, 112, 245, 161, 129, 11, 122, 247, 140, 175, 75, 199, 10, 102, 15, 13, 245, 30, 66, 186, 249, 29, 77, 229, 178, 50, 141, 224, 232, 61, 252, 23, 0, 0, 0]) offset = AccessRulesChain(AccessRulesChain) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 24 
[INFO] [stdout]     [1] Reading substate: lock_handle = 24
[INFO] [stdout]     [1] Drop Lock: lock_handle = 24
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 25 
[INFO] [stdout]     [1] Reading substate: lock_handle = 25
[INFO] [stdout]     [1] Drop Lock: lock_handle = 25
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]     [1] Lock acquired: lock_handle = 26 
[INFO] [stdout]     [1] Get Mut: lock_handle = 26
[INFO] [stdout]     [1] Drop Lock: lock_handle = 26
[INFO] [stdout]         [2] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000000])) offset = Package(Info) flags = (empty)
[INFO] [stdout]         [2] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000000])) offset = Global(Global) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 0 
[INFO] [stdout]         [2] Reading substate: lock_handle = 0
[INFO] [stdout]         [2] Lock acquired: lock_handle = 1 
[INFO] [stdout]         [2] Reading substate: lock_handle = 1
[INFO] [stdout]         [2] Drop Lock: lock_handle = 1
[INFO] [stdout]         [2] Lock substate: node_id = Component([175, 85, 112, 245, 161, 129, 11, 122, 247, 140, 175, 75, 199, 10, 102, 15, 13, 245, 30, 66, 186, 249, 29, 77, 229, 178, 50, 141, 224, 232, 61, 252, 23, 0, 0, 0]) offset = Component(State) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 2 
[INFO] [stdout]         [2] Reading substate: lock_handle = 2
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Invoking: TransactionRuntimeGetHashInvocation { receiver: 1 }
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 3 
[INFO] [stdout]         [2] Reading substate: lock_handle = 3
[INFO] [stdout]         [2] Drop Lock: lock_handle = 3
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 4 
[INFO] [stdout]         [2] Get Mut: lock_handle = 4
[INFO] [stdout]         [2] Drop Lock: lock_handle = 4
[INFO] [stdout]             [3] Lock substate: node_id = TransactionRuntime(1) offset = TransactionRuntime(TransactionRuntime) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 0 
[INFO] [stdout]             [3] Reading substate: lock_handle = 0
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 1 
[INFO] [stdout]             [3] Get Mut: lock_handle = 1
[INFO] [stdout]             [3] Drop Lock: lock_handle = 1
[INFO] [stdout]         [2] Exiting invoke: output = 3d4aca3100d7105c85aeea8ec86d07557f17a8a8ce8aefe11720786e8f13b8bc
[INFO] [stdout]         [2] Invoking: EpochManagerGetCurrentEpochInvocation { receiver: EpochManagerComponent[040000000000000000000000000000000000000000000000000000] }
[INFO] [stdout]         [2] Lock substate: node_id = Global(Component(EpochManagerComponent[040000000000000000000000000000000000000000000000000000])) offset = Global(Global) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 5 
[INFO] [stdout]         [2] Reading substate: lock_handle = 5
[INFO] [stdout]         [2] Lock substate: node_id = EpochManager([175, 85, 112, 245, 161, 129, 11, 122, 247, 140, 175, 75, 199, 10, 102, 15, 13, 245, 30, 66, 186, 249, 29, 77, 229, 178, 50, 141, 224, 232, 61, 252, 17, 0, 0, 0]) offset = AccessRulesChain(AccessRulesChain) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 6 
[INFO] [stdout]         [2] Reading substate: lock_handle = 6
[INFO] [stdout]         [2] Drop Lock: lock_handle = 6
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 7 
[INFO] [stdout]         [2] Reading substate: lock_handle = 7
[INFO] [stdout]         [2] Drop Lock: lock_handle = 7
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 8 
[INFO] [stdout]         [2] Get Mut: lock_handle = 8
[INFO] [stdout]         [2] Drop Lock: lock_handle = 8
[INFO] [stdout]             [3] Lock substate: node_id = EpochManager([175, 85, 112, 245, 161, 129, 11, 122, 247, 140, 175, 75, 199, 10, 102, 15, 13, 245, 30, 66, 186, 249, 29, 77, 229, 178, 50, 141, 224, 232, 61, 252, 17, 0, 0, 0]) offset = EpochManager(EpochManager) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 0 
[INFO] [stdout]             [3] Reading substate: lock_handle = 0
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 1 
[INFO] [stdout]             [3] Get Mut: lock_handle = 1
[INFO] [stdout]             [3] Drop Lock: lock_handle = 1
[INFO] [stdout]         [2] Exiting invoke: output = 1
[INFO] [stdout]         [2] Lock substate: node_id = KeyValueStore([175, 85, 112, 245, 161, 129, 11, 122, 247, 140, 175, 75, 199, 10, 102, 15, 13, 245, 30, 66, 186, 249, 29, 77, 229, 178, 50, 141, 224, 232, 61, 252, 22, 0, 0, 0]) offset = KeyValueStore(Entry([92, 176, 61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188])) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 9 
[INFO] [stdout]         [2] Reading substate: lock_handle = 9
[INFO] [stdout]         [2] Drop Lock: lock_handle = 9
[INFO] [stdout]         [2] Lock substate: node_id = KeyValueStore([175, 85, 112, 245, 161, 129, 11, 122, 247, 140, 175, 75, 199, 10, 102, 15, 13, 245, 30, 66, 186, 249, 29, 77, 229, 178, 50, 141, 224, 232, 61, 252, 22, 0, 0, 0]) offset = KeyValueStore(Entry([92, 176, 61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188])) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 10 
[INFO] [stdout]         [2] Get Mut: lock_handle = 10
[INFO] [stdout]         [2] Drop Lock: lock_handle = 10
[INFO] [stdout]         [2] Invoking: VaultTakeInvocation { receiver: [175, 85, 112, 245, 161, 129, 11, 122, 247, 140, 175, 75, 199, 10, 102, 15, 13, 245, 30, 66, 186, 249, 29, 77, 229, 178, 50, 141, 224, 232, 61, 252, 21, 0, 0, 0], amount: 1000 }
[INFO] [stdout]         [2] Lock substate: node_id = Vault([175, 85, 112, 245, 161, 129, 11, 122, 247, 140, 175, 75, 199, 10, 102, 15, 13, 245, 30, 66, 186, 249, 29, 77, 229, 178, 50, 141, 224, 232, 61, 252, 21, 0, 0, 0]) offset = Vault(Vault) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 11 
[INFO] [stdout]         [2] Reading substate: lock_handle = 11
[INFO] [stdout]         [2] Drop Lock: lock_handle = 11
[INFO] [stdout]         [2] Lock substate: node_id = Global(Resource(NormalResource[000000000000000000000000000000000000000000000000000000])) offset = VaultAccessRulesChain(AccessRulesChain) flags = (empty)
[INFO] [stdout]         [2] Lock substate: node_id = Global(Resource(NormalResource[000000000000000000000000000000000000000000000000000000])) offset = Global(Global) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 12 
[INFO] [stdout]         [2] Reading substate: lock_handle = 12
[INFO] [stdout]         [2] Lock acquired: lock_handle = 13 
[INFO] [stdout]         [2] Reading substate: lock_handle = 13
[INFO] [stdout]         [2] Drop Lock: lock_handle = 13
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 14 
[INFO] [stdout]         [2] Reading substate: lock_handle = 14
[INFO] [stdout]         [2] Drop Lock: lock_handle = 14
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 15 
[INFO] [stdout]         [2] Get Mut: lock_handle = 15
[INFO] [stdout]         [2] Drop Lock: lock_handle = 15
[INFO] [stdout]             [3] Lock substate: node_id = Vault([175, 85, 112, 245, 161, 129, 11, 122, 247, 140, 175, 75, 199, 10, 102, 15, 13, 245, 30, 66, 186, 249, 29, 77, 229, 178, 50, 141, 224, 232, 61, 252, 21, 0, 0, 0]) offset = Vault(Vault) flags = MUTABLE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 0 
[INFO] [stdout]             [3] Get Mut: lock_handle = 0
[INFO] [stdout]             [3] Creating node: node = Bucket(BucketSubstate { resource: RefCell { value: Fungible { resource_address: NormalResource[000000000000000000000000000000000000000000000000000000], divisibility: 18, locked_amounts: {}, liquid_amount: 1000 } } })
[INFO] [stdout]             [3] Lock substate: node_id = Bucket(2) offset = Bucket(Bucket) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 1 
[INFO] [stdout]             [3] Reading substate: lock_handle = 1
[INFO] [stdout]             [3] Drop Lock: lock_handle = 1
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 2 
[INFO] [stdout]             [3] Get Mut: lock_handle = 2
[INFO] [stdout]             [3] Drop Lock: lock_handle = 2
[INFO] [stdout]         [2] Exiting invoke: output = Bucket(2)
[INFO] [stdout]         [2] Get Mut: lock_handle = 2
[INFO] [stdout]         [2] Drop Lock: lock_handle = 2
[INFO] [stdout]         [2] Lock substate: node_id = Bucket(2) offset = Bucket(Bucket) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 16 
[INFO] [stdout]         [2] Reading substate: lock_handle = 16
[INFO] [stdout]         [2] Drop Lock: lock_handle = 16
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 17 
[INFO] [stdout]         [2] Get Mut: lock_handle = 17
[INFO] [stdout]         [2] Drop Lock: lock_handle = 17
[INFO] [stdout]     [1] Exiting invoke: output = Custom { value: Own(Bucket(2)) }
[INFO] [stdout]     [1] Invoking: WorktopPutInvocation { bucket: Bucket(2) }
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 27 
[INFO] [stdout]     [1] Reading substate: lock_handle = 27
[INFO] [stdout]     [1] Drop Lock: lock_handle = 27
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]     [1] Lock acquired: lock_handle = 28 
[INFO] [stdout]     [1] Get Mut: lock_handle = 28
[INFO] [stdout]     [1] Drop Lock: lock_handle = 28
[INFO] [stdout]     [1] Lock substate: node_id = Bucket(2) offset = Bucket(Bucket) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 29 
[INFO] [stdout]     [1] Reading substate: lock_handle = 29
[INFO] [stdout]     [1] Drop Lock: lock_handle = 29
[INFO] [stdout]         [2] Lock substate: node_id = Worktop offset = Worktop(Worktop) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 0 
[INFO] [stdout]         [2] Invoking: BucketGetResourceAddressInvocation { receiver: 2 }
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 1 
[INFO] [stdout]         [2] Reading substate: lock_handle = 1
[INFO] [stdout]         [2] Drop Lock: lock_handle = 1
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 2 
[INFO] [stdout]         [2] Get Mut: lock_handle = 2
[INFO] [stdout]         [2] Drop Lock: lock_handle = 2
[INFO] [stdout]             [3] Lock substate: node_id = Bucket(2) offset = Bucket(Bucket) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 0 
[INFO] [stdout]             [3] Reading substate: lock_handle = 0
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 1 
[INFO] [stdout]             [3] Get Mut: lock_handle = 1
[INFO] [stdout]             [3] Drop Lock: lock_handle = 1
[INFO] [stdout]         [2] Exiting invoke: output = NormalResource[000000000000000000000000000000000000000000000000000000]
[INFO] [stdout]         [2] Get Mut: lock_handle = 0
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 3 
[INFO] [stdout]         [2] Get Mut: lock_handle = 3
[INFO] [stdout]         [2] Drop Lock: lock_handle = 3
[INFO] [stdout]     [1] Exiting invoke: output = ()
[INFO] [stdout]     [1] Lock substate: node_id = TransactionRuntime(1) offset = TransactionRuntime(TransactionRuntime) flags = MUTABLE
[INFO] [stdout]     [1] Lock acquired: lock_handle = 30 
[INFO] [stdout]     [1] Get Mut: lock_handle = 30
[INFO] [stdout]     [1] Drop Lock: lock_handle = 30
[INFO] [stdout]     [1] Invoking: WorktopTakeAllInvocation { resource_address: NormalResource[000000000000000000000000000000000000000000000000000000] }
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 31 
[INFO] [stdout]     [1] Reading substate: lock_handle = 31
[INFO] [stdout]     [1] Drop Lock: lock_handle = 31
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]     [1] Lock acquired: lock_handle = 32 
[INFO] [stdout]     [1] Get Mut: lock_handle = 32
[INFO] [stdout]     [1] Drop Lock: lock_handle = 32
[INFO] [stdout]         [2] Lock substate: node_id = Worktop offset = Worktop(Worktop) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 0 
[INFO] [stdout]         [2] Get Mut: lock_handle = 0
[INFO] [stdout]         [2] Invoking: BucketGetAmountInvocation { receiver: 2 }
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 1 
[INFO] [stdout]         [2] Reading substate: lock_handle = 1
[INFO] [stdout]         [2] Drop Lock: lock_handle = 1
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 2 
[INFO] [stdout]         [2] Get Mut: lock_handle = 2
[INFO] [stdout]         [2] Drop Lock: lock_handle = 2
[INFO] [stdout]             [3] Lock substate: node_id = Bucket(2) offset = Bucket(Bucket) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 0 
[INFO] [stdout]             [3] Reading substate: lock_handle = 0
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 1 
[INFO] [stdout]             [3] Get Mut: lock_handle = 1
[INFO] [stdout]             [3] Drop Lock: lock_handle = 1
[INFO] [stdout]         [2] Exiting invoke: output = 1000
[INFO] [stdout]         [2] Invoking: BucketTakeInvocation { receiver: 2, amount: 1000 }
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 3 
[INFO] [stdout]         [2] Reading substate: lock_handle = 3
[INFO] [stdout]         [2] Drop Lock: lock_handle = 3
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 4 
[INFO] [stdout]         [2] Get Mut: lock_handle = 4
[INFO] [stdout]         [2] Drop Lock: lock_handle = 4
[INFO] [stdout]             [3] Lock substate: node_id = Bucket(2) offset = Bucket(Bucket) flags = MUTABLE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 0 
[INFO] [stdout]             [3] Get Mut: lock_handle = 0
[INFO] [stdout]             [3] Creating node: node = Bucket(BucketSubstate { resource: RefCell { value: Fungible { resource_address: NormalResource[000000000000000000000000000000000000000000000000000000], divisibility: 18, locked_amounts: {}, liquid_amount: 1000 } } })
[INFO] [stdout]             [3] Lock substate: node_id = Bucket(3) offset = Bucket(Bucket) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 1 
[INFO] [stdout]             [3] Reading substate: lock_handle = 1
[INFO] [stdout]             [3] Drop Lock: lock_handle = 1
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 2 
[INFO] [stdout]             [3] Get Mut: lock_handle = 2
[INFO] [stdout]             [3] Drop Lock: lock_handle = 2
[INFO] [stdout]         [2] Exiting invoke: output = Bucket(3)
[INFO] [stdout]         [2] Lock substate: node_id = Bucket(3) offset = Bucket(Bucket) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 5 
[INFO] [stdout]         [2] Reading substate: lock_handle = 5
[INFO] [stdout]         [2] Drop Lock: lock_handle = 5
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 6 
[INFO] [stdout]         [2] Get Mut: lock_handle = 6
[INFO] [stdout]         [2] Drop Lock: lock_handle = 6
[INFO] [stdout]     [1] Exiting invoke: output = Bucket(3)
[INFO] [stdout]     [1] Lock substate: node_id = TransactionRuntime(1) offset = TransactionRuntime(TransactionRuntime) flags = MUTABLE
[INFO] [stdout]     [1] Lock acquired: lock_handle = 33 
[INFO] [stdout]     [1] Get Mut: lock_handle = 33
[INFO] [stdout]     [1] Drop Lock: lock_handle = 33
[INFO] [stdout]     [1] Invoking: ScryptoInvocation { package_address: NormalPackage[010000000000000000000000000000000000000000000000000001], blueprint_name: "Account", fn_name: "new_with_resource", receiver: None, args: [92, 33, 2, 34, 2, 1, 34, 0, 1, 34, 0, 1, 34, 0, 1, 33, 2, 130, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 183, 2, 26, 46, 211, 41, 120, 92, 239, 41, 229, 98, 247, 52, 136, 200, 162, 187, 157, 188, 87, 0, 179, 97, 213, 75, 155, 5, 84, 144, 0, 3, 0, 0, 0] }
[INFO] [stdout]     [1] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000001])) offset = Package(Info) flags = (empty)
[INFO] [stdout]     [1] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000001])) offset = Global(Global) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 34 
[INFO] [stdout]     [1] Reading substate: lock_handle = 34
[INFO] [stdout]     [1] Lock acquired: lock_handle = 35 
[INFO] [stdout]     [1] Reading substate: lock_handle = 35
[INFO] [stdout]     [1] Drop Lock: lock_handle = 35
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 36 
[INFO] [stdout]     [1] Reading substate: lock_handle = 36
[INFO] [stdout]     [1] Drop Lock: lock_handle = 36
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]     [1] Lock acquired: lock_handle = 37 
[INFO] [stdout]     [1] Get Mut: lock_handle = 37
[INFO] [stdout]     [1] Drop Lock: lock_handle = 37
[INFO] [stdout]     [1] Lock substate: node_id = Bucket(3) offset = Bucket(Bucket) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 38 
[INFO] [stdout]     [1] Reading substate: lock_handle = 38
[INFO] [stdout]     [1] Drop Lock: lock_handle = 38
[INFO] [stdout]         [2] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000001])) offset = Package(Info) flags = (empty)
[INFO] [stdout]         [2] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000001])) offset = Global(Global) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 0 
[INFO] [stdout]         [2] Reading substate: lock_handle = 0
[INFO] [stdout]         [2] Lock acquired: lock_handle = 1 
[INFO] [stdout]         [2] Reading substate: lock_handle = 1
[INFO] [stdout]         [2] Drop Lock: lock_handle = 1
[INFO] [stdout]         [2] Creating node: node = KeyValueStore(KeyValueStore { loaded_entries: {} })
[INFO] [stdout]         [2] Invoking: ResourceManagerCreateVaultInvocation { receiver: NormalResource[000000000000000000000000000000000000000000000000000000] }
[INFO] [stdout]         [2] Lock substate: node_id = Global(Resource(NormalResource[000000000000000000000000000000000000000000000000000000])) offset = Global(Global) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 2 
[INFO] [stdout]         [2] Reading substate: lock_handle = 2
[INFO] [stdout]         [2] Lock substate: node_id = ResourceManager([175, 85, 112, 245, 161, 129, 11, 122, 247, 140, 175, 75, 199, 10, 102, 15, 13, 245, 30, 66, 186, 249, 29, 77, 229, 178, 50, 141, 224, 232, 61, 252, 3, 0, 0, 0]) offset = AccessRulesChain(AccessRulesChain) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 3 
[INFO] [stdout]         [2] Reading substate: lock_handle = 3
[INFO] [stdout]         [2] Drop Lock: lock_handle = 3
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 4 
[INFO] [stdout]         [2] Reading substate: lock_handle = 4
[INFO] [stdout]         [2] Drop Lock: lock_handle = 4
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 5 
[INFO] [stdout]         [2] Get Mut: lock_handle = 5
[INFO] [stdout]         [2] Drop Lock: lock_handle = 5
[INFO] [stdout]             [3] Lock substate: node_id = ResourceManager([175, 85, 112, 245, 161, 129, 11, 122, 247, 140, 175, 75, 199, 10, 102, 15, 13, 245, 30, 66, 186, 249, 29, 77, 229, 178, 50, 141, 224, 232, 61, 252, 3, 0, 0, 0]) offset = ResourceManager(ResourceManager) flags = MUTABLE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 0 
[INFO] [stdout]             [3] Reading substate: lock_handle = 0
[INFO] [stdout]             [3] Creating node: node = Vault(VaultRuntimeSubstate { resource: RefCell { value: Fungible { resource_address: NormalResource[000000000000000000000000000000000000000000000000000000], divisibility: 18, locked_amounts: {}, liquid_amount: 0 } } })
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 1 
[INFO] [stdout]             [3] Get Mut: lock_handle = 1
[INFO] [stdout]             [3] Drop Lock: lock_handle = 1
[INFO] [stdout]         [2] Exiting invoke: output = Vault([61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 6, 0, 0, 0])
[INFO] [stdout]         [2] Creating node: node = Component(ComponentInfoSubstate { package_address: NormalPackage[010000000000000000000000000000000000000000000000000001], blueprint_name: "Account" }, ComponentStateSubstate { raw: [92, 33, 1, 144, 4, 61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 4, 0, 0, 0] }, ComponentRoyaltyConfigSubstate { royalty_config: RoyaltyConfig { rules: {}, default_rule: 0 } }, ComponentRoyaltyAccumulatorSubstate { royalty: Vault([61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 6, 0, 0, 0]) }, MetadataSubstate { metadata: {} }, AccessRulesChainSubstate { access_rules_chain: [AccessRules { method_auth: {Native(Component(SetRoyaltyConfig)): Group("royalty"), Native(Component(ClaimRoyalty)): Group("royalty")}, grouped_auth: {"royalty": AllowAll}, default_auth: AllowAll, method_auth_mutability: {Native(Component(SetRoyaltyConfig)): DenyAll, Native(Component(ClaimRoyalty)): DenyAll}, grouped_auth_mutability: {"royalty": AllowAll}, default_auth_mutability: AllowAll }] })
[INFO] [stdout]         [2] Lock substate: node_id = Component([61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 5, 0, 0, 0]) offset = Component(Info) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 6 
[INFO] [stdout]         [2] Reading substate: lock_handle = 6
[INFO] [stdout]         [2] Drop Lock: lock_handle = 6
[INFO] [stdout]         [2] Invoking: ScryptoInvocation { package_address: NormalPackage[010000000000000000000000000000000000000000000000000001], blueprint_name: "Account", fn_name: "deposit", receiver: Some(Component([61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 5, 0, 0, 0])), args: [92, 33, 1, 144, 0, 3, 0, 0, 0] }
[INFO] [stdout]         [2] Lock substate: node_id = Component([61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 5, 0, 0, 0]) offset = Component(Info) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 7 
[INFO] [stdout]         [2] Reading substate: lock_handle = 7
[INFO] [stdout]         [2] Drop Lock: lock_handle = 7
[INFO] [stdout]         [2] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000001])) offset = Package(Info) flags = (empty)
[INFO] [stdout]         [2] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000001])) offset = Global(Global) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 8 
[INFO] [stdout]         [2] Reading substate: lock_handle = 8
[INFO] [stdout]         [2] Lock acquired: lock_handle = 9 
[INFO] [stdout]         [2] Reading substate: lock_handle = 9
[INFO] [stdout]         [2] Drop Lock: lock_handle = 9
[INFO] [stdout]         [2] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000001])) offset = Package(Info) flags = (empty)
[INFO] [stdout]         [2] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000001])) offset = Global(Global) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 10 
[INFO] [stdout]         [2] Reading substate: lock_handle = 10
[INFO] [stdout]         [2] Lock acquired: lock_handle = 11 
[INFO] [stdout]         [2] Reading substate: lock_handle = 11
[INFO] [stdout]         [2] Drop Lock: lock_handle = 11
[INFO] [stdout]         [2] Lock substate: node_id = Component([61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 5, 0, 0, 0]) offset = Component(State) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 12 
[INFO] [stdout]         [2] Reading substate: lock_handle = 12
[INFO] [stdout]         [2] Drop Lock: lock_handle = 12
[INFO] [stdout]         [2] Lock substate: node_id = Component([61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 5, 0, 0, 0]) offset = AccessRulesChain(AccessRulesChain) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 13 
[INFO] [stdout]         [2] Reading substate: lock_handle = 13
[INFO] [stdout]         [2] Drop Lock: lock_handle = 13
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 14 
[INFO] [stdout]         [2] Reading substate: lock_handle = 14
[INFO] [stdout]         [2] Drop Lock: lock_handle = 14
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 15 
[INFO] [stdout]         [2] Get Mut: lock_handle = 15
[INFO] [stdout]         [2] Drop Lock: lock_handle = 15
[INFO] [stdout]         [2] Lock substate: node_id = Bucket(3) offset = Bucket(Bucket) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 16 
[INFO] [stdout]         [2] Reading substate: lock_handle = 16
[INFO] [stdout]         [2] Drop Lock: lock_handle = 16
[INFO] [stdout]             [3] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000001])) offset = Package(Info) flags = (empty)
[INFO] [stdout]             [3] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000001])) offset = Global(Global) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 0 
[INFO] [stdout]             [3] Reading substate: lock_handle = 0
[INFO] [stdout]             [3] Lock acquired: lock_handle = 1 
[INFO] [stdout]             [3] Reading substate: lock_handle = 1
[INFO] [stdout]             [3] Drop Lock: lock_handle = 1
[INFO] [stdout]             [3] Lock substate: node_id = Component([61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 5, 0, 0, 0]) offset = Component(State) flags = MUTABLE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 2 
[INFO] [stdout]             [3] Reading substate: lock_handle = 2
[INFO] [stdout]             [3] Invoking: BucketGetResourceAddressInvocation { receiver: 3 }
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 3 
[INFO] [stdout]             [3] Reading substate: lock_handle = 3
[INFO] [stdout]             [3] Drop Lock: lock_handle = 3
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 4 
[INFO] [stdout]             [3] Get Mut: lock_handle = 4
[INFO] [stdout]             [3] Drop Lock: lock_handle = 4
[INFO] [stdout]                 [4] Lock substate: node_id = Bucket(3) offset = Bucket(Bucket) flags = (empty)
[INFO] [stdout]                 [4] Lock acquired: lock_handle = 0 
[INFO] [stdout]                 [4] Reading substate: lock_handle = 0
[INFO] [stdout]                 [4] Reading owned nodes
[INFO] [stdout]                 [4] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]                 [4] Lock acquired: lock_handle = 1 
[INFO] [stdout]                 [4] Get Mut: lock_handle = 1
[INFO] [stdout]                 [4] Drop Lock: lock_handle = 1
[INFO] [stdout]             [3] Exiting invoke: output = NormalResource[000000000000000000000000000000000000000000000000000000]
[INFO] [stdout]             [3] Lock substate: node_id = KeyValueStore([61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 4, 0, 0, 0]) offset = KeyValueStore(Entry([92, 130, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0])) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 5 
[INFO] [stdout]             [3] Reading substate: lock_handle = 5
[INFO] [stdout]             [3] Drop Lock: lock_handle = 5
[INFO] [stdout]             [3] Invoking: BucketGetResourceAddressInvocation { receiver: 3 }
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 6 
[INFO] [stdout]             [3] Reading substate: lock_handle = 6
[INFO] [stdout]             [3] Drop Lock: lock_handle = 6
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 7 
[INFO] [stdout]             [3] Get Mut: lock_handle = 7
[INFO] [stdout]             [3] Drop Lock: lock_handle = 7
[INFO] [stdout]                 [4] Lock substate: node_id = Bucket(3) offset = Bucket(Bucket) flags = (empty)
[INFO] [stdout]                 [4] Lock acquired: lock_handle = 0 
[INFO] [stdout]                 [4] Reading substate: lock_handle = 0
[INFO] [stdout]                 [4] Reading owned nodes
[INFO] [stdout]                 [4] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]                 [4] Lock acquired: lock_handle = 1 
[INFO] [stdout]                 [4] Get Mut: lock_handle = 1
[INFO] [stdout]                 [4] Drop Lock: lock_handle = 1
[INFO] [stdout]             [3] Exiting invoke: output = NormalResource[000000000000000000000000000000000000000000000000000000]
[INFO] [stdout]             [3] Invoking: ResourceManagerCreateVaultInvocation { receiver: NormalResource[000000000000000000000000000000000000000000000000000000] }
[INFO] [stdout]             [3] Lock substate: node_id = Global(Resource(NormalResource[000000000000000000000000000000000000000000000000000000])) offset = Global(Global) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 8 
[INFO] [stdout]             [3] Reading substate: lock_handle = 8
[INFO] [stdout]             [3] Lock substate: node_id = ResourceManager([175, 85, 112, 245, 161, 129, 11, 122, 247, 140, 175, 75, 199, 10, 102, 15, 13, 245, 30, 66, 186, 249, 29, 77, 229, 178, 50, 141, 224, 232, 61, 252, 3, 0, 0, 0]) offset = AccessRulesChain(AccessRulesChain) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 9 
[INFO] [stdout]             [3] Reading substate: lock_handle = 9
[INFO] [stdout]             [3] Drop Lock: lock_handle = 9
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 10 
[INFO] [stdout]             [3] Reading substate: lock_handle = 10
[INFO] [stdout]             [3] Drop Lock: lock_handle = 10
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 11 
[INFO] [stdout]             [3] Get Mut: lock_handle = 11
[INFO] [stdout]             [3] Drop Lock: lock_handle = 11
[INFO] [stdout]                 [4] Lock substate: node_id = ResourceManager([175, 85, 112, 245, 161, 129, 11, 122, 247, 140, 175, 75, 199, 10, 102, 15, 13, 245, 30, 66, 186, 249, 29, 77, 229, 178, 50, 141, 224, 232, 61, 252, 3, 0, 0, 0]) offset = ResourceManager(ResourceManager) flags = MUTABLE
[INFO] [stdout]                 [4] Lock acquired: lock_handle = 0 
[INFO] [stdout]                 [4] Reading substate: lock_handle = 0
[INFO] [stdout]                 [4] Creating node: node = Vault(VaultRuntimeSubstate { resource: RefCell { value: Fungible { resource_address: NormalResource[000000000000000000000000000000000000000000000000000000], divisibility: 18, locked_amounts: {}, liquid_amount: 0 } } })
[INFO] [stdout]                 [4] Reading owned nodes
[INFO] [stdout]                 [4] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]                 [4] Lock acquired: lock_handle = 1 
[INFO] [stdout]                 [4] Get Mut: lock_handle = 1
[INFO] [stdout]                 [4] Drop Lock: lock_handle = 1
[INFO] [stdout]             [3] Exiting invoke: output = Vault([61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 7, 0, 0, 0])
[INFO] [stdout]             [3] Invoking: VaultPutInvocation { receiver: [61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 7, 0, 0, 0], bucket: Bucket(3) }
[INFO] [stdout]             [3] Lock substate: node_id = Vault([61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 7, 0, 0, 0]) offset = Vault(Vault) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 12 
[INFO] [stdout]             [3] Reading substate: lock_handle = 12
[INFO] [stdout]             [3] Drop Lock: lock_handle = 12
[INFO] [stdout]             [3] Lock substate: node_id = Global(Resource(NormalResource[000000000000000000000000000000000000000000000000000000])) offset = VaultAccessRulesChain(AccessRulesChain) flags = (empty)
[INFO] [stdout]             [3] Lock substate: node_id = Global(Resource(NormalResource[000000000000000000000000000000000000000000000000000000])) offset = Global(Global) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 13 
[INFO] [stdout]             [3] Reading substate: lock_handle = 13
[INFO] [stdout]             [3] Lock acquired: lock_handle = 14 
[INFO] [stdout]             [3] Reading substate: lock_handle = 14
[INFO] [stdout]             [3] Drop Lock: lock_handle = 14
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 15 
[INFO] [stdout]             [3] Reading substate: lock_handle = 15
[INFO] [stdout]             [3] Drop Lock: lock_handle = 15
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 16 
[INFO] [stdout]             [3] Get Mut: lock_handle = 16
[INFO] [stdout]             [3] Drop Lock: lock_handle = 16
[INFO] [stdout]             [3] Lock substate: node_id = Bucket(3) offset = Bucket(Bucket) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 17 
[INFO] [stdout]             [3] Reading substate: lock_handle = 17
[INFO] [stdout]             [3] Drop Lock: lock_handle = 17
[INFO] [stdout]                 [4] Lock substate: node_id = Vault([61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 7, 0, 0, 0]) offset = Vault(Vault) flags = MUTABLE
[INFO] [stdout]                 [4] Lock acquired: lock_handle = 0 
[INFO] [stdout]                 [4] Dropping node: node_id = Bucket(3)
[INFO] [stdout]                 [4] Get Mut: lock_handle = 0
[INFO] [stdout]                 [4] Reading owned nodes
[INFO] [stdout]                 [4] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]                 [4] Lock acquired: lock_handle = 1 
[INFO] [stdout]                 [4] Get Mut: lock_handle = 1
[INFO] [stdout]                 [4] Drop Lock: lock_handle = 1
[INFO] [stdout]             [3] Exiting invoke: output = ()
[INFO] [stdout]             [3] Lock substate: node_id = KeyValueStore([61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 4, 0, 0, 0]) offset = KeyValueStore(Entry([92, 130, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0])) flags = MUTABLE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 18 
[INFO] [stdout]             [3] Get Mut: lock_handle = 18
[INFO] [stdout]             [3] Drop Lock: lock_handle = 18
[INFO] [stdout]             [3] Get Mut: lock_handle = 2
[INFO] [stdout]             [3] Drop Lock: lock_handle = 2
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 19 
[INFO] [stdout]             [3] Get Mut: lock_handle = 19
[INFO] [stdout]             [3] Drop Lock: lock_handle = 19
[INFO] [stdout]         [2] Exiting invoke: output = Tuple { fields: [] }
[INFO] [stdout]         [2] Invoking: AccessRulesAddAccessCheckInvocation { receiver: Component([61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 5, 0, 0, 0]), access_rules: AccessRules { method_auth: {ScryptoMethod("balance"): AccessRule(AllowAll), ScryptoMethod("deposit"): AccessRule(AllowAll), ScryptoMethod("deposit_batch"): AccessRule(AllowAll)}, grouped_auth: {}, default_auth: Protected(ProofRule(Require(StaticNonFungible(NormalResource[000000000000000000000000000000000000000000000000000001]:[2ed329785cef29e562f73488c8a2bb9dbc5700b361d54b9b0554])))), method_auth_mutability: {ScryptoMethod("balance"): DenyAll, ScryptoMethod("deposit"): DenyAll, ScryptoMethod("deposit_batch"): DenyAll}, grouped_auth_mutability: {}, default_auth_mutability: Protected(ProofRule(Require(StaticNonFungible(NormalResource[000000000000000000000000000000000000000000000000000001]:[2ed329785cef29e562f73488c8a2bb9dbc5700b361d54b9b0554])))) } }
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 17 
[INFO] [stdout]         [2] Reading substate: lock_handle = 17
[INFO] [stdout]         [2] Drop Lock: lock_handle = 17
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]             [3] Lock substate: node_id = Component([61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 5, 0, 0, 0]) offset = Component(Info) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 0 
[INFO] [stdout]             [3] Reading substate: lock_handle = 0
[INFO] [stdout]             [3] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000001])) offset = Package(Info) flags = (empty)
[INFO] [stdout]             [3] Lock substate: node_id = Global(Package(NormalPackage[010000000000000000000000000000000000000000000000000001])) offset = Global(Global) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 1 
[INFO] [stdout]             [3] Reading substate: lock_handle = 1
[INFO] [stdout]             [3] Lock acquired: lock_handle = 2 
[INFO] [stdout]             [3] Reading substate: lock_handle = 2
[INFO] [stdout]             [3] Lock substate: node_id = Component([61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 5, 0, 0, 0]) offset = AccessRulesChain(AccessRulesChain) flags = MUTABLE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 3 
[INFO] [stdout]             [3] Get Mut: lock_handle = 3
[INFO] [stdout]         [2] Exiting invoke: output = ()
[INFO] [stdout]         [2] Invoking: ComponentGlobalizeInvocation { component_id: [61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 5, 0, 0, 0] }
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 18 
[INFO] [stdout]         [2] Reading substate: lock_handle = 18
[INFO] [stdout]         [2] Drop Lock: lock_handle = 18
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 19 
[INFO] [stdout]         [2] Get Mut: lock_handle = 19
[INFO] [stdout]         [2] Drop Lock: lock_handle = 19
[INFO] [stdout]             [3] Lock substate: node_id = Component([61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 5, 0, 0, 0]) offset = Component(Info) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 0 
[INFO] [stdout]             [3] Reading substate: lock_handle = 0
[INFO] [stdout]             [3] Drop Lock: lock_handle = 0
[INFO] [stdout]             [3] Creating node: node = Global(Component([61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 5, 0, 0, 0]))
[INFO] [stdout]             [3] Lock substate: node_id = Component([61, 74, 202, 49, 0, 215, 16, 92, 133, 174, 234, 142, 200, 109, 7, 85, 127, 23, 168, 168, 206, 138, 239, 225, 23, 32, 120, 110, 143, 19, 184, 188, 5, 0, 0, 0]) offset = Component(Info) flags = (empty)
[INFO] [stdout]             [3] Lock acquired: lock_handle = 1 
[INFO] [stdout]             [3] Reading substate: lock_handle = 1
[INFO] [stdout]             [3] Drop Lock: lock_handle = 1
[INFO] [stdout]             [3] Reading owned nodes
[INFO] [stdout]             [3] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]             [3] Lock acquired: lock_handle = 2 
[INFO] [stdout]             [3] Get Mut: lock_handle = 2
[INFO] [stdout]             [3] Drop Lock: lock_handle = 2
[INFO] [stdout]         [2] Exiting invoke: output = AccountComponent[03e5be057f675d8482571ceac19b4cad25472ceca4e2729d34f468]
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 20 
[INFO] [stdout]         [2] Get Mut: lock_handle = 20
[INFO] [stdout]         [2] Drop Lock: lock_handle = 20
[INFO] [stdout]     [1] Exiting invoke: output = Custom { value: ComponentAddress(AccountComponent[03e5be057f675d8482571ceac19b4cad25472ceca4e2729d34f468]) }
[INFO] [stdout]     [1] Lock substate: node_id = TransactionRuntime(1) offset = TransactionRuntime(TransactionRuntime) flags = MUTABLE
[INFO] [stdout]     [1] Lock acquired: lock_handle = 39 
[INFO] [stdout]     [1] Get Mut: lock_handle = 39
[INFO] [stdout]     [1] Drop Lock: lock_handle = 39
[INFO] [stdout]     [1] Dropping node: node_id = TransactionRuntime(1)
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]     [1] Lock acquired: lock_handle = 40 
[INFO] [stdout]     [1] Get Mut: lock_handle = 40
[INFO] [stdout]     [1] Drop Lock: lock_handle = 40
[INFO] [stdout]     [1] Dropping node: node_id = Worktop
[INFO] [stdout]     [1] Lock substate: node_id = Worktop offset = Worktop(Worktop) flags = MUTABLE
[INFO] [stdout]     [1] Lock acquired: lock_handle = 41 
[INFO] [stdout]     [1] Get Mut: lock_handle = 41
[INFO] [stdout]     [1] Invoking: BucketGetAmountInvocation { receiver: 2 }
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = (empty)
[INFO] [stdout]     [1] Lock acquired: lock_handle = 42 
[INFO] [stdout]     [1] Reading substate: lock_handle = 42
[INFO] [stdout]     [1] Drop Lock: lock_handle = 42
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Reading owned nodes
[INFO] [stdout]     [1] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]     [1] Lock acquired: lock_handle = 43 
[INFO] [stdout]     [1] Get Mut: lock_handle = 43
[INFO] [stdout]     [1] Drop Lock: lock_handle = 43
[INFO] [stdout]         [2] Lock substate: node_id = Bucket(2) offset = Bucket(Bucket) flags = (empty)
[INFO] [stdout]         [2] Lock acquired: lock_handle = 0 
[INFO] [stdout]         [2] Reading substate: lock_handle = 0
[INFO] [stdout]         [2] Reading owned nodes
[INFO] [stdout]         [2] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout]         [2] Lock acquired: lock_handle = 1 
[INFO] [stdout]         [2] Get Mut: lock_handle = 1
[INFO] [stdout]         [2] Drop Lock: lock_handle = 1
[INFO] [stdout]     [1] Exiting invoke: output = 0
[INFO] [stdout]     [1] Drop Lock: lock_handle = 41
[INFO] [stdout] [0] Dropping node: node_id = AuthZoneStack(0)
[INFO] [stdout] [0] Lock substate: node_id = AuthZoneStack(0) offset = AuthZoneStack(AuthZoneStack) flags = MUTABLE
[INFO] [stdout] [0] Lock acquired: lock_handle = 2 
[INFO] [stdout] [0] Get Mut: lock_handle = 2
[INFO] [stdout] [0] Drop Lock: lock_handle = 2
[INFO] [stdout] [0] Exiting invoke: output = [Scrypto(Tuple()), Scrypto(Own("Bucket(2)")), Native(ManifestBucket(0)), Scrypto(ComponentAddress("AccountComponent[03e5be057f675d8482571ceac19b4cad25472ceca4e2729d34f468]"))]
[INFO] [stdout] ---------------------------------Cost Analysis----------------------------------
[INFO] [stdout] create_node                   :      27500
[INFO] [stdout] drop_lock                     :      59500
[INFO] [stdout] drop_node                     :      10000
[INFO] [stdout] instantiate_wasm              :    1081730
[INFO] [stdout] invoke                        :      10500
[INFO] [stdout] lock_substate                 :      80500
[INFO] [stdout] read_owned_nodes              :      55000
[INFO] [stdout] read_substate                 :     260000
[INFO] [stdout] run_native_method             :      34000
[INFO] [stdout] run_wasm                      :    1657010
[INFO] [stdout] tx_base_fee                   :      50000
[INFO] [stdout] tx_payload_cost               :       1580
[INFO] [stdout] tx_signature_verification     :          0
[INFO] [stdout] write_substate                :     152500
[INFO] [stdout] ----------------------------------Cost Totals-----------------------------------
[INFO] [stdout] Total Cost Units Consumed     :    3479820
[INFO] [stdout] Cost Unit Limit               :  100000000
[INFO] [stdout] Execution XRD                 :   0.347982
[INFO] [stdout] Royalty XRD                   :          0
[INFO] [stdout] --------------------------------Application Logs--------------------------------
[INFO] [stdout] None
[INFO] [stdout] 
[INFO] [stdout] thread 'test_hello' (18) panicked at /opt/rustwide/cargo-home/git/checkouts/radixdlt-scrypto-48b6acf686a86e20/e9381b7/scrypto-unit/src/test_runner.rs:54:13:
[INFO] [stdout] Failed to compile package: "/opt/rustwide/workdir"
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56e0e69a8372 - std::backtrace_rs::backtrace::libunwind::trace::h90e89fac33585832
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x56e0e69a8372 - std::backtrace_rs::backtrace::trace_unsynchronized::heebcf2380a8e7359
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x56e0e69a8372 - std::sys::backtrace::_print_fmt::hdf3e2a22d2f810db
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/sys/backtrace.rs:68:9
[INFO] [stdout]    3:     0x56e0e69a8372 - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::hac1d885928ba8582
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/sys/backtrace.rs:38:26
[INFO] [stdout]    4:     0x56e0e69b9fd7 - core::fmt::rt::Argument::fmt::h6901eb7c19abebe8
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x56e0e69b9fd7 - core::fmt::write::h83ebb4d32483be9e
[INFO] [stdout]    6:     0x56e0e6976856 - std::io::default_write_fmt::h751a79cdae495f10
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x56e0e6976856 - std::io::Write::write_fmt::h9ef514f8700ff7b2
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x56e0e69875e9 - std::sys::backtrace::BacktraceLock::print::hcc2e1a3ac996c911
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/sys/backtrace.rs:41:9
[INFO] [stdout]    9:     0x56e0e69875e9 - std::panicking::default_hook::{{closure}}::h8e9c4d1276f0925f
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x56e0e6987481 - std::panicking::default_hook::h2b2078d38b534dfb
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x56e0e5fcafbe - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h39d051e7dedac6a7
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   12:     0x56e0e5fcafbe - test::test_main_with_exit_callback::{{closure}}::h4b005fe75814c302
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x56e0e6987862 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hd426070722c13cbf
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   14:     0x56e0e6987862 - std::panicking::panic_with_hook::h39b739724e701bfd
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x56e0e69876a8 - std::panicking::panic_handler::{{closure}}::he540c4833054e458
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x56e0e69826b9 - std::sys::backtrace::__rust_end_short_backtrace::hfa179d89deec8aed
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/sys/backtrace.rs:176:18
[INFO] [stdout]   17:     0x56e0e696ab6d - __rustc[d131491b17107b07]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x56e0e69c2bfc - core::panicking::panic_fmt::ha564519d657d9c46
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x56e0e6331293 - scrypto_unit::test_runner::Compile::compile::ha68a64ed89d13202
[INFO] [stdout]                                at /opt/rustwide/cargo-home/git/checkouts/radixdlt-scrypto-48b6acf686a86e20/e9381b7/scrypto-unit/src/test_runner.rs:54:13
[INFO] [stdout]   20:     0x56e0e632e536 - scrypto_unit::test_runner::TestRunner::compile_and_publish::h91822d8e6f4cdfbc
[INFO] [stdout]                                at /opt/rustwide/cargo-home/git/checkouts/radixdlt-scrypto-48b6acf686a86e20/e9381b7/scrypto-unit/src/test_runner.rs:551:27
[INFO] [stdout]   21:     0x56e0e5faa41a - lib::test_hello::h7413731002a2f1bd
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/lib.rs:15:39
[INFO] [stdout]   22:     0x56e0e5fab727 - lib::test_hello::{{closure}}::hb953893b8a496f96
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/lib.rs:7:16
[INFO] [stdout]   23:     0x56e0e5fab8f6 - core::ops::function::FnOnce::call_once::hdedb267a4ec05244
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x56e0e5fcadeb - core::ops::function::FnOnce::call_once::h0217b30cfdee2e67
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x56e0e5fcadeb - test::__rust_begin_short_backtrace::h6d98714a1e28f556
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/test/src/lib.rs:663:18
[INFO] [stdout]   26:     0x56e0e5fde77a - test::run_test_in_process::{{closure}}::he2c70d2634d3a3cc
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/test/src/lib.rs:686:74
[INFO] [stdout]   27:     0x56e0e5fde77a - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h40a0a825bcf17fdd
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   28:     0x56e0e5fde77a - std::panicking::catch_unwind::do_call::h6c8eb5dd6e7b5975
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x56e0e5fde77a - std::panicking::catch_unwind::h55455eac2972ad1e
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x56e0e5fde77a - std::panic::catch_unwind::h0025465150a5e558
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x56e0e5fde77a - test::run_test_in_process::hb61e682f9b630fbc
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/test/src/lib.rs:686:27
[INFO] [stdout]   32:     0x56e0e5fde77a - test::run_test::{{closure}}::hc2c2a7a38ab176e2
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/test/src/lib.rs:607:43
[INFO] [stdout]   33:     0x56e0e5fb8a14 - test::run_test::{{closure}}::ha91867b85cd2a911
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/test/src/lib.rs:637:41
[INFO] [stdout]   34:     0x56e0e5fb8a14 - std::sys::backtrace::__rust_begin_short_backtrace::ha00a9fe02049c339
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/sys/backtrace.rs:160:18
[INFO] [stdout]   35:     0x56e0e5fbc342 - std::thread::lifecycle::spawn_unchecked::{{closure}}::{{closure}}::hd629fba9153dfb55
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/thread/lifecycle.rs:92:13
[INFO] [stdout]   36:     0x56e0e5fbc342 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h4189018816a5c5d9
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   37:     0x56e0e5fbc342 - std::panicking::catch_unwind::do_call::hf9fcdbca3c2e9877
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x56e0e5fbc342 - std::panicking::catch_unwind::h97b92d88631c7c64
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x56e0e5fbc342 - std::panic::catch_unwind::ha77cdbb68e725e5e
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x56e0e5fbc342 - std::thread::lifecycle::spawn_unchecked::{{closure}}::h511f05bc1a132253
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/thread/lifecycle.rs:90:26
[INFO] [stdout]   41:     0x56e0e5fbc342 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h6b68b31e9b685cb8
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x56e0e697db8f - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h22a27d165e4e871d
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/alloc/src/boxed.rs:2206:9
[INFO] [stdout]   43:     0x56e0e697db8f - std::sys::thread::unix::Thread::new::thread_start::h45cc87bb053add0f
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x71dc5a29baa4 - <unknown>
[INFO] [stdout]   45:     0x71dc5a328a64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     test_hello
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.32s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test lib`
[INFO] running `Command { std: "docker" "inspect" "2b60ea01628796596198934c2e677efb51455189277dcd39a6ed8420bb038eda", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2b60ea01628796596198934c2e677efb51455189277dcd39a6ed8420bb038eda", kill_on_drop: false }`
[INFO] [stdout] 2b60ea01628796596198934c2e677efb51455189277dcd39a6ed8420bb038eda
