Nov 16 16:45:13.808 INFO testing pitkley/dfw against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 16 16:45:13.809 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 16:45:14.207 INFO [stdout] a7958f8aedda22e825705d306da8936e163cf739f904ee1114084f961f0e9a31
Nov 16 16:45:14.210 INFO running `"docker" "start" "-a" "a7958f8aedda22e825705d306da8936e163cf739f904ee1114084f961f0e9a31"`
Nov 16 16:45:15.595 INFO [stderr] usermod: no changes
Nov 16 16:45:16.063 INFO [stderr]    Compiling proc-macro2 v0.4.16
Nov 16 16:45:16.087 INFO [stderr]    Compiling crossbeam v0.2.12
Nov 16 16:45:16.088 INFO [stderr]    Compiling slog v2.3.3
Nov 16 16:45:16.103 INFO [stderr]    Compiling take_mut v0.2.2
Nov 16 16:45:16.104 INFO [stderr]    Compiling arc-swap v0.3.0
Nov 16 16:45:16.104 INFO [stderr]    Compiling trackable v0.2.18
Nov 16 16:45:16.104 INFO [stderr]    Compiling unicode-bidi v0.2.6
Nov 16 16:45:16.105 INFO [stderr]    Compiling miniz-sys v0.1.10
Nov 16 16:45:17.531 INFO [stderr]    Compiling unix_socket v0.5.0
Nov 16 16:45:19.295 INFO [stderr]    Compiling filetime v0.1.15
Nov 16 16:45:20.728 INFO [stderr]    Compiling term v0.5.1
Nov 16 16:45:20.739 INFO [stderr]    Compiling jed v0.1.2
Nov 16 16:45:20.758 INFO [stderr]    Compiling signal-hook v0.1.4
Nov 16 16:45:22.011 INFO [stderr]    Compiling slog-kvfilter v0.6.0
Nov 16 16:45:22.058 INFO [stderr]    Compiling uuid v0.2.3
Nov 16 16:45:22.107 INFO [stderr]    Compiling slog-scope v4.0.1
Nov 16 16:45:22.242 INFO [stderr]    Compiling tar v0.3.4
Nov 16 16:45:28.736 INFO [stderr]    Compiling slog-async v2.3.0
Nov 16 16:45:28.739 INFO [stderr]    Compiling hyperlocal v0.3.0
Nov 16 16:45:28.763 INFO [stderr]    Compiling hyper-openssl v0.2.7
Nov 16 16:45:28.774 INFO [stderr]    Compiling crossbeam-channel v0.2.4
Nov 16 16:45:28.857 INFO [stderr]    Compiling slog-stdlog v3.0.2
Nov 16 16:45:28.922 INFO [stderr]    Compiling url v0.5.10
Nov 16 16:45:30.361 INFO [stderr]    Compiling slog-term v2.4.0
Nov 16 16:45:36.176 INFO [stderr]    Compiling iptables v0.2.2
Nov 16 16:45:38.983 INFO [stderr]    Compiling flate2 v0.2.20
Nov 16 16:45:39.139 INFO [stderr]    Compiling quote v0.6.8
Nov 16 16:45:44.477 INFO [stderr]    Compiling syn v0.14.9
Nov 16 16:45:50.543 INFO [stderr]    Compiling shiplift v0.3.2
Nov 16 16:46:03.735 INFO [stderr]    Compiling synstructure v0.9.0
Nov 16 16:46:03.735 INFO [stderr]    Compiling serde_derive v1.0.76
Nov 16 16:46:07.409 INFO [stderr]    Compiling failure_derive v0.1.2
Nov 16 16:46:09.959 INFO [stderr]    Compiling failure v0.1.2
Nov 16 16:46:16.575 INFO [stderr]    Compiling sloggers v0.2.8
Nov 16 16:46:18.603 INFO [stderr]    Compiling dfw v0.5.1-alpha.0 (/source)
Nov 16 16:46:36.843 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 21s
Nov 16 16:46:36.860 INFO [stderr] su: No module specific data is present
Nov 16 16:46:37.487 INFO running `"docker" "inspect" "a7958f8aedda22e825705d306da8936e163cf739f904ee1114084f961f0e9a31"`
Nov 16 16:46:37.758 INFO running `"docker" "rm" "-f" "a7958f8aedda22e825705d306da8936e163cf739f904ee1114084f961f0e9a31"`
Nov 16 16:46:38.036 INFO [stdout] a7958f8aedda22e825705d306da8936e163cf739f904ee1114084f961f0e9a31
Nov 16 16:46:38.038 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 16:46:38.378 INFO [stdout] 1c65ab3488c1a482fbb073afd5c4b65046cf1abbac8c38e4f46c90f65579809f
Nov 16 16:46:38.380 INFO running `"docker" "start" "-a" "1c65ab3488c1a482fbb073afd5c4b65046cf1abbac8c38e4f46c90f65579809f"`
Nov 16 16:46:38.975 INFO [stderr] usermod: no changes
Nov 16 16:46:39.229 INFO [stderr]    Compiling eval v0.4.3
Nov 16 16:46:40.968 INFO [stderr]    Compiling dfw v0.5.1-alpha.0 (/source)
Nov 16 16:46:42.103 INFO [stderr] warning: function is never used: `load_loglines`
Nov 16 16:46:42.103 INFO [stderr]    --> tests/logs/mod.rs:174:1
Nov 16 16:46:42.104 INFO [stderr]     |
Nov 16 16:46:42.104 INFO [stderr] 174 | pub fn load_loglines(log_path: &str) -> Vec<LogLine> {
Nov 16 16:46:42.104 INFO [stderr]     | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 16 16:46:42.105 INFO [stderr]     |
Nov 16 16:46:42.105 INFO [stderr]     = note: #[warn(dead_code)] on by default
Nov 16 16:46:42.106 INFO [stderr] 
Nov 16 16:46:53.568 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 14.57s
Nov 16 16:46:53.592 INFO [stderr] su: No module specific data is present
Nov 16 16:46:54.143 INFO running `"docker" "inspect" "1c65ab3488c1a482fbb073afd5c4b65046cf1abbac8c38e4f46c90f65579809f"`
Nov 16 16:46:54.398 INFO running `"docker" "rm" "-f" "1c65ab3488c1a482fbb073afd5c4b65046cf1abbac8c38e4f46c90f65579809f"`
Nov 16 16:46:54.723 INFO [stdout] 1c65ab3488c1a482fbb073afd5c4b65046cf1abbac8c38e4f46c90f65579809f
Nov 16 16:46:54.731 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 16:46:55.325 INFO [stdout] c9755c7caac38b575ea1a2b125a607ed9155e0841b70275c4557ecef9d251de8
Nov 16 16:46:55.329 INFO running `"docker" "start" "-a" "c9755c7caac38b575ea1a2b125a607ed9155e0841b70275c4557ecef9d251de8"`
Nov 16 16:46:56.728 INFO [stderr] usermod: no changes
Nov 16 16:46:57.141 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.37s
Nov 16 16:46:57.171 INFO [stderr]      Running /target/debug/deps/dfw-ab0dab6b98463cb5
Nov 16 16:46:57.195 INFO [stdout] 
Nov 16 16:46:57.196 INFO [stdout] running 4 tests
Nov 16 16:46:57.207 INFO [stdout] test iptables::tests_iptablesrestore::double_append_replace ... ok
Nov 16 16:46:57.208 INFO [stderr]      Running /target/debug/deps/dfw-2ce9dada46c90da3
Nov 16 16:46:57.208 INFO [stdout] test iptables::tests_iptablesrestore::restore_append ... ok
Nov 16 16:46:57.209 INFO [stdout] test iptables::tests_iptablesrestore::double_append ... ok
Nov 16 16:46:57.209 INFO [stdout] test iptables::tests_iptablesrestore::restore_set_policy ... ok
Nov 16 16:46:57.210 INFO [stdout] 
Nov 16 16:46:57.210 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 16:46:57.210 INFO [stdout] 
Nov 16 16:46:57.215 INFO [stdout] 
Nov 16 16:46:57.216 INFO [stderr]      Running /target/debug/deps/dfw-4de59e2eaedc2311
Nov 16 16:46:57.216 INFO [stdout] running 0 tests
Nov 16 16:46:57.217 INFO [stdout] 
Nov 16 16:46:57.217 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 16:46:57.217 INFO [stdout] 
Nov 16 16:46:57.223 INFO [stdout] 
Nov 16 16:46:57.223 INFO [stdout] running 0 tests
Nov 16 16:46:57.223 INFO [stdout] 
Nov 16 16:46:57.223 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 16:46:57.223 INFO [stdout] 
Nov 16 16:46:57.231 INFO [stdout] 
Nov 16 16:46:57.231 INFO [stderr]      Running /target/debug/deps/iptables_dummy-ef5ec0b79c30b721
Nov 16 16:46:57.231 INFO [stdout] running 21 tests
Nov 16 16:46:57.235 INFO [stdout] test dummy_append ... ok
Nov 16 16:46:57.235 INFO [stdout] test dummy_append_unique ... ok
Nov 16 16:46:57.235 INFO [stdout] test dummy_append_replace ... ok
Nov 16 16:46:57.235 INFO [stdout] test dummy_exists ... ok
Nov 16 16:46:57.235 INFO [stdout] test dummy_delete_all ... ok
Nov 16 16:46:57.235 INFO [stdout] test dummy_delete_chain ... ok
Nov 16 16:46:57.235 INFO [stdout] test dummy_execute ... ok
Nov 16 16:46:57.235 INFO [stdout] test dummy_list ... ok
Nov 16 16:46:57.235 INFO [stdout] test dummy_list_chains ... ok
Nov 16 16:46:57.235 INFO [stdout] test dummy_list_table ... ok
Nov 16 16:46:57.235 INFO [stdout] test dummy_new_chain ... ok
Nov 16 16:46:57.235 INFO [stdout] test dummy_rename_chain ... ok
Nov 16 16:46:57.235 INFO [stdout] test dummy_replace ... ok
Nov 16 16:46:57.235 INFO [stdout] test dummy_set_policy ... ok
Nov 16 16:46:57.235 INFO [stdout] test dummy_insert ... ok
Nov 16 16:46:57.235 INFO [stdout] test dummy_flush_chain ... ok
Nov 16 16:46:57.235 INFO [stdout] test dummy_flush_table ... ok
Nov 16 16:46:57.235 INFO [stdout] test dummy_get_policy ... ok
Nov 16 16:46:57.240 INFO [stdout] test dummy_insert_unique ... ok
Nov 16 16:46:57.240 INFO [stdout] test dummy_chain_exists ... ok
Nov 16 16:46:57.240 INFO [stdout] test dummy_delete ... ok
Nov 16 16:46:57.240 INFO [stdout] 
Nov 16 16:46:57.240 INFO [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 16:46:57.240 INFO [stdout] 
Nov 16 16:46:57.247 INFO [stderr]      Running /target/debug/deps/iptables_logger-92718d9390a63988
Nov 16 16:46:57.252 INFO [stdout] 
Nov 16 16:46:57.252 INFO [stdout] running 1 test
Nov 16 16:46:57.262 INFO [stdout] test log_all ... ok
Nov 16 16:46:57.262 INFO [stdout] 
Nov 16 16:46:57.262 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 16:46:57.262 INFO [stdout] 
Nov 16 16:46:57.271 INFO [stderr]      Running /target/debug/deps/logline-44e831134fdb3d2c
Nov 16 16:46:57.283 INFO [stdout] 
Nov 16 16:46:57.283 INFO [stdout] running 15 tests
Nov 16 16:46:57.295 INFO [stdout] test logline_from_string_without_command ... ok
Nov 16 16:46:57.303 INFO [stdout] test regex_ne_regex ... ok
Nov 16 16:46:57.311 INFO [stdout] test regex_ne_self ... ok
Nov 16 16:46:57.311 INFO [stdout] test string_eq_self ... ok
Nov 16 16:46:57.327 INFO [stdout] test logline_from_wrong_string ... ok
Nov 16 16:46:57.359 INFO [stdout] test string_eq_string ... ok
Nov 16 16:46:57.407 INFO [stdout] test logline_from_string_with_eval ... ok
Nov 16 16:46:57.407 INFO [stdout] test logline_from_string ... ok
Nov 16 16:46:57.407 INFO [stdout] test logline_from_string_with_expansions ... ok
Nov 16 16:46:57.407 INFO [stdout] test string_ne_string ... ok
Nov 16 16:46:57.425 INFO [stdout] test string_eq_regex ... ok
Nov 16 16:46:57.435 INFO [stdout] test string_eq_regex_eval ... ok
Nov 16 16:46:57.462 INFO [stdout] test string_ne_regex_eval ... ok
Nov 16 16:46:57.462 INFO [stdout] test logline_from_string_with_wrong_expansion ... ok
Nov 16 16:46:57.463 INFO [stdout] test string_ne_regex ... ok
Nov 16 16:46:57.463 INFO [stdout] 
Nov 16 16:46:57.463 INFO [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 16:46:57.464 INFO [stdout] 
Nov 16 16:46:57.492 INFO [stdout] 
Nov 16 16:46:57.492 INFO [stdout] running 13 tests
Nov 16 16:46:57.492 INFO [stdout] test parse_expose_port_seq_int ... ok
Nov 16 16:46:57.492 INFO [stdout] test parse_conf_path ... ok
Nov 16 16:46:57.493 INFO [stdout] test parse_expose_port_single_struct ... ok
Nov 16 16:46:57.493 INFO [stdout] test parse_expose_port_single_string ... ok
Nov 16 16:46:57.493 INFO [stdout] test parse_expose_port_single_int ... ok
Nov 16 16:46:57.494 INFO [stdout] test parse_expose_port_seq_struct ... ok
Nov 16 16:46:57.494 INFO [stderr]      Running /target/debug/deps/types-c3bd93715b002732
Nov 16 16:46:57.494 INFO [stdout] test parse_external_network_interfaces_seq ... ok
Nov 16 16:46:57.494 INFO [stdout] test parse_external_network_interfaces_single ... ok
Nov 16 16:46:57.503 INFO [stdout] test parse_expose_port_seq_string ... ok
Nov 16 16:46:57.523 INFO [stdout] test parse_conf_file ... ok
Nov 16 16:46:57.651 INFO [stdout] test parse_expose_port_string_invalid_format ... ok
Nov 16 16:46:57.651 INFO [stdout] test parse_expose_port_string_invalid_int2 ... ok
Nov 16 16:46:57.651 INFO [stdout] test parse_expose_port_string_invalid_int ... ok
Nov 16 16:46:57.651 INFO [stdout] 
Nov 16 16:46:57.651 INFO [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 16:46:57.651 INFO [stdout] 
Nov 16 16:46:57.657 INFO [stderr]    Doc-tests dfw
Nov 16 16:47:00.091 INFO [stdout] 
Nov 16 16:47:00.091 INFO [stdout] running 2 tests
Nov 16 16:47:09.727 INFO [stdout] test src/types.rs - types::ExposePort::from_str (line 400) ... ok
Nov 16 16:47:09.730 INFO [stdout] test src/types.rs - types::ExposePort::from_str (line 408) ... ok
Nov 16 16:47:09.730 INFO [stdout] 
Nov 16 16:47:09.730 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 16:47:09.730 INFO [stdout] 
Nov 16 16:47:09.828 INFO [stderr] su: No module specific data is present
Nov 16 16:47:11.322 INFO running `"docker" "inspect" "c9755c7caac38b575ea1a2b125a607ed9155e0841b70275c4557ecef9d251de8"`
Nov 16 16:47:11.703 INFO running `"docker" "rm" "-f" "c9755c7caac38b575ea1a2b125a607ed9155e0841b70275c4557ecef9d251de8"`
Nov 16 16:47:12.015 INFO [stdout] c9755c7caac38b575ea1a2b125a607ed9155e0841b70275c4557ecef9d251de8