[INFO] updating cached repository https://github.com/jlgerber/jsptemplate
[INFO] running `"git" "-c" "credential.helper=" "-c" "credential.helper=/big/crater/work/cargo-home/bin/git-credential-null" "-c" "remote.origin.fetch=refs/heads/*:refs/heads/*" "fetch" "origin" "--force" "--prune"`
[INFO] running `"git" "rev-parse" "HEAD"`
[INFO] [stdout] aa9b65ee3d519904a123682c251662b18a45a6b0
[INFO] testing jlgerber/jsptemplate against beta-2019-09-28 for beta-1.39-1
[INFO] running `"git" "clone" "work/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjlgerber%2Fjsptemplate" "work/builds/worker-1/source"`
[INFO] [stderr] Cloning into 'work/builds/worker-1/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/jlgerber/jsptemplate on toolchain beta-2019-09-28
[INFO] running `"/big/crater/work/cargo-home/bin/cargo" "+beta-2019-09-28" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] started tweaking git repo https://github.com/jlgerber/jsptemplate
[INFO] finished tweaking git repo https://github.com/jlgerber/jsptemplate
[INFO] tweaked toml for git repo https://github.com/jlgerber/jsptemplate written to work/builds/worker-1/source/Cargo.toml
[INFO] crate git repo https://github.com/jlgerber/jsptemplate already has a lockfile, it will not be regenerated
[INFO] running `"/big/crater/work/cargo-home/bin/cargo" "+beta-2019-09-28" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] running `"docker" "create" "-v" "/big/crater/work/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/big/crater/work/builds/worker-1/source:/opt/rustwide/workdir:ro,Z" "-v" "/big/crater/work/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/big/crater/work/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=1000" "-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" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2019-09-28" "build" "--frozen"`
[INFO] [stdout] a3ee7940147b818d89125e00efc425bc749c535ef0cfabcb7da766cf9182a73c
[INFO] running `"docker" "start" "-a" "a3ee7940147b818d89125e00efc425bc749c535ef0cfabcb7da766cf9182a73c"`
[INFO] [stderr]    Compiling serde v1.0.93
[INFO] [stderr]    Compiling winapi v0.3.7
[INFO] [stderr]    Compiling ascii v0.9.1
[INFO] [stderr]    Compiling shellexpand v1.0.0
[INFO] [stderr]    Compiling lexical-core v0.4.1
[INFO] [stderr]    Compiling shellfn-core v0.1.1
[INFO] [stderr]    Compiling syn v0.15.38
[INFO] [stderr]    Compiling stackvector v1.0.6
[INFO] [stderr]    Compiling shellwords v1.0.0
[INFO] [stderr]    Compiling chrono v0.4.7
[INFO] [stderr]    Compiling xnixperms v0.2.0 (https://github.com/jlgerber/xnixperms.git#7a213868)
[INFO] [stderr]    Compiling winconsole v0.10.0
[INFO] [stderr]    Compiling colored v1.8.0
[INFO] [stderr]    Compiling fern v0.5.8
[INFO] [stderr]    Compiling nom v5.0.0
[INFO] [stderr]    Compiling darling_core v0.8.6
[INFO] [stderr]    Compiling synstructure v0.10.2
[INFO] [stderr]    Compiling aschar-casesensitive v0.2.0 (https://github.com/jlgerber/aschar-casesensitive.git#6d27c1c2)
[INFO] [stderr]    Compiling serde_derive v1.0.93
[INFO] [stderr]    Compiling failure_derive v0.1.5
[INFO] [stderr]    Compiling structopt-derive v0.2.16
[INFO] [stderr]    Compiling failure v0.1.5
[INFO] [stderr]    Compiling darling_macro v0.8.6
[INFO] [stderr]    Compiling structopt v0.2.16
[INFO] [stderr]    Compiling dotenv v0.14.1
[INFO] [stderr]    Compiling levelspecter v0.9.0 (https://github.com/jlgerber/levelspecter.git#77e7286a)
[INFO] [stderr]    Compiling darling v0.8.6
[INFO] [stderr]    Compiling shellfn-attribute v0.1.1
[INFO] [stderr]    Compiling shellfn v0.1.1
[INFO] [stderr]    Compiling serde_json v1.0.39
[INFO] [stderr]    Compiling serde_regex v0.4.0
[INFO] [stderr]    Compiling petgraph v0.4.13
[INFO] [stderr]    Compiling jsp v0.36.0 (https://github.com/jlgerber/jobsyspolice.git#734d87b2)
[INFO] [stderr]    Compiling jspcompile v0.7.0 (/opt/rustwide/workdir)
[INFO] [stderr] warning: unused import: `alphanumeric1`
[INFO] [stderr]  --> src/parser/edge.rs:7:36
[INFO] [stderr]   |
[INFO] [stderr] 7 |     character::complete::{ space0, alphanumeric1,},
[INFO] [stderr]   |                                    ^^^^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `#[warn(unused_imports)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: unnecessary trailing semicolon
[INFO] [stderr]    --> src/main.rs:126:35
[INFO] [stderr]     |
[INFO] [stderr] 126 |         .trace(Color::BrightCyan);;
[INFO] [stderr]     |                                   ^ help: remove this semicolon
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(redundant_semicolon)]` on by default
[INFO] [stderr] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 15s
[INFO] running `"docker" "inspect" "a3ee7940147b818d89125e00efc425bc749c535ef0cfabcb7da766cf9182a73c"`
[INFO] running `"docker" "rm" "-f" "a3ee7940147b818d89125e00efc425bc749c535ef0cfabcb7da766cf9182a73c"`
[INFO] [stdout] a3ee7940147b818d89125e00efc425bc749c535ef0cfabcb7da766cf9182a73c
[INFO] running `"docker" "create" "-v" "/big/crater/work/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/big/crater/work/builds/worker-1/source:/opt/rustwide/workdir:ro,Z" "-v" "/big/crater/work/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/big/crater/work/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=1000" "-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" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2019-09-28" "test" "--frozen" "--no-run"`
[INFO] [stdout] 0f209b7cf3f9defac1187daa4cc9d14d16dc156ee409b37d663a6482500dcb82
[INFO] running `"docker" "start" "-a" "0f209b7cf3f9defac1187daa4cc9d14d16dc156ee409b37d663a6482500dcb82"`
[INFO] [stderr]    Compiling jspcompile v0.7.0 (/opt/rustwide/workdir)
[INFO] [stderr] warning: unused import: `alphanumeric1`
[INFO] [stderr]  --> src/parser/edge.rs:7:36
[INFO] [stderr]   |
[INFO] [stderr] 7 |     character::complete::{ space0, alphanumeric1,},
[INFO] [stderr]   |                                    ^^^^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `#[warn(unused_imports)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: unnecessary trailing semicolon
[INFO] [stderr]    --> src/main.rs:126:35
[INFO] [stderr]     |
[INFO] [stderr] 126 |         .trace(Color::BrightCyan);;
[INFO] [stderr]     |                                   ^ help: remove this semicolon
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(redundant_semicolon)]` on by default
[INFO] [stderr] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.22s
[INFO] running `"docker" "inspect" "0f209b7cf3f9defac1187daa4cc9d14d16dc156ee409b37d663a6482500dcb82"`
[INFO] running `"docker" "rm" "-f" "0f209b7cf3f9defac1187daa4cc9d14d16dc156ee409b37d663a6482500dcb82"`
[INFO] [stdout] 0f209b7cf3f9defac1187daa4cc9d14d16dc156ee409b37d663a6482500dcb82
[INFO] running `"docker" "create" "-v" "/big/crater/work/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/big/crater/work/builds/worker-1/source:/opt/rustwide/workdir:ro,Z" "-v" "/big/crater/work/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/big/crater/work/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=1000" "-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" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2019-09-28" "test" "--frozen"`
[INFO] [stdout] 5b9e8fdcb64d164ce75f1f352c13daef1b1aa422d56d80047f0e0c9d626de080
[INFO] running `"docker" "start" "-a" "5b9e8fdcb64d164ce75f1f352c13daef1b1aa422d56d80047f0e0c9d626de080"`
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.54s
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/jspcompile-6794685c0eff4c85
[INFO] [stdout] 
[INFO] [stdout] running 83 tests
[INFO] [stdout] test components::metadata::tests::can_create_metadata ... ok
[INFO] [stdout] test components::metadata::tests::can_create_metadata_and_set_perms ... ok
[INFO] [stdout] test components::metadata::tests::can_create_metadata_and_set_owner ... ok
[INFO] [stdout] test components::metadata::tests::can_create_metadata_and_set_varname ... ok
[INFO] [stdout] test components::metadata::tests::can_create_metadata_and_set_volume ... ok
[INFO] [stdout] test components::metadata::tests::can_get_owner ... ok
[INFO] [stdout] test components::metadata::tests::can_get_permissions ... ok
[INFO] [stdout] test components::metadata::tests::can_get_varname ... ok
[INFO] [stdout] test components::metadata::tests::can_get_volume ... ok
[INFO] [stdout] test components::metadata::tests::can_take_owner ... ok
[INFO] [stdout] test components::metadata::tests::can_take_permissions ... ok
[INFO] [stdout] test components::metadata::tests::can_take_varname ... ok
[INFO] [stdout] test helpers::id_char::is_not_id_char_test ... ok
[INFO] [stdout] test helpers::id_char::is_ident_char_test ... ok
[INFO] [stdout] test helpers::quoted_regex_str::is_quoted_regex_str_bad_test ... ok
[INFO] [stdout] test helpers::perms_test::split_perms ... ok
[INFO] [stdout] test helpers::quoted_regex_str::is_var_bad2_test ... ok
[INFO] [stdout] test helpers::quoted_regex_str::is_quoted_regex_str_test ... ok
[INFO] [stdout] test helpers::regex_char::is_not_id_char_test ... ok
[INFO] [stdout] test helpers::regex_str::is_regex_str_bad_test ... ok
[INFO] [stdout] test helpers::regex_str::is_regex_str_test ... ok
[INFO] [stdout] test helpers::regex_char::is_regex_char_test ... ok
[INFO] [stdout] test helpers::variable::is_var_bad_test ... ok
[INFO] [stdout] test helpers::variable::is_var_bad2_test ... ok
[INFO] [stdout] test helpers::variable::is_var_test ... ok
[INFO] [stdout] test parser::comment::comment::can_parse_comment_2 ... ok
[INFO] [stdout] test parser::comment::comment::can_parse_comment_double_slash ... ok
[INFO] [stdout] test parser::comment::comment::can_parse_comment_pound ... ok
[INFO] [stdout] test parser::edge::parse_edges::can_parse_edge ... ok
[INFO] [stdout] test parser::edge::parse_edges::can_parse_edges_2 ... ok
[INFO] [stdout] test parser::edge::parse_edges::can_parse_spaces_in_header_with_space_ending ... ok
[INFO] [stdout] test parser::edge::parse_edges::can_parse_edges_3 ... ok
[INFO] [stdout] test parser::edge::parse_edges::can_parse_edges_4 ... ok
[INFO] [stdout] test parser::header::section_header::can_parse_no_space_node ... ok
[INFO] [stdout] test parser::header::section_header::can_parse_no_space_header ... ok
[INFO] [stdout] test parser::header::section_header::can_parse_no_space_nodes ... ok
[INFO] [stdout] test parser::header::section_header::can_parse_spaces_in_header_2 ... ok
[INFO] [stdout] test parser::header::section_header::can_parse_spaces_in_header_with_carriage_return_ending ... ok
[INFO] [stdout] test parser::metadata::parse_components_tests::can_parse_2_volumes ... ok
[INFO] [stdout] test parser::header::section_header::can_parse_no_space_unknown ... ok
[INFO] [stdout] test parser::metadata::parse_components_tests::can_parse_volume ... ok
[INFO] [stdout] test parser::metadata::parse_components_tests::can_parse_volume_and_owner_and_perms ... ok
[INFO] [stdout] test parser::header::section_header::can_parse_spaces_in_header ... ok
[INFO] [stdout] test parser::header::section_header::can_parse_no_space_graph ... ok
[INFO] [stdout] test parser::metadata::parse_components_tests::can_parse_volume_and_owner ... ok
[INFO] [stdout] test parser::metadata::permissions_tests::can_parse_perms_no_spaces ... ok
[INFO] [stdout] test parser::metadata::permissions_tests::can_parse_perms_spaces ... ok
[INFO] [stdout] test parser::metadata::parse_components_tests::can_parse_volume_and_owner_and_perms_and_varname ... ok
[INFO] [stdout] test parser::metadata::tests::can_parse_owner_spaces ... ok
[INFO] [stdout] test parser::metadata::tests::can_parse_owner_no_spaces ... ok
[INFO] [stdout] test parser::metadata::tests::can_parse_owner_more_spaces ... ok
[INFO] [stdout] test parser::metadata::tests::can_parse_owner_variable ... ok
[INFO] [stdout] test parser::metadata::varname_tests::can_parse_varname_spaces ... ok
[INFO] [stdout] test parser::metadata::varname_tests::can_parse_varname_no_spaces ... ok
[INFO] [stdout] test parser::metadata::volume_tests::can_parse_volume_no_spaces ... ok
[INFO] [stdout] test parser::metadata::varname_tests::can_parse_varname_more_spaces ... ok
[INFO] [stdout] test parser::metadata::volume_tests::can_parse_volume_spaces ... ok
[INFO] [stdout] test parser::node::parse_node::can_parse_node_pair ... ok
[INFO] [stdout] test parser::node::parse_node::can_parse_revar_simple ... ok
[INFO] [stdout] test parser::node::parse_node::can_parse_node_regexsimple ... ok
[INFO] [stdout] test parser::node::parse_node_pair::can_parse_node_pair ... ok
[INFO] [stdout] test parser::node::parse_node_regexcomplex::can_parse_node_regexsimplewith_return ... ok
[INFO] [stdout] test parser::node::parse_node_regexsimple::can_parse_node_regexsimple ... ok
[INFO] [stdout] test parser::node::parse_node::can_parse_node_revar ... ok
[INFO] [stdout] test parser::node::parse_node_regexcomplex::can_parse_node_regexcomplex ... ok
[INFO] [stdout] test parser::node::parse_node::can_parse_node_regexcomplex ... ok
[INFO] [stdout] test parser::node::parse_node_pair::can_parse_node_pair_meta ... ok
[INFO] [stdout] test parser::node::parse_node_revar::can_parse_node_pair_with_return ... ok
[INFO] [stdout] test parser::node::parse_node_regexsimple::can_parse_node_regexsimplewith_return ... ok
[INFO] [stdout] test parser::regex::parse_regex::can_parse_regex_complex ... ok
[INFO] [stdout] test parser::node::parse_node_simple::can_parse_node_simple ... ok
[INFO] [stdout] test parser::regex::parse_regex::can_parse_regex_simple ... ok
[INFO] [stdout] test parser::node::parse_node_simple::can_parse_node_simple_meta ... ok
[INFO] [stdout] test parser::node::parse_node_revar::can_parse_node_revar ... ok
[INFO] [stdout] test parser::regex::parse_regex_complex::can_parse_regex_complex_with_return ... ok
[INFO] [stdout] test parser::regex::parse_regex_complex::can_parse_regex_complex ... ok
[INFO] [stdout] test parser::regex::parse_regex_complex::fails_parse_regex_complex_missing_quote ... ok
[INFO] [stdout] test parser::regex::parse_regex_simple::can_parse_regex_simple ... ok
[INFO] [stdout] test parser::regex::parse_regex_complex::fails_parse_regex_complex_space ... ok
[INFO] [stdout] test parser::regex::parse_regex_simple::can_parse_regex_simple_with_carriage_return ... ok
[INFO] [stdout] test parser::regex::parse_regex_simple::can_parse_regex_simple_with_return ... ok
[INFO] [stdout] test parser::regex::parse_regex_simple::fails_regex_simple_space ... ok
[INFO] [stdout] test parser::regex::parse_regex_simple::fails_regex_simple_missing_quote ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 83 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/jspcompile-a5812585a0757a5b
[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
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests jspcompile
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test src/components/metadata.rs - components::metadata::Metadata::take_permissions (line 158) ... FAILED
[INFO] [stdout] test src/components/metadata.rs - components::metadata::Metadata::take_varname (line 221) ... FAILED
[INFO] [stdout] test src/components/metadata.rs - components::metadata::Metadata::is_volume (line 75) ... FAILED
[INFO] [stdout] test src/components/metadata.rs - components::metadata::Metadata::varname (line 203) ... FAILED
[INFO] [stdout] test src/components/metadata.rs - components::metadata::Metadata::set_volume (line 53) ... FAILED
[INFO] [stdout] test src/components/metadata.rs - components::metadata::Metadata::permissions (line 135) ... FAILED
[INFO] [stdout] test src/components/metadata.rs - components::metadata::Metadata::set_permissions (line 110) ... ok
[INFO] [stdout] test src/components/metadata.rs - components::metadata::Metadata::set_permissions (line 100) ... ok
[INFO] [stdout] test src/components/metadata.rs - components::metadata::Metadata::set_varname (line 185) ... ok
[INFO] [stdout] test src/components/metadata.rs - components::metadata::Metadata::set_permissions (line 117) ... ok
[INFO] [stdout] test src/loader.rs - loader::Loader::setup (line 88) ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- src/components/metadata.rs - components::metadata::Metadata::take_permissions (line 158) stdout ----
[INFO] [stdout] Couldn't compile the test.
[INFO] [stdout] ---- src/components/metadata.rs - components::metadata::Metadata::take_varname (line 221) stdout ----
[INFO] [stdout] Couldn't compile the test.
[INFO] [stdout] ---- src/components/metadata.rs - components::metadata::Metadata::is_volume (line 75) stdout ----
[INFO] [stdout] Couldn't compile the test.
[INFO] [stdout] ---- src/components/metadata.rs - components::metadata::Metadata::varname (line 203) stdout ----
[INFO] [stdout] Couldn't compile the test.
[INFO] [stdout] ---- src/components/metadata.rs - components::metadata::Metadata::set_volume (line 53) stdout ----
[INFO] [stdout] Couldn't compile the test.
[INFO] [stdout] ---- src/components/metadata.rs - components::metadata::Metadata::permissions (line 135) stdout ----
[INFO] [stdout] Couldn't compile the test.
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     src/components/metadata.rs - components::metadata::Metadata::is_volume (line 75)
[INFO] [stdout]     src/components/metadata.rs - components::metadata::Metadata::permissions (line 135)
[INFO] [stdout]     src/components/metadata.rs - components::metadata::Metadata::set_volume (line 53)
[INFO] [stdout]     src/components/metadata.rs - components::metadata::Metadata::take_permissions (line 158)
[INFO] [stdout]     src/components/metadata.rs - components::metadata::Metadata::take_varname (line 221)
[INFO] [stdout]     src/components/metadata.rs - components::metadata::Metadata::varname (line 203)
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 5 passed; 6 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass '--doc'
[INFO] running `"docker" "inspect" "5b9e8fdcb64d164ce75f1f352c13daef1b1aa422d56d80047f0e0c9d626de080"`
[INFO] running `"docker" "rm" "-f" "5b9e8fdcb64d164ce75f1f352c13daef1b1aa422d56d80047f0e0c9d626de080"`
[INFO] [stdout] 5b9e8fdcb64d164ce75f1f352c13daef1b1aa422d56d80047f0e0c9d626de080