Oct 14 23:49:55.156 INFO testing dalance/pipecolor against master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513 for rustdoc-test-static-cling-1 Oct 14 23:49:55.157 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-2/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-2/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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"` Oct 14 23:49:55.693 INFO blam! 2f3d66b37c03ce9fac54544aa7a5818aaf175c2b5a0cdf0f65136fa0109bec15 Oct 14 23:49:55.696 INFO running `"docker" "start" "-a" "2f3d66b37c03ce9fac54544aa7a5818aaf175c2b5a0cdf0f65136fa0109bec15"` Oct 14 23:49:57.779 INFO kablam! usermod: no changes Oct 14 23:49:57.979 INFO kablam! Compiling termion v1.5.1 Oct 14 23:49:57.991 INFO kablam! Compiling serde_json v1.0.16 Oct 14 23:49:57.991 INFO kablam! Compiling toml v0.4.5 Oct 14 23:49:57.999 INFO kablam! Compiling nix v0.9.0 Oct 14 23:49:58.007 INFO kablam! Compiling nix v0.10.0 Oct 14 23:49:58.007 INFO kablam! Compiling regex v0.2.10 Oct 14 23:49:58.015 INFO kablam! Compiling structopt v0.2.5 Oct 14 23:50:15.252 INFO kablam! Compiling cargo_metadata v0.3.3 Oct 14 23:50:28.695 INFO kablam! Compiling skeptic v0.13.2 Oct 14 23:50:31.017 INFO kablam! Compiling timeout-readwrite v0.1.0 Oct 14 23:50:37.755 INFO kablam! Compiling proc-reader v0.4.0 Oct 14 23:50:43.035 INFO kablam! Compiling pipecolor v0.4.1-pre (/source) Oct 14 23:50:44.237 INFO kablam! warning: unused `#[macro_use]` import Oct 14 23:50:44.238 INFO kablam! --> src/main.rs:13:1 Oct 14 23:50:44.238 INFO kablam! | Oct 14 23:50:44.239 INFO kablam! 13 | #[macro_use] Oct 14 23:50:44.239 INFO kablam! | ^^^^^^^^^^^^ Oct 14 23:50:44.240 INFO kablam! | Oct 14 23:50:44.241 INFO kablam! = note: #[warn(unused_imports)] on by default Oct 14 23:50:44.241 INFO kablam! Oct 14 23:50:44.277 INFO kablam! warning: use of deprecated item 'std::env::home_dir': This function's behavior is unexpected and probably not what you want. Consider using the home_dir function from https://crates.io/crates/dirs instead. Oct 14 23:50:44.278 INFO kablam! --> src/main.rs:29:5 Oct 14 23:50:44.279 INFO kablam! | Oct 14 23:50:44.279 INFO kablam! 29 | use std::env::home_dir; Oct 14 23:50:44.280 INFO kablam! | ^^^^^^^^^^^^^^^^^^ Oct 14 23:50:44.281 INFO kablam! | Oct 14 23:50:44.281 INFO kablam! = note: #[warn(deprecated)] on by default Oct 14 23:50:44.282 INFO kablam! Oct 14 23:50:44.283 INFO kablam! warning: use of deprecated item 'std::env::home_dir': This function's behavior is unexpected and probably not what you want. Consider using the home_dir function from https://crates.io/crates/dirs instead. Oct 14 23:50:44.283 INFO kablam! --> src/main.rs:137:33 Oct 14 23:50:44.284 INFO kablam! | Oct 14 23:50:44.285 INFO kablam! 137 | } else if let Some(mut p) = home_dir() { Oct 14 23:50:44.285 INFO kablam! | ^^^^^^^^ Oct 14 23:50:44.286 INFO kablam! Oct 14 23:50:50.018 INFO kablam! Finished dev [unoptimized + debuginfo] target(s) in 52.22s Oct 14 23:50:50.027 INFO kablam! su: No module specific data is present Oct 14 23:50:51.912 INFO running `"docker" "rm" "-f" "2f3d66b37c03ce9fac54544aa7a5818aaf175c2b5a0cdf0f65136fa0109bec15"` Oct 14 23:50:52.275 INFO blam! 2f3d66b37c03ce9fac54544aa7a5818aaf175c2b5a0cdf0f65136fa0109bec15 Oct 14 23:50:52.283 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-2/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-2/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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"` Oct 14 23:50:52.776 INFO blam! 85a1a91bf62c8639338fe1c7fbe83762a2ccc7f4809233ea21179084d064ff6e Oct 14 23:50:52.779 INFO running `"docker" "start" "-a" "85a1a91bf62c8639338fe1c7fbe83762a2ccc7f4809233ea21179084d064ff6e"` Oct 14 23:50:55.163 INFO kablam! usermod: no changes Oct 14 23:50:55.355 INFO kablam! Compiling proc-reader v0.4.0 Oct 14 23:50:58.240 INFO kablam! Compiling pipecolor v0.4.1-pre (/source) Oct 14 23:50:59.403 INFO kablam! warning: unused `#[macro_use]` import Oct 14 23:50:59.403 INFO kablam! --> src/main.rs:13:1 Oct 14 23:50:59.403 INFO kablam! | Oct 14 23:50:59.403 INFO kablam! 13 | #[macro_use] Oct 14 23:50:59.403 INFO kablam! | ^^^^^^^^^^^^ Oct 14 23:50:59.403 INFO kablam! | Oct 14 23:50:59.403 INFO kablam! = note: #[warn(unused_imports)] on by default Oct 14 23:50:59.403 INFO kablam! Oct 14 23:50:59.443 INFO kablam! warning: use of deprecated item 'std::env::home_dir': This function's behavior is unexpected and probably not what you want. Consider using the home_dir function from https://crates.io/crates/dirs instead. Oct 14 23:50:59.443 INFO kablam! --> src/main.rs:29:5 Oct 14 23:50:59.443 INFO kablam! | Oct 14 23:50:59.443 INFO kablam! 29 | use std::env::home_dir; Oct 14 23:50:59.443 INFO kablam! | ^^^^^^^^^^^^^^^^^^ Oct 14 23:50:59.443 INFO kablam! | Oct 14 23:50:59.443 INFO kablam! = note: #[warn(deprecated)] on by default Oct 14 23:50:59.443 INFO kablam! Oct 14 23:50:59.445 INFO kablam! warning: use of deprecated item 'std::env::home_dir': This function's behavior is unexpected and probably not what you want. Consider using the home_dir function from https://crates.io/crates/dirs instead. Oct 14 23:50:59.445 INFO kablam! --> src/main.rs:137:33 Oct 14 23:50:59.445 INFO kablam! | Oct 14 23:50:59.445 INFO kablam! 137 | } else if let Some(mut p) = home_dir() { Oct 14 23:50:59.445 INFO kablam! | ^^^^^^^^ Oct 14 23:50:59.445 INFO kablam! Oct 14 23:51:05.584 INFO kablam! Finished dev [unoptimized + debuginfo] target(s) in 10.40s Oct 14 23:51:05.599 INFO kablam! su: No module specific data is present Oct 14 23:51:07.605 INFO running `"docker" "rm" "-f" "85a1a91bf62c8639338fe1c7fbe83762a2ccc7f4809233ea21179084d064ff6e"` Oct 14 23:51:07.863 INFO blam! 85a1a91bf62c8639338fe1c7fbe83762a2ccc7f4809233ea21179084d064ff6e Oct 14 23:51:07.867 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-2/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-2/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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"` Oct 14 23:51:08.363 INFO blam! 316697e28b68201a16a2456729b77693c47f3c68fcc255b3d15aae61267a602e Oct 14 23:51:08.365 INFO running `"docker" "start" "-a" "316697e28b68201a16a2456729b77693c47f3c68fcc255b3d15aae61267a602e"` Oct 14 23:51:10.438 INFO kablam! usermod: no changes Oct 14 23:51:10.720 INFO kablam! Compiling proc-reader v0.4.0 Oct 14 23:51:13.521 INFO kablam! Compiling pipecolor v0.4.1-pre (/source) Oct 14 23:51:14.707 INFO kablam! warning: unused `#[macro_use]` import Oct 14 23:51:14.707 INFO kablam! --> src/main.rs:13:1 Oct 14 23:51:14.707 INFO kablam! | Oct 14 23:51:14.707 INFO kablam! 13 | #[macro_use] Oct 14 23:51:14.707 INFO kablam! | ^^^^^^^^^^^^ Oct 14 23:51:14.707 INFO kablam! | Oct 14 23:51:14.707 INFO kablam! = note: #[warn(unused_imports)] on by default Oct 14 23:51:14.707 INFO kablam! Oct 14 23:51:14.747 INFO kablam! warning: use of deprecated item 'std::env::home_dir': This function's behavior is unexpected and probably not what you want. Consider using the home_dir function from https://crates.io/crates/dirs instead. Oct 14 23:51:14.747 INFO kablam! --> src/main.rs:29:5 Oct 14 23:51:14.747 INFO kablam! | Oct 14 23:51:14.747 INFO kablam! 29 | use std::env::home_dir; Oct 14 23:51:14.747 INFO kablam! | ^^^^^^^^^^^^^^^^^^ Oct 14 23:51:14.747 INFO kablam! | Oct 14 23:51:14.747 INFO kablam! = note: #[warn(deprecated)] on by default Oct 14 23:51:14.747 INFO kablam! Oct 14 23:51:14.747 INFO kablam! warning: use of deprecated item 'std::env::home_dir': This function's behavior is unexpected and probably not what you want. Consider using the home_dir function from https://crates.io/crates/dirs instead. Oct 14 23:51:14.747 INFO kablam! --> src/main.rs:137:33 Oct 14 23:51:14.747 INFO kablam! | Oct 14 23:51:14.747 INFO kablam! 137 | } else if let Some(mut p) = home_dir() { Oct 14 23:51:14.747 INFO kablam! | ^^^^^^^^ Oct 14 23:51:14.747 INFO kablam! Oct 14 23:51:20.767 INFO kablam! Finished dev [unoptimized + debuginfo] target(s) in 10.30s Oct 14 23:51:20.775 INFO kablam! Running /target/debug/deps/pipecolor-0ce5461f297c11a3 Oct 14 23:51:20.778 INFO blam! Oct 14 23:51:20.778 INFO blam! running 6 tests Oct 14 23:51:20.865 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:04:20:54 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.866 INFO blam! xxx.xx.xx.xx - - [25/Mar/2018:04:23:20 +0900] "HEAD / HTTP/1.1" 302 - "https://xxx.xxx" "Mozilla/5.0 (compatible; MSIE 9.0; Windows NT 6.1; WOW64; Trident/5.0)" Oct 14 23:51:20.866 INFO blam! xxx.xxx.xx.xx - - [25/Mar/2018:12:14:55 +0900] "POST /xxx.php HTTP/1.1" 302 281 "-" "Mozilla/5.0 (Windows NT 6.1; rv:34.0) Gecko/20100101 Firefox/34.0" Oct 14 23:51:20.866 INFO blam! xx.xxx.xxx.xxx - - [25/Mar/2018:14:12:13 +0900] "GET / HTTP/1.1" 302 278 "-" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.143 Safari/537.36" Oct 14 23:51:20.867 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:13:04 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.867 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:13:04 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.868 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:15:59 +0900] "GET /en/index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (iPad; CPU OS 11_2_6 like Mac OS X) AppleWebKit/604.5.6 (KHTML, like Gecko) Version/11.0 Mobile/15D100 Safari/604.1" Oct 14 23:51:20.868 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:18:36 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.869 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:18:36 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.869 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:24:05 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.878 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:04:20:54 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.879 INFO blam! xxx.xx.xx.xx - - [25/Mar/2018:04:23:20 +0900] "HEAD / HTTP/1.1" 302 - "https://xxx.xxx" "Mozilla/5.0 (compatible; MSIE 9.0; Windows NT 6.1; WOW64; Trident/5.0)" Oct 14 23:51:20.879 INFO blam! xxx.xxx.xx.xx - - [25/Mar/2018:12:14:55 +0900] "POST /xxx.php HTTP/1.1" 302 281 "-" "Mozilla/5.0 (Windows NT 6.1; rv:34.0) Gecko/20100101 Firefox/34.0" Oct 14 23:51:20.879 INFO blam! xx.xxx.xxx.xxx - - [25/Mar/2018:14:12:13 +0900] "GET / HTTP/1.1" 302 278 "-" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.143 Safari/537.36" Oct 14 23:51:20.879 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:13:04 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.899 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:13:04 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.899 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:15:59 +0900] "GET /en/index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (iPad; CPU OS 11_2_6 like Mac OS X) AppleWebKit/604.5.6 (KHTML, like Gecko) Version/11.0 Mobile/15D100 Safari/604.1" Oct 14 23:51:20.899 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:18:36 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.899 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:18:36 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.900 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:24:05 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.900 INFO blam! test colorize::tests::test_colorize ... ok Oct 14 23:51:20.905 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:04:20:54 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.905 INFO blam! xxx.xx.xx.xx - - [25/Mar/2018:04:23:20 +0900] "HEAD / HTTP/1.1" 302 - "https://xxx.xxx" "Mozilla/5.0 (compatible; MSIE 9.0; Windows NT 6.1; WOW64; Trident/5.0)" Oct 14 23:51:20.905 INFO blam! xxx.xxx.xx.xx - - [25/Mar/2018:12:14:55 +0900] "POST /xxx.php HTTP/1.1" 302 281 "-" "Mozilla/5.0 (Windows NT 6.1; rv:34.0) Gecko/20100101 Firefox/34.0" Oct 14 23:51:20.905 INFO blam! xx.xxx.xxx.xxx - - [25/Mar/2018:14:12:13 +0900] "GET / HTTP/1.1" 302 278 "-" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.143 Safari/537.36" Oct 14 23:51:20.905 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:13:04 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.905 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:13:04 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.905 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:15:59 +0900] "GET /en/index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (iPad; CPU OS 11_2_6 like Mac OS X) AppleWebKit/604.5.6 (KHTML, like Gecko) Version/11.0 Mobile/15D100 Safari/604.1" Oct 14 23:51:20.906 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:18:36 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.906 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:18:36 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.906 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:24:05 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.906 INFO blam! Mar 25 04:17:30 xxx postfix/smtpd[19644]: connect from example.com[xxx.xxx.xx.xxx] Oct 14 23:51:20.906 INFO blam! Mar 25 04:17:30 xxx postfix/smtpd[19644]: SSL_accept error from example.com[xxx.xxx.xx.xxx]: -1 Oct 14 23:51:20.906 INFO blam! Mar 25 04:17:30 xxx postfix/smtpd[19644]: lost connection after STARTTLS from example.com[xxx.xxx.xx.xxx] Oct 14 23:51:20.906 INFO blam! Mar 25 04:17:30 xxx postfix/smtpd[19644]: disconnect from example.com[xxx.xxx.xx.xxx] Oct 14 23:51:20.906 INFO blam! Mar 25 04:17:31 xxx dovecot: imap-login: Disconnected: rip=xxx.xxx.xx.xxx, lip=xxx.xx.xxx.xxx Oct 14 23:51:20.906 INFO blam! Mar 25 04:17:45 xxx postfix/smtpd[19644]: connect from example.com[xxx.xxx.xx.xxx] Oct 14 23:51:20.906 INFO blam! Mar 25 04:17:45 xxx postfix/smtpd[19644]: SSL_accept error from example.com[xxx.xxx.xx.xxx]: -1 Oct 14 23:51:20.906 INFO blam! Mar 25 04:17:45 xxx postfix/smtpd[19644]: lost connection after STARTTLS from example.com[xxx.xxx.xx.xxx] Oct 14 23:51:20.906 INFO blam! Mar 25 04:17:45 xxx postfix/smtpd[19644]: disconnect from example.com[xxx.xxx.xx.xxx] Oct 14 23:51:20.906 INFO blam! Mar 25 04:17:46 xxx dovecot: imap-login: Login: user=, method=PLAIN, rip=xxx.xxx.xx.xx, lip=xxx.xx.xxx.xxx, TLS Oct 14 23:51:20.906 INFO blam! Mar 25 04:17:46 xxx dovecot: imap-login: Disconnected: rip=xxx.xxx.xx.xxx, lip=xxx.xx.xxx.xxx Oct 14 23:51:20.906 INFO blam! Mar 25 04:17:46 xxx dovecot: IMAP(xxx): Connection closed Oct 14 23:51:20.906 INFO blam! Mar 25 04:18:00 xxx postfix/smtpd[19644]: connect from example.com[xxx.xxx.xx.xxx] Oct 14 23:51:20.906 INFO blam! Mar 25 04:18:00 xxx postfix/smtpd[19644]: SSL_accept error from example.com[xxx.xxx.xx.xxx]: -1 Oct 14 23:51:20.906 INFO blam! Mar 25 04:18:00 xxx postfix/smtpd[19644]: lost connection after STARTTLS from example.com[xxx.xxx.xx.xxx] Oct 14 23:51:20.906 INFO blam! Mar 25 04:18:00 xxx postfix/smtpd[19644]: disconnect from example.com[xxx.xxx.xx.xxx] Oct 14 23:51:20.906 INFO blam! Mar 25 04:18:01 xxx dovecot: imap-login: Disconnected: rip=xxx.xxx.xx.xxx, lip=xxx.xx.xxx.xxx Oct 14 23:51:20.906 INFO blam! Mar 25 04:18:04 xxx dovecot: pop3-login: Login: user=, method=PLAIN, rip=xxx.xx.xxx.xx, lip=xxx.xx.xxx.xxx Oct 14 23:51:20.906 INFO blam! Mar 25 04:18:04 xxx dovecot: pop3-login: Login: user=, method=PLAIN, rip=xxx.xxx.xx.xxx, lip=xxx.xx.xxx.xxx, TLS Oct 14 23:51:20.906 INFO blam! Mar 25 04:18:04 xxx dovecot: POP3(xxx): Disconnected: Logged out top=0/0, retr=0/0, del=0/582, size=97911928 Oct 14 23:51:20.911 INFO blam! test tests::test_run ... ok Oct 14 23:51:20.911 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:04:20:54 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.911 INFO blam! xxx.xx.xx.xx - - [25/Mar/2018:04:23:20 +0900] "HEAD / HTTP/1.1" 302 - "https://xxx.xxx" "Mozilla/5.0 (compatible; MSIE 9.0; Windows NT 6.1; WOW64; Trident/5.0)" Oct 14 23:51:20.911 INFO blam! xxx.xxx.xx.xx - - [25/Mar/2018:12:14:55 +0900] "POST /xxx.php HTTP/1.1" 302 281 "-" "Mozilla/5.0 (Windows NT 6.1; rv:34.0) Gecko/20100101 Firefox/34.0" Oct 14 23:51:20.911 INFO blam! xx.xxx.xxx.xxx - - [25/Mar/2018:14:12:13 +0900] "GET / HTTP/1.1" 302 278 "-" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.143 Safari/537.36" Oct 14 23:51:20.911 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:13:04 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.911 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:13:04 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.911 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:15:59 +0900] "GET /en/index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (iPad; CPU OS 11_2_6 like Mac OS X) AppleWebKit/604.5.6 (KHTML, like Gecko) Version/11.0 Mobile/15D100 Safari/604.1" Oct 14 23:51:20.911 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:18:36 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.911 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:18:36 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.911 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:24:05 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.914 INFO blam! test tests::test_verbose ... ok Oct 14 23:51:20.917 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:04:20:54 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.917 INFO blam! xxx.xx.xx.xx - - [25/Mar/2018:04:23:20 +0900] "HEAD / HTTP/1.1" 302 - "https://xxx.xxx" "Mozilla/5.0 (compatible; MSIE 9.0; Windows NT 6.1; WOW64; Trident/5.0)" Oct 14 23:51:20.917 INFO blam! xxx.xxx.xx.xx - - [25/Mar/2018:12:14:55 +0900] "POST /xxx.php HTTP/1.1" 302 281 "-" "Mozilla/5.0 (Windows NT 6.1; rv:34.0) Gecko/20100101 Firefox/34.0" Oct 14 23:51:20.917 INFO blam! xx.xxx.xxx.xxx - - [25/Mar/2018:14:12:13 +0900] "GET / HTTP/1.1" 302 278 "-" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.143 Safari/537.36" Oct 14 23:51:20.918 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:13:04 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.919 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:13:04 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.919 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:15:59 +0900] "GET /en/index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (iPad; CPU OS 11_2_6 like Mac OS X) AppleWebKit/604.5.6 (KHTML, like Gecko) Version/11.0 Mobile/15D100 Safari/604.1" Oct 14 23:51:20.919 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:18:36 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.920 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:18:36 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.920 INFO blam! xxx.xxx.xx.xxx - - [25/Mar/2018:14:24:05 +0900] "GET /index.html HTTP/1.1" 302 278 "-" "Mozilla/5.0 (compatible; MSIE 10.0; Windows NT 6.1; Trident/6.0)" Oct 14 23:51:20.921 INFO blam! test tests::test_mode ... ok Oct 14 23:51:21.089 INFO blam! test colorize::tests::test_colorize_fail ... ok Oct 14 23:51:21.089 INFO blam! test tests::test_read_config_fail ... ok Oct 14 23:51:21.089 INFO blam! Oct 14 23:51:21.089 INFO blam! test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Oct 14 23:51:21.089 INFO blam! Oct 14 23:51:21.092 INFO kablam! su: No module specific data is present Oct 14 23:51:22.846 INFO running `"docker" "rm" "-f" "316697e28b68201a16a2456729b77693c47f3c68fcc255b3d15aae61267a602e"` Oct 14 23:51:23.207 INFO blam! 316697e28b68201a16a2456729b77693c47f3c68fcc255b3d15aae61267a602e