Nov 17 15:30:12.329 INFO testing FTV-Subtil/rs_http_worker against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 17 15:30:12.329 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 17 15:30:12.504 INFO [stdout] fa4877e1221d1684403d52c03cf690bbfd9b309e759352d853e166be07e396eb
Nov 17 15:30:12.505 INFO running `"docker" "start" "-a" "fa4877e1221d1684403d52c03cf690bbfd9b309e759352d853e166be07e396eb"`
Nov 17 15:30:12.858 INFO [stderr] usermod: no changes
Nov 17 15:30:13.163 INFO [stderr]    Compiling cookie-factory v0.2.2
Nov 17 15:30:13.166 INFO [stderr]    Compiling memchr v1.0.2
Nov 17 15:30:13.167 INFO [stderr]    Compiling log v0.4.6
Nov 17 15:30:13.180 INFO [stderr]    Compiling serde_json v1.0.11
Nov 17 15:30:13.182 INFO [stderr]    Compiling sasl v0.4.1
Nov 17 15:30:13.184 INFO [stderr]    Compiling regex v0.2.10
Nov 17 15:30:14.251 INFO [stderr]    Compiling nom v3.2.1
Nov 17 15:30:14.263 INFO [stderr]    Compiling log v0.3.9
Nov 17 15:30:14.263 INFO [stderr]    Compiling tokio-io v0.1.6
Nov 17 15:30:14.263 INFO [stderr]    Compiling mio v0.6.14
Nov 17 15:30:16.507 INFO [stderr]    Compiling tokio-threadpool v0.1.0
Nov 17 15:30:25.875 INFO [stderr]    Compiling amq-protocol-types v0.20.0
Nov 17 15:30:29.279 INFO [stderr]    Compiling tokio-reactor v0.1.0
Nov 17 15:30:32.203 INFO [stderr]    Compiling handlebars v0.27.0
Nov 17 15:30:33.868 INFO [stderr]    Compiling tokio v0.1.3
Nov 17 15:30:36.555 INFO [stderr]    Compiling tokio-core v0.1.13
Nov 17 15:30:41.419 INFO [stderr]    Compiling amq-protocol-codegen v0.27.0
Nov 17 15:30:41.547 INFO [stderr]    Compiling tokio-proto v0.1.1
Nov 17 15:30:41.547 INFO [stderr]    Compiling tokio-tls v0.1.4
Nov 17 15:30:46.355 INFO [stderr]    Compiling hyper v0.11.22
Nov 17 15:30:54.329 INFO [stderr]    Compiling amq-protocol v0.19.1
Nov 17 15:31:06.623 INFO [stderr]    Compiling hyper-tls v0.1.2
Nov 17 15:31:09.487 INFO [stderr]    Compiling reqwest v0.8.5
Nov 17 15:31:15.343 INFO [stderr]    Compiling lapin-async v0.10.0
Nov 17 15:31:34.443 INFO [stderr]    Compiling lapin-futures v0.10.0
Nov 17 15:31:36.583 INFO [stderr]    Compiling amqp_worker v0.1.3
Nov 17 15:31:40.418 INFO [stderr]    Compiling http_worker v0.1.0 (/source)
Nov 17 15:31:48.663 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 35s
Nov 17 15:31:48.677 INFO [stderr] su: No module specific data is present
Nov 17 15:31:49.154 INFO running `"docker" "inspect" "fa4877e1221d1684403d52c03cf690bbfd9b309e759352d853e166be07e396eb"`
Nov 17 15:31:49.269 INFO running `"docker" "rm" "-f" "fa4877e1221d1684403d52c03cf690bbfd9b309e759352d853e166be07e396eb"`
Nov 17 15:31:49.416 INFO [stdout] fa4877e1221d1684403d52c03cf690bbfd9b309e759352d853e166be07e396eb
Nov 17 15:31:49.422 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 17 15:31:49.767 INFO [stdout] 69a21394ea4da2dd3a599f7f272b6945f0a7f6ca3136b3ebee21299947e26bb4
Nov 17 15:31:49.772 INFO running `"docker" "start" "-a" "69a21394ea4da2dd3a599f7f272b6945f0a7f6ca3136b3ebee21299947e26bb4"`
Nov 17 15:31:51.021 INFO [stderr] usermod: no changes
Nov 17 15:31:51.331 INFO [stderr]    Compiling http_worker v0.1.0 (/source)
Nov 17 15:31:59.479 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 8.42s
Nov 17 15:31:59.505 INFO [stderr] su: No module specific data is present
Nov 17 15:32:00.237 INFO running `"docker" "inspect" "69a21394ea4da2dd3a599f7f272b6945f0a7f6ca3136b3ebee21299947e26bb4"`
Nov 17 15:32:00.579 INFO running `"docker" "rm" "-f" "69a21394ea4da2dd3a599f7f272b6945f0a7f6ca3136b3ebee21299947e26bb4"`
Nov 17 15:32:00.883 INFO [stdout] 69a21394ea4da2dd3a599f7f272b6945f0a7f6ca3136b3ebee21299947e26bb4
Nov 17 15:32:00.887 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 17 15:32:01.385 INFO [stdout] 7de57a71e1f6bdf5c23384f2b60e8d47cc5cc78673743bcdaa5aebcddc009e43
Nov 17 15:32:01.387 INFO running `"docker" "start" "-a" "7de57a71e1f6bdf5c23384f2b60e8d47cc5cc78673743bcdaa5aebcddc009e43"`
Nov 17 15:32:02.932 INFO [stderr] usermod: no changes
Nov 17 15:32:03.203 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.24s
Nov 17 15:32:03.208 INFO [stderr]      Running /target/debug/deps/http_worker-684e7431017216f2
Nov 17 15:32:03.213 INFO [stdout] 
Nov 17 15:32:03.213 INFO [stdout] running 2 tests
Nov 17 15:32:03.235 INFO [stdout] test message::nack_message_test ... ok
Nov 17 15:32:03.511 INFO [stdout] test message::ack_message_test ... FAILED
Nov 17 15:32:03.511 INFO [stdout] 
Nov 17 15:32:03.512 INFO [stdout] failures:
Nov 17 15:32:03.512 INFO [stdout] 
Nov 17 15:32:03.512 INFO [stdout] ---- message::ack_message_test stdout ----
Nov 17 15:32:03.513 INFO [stdout] Job { job_id: 690, parameters: Parameters { requirements: Requirements { paths: None }, source: Resource { path: "https://staticftv-a.akamaihd.net/sous-titres/france4/20180214/172524974-5a843dcd126f8-1518616910.ttml" }, destination: Resource { path: "/tmp/172524974-5a843dcd126f8-1518616910.ttml" } } }
Nov 17 15:32:03.513 INFO [stdout] thread 'message::ack_message_test' panicked at 'called `Result::unwrap()` on an `Err` value: Error { kind: Io(Custom { kind: Other, error: StringError("failed to lookup address information: Temporary failure in name resolution") }), url: Some("https://staticftv-a.akamaihd.net/sous-titres/france4/20180214/172524974-5a843dcd126f8-1518616910.ttml") }', libcore/result.rs:1009:5
Nov 17 15:32:03.513 INFO [stdout] stack backtrace:
Nov 17 15:32:03.514 INFO [stdout]    0:     0x5607a30f5a83 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h1a944b80a560daa8
Nov 17 15:32:03.514 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 17 15:32:03.514 INFO [stdout]    1:     0x5607a30efd08 - std::sys_common::backtrace::_print::h18f735b4c3a49bdb
Nov 17 15:32:03.514 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Nov 17 15:32:03.515 INFO [stdout]    2:     0x5607a30f4564 - std::panicking::default_hook::{{closure}}::h97ab3548a215c097
Nov 17 15:32:03.515 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Nov 17 15:32:03.515 INFO [stdout]                                at libstd/panicking.rs:211
Nov 17 15:32:03.516 INFO [stdout]    3:     0x5607a30f424c - std::panicking::default_hook::h98c2a01ef734e4b0
Nov 17 15:32:03.516 INFO [stdout]                                at libstd/panicking.rs:221
Nov 17 15:32:03.516 INFO [stdout]    4:     0x5607a30f4c70 - std::panicking::rust_panic_with_hook::hb4b3f28eb79ea752
Nov 17 15:32:03.516 INFO [stdout]                                at libstd/panicking.rs:476
Nov 17 15:32:03.517 INFO [stdout]    5:     0x5607a30f47f1 - std::panicking::continue_panic_fmt::h61fd5a7b1a268752
Nov 17 15:32:03.517 INFO [stdout]                                at libstd/panicking.rs:390
Nov 17 15:32:03.517 INFO [stdout]    6:     0x5607a30f46d5 - rust_begin_unwind
Nov 17 15:32:03.518 INFO [stdout]                                at libstd/panicking.rs:325
Nov 17 15:32:03.518 INFO [stdout]    7:     0x5607a3108a0c - core::panicking::panic_fmt::h400423a63e8ecedb
Nov 17 15:32:03.518 INFO [stdout]                                at libcore/panicking.rs:77
Nov 17 15:32:03.519 INFO [stdout]    8:     0x5607a2db424b - core::result::unwrap_failed::h59496ae0b886eb4d
Nov 17 15:32:03.519 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/macros.rs:26
Nov 17 15:32:03.519 INFO [stdout]    9:     0x5607a2db3b02 - <core::result::Result<T, E>>::unwrap::h23c987ff05068839
Nov 17 15:32:03.520 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/result.rs:808
Nov 17 15:32:03.520 INFO [stdout]   10:     0x5607a2da8adb - http_worker::message::process::hc1b14e35c37ff084
Nov 17 15:32:03.520 INFO [stdout]                                at src/message.rs:69
Nov 17 15:32:03.520 INFO [stdout]   11:     0x5607a2daf92e - http_worker::message::ack_message_test::ha2ce41d66f2cd327
Nov 17 15:32:03.521 INFO [stdout]                                at src/message.rs:99
Nov 17 15:32:03.521 INFO [stdout]   12:     0x5607a2daf859 - http_worker::message::ack_message_test::{{closure}}::h2e362dc9ef2dadd6
Nov 17 15:32:03.521 INFO [stdout]                                at src/message.rs:94
Nov 17 15:32:03.522 INFO [stdout]   13:     0x5607a2dafcfd - core::ops::function::FnOnce::call_once::hbdc9eca6a8ddb2a1
Nov 17 15:32:03.522 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/ops/function.rs:238
Nov 17 15:32:03.522 INFO [stdout]   14:     0x5607a2dbea8e - <F as alloc::boxed::FnBox<A>>::call_box::h9dcb7fc9b8bb4240
Nov 17 15:32:03.523 INFO [stdout]                                at libtest/lib.rs:1471
Nov 17 15:32:03.523 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/ops/function.rs:238
Nov 17 15:32:03.523 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673
Nov 17 15:32:03.524 INFO [stdout]   15:     0x5607a3103a89 - __rust_maybe_catch_panic
Nov 17 15:32:03.524 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 17 15:32:03.524 INFO [stdout]   16:     0x5607a2db6af3 - std::sys_common::backtrace::__rust_begin_short_backtrace::h89ea8afe71e36f86
Nov 17 15:32:03.525 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289
Nov 17 15:32:03.525 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398
Nov 17 15:32:03.525 INFO [stdout]                                at libtest/lib.rs:1426
Nov 17 15:32:03.526 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/sys_common/backtrace.rs:136
Nov 17 15:32:03.526 INFO [stdout]   17:     0x5607a2db79a4 - std::panicking::try::do_call::h4132042e0cbea3ae
Nov 17 15:32:03.526 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:477
Nov 17 15:32:03.527 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:319
Nov 17 15:32:03.527 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:310
Nov 17 15:32:03.527 INFO [stdout]   18:     0x5607a3103a89 - __rust_maybe_catch_panic
Nov 17 15:32:03.527 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 17 15:32:03.528 INFO [stdout]   19:     0x5607a2dbebbc - <F as alloc::boxed::FnBox<A>>::call_box::hab7f616f05880bf6
Nov 17 15:32:03.528 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289
Nov 17 15:32:03.528 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398
Nov 17 15:32:03.529 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:476
Nov 17 15:32:03.529 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673
Nov 17 15:32:03.529 INFO [stdout]   20:     0x5607a30f788d - std::sys::unix::thread::Thread::new::thread_start::h5f8d67158a1ca038
Nov 17 15:32:03.530 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:683
Nov 17 15:32:03.530 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Nov 17 15:32:03.530 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Nov 17 15:32:03.531 INFO [stdout]   21:     0x7fe92b1196b9 - start_thread
Nov 17 15:32:03.531 INFO [stdout]   22:     0x7fe92ac3941c - clone
Nov 17 15:32:03.531 INFO [stdout]   23:                0x0 - <unknown>
Nov 17 15:32:03.532 INFO [stdout] 
Nov 17 15:32:03.532 INFO [stdout] 
Nov 17 15:32:03.532 INFO [stdout] failures:
Nov 17 15:32:03.533 INFO [stdout]     message::ack_message_test
Nov 17 15:32:03.533 INFO [stdout] 
Nov 17 15:32:03.533 INFO [stdout] test result: FAILED. 1 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 15:32:03.534 INFO [stdout] 
Nov 17 15:32:03.534 INFO [stderr] error: test failed, to rerun pass '--bin http_worker'
Nov 17 15:32:03.534 INFO [stderr] su: No module specific data is present
Nov 17 15:32:04.844 INFO running `"docker" "inspect" "7de57a71e1f6bdf5c23384f2b60e8d47cc5cc78673743bcdaa5aebcddc009e43"`
Nov 17 15:32:05.183 INFO running `"docker" "rm" "-f" "7de57a71e1f6bdf5c23384f2b60e8d47cc5cc78673743bcdaa5aebcddc009e43"`
Nov 17 15:32:05.423 INFO [stdout] 7de57a71e1f6bdf5c23384f2b60e8d47cc5cc78673743bcdaa5aebcddc009e43