Intermittent timeout in /XMLHttpRequest/send-entity-body-document.htm

[emilio]

TIMEOUT [expected OK] /XMLHttpRequest/send-entity-body-document.htm

[jdm]

I've looked at an rr recording of this. It appears that it's a timeout because there are enough confounding factors under heavy load; it has just completed one subtest when the harness timeout triggers. One annoyance is that with seven iframes, we first load seven about:blank documents, dispatch load events for them, notify the constellation, then disregard the notifications that they loaded because we've begun loading the actual documents instead. Then we get the network responses for each of those seven documents, parse them, run load events, notify the constellation, and then get a notification from the constellation that allows us to fire the load event for the first iframe.

[SimonSapin]

Is this the same issue?

#19763 (comment)

  ▶ TIMEOUT [expected OK] /XMLHttpRequest/responseText-status.html
  │ 
  │ VMware, Inc.
  │ softpipe
  │ 3.3 (Core Profile) Mesa 17.3.0-devel
  │ Failed to receive API response: Io(Error { repr: Os { code: 104, message: "Connection reset by peer" } }) (thread Constellation, at libcore/result.rs:916)
  │ stack backtrace:
  │    0:     0x7f4cf3db0fcc - backtrace::backtrace::trace::hb75b773203fae4d0
  │    1:     0x7f4cf3db0432 - backtrace::capture::Backtrace::new::h5c9ac6f51a9d2e41
  │    2:     0x7f4cf1315978 - servo::main::{{closure}}::h011eacf8ecbff923
  │    3:     0x7f4cf3dc0895 - std::panicking::rust_panic_with_hook::h8d3a97e694abd4a9
  │                         at libstd/panicking.rs:577
  │    4:     0x7f4cf3dc071e - std::panicking::begin_panic::h3d56d94a91bc5ab7
  │                         at libstd/panicking.rs:537
  │    5:     0x7f4cf3dc061a - std::panicking::begin_panic_fmt::heff2ac76747287e6
  │                         at libstd/panicking.rs:521
  │    6:     0x7f4cf3dc05b2 - rust_begin_unwind
  │                         at libstd/panicking.rs:497
  │    7:     0x7f4cf3df41d0 - core::panicking::panic_fmt::h8d2a1d48b93388a4
  │                         at libcore/panicking.rs:71
  │    8:     0x7f4cf34e201d - core::result::unwrap_failed::ha87097ce1f79cc16
  │    9:     0x7f4cf34e6c01 - webrender_api::api::RenderApiSender::create_api::h0e3b7edad6a8c82e
  │   10:     0x7f4cf1429056 - constellation::pipeline::UnprivilegedPipelineContent::start_all::h2651173f1fd3220f
  │   11:     0x7f4cf143f366 - <constellation::constellation::Constellation<Message, LTF, STF>>::new_pipeline::h58d8e417f4b8bbf2
  │   12:     0x7f4cf145b26c - <constellation::constellation::Constellation<Message, LTF, STF>>::handle_request_from_compositor::he272a09f2021e98d
  │   13:     0x7f4cf1461e84 - <constellation::constellation::Constellation<Message, LTF, STF>>::run::h47a4a57842948f07
  │   14:     0x7f4cf13a4582 - _ZN3std10sys_common9backtrace28__rust_begin_short_backtrace17h4ffea2157e01a088E.llvm.B8137DA6
  │   15:     0x7f4cf142b6c5 - _ZN3std9panicking3try7do_call17hb04625a1ebd41b09E.llvm.246FDB5D
  │   16:     0x7f4cf3de8c7e - __rust_maybe_catch_panic
  │                         at libpanic_unwind/lib.rs:102
  │   17:     0x7f4cf13a9dba - <F as alloc::boxed::FnBox<A>>::call_box::h092dca0581362912
  │   18:     0x7f4cf3dcd7f7 - <alloc::boxed::Box<alloc::boxed::FnBox<A, Output$u3d$R$GT$$u20$$u2b$$u20$$u27$a$GT$$u20$as$u20$core..ops..function..FnOnce$LT$A$GT$$GT$::call_once::h2868c885f21882c0
  │                         at /checkout/src/liballoc/boxed.rs:827
  │                          - std::sys_common::thread::start_thread::h63baebbba56a8844
  │                         at libstd/sys_common/thread.rs:24
  │   19:     0x7f4cf3dd44c8 - std::sys::unix::thread::Thread::new::thread_start::h30e00ffa8c70cc92
  │                         at libstd/sys/unix/thread.rs:90
  │   20:     0x7f4cf022f183 - start_thread
  │   21:     0x7f4ceeaf6ffc - clone
  │   22:                0x0 - <unknown>
  │ ERROR:servo: Failed to receive API response: Io(Error { repr: Os { code: 104, message: "Connection reset by peer" } })
  │ IpcSelectionResult::unwrap(): channel 1 closed (thread ResourceManager, at /home/servo/.cargo/registry/src/github.com-1ecc6299db9ec823/ipc-channel-0.9.0/src/ipc.rs:334)
  │ called `Result::unwrap()` on an `Err` value: Io(Error { repr: Os { code: 104, message: "Connection reset by peer" } }) (thread StorageManager, at libcore/result.rs:916)
  │ stack backtrace:
  │    0:     0x7f4cf3db0fcc - backtrace::backtrace::trace::hb75b773203fae4d0
  │    1:     0x7f4cf3db0432 - backtrace::capture::Backtrace::new::h5c9ac6f51a9d2e41
  │    2:     0x7f4cf1315978 - servo::main::{{closure}}::h011eacf8ecbff923
  │    3:     0x7f4cf3dc0895 - std::panicking::rust_panic_with_hook::h8d3a97e694abd4a9
  │                         at libstd/panicking.rs:577
  │    4:     0x7f4cf3dc071e - std::panicking::begin_panic::h3d56d94a91bc5ab7
  │                         at libstd/panicking.rs:537
  │    5:     0x7f4cf3dc061a - std::panicking::begin_panic_fmt::heff2ac76747287e6
  │                         at libstd/panicking.rs:521
  │    6:     0x7f4cf34ffc13 - ipc_channel::ipc::IpcSelectionResult::unwrap::h0da93c4784c5433d
  │    7:     0x7f4cf27c0432 - net::resource_thread::ResourceChannelManager::start::h9d23039ae72a8269
  │    8:     0x7f4cf2831298 - std::sys_common::backtrace::__rust_begin_short_backtrace::h35725ec5358d7717
  │    9:     0x7f4cf27d0b1f - _ZN3std9panicking3try7do_call17h25720e136cccc03dE.llvm.662D243A
  │   10:     0x7f4cf3de8c7e - __rust_maybe_catch_panic
  │                         at libpanic_unwind/lib.rs:102
  │   11:     0x7f4cf281e2cd - <F as alloc::boxed::FnBox<A>>::call_box::hb28818861be1adb9
  │   12:     0x7f4cf3dcd7f7 - <alloc::boxed::Box<alloc::boxed::FnBox<A, Output$u3d$R$GT$$u20$$u2b$$u20$$u27$a$GT$$u20$as$u20$core..ops..function..FnOnce$LT$A$GT$$GT$::call_once::h2868c885f21882c0
  │                         at /checkout/src/liballoc/boxed.rs:827
  │                          - std::sys_common::thread::start_thread::h63baebbba56a8844
  │                         at libstd/sys_common/thread.rs:24
  │   13:     0x7f4cf3dd44c8 - std::sys::unix::thread::Thread::new::thread_start::h30e00ffa8c70cc92
  │                         at libstd/sys/unix/thread.rs:90
  │   14:     0x7f4cf022f183 - start_thread
  │   15:     0x7f4ceeaf6ffc - clone
  │   16:                0x0 - <unknown>
  │ ERROR:servo: IpcSelectionResult::unwrap(): channel 1 closed
  │ stack backtrace:
  │    0:     0x7f4cf3db0fcc - backtrace::backtrace::trace::hb75b773203fae4d0
  │    1:     0x7f4cf3db0432 - backtrace::capture::Backtrace::new::h5c9ac6f51a9d2e41
  │    2:     0x7f4cf1315978 - servo::main::{{closure}}::h011eacf8ecbff923
  │    3:     0x7f4cf3dc0895 - std::panicking::rust_panic_with_hook::h8d3a97e694abd4a9
  │                         at libstd/panicking.rs:577
  │    4:     0x7f4cf3dc071e - std::panicking::begin_panic::h3d56d94a91bc5ab7
  │                         at libstd/panicking.rs:537
  │    5:     0x7f4cf3dc061a - std::panicking::begin_panic_fmt::heff2ac76747287e6
  │                         at libstd/panicking.rs:521
  │    6:     0x7f4cf3dc05b2 - rust_begin_unwind
  │                         at libstd/panicking.rs:497
  │    7:     0x7f4cf3df41d0 - core::panicking::panic_fmt::h8d2a1d48b93388a4
  │                         at libcore/panicking.rs:71
  │    8:     0x7f4cf27bcf3d - core::result::unwrap_failed::h45d114520996eca7
  │    9:     0x7f4cf2841a8e - _ZN141_$LT$ipc_channel..ipc..IpcSender$LT$net_traits..storage_thread..StorageThreadMsg$GT$$u20$as$u20$net..storage_thread..StorageThreadFactory$GT$3new28_$u7b$$u7b$closure$u7d$$u7d$17hf6d206b9b62cc72dE.llvm.CC76F27D
  │   10:     0x7f4cf2832bdb - std::sys_common::backtrace::__rust_begin_short_backtrace::h7d17d54b1f49248d
  │   11:     0x7f4cf27d0d8b - _ZN3std9panicking3try7do_call17hee1c946a7b03eadaE.llvm.662D243A
  │   12:     0x7f4cf3de8c7e - __rust_maybe_catch_panic
  │                         at libpanic_unwind/lib.rs:102
  │   13:     0x7f4cf281d2a6 - <F as alloc::boxed::FnBox<A>>::call_box::h1a7fbb0c3561d961
  │   14:     0x7f4cf3dcd7f7 - <alloc::boxed::Box<alloc::boxed::FnBox<A, Output$u3d$R$GT$$u20$$u2b$$u20$$u27$a$GT$$u20$as$u20$core..ops..function..FnOnce$LT$A$GT$$GT$::call_once::h2868c885f21882c0
  │                         at /checkout/src/liballoc/boxed.rs:827
  │                          - std::sys_common::thread::start_thread::h63baebbba56a8844
  │                         at libstd/sys_common/thread.rs:24
  │   15:     0x7f4cf3dd44c8 - std::sys::unix::thread::Thread::new::thread_start::h30e00ffa8c70cc92
  │                         at libstd/sys/unix/thread.rs:90
  │   16:     0x7f4cf022f183 - start_thread
  │   17:     0x7f4ceeaf6ffc - clone
  │   18:                0x0 - <unknown>
  └ ERROR:servo: called `Result::unwrap()` on an `Err` value: Io(Error { repr: Os { code: 104, message: "Connection reset by peer" } })

[jdm]

@SimonSapin No, that's #13480. What led you to suspect this issue?

[SimonSapin]

I searched for "timeout" and "XMLHttpRequest".