Oct 14 23:49:52.844 INFO testing dalance/pipecolor against try#40d4795669493f1965de7c44029c69552134fe1f for rustdoc-test-static-cling-1
Oct 14 23:49:52.846 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-5/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-5/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/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 +40d4795669493f1965de7c44029c69552134fe1f-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:53.324 INFO blam! 3e5d6fd4a41e571fcb1927ecebc9fb755f7c73c872adbb872ed11554af089553
Oct 14 23:49:53.327 INFO running `"docker" "start" "-a" "3e5d6fd4a41e571fcb1927ecebc9fb755f7c73c872adbb872ed11554af089553"`
Oct 14 23:49:54.652 INFO kablam! usermod: no changes
Oct 14 23:49:54.980 INFO kablam!    Compiling serde v1.0.36
Oct 14 23:49:54.981 INFO kablam!    Compiling backtrace-sys v0.1.16
Oct 14 23:49:54.996 INFO kablam!    Compiling atty v0.2.8
Oct 14 23:49:54.997 INFO kablam!    Compiling termion v1.5.1
Oct 14 23:49:54.999 INFO kablam!    Compiling nix v0.9.0
Oct 14 23:49:55.030 INFO kablam!    Compiling regex-syntax v0.5.3
Oct 14 23:49:55.031 INFO kablam!    Compiling thread_local v0.3.5
Oct 14 23:49:55.031 INFO kablam!    Compiling syn v0.12.14
Oct 14 23:49:56.891 INFO kablam!    Compiling nix v0.10.0
Oct 14 23:49:59.801 INFO kablam!    Compiling clap v2.31.2
Oct 14 23:50:48.977 INFO kablam!    Compiling timeout-readwrite v0.1.0
Oct 14 23:50:54.487 INFO kablam!    Compiling serde_derive_internals v0.22.2
Oct 14 23:50:57.273 INFO kablam!    Compiling structopt-derive v0.2.5
Oct 14 23:50:57.847 INFO kablam!    Compiling regex v0.2.10
Oct 14 23:50:58.188 INFO kablam!    Compiling backtrace v0.3.5
Oct 14 23:51:02.592 INFO kablam!    Compiling error-chain v0.11.0
Oct 14 23:51:07.417 INFO kablam!    Compiling semver v0.8.0
Oct 14 23:51:07.419 INFO kablam!    Compiling serde_json v1.0.16
Oct 14 23:51:07.421 INFO kablam!    Compiling toml v0.4.5
Oct 14 23:51:13.235 INFO kablam!    Compiling serde_derive v1.0.36
Oct 14 23:51:29.299 INFO kablam!    Compiling structopt v0.2.5
Oct 14 23:51:38.063 INFO kablam!    Compiling cargo_metadata v0.3.3
Oct 14 23:51:41.423 INFO kablam!    Compiling skeptic v0.13.2
Oct 14 23:51:45.487 INFO kablam!    Compiling proc-reader v0.4.0
Oct 14 23:51:50.619 INFO kablam!    Compiling pipecolor v0.4.1-pre (/source)
Oct 14 23:51:51.647 INFO kablam! warning: unused `#[macro_use]` import
Oct 14 23:51:51.647 INFO kablam!   --> src/main.rs:13:1
Oct 14 23:51:51.647 INFO kablam!    |
Oct 14 23:51:51.647 INFO kablam! 13 | #[macro_use]
Oct 14 23:51:51.647 INFO kablam!    | ^^^^^^^^^^^^
Oct 14 23:51:51.647 INFO kablam!    |
Oct 14 23:51:51.647 INFO kablam!    = note: #[warn(unused_imports)] on by default
Oct 14 23:51:51.647 INFO kablam! 
Oct 14 23:51:51.691 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:51.691 INFO kablam!   --> src/main.rs:29:5
Oct 14 23:51:51.691 INFO kablam!    |
Oct 14 23:51:51.691 INFO kablam! 29 | use std::env::home_dir;
Oct 14 23:51:51.691 INFO kablam!    |     ^^^^^^^^^^^^^^^^^^
Oct 14 23:51:51.691 INFO kablam!    |
Oct 14 23:51:51.691 INFO kablam!    = note: #[warn(deprecated)] on by default
Oct 14 23:51:51.691 INFO kablam! 
Oct 14 23:51:51.691 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:51.691 INFO kablam!    --> src/main.rs:137:33
Oct 14 23:51:51.691 INFO kablam!     |
Oct 14 23:51:51.691 INFO kablam! 137 |     } else if let Some(mut p) = home_dir() {
Oct 14 23:51:51.691 INFO kablam!     |                                 ^^^^^^^^
Oct 14 23:51:51.691 INFO kablam! 
Oct 14 23:51:56.799 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 2m 02s
Oct 14 23:51:56.815 INFO kablam! su: No module specific data is present
Oct 14 23:51:58.479 INFO running `"docker" "rm" "-f" "3e5d6fd4a41e571fcb1927ecebc9fb755f7c73c872adbb872ed11554af089553"`
Oct 14 23:51:58.763 INFO blam! 3e5d6fd4a41e571fcb1927ecebc9fb755f7c73c872adbb872ed11554af089553
Oct 14 23:51:58.765 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-5/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-5/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/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 +40d4795669493f1965de7c44029c69552134fe1f-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:51:59.167 INFO blam! c1786687c66bfd4350ada1fb31e997b2d1d0aa8f9e1b1b5ae2bc22b8638c28c4
Oct 14 23:51:59.169 INFO running `"docker" "start" "-a" "c1786687c66bfd4350ada1fb31e997b2d1d0aa8f9e1b1b5ae2bc22b8638c28c4"`
Oct 14 23:52:00.606 INFO kablam! usermod: no changes
Oct 14 23:52:00.811 INFO kablam!    Compiling proc-reader v0.4.0
Oct 14 23:52:03.289 INFO kablam!    Compiling pipecolor v0.4.1-pre (/source)
Oct 14 23:52:04.375 INFO kablam! warning: unused `#[macro_use]` import
Oct 14 23:52:04.375 INFO kablam!   --> src/main.rs:13:1
Oct 14 23:52:04.375 INFO kablam!    |
Oct 14 23:52:04.375 INFO kablam! 13 | #[macro_use]
Oct 14 23:52:04.375 INFO kablam!    | ^^^^^^^^^^^^
Oct 14 23:52:04.375 INFO kablam!    |
Oct 14 23:52:04.375 INFO kablam!    = note: #[warn(unused_imports)] on by default
Oct 14 23:52:04.375 INFO kablam! 
Oct 14 23:52:04.411 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:52:04.411 INFO kablam!   --> src/main.rs:29:5
Oct 14 23:52:04.411 INFO kablam!    |
Oct 14 23:52:04.411 INFO kablam! 29 | use std::env::home_dir;
Oct 14 23:52:04.411 INFO kablam!    |     ^^^^^^^^^^^^^^^^^^
Oct 14 23:52:04.411 INFO kablam!    |
Oct 14 23:52:04.411 INFO kablam!    = note: #[warn(deprecated)] on by default
Oct 14 23:52:04.411 INFO kablam! 
Oct 14 23:52:04.411 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:52:04.411 INFO kablam!    --> src/main.rs:137:33
Oct 14 23:52:04.411 INFO kablam!     |
Oct 14 23:52:04.411 INFO kablam! 137 |     } else if let Some(mut p) = home_dir() {
Oct 14 23:52:04.411 INFO kablam!     |                                 ^^^^^^^^
Oct 14 23:52:04.412 INFO kablam! 
Oct 14 23:52:10.371 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 9.73s
Oct 14 23:52:10.379 INFO kablam! su: No module specific data is present
Oct 14 23:52:11.514 INFO running `"docker" "rm" "-f" "c1786687c66bfd4350ada1fb31e997b2d1d0aa8f9e1b1b5ae2bc22b8638c28c4"`
Oct 14 23:52:11.787 INFO blam! c1786687c66bfd4350ada1fb31e997b2d1d0aa8f9e1b1b5ae2bc22b8638c28c4
Oct 14 23:52:11.791 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-5/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-5/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/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 +40d4795669493f1965de7c44029c69552134fe1f-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:52:12.343 INFO blam! 70079cd7cbf32b9dd2fcf24eee7dba2d8ac4579625c5c5c0a4bc72cf4ad281a2
Oct 14 23:52:12.347 INFO running `"docker" "start" "-a" "70079cd7cbf32b9dd2fcf24eee7dba2d8ac4579625c5c5c0a4bc72cf4ad281a2"`
Oct 14 23:52:13.500 INFO kablam! usermod: no changes
Oct 14 23:52:13.656 INFO kablam!    Compiling proc-reader v0.4.0
Oct 14 23:52:15.901 INFO kablam!    Compiling pipecolor v0.4.1-pre (/source)
Oct 14 23:52:16.964 INFO kablam! warning: unused `#[macro_use]` import
Oct 14 23:52:16.964 INFO kablam!   --> src/main.rs:13:1
Oct 14 23:52:16.965 INFO kablam!    |
Oct 14 23:52:16.965 INFO kablam! 13 | #[macro_use]
Oct 14 23:52:16.965 INFO kablam!    | ^^^^^^^^^^^^
Oct 14 23:52:16.965 INFO kablam!    |
Oct 14 23:52:16.965 INFO kablam!    = note: #[warn(unused_imports)] on by default
Oct 14 23:52:16.966 INFO kablam! 
Oct 14 23:52:17.003 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:52:17.003 INFO kablam!   --> src/main.rs:29:5
Oct 14 23:52:17.003 INFO kablam!    |
Oct 14 23:52:17.003 INFO kablam! 29 | use std::env::home_dir;
Oct 14 23:52:17.003 INFO kablam!    |     ^^^^^^^^^^^^^^^^^^
Oct 14 23:52:17.003 INFO kablam!    |
Oct 14 23:52:17.003 INFO kablam!    = note: #[warn(deprecated)] on by default
Oct 14 23:52:17.003 INFO kablam! 
Oct 14 23:52:17.003 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:52:17.003 INFO kablam!    --> src/main.rs:137:33
Oct 14 23:52:17.003 INFO kablam!     |
Oct 14 23:52:17.003 INFO kablam! 137 |     } else if let Some(mut p) = home_dir() {
Oct 14 23:52:17.003 INFO kablam!     |                                 ^^^^^^^^
Oct 14 23:52:17.003 INFO kablam! 
Oct 14 23:52:21.279 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 7.73s
Oct 14 23:52:21.283 INFO kablam!      Running /target/debug/deps/pipecolor-151c1ffa6b7d34f8
Oct 14 23:52:21.285 INFO blam! 
Oct 14 23:52:21.285 INFO blam! running 6 tests
Oct 14 23:52:21.319 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:52:21.319 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:52:21.319 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:52:21.319 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:52:21.319 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:52:21.319 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:52:21.319 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:52:21.327 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:52:21.327 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:52:21.327 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:52:21.327 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:52:21.331 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:52:21.331 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:52:21.331 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:52:21.331 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:52:21.331 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:52:21.331 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:52:21.331 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:52:21.331 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:52:21.331 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:52:21.343 INFO blam! test colorize::tests::test_colorize ... ok
Oct 14 23:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 INFO blam! Mar 25 04:17:30 xxx postfix/smtpd[19644]: connect from example.com[xxx.xxx.xx.xxx]
Oct 14 23:52:21.343 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:52:21.343 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:52:21.343 INFO blam! Mar 25 04:17:30 xxx postfix/smtpd[19644]: disconnect from example.com[xxx.xxx.xx.xxx]
Oct 14 23:52:21.343 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:52:21.343 INFO blam! Mar 25 04:17:45 xxx postfix/smtpd[19644]: connect from example.com[xxx.xxx.xx.xxx]
Oct 14 23:52:21.343 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:52:21.343 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:52:21.343 INFO blam! Mar 25 04:17:45 xxx postfix/smtpd[19644]: disconnect from example.com[xxx.xxx.xx.xxx]
Oct 14 23:52:21.343 INFO blam! Mar 25 04:17:46 xxx dovecot: imap-login: Login: user=<xxx>, method=PLAIN, rip=xxx.xxx.xx.xx, lip=xxx.xx.xxx.xxx, TLS
Oct 14 23:52:21.343 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:52:21.343 INFO blam! Mar 25 04:17:46 xxx dovecot: IMAP(xxx): Connection closed
Oct 14 23:52:21.343 INFO blam! Mar 25 04:18:00 xxx postfix/smtpd[19644]: connect from example.com[xxx.xxx.xx.xxx]
Oct 14 23:52:21.343 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:52:21.343 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:52:21.343 INFO blam! Mar 25 04:18:00 xxx postfix/smtpd[19644]: disconnect from example.com[xxx.xxx.xx.xxx]
Oct 14 23:52:21.343 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:52:21.343 INFO blam! Mar 25 04:18:04 xxx dovecot: pop3-login: Login: user=<xxx>, method=PLAIN, rip=xxx.xx.xxx.xx, lip=xxx.xx.xxx.xxx
Oct 14 23:52:21.343 INFO blam! Mar 25 04:18:04 xxx dovecot: pop3-login: Login: user=<xxx>, method=PLAIN, rip=xxx.xxx.xx.xxx, lip=xxx.xx.xxx.xxx, TLS
Oct 14 23:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.343 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:52:21.344 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:52:21.344 INFO blam! test tests::test_mode ... ok
Oct 14 23:52:21.344 INFO blam! test tests::test_run ... ok
Oct 14 23:52:21.344 INFO blam! test tests::test_verbose ... ok
Oct 14 23:52:21.439 INFO blam! test tests::test_read_config_fail ... ok
Oct 14 23:52:21.439 INFO blam! test colorize::tests::test_colorize_fail ... ok
Oct 14 23:52:21.439 INFO blam! 
Oct 14 23:52:21.439 INFO blam! test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 14 23:52:21.439 INFO blam! 
Oct 14 23:52:21.441 INFO kablam! su: No module specific data is present
Oct 14 23:52:22.055 INFO running `"docker" "rm" "-f" "70079cd7cbf32b9dd2fcf24eee7dba2d8ac4579625c5c5c0a4bc72cf4ad281a2"`
Oct 14 23:52:22.168 INFO blam! 70079cd7cbf32b9dd2fcf24eee7dba2d8ac4579625c5c5c0a4bc72cf4ad281a2