[INFO] updating cached repository jlgerber/jsptemplate
[INFO] running `"git" "fetch" "--all"`
[INFO] [stdout] Fetching origin
[INFO] [stderr] From git://github.com/jlgerber/jsptemplate
[INFO] [stderr]  * branch            HEAD       -> FETCH_HEAD
[INFO] running `"git" "clone" "work/cache/sources/gh/jlgerber/jsptemplate" "work/ex/beta-1.38-1/sources/1.37.0/gh/jlgerber/jsptemplate"`
[INFO] [stderr] Cloning into 'work/ex/beta-1.38-1/sources/1.37.0/gh/jlgerber/jsptemplate'...
[INFO] [stderr] done.
[INFO] running `"git" "clone" "work/cache/sources/gh/jlgerber/jsptemplate" "work/ex/beta-1.38-1/sources/beta-2019-08-13/gh/jlgerber/jsptemplate"`
[INFO] [stderr] Cloning into 'work/ex/beta-1.38-1/sources/beta-2019-08-13/gh/jlgerber/jsptemplate'...
[INFO] [stderr] done.
[INFO] running `"git" "rev-parse" "HEAD"`
[INFO] [stdout] aa9b65ee3d519904a123682c251662b18a45a6b0
[INFO] sha for GitHub repo jlgerber/jsptemplate: aa9b65ee3d519904a123682c251662b18a45a6b0
[INFO] validating manifest of jlgerber/jsptemplate on toolchain 1.37.0
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+1.37.0" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] validating manifest of jlgerber/jsptemplate on toolchain beta-2019-08-13
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+beta-2019-08-13" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] started frobbing jlgerber/jsptemplate
[INFO] finished frobbing jlgerber/jsptemplate
[INFO] frobbed toml for jlgerber/jsptemplate written to work/ex/beta-1.38-1/sources/1.37.0/gh/jlgerber/jsptemplate/Cargo.toml
[INFO] started frobbing jlgerber/jsptemplate
[INFO] finished frobbing jlgerber/jsptemplate
[INFO] frobbed toml for jlgerber/jsptemplate written to work/ex/beta-1.38-1/sources/beta-2019-08-13/gh/jlgerber/jsptemplate/Cargo.toml
[INFO] crate jlgerber/jsptemplate already has a lockfile, it will not be regenerated
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+1.37.0" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+beta-2019-08-13" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] testing jlgerber/jsptemplate against 1.37.0 for beta-1.38-1
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.38-1/worker-2/1.37.0:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.38-1/sources/1.37.0/gh/jlgerber/jsptemplate:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+1.37.0" "build" "--frozen"`
[INFO] [stdout] e96d47b12622cee572e73775b8076c822ccd5caba787cd0cd9038b4a65d9d00c
[INFO] running `"docker" "start" "-a" "e96d47b12622cee572e73775b8076c822ccd5caba787cd0cd9038b4a65d9d00c"`
[INFO] [stderr]    Compiling syn v0.15.38
[INFO] [stderr]    Compiling static_assertions v0.2.5
[INFO] [stderr]    Compiling serde v1.0.93
[INFO] [stderr]    Compiling ascii v0.9.1
[INFO] [stderr]    Compiling backtrace v0.3.31
[INFO] [stderr]    Compiling nom v5.0.0
[INFO] [stderr]    Compiling shellfn-core v0.1.1
[INFO] [stderr]    Compiling lexical-core v0.4.1
[INFO] [stderr]    Compiling users v0.9.1
[INFO] [stderr]    Compiling nix v0.14.1
[INFO] [stderr]    Compiling stackvector v1.0.6
[INFO] [stderr]    Compiling cgmath v0.16.1
[INFO] [stderr]    Compiling chrono v0.4.7
[INFO] [stderr]    Compiling shellwords v1.0.0
[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 aschar-casesensitive v0.2.0 (https://github.com/jlgerber/aschar-casesensitive.git#6d27c1c2)
[INFO] [stderr]    Compiling darling_core v0.8.6
[INFO] [stderr]    Compiling synstructure v0.10.2
[INFO] [stderr]    Compiling serde_derive v1.0.93
[INFO] [stderr]    Compiling structopt-derive v0.2.16
[INFO] [stderr]    Compiling failure_derive v0.1.5
[INFO] [stderr]    Compiling structopt v0.2.16
[INFO] [stderr]    Compiling failure v0.1.5
[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_macro v0.8.6
[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 petgraph v0.4.13
[INFO] [stderr]    Compiling serde_regex v0.4.0
[INFO] [stderr]    Compiling serde_json v1.0.39
[INFO] [stderr]    Compiling jsp v0.36.0 (https://github.com/jlgerber/jobsyspolice.git#734d87b2)
[INFO] [stderr]    Compiling jspcompile v0.7.0 (/opt/crater/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]     Finished dev [unoptimized + debuginfo] target(s) in 2m 18s
[INFO] running `"docker" "inspect" "e96d47b12622cee572e73775b8076c822ccd5caba787cd0cd9038b4a65d9d00c"`
[INFO] running `"docker" "rm" "-f" "e96d47b12622cee572e73775b8076c822ccd5caba787cd0cd9038b4a65d9d00c"`
[INFO] [stdout] e96d47b12622cee572e73775b8076c822ccd5caba787cd0cd9038b4a65d9d00c
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.38-1/worker-2/1.37.0:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.38-1/sources/1.37.0/gh/jlgerber/jsptemplate:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+1.37.0" "test" "--frozen" "--no-run"`
[INFO] [stdout] bb3e7a331934a7d68a566bc7d9d5fbdac5c07ec29b3a2c2780d803d429733733
[INFO] running `"docker" "start" "-a" "bb3e7a331934a7d68a566bc7d9d5fbdac5c07ec29b3a2c2780d803d429733733"`
[INFO] [stderr]    Compiling jspcompile v0.7.0 (/opt/crater/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]     Finished dev [unoptimized + debuginfo] target(s) in 6.03s
[INFO] running `"docker" "inspect" "bb3e7a331934a7d68a566bc7d9d5fbdac5c07ec29b3a2c2780d803d429733733"`
[INFO] running `"docker" "rm" "-f" "bb3e7a331934a7d68a566bc7d9d5fbdac5c07ec29b3a2c2780d803d429733733"`
[INFO] [stdout] bb3e7a331934a7d68a566bc7d9d5fbdac5c07ec29b3a2c2780d803d429733733
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.38-1/worker-2/1.37.0:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.38-1/sources/1.37.0/gh/jlgerber/jsptemplate:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+1.37.0" "test" "--frozen"`
[INFO] [stdout] 1d9a1cb00d85b8fd03d6147cce959c8de8f58fbf7455bbc3b17330211eccaa76
[INFO] running `"docker" "start" "-a" "1d9a1cb00d85b8fd03d6147cce959c8de8f58fbf7455bbc3b17330211eccaa76"`
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.14s
[INFO] [stderr]      Running /opt/crater/target/debug/deps/jspcompile-02187b711f3d697d
[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_owner ... 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_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_get_varname ... ok
[INFO] [stdout] test components::metadata::tests::can_get_permissions ... ok
[INFO] [stdout] test components::metadata::tests::can_get_owner ... ok
[INFO] [stdout] test components::metadata::tests::can_create_metadata_and_set_volume ... ok
[INFO] [stdout] test components::metadata::tests::can_take_varname ... ok
[INFO] [stdout] test helpers::id_char::is_ident_char_test ... ok
[INFO] [stdout] test helpers::id_char::is_not_id_char_test ... ok
[INFO] [stdout] test helpers::perms_test::split_perms ... ok
[INFO] [stdout] test helpers::quoted_regex_str::is_quoted_regex_str_bad_test ... ok
[INFO] [stdout] test helpers::quoted_regex_str::is_quoted_regex_str_test ... ok
[INFO] [stdout] test helpers::quoted_regex_str::is_var_bad2_test ... ok
[INFO] [stdout] test helpers::regex_char::is_not_id_char_test ... ok
[INFO] [stdout] test helpers::regex_char::is_regex_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::variable::is_var_bad2_test ... ok
[INFO] [stdout] test helpers::variable::is_var_bad_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_edges_3 ... ok
[INFO] [stdout] test parser::edge::parse_edges::can_parse_edges_4 ... ok
[INFO] [stdout] test parser::edge::parse_edges::can_parse_spaces_in_header_with_space_ending ... ok
[INFO] [stdout] test parser::header::section_header::can_parse_no_space_graph ... 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_node ... ok
[INFO] [stdout] test parser::header::section_header::can_parse_no_space_nodes ... ok
[INFO] [stdout] test parser::header::section_header::can_parse_no_space_unknown ... ok
[INFO] [stdout] test parser::header::section_header::can_parse_spaces_in_header ... 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::metadata::parse_components_tests::can_parse_volume ... ok
[INFO] [stdout] test parser::metadata::parse_components_tests::can_parse_volume_and_owner ... ok
[INFO] [stdout] test parser::metadata::parse_components_tests::can_parse_volume_and_owner_and_perms ... ok
[INFO] [stdout] test parser::metadata::parse_components_tests::can_parse_volume_and_owner_and_perms_and_varname ... 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::tests::can_parse_owner_more_spaces ... ok
[INFO] [stdout] test parser::metadata::tests::can_parse_owner_no_spaces ... ok
[INFO] [stdout] test parser::metadata::tests::can_parse_owner_spaces ... ok
[INFO] [stdout] test parser::metadata::tests::can_parse_owner_variable ... ok
[INFO] [stdout] test parser::metadata::varname_tests::can_parse_varname_more_spaces ... ok
[INFO] [stdout] test parser::metadata::varname_tests::can_parse_varname_no_spaces ... ok
[INFO] [stdout] test parser::metadata::varname_tests::can_parse_varname_spaces ... ok
[INFO] [stdout] test parser::metadata::volume_tests::can_parse_volume_no_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_node_regexcomplex ... ok
[INFO] [stdout] test parser::node::parse_node::can_parse_node_regexsimple ... ok
[INFO] [stdout] test parser::node::parse_node::can_parse_node_revar ... ok
[INFO] [stdout] test parser::node::parse_node::can_parse_revar_simple ... ok
[INFO] [stdout] test parser::node::parse_node_pair::can_parse_node_pair ... ok
[INFO] [stdout] test parser::node::parse_node_pair::can_parse_node_pair_meta ... ok
[INFO] [stdout] test parser::node::parse_node_regexcomplex::can_parse_node_regexcomplex ... 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_regexsimple::can_parse_node_regexsimplewith_return ... ok
[INFO] [stdout] test parser::node::parse_node_revar::can_parse_node_pair_with_return ... ok
[INFO] [stdout] test parser::node::parse_node_revar::can_parse_node_revar ... ok
[INFO] [stdout] test parser::node::parse_node_simple::can_parse_node_simple ... ok
[INFO] [stdout] test parser::node::parse_node_simple::can_parse_node_simple_meta ... ok
[INFO] [stdout] test parser::regex::parse_regex::can_parse_regex_complex ... ok
[INFO] [stdout] test parser::regex::parse_regex::can_parse_regex_simple ... ok
[INFO] [stdout] test parser::regex::parse_regex_complex::can_parse_regex_complex ... ok
[INFO] [stdout] test parser::regex::parse_regex_complex::can_parse_regex_complex_with_return ... ok
[INFO] [stdout] test parser::regex::parse_regex_complex::fails_parse_regex_complex_missing_quote ... 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 ... 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_missing_quote ... ok
[INFO] [stdout] test parser::regex::parse_regex_simple::fails_regex_simple_space ... 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/crater/target/debug/deps/jspcompile-5087e0f1928a82ec
[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::set_volume (line 53) ... 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 100) ... ok
[INFO] [stdout] test src/components/metadata.rs - components::metadata::Metadata::set_permissions (line 117) ... ok
[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::permissions (line 135) ... ok
[INFO] [stdout] test src/components/metadata.rs - components::metadata::Metadata::is_volume (line 75) ... ok
[INFO] [stdout] test src/components/metadata.rs - components::metadata::Metadata::take_permissions (line 158) ... ok
[INFO] [stdout] test src/components/metadata.rs - components::metadata::Metadata::take_varname (line 221) ... ok
[INFO] [stdout] test src/loader.rs - loader::Loader::setup (line 88) ... ok
[INFO] [stdout] test src/components/metadata.rs - components::metadata::Metadata::varname (line 203) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] running `"docker" "inspect" "1d9a1cb00d85b8fd03d6147cce959c8de8f58fbf7455bbc3b17330211eccaa76"`
[INFO] running `"docker" "rm" "-f" "1d9a1cb00d85b8fd03d6147cce959c8de8f58fbf7455bbc3b17330211eccaa76"`
[INFO] [stdout] 1d9a1cb00d85b8fd03d6147cce959c8de8f58fbf7455bbc3b17330211eccaa76