Oct 11 00:45:28.223 INFO testing dalance/pipecolor against master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513 for rustdoc-test-static-cling
Oct 11 00:45:28.223 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt build --frozen
Oct 11 00:45:28.223 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-0/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-0/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,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" "CMD=cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt build --frozen" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-m" "1536M" "--network" "none" "crater"`
Oct 11 00:45:28.766 INFO blam! 2b3183d3a0c0405dde7983658d163f84748cd88ac29e2067af7e28a384ef7917
Oct 11 00:45:28.770 INFO running `"docker" "start" "-a" "2b3183d3a0c0405dde7983658d163f84748cd88ac29e2067af7e28a384ef7917"`
Oct 11 00:45:30.510 INFO kablam! usermod: no changes
Oct 11 00:45:30.716 INFO kablam!    Compiling cc v1.0.9
Oct 11 00:45:30.716 INFO kablam!    Compiling rustc-demangle v0.1.7
Oct 11 00:45:30.716 INFO kablam!    Compiling itoa v0.4.1
Oct 11 00:45:30.717 INFO kablam!    Compiling serde v1.0.36
Oct 11 00:45:30.720 INFO kablam!    Compiling same-file v0.1.3
Oct 11 00:45:30.720 INFO kablam!    Compiling remove_dir_all v0.5.1
Oct 11 00:45:30.724 INFO kablam!    Compiling glob v0.2.11
Oct 11 00:45:30.724 INFO kablam!    Compiling bytecount v0.2.0
Oct 11 00:45:31.584 INFO kablam!    Compiling nix v0.10.0
Oct 11 00:45:33.064 INFO kablam!    Compiling regex v0.2.10
Oct 11 00:45:33.068 INFO kablam!    Compiling termion v1.5.1
Oct 11 00:45:33.070 INFO kablam!    Compiling nix v0.9.0
Oct 11 00:45:33.105 INFO kablam!    Compiling walkdir v1.0.7
Oct 11 00:45:34.676 INFO kablam!    Compiling regex-syntax v0.5.3
Oct 11 00:45:44.375 INFO kablam!    Compiling tempdir v0.3.7
Oct 11 00:45:44.483 INFO kablam!    Compiling bytes v0.4.6
Oct 11 00:45:44.724 INFO kablam!    Compiling pulldown-cmark v0.1.2
Oct 11 00:45:44.816 INFO kablam!    Compiling thread_local v0.3.5
Oct 11 00:45:45.245 INFO kablam!    Compiling syn v0.12.14
Oct 11 00:45:47.137 INFO kablam!    Compiling backtrace-sys v0.1.16
Oct 11 00:46:15.101 INFO kablam!    Compiling timeout-readwrite v0.1.0
Oct 11 00:46:17.934 INFO kablam!    Compiling serde_derive_internals v0.22.2
Oct 11 00:46:17.968 INFO kablam!    Compiling structopt-derive v0.2.5
Oct 11 00:46:20.363 INFO kablam!    Compiling serde_json v1.0.16
Oct 11 00:46:20.363 INFO kablam!    Compiling semver v0.8.0
Oct 11 00:46:20.363 INFO kablam!    Compiling toml v0.4.5
Oct 11 00:46:33.491 INFO kablam!    Compiling structopt v0.2.5
Oct 11 00:46:33.589 INFO kablam!    Compiling serde_derive v1.0.36
Oct 11 00:46:33.912 INFO kablam!    Compiling backtrace v0.3.5
Oct 11 00:46:34.834 INFO kablam!    Compiling error-chain v0.11.0
Oct 11 00:46:41.064 INFO kablam!    Compiling cargo_metadata v0.3.3
Oct 11 00:46:43.833 INFO kablam!    Compiling skeptic v0.13.2
Oct 11 00:46:46.800 INFO kablam!    Compiling proc-reader v0.4.0
Oct 11 00:46:49.452 INFO kablam!    Compiling pipecolor v0.4.1-pre (/source)
Oct 11 00:46:49.944 INFO kablam! warning: unused `#[macro_use]` import
Oct 11 00:46:49.944 INFO kablam!   --> src/main.rs:13:1
Oct 11 00:46:49.944 INFO kablam!    |
Oct 11 00:46:49.944 INFO kablam! 13 | #[macro_use]
Oct 11 00:46:49.945 INFO kablam!    | ^^^^^^^^^^^^
Oct 11 00:46:49.945 INFO kablam!    |
Oct 11 00:46:49.945 INFO kablam!    = note: #[warn(unused_imports)] on by default
Oct 11 00:46:49.945 INFO kablam! 
Oct 11 00:46:49.976 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 11 00:46:49.976 INFO kablam!   --> src/main.rs:29:5
Oct 11 00:46:49.976 INFO kablam!    |
Oct 11 00:46:49.976 INFO kablam! 29 | use std::env::home_dir;
Oct 11 00:46:49.976 INFO kablam!    |     ^^^^^^^^^^^^^^^^^^
Oct 11 00:46:49.976 INFO kablam!    |
Oct 11 00:46:49.976 INFO kablam!    = note: #[warn(deprecated)] on by default
Oct 11 00:46:49.976 INFO kablam! 
Oct 11 00:46:49.984 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 11 00:46:49.984 INFO kablam!    --> src/main.rs:137:33
Oct 11 00:46:49.984 INFO kablam!     |
Oct 11 00:46:49.984 INFO kablam! 137 |     } else if let Some(mut p) = home_dir() {
Oct 11 00:46:49.984 INFO kablam!     |                                 ^^^^^^^^
Oct 11 00:46:49.984 INFO kablam! 
Oct 11 00:46:53.400 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 1m 22s
Oct 11 00:46:53.408 INFO kablam! su: No module specific data is present
Oct 11 00:46:53.712 INFO running `"docker" "rm" "-f" "2b3183d3a0c0405dde7983658d163f84748cd88ac29e2067af7e28a384ef7917"`
Oct 11 00:46:53.817 INFO blam! 2b3183d3a0c0405dde7983658d163f84748cd88ac29e2067af7e28a384ef7917
Oct 11 00:46:53.822 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen --no-run
Oct 11 00:46:53.822 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-0/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-0/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,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" "CMD=cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen --no-run" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-m" "1536M" "--network" "none" "crater"`
Oct 11 00:46:54.080 INFO blam! 5711542a7fb441949a249b0d391cac61893fda3d25608b3819b9b7b9db1666b2
Oct 11 00:46:54.081 INFO running `"docker" "start" "-a" "5711542a7fb441949a249b0d391cac61893fda3d25608b3819b9b7b9db1666b2"`
Oct 11 00:46:54.673 INFO kablam! usermod: no changes
Oct 11 00:46:54.793 INFO kablam!    Compiling proc-reader v0.4.0
Oct 11 00:46:55.985 INFO kablam!    Compiling pipecolor v0.4.1-pre (/source)
Oct 11 00:46:56.505 INFO kablam! warning: unused `#[macro_use]` import
Oct 11 00:46:56.505 INFO kablam!   --> src/main.rs:13:1
Oct 11 00:46:56.505 INFO kablam!    |
Oct 11 00:46:56.505 INFO kablam! 13 | #[macro_use]
Oct 11 00:46:56.505 INFO kablam!    | ^^^^^^^^^^^^
Oct 11 00:46:56.505 INFO kablam!    |
Oct 11 00:46:56.505 INFO kablam!    = note: #[warn(unused_imports)] on by default
Oct 11 00:46:56.505 INFO kablam! 
Oct 11 00:46:56.541 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 11 00:46:56.542 INFO kablam!   --> src/main.rs:29:5
Oct 11 00:46:56.542 INFO kablam!    |
Oct 11 00:46:56.542 INFO kablam! 29 | use std::env::home_dir;
Oct 11 00:46:56.542 INFO kablam!    |     ^^^^^^^^^^^^^^^^^^
Oct 11 00:46:56.542 INFO kablam!    |
Oct 11 00:46:56.542 INFO kablam!    = note: #[warn(deprecated)] on by default
Oct 11 00:46:56.542 INFO kablam! 
Oct 11 00:46:56.542 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 11 00:46:56.542 INFO kablam!    --> src/main.rs:137:33
Oct 11 00:46:56.542 INFO kablam!     |
Oct 11 00:46:56.542 INFO kablam! 137 |     } else if let Some(mut p) = home_dir() {
Oct 11 00:46:56.542 INFO kablam!     |                                 ^^^^^^^^
Oct 11 00:46:56.542 INFO kablam! 
Oct 11 00:47:00.316 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 5.62s
Oct 11 00:47:00.322 INFO kablam! su: No module specific data is present
Oct 11 00:47:00.558 INFO running `"docker" "rm" "-f" "5711542a7fb441949a249b0d391cac61893fda3d25608b3819b9b7b9db1666b2"`
Oct 11 00:47:00.660 INFO blam! 5711542a7fb441949a249b0d391cac61893fda3d25608b3819b9b7b9db1666b2
Oct 11 00:47:00.664 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen
Oct 11 00:47:00.664 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-0/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-0/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,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" "CMD=cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-m" "1536M" "--network" "none" "crater"`
Oct 11 00:47:00.943 INFO blam! 4caeddb301d938e0b5218adf6ba82d95f1edf469881141c198c5531d7f0a8d11
Oct 11 00:47:00.945 INFO running `"docker" "start" "-a" "4caeddb301d938e0b5218adf6ba82d95f1edf469881141c198c5531d7f0a8d11"`
Oct 11 00:47:02.117 INFO kablam! usermod: no changes
Oct 11 00:47:02.292 INFO kablam!    Compiling proc-reader v0.4.0
Oct 11 00:47:04.556 INFO kablam!    Compiling pipecolor v0.4.1-pre (/source)
Oct 11 00:47:05.208 INFO kablam! warning: unused `#[macro_use]` import
Oct 11 00:47:05.208 INFO kablam!   --> src/main.rs:13:1
Oct 11 00:47:05.208 INFO kablam!    |
Oct 11 00:47:05.208 INFO kablam! 13 | #[macro_use]
Oct 11 00:47:05.208 INFO kablam!    | ^^^^^^^^^^^^
Oct 11 00:47:05.208 INFO kablam!    |
Oct 11 00:47:05.208 INFO kablam!    = note: #[warn(unused_imports)] on by default
Oct 11 00:47:05.208 INFO kablam! 
Oct 11 00:47:05.236 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 11 00:47:05.236 INFO kablam!   --> src/main.rs:29:5
Oct 11 00:47:05.236 INFO kablam!    |
Oct 11 00:47:05.236 INFO kablam! 29 | use std::env::home_dir;
Oct 11 00:47:05.236 INFO kablam!    |     ^^^^^^^^^^^^^^^^^^
Oct 11 00:47:05.236 INFO kablam!    |
Oct 11 00:47:05.240 INFO kablam!    = note: #[warn(deprecated)] on by default
Oct 11 00:47:05.240 INFO kablam! 
Oct 11 00:47:05.240 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 11 00:47:05.240 INFO kablam!    --> src/main.rs:137:33
Oct 11 00:47:05.240 INFO kablam!     |
Oct 11 00:47:05.240 INFO kablam! 137 |     } else if let Some(mut p) = home_dir() {
Oct 11 00:47:05.240 INFO kablam!     |                                 ^^^^^^^^
Oct 11 00:47:05.240 INFO kablam! 
Oct 11 00:47:10.684 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 8.52s
Oct 11 00:47:10.692 INFO kablam!      Running /target/debug/deps/pipecolor-0ce5461f297c11a3
Oct 11 00:47:10.705 INFO blam! 
Oct 11 00:47:10.705 INFO blam! running 6 tests
Oct 11 00:47:10.765 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 11 00:47:10.765 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 11 00:47:10.766 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 11 00:47:10.766 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 11 00:47:10.766 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 11 00:47:10.767 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 11 00:47:10.767 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 11 00:47:10.767 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 11 00:47:10.767 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 11 00:47:10.768 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 11 00:47:10.768 INFO blam! Mar 25 04:17:30 xxx postfix/smtpd[19644]: connect from example.com[xxx.xxx.xx.xxx]
Oct 11 00:47:10.768 INFO blam! Mar 25 04:17:30 xxx postfix/smtpd[19644]: SSL_accept error from example.com[xxx.xxx.xx.xxx]: -1
Oct 11 00:47:10.769 INFO blam! Mar 25 04:17:30 xxx postfix/smtpd[19644]: lost connection after STARTTLS from example.com[xxx.xxx.xx.xxx]
Oct 11 00:47:10.769 INFO blam! Mar 25 04:17:30 xxx postfix/smtpd[19644]: disconnect from example.com[xxx.xxx.xx.xxx]
Oct 11 00:47:10.769 INFO blam! Mar 25 04:17:31 xxx dovecot: imap-login: Disconnected: rip=xxx.xxx.xx.xxx, lip=xxx.xx.xxx.xxx
Oct 11 00:47:10.770 INFO blam! Mar 25 04:17:45 xxx postfix/smtpd[19644]: connect from example.com[xxx.xxx.xx.xxx]
Oct 11 00:47:10.770 INFO blam! Mar 25 04:17:45 xxx postfix/smtpd[19644]: SSL_accept error from example.com[xxx.xxx.xx.xxx]: -1
Oct 11 00:47:10.770 INFO blam! Mar 25 04:17:45 xxx postfix/smtpd[19644]: lost connection after STARTTLS from example.com[xxx.xxx.xx.xxx]
Oct 11 00:47:10.770 INFO blam! Mar 25 04:17:45 xxx postfix/smtpd[19644]: disconnect from example.com[xxx.xxx.xx.xxx]
Oct 11 00:47:10.771 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 11 00:47:10.771 INFO blam! Mar 25 04:17:46 xxx dovecot: imap-login: Disconnected: rip=xxx.xxx.xx.xxx, lip=xxx.xx.xxx.xxx
Oct 11 00:47:10.771 INFO blam! Mar 25 04:17:46 xxx dovecot: IMAP(xxx): Connection closed
Oct 11 00:47:10.771 INFO blam! Mar 25 04:18:00 xxx postfix/smtpd[19644]: connect from example.com[xxx.xxx.xx.xxx]
Oct 11 00:47:10.772 INFO blam! Mar 25 04:18:00 xxx postfix/smtpd[19644]: SSL_accept error from example.com[xxx.xxx.xx.xxx]: -1
Oct 11 00:47:10.777 INFO blam! Mar 25 04:18:00 xxx postfix/smtpd[19644]: lost connection after STARTTLS from example.com[xxx.xxx.xx.xxx]
Oct 11 00:47:10.777 INFO blam! Mar 25 04:18:00 xxx postfix/smtpd[19644]: disconnect from example.com[xxx.xxx.xx.xxx]
Oct 11 00:47:10.777 INFO blam! Mar 25 04:18:01 xxx dovecot: imap-login: Disconnected: rip=xxx.xxx.xx.xxx, lip=xxx.xx.xxx.xxx
Oct 11 00:47:10.777 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 11 00:47:10.778 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 11 00:47:10.778 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 11 00:47:10.778 INFO blam! test tests::test_run ... ok
Oct 11 00:47:10.779 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 11 00:47:10.779 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 11 00:47:10.779 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 11 00:47:10.779 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 11 00:47:10.780 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 11 00:47:10.780 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 11 00:47:10.788 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 11 00:47:10.788 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 11 00:47:10.789 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 11 00:47:10.789 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 11 00:47:10.789 INFO blam! test tests::test_verbose ... ok
Oct 11 00:47:10.789 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 11 00:47:10.790 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 11 00:47:10.790 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 11 00:47:10.790 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 11 00:47:10.791 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 11 00:47:10.791 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 11 00:47:10.791 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 11 00:47:10.791 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 11 00:47:10.792 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 11 00:47:10.792 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 11 00:47:10.800 INFO blam! test colorize::tests::test_colorize ... ok
Oct 11 00:47:10.808 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 11 00:47:10.809 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 11 00:47:10.809 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 11 00:47:10.809 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 11 00:47:10.810 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 11 00:47:10.810 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 11 00:47:10.810 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 11 00:47:10.810 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 11 00:47:10.811 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 11 00:47:10.811 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 11 00:47:10.820 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 11 00:47:10.821 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 11 00:47:10.821 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 11 00:47:10.821 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 11 00:47:10.821 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 11 00:47:10.821 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 11 00:47:10.821 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 11 00:47:10.821 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 11 00:47:10.821 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 11 00:47:10.821 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 11 00:47:10.821 INFO blam! test tests::test_mode ... ok
Oct 11 00:47:10.880 INFO blam! test tests::test_read_config_fail ... ok
Oct 11 00:47:10.884 INFO blam! test colorize::tests::test_colorize_fail ... ok
Oct 11 00:47:10.884 INFO blam! 
Oct 11 00:47:10.884 INFO blam! test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 11 00:47:10.884 INFO blam! 
Oct 11 00:47:10.892 INFO kablam! su: No module specific data is present
Oct 11 00:47:11.482 INFO running `"docker" "rm" "-f" "4caeddb301d938e0b5218adf6ba82d95f1edf469881141c198c5531d7f0a8d11"`
Oct 11 00:47:11.960 INFO blam! 4caeddb301d938e0b5218adf6ba82d95f1edf469881141c198c5531d7f0a8d11