[INFO] fetching crate xmlparser 0.13.3...
[INFO] testing xmlparser-0.13.3 against beta-2022-02-22 for beta-1.60-1
[INFO] extracting crate xmlparser 0.13.3 into /workspace/builds/worker-44/source
[INFO] validating manifest of crates.io crate xmlparser 0.13.3 on toolchain beta-2022-02-22
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-02-22" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate xmlparser 0.13.3
[INFO] finished tweaking crates.io crate xmlparser 0.13.3
[INFO] tweaked toml for crates.io crate xmlparser 0.13.3 written to /workspace/builds/worker-44/source/Cargo.toml
[INFO] crate crates.io crate xmlparser 0.13.3 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-02-22" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-44/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-44/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" "+beta-2022-02-22" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] b54c32d92356739c9b38bc3696620b48939c4e2677eac5bd93a192fdc6780e93
[INFO] running `Command { std: "docker" "start" "-a" "b54c32d92356739c9b38bc3696620b48939c4e2677eac5bd93a192fdc6780e93", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b54c32d92356739c9b38bc3696620b48939c4e2677eac5bd93a192fdc6780e93", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b54c32d92356739c9b38bc3696620b48939c4e2677eac5bd93a192fdc6780e93", kill_on_drop: false }`
[INFO] [stdout] b54c32d92356739c9b38bc3696620b48939c4e2677eac5bd93a192fdc6780e93
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-44/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-44/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" "+beta-2022-02-22" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2da83968078c985d8ef95f64d7009d35f9bbda6c5c119895df1068131e278910
[INFO] running `Command { std: "docker" "start" "-a" "2da83968078c985d8ef95f64d7009d35f9bbda6c5c119895df1068131e278910", kill_on_drop: false }`
[INFO] [stderr]    Compiling xmlparser v0.13.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.51s
[INFO] running `Command { std: "docker" "inspect" "2da83968078c985d8ef95f64d7009d35f9bbda6c5c119895df1068131e278910", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2da83968078c985d8ef95f64d7009d35f9bbda6c5c119895df1068131e278910", kill_on_drop: false }`
[INFO] [stdout] 2da83968078c985d8ef95f64d7009d35f9bbda6c5c119895df1068131e278910
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-44/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-44/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" "+beta-2022-02-22" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7c6497bbff256f2a33889c52cbabbdf84db2f915de354c2982e394bd4351d112
[INFO] running `Command { std: "docker" "start" "-a" "7c6497bbff256f2a33889c52cbabbdf84db2f915de354c2982e394bd4351d112", kill_on_drop: false }`
[INFO] [stderr]    Compiling xmlparser v0.13.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 1.42s
[INFO] running `Command { std: "docker" "inspect" "7c6497bbff256f2a33889c52cbabbdf84db2f915de354c2982e394bd4351d112", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7c6497bbff256f2a33889c52cbabbdf84db2f915de354c2982e394bd4351d112", kill_on_drop: false }`
[INFO] [stdout] 7c6497bbff256f2a33889c52cbabbdf84db2f915de354c2982e394bd4351d112
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-44/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-44/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" "+beta-2022-02-22" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 7ae9203bbada3ad9a53c6eee6f8c903af65ac06b581f2d410b6f28f8eb4f4ea6
[INFO] running `Command { std: "docker" "start" "-a" "7ae9203bbada3ad9a53c6eee6f8c903af65ac06b581f2d410b6f28f8eb4f4ea6", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.06s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/xmlparser-a1569fc9d2ebee92)
[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] [stderr]      Running tests/api.rs (/opt/rustwide/target/debug/deps/api-5ff0ef77ba227c82)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test err_size_1 ... ok
[INFO] [stdout] test err_size_2 ... ok
[INFO] [stdout] test span_size ... ok
[INFO] [stdout] test text_pos_3 ... ok
[INFO] [stdout] test text_pos_1 ... ok
[INFO] [stdout] test text_pos_2 ... ok
[INFO] [stdout] test token_size ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cdata.rs (/opt/rustwide/target/debug/deps/cdata-b6a06a6b81797a90)
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test cdata_01 ... ok
[INFO] [stdout] test cdata_02 ... ok
[INFO] [stdout] test cdata_06 ... ok
[INFO] [stdout] test cdata_03 ... ok
[INFO] [stdout] test cdata_07 ... ok
[INFO] [stdout] test cdata_08 ... ok
[INFO] [stdout] test cdata_04 ... ok
[INFO] [stdout] test cdata_09 ... ok
[INFO] [stdout] test cdata_err_01 ... ok
[INFO] [stdout] test cdata_05 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/comments.rs (/opt/rustwide/target/debug/deps/comments-bb82db0d6f00b83a)
[INFO] [stdout] 
[INFO] [stdout] running 46 tests
[INFO] [stdout] test comment_01 ... ok
[INFO] [stdout] test comment_04 ... ok
[INFO] [stdout] test comment_02 ... ok
[INFO] [stdout] test comment_03 ... ok
[INFO] [stdout] test comment_05 ... ok
[INFO] [stdout] test comment_06 ... ok
[INFO] [stdout] test comment_07 ... ok
[INFO] [stdout] test comment_08 ... ok
[INFO] [stdout] test comment_09 ... ok
[INFO] [stdout] test comment_10 ... ok
[INFO] [stdout] test comment_err_01 ... ok
[INFO] [stdout] test comment_err_02 ... ok
[INFO] [stdout] test comment_11 ... ok
[INFO] [stdout] test comment_err_03 ... ok
[INFO] [stdout] test comment_err_04 ... ok
[INFO] [stdout] test comment_err_05 ... ok
[INFO] [stdout] test comment_err_07 ... ok
[INFO] [stdout] test comment_err_06 ... ok
[INFO] [stdout] test comment_err_08 ... ok
[INFO] [stdout] test comment_err_09 ... ok
[INFO] [stdout] test comment_err_10 ... ok
[INFO] [stdout] test comment_err_12 ... ok
[INFO] [stdout] test comment_err_11 ... ok
[INFO] [stdout] test comment_err_13 ... ok
[INFO] [stdout] test comment_err_14 ... ok
[INFO] [stdout] test comment_err_15 ... ok
[INFO] [stdout] test comment_err_16 ... ok
[INFO] [stdout] test comment_err_17 ... ok
[INFO] [stdout] test comment_err_19 ... ok
[INFO] [stdout] test comment_err_18 ... ok
[INFO] [stdout] test comment_err_20 ... ok
[INFO] [stdout] test comment_err_21 ... ok
[INFO] [stdout] test comment_err_23 ... ok
[INFO] [stdout] test comment_err_24 ... ok
[INFO] [stdout] test comment_err_22 ... ok
[INFO] [stdout] test comment_err_25 ... ok
[INFO] [stdout] test comment_err_26 ... ok
[INFO] [stdout] test comment_err_27 ... ok
[INFO] [stdout] test comment_err_28 ... ok
[INFO] [stdout] test comment_err_29 ... ok
[INFO] [stdout] test comment_err_30 ... ok
[INFO] [stdout] test comment_err_32 ... ok
[INFO] [stdout] test comment_err_31 ... ok
[INFO] [stdout] test comment_err_33 ... ok
[INFO] [stdout] test comment_err_34 ... ok
[INFO] [stdout] test comment_err_35 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 46 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/doctype.rs (/opt/rustwide/target/debug/deps/doctype-4bbf1e3ea1f412a4)
[INFO] [stdout] 
[INFO] [stdout] running 19 tests
[INFO] [stdout] test dtd_03 ... ok
[INFO] [stdout] test dtd_02 ... ok
[INFO] [stdout] test dtd_01 ... ok
[INFO] [stdout] test dtd_04 ... ok
[INFO] [stdout] test dtd_05 ... ok
[INFO] [stdout] test dtd_07 ... ok
[INFO] [stdout] test dtd_06 ... ok
[INFO] [stdout] test dtd_entity_01 ... ok
[INFO] [stdout] test dtd_entity_02 ... ok
[INFO] [stdout] test dtd_entity_03 ... ok
[INFO] [stdout] test dtd_08 ... ok
[INFO] [stdout] test dtd_entity_04 ... ok
[INFO] [stdout] test dtd_entity_05 ... ok
[INFO] [stdout] test dtd_err_01 ... ok
[INFO] [stdout] test dtd_entity_06 ... ok
[INFO] [stdout] test dtd_err_02 ... ok
[INFO] [stdout] test dtd_err_03 ... ok
[INFO] [stdout] test dtd_err_04 ... ok
[INFO] [stdout] test dtd_err_05 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/document.rs (/opt/rustwide/target/debug/deps/document-07a5fd1558351545)
[INFO] [stdout] 
[INFO] [stdout] running 15 tests
[INFO] [stderr]      Running tests/elements.rs (/opt/rustwide/target/debug/deps/elements-babe51345778e4d7)
[INFO] [stdout] test document_01 ... ok
[INFO] [stdout] test document_03 ... ok
[INFO] [stdout] test document_06 ... ok
[INFO] [stdout] test document_08 ... ok
[INFO] [stdout] test document_07 ... ok
[INFO] [stdout] test document_09 ... ok
[INFO] [stdout] test document_err_04 ... ok
[INFO] [stdout] test document_err_01 ... ok
[INFO] [stdout] test document_err_02 ... ok
[INFO] [stdout] test document_err_05 ... ok
[INFO] [stdout] test document_02 ... ok
[INFO] [stdout] test document_05 ... ok
[INFO] [stdout] test document_err_03 ... ok
[INFO] [stdout] test document_err_06 ... ok
[INFO] [stdout] test parse_fragment_1 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 40 tests
[INFO] [stdout] test attribute_01 ... ok
[INFO] [stdout] test attribute_02 ... ok
[INFO] [stdout] test attribute_03 ... ok
[INFO] [stdout] test attribute_04 ... ok
[INFO] [stdout] test attribute_05 ... ok
[INFO] [stdout] test attribute_07 ... ok
[INFO] [stdout] test attribute_err_02 ... ok
[INFO] [stdout] test attribute_06 ... ok
[INFO] [stdout] test attribute_err_03 ... ok
[INFO] [stdout] test attribute_err_01 ... ok
[INFO] [stdout] test attribute_err_05 ... ok
[INFO] [stdout] test attribute_err_06 ... ok
[INFO] [stdout] test element_01 ... ok
[INFO] [stdout] test element_err_01 ... ok
[INFO] [stdout] test element_03 ... ok
[INFO] [stdout] test element_08 ... ok
[INFO] [stdout] test element_02 ... ok
[INFO] [stdout] test element_06 ... ok
[INFO] [stdout] test element_07 ... ok
[INFO] [stdout] test attribute_err_07 ... ok
[INFO] [stdout] test element_err_02 ... ok
[INFO] [stdout] test element_04 ... ok
[INFO] [stdout] test element_err_06 ... ok
[INFO] [stdout] test element_err_03 ... ok
[INFO] [stdout] test element_err_05 ... ok
[INFO] [stdout] test attribute_err_04 ... ok
[INFO] [stdout] test element_err_12 ... ok
[INFO] [stdout] test element_err_10 ... ok
[INFO] [stdout] test element_err_09 ... ok
[INFO] [stdout] test element_err_08 ... ok
[INFO] [stdout] test element_err_04 ... ok
[INFO] [stdout] test element_err_11 ... ok
[INFO] [stdout] test element_err_07 ... ok
[INFO] [stdout] test element_err_13 ... ok
[INFO] [stdout] test element_err_17 ... ok
[INFO] [stdout] test element_err_14 ... ok
[INFO] [stdout] test element_err_16 ... ok
[INFO] [stdout] test element_err_18 ... ok
[INFO] [stdout] test element_err_15 ... ok
[INFO] [stdout] test element_err_19 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 40 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/pi.rs (/opt/rustwide/target/debug/deps/pi-b3008230340c243d)
[INFO] [stdout] 
[INFO] [stdout] running 32 tests
[INFO] [stderr]      Running tests/text.rs (/opt/rustwide/target/debug/deps/text-4200303ba563a1fe)
[INFO] [stdout] test declaration_01 ... ok
[INFO] [stdout] test declaration_09 ... ok
[INFO] [stdout] test declaration_err_03 ... ok
[INFO] [stdout] test declaration_08 ... ok
[INFO] [stdout] test declaration_07 ... ok
[INFO] [stdout] test declaration_05 ... ok
[INFO] [stdout] test declaration_err_04 ... ok
[INFO] [stdout] test declaration_err_02 ... ok
[INFO] [stdout] test declaration_10 ... ok
[INFO] [stdout] test declaration_err_01 ... ok
[INFO] [stdout] test declaration_04 ... ok
[INFO] [stdout] test declaration_err_05 ... ok
[INFO] [stdout] test declaration_03 ... ok
[INFO] [stdout] test declaration_02 ... ok
[INFO] [stdout] test declaration_06 ... ok
[INFO] [stdout] test declaration_err_09 ... ok
[INFO] [stdout] test declaration_err_06 ... ok
[INFO] [stdout] test declaration_err_07 ... ok
[INFO] [stdout] test declaration_err_11 ... ok
[INFO] [stdout] test declaration_err_10 ... ok
[INFO] [stdout] test declaration_err_13 ... ok
[INFO] [stdout] test declaration_err_12 ... ok
[INFO] [stdout] test declaration_err_14 ... ok
[INFO] [stdout] test declaration_err_16 ... ok
[INFO] [stdout] test declaration_err_08 ... ok
[INFO] [stdout] test pi_01 ... ok
[INFO] [stdout] test pi_02 ... ok
[INFO] [stdout] test declaration_err_15 ... ok
[INFO] [stdout] test pi_03 ... ok
[INFO] [stdout] test pi_err_01 ... ok
[INFO] [stdout] test pi_05 ... ok
[INFO] [stdout] test pi_04 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 32 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test text_01 ... ok
[INFO] [stdout] test text_err_01 ... ok
[INFO] [stdout] test text_err_02 ... ok
[INFO] [stdout] test text_02 ... ok
[INFO] [stdout] test text_07 ... ok
[INFO] [stdout] test text_03 ... ok
[INFO] [stdout] test text_06 ... ok
[INFO] [stdout] test text_04 ... ok
[INFO] [stdout] test text_05 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/token.rs (/opt/rustwide/target/debug/deps/token-d1db7989e2017063)
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests xmlparser
[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] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test src/stream.rs - stream::Stream::advance (line 151) ... ok
[INFO] [stdout] test src/stream.rs - stream::Stream::consume_byte (line 192) ... ok
[INFO] [stdout] test src/stream.rs - stream::Stream::gen_text_pos_from (line 592) ... ok
[INFO] [stdout] test src/lib.rs - (line 6) ... ok
[INFO] [stdout] test src/stream.rs - stream::Stream::starts_with (line 170) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.53s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "7ae9203bbada3ad9a53c6eee6f8c903af65ac06b581f2d410b6f28f8eb4f4ea6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7ae9203bbada3ad9a53c6eee6f8c903af65ac06b581f2d410b6f28f8eb4f4ea6", kill_on_drop: false }`
[INFO] [stdout] 7ae9203bbada3ad9a53c6eee6f8c903af65ac06b581f2d410b6f28f8eb4f4ea6