Nov 16 20:56:51.291 INFO testing lukaspustina/ceres against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 16 20:56:51.293 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 20:56:51.752 INFO [stdout] d76659bbc0d2c0d345d0bceee27d0f2d08930c48db611652300074d9ed875042
Nov 16 20:56:51.755 INFO running `"docker" "start" "-a" "d76659bbc0d2c0d345d0bceee27d0f2d08930c48db611652300074d9ed875042"`
Nov 16 20:56:53.714 INFO [stderr] usermod: no changes
Nov 16 20:56:54.382 INFO [stderr]    Compiling webbrowser v0.2.2
Nov 16 20:56:54.407 INFO [stderr]    Compiling inotify-sys v0.1.3
Nov 16 20:56:54.407 INFO [stderr]    Compiling subprocess v0.1.13
Nov 16 20:56:54.423 INFO [stderr]    Compiling rusoto_core v0.31.0
Nov 16 20:56:54.432 INFO [stderr]    Compiling crossbeam-epoch v0.6.0
Nov 16 20:56:54.432 INFO [stderr]    Compiling prettytable-rs v0.7.0
Nov 16 20:56:54.439 INFO [stderr]    Compiling chrono v0.4.6
Nov 16 20:56:54.439 INFO [stderr]    Compiling clams-derive v0.0.4
Nov 16 20:56:56.349 INFO [stderr]    Compiling serde_urlencoded v0.5.3
Nov 16 20:56:59.140 INFO [stderr]    Compiling fern v0.5.6
Nov 16 20:57:00.592 INFO [stderr]    Compiling regex v0.2.11
Nov 16 20:57:07.538 INFO [stderr]    Compiling inotify v0.5.1
Nov 16 20:57:07.601 INFO [stderr]    Compiling os_type v2.2.0
Nov 16 20:57:07.644 INFO [stderr]    Compiling globset v0.4.2
Nov 16 20:57:07.712 INFO [stderr]    Compiling hyper-native-tls v0.2.4
Nov 16 20:57:07.804 INFO [stderr]    Compiling console v0.6.2
Nov 16 20:57:07.888 INFO [stderr]    Compiling crossbeam-channel v0.2.6
Nov 16 20:57:08.049 INFO [stderr]    Compiling chrono-humanize v0.0.11
Nov 16 20:57:11.811 INFO [stderr]    Compiling tokio-proto v0.1.1
Nov 16 20:57:11.859 INFO [stderr]    Compiling tail v0.3.0
Nov 16 20:57:11.911 INFO [stderr]    Compiling human-panic v1.0.1
Nov 16 20:57:24.307 INFO [stderr]    Compiling ignore v0.4.4
Nov 16 20:57:25.623 INFO [stderr]    Compiling handlebars v0.29.1
Nov 16 20:57:25.623 INFO [stderr]    Compiling indicatif v0.9.0
Nov 16 20:57:27.719 INFO [stderr]    Compiling hyper v0.11.27
Nov 16 20:57:31.508 INFO [stderr]    Compiling clams v0.0.9
Nov 16 20:57:45.983 INFO [stderr]    Compiling hyper-tls v0.1.4
Nov 16 20:57:45.983 INFO [stderr]    Compiling service_world v0.1.1
Nov 16 20:57:48.192 INFO [stderr]    Compiling rusoto_credential v0.10.0
Nov 16 20:57:48.192 INFO [stderr]    Compiling reqwest v0.8.8
Nov 16 20:57:48.192 INFO [stderr]    Compiling hubcaps v0.4.10
Nov 16 20:58:08.408 INFO [stderr]    Compiling rusoto_sts v0.31.0
Nov 16 20:58:08.408 INFO [stderr]    Compiling rusoto_ec2 v0.31.0
Nov 16 20:58:41.303 INFO [stderr]    Compiling ceres v0.0.24 (/source)
Nov 16 20:59:13.975 INFO [stderr] warning: unused `#[macro_use]` import
Nov 16 20:59:13.975 INFO [stderr]  --> src/main.rs:8:1
Nov 16 20:59:13.976 INFO [stderr]   |
Nov 16 20:59:13.976 INFO [stderr] 8 | #[macro_use]
Nov 16 20:59:13.976 INFO [stderr]   | ^^^^^^^^^^^^
Nov 16 20:59:13.977 INFO [stderr]   |
Nov 16 20:59:13.977 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Nov 16 20:59:13.977 INFO [stderr] 
Nov 16 20:59:29.099 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2m 35s
Nov 16 20:59:29.140 INFO [stderr] su: No module specific data is present
Nov 16 20:59:29.635 INFO running `"docker" "inspect" "d76659bbc0d2c0d345d0bceee27d0f2d08930c48db611652300074d9ed875042"`
Nov 16 20:59:29.893 INFO running `"docker" "rm" "-f" "d76659bbc0d2c0d345d0bceee27d0f2d08930c48db611652300074d9ed875042"`
Nov 16 20:59:30.121 INFO [stdout] d76659bbc0d2c0d345d0bceee27d0f2d08930c48db611652300074d9ed875042
Nov 16 20:59:30.123 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 20:59:30.656 INFO [stdout] 36495fb232e0c57b80df07b2ecc5ba04d019adfe66a95d60f95ea29c867e0cf6
Nov 16 20:59:30.659 INFO running `"docker" "start" "-a" "36495fb232e0c57b80df07b2ecc5ba04d019adfe66a95d60f95ea29c867e0cf6"`
Nov 16 20:59:32.159 INFO [stderr] usermod: no changes
Nov 16 20:59:32.755 INFO [stderr]    Compiling num-complex v0.1.43
Nov 16 20:59:32.755 INFO [stderr]    Compiling num-bigint v0.1.44
Nov 16 20:59:32.755 INFO [stderr]    Compiling num-iter v0.1.37
Nov 16 20:59:32.755 INFO [stderr]    Compiling env_logger v0.5.13
Nov 16 20:59:36.057 INFO [stderr]    Compiling quickcheck v0.6.2
Nov 16 20:59:38.754 INFO [stderr]    Compiling num-rational v0.1.42
Nov 16 20:59:41.252 INFO [stderr]    Compiling num v0.1.42
Nov 16 20:59:41.545 INFO [stderr]    Compiling spectral v0.6.0
Nov 16 20:59:42.887 INFO [stderr]    Compiling ceres v0.0.24 (/source)
Nov 16 20:59:53.140 INFO [stderr] warning: unused `#[macro_use]` import
Nov 16 20:59:53.141 INFO [stderr]  --> src/main.rs:8:1
Nov 16 20:59:53.141 INFO [stderr]   |
Nov 16 20:59:53.141 INFO [stderr] 8 | #[macro_use]
Nov 16 20:59:53.142 INFO [stderr]   | ^^^^^^^^^^^^
Nov 16 20:59:53.143 INFO [stderr]   |
Nov 16 20:59:53.143 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Nov 16 20:59:53.144 INFO [stderr] 
Nov 16 21:00:10.683 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 38.49s
Nov 16 21:00:10.719 INFO [stderr] su: No module specific data is present
Nov 16 21:00:11.495 INFO running `"docker" "inspect" "36495fb232e0c57b80df07b2ecc5ba04d019adfe66a95d60f95ea29c867e0cf6"`
Nov 16 21:00:11.796 INFO running `"docker" "rm" "-f" "36495fb232e0c57b80df07b2ecc5ba04d019adfe66a95d60f95ea29c867e0cf6"`
Nov 16 21:00:12.080 INFO [stdout] 36495fb232e0c57b80df07b2ecc5ba04d019adfe66a95d60f95ea29c867e0cf6
Nov 16 21:00:12.087 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 21:00:12.501 INFO [stdout] 754a884b25994af68c72a18d4a81afb0e435ebe340cb46b907e44936d0d32261
Nov 16 21:00:12.509 INFO running `"docker" "start" "-a" "754a884b25994af68c72a18d4a81afb0e435ebe340cb46b907e44936d0d32261"`
Nov 16 21:00:13.898 INFO [stderr] usermod: no changes
Nov 16 21:00:14.303 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.36s
Nov 16 21:00:14.314 INFO [stderr]      Running /target/debug/deps/ceres-9edb7affdc77f59d
Nov 16 21:00:14.343 INFO [stdout] 
Nov 16 21:00:14.343 INFO [stdout] running 25 tests
Nov 16 21:00:14.343 INFO [stdout] test config::tests::serialize_deserialize_round_trip ... ok
Nov 16 21:00:14.347 INFO [stdout] test output::instances::table_output::tests::format_tags_one_kv ... ok
Nov 16 21:00:14.347 INFO [stdout] test output::instances::table_output::tests::format_tags_multiple_kv_with_filter ... ok
Nov 16 21:00:14.347 INFO [stdout] test output::instances::table_output::tests::format_tags_multiple_kv ... ok
Nov 16 21:00:14.347 INFO [stdout] test config::tests::load_from_file ... ok
Nov 16 21:00:14.359 INFO [stdout] test provider::filter::tests::filter_instance_with_instance_id_okay ... ok
Nov 16 21:00:14.359 INFO [stdout] test provider::filter::tests::filter_instance_with_instance_id_fail ... ok
Nov 16 21:00:14.359 INFO [stdout] test provider::filter::tests::filter_instance_with_empty_filter ... ok
Nov 16 21:00:14.359 INFO [stdout] test provider::filter::tests::filter_instance_with_instance_id_and_image_id_okay ... ok
Nov 16 21:00:14.359 INFO [stdout] test provider::filter::tests::filter_instance_with_instance_id_and_image_id_fail ... ok
Nov 16 21:00:14.359 INFO [stdout] test provider::filter::tests::filter_instance_with_missing_tag ... ok
Nov 16 21:00:14.360 INFO [stdout] test output::instances::table_output::tests::format_tags_empty ... ok
Nov 16 21:00:14.371 INFO [stdout] test provider::filter::tests::filter_instance_with_tags_fail ... ok
Nov 16 21:00:14.371 INFO [stdout] test provider::tests::extract_tags_filter_one_tag ... ok
Nov 16 21:00:14.371 INFO [stdout] test provider::tests::extract_tags_filter_empty ... ok
Nov 16 21:00:14.371 INFO [stdout] test provider::filter::tests::parse_tags_filter_to_hash_okay ... ok
Nov 16 21:00:14.371 INFO [stdout] test provider::filter::tests::filter_instance_with_tag_without_value_okay ... ok
Nov 16 21:00:14.371 INFO [stdout] test provider::filter::tests::filter_instance_with_tags_okay ... ok
Nov 16 21:00:14.371 INFO [stdout] test provider::filter::tests::parse_filter_no_tags_okay ... ok
Nov 16 21:00:14.372 INFO [stdout] test provider::filter::tests::parse_filter_with_tags_okay ... ok
Nov 16 21:00:14.372 INFO [stdout] test provider::tests::extract_tags_filter_two_tag ... ok
Nov 16 21:00:14.395 INFO [stdout] test utils::tests::run_command_successfully ... ok
Nov 16 21:00:14.407 INFO [stdout] test utils::tests::run_command_successfully_and_check_log_file ... ok
Nov 16 21:00:14.626 INFO [stdout] test utils::tests::run_non_existing_command ... ok
Nov 16 21:00:14.626 INFO [stdout] test provider::filter::tests::filter_instance_with_invalid_re ... ok
Nov 16 21:00:14.626 INFO [stdout] 
Nov 16 21:00:14.626 INFO [stdout] test result: ok. 25 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:00:14.626 INFO [stdout] 
Nov 16 21:00:14.639 INFO [stderr]      Running /target/debug/deps/ceres-8c510b4921f62a50
Nov 16 21:00:14.639 INFO [stderr]      Running /target/debug/deps/it_instances-be018fbecaf90b2c
Nov 16 21:00:14.640 INFO [stdout] 
Nov 16 21:00:14.640 INFO [stdout] running 0 tests
Nov 16 21:00:14.640 INFO [stdout] 
Nov 16 21:00:14.640 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:00:14.640 INFO [stdout] 
Nov 16 21:00:14.640 INFO [stdout] 
Nov 16 21:00:14.640 INFO [stdout] running 1 test
Nov 16 21:00:14.648 INFO [stdout] test noop_okay ... ok
Nov 16 21:00:14.648 INFO [stdout] 
Nov 16 21:00:14.648 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:00:14.648 INFO [stdout] 
Nov 16 21:00:14.649 INFO [stderr]    Doc-tests ceres
Nov 16 21:00:22.649 INFO [stdout] 
Nov 16 21:00:22.650 INFO [stdout] running 0 tests
Nov 16 21:00:22.650 INFO [stdout] 
Nov 16 21:00:22.650 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 21:00:22.651 INFO [stdout] 
Nov 16 21:00:22.682 INFO [stderr] su: No module specific data is present
Nov 16 21:00:23.256 INFO running `"docker" "inspect" "754a884b25994af68c72a18d4a81afb0e435ebe340cb46b907e44936d0d32261"`
Nov 16 21:00:23.488 INFO running `"docker" "rm" "-f" "754a884b25994af68c72a18d4a81afb0e435ebe340cb46b907e44936d0d32261"`
Nov 16 21:00:23.703 INFO [stdout] 754a884b25994af68c72a18d4a81afb0e435ebe340cb46b907e44936d0d32261