[INFO] fetching crate ftml 1.12.6...
[INFO] testing ftml-1.12.6 against 1.59.0 for beta-1.60-1
[INFO] extracting crate ftml 1.12.6 into /workspace/builds/worker-42/source
[INFO] validating manifest of crates.io crate ftml 1.12.6 on toolchain 1.59.0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.59.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate ftml 1.12.6
[INFO] finished tweaking crates.io crate ftml 1.12.6
[INFO] tweaked toml for crates.io crate ftml 1.12.6 written to /workspace/builds/worker-42/source/Cargo.toml
[INFO] crate crates.io crate ftml 1.12.6 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.59.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-42/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-42/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+1.59.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] f91b3a3821174c2158a576944806a5c0e4f4a6424e90d125413fa6f258f8a51e
[INFO] running `Command { std: "docker" "start" "-a" "f91b3a3821174c2158a576944806a5c0e4f4a6424e90d125413fa6f258f8a51e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f91b3a3821174c2158a576944806a5c0e4f4a6424e90d125413fa6f258f8a51e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f91b3a3821174c2158a576944806a5c0e4f4a6424e90d125413fa6f258f8a51e", kill_on_drop: false }`
[INFO] [stdout] f91b3a3821174c2158a576944806a5c0e4f4a6424e90d125413fa6f258f8a51e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-42/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-42/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+1.59.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 413abc52a6d20fa720565569985a5c977daaf9719b90a60e542a8f47d0269293
[INFO] running `Command { std: "docker" "start" "-a" "413abc52a6d20fa720565569985a5c977daaf9719b90a60e542a8f47d0269293", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.35
[INFO] [stderr]    Compiling syn v1.0.84
[INFO] [stderr]    Compiling tinyvec_macros v0.1.0
[INFO] [stderr]    Compiling matches v0.1.9
[INFO] [stderr]    Compiling serde_json v1.0.73
[INFO] [stderr]    Compiling unicode-bidi v0.3.7
[INFO] [stderr]    Compiling semver v1.0.4
[INFO] [stderr]    Compiling unicode-segmentation v1.8.0
[INFO] [stderr]    Compiling cbindgen v0.20.0
[INFO] [stderr]    Compiling strsim v0.8.0
[INFO] [stderr]    Compiling maplit v1.0.2
[INFO] [stderr]    Compiling ucd-trie v0.1.3
[INFO] [stderr]    Compiling slog v2.7.0
[INFO] [stderr]    Compiling rustversion v1.0.6
[INFO] [stderr]    Compiling smallvec v1.7.0
[INFO] [stderr]    Compiling match_cfg v0.1.0
[INFO] [stderr]    Compiling trim-in-place v0.1.6
[INFO] [stderr]    Compiling entities v1.0.1
[INFO] [stderr]    Compiling void v1.0.2
[INFO] [stderr]    Compiling str-macro v1.0.0
[INFO] [stderr]    Compiling latex2mathml v0.2.3
[INFO] [stderr]    Compiling strum v0.23.0
[INFO] [stderr]    Compiling ref-map v0.1.3
[INFO] [stderr]    Compiling libc v0.2.112
[INFO] [stderr]    Compiling serde_derive v1.0.132
[INFO] [stderr]    Compiling serde v1.0.132
[INFO] [stderr]    Compiling num-traits v0.2.14
[INFO] [stderr]    Compiling num-integer v0.1.44
[INFO] [stderr]    Compiling indexmap v1.7.0
[INFO] [stderr]    Compiling unicase v2.6.0
[INFO] [stderr]    Compiling aho-corasick v0.7.18
[INFO] [stderr]    Compiling tinyvec v1.5.1
[INFO] [stderr]    Compiling form_urlencoded v1.0.1
[INFO] [stderr]    Compiling pest v2.1.3
[INFO] [stderr]    Compiling heck v0.3.3
[INFO] [stderr]    Compiling jobserver v0.1.24
[INFO] [stderr]    Compiling getrandom v0.2.3
[INFO] [stderr]    Compiling time v0.1.44
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling parking_lot_core v0.8.5
[INFO] [stderr]    Compiling hostname v0.3.1
[INFO] [stderr]    Compiling regex v1.5.4
[INFO] [stderr]    Compiling clap v2.34.0
[INFO] [stderr]    Compiling unicode-normalization v0.1.19
[INFO] [stderr]    Compiling quote v1.0.10
[INFO] [stderr]    Compiling rand_core v0.6.3
[INFO] [stderr]    Compiling cc v1.0.72
[INFO] [stderr]    Compiling parking_lot v0.11.2
[INFO] [stderr]    Compiling pest_meta v2.1.3
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand v0.8.4
[INFO] [stderr]    Compiling idna v0.2.3
[INFO] [stderr]    Compiling wikidot-normalize v0.9.2
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling libz-sys v1.1.3
[INFO] [stderr]    Compiling libgit2-sys v0.12.26+1.3.0
[INFO] [stderr]    Compiling tempfile v3.2.0
[INFO] [stderr]    Compiling url v2.2.2
[INFO] [stderr]    Compiling git2 v0.13.25
[INFO] [stderr]    Compiling pest_generator v2.1.3
[INFO] [stderr]    Compiling enum-map-derive v0.6.0
[INFO] [stderr]    Compiling serde_repr v0.1.7
[INFO] [stderr]    Compiling pest_derive v2.1.0
[INFO] [stderr]    Compiling strum_macros v0.23.1
[INFO] [stderr]    Compiling enum-map v1.1.1
[INFO] [stderr]    Compiling toml v0.5.8
[INFO] [stderr]    Compiling slog-json v2.4.0
[INFO] [stderr]    Compiling slog-bunyan v2.3.0
[INFO] [stderr]    Compiling cargo-lock v7.0.1
[INFO] [stderr]    Compiling built v0.5.1
[INFO] [stderr]    Compiling ftml v1.12.6 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 57.69s
[INFO] running `Command { std: "docker" "inspect" "413abc52a6d20fa720565569985a5c977daaf9719b90a60e542a8f47d0269293", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "413abc52a6d20fa720565569985a5c977daaf9719b90a60e542a8f47d0269293", kill_on_drop: false }`
[INFO] [stdout] 413abc52a6d20fa720565569985a5c977daaf9719b90a60e542a8f47d0269293
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-42/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-42/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+1.59.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] c1f0362d50d70b07eed654321e0d9f1d37d665cffc7e04c11b9eae1e95c4af7b
[INFO] running `Command { std: "docker" "start" "-a" "c1f0362d50d70b07eed654321e0d9f1d37d665cffc7e04c11b9eae1e95c4af7b", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling crossbeam-utils v0.8.5
[INFO] [stderr]    Compiling rle-decode-fast v1.0.1
[INFO] [stderr]    Compiling slog-async v2.7.0
[INFO] [stderr]    Compiling arc-swap v1.5.0
[INFO] [stderr]    Compiling take_mut v0.2.2
[INFO] [stderr]    Compiling quick-error v1.2.3
[INFO] [stderr]    Compiling byteorder v1.4.3
[INFO] [stderr]    Compiling quick-error v2.0.1
[INFO] [stderr]    Compiling termcolor v1.1.2
[INFO] [stderr]    Compiling num-traits v0.2.14
[INFO] [stderr]    Compiling crc32fast v1.3.0
[INFO] [stderr]    Compiling thread_local v1.1.3
[INFO] [stderr]    Compiling log v0.4.14
[INFO] [stderr]    Compiling bit-set v0.5.2
[INFO] [stderr]    Compiling dirs-sys-next v0.1.2
[INFO] [stderr]    Compiling wait-timeout v0.2.0
[INFO] [stderr]    Compiling rand_xorshift v0.3.0
[INFO] [stderr]    Compiling trackable_derive v1.0.0
[INFO] [stderr]    Compiling slog-kvfilter v0.7.0
[INFO] [stderr]    Compiling tempfile v3.2.0
[INFO] [stderr]    Compiling libflate_lz77 v1.1.0
[INFO] [stderr]    Compiling dirs-next v2.0.0
[INFO] [stderr]    Compiling slog-scope v4.4.0
[INFO] [stderr]    Compiling libflate v1.1.1
[INFO] [stderr]    Compiling rusty-fork v0.3.0
[INFO] [stderr]    Compiling term v0.7.0
[INFO] [stderr]    Compiling slog-stdlog v4.1.0
[INFO] [stderr]    Compiling crossbeam-channel v0.5.1
[INFO] [stderr]    Compiling trackable v1.2.0
[INFO] [stderr]    Compiling num-integer v0.1.44
[INFO] [stderr]    Compiling proptest v1.0.0
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling slog-json v2.4.0
[INFO] [stderr]    Compiling slog-term v2.8.0
[INFO] [stderr]    Compiling slog-bunyan v2.3.0
[INFO] [stderr]    Compiling ftml v1.12.6 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling sloggers v2.1.1
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 32.36s
[INFO] running `Command { std: "docker" "inspect" "c1f0362d50d70b07eed654321e0d9f1d37d665cffc7e04c11b9eae1e95c4af7b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c1f0362d50d70b07eed654321e0d9f1d37d665cffc7e04c11b9eae1e95c4af7b", kill_on_drop: false }`
[INFO] [stdout] c1f0362d50d70b07eed654321e0d9f1d37d665cffc7e04c11b9eae1e95c4af7b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-42/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-42/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+1.59.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 673af70e693482a1f5a996bfaf2593af10fe6a423c4aa22de9843959bf7a41d9
[INFO] running `Command { std: "docker" "start" "-a" "673af70e693482a1f5a996bfaf2593af10fe6a423c4aa22de9843959bf7a41d9", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.32s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/ftml-e846b73067ccf5a5)
[INFO] [stdout] 
[INFO] [stdout] running 66 tests
[INFO] [stderr] Feb 26 18:35:39.247 INFO Test logging message, span: 5..10, module: ftml::span_wrap:62
[INFO] [stdout] test data::page_ref::page_ref ... ok
[INFO] [stdout] test ffi::string::ffi_string ... ok
[INFO] [stdout] test ffi::vec::vec ... ok
[INFO] [stdout] test includes::include_ref::to_owned ... ok
[INFO] [stdout] test includes::includer::debug::map_wrap ... ok
[INFO] [stdout] test non_empty_vec::others ... ok
[INFO] [stdout] test parsing::depth::depth_types ... ok
[INFO] [stdout] test parsing::depth::depth ... ok
[INFO] [stdout] test info::info ... ok
[INFO] [stdout] test parsing::exception::log ... ok
[INFO] [stdout] test parsing::outcome::default ... ok
[INFO] [stdout] test non_empty_vec::first ... ok
[INFO] [stdout] test parsing::outcome::outcome ... ok
[INFO] [stdout] test parsing::rule::impls::block::blocks::char::test_get_char ... ok
[INFO] [stdout] test parsing::rule::impls::block::blocks::char::test_strip_entity ... ok
[INFO] [stdout] test parsing::parser_wrap::wrap ... ok
[INFO] [stdout] test parsing::rule::impls::block::blocks::embed::embed_builder_types ... ok
[INFO] [stdout] test parsing::rule::impls::block::blocks::module::mapping::module_rule_map ... ok
[INFO] [stdout] test parsing::rule::impls::link_triple::test_strip_category ... ok
[INFO] [stdout] test parsing::parser::parser_newline_flag ... ok
[INFO] [stdout] test parsing::rule::impls::block::blocks::date::date ... ok
[INFO] [stdout] test parsing::rule::impls::block::mapping::block_rule_map ... ok
[INFO] [stdout] test parsing::string::test_parse_string ... ok
[INFO] [stdout] test parsing::rule::impls::block::blocks::date::timezone ... ok
[INFO] [stdout] test preproc::fn_type ... ok
[INFO] [stdout] test render::html::escape::test ... ok
[INFO] [stdout] test parsing::string::test_slice_middle ... ok
[INFO] [stdout] test render::html::attributes::attr_macro ... ok
[INFO] [stdout] test render::debug::debug ... ok
[INFO] [stdout] test render::html::test::html ... ok
[INFO] [stdout] test parsing::rule::impls::block::blocks::char::test_get_entity ... ok
[INFO] [stdout] test render::html::random::html_id ... ok
[INFO] [stdout] test parsing::token::test::tokens ... ok
[INFO] [stdout] test render::null::null ... ok
[INFO] [stdout] test render::json::json ... ok
[INFO] [stdout] test preproc::whitespace::test_substitute ... ok
[INFO] [stdout] test preproc::whitespace::regexes ... ok
[INFO] [stdout] test preproc::typography::regexes ... ok
[INFO] [stdout] test preproc::typography::test_substitute ... ok
[INFO] [stdout] test data::page_ref::prop::page_ref_prop ... ok
[INFO] [stdout] test text::slice ... ok
[INFO] [stdout] test preproc::test::prefilter ... ok
[INFO] [stdout] test span_wrap::span_wrap ... ok
[INFO] [stdout] test tree::borrowed_to_owned ... ok
[INFO] [stdout] test tree::heading::heading ... ok
[INFO] [stdout] test tree::link::test_link_location ... ok
[INFO] [stdout] test tree::element::iter_ref::iter ... ok
[INFO] [stdout] test tree::element::iter_owned::iter ... ok
[INFO] [stdout] test includes::test::includes ... ok
[INFO] [stdout] test utf16::test::utf16_indices ... ok
[INFO] [stdout] test utf16::test::utf16_slices ... ok
[INFO] [stdout] test tree::align::image_alignment ... ok
[INFO] [stdout] test test::large::recursion_depth ... ok
[INFO] [stdout] test test::settings::settings ... ok
[INFO] [stdout] test preproc::test::prefilter_prop ... ok
[INFO] [stdout] test parsing::check_step::check_step_fail - should panic ... ok
[INFO] [stdout] test parsing::string::test_parse_string_escape - should panic ... ok
[INFO] [stdout] test text::slice_invalid - should panic ... ok
[INFO] [stdout] test utf16::test::utf16_prop ... ok
[INFO] [stdout] test tokenizer::test::tokenizer_prop ... ok
[INFO] [stdout] test test::large::large_payload ... ok
[INFO] [stdout] test parsing::depth::test::shallow_depth_prop ... ok
[INFO] [stdout] test test::ast::ast_and_html ... ok
[INFO] [stdout] test parsing::depth::test::deep_depth_prop ... ok
[INFO] [stdout] test test::prop::render_text_prop ... ok
[INFO] [stdout] test test::prop::render_html_prop ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 66 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 27.72s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests ftml
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "673af70e693482a1f5a996bfaf2593af10fe6a423c4aa22de9843959bf7a41d9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "673af70e693482a1f5a996bfaf2593af10fe6a423c4aa22de9843959bf7a41d9", kill_on_drop: false }`
[INFO] [stdout] 673af70e693482a1f5a996bfaf2593af10fe6a423c4aa22de9843959bf7a41d9