Nov 16 16:45:11.184 INFO testing pitkley/dfw against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 16 16:45:11.185 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 16:45:11.584 INFO [stdout] 92c41474c448560ba85333d1fd47ca74326bf627fed8d89cff8bb0c23ff2b8e3
Nov 16 16:45:11.586 INFO running `"docker" "start" "-a" "92c41474c448560ba85333d1fd47ca74326bf627fed8d89cff8bb0c23ff2b8e3"`
Nov 16 16:45:12.694 INFO [stderr] usermod: no changes
Nov 16 16:45:13.021 INFO [stderr]    Compiling slog v2.3.3
Nov 16 16:45:13.032 INFO [stderr]    Compiling arc-swap v0.3.0
Nov 16 16:45:13.032 INFO [stderr]    Compiling trackable v0.2.18
Nov 16 16:45:13.033 INFO [stderr]    Compiling miniz-sys v0.1.10
Nov 16 16:45:13.034 INFO [stderr]    Compiling unicode-bidi v0.2.6
Nov 16 16:45:13.052 INFO [stderr]    Compiling unix_socket v0.5.0
Nov 16 16:45:13.053 INFO [stderr]    Compiling filetime v0.1.15
Nov 16 16:45:13.054 INFO [stderr]    Compiling term v0.5.1
Nov 16 16:45:18.279 INFO [stderr]    Compiling jed v0.1.2
Nov 16 16:45:18.296 INFO [stderr]    Compiling nix v0.7.0
Nov 16 16:45:18.299 INFO [stderr]    Compiling signal-hook v0.1.4
Nov 16 16:45:18.415 INFO [stderr]    Compiling slog-scope v4.0.1
Nov 16 16:45:18.448 INFO [stderr]    Compiling slog-kvfilter v0.6.0
Nov 16 16:45:18.523 INFO [stderr]    Compiling tar v0.3.4
Nov 16 16:45:18.851 INFO [stderr]    Compiling mime v0.2.6
Nov 16 16:45:19.827 INFO [stderr]    Compiling uuid v0.2.3
Nov 16 16:45:22.387 INFO [stderr]    Compiling toml v0.4.6
Nov 16 16:45:22.415 INFO [stderr]    Compiling syn v0.14.9
Nov 16 16:45:23.055 INFO [stderr]    Compiling slog-stdlog v3.0.2
Nov 16 16:45:23.091 INFO [stderr]    Compiling url v0.5.10
Nov 16 16:45:23.107 INFO [stderr]    Compiling hyper v0.10.13
Nov 16 16:45:23.107 INFO [stderr]    Compiling regex v0.2.11
Nov 16 16:45:23.295 INFO [stderr]    Compiling slog-async v2.3.0
Nov 16 16:45:23.896 INFO [stderr]    Compiling slog-term v2.4.0
Nov 16 16:45:25.947 INFO [stderr]    Compiling crossbeam-channel v0.2.4
Nov 16 16:45:36.091 INFO [stderr]    Compiling flate2 v0.2.20
Nov 16 16:46:01.831 INFO [stderr]    Compiling iptables v0.2.2
Nov 16 16:46:04.159 INFO [stderr]    Compiling hyperlocal v0.3.0
Nov 16 16:46:04.159 INFO [stderr]    Compiling hyper-openssl v0.2.7
Nov 16 16:46:06.407 INFO [stderr]    Compiling shiplift v0.3.2
Nov 16 16:46:06.711 INFO [stderr]    Compiling synstructure v0.9.0
Nov 16 16:46:06.711 INFO [stderr]    Compiling serde_derive v1.0.76
Nov 16 16:46:10.383 INFO [stderr]    Compiling failure_derive v0.1.2
Nov 16 16:46:14.019 INFO [stderr]    Compiling failure v0.1.2
Nov 16 16:46:19.425 INFO [stderr]    Compiling sloggers v0.2.8
Nov 16 16:46:21.415 INFO [stderr]    Compiling dfw v0.5.1-alpha.0 (/source)
Nov 16 16:46:39.659 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 26s
Nov 16 16:46:39.677 INFO [stderr] su: No module specific data is present
Nov 16 16:46:40.140 INFO running `"docker" "inspect" "92c41474c448560ba85333d1fd47ca74326bf627fed8d89cff8bb0c23ff2b8e3"`
Nov 16 16:46:40.209 INFO running `"docker" "rm" "-f" "92c41474c448560ba85333d1fd47ca74326bf627fed8d89cff8bb0c23ff2b8e3"`
Nov 16 16:46:40.294 INFO [stdout] 92c41474c448560ba85333d1fd47ca74326bf627fed8d89cff8bb0c23ff2b8e3
Nov 16 16:46:40.296 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 16:46:40.512 INFO [stdout] 2c69af14fd43b4a1bfad68015686b29c416320c8d7a8a8e88b17f9bf3f687c9c
Nov 16 16:46:40.513 INFO running `"docker" "start" "-a" "2c69af14fd43b4a1bfad68015686b29c416320c8d7a8a8e88b17f9bf3f687c9c"`
Nov 16 16:46:41.155 INFO [stderr] usermod: no changes
Nov 16 16:46:41.430 INFO [stderr]    Compiling eval v0.4.3
Nov 16 16:46:43.196 INFO [stderr]    Compiling dfw v0.5.1-alpha.0 (/source)
Nov 16 16:46:45.307 INFO [stderr] warning: function is never used: `load_loglines`
Nov 16 16:46:45.313 INFO [stderr]    --> tests/logs/mod.rs:174:1
Nov 16 16:46:45.313 INFO [stderr]     |
Nov 16 16:46:45.313 INFO [stderr] 174 | pub fn load_loglines(log_path: &str) -> Vec<LogLine> {
Nov 16 16:46:45.314 INFO [stderr]     | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 16 16:46:45.314 INFO [stderr]     |
Nov 16 16:46:45.315 INFO [stderr]     = note: #[warn(dead_code)] on by default
Nov 16 16:46:45.315 INFO [stderr] 
Nov 16 16:46:59.330 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 18.15s
Nov 16 16:46:59.359 INFO [stderr] su: No module specific data is present
Nov 16 16:47:00.897 INFO running `"docker" "inspect" "2c69af14fd43b4a1bfad68015686b29c416320c8d7a8a8e88b17f9bf3f687c9c"`
Nov 16 16:47:01.243 INFO running `"docker" "rm" "-f" "2c69af14fd43b4a1bfad68015686b29c416320c8d7a8a8e88b17f9bf3f687c9c"`
Nov 16 16:47:01.499 INFO [stdout] 2c69af14fd43b4a1bfad68015686b29c416320c8d7a8a8e88b17f9bf3f687c9c
Nov 16 16:47:01.507 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 16:47:02.075 INFO [stdout] 8bceadad59f3fa99689d7652cd4250fc278200cb2cb066b6c841f48681acb403
Nov 16 16:47:02.077 INFO running `"docker" "start" "-a" "8bceadad59f3fa99689d7652cd4250fc278200cb2cb066b6c841f48681acb403"`
Nov 16 16:47:04.520 INFO [stderr] usermod: no changes
Nov 16 16:47:04.950 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.40s
Nov 16 16:47:04.984 INFO [stderr]      Running /target/debug/deps/dfw-1ef030c74929ca1e
Nov 16 16:47:04.989 INFO [stdout] 
Nov 16 16:47:04.989 INFO [stdout] running 4 tests
Nov 16 16:47:04.989 INFO [stdout] test iptables::tests_iptablesrestore::restore_set_policy ... ok
Nov 16 16:47:04.989 INFO [stdout] test iptables::tests_iptablesrestore::restore_append ... ok
Nov 16 16:47:04.991 INFO [stdout] test iptables::tests_iptablesrestore::double_append ... ok
Nov 16 16:47:04.995 INFO [stdout] test iptables::tests_iptablesrestore::double_append_replace ... ok
Nov 16 16:47:04.995 INFO [stdout] 
Nov 16 16:47:04.995 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 16:47:04.995 INFO [stdout] 
Nov 16 16:47:04.996 INFO [stderr]      Running /target/debug/deps/dfw-58ff560c89bad94a
Nov 16 16:47:05.000 INFO [stdout] 
Nov 16 16:47:05.000 INFO [stdout] running 0 tests
Nov 16 16:47:05.000 INFO [stdout] 
Nov 16 16:47:05.000 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 16:47:05.000 INFO [stdout] 
Nov 16 16:47:05.001 INFO [stderr]      Running /target/debug/deps/dfw-80a97f87e76ddf24
Nov 16 16:47:05.004 INFO [stdout] 
Nov 16 16:47:05.005 INFO [stdout] running 0 tests
Nov 16 16:47:05.005 INFO [stdout] 
Nov 16 16:47:05.005 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 16:47:05.005 INFO [stdout] 
Nov 16 16:47:05.005 INFO [stderr]      Running /target/debug/deps/iptables_dummy-6172d8c87c05f68b
Nov 16 16:47:05.008 INFO [stdout] 
Nov 16 16:47:05.008 INFO [stdout] running 21 tests
Nov 16 16:47:05.015 INFO [stdout] test dummy_delete_all ... ok
Nov 16 16:47:05.015 INFO [stdout] test dummy_append ... ok
Nov 16 16:47:05.015 INFO [stdout] test dummy_delete ... ok
Nov 16 16:47:05.015 INFO [stdout] test dummy_chain_exists ... ok
Nov 16 16:47:05.015 INFO [stdout] test dummy_append_replace ... ok
Nov 16 16:47:05.015 INFO [stdout] test dummy_append_unique ... ok
Nov 16 16:47:05.015 INFO [stdout] test dummy_insert_unique ... ok
Nov 16 16:47:05.017 INFO [stdout] test dummy_insert ... ok
Nov 16 16:47:05.017 INFO [stdout] test dummy_get_policy ... ok
Nov 16 16:47:05.017 INFO [stdout] test dummy_flush_chain ... ok
Nov 16 16:47:05.017 INFO [stdout] test dummy_exists ... ok
Nov 16 16:47:05.017 INFO [stdout] test dummy_execute ... ok
Nov 16 16:47:05.018 INFO [stdout] test dummy_flush_table ... ok
Nov 16 16:47:05.023 INFO [stdout] test dummy_list_chains ... ok
Nov 16 16:47:05.023 INFO [stdout] test dummy_new_chain ... ok
Nov 16 16:47:05.023 INFO [stdout] test dummy_replace ... ok
Nov 16 16:47:05.023 INFO [stdout] test dummy_rename_chain ... ok
Nov 16 16:47:05.027 INFO [stdout] test dummy_set_policy ... ok
Nov 16 16:47:05.027 INFO [stdout] test dummy_list ... ok
Nov 16 16:47:05.027 INFO [stdout] test dummy_list_table ... ok
Nov 16 16:47:05.027 INFO [stdout] test dummy_delete_chain ... ok
Nov 16 16:47:05.027 INFO [stdout] 
Nov 16 16:47:05.027 INFO [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 16:47:05.027 INFO [stdout] 
Nov 16 16:47:05.031 INFO [stderr]      Running /target/debug/deps/iptables_logger-0969fc59d24a284e
Nov 16 16:47:05.041 INFO [stdout] 
Nov 16 16:47:05.041 INFO [stdout] running 1 test
Nov 16 16:47:05.051 INFO [stdout] test log_all ... ok
Nov 16 16:47:05.051 INFO [stdout] 
Nov 16 16:47:05.051 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 16:47:05.051 INFO [stdout] 
Nov 16 16:47:05.052 INFO [stderr]      Running /target/debug/deps/logline-112ff538d2163387
Nov 16 16:47:05.061 INFO [stdout] 
Nov 16 16:47:05.061 INFO [stdout] running 15 tests
Nov 16 16:47:05.063 INFO [stdout] test regex_ne_regex ... ok
Nov 16 16:47:05.063 INFO [stdout] test regex_ne_self ... ok
Nov 16 16:47:05.079 INFO [stdout] test logline_from_string_without_command ... ok
Nov 16 16:47:05.083 INFO [stdout] test string_eq_self ... ok
Nov 16 16:47:05.089 INFO [stdout] test string_eq_string ... ok
Nov 16 16:47:05.114 INFO [stdout] test string_eq_regex ... ok
Nov 16 16:47:05.125 INFO [stdout] test logline_from_string_with_eval ... ok
Nov 16 16:47:05.147 INFO [stdout] test string_eq_regex_eval ... ok
Nov 16 16:47:05.147 INFO [stdout] test string_ne_string ... ok
Nov 16 16:47:05.150 INFO [stdout] test logline_from_string_with_expansions ... ok
Nov 16 16:47:05.151 INFO [stdout] test logline_from_wrong_string ... ok
Nov 16 16:47:05.172 INFO [stdout] test logline_from_string_with_wrong_expansion ... ok
Nov 16 16:47:05.172 INFO [stdout] test logline_from_string ... ok
Nov 16 16:47:05.179 INFO [stdout] test string_ne_regex_eval ... ok
Nov 16 16:47:05.189 INFO [stdout] test string_ne_regex ... ok
Nov 16 16:47:05.189 INFO [stdout] 
Nov 16 16:47:05.190 INFO [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 16:47:05.190 INFO [stdout] 
Nov 16 16:47:05.191 INFO [stderr]      Running /target/debug/deps/types-b3009b36dcba3bd3
Nov 16 16:47:05.194 INFO [stdout] 
Nov 16 16:47:05.194 INFO [stdout] running 13 tests
Nov 16 16:47:05.239 INFO [stdout] test parse_expose_port_seq_struct ... ok
Nov 16 16:47:05.240 INFO [stdout] test parse_expose_port_seq_int ... ok
Nov 16 16:47:05.240 INFO [stdout] test parse_expose_port_seq_string ... ok
Nov 16 16:47:05.240 INFO [stdout] test parse_expose_port_single_int ... ok
Nov 16 16:47:05.240 INFO [stdout] test parse_conf_path ... ok
Nov 16 16:47:05.243 INFO [stdout] test parse_expose_port_single_string ... ok
Nov 16 16:47:05.247 INFO [stdout] test parse_external_network_interfaces_seq ... ok
Nov 16 16:47:05.247 INFO [stdout] test parse_external_network_interfaces_single ... ok
Nov 16 16:47:05.259 INFO [stdout] test parse_conf_file ... ok
Nov 16 16:47:05.259 INFO [stdout] test parse_expose_port_single_struct ... ok
Nov 16 16:47:05.429 INFO [stdout] test parse_expose_port_string_invalid_format ... ok
Nov 16 16:47:05.429 INFO [stdout] test parse_expose_port_string_invalid_int2 ... ok
Nov 16 16:47:05.429 INFO [stdout] test parse_expose_port_string_invalid_int ... ok
Nov 16 16:47:05.429 INFO [stdout] 
Nov 16 16:47:05.429 INFO [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 16:47:05.429 INFO [stdout] 
Nov 16 16:47:05.434 INFO [stderr]    Doc-tests dfw
Nov 16 16:47:07.834 INFO [stdout] 
Nov 16 16:47:07.834 INFO [stdout] running 2 tests
Nov 16 16:47:17.476 INFO [stdout] test src/types.rs - types::ExposePort::from_str (line 408) ... ok
Nov 16 16:47:18.023 INFO [stdout] test src/types.rs - types::ExposePort::from_str (line 400) ... ok
Nov 16 16:47:18.023 INFO [stdout] 
Nov 16 16:47:18.024 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 16:47:18.024 INFO [stdout] 
Nov 16 16:47:18.060 INFO [stderr] su: No module specific data is present
Nov 16 16:47:19.494 INFO running `"docker" "inspect" "8bceadad59f3fa99689d7652cd4250fc278200cb2cb066b6c841f48681acb403"`
Nov 16 16:47:19.867 INFO running `"docker" "rm" "-f" "8bceadad59f3fa99689d7652cd4250fc278200cb2cb066b6c841f48681acb403"`
Nov 16 16:47:20.257 INFO [stdout] 8bceadad59f3fa99689d7652cd4250fc278200cb2cb066b6c841f48681acb403