[INFO] fetching crate atelier_smithy 0.2.13...
[INFO] testing atelier_smithy-0.2.13 against master#a77da2d454e6caa227a85b16410b95f93495e7e0 for pr-91031
[INFO] extracting crate atelier_smithy 0.2.13 into /workspace/builds/worker-97/source
[INFO] validating manifest of crates.io crate atelier_smithy 0.2.13 on toolchain a77da2d454e6caa227a85b16410b95f93495e7e0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate atelier_smithy 0.2.13
[INFO] finished tweaking crates.io crate atelier_smithy 0.2.13
[INFO] tweaked toml for crates.io crate atelier_smithy 0.2.13 written to /workspace/builds/worker-97/source/Cargo.toml
[INFO] crate crates.io crate atelier_smithy 0.2.13 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "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-97/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-97/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 77f055320c759840099f7233a4dd2039f21036885caa9580ebff5e09e5075371
[INFO] running `Command { std: "docker" "start" "-a" "77f055320c759840099f7233a4dd2039f21036885caa9580ebff5e09e5075371", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "77f055320c759840099f7233a4dd2039f21036885caa9580ebff5e09e5075371", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "77f055320c759840099f7233a4dd2039f21036885caa9580ebff5e09e5075371", kill_on_drop: false }`
[INFO] [stdout] 77f055320c759840099f7233a4dd2039f21036885caa9580ebff5e09e5075371
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-97/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-97/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=forbid" "-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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b0ec8be042fd3a8cf2e7026d51789930a88c20363b75e635f96a5032d84d6382
[INFO] running `Command { std: "docker" "start" "-a" "b0ec8be042fd3a8cf2e7026d51789930a88c20363b75e635f96a5032d84d6382", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling ucd-trie v0.1.3
[INFO] [stderr]    Compiling libc v0.2.97
[INFO] [stderr]    Compiling syn v1.0.73
[INFO] [stderr]    Compiling maplit v1.0.2
[INFO] [stderr]    Compiling rustc-demangle v0.1.20
[INFO] [stderr]    Compiling regex-syntax v0.6.25
[INFO] [stderr]    Compiling ascii_tree v0.1.1
[INFO] [stderr]    Compiling paste v1.0.5
[INFO] [stderr]    Compiling escape_string v0.1.1
[INFO] [stderr]    Compiling memchr v2.4.0
[INFO] [stderr]    Compiling proc-macro2 v1.0.27
[INFO] [stderr]    Compiling backtrace v0.3.60
[INFO] [stderr]    Compiling addr2line v0.15.2
[INFO] [stderr]    Compiling error-chain v0.12.4
[INFO] [stderr]    Compiling miniz_oxide v0.4.4
[INFO] [stderr]    Compiling pest v2.1.3
[INFO] [stderr]    Compiling object v0.25.3
[INFO] [stderr]    Compiling aho-corasick v0.7.18
[INFO] [stderr]    Compiling quote v1.0.9
[INFO] [stderr]    Compiling pest_meta v2.1.3
[INFO] [stderr]    Compiling regex v1.5.4
[INFO] [stderr]    Compiling pest_generator v2.1.3
[INFO] [stderr]    Compiling atelier_core v0.2.20
[INFO] [stderr]    Compiling pest_derive v2.1.0
[INFO] [stderr]    Compiling pest_ascii_tree v0.1.0
[INFO] [stderr]    Compiling atelier_smithy v0.2.13 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2m 30s
[INFO] running `Command { std: "docker" "inspect" "b0ec8be042fd3a8cf2e7026d51789930a88c20363b75e635f96a5032d84d6382", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b0ec8be042fd3a8cf2e7026d51789930a88c20363b75e635f96a5032d84d6382", kill_on_drop: false }`
[INFO] [stdout] b0ec8be042fd3a8cf2e7026d51789930a88c20363b75e635f96a5032d84d6382
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-97/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-97/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=forbid" "-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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] d8667aa25c0d306f24a5e3baafe7e7ac498b69923f6ec519471aa84bf96c2d95
[INFO] running `Command { std: "docker" "start" "-a" "d8667aa25c0d306f24a5e3baafe7e7ac498b69923f6ec519471aa84bf96c2d95", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling diff v0.1.12
[INFO] [stderr]    Compiling pretty_assertions v0.7.2
[INFO] [stderr]    Compiling atelier_test v0.1.3
[INFO] [stderr]    Compiling atelier_smithy v0.2.13 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 6.12s
[INFO] running `Command { std: "docker" "inspect" "d8667aa25c0d306f24a5e3baafe7e7ac498b69923f6ec519471aa84bf96c2d95", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d8667aa25c0d306f24a5e3baafe7e7ac498b69923f6ec519471aa84bf96c2d95", kill_on_drop: false }`
[INFO] [stdout] d8667aa25c0d306f24a5e3baafe7e7ac498b69923f6ec519471aa84bf96c2d95
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-97/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-97/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=forbid" "-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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] f4f64a95d5804c9d2323e7d3af09ff0475b0052403a5fca41cac30e42cd257de
[INFO] running `Command { std: "docker" "start" "-a" "f4f64a95d5804c9d2323e7d3af09ff0475b0052403a5fca41cac30e42cd257de", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.05s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/atelier_smithy-559b5b7abf4951e7)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test parser::smithy::tests::test_api_level_parser ... ok
[INFO] [stdout] test parser::smithy::tests::test_low_level_parser ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/selector_tests.rs (/opt/rustwide/target/debug/deps/selector_tests-779715c8c07ab65e)
[INFO] [stdout] 
[INFO] [stdout] running 79 tests
[INFO] [stdout] test test_spec_context_values_1 ... ok
[INFO] [stdout] test test_spec_neighbors_3 ... ok
[INFO] [stdout] test test_spec_neighbors_11 ... ok
[INFO] [stdout] test test_spec_neighbors_1 ... ok
[INFO] [stdout] test test_spec_neighbors_2 ... ok
[INFO] [stdout] test test_spec_functions_1 ... ok
[INFO] [stdout] test test_spec_neighbors_6 ... ok
[INFO] [stdout] test test_spec_context_values_2 ... ok
[INFO] [stdout] test test_spec_context_values_5 ... ok
[INFO] [stdout] test test_spec_neighbors_5 ... ok
[INFO] [stdout] test test_spec_id_attribute_5 ... ok
[INFO] [stdout] test test_spec_id_attribute_7 ... ok
[INFO] [stdout] test test_spec_neighbors_4 ... ok
[INFO] [stdout] test test_spec_multiple_1 ... ok
[INFO] [stdout] test test_spec_context_values_6 ... ok
[INFO] [stdout] test test_spec_id_attribute_2 ... ok
[INFO] [stdout] test test_spec_context_values_4 ... ok
[INFO] [stdout] test test_spec_id_attribute_4 ... ok
[INFO] [stdout] test test_spec_id_attribute_1 ... ok
[INFO] [stdout] test test_spec_context_values_3 ... ok
[INFO] [stdout] test test_spec_neighbors_7 ... ok
[INFO] [stdout] test test_spec_context_values_7 ... ok
[INFO] [stdout] test test_spec_id_attribute_6 ... ok
[INFO] [stdout] test test_spec_neighbors_8 ... ok
[INFO] [stdout] test test_spec_neighbors_10 ... ok
[INFO] [stdout] test test_spec_node_attribute_3 ... ok
[INFO] [stdout] test test_spec_node_attribute_1 ... ok
[INFO] [stdout] test test_spec_node_attribute_2 ... ok
[INFO] [stdout] test test_spec_neighbors_9 ... ok
[INFO] [stdout] test test_spec_node_attribute_4 ... ok
[INFO] [stdout] test test_spec_selector_attr_4 ... ok
[INFO] [stdout] test test_spec_selector_attr_3 ... ok
[INFO] [stdout] test test_spec_shape_types_all ... ok
[INFO] [stdout] test test_spec_shape_types_integer ... ok
[INFO] [stdout] test test_spec_shape_types_set ... ok
[INFO] [stdout] test test_spec_shape_types_string ... ok
[INFO] [stdout] test test_spec_shape_types_boolean ... ok
[INFO] [stdout] test test_spec_selector_attr_2 ... ok
[INFO] [stdout] test test_spec_shape_types_byte ... ok
[INFO] [stdout] test test_spec_shape_types_document ... ok
[INFO] [stdout] test test_spec_shape_types_big_integer ... ok
[INFO] [stdout] test test_spec_shape_types_collection ... ok
[INFO] [stdout] test test_spec_shape_types_timestamp ... ok
[INFO] [stdout] test test_spec_selector_attr_5 ... ok
[INFO] [stdout] test test_spec_selector_attr_7 ... ok
[INFO] [stdout] test test_spec_service_attribute_2 ... ok
[INFO] [stdout] test test_spec_service_attribute_4 ... ok
[INFO] [stdout] test test_spec_service_attribute_1 ... ok
[INFO] [stdout] test test_spec_selector_attr_6 ... ok
[INFO] [stdout] test test_spec_service_attribute_3 ... ok
[INFO] [stdout] test test_spec_shape_types_long ... ok
[INFO] [stdout] test test_spec_shape_types_member ... ok
[INFO] [stdout] test test_spec_shape_types_number ... ok
[INFO] [stdout] test test_spec_shape_types_short ... ok
[INFO] [stdout] test test_spec_shape_types_map ... ok
[INFO] [stdout] test test_spec_shape_types_service ... ok
[INFO] [stdout] test test_spec_shape_types_resource ... ok
[INFO] [stdout] test test_spec_shape_types_operation ... ok
[INFO] [stdout] test test_spec_shape_types_simple_type ... ok
[INFO] [stdout] test test_spec_shape_types_blob ... ok
[INFO] [stdout] test test_spec_shape_types_big_decimal ... ok
[INFO] [stdout] test test_spec_shape_types_structure ... ok
[INFO] [stdout] test test_spec_shape_types_float ... ok
[INFO] [stdout] test test_spec_shape_types_list ... ok
[INFO] [stdout] test test_spec_shape_types_union ... ok
[INFO] [stdout] test test_spec_projection_2 ... ok
[INFO] [stdout] test test_spec_projection_1 ... ok
[INFO] [stdout] test test_spec_selector_attr_1 ... ok
[INFO] [stdout] test test_spec_shape_types_double ... ok
[INFO] [stdout] test test_spec_trait_attribute_2 ... ok
[INFO] [stdout] test test_spec_trait_attribute_4 ... ok
[INFO] [stdout] test test_spec_trait_attribute_5 ... ok
[INFO] [stdout] test test_spec_projection_3 ... ok
[INFO] [stdout] test test_spec_trait_attribute_1 ... ok
[INFO] [stdout] test test_spec_trait_attribute_7 ... ok
[INFO] [stdout] test test_spec_trait_attribute_8 ... ok
[INFO] [stdout] test test_spec_trait_attribute_3 ... ok
[INFO] [stdout] test test_spec_trait_attribute_6 ... ok
[INFO] [stdout] test test_spec_shape_types_fail ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 79 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.64s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/smithy_simple_parse_tests.rs (/opt/rustwide/target/debug/deps/smithy_simple_parse_tests-4cc6efbed940c941)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test empty_file ... ok
[INFO] [stdout] test namespace_only ... ok
[INFO] [stdout] test simple_shape_with_block_text_2 ... ok
[INFO] [stdout] test simple_shape_only ... ok
[INFO] [stdout] test simple_shape_with_block_text ... ok
[INFO] [stdout] test simple_shape_with_traits_and_comments ... ok
[INFO] [stdout] test simple_shape_with_traits_and_documentation ... ok
[INFO] [stdout] test simple_shape_with_traits ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/smithy_success_tests.rs (/opt/rustwide/target/debug/deps/smithy_success_tests-41f7cb7f7c0a6771)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test test_motd_example ... ok
[INFO] [stdout] test test_mqtt_api_example ... ok
[INFO] [stdout] test test_waiters_example ... ok
[INFO] [stdout] test test_weather_example ... ok
[INFO] [stdout] test test_smithy_prelude ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/smithy_write_motd.rs (/opt/rustwide/target/debug/deps/smithy_write_motd-a1e3e3bd70d2279c)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test write_motd_model ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/smithy_write_weather.rs (/opt/rustwide/target/debug/deps/smithy_write_weather-c08c1dda2319b89c)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]    Doc-tests atelier_smithy
[INFO] [stdout] test write_weather_example ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test src/lib.rs - (line 32) ... ok
[INFO] [stdout] test src/lib.rs - (line 16) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.41s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "f4f64a95d5804c9d2323e7d3af09ff0475b0052403a5fca41cac30e42cd257de", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f4f64a95d5804c9d2323e7d3af09ff0475b0052403a5fca41cac30e42cd257de", kill_on_drop: false }`
[INFO] [stdout] f4f64a95d5804c9d2323e7d3af09ff0475b0052403a5fca41cac30e42cd257de