[INFO] fetching crate roxmltree 0.14.1...
[INFO] testing roxmltree-0.14.1 against beta-2021-09-08 for beta-1.56-1
[INFO] extracting crate roxmltree 0.14.1 into /workspace/builds/worker-21/source
[INFO] validating manifest of crates.io crate roxmltree 0.14.1 on toolchain beta-2021-09-08
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-09-08" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate roxmltree 0.14.1
[INFO] finished tweaking crates.io crate roxmltree 0.14.1
[INFO] tweaked toml for crates.io crate roxmltree 0.14.1 written to /workspace/builds/worker-21/source/Cargo.toml
[INFO] crate crates.io crate roxmltree 0.14.1 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-09-08" "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-21/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-21/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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-09-08" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] e7db8ce1823e7442d5805424d82543ea678299d19b69f27210f32bd5bedbfb99
[INFO] running `Command { std: "docker" "start" "-a" "e7db8ce1823e7442d5805424d82543ea678299d19b69f27210f32bd5bedbfb99", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e7db8ce1823e7442d5805424d82543ea678299d19b69f27210f32bd5bedbfb99", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e7db8ce1823e7442d5805424d82543ea678299d19b69f27210f32bd5bedbfb99", kill_on_drop: false }`
[INFO] [stdout] e7db8ce1823e7442d5805424d82543ea678299d19b69f27210f32bd5bedbfb99
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-21/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-21/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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-09-08" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 09fad6252418ac37bb4ba074b7454142c407235fcbf37f3b4ade3456b318d18e
[INFO] running `Command { std: "docker" "start" "-a" "09fad6252418ac37bb4ba074b7454142c407235fcbf37f3b4ade3456b318d18e", kill_on_drop: false }`
[INFO] [stderr]    Compiling xmlparser v0.13.3
[INFO] [stderr]    Compiling roxmltree v0.14.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.77s
[INFO] running `Command { std: "docker" "inspect" "09fad6252418ac37bb4ba074b7454142c407235fcbf37f3b4ade3456b318d18e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "09fad6252418ac37bb4ba074b7454142c407235fcbf37f3b4ade3456b318d18e", kill_on_drop: false }`
[INFO] [stdout] 09fad6252418ac37bb4ba074b7454142c407235fcbf37f3b4ade3456b318d18e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-21/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-21/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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-09-08" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 4d24aeece8a396e84140445c08fcaaa09b18c4524e051f85e6989f11894c3fd7
[INFO] running `Command { std: "docker" "start" "-a" "4d24aeece8a396e84140445c08fcaaa09b18c4524e051f85e6989f11894c3fd7", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling difference v2.0.0
[INFO] [stderr]    Compiling ansi_term v0.11.0
[INFO] [stderr]    Compiling pretty_assertions v0.5.1
[INFO] [stderr]    Compiling roxmltree v0.14.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 3.59s
[INFO] running `Command { std: "docker" "inspect" "4d24aeece8a396e84140445c08fcaaa09b18c4524e051f85e6989f11894c3fd7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4d24aeece8a396e84140445c08fcaaa09b18c4524e051f85e6989f11894c3fd7", kill_on_drop: false }`
[INFO] [stdout] 4d24aeece8a396e84140445c08fcaaa09b18c4524e051f85e6989f11894c3fd7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-21/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-21/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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-09-08" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] f7e68293f3e0d9a0568c6710cdf8ab58a831cc61f84dd92c8496560636eeac00
[INFO] running `Command { std: "docker" "start" "-a" "f7e68293f3e0d9a0568c6710cdf8ab58a831cc61f84dd92c8496560636eeac00", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.50s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/roxmltree-49a2ede3b2a1e239)
[INFO] [stdout] running 0 tests
[INFO] [stderr]      Running tests/api.rs (/opt/rustwide/target/debug/deps/api-0ab7b7f411bfaee6)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test get_text_01 ... ok
[INFO] [stdout] test lifetimes ... ok
[INFO] [stdout] test next_sibling_element_01 ... ok
[INFO] [stdout] test lookup_prefix_02 ... ok
[INFO] [stdout] test get_text_02 ... ok
[INFO] [stdout] test get_pi ... ok
[INFO] [stdout] test lookup_namespace_uri ... ok
[INFO] [stdout] test api_01 ... ok
[INFO] [stdout] test tag_name_lifetime ... ok
[INFO] [stdout] test nodes_document_order ... ok
[INFO] [stdout] test text_pos_01 ... ok
[INFO] [stdout] test lookup_prefix_01 ... ok
[INFO] [stdout] test next_prev_element_01 ... ok
[INFO] [stderr]      Running tests/ast.rs (/opt/rustwide/target/debug/deps/ast-81c7ea8d4f5311cf)
[INFO] [stdout] test text_pos_03 ... ok
[INFO] [stdout] test text_pos_02 ... ok
[INFO] [stdout] test root_element_01 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/dom-api.rs (/opt/rustwide/target/debug/deps/dom_api-4212452406336822)
[INFO] [stdout] test compare_ast ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test child_element_count ... ok
[INFO] [stdout] test contains ... ok
[INFO] [stdout] test parent_element ... ok
[INFO] [stdout] test owner_document ... ok
[INFO] [stderr]    Doc-tests roxmltree
[INFO] [stdout] test first_element_child ... ok
[INFO] [stdout] test get_element_by_id ... ok
[INFO] [stdout] test last_element_child ... ok
[INFO] [stdout] test get_elements_by_tag_name_ns ... ok
[INFO] [stdout] test get_elements_by_tag_name ... ok
[INFO] [stdout] test children ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.10s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 31 tests
[INFO] [stdout] test src/lib.rs - Node::has_tag_name (line 817) ... ok
[INFO] [stdout] test src/lib.rs - Namespace::name (line 519) ... ok
[INFO] [stdout] test src/lib.rs - Node::lookup_prefix (line 872) ... ok
[INFO] [stdout] test src/lib.rs - Attribute::value (line 438) ... ok
[INFO] [stdout] test src/lib.rs - ExpandedName::name (line 629) ... ok
[INFO] [stdout] test src/lib.rs - Document::root_element (line 131) ... ok
[INFO] [stdout] test src/parse.rs - parse::Document::parse_with_options (line 267) ... ok
[INFO] [stdout] test src/lib.rs - Document::get_node (line 105) ... ok
[INFO] [stdout] test src/lib.rs - Node::namespaces (line 989) ... ok
[INFO] [stdout] test src/lib.rs - Document::text_pos_at (line 155) ... ok
[INFO] [stdout] test src/lib.rs - Node::has_attribute (line 947) ... ok
[INFO] [stdout] test src/lib.rs - ExpandedName::namespace (line 615) ... ok
[INFO] [stdout] test src/lib.rs - Node::lookup_prefix (line 866) ... ok
[INFO] [stdout] test src/lib.rs - Node::tail (line 1056) ... ok
[INFO] [stdout] test src/lib.rs - Node::tag_name (line 799) ... ok
[INFO] [stdout] test src/lib.rs - Attribute::namespace (line 404) ... ok
[INFO] [stdout] test src/lib.rs - Document::input_text (line 175) ... ok
[INFO] [stdout] test src/lib.rs - Node::attribute (line 908) ... ok
[INFO] [stdout] test src/lib.rs - Node::attribute (line 914) ... ok
[INFO] [stdout] test src/lib.rs - Node::text (line 1014) ... ok
[INFO] [stdout] test src/lib.rs - Node::lookup_namespace_uri (line 895) ... ok
[INFO] [stdout] test src/lib.rs - Node::text (line 1027) ... ok
[INFO] [stdout] test src/lib.rs - Document::root (line 89) ... ok
[INFO] [stdout] test src/lib.rs - Namespace::uri (line 535) ... ok
[INFO] [stdout] test src/lib.rs - Attribute::name (line 421) ... ok
[INFO] [stdout] test src/lib.rs - Namespace::name (line 511) ... ok
[INFO] [stdout] test src/lib.rs - Node::default_namespace (line 853) ... ok
[INFO] [stdout] test src/lib.rs - Node::lookup_namespace_uri (line 889) ... ok
[INFO] [stdout] test src/lib.rs - Node::default_namespace (line 847) ... ok
[INFO] [stdout] test src/parse.rs - parse::Document::parse (line 251) ... ok
[INFO] [stdout] test src/lib.rs - Node::attributes (line 970) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 31 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.17s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "f7e68293f3e0d9a0568c6710cdf8ab58a831cc61f84dd92c8496560636eeac00", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f7e68293f3e0d9a0568c6710cdf8ab58a831cc61f84dd92c8496560636eeac00", kill_on_drop: false }`
[INFO] [stdout] f7e68293f3e0d9a0568c6710cdf8ab58a831cc61f84dd92c8496560636eeac00