Nov 16 18:33:04.616 INFO testing naokirin/logian against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 16 18:33:04.616 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 16 18:33:04.958 INFO [stdout] c8ac2a463409e23b70bd5af3e7e4a7c7fb1131a503f713f99730b63e4f025801
Nov 16 18:33:04.964 INFO running `"docker" "start" "-a" "c8ac2a463409e23b70bd5af3e7e4a7c7fb1131a503f713f99730b63e4f025801"`
Nov 16 18:33:05.503 INFO [stderr] usermod: no changes
Nov 16 18:33:05.643 INFO [stderr]    Compiling serde v1.0.19
Nov 16 18:33:05.644 INFO [stderr]    Compiling humansize v1.0.1
Nov 16 18:33:05.644 INFO [stderr]    Compiling memchr v1.0.2
Nov 16 18:33:05.646 INFO [stderr]    Compiling thread_local v0.3.4
Nov 16 18:33:05.646 INFO [stderr]    Compiling backtrace-sys v0.1.16
Nov 16 18:33:05.646 INFO [stderr]    Compiling serde_derive_internals v0.17.0
Nov 16 18:33:06.039 INFO [stderr]    Compiling backtrace v0.3.3
Nov 16 18:33:06.850 INFO [stderr]    Compiling aho-corasick v0.6.3
Nov 16 18:33:08.439 INFO [stderr]    Compiling error-chain v0.10.0
Nov 16 18:33:08.971 INFO [stderr]    Compiling regex v0.2.2
Nov 16 18:33:11.203 INFO [stderr]    Compiling serde_derive v1.0.19
Nov 16 18:33:26.608 INFO [stderr]    Compiling serde_json v1.0.6
Nov 16 18:33:26.609 INFO [stderr]    Compiling docopt v0.8.1
Nov 16 18:33:36.995 INFO [stderr]    Compiling tera v0.10.10
Nov 16 18:33:49.823 INFO [stderr]    Compiling logian v0.0.1 (/source)
Nov 16 18:34:00.187 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 54.66s
Nov 16 18:34:00.196 INFO [stderr] su: No module specific data is present
Nov 16 18:34:01.619 INFO running `"docker" "inspect" "c8ac2a463409e23b70bd5af3e7e4a7c7fb1131a503f713f99730b63e4f025801"`
Nov 16 18:34:01.923 INFO running `"docker" "rm" "-f" "c8ac2a463409e23b70bd5af3e7e4a7c7fb1131a503f713f99730b63e4f025801"`
Nov 16 18:34:02.197 INFO [stdout] c8ac2a463409e23b70bd5af3e7e4a7c7fb1131a503f713f99730b63e4f025801
Nov 16 18:34:02.205 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 16 18:34:02.655 INFO [stdout] cae94dffa3dfe531805fb535c8666666111b72b5ed0bb342854e1c09fb92a243
Nov 16 18:34:02.661 INFO running `"docker" "start" "-a" "cae94dffa3dfe531805fb535c8666666111b72b5ed0bb342854e1c09fb92a243"`
Nov 16 18:34:04.547 INFO [stderr] usermod: no changes
Nov 16 18:34:04.731 INFO [stderr]    Compiling logian v0.0.1 (/source)
Nov 16 18:34:11.672 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.08s
Nov 16 18:34:11.681 INFO [stderr] su: No module specific data is present
Nov 16 18:34:12.407 INFO running `"docker" "inspect" "cae94dffa3dfe531805fb535c8666666111b72b5ed0bb342854e1c09fb92a243"`
Nov 16 18:34:12.702 INFO running `"docker" "rm" "-f" "cae94dffa3dfe531805fb535c8666666111b72b5ed0bb342854e1c09fb92a243"`
Nov 16 18:34:12.989 INFO [stdout] cae94dffa3dfe531805fb535c8666666111b72b5ed0bb342854e1c09fb92a243
Nov 16 18:34:12.994 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 16 18:34:13.533 INFO [stdout] 84c6e764428de101e703d2a805c21031c390b73026c736f89ed1b935f7168ed8
Nov 16 18:34:13.535 INFO running `"docker" "start" "-a" "84c6e764428de101e703d2a805c21031c390b73026c736f89ed1b935f7168ed8"`
Nov 16 18:34:14.723 INFO [stderr] usermod: no changes
Nov 16 18:34:14.918 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.15s
Nov 16 18:34:14.923 INFO [stderr]      Running /target/debug/deps/logian-41b7ccb0b46aa5bd
Nov 16 18:34:14.923 INFO [stdout] 
Nov 16 18:34:14.923 INFO [stdout] running 0 tests
Nov 16 18:34:14.923 INFO [stdout] 
Nov 16 18:34:14.923 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 18:34:14.923 INFO [stdout] 
Nov 16 18:34:14.924 INFO [stderr]      Running /target/debug/deps/logian-c8b057f8a92eac64
Nov 16 18:34:14.931 INFO [stdout] 
Nov 16 18:34:14.931 INFO [stdout] running 0 tests
Nov 16 18:34:14.931 INFO [stdout] 
Nov 16 18:34:14.931 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 18:34:14.931 INFO [stdout] 
Nov 16 18:34:14.932 INFO [stderr]      Running /target/debug/deps/lib-4d019dcacad921a4
Nov 16 18:34:14.943 INFO [stdout] 
Nov 16 18:34:14.943 INFO [stdout] running 26 tests
Nov 16 18:34:14.955 INFO [stdout] test file::test_reader::test_read_no_file ... ok
Nov 16 18:34:14.955 INFO [stdout] test schema::test_generator::test_generate_empty_field_type_schema ... ok
Nov 16 18:34:14.971 INFO [stdout] test schema::test_generator::test_generate_fields_log_schema ... ok
Nov 16 18:34:14.983 INFO [stdout] test schema::test_generator::test_generate_one_field_default_log_schema ... ok
Nov 16 18:34:14.983 INFO [stdout] test schema::test_generator::test_generate_one_field_log_schema ... ok
Nov 16 18:34:14.983 INFO [stdout] test schema::test_generator::test_generate_empty_field_default_log_schema ... ok
Nov 16 18:34:14.991 INFO [stdout] test schema::test_parser::test_error_to_parse_log_schema_for_no_string_type_name ... ok
Nov 16 18:34:14.991 INFO [stdout] test schema::test_generator::test_generate_fields_default_log_schema ... ok
Nov 16 18:34:14.991 INFO [stdout] test schema::test_parser::test_error_to_parse_default_log_schema_for_not_object ... ok
Nov 16 18:34:14.991 INFO [stdout] test schema::test_parser::test_error_to_parse_log_schema_for_undefined_type ... ok
Nov 16 18:34:14.991 INFO [stdout] test schema::test_parser::test_error_to_parse_log_schema_for_not_array ... ok
Nov 16 18:34:15.003 INFO [stdout] test schema::test_parser::test_error_to_parse_user_defined_schema_for_not_array ... ok
Nov 16 18:34:15.011 INFO [stdout] test schema::test_parser::test_error_to_parse_user_defined_type_for_no_string_type_name ... ok
Nov 16 18:34:15.011 INFO [stdout] test schema::test_parser::test_error_to_parse_default_log_schema_for_no_string_type_name ... ok
Nov 16 18:34:15.012 INFO [stdout] test schema::test_parser::test_error_to_parse_default_log_schema_for_undefined_type ... ok
Nov 16 18:34:15.012 INFO [stdout] test schema::test_parser::test_error_to_parse_user_defined_type_for_undefined_type ... ok
Nov 16 18:34:15.012 INFO [stdout] test schema::test_generator::test_generate_fields_type_schema ... ok
Nov 16 18:34:15.024 INFO [stdout] test schema::test_parser::test_parse_empty_schema ... ok
Nov 16 18:34:15.024 INFO [stdout] test schema::test_parser::test_parse_log_schema_with_user_defined_type ... ok
Nov 16 18:34:15.024 INFO [stdout] test schema::test_generator::test_generate_empty_field_log_schema ... ok
Nov 16 18:34:15.025 INFO [stdout] test schema::test_parser::test_parse_mutiple_field_schema ... ok
Nov 16 18:34:15.025 INFO [stdout] test schema::test_parser::test_parse_nullable_field ... ok
Nov 16 18:34:15.025 INFO [stdout] test schema::test_parser::test_parse_user_defined_type ... ok
Nov 16 18:34:15.025 INFO [stdout] test schema::test_generator::test_generate_one_field_type_schema ... ok
Nov 16 18:34:15.025 INFO [stdout] test schema::test_parser::test_parse_one_field_schema ... ok
Nov 16 18:34:15.032 INFO [stdout] test schema::test_parser::test_parse_default_log_schema ... ok
Nov 16 18:34:15.032 INFO [stderr]    Doc-tests logian
Nov 16 18:34:15.032 INFO [stdout] 
Nov 16 18:34:15.032 INFO [stdout] test result: ok. 26 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 18:34:15.032 INFO [stdout] 
Nov 16 18:34:16.791 INFO [stdout] 
Nov 16 18:34:16.791 INFO [stdout] running 0 tests
Nov 16 18:34:16.791 INFO [stdout] 
Nov 16 18:34:16.791 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 18:34:16.791 INFO [stdout] 
Nov 16 18:34:16.823 INFO [stderr] su: No module specific data is present
Nov 16 18:34:17.453 INFO running `"docker" "inspect" "84c6e764428de101e703d2a805c21031c390b73026c736f89ed1b935f7168ed8"`
Nov 16 18:34:17.716 INFO running `"docker" "rm" "-f" "84c6e764428de101e703d2a805c21031c390b73026c736f89ed1b935f7168ed8"`
Nov 16 18:34:17.991 INFO [stdout] 84c6e764428de101e703d2a805c21031c390b73026c736f89ed1b935f7168ed8