Closed Bug 1429782 Opened 7 years ago Closed 7 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | dom/base/test/test_bug597345.html | application timed out after 370 seconds with no output

Tracking

()

Status:

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Treeherder Bug Filer

Reporter

Description

•

7 years ago

treeherder

Filed by: apavel [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=155626966&repo=mozilla-inbound https://queue.taskcluster.net/v1/task/XVhM4ln3TIykTM3kd7FmSg/runs/0/artifacts/public/logs/live_backing.log [task 2018-01-11T13:33:58.709Z] 13:33:58 INFO - TEST-START | dom/base/test/test_bug597345.html [task 2018-01-11T13:33:58.816Z] 13:33:58 INFO - GECKO(1271) | ++DOMWINDOW == 32 (0x7f9abfc13000) [pid = 1332] [serial = 1026] [outer = 0x7f9adf57ba40] [task 2018-01-11T13:33:59.370Z] 13:33:59 INFO - GECKO(1271) | [Child 1332, Main Thread] ###!!! ASSERTION: Incorrect preload bookkeeping: 'i != mScriptLoader->mPreloads.NoIndex', file /builds/worker/workspace/build/src/dom/script/ScriptLoadHandler.cpp, line 219 [task 2018-01-11T13:34:54.309Z] 13:34:54 INFO - GECKO(1271) | #01: mozilla::dom::ScriptLoadHandler::EnsureDecoder [dom/script/ScriptLoadHandler.cpp:148] [task 2018-01-11T13:34:54.310Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.312Z] 13:34:54 INFO - GECKO(1271) | #02: mozilla::dom::ScriptLoadHandler::OnIncrementalData [dom/script/ScriptLoadHandler.cpp:63] [task 2018-01-11T13:34:54.313Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.314Z] 13:34:54 INFO - GECKO(1271) | #03: nsIncrementalStreamLoader::WriteSegmentFun [netwerk/base/nsIncrementalStreamLoader.cpp:136] [task 2018-01-11T13:34:54.315Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.318Z] 13:34:54 INFO - GECKO(1271) | #04: nsStringInputStream::ReadSegments [xpcom/io/nsStringStream.cpp:258] [task 2018-01-11T13:34:54.319Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.319Z] 13:34:54 INFO - GECKO(1271) | #05: nsIncrementalStreamLoader::OnDataAvailable [xpcom/base/nsCOMPtr.h:631] [task 2018-01-11T13:34:54.321Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.321Z] 13:34:54 INFO - GECKO(1271) | #06: mozilla::net::HttpChannelChild::DoOnDataAvailable [netwerk/protocol/http/HttpChannelChild.cpp:972] [task 2018-01-11T13:34:54.322Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.323Z] 13:34:54 INFO - GECKO(1271) | #07: mozilla::net::HttpChannelChild::OnTransportAndData [xpcom/base/nsCOMPtr.h:800] [task 2018-01-11T13:34:54.324Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.325Z] 13:34:54 INFO - GECKO(1271) | #08: mozilla::net::ChannelEventQueue::FlushQueue [netwerk/ipc/ChannelEventQueue.cpp:65] [task 2018-01-11T13:34:54.326Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.328Z] 13:34:54 INFO - GECKO(1271) | #09: mozilla::net::ChannelEventQueue::ResumeInternal()::CompleteResumeRunnable::Run() [task 2018-01-11T13:34:54.329Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.331Z] 13:34:54 INFO - GECKO(1271) | #10: mozilla::SchedulerGroup::Runnable::Run [xpcom/threads/SchedulerGroup.cpp:395] [task 2018-01-11T13:34:54.332Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.334Z] 13:34:54 INFO - GECKO(1271) | #11: nsThread::ProcessNextEvent [mfbt/Maybe.h:445] [task 2018-01-11T13:34:54.336Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.337Z] 13:34:54 INFO - GECKO(1271) | #12: NS_ProcessNextEvent [xpcom/threads/nsThreadUtils.cpp:517] [task 2018-01-11T13:34:54.339Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.341Z] 13:34:54 INFO - GECKO(1271) | #13: mozilla::ipc::MessagePump::Run [ipc/glue/MessagePump.cpp:98] [task 2018-01-11T13:34:54.343Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.345Z] 13:34:54 INFO - GECKO(1271) | #14: MessageLoop::RunInternal [ipc/chromium/src/base/message_loop.cc:327] [task 2018-01-11T13:34:54.346Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.347Z] 13:34:54 INFO - GECKO(1271) | #15: MessageLoop::Run [ipc/chromium/src/base/message_loop.cc:298] [task 2018-01-11T13:34:54.349Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.349Z] 13:34:54 INFO - GECKO(1271) | #16: nsBaseAppShell::Run [widget/nsBaseAppShell.cpp:159] [task 2018-01-11T13:34:54.350Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.351Z] 13:34:54 INFO - GECKO(1271) | #17: XRE_RunAppShell [toolkit/xre/nsEmbedFunctions.cpp:834] [task 2018-01-11T13:34:54.352Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.353Z] 13:34:54 INFO - GECKO(1271) | #18: mozilla::ipc::MessagePumpForChildProcess::Run [ipc/glue/MessagePump.cpp:269] [task 2018-01-11T13:34:54.354Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.355Z] 13:34:54 INFO - GECKO(1271) | #19: MessageLoop::RunInternal [ipc/chromium/src/base/message_loop.cc:327] [task 2018-01-11T13:34:54.356Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.357Z] 13:34:54 INFO - GECKO(1271) | #20: MessageLoop::Run [ipc/chromium/src/base/message_loop.cc:298] [task 2018-01-11T13:34:54.357Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.358Z] 13:34:54 INFO - GECKO(1271) | #21: XRE_InitChildProcess [toolkit/xre/nsEmbedFunctions.cpp:707] [task 2018-01-11T13:34:54.358Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.481Z] 13:34:54 INFO - GECKO(1271) | #22: content_process_main [ipc/contentproc/plugin-container.cpp:65] [task 2018-01-11T13:34:54.481Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.482Z] 13:34:54 INFO - GECKO(1271) | #23: main [browser/app/nsBrowserApp.cpp:283] [task 2018-01-11T13:34:54.482Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.483Z] 13:34:54 INFO - GECKO(1271) | #24: libc.so.6 + 0x20830 [task 2018-01-11T13:34:54.485Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.486Z] 13:34:54 INFO - GECKO(1271) | #25: _start [task 2018-01-11T13:34:54.486Z] 13:34:54 INFO - [task 2018-01-11T13:34:54.487Z] 13:34:54 INFO - GECKO(1271) | Hit MOZ_CRASH(ElementAt(aIndex = 18446744073709551615, aLength = 0)) at /builds/worker/workspace/build/src/xpcom/ds/nsTArray.cpp:28 [task 2018-01-11T13:34:54.488Z] 13:34:54 INFO - GECKO(1271) | [Parent 1271, Gecko_IOThread] WARNING: pipe error (67): Connection reset by peer: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 353 [task 2018-01-11T13:34:54.489Z] 13:34:54 INFO - GECKO(1271) | [Parent 1271, Gecko_IOThread] WARNING: pipe error (65): Connection reset by peer: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 353 [task 2018-01-11T13:34:54.489Z] 13:34:54 INFO - GECKO(1271) | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150084,name=PBrowser::Msg_Destroy) Channel error: cannot send/recv [task 2018-01-11T13:34:54.490Z] 13:34:54 INFO - GECKO(1271) | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150084,name=PBrowser::Msg_Destroy) Channel error: cannot send/recv [task 2018-01-11T13:34:54.490Z] 13:34:54 INFO - GECKO(1271) | ++DOCSHELL 0x7f43e8042000 == 4 [pid = 1271] [id = {037cba94-a9a7-46ed-8d44-75b55934562a}] [task 2018-01-11T13:34:54.491Z] 13:34:54 INFO - GECKO(1271) | ++DOMWINDOW == 9 (0x7f4411d693f0) [pid = 1271] [serial = 40] [outer = (nil)] [task 2018-01-11T13:34:54.492Z] 13:34:54 INFO - GECKO(1271) | ++DOMWINDOW == 10 (0x7f43f111ec00) [pid = 1271] [serial = 41] [outer = 0x7f4411d693f0] [task 2018-01-11T13:34:54.494Z] 13:34:54 INFO - GECKO(1271) | [Parent 1271, Main Thread] WARNING: NS_ENSURE_TRUE(aSecondURI) failed: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 98 [task 2018-01-11T13:34:54.495Z] 13:34:54 INFO - GECKO(1271) | ++DOMWINDOW == 11 (0x7f43f111dc00) [pid = 1271] [serial = 42] [outer = 0x7f4411d693f0] [task 2018-01-11T13:34:54.499Z] 13:34:54 INFO - GECKO(1271) | --DOMWINDOW == 10 (0x7f43f111ec00) [pid = 1271] [serial = 41] [outer = (nil)] [url = about:blank] [task 2018-01-11T13:34:54.500Z] 13:34:54 INFO - GECKO(1271) | --DOMWINDOW == 9 (0x7f4411d6a370) [pid = 1271] [serial = 34] [outer = (nil)] [url = chrome://browser/content/browser.xul] [task 2018-01-11T13:34:54.763Z] 13:34:54 INFO - GECKO(1271) | --DOMWINDOW == 8 (0x7f4410e24800) [pid = 1271] [serial = 35] [outer = (nil)] [url = about:blank] [task 2018-01-11T13:36:29.046Z] 13:36:29 INFO - GECKO(1271) | [Parent 1271, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80070057: file /builds/worker/workspace/build/src/netwerk/base/nsChannelClassifier.cpp, line 344 [task 2018-01-11T13:36:29.047Z] 13:36:29 INFO - GECKO(1271) | [Parent 1271, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80070057: file /builds/worker/workspace/build/src/netwerk/base/nsChannelClassifier.cpp, line 344 [task 2018-01-11T13:42:39.055Z] 13:42:39 INFO - Buffered messages finished [task 2018-01-11T13:42:39.057Z] 13:42:39 ERROR - TEST-UNEXPECTED-TIMEOUT | dom/base/test/test_bug597345.html | application timed out after 370 seconds with no output [task 2018-01-11T13:42:39.081Z] 13:42:39 ERROR - Force-terminating active process(es). [task 2018-01-11T13:42:39.082Z] 13:42:39 INFO - Determining child pids from psutil... [task 2018-01-11T13:42:39.084Z] 13:42:39 INFO - [1368] [task 2018-01-11T13:42:39.085Z] 13:42:39 INFO - ==> process 1271 launched child process 1294 [task 2018-01-11T13:42:39.085Z] 13:42:39 INFO - ==> process 1271 launched child process 1332 [task 2018-01-11T13:42:39.085Z] 13:42:39 INFO - ==> process 1271 launched child process 1368 [task 2018-01-11T13:42:39.085Z] 13:42:39 INFO - ==> process 1271 launched child process 1454 [task 2018-01-11T13:42:39.085Z] 13:42:39 INFO - Found child pids: set([1368, 1332, 1454, 1294]) [task 2018-01-11T13:42:39.085Z] 13:42:39 INFO - Failed to get child procs [task 2018-01-11T13:42:39.085Z] 13:42:39 INFO - Killing process: 1368 [task 2018-01-11T13:42:39.088Z] 13:42:39 INFO - TEST-INFO | started process screentopng [task 2018-01-11T13:42:39.634Z] 13:42:39 INFO - TEST-INFO | screentopng: exit 0 [task 2018-01-11T13:42:39.635Z] 13:42:39 INFO - Killing process: 1332 [task 2018-01-11T13:42:39.636Z] 13:42:39 INFO - Not taking screenshot here: see the one that was previously logged [task 2018-01-11T13:42:39.637Z] 13:42:39 INFO - Can't trigger Breakpad, process no longer exists [task 2018-01-11T13:42:39.638Z] 13:42:39 INFO - Killing process: 1454 [task 2018-01-11T13:42:39.639Z] 13:42:39 INFO - Not taking screenshot here: see the one that was previously logged [task 2018-01-11T13:42:39.641Z] 13:42:39 INFO - Can't trigger Breakpad, process no longer exists [task 2018-01-11T13:42:39.641Z] 13:42:39 INFO - Killing process: 1294 [task 2018-01-11T13:42:39.641Z] 13:42:39 INFO - Not taking screenshot here: see the one that was previously logged [task 2018-01-11T13:42:39.642Z] 13:42:39 INFO - Can't trigger Breakpad, process no longer exists [task 2018-01-11T13:42:39.643Z] 13:42:39 INFO - Killing process: 1271 [task 2018-01-11T13:42:39.644Z] 13:42:39 INFO - Not taking screenshot here: see the one that was previously logged [task 2018-01-11T13:42:39.808Z] 13:42:39 INFO - psutil found pid 1271 dead [task 2018-01-11T13:42:39.954Z] 13:42:39 WARNING - runtests.py | Failed to get app exit code - running/crashed? [task 2018-01-11T13:42:39.957Z] 13:42:39 INFO - TEST-INFO | Main app process: exit 0 [task 2018-01-11T13:42:39.958Z] 13:42:39 INFO - runtests.py | Application ran for: 0:16:50.553809 [task 2018-01-11T13:42:39.959Z] 13:42:39 INFO - zombiecheck | Reading PID log: /tmp/tmpzT8DMipidlog [task 2018-01-11T13:42:39.960Z] 13:42:39 INFO - ==> process 1271 launched child process 1294 [task 2018-01-11T13:42:39.961Z] 13:42:39 INFO - ==> process 1271 launched child process 1332 [task 2018-01-11T13:42:39.962Z] 13:42:39 INFO - ==> process 1271 launched child process 1368 [task 2018-01-11T13:42:39.962Z] 13:42:39 INFO - ==> process 1271 launched child process 1454 [task 2018-01-11T13:42:39.963Z] 13:42:39 INFO - zombiecheck | Checking for orphan process with PID: 1368 [task 2018-01-11T13:42:39.965Z] 13:42:39 INFO - zombiecheck | Checking for orphan process with PID: 1332 [task 2018-01-11T13:42:39.967Z] 13:42:39 INFO - zombiecheck | Checking for orphan process with PID: 1454 [task 2018-01-11T13:42:39.968Z] 13:42:39 INFO - zombiecheck | Checking for orphan process with PID: 1294 [task 2018-01-11T13:42:39.976Z] 13:42:39 INFO - mozcrash Copy/paste: /usr/local/bin/linux64-minidump_stackwalk /tmp/tmpGzR27k.mozrunner/minidumps/2a57efc1-3da7-94ce-2f5f-f0540a33961e.dmp /builds/worker/workspace/build/symbols [task 2018-01-11T13:42:48.527Z] 13:42:48 INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/2a57efc1-3da7-94ce-2f5f-f0540a33961e.dmp [task 2018-01-11T13:42:48.529Z] 13:42:48 INFO - mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/2a57efc1-3da7-94ce-2f5f-f0540a33961e.extra [task 2018-01-11T13:42:48.603Z] 13:42:48 INFO - PROCESS-CRASH | Main app process exited normally | application crashed [@ libc-2.23.so + 0xfb70d] [task 2018-01-11T13:42:48.605Z] 13:42:48 INFO - Crash dump filename: /tmp/tmpGzR27k.mozrunner/minidumps/2a57efc1-3da7-94ce-2f5f-f0540a33961e.dmp [task 2018-01-11T13:42:48.606Z] 13:42:48 INFO - Operating system: Linux [task 2018-01-11T13:42:48.607Z] 13:42:48 INFO - 0.0.0 Linux 4.4.0-98-generic #121~14.04.1-Ubuntu SMP Wed Oct 11 11:54:55 UTC 2017 x86_64 [task 2018-01-11T13:42:48.607Z] 13:42:48 INFO - CPU: amd64 [task 2018-01-11T13:42:48.608Z] 13:42:48 INFO - family 6 model 62 stepping 4 [task 2018-01-11T13:42:48.608Z] 13:42:48 INFO - 2 CPUs [task 2018-01-11T13:42:48.609Z] 13:42:48 INFO - [task 2018-01-11T13:42:48.610Z] 13:42:48 INFO - GPU: UNKNOWN [task 2018-01-11T13:42:48.611Z] 13:42:48 INFO - [task 2018-01-11T13:42:48.612Z] 13:42:48 INFO - Crash reason: SIGABRT [task 2018-01-11T13:42:48.612Z] 13:42:48 INFO - Crash address: 0x3e8000003e1 [task 2018-01-11T13:42:48.612Z] 13:42:48 INFO - Process uptime: not available [task 2018-01-11T13:42:48.614Z] 13:42:48 INFO - [task 2018-01-11T13:42:48.615Z] 13:42:48 INFO - Thread 0 (crashed) [task 2018-01-11T13:42:48.615Z] 13:42:48 INFO - 0 libc-2.23.so + 0xfb70d

Comment hidden (Intermittent Failures Robot)

Firefox Bug Husbandry Bot

Comment 2

•

7 years ago

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: NEW → RESOLVED

Closed: 7 years ago

Resolution: --- → INCOMPLETE

Nobody; OK to take it and work on it

Assignee

Updated

•

6 years ago

Component: DOM → DOM: Core & HTML

You need to log in before you can comment on or make changes to this bug.

Bugzilla

Intermittent TEST-UNEXPECTED-TIMEOUT | dom/base/test/test_bug597345.html | application timed out after 370 seconds with no output

Categories

(Core :: DOM: Core & HTML, defect, P5)

Tracking

()

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Crash Data

Security

(public)

User Story

Description

Comment 1

Comment 2

Updated