Nov 17 15:30:12.131 INFO testing FTV-Subtil/rs_http_worker against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 17 15:30:12.131 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 17 15:30:12.361 INFO [stdout] 56a17259e8e9105c5a0f76d959e8a9a5b4253715404f4a61de276be17af79412
Nov 17 15:30:12.362 INFO running `"docker" "start" "-a" "56a17259e8e9105c5a0f76d959e8a9a5b4253715404f4a61de276be17af79412"`
Nov 17 15:30:12.769 INFO [stderr] usermod: no changes
Nov 17 15:30:13.063 INFO [stderr]    Compiling cookie-factory v0.2.2
Nov 17 15:30:13.066 INFO [stderr]    Compiling memchr v1.0.2
Nov 17 15:30:13.066 INFO [stderr]    Compiling log v0.4.6
Nov 17 15:30:13.069 INFO [stderr]    Compiling base64 v0.5.2
Nov 17 15:30:13.071 INFO [stderr]    Compiling tokio-timer v0.1.2
Nov 17 15:30:13.762 INFO [stderr]    Compiling nom v3.2.1
Nov 17 15:30:14.179 INFO [stderr]    Compiling log v0.3.9
Nov 17 15:30:14.199 INFO [stderr]    Compiling tokio-io v0.1.6
Nov 17 15:30:14.251 INFO [stderr]    Compiling mio v0.6.14
Nov 17 15:30:14.475 INFO [stderr]    Compiling sasl v0.4.1
Nov 17 15:30:16.951 INFO [stderr]    Compiling handlebars v0.27.0
Nov 17 15:30:17.455 INFO [stderr]    Compiling tokio-threadpool v0.1.0
Nov 17 15:30:20.819 INFO [stderr]    Compiling amq-protocol-types v0.20.0
Nov 17 15:30:22.436 INFO [stderr]    Compiling tokio-reactor v0.1.0
Nov 17 15:30:26.773 INFO [stderr]    Compiling tokio v0.1.3
Nov 17 15:30:30.223 INFO [stderr]    Compiling tokio-core v0.1.13
Nov 17 15:30:33.953 INFO [stderr]    Compiling amq-protocol-codegen v0.27.0
Nov 17 15:30:36.573 INFO [stderr]    Compiling tokio-proto v0.1.1
Nov 17 15:30:36.573 INFO [stderr]    Compiling tokio-tls v0.1.4
Nov 17 15:30:40.435 INFO [stderr]    Compiling hyper v0.11.22
Nov 17 15:30:45.490 INFO [stderr]    Compiling amq-protocol v0.19.1
Nov 17 15:31:01.771 INFO [stderr]    Compiling hyper-tls v0.1.2
Nov 17 15:31:04.369 INFO [stderr]    Compiling reqwest v0.8.5
Nov 17 15:31:08.155 INFO [stderr]    Compiling lapin-async v0.10.0
Nov 17 15:31:27.705 INFO [stderr]    Compiling lapin-futures v0.10.0
Nov 17 15:31:29.420 INFO [stderr]    Compiling amqp_worker v0.1.3
Nov 17 15:31:33.731 INFO [stderr]    Compiling http_worker v0.1.0 (/source)
Nov 17 15:31:42.275 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 29s
Nov 17 15:31:42.303 INFO [stderr] su: No module specific data is present
Nov 17 15:31:42.805 INFO running `"docker" "inspect" "56a17259e8e9105c5a0f76d959e8a9a5b4253715404f4a61de276be17af79412"`
Nov 17 15:31:43.057 INFO running `"docker" "rm" "-f" "56a17259e8e9105c5a0f76d959e8a9a5b4253715404f4a61de276be17af79412"`
Nov 17 15:31:43.371 INFO [stdout] 56a17259e8e9105c5a0f76d959e8a9a5b4253715404f4a61de276be17af79412
Nov 17 15:31:43.388 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 17 15:31:43.855 INFO [stdout] eb98eaadee45f8d94dbaa88a9238a2581d54c766a40997d2c4bba42a8b3be195
Nov 17 15:31:43.863 INFO running `"docker" "start" "-a" "eb98eaadee45f8d94dbaa88a9238a2581d54c766a40997d2c4bba42a8b3be195"`
Nov 17 15:31:45.027 INFO [stderr] usermod: no changes
Nov 17 15:31:45.351 INFO [stderr]    Compiling http_worker v0.1.0 (/source)
Nov 17 15:31:52.300 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.25s
Nov 17 15:31:52.315 INFO [stderr] su: No module specific data is present
Nov 17 15:31:52.863 INFO running `"docker" "inspect" "eb98eaadee45f8d94dbaa88a9238a2581d54c766a40997d2c4bba42a8b3be195"`
Nov 17 15:31:53.066 INFO running `"docker" "rm" "-f" "eb98eaadee45f8d94dbaa88a9238a2581d54c766a40997d2c4bba42a8b3be195"`
Nov 17 15:31:53.252 INFO [stdout] eb98eaadee45f8d94dbaa88a9238a2581d54c766a40997d2c4bba42a8b3be195
Nov 17 15:31:53.268 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 17 15:31:53.641 INFO [stdout] 2cfdadc2587376666fe8bfe0748456f421dd50d38d62a3a1dc4dd1c394569728
Nov 17 15:31:53.649 INFO running `"docker" "start" "-a" "2cfdadc2587376666fe8bfe0748456f421dd50d38d62a3a1dc4dd1c394569728"`
Nov 17 15:31:55.123 INFO [stderr] usermod: no changes
Nov 17 15:31:55.505 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.36s
Nov 17 15:31:55.535 INFO [stderr]      Running /target/debug/deps/http_worker-06319ede28087301
Nov 17 15:31:55.543 INFO [stdout] 
Nov 17 15:31:55.543 INFO [stdout] running 2 tests
Nov 17 15:31:55.543 INFO [stdout] test message::nack_message_test ... ok
Nov 17 15:31:55.853 INFO [stdout] test message::ack_message_test ... FAILED
Nov 17 15:31:55.853 INFO [stdout] 
Nov 17 15:31:55.853 INFO [stdout] failures:
Nov 17 15:31:55.853 INFO [stdout] 
Nov 17 15:31:55.853 INFO [stdout] ---- message::ack_message_test stdout ----
Nov 17 15:31:55.853 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:31:55.853 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:31:55.853 INFO [stdout] stack backtrace:
Nov 17 15:31:55.853 INFO [stdout]    0:     0x55a2a1344f03 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h26df0f6fdb27c709
Nov 17 15:31:55.853 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 17 15:31:55.853 INFO [stdout]    1:     0x55a2a133f2c8 - std::sys_common::backtrace::_print::h84c40fecda6d638e
Nov 17 15:31:55.853 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Nov 17 15:31:55.853 INFO [stdout]    2:     0x55a2a13439e4 - std::panicking::default_hook::{{closure}}::h77e370c0567fc0a7
Nov 17 15:31:55.853 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Nov 17 15:31:55.853 INFO [stdout]                                at libstd/panicking.rs:211
Nov 17 15:31:55.853 INFO [stdout]    3:     0x55a2a13436cc - std::panicking::default_hook::hb9a45c11a33bbcb2
Nov 17 15:31:55.853 INFO [stdout]                                at libstd/panicking.rs:221
Nov 17 15:31:55.853 INFO [stdout]    4:     0x55a2a13440f0 - std::panicking::rust_panic_with_hook::ha8d95048d940f754
Nov 17 15:31:55.853 INFO [stdout]                                at libstd/panicking.rs:476
Nov 17 15:31:55.853 INFO [stdout]    5:     0x55a2a1343c71 - std::panicking::continue_panic_fmt::hfc82a3747387e2c6
Nov 17 15:31:55.853 INFO [stdout]                                at libstd/panicking.rs:390
Nov 17 15:31:55.853 INFO [stdout]    6:     0x55a2a1343b55 - rust_begin_unwind
Nov 17 15:31:55.853 INFO [stdout]                                at libstd/panicking.rs:325
Nov 17 15:31:55.853 INFO [stdout]    7:     0x55a2a1357f5c - core::panicking::panic_fmt::h0b2a5728bdf6d5e2
Nov 17 15:31:55.853 INFO [stdout]                                at libcore/panicking.rs:77
Nov 17 15:31:55.853 INFO [stdout]    8:     0x55a2a1002d2b - core::result::unwrap_failed::h110f50c210e75b4d
Nov 17 15:31:55.853 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libcore/macros.rs:26
Nov 17 15:31:55.853 INFO [stdout]    9:     0x55a2a10028a2 - <core::result::Result<T, E>>::unwrap::hd0dffb812a846d9e
Nov 17 15:31:55.853 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libcore/result.rs:808
Nov 17 15:31:55.853 INFO [stdout]   10:     0x55a2a0ff757b - http_worker::message::process::h43dc8303150fb9a9
Nov 17 15:31:55.853 INFO [stdout]                                at src/message.rs:69
Nov 17 15:31:55.853 INFO [stdout]   11:     0x55a2a0ffe3ce - http_worker::message::ack_message_test::hd406c3a856085223
Nov 17 15:31:55.853 INFO [stdout]                                at src/message.rs:99
Nov 17 15:31:55.853 INFO [stdout]   12:     0x55a2a0ffe2f9 - http_worker::message::ack_message_test::{{closure}}::h192fc49d45d50aa5
Nov 17 15:31:55.853 INFO [stdout]                                at src/message.rs:94
Nov 17 15:31:55.853 INFO [stdout]   13:     0x55a2a0ffe6bd - core::ops::function::FnOnce::call_once::h25254ce9764566c5
Nov 17 15:31:55.853 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libcore/ops/function.rs:238
Nov 17 15:31:55.853 INFO [stdout]   14:     0x55a2a100d73e - <F as alloc::boxed::FnBox<A>>::call_box::hae1ea457a0ccd8d5
Nov 17 15:31:55.853 INFO [stdout]                                at libtest/lib.rs:1471
Nov 17 15:31:55.853 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libcore/ops/function.rs:238
Nov 17 15:31:55.853 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:673
Nov 17 15:31:55.853 INFO [stdout]   15:     0x55a2a1353019 - __rust_maybe_catch_panic
Nov 17 15:31:55.853 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 17 15:31:55.853 INFO [stdout]   16:     0x55a2a1005593 - std::sys_common::backtrace::__rust_begin_short_backtrace::hd0ded23d435d70d0
Nov 17 15:31:55.853 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:289
Nov 17 15:31:55.853 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:398
Nov 17 15:31:55.853 INFO [stdout]                                at libtest/lib.rs:1426
Nov 17 15:31:55.853 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/sys_common/backtrace.rs:136
Nov 17 15:31:55.853 INFO [stdout]   17:     0x55a2a1006434 - std::panicking::try::do_call::h031514fb401b7159
Nov 17 15:31:55.853 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/thread/mod.rs:477
Nov 17 15:31:55.853 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:319
Nov 17 15:31:55.853 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:310
Nov 17 15:31:55.853 INFO [stdout]   18:     0x55a2a1353019 - __rust_maybe_catch_panic
Nov 17 15:31:55.853 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 17 15:31:55.853 INFO [stdout]   19:     0x55a2a100d61c - <F as alloc::boxed::FnBox<A>>::call_box::h44700b7a2ec6fd33
Nov 17 15:31:55.853 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:289
Nov 17 15:31:55.853 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:398
Nov 17 15:31:55.853 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/thread/mod.rs:476
Nov 17 15:31:55.853 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:673
Nov 17 15:31:55.853 INFO [stdout]   20:     0x55a2a1346e1d - std::sys::unix::thread::Thread::new::thread_start::h8526625243d2f13b
Nov 17 15:31:55.853 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:683
Nov 17 15:31:55.853 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Nov 17 15:31:55.853 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Nov 17 15:31:55.853 INFO [stdout]   21:     0x7f445a2136b9 - start_thread
Nov 17 15:31:55.853 INFO [stdout]   22:     0x7f4459d3341c - clone
Nov 17 15:31:55.853 INFO [stdout]   23:                0x0 - <unknown>
Nov 17 15:31:55.853 INFO [stdout] 
Nov 17 15:31:55.853 INFO [stdout] 
Nov 17 15:31:55.853 INFO [stdout] failures:
Nov 17 15:31:55.853 INFO [stdout]     message::ack_message_test
Nov 17 15:31:55.853 INFO [stdout] 
Nov 17 15:31:55.853 INFO [stdout] test result: FAILED. 1 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 15:31:55.853 INFO [stdout] 
Nov 17 15:31:55.871 INFO [stderr] error: test failed, to rerun pass '--bin http_worker'
Nov 17 15:31:55.871 INFO [stderr] su: No module specific data is present
Nov 17 15:31:57.053 INFO running `"docker" "inspect" "2cfdadc2587376666fe8bfe0748456f421dd50d38d62a3a1dc4dd1c394569728"`
Nov 17 15:31:57.326 INFO running `"docker" "rm" "-f" "2cfdadc2587376666fe8bfe0748456f421dd50d38d62a3a1dc4dd1c394569728"`
Nov 17 15:31:57.627 INFO [stdout] 2cfdadc2587376666fe8bfe0748456f421dd50d38d62a3a1dc4dd1c394569728