[INFO] cloning repository https://github.com/Bazooh/RustLox
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/Bazooh/RustLox" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBazooh%2FRustLox", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBazooh%2FRustLox'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] f0dfc0cf917f5997c94a6cb5556b8b9c354da810
[INFO] testing Bazooh/RustLox against 1.98.0-beta.1 for beta-1.98-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBazooh%2FRustLox" "/workspace/builds/worker-7-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-7-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/Bazooh/RustLox
[INFO] finished tweaking git repo https://github.com/Bazooh/RustLox
[INFO] tweaked toml for git repo https://github.com/Bazooh/RustLox written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/Bazooh/RustLox on toolchain 1.98.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.98.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/Bazooh/RustLox 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.98.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3d5ced03c013a94a2f102a4510f48a6e9184255caf5fd8244f58017bde7f5210" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 7423b470e690500322db77f5c59bea80443b1f5c81433d16425fc6dc22a4f3a2
[INFO] running `Command { std: "docker" "start" "7423b470e690500322db77f5c59bea80443b1f5c81433d16425fc6dc22a4f3a2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "7423b470e690500322db77f5c59bea80443b1f5c81433d16425fc6dc22a4f3a2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "7423b470e690500322db77f5c59bea80443b1f5c81433d16425fc6dc22a4f3a2" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "7423b470e690500322db77f5c59bea80443b1f5c81433d16425fc6dc22a4f3a2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "7423b470e690500322db77f5c59bea80443b1f5c81433d16425fc6dc22a4f3a2" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.104
[INFO] [stderr]    Compiling quote v1.0.42
[INFO] [stderr]    Compiling unicode-ident v1.0.22
[INFO] [stderr]    Compiling winnow v0.7.14
[INFO] [stderr]    Compiling memchr v2.7.6
[INFO] [stderr]    Compiling equivalent v1.0.2
[INFO] [stderr]    Compiling semver v1.0.27
[INFO] [stderr]    Compiling hashbrown v0.16.1
[INFO] [stderr]    Compiling toml_datetime v0.7.5+spec-1.1.0
[INFO] [stderr]    Compiling regex-syntax v0.8.8
[INFO] [stderr]    Compiling cfg-if v1.0.4
[INFO] [stderr]    Compiling futures-task v0.3.31
[INFO] [stderr]    Compiling slab v0.4.11
[INFO] [stderr]    Compiling rustc_version v0.4.1
[INFO] [stderr]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling relative-path v1.9.3
[INFO] [stderr]    Compiling glob v0.3.3
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling rstest_macros v0.26.1
[INFO] [stderr]    Compiling pin-project-lite v0.2.16
[INFO] [stderr]    Compiling indexmap v2.13.0
[INFO] [stderr]    Compiling futures-timer v3.0.3
[INFO] [stderr]    Compiling strum v0.27.2
[INFO] [stderr]    Compiling syn v2.0.112
[INFO] [stderr]    Compiling toml_parser v1.0.6+spec-1.1.0
[INFO] [stderr]    Compiling toml_edit v0.23.10+spec-1.0.0
[INFO] [stderr]    Compiling regex-automata v0.4.13
[INFO] [stderr]    Compiling proc-macro-crate v3.4.0
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling strum_macros v0.27.2
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling rstest v0.26.1
[INFO] [stderr]    Compiling RustLox v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: method `repr` is never used
[INFO] [stdout]   --> src/instruction.rs:96:12
[INFO] [stdout]    |
[INFO] [stdout] 95 | impl Instruction {
[INFO] [stdout]    | ---------------- method in this implementation
[INFO] [stdout] 96 |     pub fn repr(&self) -> String {
[INFO] [stdout]    |            ^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `parse_expect` is never used
[INFO] [stdout]  --> src/test.rs:6:4
[INFO] [stdout]   |
[INFO] [stdout] 6 | fn parse_expect(content: &str) -> (String, String) {
[INFO] [stdout]   |    ^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `test_file` is never used
[INFO] [stdout]   --> src/test.rs:35:4
[INFO] [stdout]    |
[INFO] [stdout] 35 | fn test_file(path: &str, func: fn(&str) -> (String, String)) {
[INFO] [stdout]    |    ^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `test_interpret_file` is never used
[INFO] [stdout]   --> src/test.rs:91:4
[INFO] [stdout]    |
[INFO] [stdout] 91 | fn test_interpret_file(path: &str) {
[INFO] [stdout]    |    ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `test_tokenize_file` is never used
[INFO] [stdout]    --> src/test.rs:108:4
[INFO] [stdout]     |
[INFO] [stdout] 108 | fn test_tokenize_file(path: &str) {
[INFO] [stdout]     |    ^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 37.56s
[INFO] running `Command { std: "docker" "inspect" "7423b470e690500322db77f5c59bea80443b1f5c81433d16425fc6dc22a4f3a2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "7423b470e690500322db77f5c59bea80443b1f5c81433d16425fc6dc22a4f3a2" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling RustLox v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: method `repr` is never used
[INFO] [stdout]   --> src/instruction.rs:96:12
[INFO] [stdout]    |
[INFO] [stdout] 95 | impl Instruction {
[INFO] [stdout]    | ---------------- method in this implementation
[INFO] [stdout] 96 |     pub fn repr(&self) -> String {
[INFO] [stdout]    |            ^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.26s
[INFO] running `Command { std: "docker" "inspect" "7423b470e690500322db77f5c59bea80443b1f5c81433d16425fc6dc22a4f3a2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "7423b470e690500322db77f5c59bea80443b1f5c81433d16425fc6dc22a4f3a2" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr] warning: method `repr` is never used
[INFO] [stderr]   --> src/instruction.rs:96:12
[INFO] [stderr]    |
[INFO] [stderr] 95 | impl Instruction {
[INFO] [stderr]    | ---------------- method in this implementation
[INFO] [stderr] 96 |     pub fn repr(&self) -> String {
[INFO] [stderr]    |            ^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `RustLox` (bin "RustLox" test) generated 1 warning
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.11s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/RustLox-3ae095414c4225f8)
[INFO] [stdout] 
[INFO] [stdout] running 31 tests
[INFO] [stdout] test test::test_empty_file ... ok
[INFO] [stdout] test test::test_module::case_02_block ... ok
[INFO] [stdout] test test::test_module::case_03_bool ... ok
[INFO] [stdout] test test::test_module::case_09_expressions ... ok
[INFO] [stdout] test test::test_module::case_04_call ... ok
[INFO] [stdout] test test::test_module::case_07_comments ... ok
[INFO] [stdout] test test::test_module::case_08_constructor ... FAILED
[INFO] [stdout] test test::test_module::case_01_assignment ... ok
[INFO] [stdout] test test::test_module::case_05_class ... FAILED
[INFO] [stdout] test test::test_module::case_15_logical_operator ... ok
[INFO] [stdout] test test::test_module::case_17_nil ... ok
[INFO] [stdout] test test::test_module::case_11_for_ ... ok
[INFO] [stdout] test test::test_module::case_14_inheritance ... FAILED
[INFO] [stdout] test test::test_module::case_20_print ... ok
[INFO] [stdout] test test::test_module::case_13_if_ ... ok
[INFO] [stdout] test test::test_module::case_18_number ... ok
[INFO] [stdout] test test::test_module::case_06_closure ... ok
[INFO] [stdout] test test::test_module::case_21_regression ... ok
[INFO] [stdout] test test::test_module::case_25_this ... FAILED
[INFO] [stdout] test test::test_module::case_24_super ... FAILED
[INFO] [stdout] test test::test_module::case_23_string ... ok
[INFO] [stdout] test test::test_precedence ... ok
[INFO] [stdout] test test::test_module::case_12_function ... ok
[INFO] [stdout] test test::test_module::case_27_while_ ... ok
[INFO] [stdout] test test::test_module::case_16_method ... ok
[INFO] [stdout] test test::test_module::case_22_return_ ... ok
[INFO] [stdout] test test::test_unexpected_character ... ok
[INFO] [stdout] test test::test_tokenize::case_1 ... ok
[INFO] [stdout] test test::test_module::case_26_variable ... ok
[INFO] [stdout] test test::test_module::case_19_operator ... ok
[INFO] [stdout] test test::test_module::case_10_field ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_08_constructor stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 15,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     Constant(
[INFO] [stdout]         1.0,
[INFO] [stdout]     ),
[INFO] [stdout]     Constant(
[INFO] [stdout]         2.0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 3, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: CreateGlobal { string_index: 3 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: Constant(1.0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Constant(2.0) Stack: ["1"]
[INFO] [stdout] GC: checking value "1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: GetGlobal { string_index: 3 } Stack: ["1", "2"]
[INFO] [stdout] GC: checking value "1"
[INFO] [stdout] GC: checking value "2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Call { arg_count: 2 } Stack: ["1", "2", "<class Foo>"]
[INFO] [stdout] GC: checking value "1"
[INFO] [stdout] GC: checking value "2"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 16: String(0) Stack: ["1", "2"]
[INFO] [stdout] GC: checking value "1"
[INFO] [stdout] GC: checking value "2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>"]
[INFO] [stdout] FREEING: ["<fn init>", "<Foo instance>"]
[INFO] [stdout] Executing byte at 17: Print Stack: ["1", "2", "init"]
[INFO] [stdout] GC: checking value "1"
[INFO] [stdout] GC: checking value "2"
[INFO] [stdout] GC: checking value "init"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["init", "<class Foo>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 18: GetCaptured(0) Stack: ["1", "2"]
[INFO] [stdout] GC: checking value "1"
[INFO] [stdout] GC: checking value "2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>"]
[INFO] [stdout] FREEING: ["init"]
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_08_constructor' (676) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x64a6e48bee41 - std[73adb7dc35730857]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x64a6e48bee41 - std[73adb7dc35730857]::backtrace_rs::backtrace::trace_unsynchronized::<std[73adb7dc35730857]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x64a6e48bee41 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x64a6e48bee41 - <<std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6883ba1bc0fe4ed1]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x64a6e48d613a - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x64a6e48d613a - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stdout]    6:     0x64a6e48c35cc - std[73adb7dc35730857]::io::default_write_fmt::<alloc[55a36b64bcbf2c0d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:626:11
[INFO] [stdout]    7:     0x64a6e48c35cc - <alloc[55a36b64bcbf2c0d]::vec::Vec<u8> as std[73adb7dc35730857]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:1730:13
[INFO] [stdout]    8:     0x64a6e489cf16 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x64a6e489cf16 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x64a6e48b6ae9 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x64a6e45df670 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   12:     0x64a6e45df670 - test[980ffaebd391d06d]::test_main_inner::<test[980ffaebd391d06d]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x64a6e48b6ca2 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   14:     0x64a6e48b6ca2 - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x64a6e489cfc2 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:688:13
[INFO] [stdout]   16:     0x64a6e48946f9 - std[73adb7dc35730857]::sys::backtrace::__rust_end_short_backtrace::<std[73adb7dc35730857]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x64a6e489dbbd - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x64a6e48d69bc - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x64a6e48d66b4 - core[6883ba1bc0fe4ed1]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x64a6e48d66b4 - core[6883ba1bc0fe4ed1]::option::expect_failed
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/option.rs:2260:5
[INFO] [stdout]   21:     0x64a6e45a5fc0 - <core[6883ba1bc0fe4ed1]::option::Option<&RustLox[79fb2770ae4f4ed6]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/option.rs:969:21
[INFO] [stdout]   22:     0x64a6e45c52ae - <RustLox[79fb2770ae4f4ed6]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x64a6e45bc8ad - <RustLox[79fb2770ae4f4ed6]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x64a6e459683a - <RustLox[79fb2770ae4f4ed6]::interpreter::Interpreter<&mut alloc[55a36b64bcbf2c0d]::string::String, &mut alloc[55a36b64bcbf2c0d]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x64a6e4598de6 - <RustLox[79fb2770ae4f4ed6]::interpreter::Interpreter<&mut alloc[55a36b64bcbf2c0d]::string::String, &mut alloc[55a36b64bcbf2c0d]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:664:38
[INFO] [stdout]   26:     0x64a6e45a30ba - <RustLox[79fb2770ae4f4ed6]::interpreter::Interpreter<&mut alloc[55a36b64bcbf2c0d]::string::String, &mut alloc[55a36b64bcbf2c0d]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x64a6e45bb01d - RustLox[79fb2770ae4f4ed6]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x64a6e45b160a - <RustLox[79fb2770ae4f4ed6]::test::test_interpret_file::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x64a6e45be4b8 - RustLox[79fb2770ae4f4ed6]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x64a6e45be24a - RustLox[79fb2770ae4f4ed6]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x64a6e45bd17d - RustLox[79fb2770ae4f4ed6]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x64a6e45bfb62 - RustLox[79fb2770ae4f4ed6]::test::test_module::case_08_constructor
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x64a6e45bb7a7 - RustLox[79fb2770ae4f4ed6]::test::test_module::case_08_constructor::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x64a6e45b1c46 - <RustLox[79fb2770ae4f4ed6]::test::test_module::case_08_constructor::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x64a6e45d29ab - <fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x64a6e45d29ab - test[980ffaebd391d06d]::__rust_begin_short_backtrace::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:724:18
[INFO] [stdout]   37:     0x64a6e45dfff5 - test[980ffaebd391d06d]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]   38:     0x64a6e45dfff5 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x64a6e45dfff5 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   40:     0x64a6e45dfff5 - std[73adb7dc35730857]::panicking::catch_unwind::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x64a6e45dfff5 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x64a6e45dfff5 - test[980ffaebd391d06d]::run_test_in_process
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   43:     0x64a6e45dfff5 - test[980ffaebd391d06d]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   44:     0x64a6e45daaa4 - test[980ffaebd391d06d]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   45:     0x64a6e45daaa4 - std[73adb7dc35730857]::sys::backtrace::__rust_begin_short_backtrace::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x64a6e45e3142 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   47:     0x64a6e45e3142 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x64a6e45e3142 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   49:     0x64a6e45e3142 - std[73adb7dc35730857]::panicking::catch_unwind::<(), core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x64a6e45e3142 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x64a6e45e3142 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   52:     0x64a6e45e3142 - <std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x64a6e48be35f - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2319:9
[INFO] [stdout]   54:     0x64a6e48be35f - <std[73adb7dc35730857]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   55:     0x70504eaedaa4 - <unknown>
[INFO] [stdout]   56:     0x70504eb7aa64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_05_class stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 0, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: GetGlobal { string_index: 0 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: Print Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 9,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 1, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: CreateGlobal { string_index: 1 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: GetGlobal { string_index: 1 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Call { arg_count: 0 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: DotProperty(0) Stack: ["<Foo instance>"]
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<Foo instance>", "<fn returnSelf>", "<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Call { arg_count: 0 } Stack: ["<fn returnSelf>"]
[INFO] [stdout] GC: checking value "<fn returnSelf>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn returnSelf>", "<fn returnSelf>", "<Foo instance>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: GetGlobal { string_index: 1 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: ["<fn returnSelf>", "<Foo instance>"]
[INFO] [stdout] Executing byte at 11: Return Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Print Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn returnSelf>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 0, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: GetGlobal { string_index: 0 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: Call { arg_count: 0 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: CreateGlobal { string_index: 1 } Stack: ["<Foo instance>"]
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<Foo instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: GetGlobal { string_index: 1 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<Foo instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Call { arg_count: 0 } Stack: ["<Foo instance>"]
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: reachable: ["<Foo instance>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 9,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Capture(
[INFO] [stdout]         Local(
[INFO] [stdout]             0,
[INFO] [stdout]         ),
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 1, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: GetLocal(0) Stack: ["&<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["&<pointer 1>", "<fn returnSelf>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: Call { arg_count: 0 } Stack: ["&<class Foo>", "<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn returnSelf>", "<class Foo>", "&<pointer 1>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: DotProperty(0) Stack: ["&<class Foo>", "<Foo instance>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn returnSelf>", "<fn returnSelf>", "&<pointer 1>", "<Foo instance>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: Call { arg_count: 0 } Stack: ["&<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<fn returnSelf>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["&<pointer 1>", "<fn returnSelf>", "<fn returnSelf>", "<Foo instance>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: Capture(Local(0)) Stack: ["&<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>", "&<pointer 1>"]
[INFO] [stdout] FREEING: ["<fn returnSelf>", "<Foo instance>"]
[INFO] [stdout] Executing byte at 11: GetCaptured(2) Stack: ["&<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn returnSelf>", "&<pointer 1>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 12: Return Stack: ["&<class Foo>", "<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["&<pointer 1>", "<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Print Stack: ["&<class Foo>", "<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>", "&<pointer 1>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Pop(1) Stack: ["&<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["&<pointer 1>", "<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: ["&<pointer 1>", "<fn returnSelf>", "<class Foo>"]
[INFO] [stdout] [
[INFO] [stdout]     Constant(
[INFO] [stdout]         0.0,
[INFO] [stdout]     ),
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 25,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 31,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Constant(
[INFO] [stdout]         1.0,
[INFO] [stdout]     ),
[INFO] [stdout]     Add,
[INFO] [stdout]     AssignGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Constant(0.0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["0"]
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: Class { name_index: 4, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: CreateGlobal { string_index: 4 } Stack: ["<class A>"]
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class A>", "<fn print_id>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: GetGlobal { string_index: 4 } Stack: []
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class A>", "<fn print_id>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Call { arg_count: 0 } Stack: ["<class A>"]
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn init>", "<fn print_id>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 32: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn print_id>", "<fn init>"]
[INFO] [stdout] FREEING: ["<A instance>", "<fn init>", "<fn print_id>"]
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_05_class' (673) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x64a6e48bee41 - std[73adb7dc35730857]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x64a6e48bee41 - std[73adb7dc35730857]::backtrace_rs::backtrace::trace_unsynchronized::<std[73adb7dc35730857]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x64a6e48bee41 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x64a6e48bee41 - <<std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6883ba1bc0fe4ed1]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x64a6e48d613a - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x64a6e48d613a - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stdout]    6:     0x64a6e48c35cc - std[73adb7dc35730857]::io::default_write_fmt::<alloc[55a36b64bcbf2c0d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:626:11
[INFO] [stdout]    7:     0x64a6e48c35cc - <alloc[55a36b64bcbf2c0d]::vec::Vec<u8> as std[73adb7dc35730857]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:1730:13
[INFO] [stdout]    8:     0x64a6e489cf16 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x64a6e489cf16 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x64a6e48b6ae9 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x64a6e45df670 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   12:     0x64a6e45df670 - test[980ffaebd391d06d]::test_main_inner::<test[980ffaebd391d06d]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x64a6e48b6ca2 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   14:     0x64a6e48b6ca2 - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x64a6e489cfc2 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:688:13
[INFO] [stdout]   16:     0x64a6e48946f9 - std[73adb7dc35730857]::sys::backtrace::__rust_end_short_backtrace::<std[73adb7dc35730857]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x64a6e489dbbd - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x64a6e48d69bc - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x64a6e48d66b4 - core[6883ba1bc0fe4ed1]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x64a6e48d66b4 - core[6883ba1bc0fe4ed1]::option::expect_failed
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/option.rs:2260:5
[INFO] [stdout]   21:     0x64a6e45a5fc0 - <core[6883ba1bc0fe4ed1]::option::Option<&RustLox[79fb2770ae4f4ed6]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/option.rs:969:21
[INFO] [stdout]   22:     0x64a6e45c52ae - <RustLox[79fb2770ae4f4ed6]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x64a6e45bc8ad - <RustLox[79fb2770ae4f4ed6]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x64a6e459683a - <RustLox[79fb2770ae4f4ed6]::interpreter::Interpreter<&mut alloc[55a36b64bcbf2c0d]::string::String, &mut alloc[55a36b64bcbf2c0d]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x64a6e4598de6 - <RustLox[79fb2770ae4f4ed6]::interpreter::Interpreter<&mut alloc[55a36b64bcbf2c0d]::string::String, &mut alloc[55a36b64bcbf2c0d]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:664:38
[INFO] [stdout]   26:     0x64a6e45a30ba - <RustLox[79fb2770ae4f4ed6]::interpreter::Interpreter<&mut alloc[55a36b64bcbf2c0d]::string::String, &mut alloc[55a36b64bcbf2c0d]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x64a6e45bb01d - RustLox[79fb2770ae4f4ed6]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x64a6e45b160a - <RustLox[79fb2770ae4f4ed6]::test::test_interpret_file::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x64a6e45be4b8 - RustLox[79fb2770ae4f4ed6]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x64a6e45be24a - RustLox[79fb2770ae4f4ed6]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x64a6e45bd17d - RustLox[79fb2770ae4f4ed6]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x64a6e45bf762 - RustLox[79fb2770ae4f4ed6]::test::test_module::case_05_class
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x64a6e45bb4a7 - RustLox[79fb2770ae4f4ed6]::test::test_module::case_05_class::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x64a6e45b1846 - <RustLox[79fb2770ae4f4ed6]::test::test_module::case_05_class::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x64a6e45d29ab - <fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x64a6e45d29ab - test[980ffaebd391d06d]::__rust_begin_short_backtrace::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:724:18
[INFO] [stdout]   37:     0x64a6e45dfff5 - test[980ffaebd391d06d]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]   38:     0x64a6e45dfff5 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x64a6e45dfff5 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   40:     0x64a6e45dfff5 - std[73adb7dc35730857]::panicking::catch_unwind::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x64a6e45dfff5 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x64a6e45dfff5 - test[980ffaebd391d06d]::run_test_in_process
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   43:     0x64a6e45dfff5 - test[980ffaebd391d06d]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   44:     0x64a6e45daaa4 - test[980ffaebd391d06d]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   45:     0x64a6e45daaa4 - std[73adb7dc35730857]::sys::backtrace::__rust_begin_short_backtrace::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x64a6e45e3142 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   47:     0x64a6e45e3142 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x64a6e45e3142 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   49:     0x64a6e45e3142 - std[73adb7dc35730857]::panicking::catch_unwind::<(), core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x64a6e45e3142 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x64a6e45e3142 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   52:     0x64a6e45e3142 - <std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x64a6e48be35f - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2319:9
[INFO] [stdout]   54:     0x64a6e48be35f - <std[73adb7dc35730857]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   55:     0x70504eaedaa4 - <unknown>
[INFO] [stdout]   56:     0x70504eb7aa64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_14_inheritance stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 25,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 5,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 2,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 30,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 8,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 5,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 35,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 8,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 8,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 9,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 9,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 9,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 9,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         6,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         4,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 6,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         7,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 2, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: CreateGlobal { string_index: 2 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: Class { name_index: 5, superclass: Some(Global(2)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Foo>
[INFO] [stdout] Executing byte at 5: CreateGlobal { string_index: 5 } Stack: ["<class Bar>"]
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inFoo>", "<class Bar>", "<class Foo>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Class { name_index: 8, superclass: Some(Global(5)) } Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn inFoo>", "<class Foo>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Bar>
[INFO] [stdout] Executing byte at 8: CreateGlobal { string_index: 8 } Stack: ["<class Baz>"]
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn inFoo>", "<fn inBar>", "<fn inBaz>", "<class Foo>", "<class Baz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: GetGlobal { string_index: 8 } Stack: []
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<class Foo>", "<fn inFoo>", "<class Bar>", "<fn inBaz>", "<class Baz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: Call { arg_count: 0 } Stack: ["<class Baz>"]
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Baz>", "<fn inBar>", "<fn inFoo>", "<fn inBaz>", "<class Bar>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 11: CreateGlobal { string_index: 9 } Stack: ["<Baz instance>"]
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Baz>", "<class Foo>", "<fn inFoo>", "<fn inBaz>", "<Baz instance>", "<fn inBar>", "<class Bar>", "<fn inBar>", "<fn inFoo>", "<fn inBaz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 12: GetGlobal { string_index: 9 } Stack: []
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn inFoo>", "<fn inBar>", "<fn inFoo>", "<fn inBar>", "<class Foo>", "<Baz instance>", "<fn inBaz>", "<fn inBaz>", "<class Baz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 13: DotProperty(0) Stack: ["<Baz instance>"]
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<class Bar>", "<Baz instance>", "<class Foo>", "<fn inBaz>", "<fn inFoo>", "<class Baz>", "<fn inBaz>", "<fn inFoo>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 14: Call { arg_count: 0 } Stack: ["<fn inFoo>"]
[INFO] [stdout] GC: checking value "<fn inFoo>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<class Foo>", "<class Bar>", "<class Baz>", "<fn inFoo>", "<fn inBaz>", "<fn inBar>", "<fn inBaz>", "<fn inFoo>", "<Baz instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 26: String(1) Stack: []
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<fn inFoo>", "<Baz instance>", "<class Foo>", "<fn inBar>", "<class Baz>", "<fn inBaz>", "<class Bar>", "<fn inBaz>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 27: Print Stack: ["in foo"]
[INFO] [stdout] GC: checking value "in foo"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<class Bar>", "<fn inBaz>", "<Baz instance>", "<fn inFoo>", "<class Baz>", "<fn inBaz>", "<fn inFoo>", "in foo", "<fn inBar>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 28: Nil Stack: []
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<fn inBar>", "<class Foo>", "<fn inFoo>", "<Baz instance>", "<class Baz>", "<fn inBaz>", "<fn inBaz>", "<fn inFoo>", "<class Bar>"]
[INFO] [stdout] FREEING: ["in foo"]
[INFO] [stdout] Executing byte at 29: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<fn inBaz>", "<fn inBar>", "<fn inFoo>", "<Baz instance>", "<class Foo>", "<class Bar>", "<class Baz>", "<fn inFoo>", "<fn inBaz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 15: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn inFoo>", "<fn inBar>", "<class Baz>", "<fn inBar>", "<fn inBaz>", "<Baz instance>", "<fn inBaz>", "<class Bar>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 16: GetGlobal { string_index: 9 } Stack: []
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn inFoo>", "<Baz instance>", "<fn inFoo>", "<fn inBar>", "<fn inBaz>", "<class Bar>", "<class Baz>", "<fn inBaz>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 17: DotProperty(3) Stack: ["<Baz instance>"]
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<fn inBar>", "<class Baz>", "<fn inBaz>", "<fn inFoo>", "<fn inBar>", "<class Bar>", "<class Foo>", "<fn inFoo>", "<Baz instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 18: Call { arg_count: 0 } Stack: ["<fn inBar>"]
[INFO] [stdout] GC: checking value "<fn inBar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Baz instance>", "<fn inFoo>", "<fn inBar>", "<class Bar>", "<class Foo>", "<fn inBar>", "<fn inBaz>", "<fn inBaz>", "<fn inFoo>", "<class Baz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 31: String(4) Stack: []
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<fn inBar>", "<Baz instance>", "<class Baz>", "<fn inBaz>", "<fn inFoo>", "<fn inFoo>", "<class Foo>", "<class Bar>", "<fn inBaz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 32: Print Stack: ["in bar"]
[INFO] [stdout] GC: checking value "in bar"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "in bar", "<class Baz>", "<class Bar>", "<fn inBaz>", "<fn inBar>", "<fn inFoo>", "<fn inBaz>", "<class Foo>", "<fn inFoo>", "<Baz instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 33: Nil Stack: []
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Baz instance>", "<class Bar>", "<fn inBaz>", "<fn inFoo>", "<fn inBar>", "<class Foo>", "<class Baz>", "<fn inBaz>", "<fn inFoo>", "<fn inBar>"]
[INFO] [stdout] FREEING: ["in bar"]
[INFO] [stdout] Executing byte at 34: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<class Foo>", "<class Baz>", "<fn inBaz>", "<class Bar>", "<fn inBar>", "<Baz instance>", "<fn inFoo>", "<fn inBaz>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 19: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inFoo>", "<class Foo>", "<Baz instance>", "<fn inFoo>", "<class Baz>", "<fn inBaz>", "<fn inBar>", "<class Bar>", "<fn inBaz>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 20: GetGlobal { string_index: 9 } Stack: []
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inFoo>", "<fn inBar>", "<fn inBaz>", "<class Foo>", "<fn inFoo>", "<class Bar>", "<fn inBaz>", "<class Baz>", "<fn inBar>", "<Baz instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 21: DotProperty(6) Stack: ["<Baz instance>"]
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn inBar>", "<class Foo>", "<fn inFoo>", "<fn inBaz>", "<Baz instance>", "<fn inFoo>", "<fn inBar>", "<class Baz>", "<fn inBaz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 22: Call { arg_count: 0 } Stack: ["<fn inBaz>"]
[INFO] [stdout] GC: checking value "<fn inBaz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<fn inBar>", "<class Baz>", "<fn inBaz>", "<class Bar>", "<fn inFoo>", "<Baz instance>", "<fn inFoo>", "<fn inBar>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 36: String(7) Stack: []
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<class Foo>", "<fn inFoo>", "<fn inBar>", "<fn inBaz>", "<class Baz>", "<fn inBar>", "<fn inBaz>", "<Baz instance>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 37: Print Stack: ["in baz"]
[INFO] [stdout] GC: checking value "in baz"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<fn inFoo>", "<class Bar>", "<fn inFoo>", "<fn inBar>", "<fn inBaz>", "in baz", "<class Baz>", "<fn inBaz>", "<Baz instance>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 38: Nil Stack: []
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<Baz instance>", "<fn inBar>", "<fn inFoo>", "<fn inBaz>", "<fn inBar>", "<fn inFoo>", "<class Foo>", "<class Baz>", "<fn inBaz>"]
[INFO] [stdout] FREEING: ["in baz"]
[INFO] [stdout] Executing byte at 39: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Baz instance>", "<class Baz>", "<fn inBaz>", "<class Bar>", "<fn inBaz>", "<fn inFoo>", "<fn inFoo>", "<class Foo>", "<fn inBar>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 23: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn inFoo>", "<fn inFoo>", "<Baz instance>", "<fn inBar>", "<fn inBar>", "<fn inBaz>", "<class Foo>", "<class Baz>", "<fn inBaz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 24: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Baz>", "<Baz instance>", "<fn inBaz>", "<fn inBar>", "<fn inFoo>", "<class Bar>", "<class Foo>", "<fn inBaz>", "<fn inBar>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 8,
[INFO] [stdout]         type_: Function,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 0,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 0, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["<class A>"]
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: Callable { address: 8, type_: Function } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 1 } Stack: ["<fn f>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn f>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: GetGlobal { string_index: 1 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<fn f>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: Call { arg_count: 0 } Stack: ["<fn f>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<fn f>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: Class { name_index: 2, superclass: Some(Global(0)) } Stack: ["<fn f>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<fn f>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: GetLocal(1) Stack: ["<fn f>", "<class B>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn f>", "<class B>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 11: Return Stack: ["<fn f>", "<class B>", "<class B>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<fn f>", "<class B>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Print Stack: ["<class B>"]
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<class A>", "<class B>", "<fn f>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<fn f>", "<class A>"]
[INFO] [stdout] FREEING: ["<class B>"]
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 36,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 45,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 7,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 4,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 56,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 67,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 7,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 7,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         8,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         9,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         10,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         11,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         5,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         6,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 5,
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 6,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 4, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 4 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Foo>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Class { name_index: 7, superclass: Some(Global(4)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn fooPrint>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Foo>
[INFO] [stdout] Superclass address: <class Foo>
[INFO] [stdout] Executing byte at 7: CreateGlobal { string_index: 7 } Stack: ["<class Bar>"]
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn bar>", "<fn barPrint>", "<fn foo>", "<class Foo>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: GetGlobal { string_index: 7 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn bar>", "<fn foo>", "<class Foo>", "<class Bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: Call { arg_count: 0 } Stack: ["<class Bar>"]
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn foo>", "<fn bar>", "<fn barPrint>", "<class Bar>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: CreateGlobal { string_index: 5 } Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn bar>", "<fn barPrint>", "<fn foo>", "<Bar instance>", "<fn fooPrint>", "<class Foo>", "<fn barPrint>", "<fn bar>", "<fn fooPrint>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 11: String(8) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn barPrint>", "<fn foo>", "<fn fooPrint>", "<fn fooPrint>", "<fn bar>", "<class Foo>", "<class Bar>", "<fn barPrint>", "<Bar instance>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 12: String(9) Stack: ["foo 1"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["foo 1", "<fn fooPrint>", "<class Bar>", "<fn barPrint>", "<fn barPrint>", "<fn bar>", "<fn bar>", "<fn foo>", "<fn fooPrint>", "<class Foo>", "<Bar instance>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 13: GetGlobal { string_index: 5 } Stack: ["foo 1", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["foo 1", "<fn barPrint>", "<fn foo>", "foo 2", "<class Foo>", "<class Bar>", "<fn fooPrint>", "<fn fooPrint>", "<Bar instance>", "<fn bar>", "<fn foo>", "<fn bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 14: DotProperty(3) Stack: ["foo 1", "foo 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn bar>", "foo 1", "<fn foo>", "<fn fooPrint>", "<fn barPrint>", "<fn barPrint>", "<fn bar>", "<class Foo>", "<fn fooPrint>", "foo 2", "<fn foo>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 15: Call { arg_count: 2 } Stack: ["foo 1", "foo 2", "<fn foo>"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<fn foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "foo 2", "<fn bar>", "foo 1", "<Bar instance>", "<class Foo>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "<fn bar>", "<fn barPrint>", "<fn fooPrint>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 46: GetCaptured(0) Stack: ["foo 1", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<class Foo>", "<fn foo>", "<fn bar>", "<fn fooPrint>", "<fn bar>", "<fn barPrint>", "foo 1", "foo 2", "<Bar instance>", "<fn fooPrint>", "<fn foo>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 47: GetLocal(0) Stack: ["foo 1", "foo 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "foo 2", "<fn foo>", "<fn fooPrint>", "<Bar instance>", "<fn bar>", "<fn fooPrint>", "<class Foo>", "<class Bar>", "<fn bar>", "<fn barPrint>", "<fn foo>", "foo 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 48: AssignField { field_name_index: 1 } Stack: ["foo 1", "foo 2", "<Bar instance>", "foo 1"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["foo 1", "<Bar instance>", "<class Foo>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "<fn bar>", "<class Bar>", "foo 2", "<fn fooPrint>", "<fn bar>", "<fn barPrint>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 49: Pop(1) Stack: ["foo 1", "foo 2", "foo 1"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn bar>", "<class Bar>", "<fn bar>", "foo 1", "<fn barPrint>", "foo 2", "<fn foo>", "<Bar instance>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 50: GetCaptured(0) Stack: ["foo 1", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn barPrint>", "<fn foo>", "<fn bar>", "<fn barPrint>", "foo 1", "<Bar instance>", "<fn bar>", "<fn fooPrint>", "<class Foo>", "<class Bar>", "<fn fooPrint>", "foo 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 51: GetLocal(1) Stack: ["foo 1", "foo 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<Bar instance>", "<fn foo>", "<class Bar>", "foo 2", "<fn barPrint>", "foo 1", "<fn barPrint>", "<fn fooPrint>", "<fn foo>", "<class Foo>", "<fn bar>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 52: AssignField { field_name_index: 2 } Stack: ["foo 1", "foo 2", "<Bar instance>", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["foo 2", "<fn foo>", "<fn bar>", "<fn fooPrint>", "<fn fooPrint>", "<class Foo>", "<Bar instance>", "<fn barPrint>", "<class Bar>", "<fn bar>", "<fn foo>", "foo 1", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 53: Pop(1) Stack: ["foo 1", "foo 2", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<Bar instance>", "<fn fooPrint>", "foo 1", "<class Foo>", "<fn bar>", "<fn barPrint>", "<fn foo>", "<fn bar>", "<fn barPrint>", "<fn foo>", "<class Bar>", "foo 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 54: Nil Stack: ["foo 1", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["foo 2", "<fn bar>", "<class Foo>", "<class Bar>", "foo 1", "<fn foo>", "<fn barPrint>", "<fn bar>", "<fn foo>", "<fn barPrint>", "<fn fooPrint>", "<fn fooPrint>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 55: Return Stack: ["foo 1", "foo 2", "nil"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["foo 1", "<fn barPrint>", "<class Foo>", "<fn barPrint>", "foo 2", "<class Bar>", "<Bar instance>", "<fn foo>", "<fn fooPrint>", "<fn foo>", "<fn bar>", "<fn bar>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 16: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn bar>", "<fn fooPrint>", "<fn foo>", "<fn foo>", "foo 2", "foo 1", "<class Foo>", "<fn fooPrint>", "<class Bar>", "<fn barPrint>", "<fn barPrint>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 17: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn fooPrint>", "<fn foo>", "foo 2", "<class Bar>", "<fn bar>", "<fn barPrint>", "<Bar instance>", "foo 1", "<fn barPrint>", "<fn foo>", "<fn bar>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 18: DotProperty(0) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Bar>", "<fn barPrint>", "<fn foo>", "<fn bar>", "<class Foo>", "<fn fooPrint>", "<fn barPrint>", "<fn fooPrint>", "foo 2", "foo 1", "<fn bar>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 19: Call { arg_count: 0 } Stack: ["<fn fooPrint>"]
[INFO] [stdout] GC: checking value "<fn fooPrint>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Bar>", "<fn foo>", "foo 1", "<fn bar>", "<fn fooPrint>", "<fn barPrint>", "<class Foo>", "<fn barPrint>", "<Bar instance>", "<fn bar>", "foo 2", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 37: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "<fn bar>", "foo 2", "foo 1", "<fn bar>", "<class Foo>", "<fn fooPrint>", "<class Bar>", "<fn barPrint>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 38: DotProperty(1) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "foo 2", "<class Bar>", "foo 1", "<fn fooPrint>", "<class Foo>", "<fn bar>", "<fn barPrint>", "<fn fooPrint>", "<fn foo>", "<fn barPrint>", "<Bar instance>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 39: Print Stack: ["foo 1"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn fooPrint>", "<fn foo>", "<fn bar>", "<fn bar>", "<Bar instance>", "foo 1", "<class Foo>", "foo 2", "<class Bar>", "<fn foo>", "<fn barPrint>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 40: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn barPrint>", "<fn barPrint>", "<fn bar>", "<fn bar>", "<fn fooPrint>", "<Bar instance>", "<fn fooPrint>", "<fn foo>", "<fn foo>", "foo 2", "foo 1", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 41: DotProperty(2) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<Bar instance>", "foo 2", "<class Bar>", "<fn barPrint>", "<fn fooPrint>", "<fn bar>", "foo 1", "<fn fooPrint>", "<fn bar>", "<fn foo>", "<fn barPrint>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 42: Print Stack: ["foo 2"]
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "foo 2", "<Bar instance>", "<fn fooPrint>", "foo 1", "<fn barPrint>", "<fn bar>", "<class Foo>", "<fn barPrint>", "<fn foo>", "<fn fooPrint>", "<class Bar>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 43: Nil Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn barPrint>", "<fn barPrint>", "<fn fooPrint>", "<fn bar>", "foo 2", "<fn fooPrint>", "<class Foo>", "foo 1", "<fn bar>", "<class Bar>", "<fn foo>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 44: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn foo>", "<fn fooPrint>", "<class Foo>", "<fn barPrint>", "foo 2", "foo 1", "<fn bar>", "<class Bar>", "<fn foo>", "<fn fooPrint>", "<fn bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 20: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn fooPrint>", "foo 2", "<Bar instance>", "<fn foo>", "<fn barPrint>", "<fn bar>", "<fn foo>", "foo 1", "<class Foo>", "<fn barPrint>", "<class Bar>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 21: String(10) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<class Foo>", "<fn barPrint>", "<fn fooPrint>", "<fn barPrint>", "<fn fooPrint>", "<fn bar>", "<fn foo>", "<Bar instance>", "foo 1", "foo 2", "<class Bar>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 22: String(11) Stack: ["bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["foo 1", "<fn bar>", "<fn fooPrint>", "<fn fooPrint>", "<Bar instance>", "<class Foo>", "<fn foo>", "<fn barPrint>", "<fn foo>", "foo 2", "<fn barPrint>", "bar 1", "<class Bar>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 23: GetGlobal { string_index: 5 } Stack: ["bar 1", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["foo 1", "<class Bar>", "<fn barPrint>", "<class Foo>", "<fn foo>", "<fn barPrint>", "<fn fooPrint>", "<fn bar>", "bar 1", "<fn bar>", "<fn fooPrint>", "foo 2", "<fn foo>", "<Bar instance>", "bar 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 24: DotProperty(5) Stack: ["bar 1", "bar 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["foo 2", "<fn bar>", "bar 1", "<class Bar>", "<fn bar>", "<fn fooPrint>", "<class Foo>", "<fn foo>", "bar 2", "<Bar instance>", "foo 1", "<fn barPrint>", "<fn foo>", "<fn fooPrint>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 25: Call { arg_count: 2 } Stack: ["bar 1", "bar 2", "<fn bar>"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<fn bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn barPrint>", "<fn foo>", "<fn bar>", "<Bar instance>", "<fn foo>", "foo 1", "bar 1", "<fn fooPrint>", "<class Foo>", "<fn barPrint>", "bar 2", "<fn fooPrint>", "foo 2", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 57: GetCaptured(0) Stack: ["bar 1", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "foo 2", "<class Bar>", "<Bar instance>", "<fn barPrint>", "<fn foo>", "<fn fooPrint>", "<fn bar>", "bar 1", "bar 2", "foo 1", "<fn foo>", "<fn bar>", "<fn barPrint>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 58: GetLocal(0) Stack: ["bar 1", "bar 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn fooPrint>", "<fn barPrint>", "bar 2", "<fn bar>", "foo 2", "<fn bar>", "<fn foo>", "foo 1", "<fn fooPrint>", "<class Bar>", "<fn barPrint>", "bar 1", "<fn foo>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 59: AssignField { field_name_index: 1 } Stack: ["bar 1", "bar 2", "<Bar instance>", "bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Bar>", "<fn fooPrint>", "<fn foo>", "<fn bar>", "<fn fooPrint>", "foo 2", "foo 1", "bar 1", "<class Foo>", "<fn barPrint>", "bar 2", "<Bar instance>", "<fn bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 60: Pop(1) Stack: ["bar 1", "bar 2", "bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn fooPrint>", "<class Foo>", "<fn foo>", "bar 1", "bar 2", "<fn foo>", "<Bar instance>", "<fn bar>", "<fn barPrint>", "foo 2", "<fn bar>", "<fn barPrint>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: ["foo 1"]
[INFO] [stdout] Executing byte at 61: GetCaptured(0) Stack: ["bar 1", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "bar 2", "<fn fooPrint>", "<fn bar>", "<fn bar>", "<class Bar>", "<class Foo>", "bar 1", "<fn barPrint>", "<fn foo>", "<fn fooPrint>", "foo 2", "<Bar instance>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 62: GetLocal(1) Stack: ["bar 1", "bar 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn bar>", "bar 2", "<fn fooPrint>", "<fn foo>", "<fn fooPrint>", "bar 1", "<Bar instance>", "<fn barPrint>", "foo 2", "<class Bar>", "<class Foo>", "<fn foo>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 63: AssignField { field_name_index: 2 } Stack: ["bar 1", "bar 2", "<Bar instance>", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "bar 1", "<fn fooPrint>", "<fn bar>", "<fn foo>", "bar 2", "foo 2", "<class Foo>", "<class Bar>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "<Bar instance>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 64: Pop(1) Stack: ["bar 1", "bar 2", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn foo>", "<fn barPrint>", "<fn barPrint>", "<fn foo>", "<fn fooPrint>", "bar 1", "<class Foo>", "<fn fooPrint>", "<Bar instance>", "<class Bar>", "<fn bar>", "bar 2"]
[INFO] [stdout] FREEING: ["foo 2"]
[INFO] [stdout] Executing byte at 65: Nil Stack: ["bar 1", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn fooPrint>", "<fn foo>", "<fn fooPrint>", "<class Bar>", "<Bar instance>", "<fn barPrint>", "<fn bar>", "bar 2", "<class Foo>", "bar 1", "<fn bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 66: Return Stack: ["bar 1", "bar 2", "nil"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Bar>", "<fn bar>", "<fn barPrint>", "<Bar instance>", "<fn barPrint>", "bar 2", "<class Foo>", "<fn fooPrint>", "bar 1", "<fn fooPrint>", "<fn bar>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 26: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn barPrint>", "bar 1", "<fn bar>", "<class Bar>", "<fn bar>", "bar 2", "<fn fooPrint>", "<fn foo>", "<class Foo>", "<fn fooPrint>", "<Bar instance>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 27: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "bar 2", "bar 1", "<fn foo>", "<fn fooPrint>", "<fn barPrint>", "<fn bar>", "<fn bar>", "<class Foo>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 28: DotProperty(6) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn barPrint>", "<fn bar>", "bar 2", "<fn foo>", "<fn foo>", "<fn fooPrint>", "bar 1", "<Bar instance>", "<fn fooPrint>", "<class Bar>", "<fn bar>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 29: Call { arg_count: 0 } Stack: ["<fn barPrint>"]
[INFO] [stdout] GC: checking value "<fn barPrint>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<class Foo>", "<fn fooPrint>", "<fn foo>", "bar 2", "<fn barPrint>", "<fn fooPrint>", "<fn bar>", "bar 1", "<fn bar>", "<fn barPrint>", "<class Bar>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 68: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "bar 2", "<fn barPrint>", "<fn foo>", "bar 1", "<Bar instance>", "<class Foo>", "<fn fooPrint>", "<class Bar>", "<fn barPrint>", "<fn fooPrint>", "<fn bar>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 69: DotProperty(1) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["bar 1", "<class Foo>", "<fn barPrint>", "bar 2", "<fn fooPrint>", "<class Bar>", "<fn foo>", "<fn bar>", "<fn bar>", "<fn barPrint>", "<Bar instance>", "<fn foo>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 70: Print Stack: ["bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stderr] error: test failed, to rerun pass `--bin RustLox`
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["bar 1", "<fn foo>", "<class Bar>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "<Bar instance>", "<class Foo>", "<fn bar>", "<fn barPrint>", "bar 2", "<fn fooPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 71: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["bar 2", "bar 1", "<fn fooPrint>", "<fn barPrint>", "<fn bar>", "<class Bar>", "<fn bar>", "<Bar instance>", "<fn fooPrint>", "<fn barPrint>", "<class Foo>", "<fn foo>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 72: DotProperty(2) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn barPrint>", "<fn barPrint>", "bar 2", "<fn foo>", "<fn bar>", "<fn foo>", "<fn fooPrint>", "<fn bar>", "<class Foo>", "<Bar instance>", "<fn fooPrint>", "bar 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 73: Print Stack: ["bar 2"]
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn bar>", "<class Bar>", "<fn foo>", "bar 2", "<fn barPrint>", "<fn fooPrint>", "<Bar instance>", "<fn fooPrint>", "<fn bar>", "<fn foo>", "bar 1", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 74: Nil Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn fooPrint>", "<Bar instance>", "<fn foo>", "bar 1", "<class Bar>", "bar 2", "<fn fooPrint>", "<class Foo>", "<fn barPrint>", "<fn barPrint>", "<fn foo>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 75: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn bar>", "<fn barPrint>", "<fn barPrint>", "<Bar instance>", "<class Foo>", "<fn fooPrint>", "bar 2", "bar 1", "<fn foo>", "<fn foo>", "<fn bar>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 30: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn fooPrint>", "bar 1", "<class Bar>", "<fn bar>", "<fn bar>", "<fn foo>", "<fn fooPrint>", "<fn foo>", "bar 2", "<fn barPrint>", "<fn barPrint>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 31: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn bar>", "<fn fooPrint>", "<Bar instance>", "<fn fooPrint>", "<fn foo>", "<fn barPrint>", "<fn barPrint>", "<class Bar>", "bar 2", "<class Foo>", "bar 1", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 32: DotProperty(0) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn barPrint>", "bar 1", "<class Bar>", "<class Foo>", "<fn bar>", "<Bar instance>", "<fn foo>", "<fn fooPrint>", "bar 2", "<fn foo>", "<fn barPrint>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 33: Call { arg_count: 0 } Stack: ["<fn fooPrint>"]
[INFO] [stdout] GC: checking value "<fn fooPrint>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn bar>", "bar 1", "<fn barPrint>", "<fn foo>", "<fn foo>", "<fn fooPrint>", "<fn fooPrint>", "<fn bar>", "<Bar instance>", "<class Foo>", "<fn barPrint>", "bar 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 37: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn bar>", "<fn foo>", "<class Foo>", "<fn fooPrint>", "bar 1", "<fn fooPrint>", "<fn barPrint>", "<fn barPrint>", "<Bar instance>", "<class Bar>", "bar 2", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 38: DotProperty(1) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["bar 2", "<fn fooPrint>", "<class Bar>", "<fn barPrint>", "<fn foo>", "<fn bar>", "<fn foo>", "<Bar instance>", "<fn barPrint>", "bar 1", "<fn bar>", "<class Foo>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 39: Print Stack: ["bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn foo>", "<fn barPrint>", "<class Foo>", "<fn fooPrint>", "bar 2", "bar 1", "<Bar instance>", "<fn foo>", "<class Bar>", "<fn fooPrint>", "<fn barPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 40: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "bar 2", "<fn foo>", "<fn barPrint>", "<Bar instance>", "<fn bar>", "<fn fooPrint>", "<fn bar>", "<fn foo>", "<fn fooPrint>", "bar 1", "<class Foo>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 41: DotProperty(2) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "bar 1", "<fn bar>", "<fn fooPrint>", "<class Bar>", "bar 2", "<fn barPrint>", "<fn barPrint>", "<fn foo>", "<fn fooPrint>", "<Bar instance>", "<class Foo>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 42: Print Stack: ["bar 2"]
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn barPrint>", "bar 2", "<fn foo>", "<fn fooPrint>", "bar 1", "<class Bar>", "<fn barPrint>", "<fn foo>", "<fn bar>", "<fn fooPrint>", "<fn bar>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 43: Nil Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Foo>", "<fn foo>", "<Bar instance>", "<class Bar>", "<fn bar>", "<fn fooPrint>", "<fn barPrint>", "bar 2", "bar 1", "<fn bar>", "<fn barPrint>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 44: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<class Bar>", "<class Foo>", "<Bar instance>", "<fn fooPrint>", "bar 1", "<fn barPrint>", "<fn fooPrint>", "bar 2", "<fn bar>", "<fn foo>", "<fn bar>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 34: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn fooPrint>", "<fn bar>", "<fn barPrint>", "<Bar instance>", "<fn bar>", "bar 2", "<fn barPrint>", "<fn fooPrint>", "<fn foo>", "bar 1", "<class Foo>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 35: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "bar 1", "<fn barPrint>", "<Bar instance>", "bar 2", "<fn foo>", "<class Bar>", "<class Foo>", "<fn fooPrint>", "<fn fooPrint>", "<fn foo>", "<fn bar>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 15,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 22,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 3,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         5,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 1,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 1,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 3, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 3 } Stack: ["<class A>"]
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn test>", "<class A>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Class { name_index: 4, superclass: Some(Global(3)) } Stack: []
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn test>", "<class A>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: CreateGlobal { string_index: 4 } Stack: ["<class B>"]
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn test>", "<class B>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: String(5) Stack: []
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class A>", "<class B>", "<fn test>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: GetGlobal { string_index: 4 } Stack: ["value"]
[INFO] [stdout] GC: checking value "value"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn test>", "<class A>", "<class B>", "<fn init>", "value"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: Call { arg_count: 1 } Stack: ["value", "<class B>"]
[INFO] [stdout] GC: checking value "value"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class B>", "value", "<fn init>", "<fn test>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 16: GetCaptured(0) Stack: ["value"]
[INFO] [stdout] GC: checking value "value"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["value", "<class A>", "<class B>", "<fn test>", "<fn init>"]
[INFO] [stdout] FREEING: ["<fn init>", "<B instance>", "<fn test>"]
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_14_inheritance' (682) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x64a6e48bee41 - std[73adb7dc35730857]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x64a6e48bee41 - std[73adb7dc35730857]::backtrace_rs::backtrace::trace_unsynchronized::<std[73adb7dc35730857]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x64a6e48bee41 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x64a6e48bee41 - <<std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6883ba1bc0fe4ed1]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x64a6e48d613a - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x64a6e48d613a - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stdout]    6:     0x64a6e48c35cc - std[73adb7dc35730857]::io::default_write_fmt::<alloc[55a36b64bcbf2c0d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:626:11
[INFO] [stdout]    7:     0x64a6e48c35cc - <alloc[55a36b64bcbf2c0d]::vec::Vec<u8> as std[73adb7dc35730857]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:1730:13
[INFO] [stdout]    8:     0x64a6e489cf16 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x64a6e489cf16 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x64a6e48b6ae9 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x64a6e45df670 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   12:     0x64a6e45df670 - test[980ffaebd391d06d]::test_main_inner::<test[980ffaebd391d06d]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x64a6e48b6ca2 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   14:     0x64a6e48b6ca2 - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x64a6e489cfc2 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:688:13
[INFO] [stdout]   16:     0x64a6e48946f9 - std[73adb7dc35730857]::sys::backtrace::__rust_end_short_backtrace::<std[73adb7dc35730857]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x64a6e489dbbd - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x64a6e48d69bc - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x64a6e48d66b4 - core[6883ba1bc0fe4ed1]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x64a6e48d66b4 - core[6883ba1bc0fe4ed1]::option::expect_failed
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/option.rs:2260:5
[INFO] [stdout]   21:     0x64a6e45a5fc0 - <core[6883ba1bc0fe4ed1]::option::Option<&RustLox[79fb2770ae4f4ed6]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/option.rs:969:21
[INFO] [stdout]   22:     0x64a6e45c52ae - <RustLox[79fb2770ae4f4ed6]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x64a6e45bc8ad - <RustLox[79fb2770ae4f4ed6]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x64a6e459683a - <RustLox[79fb2770ae4f4ed6]::interpreter::Interpreter<&mut alloc[55a36b64bcbf2c0d]::string::String, &mut alloc[55a36b64bcbf2c0d]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x64a6e4598de6 - <RustLox[79fb2770ae4f4ed6]::interpreter::Interpreter<&mut alloc[55a36b64bcbf2c0d]::string::String, &mut alloc[55a36b64bcbf2c0d]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:664:38
[INFO] [stdout]   26:     0x64a6e45a30ba - <RustLox[79fb2770ae4f4ed6]::interpreter::Interpreter<&mut alloc[55a36b64bcbf2c0d]::string::String, &mut alloc[55a36b64bcbf2c0d]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x64a6e45bb01d - RustLox[79fb2770ae4f4ed6]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x64a6e45b160a - <RustLox[79fb2770ae4f4ed6]::test::test_interpret_file::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x64a6e45be4b8 - RustLox[79fb2770ae4f4ed6]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x64a6e45be24a - RustLox[79fb2770ae4f4ed6]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x64a6e45bd17d - RustLox[79fb2770ae4f4ed6]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x64a6e45bfbe2 - RustLox[79fb2770ae4f4ed6]::test::test_module::case_14_inheritance
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x64a6e45bb807 - RustLox[79fb2770ae4f4ed6]::test::test_module::case_14_inheritance::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x64a6e45b1cc6 - <RustLox[79fb2770ae4f4ed6]::test::test_module::case_14_inheritance::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x64a6e45d29ab - <fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x64a6e45d29ab - test[980ffaebd391d06d]::__rust_begin_short_backtrace::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:724:18
[INFO] [stdout]   37:     0x64a6e45dfff5 - test[980ffaebd391d06d]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]   38:     0x64a6e45dfff5 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x64a6e45dfff5 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   40:     0x64a6e45dfff5 - std[73adb7dc35730857]::panicking::catch_unwind::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x64a6e45dfff5 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x64a6e45dfff5 - test[980ffaebd391d06d]::run_test_in_process
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   43:     0x64a6e45dfff5 - test[980ffaebd391d06d]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   44:     0x64a6e45daaa4 - test[980ffaebd391d06d]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   45:     0x64a6e45daaa4 - std[73adb7dc35730857]::sys::backtrace::__rust_begin_short_backtrace::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x64a6e45e3142 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   47:     0x64a6e45e3142 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x64a6e45e3142 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   49:     0x64a6e45e3142 - std[73adb7dc35730857]::panicking::catch_unwind::<(), core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x64a6e45e3142 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x64a6e45e3142 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   52:     0x64a6e45e3142 - <std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x64a6e48be35f - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2319:9
[INFO] [stdout]   54:     0x64a6e48be35f - <std[73adb7dc35730857]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   55:     0x70504eaedaa4 - <unknown>
[INFO] [stdout]   56:     0x70504eb7aa64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_25_this stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 27,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Capture(
[INFO] [stdout]         Captured(
[INFO] [stdout]             0,
[INFO] [stdout]         ),
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 9,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 14,
[INFO] [stdout]         type_: Function,
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 3, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: CreateGlobal { string_index: 3 } Stack: ["<class Outer>"]
[INFO] [stdout] GC: checking value "<class Outer>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn method>", "<class Outer>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: GetGlobal { string_index: 3 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Outer>"
[INFO] [stdout] GC: reachable: ["<fn method>", "<class Outer>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Call { arg_count: 0 } Stack: ["<class Outer>"]
[INFO] [stdout] GC: checking value "<class Outer>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Outer>"
[INFO] [stdout] GC: reachable: ["<class Outer>", "<fn method>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: DotProperty(0) Stack: ["<Outer instance>"]
[INFO] [stdout] GC: checking value "<Outer instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Outer>"
[INFO] [stdout] GC: reachable: ["<fn method>", "<fn method>", "<Outer instance>", "<class Outer>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Call { arg_count: 0 } Stack: ["<fn method>"]
[INFO] [stdout] GC: checking value "<fn method>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Outer>"
[INFO] [stdout] GC: reachable: ["<fn method>", "<Outer instance>", "<class Outer>", "<fn method>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 28: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Outer>"
[INFO] [stdout] GC: reachable: ["<class Outer>", "<fn method>"]
[INFO] [stdout] FREEING: ["<Outer instance>", "<fn method>"]
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_25_this' (693) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x64a6e48bee41 - std[73adb7dc35730857]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x64a6e48bee41 - std[73adb7dc35730857]::backtrace_rs::backtrace::trace_unsynchronized::<std[73adb7dc35730857]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x64a6e48bee41 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x64a6e48bee41 - <<std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6883ba1bc0fe4ed1]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x64a6e48d613a - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x64a6e48d613a - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stdout]    6:     0x64a6e48c35cc - std[73adb7dc35730857]::io::default_write_fmt::<alloc[55a36b64bcbf2c0d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:626:11
[INFO] [stdout]    7:     0x64a6e48c35cc - <alloc[55a36b64bcbf2c0d]::vec::Vec<u8> as std[73adb7dc35730857]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:1730:13
[INFO] [stdout]    8:     0x64a6e489cf16 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x64a6e489cf16 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x64a6e48b6ae9 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x64a6e45df670 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   12:     0x64a6e45df670 - test[980ffaebd391d06d]::test_main_inner::<test[980ffaebd391d06d]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x64a6e48b6ca2 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   14:     0x64a6e48b6ca2 - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x64a6e489cfc2 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:688:13
[INFO] [stdout]   16:     0x64a6e48946f9 - std[73adb7dc35730857]::sys::backtrace::__rust_end_short_backtrace::<std[73adb7dc35730857]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x64a6e489dbbd - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x64a6e48d69bc - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x64a6e48d66b4 - core[6883ba1bc0fe4ed1]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x64a6e48d66b4 - core[6883ba1bc0fe4ed1]::option::expect_failed
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/option.rs:2260:5
[INFO] [stdout]   21:     0x64a6e45a5fc0 - <core[6883ba1bc0fe4ed1]::option::Option<&RustLox[79fb2770ae4f4ed6]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/option.rs:969:21
[INFO] [stdout]   22:     0x64a6e45c52ae - <RustLox[79fb2770ae4f4ed6]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x64a6e45bc8ad - <RustLox[79fb2770ae4f4ed6]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x64a6e459683a - <RustLox[79fb2770ae4f4ed6]::interpreter::Interpreter<&mut alloc[55a36b64bcbf2c0d]::string::String, &mut alloc[55a36b64bcbf2c0d]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x64a6e4598de6 - <RustLox[79fb2770ae4f4ed6]::interpreter::Interpreter<&mut alloc[55a36b64bcbf2c0d]::string::String, &mut alloc[55a36b64bcbf2c0d]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:664:38
[INFO] [stdout]   26:     0x64a6e45a30ba - <RustLox[79fb2770ae4f4ed6]::interpreter::Interpreter<&mut alloc[55a36b64bcbf2c0d]::string::String, &mut alloc[55a36b64bcbf2c0d]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x64a6e45bb01d - RustLox[79fb2770ae4f4ed6]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x64a6e45b160a - <RustLox[79fb2770ae4f4ed6]::test::test_interpret_file::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x64a6e45be4b8 - RustLox[79fb2770ae4f4ed6]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x64a6e45be24a - RustLox[79fb2770ae4f4ed6]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x64a6e45bd17d - RustLox[79fb2770ae4f4ed6]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x64a6e45bf6e2 - RustLox[79fb2770ae4f4ed6]::test::test_module::case_25_this
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x64a6e45bb447 - RustLox[79fb2770ae4f4ed6]::test::test_module::case_25_this::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x64a6e45b17c6 - <RustLox[79fb2770ae4f4ed6]::test::test_module::case_25_this::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x64a6e45d29ab - <fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x64a6e45d29ab - test[980ffaebd391d06d]::__rust_begin_short_backtrace::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:724:18
[INFO] [stdout]   37:     0x64a6e45dfff5 - test[980ffaebd391d06d]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]   38:     0x64a6e45dfff5 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x64a6e45dfff5 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   40:     0x64a6e45dfff5 - std[73adb7dc35730857]::panicking::catch_unwind::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x64a6e45dfff5 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x64a6e45dfff5 - test[980ffaebd391d06d]::run_test_in_process
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   43:     0x64a6e45dfff5 - test[980ffaebd391d06d]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   44:     0x64a6e45daaa4 - test[980ffaebd391d06d]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   45:     0x64a6e45daaa4 - std[73adb7dc35730857]::sys::backtrace::__rust_begin_short_backtrace::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x64a6e45e3142 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   47:     0x64a6e45e3142 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x64a6e45e3142 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   49:     0x64a6e45e3142 - std[73adb7dc35730857]::panicking::catch_unwind::<(), core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x64a6e45e3142 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x64a6e45e3142 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   52:     0x64a6e45e3142 - <std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x64a6e48be35f - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2319:9
[INFO] [stdout]   54:     0x64a6e48be35f - <std[73adb7dc35730857]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   55:     0x70504eaedaa4 - <unknown>
[INFO] [stdout]   56:     0x70504eb7aa64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_24_super stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 18,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 2,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 25,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 1,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 2, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: CreateGlobal { string_index: 2 } Stack: ["<class Base>"]
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Base>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: Class { name_index: 4, superclass: Some(Global(2)) } Stack: []
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Base>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Base>
[INFO] [stdout] Executing byte at 5: CreateGlobal { string_index: 4 } Stack: ["<class Derived>"]
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class Derived>", "<fn init>", "<class Base>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: String(1) Stack: []
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<fn init>", "<class Base>", "<class Derived>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: String(3) Stack: ["a"]
[INFO] [stdout] GC: checking value "a"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class Derived>", "<class Base>", "<fn init>", "a"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: GetGlobal { string_index: 4 } Stack: ["a", "b"]
[INFO] [stdout] GC: checking value "a"
[INFO] [stdout] GC: checking value "b"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class Derived>", "b", "<class Base>", "<fn init>", "a"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: Call { arg_count: 2 } Stack: ["a", "b", "<class Derived>"]
[INFO] [stdout] GC: checking value "a"
[INFO] [stdout] GC: checking value "b"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["a", "<fn init>", "<class Derived>", "<class Base>", "<fn init>", "b"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 26: GetLocal(0) Stack: ["a", "b"]
[INFO] [stdout] GC: checking value "a"
[INFO] [stdout] GC: checking value "b"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class Base>", "a", "<fn init>", "b", "<class Derived>"]
[INFO] [stdout] FREEING: ["<Derived instance>", "<fn init>"]
[INFO] [stdout] Executing byte at 27: GetCaptured(1) Stack: ["a", "b", "a"]
[INFO] [stdout] GC: checking value "a"
[INFO] [stdout] GC: checking value "b"
[INFO] [stdout] GC: checking value "a"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class Base>", "<class Derived>", "b", "<fn init>", "a"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 28: DotProperty(0) Stack: ["a", "b", "a", "super"]
[INFO] [stdout] GC: checking value "a"
[INFO] [stdout] GC: checking value "b"
[INFO] [stdout] GC: checking value "a"
[INFO] [stdout] GC: checking value "super"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class Derived>", "<class Base>", "<fn init>", "a", "b"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_24_super' (692) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x64a6e48bee41 - std[73adb7dc35730857]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x64a6e48bee41 - std[73adb7dc35730857]::backtrace_rs::backtrace::trace_unsynchronized::<std[73adb7dc35730857]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x64a6e48bee41 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x64a6e48bee41 - <<std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6883ba1bc0fe4ed1]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x64a6e48d613a - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x64a6e48d613a - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stdout]    6:     0x64a6e48c35cc - std[73adb7dc35730857]::io::default_write_fmt::<alloc[55a36b64bcbf2c0d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:626:11
[INFO] [stdout]    7:     0x64a6e48c35cc - <alloc[55a36b64bcbf2c0d]::vec::Vec<u8> as std[73adb7dc35730857]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:1730:13
[INFO] [stdout]    8:     0x64a6e489cf16 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x64a6e489cf16 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x64a6e48b6ae9 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x64a6e45df670 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   12:     0x64a6e45df670 - test[980ffaebd391d06d]::test_main_inner::<test[980ffaebd391d06d]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x64a6e48b6ca2 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   14:     0x64a6e48b6ca2 - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x64a6e489cfc2 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:688:13
[INFO] [stdout]   16:     0x64a6e48946f9 - std[73adb7dc35730857]::sys::backtrace::__rust_end_short_backtrace::<std[73adb7dc35730857]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x64a6e489dbbd - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x64a6e48d69bc - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x64a6e48d66b4 - core[6883ba1bc0fe4ed1]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x64a6e48d66b4 - core[6883ba1bc0fe4ed1]::option::expect_failed
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/option.rs:2260:5
[INFO] [stdout]   21:     0x64a6e45a5fc0 - <core[6883ba1bc0fe4ed1]::option::Option<&RustLox[79fb2770ae4f4ed6]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/option.rs:969:21
[INFO] [stdout]   22:     0x64a6e45c52ae - <RustLox[79fb2770ae4f4ed6]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x64a6e45bc8ad - <RustLox[79fb2770ae4f4ed6]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x64a6e459683a - <RustLox[79fb2770ae4f4ed6]::interpreter::Interpreter<&mut alloc[55a36b64bcbf2c0d]::string::String, &mut alloc[55a36b64bcbf2c0d]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x64a6e459b48b - <RustLox[79fb2770ae4f4ed6]::interpreter::Interpreter<&mut alloc[55a36b64bcbf2c0d]::string::String, &mut alloc[55a36b64bcbf2c0d]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:800:49
[INFO] [stdout]   26:     0x64a6e45a30ba - <RustLox[79fb2770ae4f4ed6]::interpreter::Interpreter<&mut alloc[55a36b64bcbf2c0d]::string::String, &mut alloc[55a36b64bcbf2c0d]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x64a6e45bb01d - RustLox[79fb2770ae4f4ed6]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x64a6e45b160a - <RustLox[79fb2770ae4f4ed6]::test::test_interpret_file::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x64a6e45be4b8 - RustLox[79fb2770ae4f4ed6]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x64a6e45be24a - RustLox[79fb2770ae4f4ed6]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x64a6e45bd17d - RustLox[79fb2770ae4f4ed6]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x64a6e45bf822 - RustLox[79fb2770ae4f4ed6]::test::test_module::case_24_super
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x64a6e45bb537 - RustLox[79fb2770ae4f4ed6]::test::test_module::case_24_super::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x64a6e45b1906 - <RustLox[79fb2770ae4f4ed6]::test::test_module::case_24_super::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x64a6e45d29ab - <fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x64a6e45d29ab - test[980ffaebd391d06d]::__rust_begin_short_backtrace::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:724:18
[INFO] [stdout]   37:     0x64a6e45dfff5 - test[980ffaebd391d06d]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]   38:     0x64a6e45dfff5 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x64a6e45dfff5 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   40:     0x64a6e45dfff5 - std[73adb7dc35730857]::panicking::catch_unwind::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x64a6e45dfff5 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x64a6e45dfff5 - test[980ffaebd391d06d]::run_test_in_process
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   43:     0x64a6e45dfff5 - test[980ffaebd391d06d]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   44:     0x64a6e45daaa4 - test[980ffaebd391d06d]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   45:     0x64a6e45daaa4 - std[73adb7dc35730857]::sys::backtrace::__rust_begin_short_backtrace::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x64a6e45e3142 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   47:     0x64a6e45e3142 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x64a6e45e3142 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   49:     0x64a6e45e3142 - std[73adb7dc35730857]::panicking::catch_unwind::<(), core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x64a6e45e3142 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x64a6e45e3142 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   52:     0x64a6e45e3142 - <std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x64a6e48be35f - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2319:9
[INFO] [stdout]   54:     0x64a6e48be35f - <std[73adb7dc35730857]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   55:     0x70504eaedaa4 - <unknown>
[INFO] [stdout]   56:     0x70504eb7aa64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     test::test_module::case_05_class
[INFO] [stdout]     test::test_module::case_08_constructor
[INFO] [stdout]     test::test_module::case_14_inheritance
[INFO] [stdout]     test::test_module::case_24_super
[INFO] [stdout]     test::test_module::case_25_this
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 26 passed; 5 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.60s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "7423b470e690500322db77f5c59bea80443b1f5c81433d16425fc6dc22a4f3a2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7423b470e690500322db77f5c59bea80443b1f5c81433d16425fc6dc22a4f3a2", kill_on_drop: false }`
[INFO] [stdout] 7423b470e690500322db77f5c59bea80443b1f5c81433d16425fc6dc22a4f3a2
