Closed
Bug 1883817
Opened 2 years ago
Closed 2 years ago
Intermittent browser/components/extensions/test/browser/browser_ext_tabs_update_url.js | single tracking bug
Categories
(WebExtensions :: Untriaged, defect, P5)
WebExtensions
Untriaged
Tracking
(Not tracked)
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure, intermittent-testcase)
Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=449696190&repo=mozilla-beta
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/NcngeZK0R1a7wwt-beTRKA/runs/0/artifacts/public/logs/live_backing.log
[task 2024-03-06T02:06:59.672Z] 02:06:59 INFO - TEST-PASS | browser/components/extensions/test/browser/browser_ext_tabs_update_url.js | Tab history contains the expected entry: https://example.com/2/. -
[task 2024-03-06T02:06:59.673Z] 02:06:59 INFO - Buffered messages logged at 02:05:10
[task 2024-03-06T02:06:59.673Z] 02:06:59 INFO - Console message: [JavaScript Error: "Timeout while waiting for extension child to shutdown: "Generated extension" (ID: {8d3802f8-0309-45c1-8872-10a6847e9f71}, moz-extension://8a452233-c111-41df-a726-cbaa685a2230/)" {file: "resource://gre/modules/Extension.sys.mjs" line: 3812}]
[task 2024-03-06T02:06:59.674Z] 02:06:59 INFO - shutdown@resource://gre/modules/Extension.sys.mjs:3812:10
[task 2024-03-06T02:06:59.675Z] 02:06:59 INFO - async*receiveMessage@resource://testing-common/SpecialPowersParent.sys.mjs:1344:28
[task 2024-03-06T02:06:59.675Z] 02:06:59 INFO - JSActor query*unload@resource://testing-common/SpecialPowersChild.sys.mjs:2018:19
[task 2024-03-06T02:06:59.676Z] 02:06:59 INFO - test_update_reload@chrome://mochitests/content/browser/browser/components/extensions/test/browser/browser_ext_tabs_update_url.js:233:19
[task 2024-03-06T02:06:59.677Z] 02:06:59 INFO - async*handleTask@chrome://mochikit/content/browser-test.js:1139:26
[task 2024-03-06T02:06:59.677Z] 02:06:59 INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1211:18
[task 2024-03-06T02:06:59.678Z] 02:06:59 INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1353:14
[task 2024-03-06T02:06:59.678Z] 02:06:59 INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1128:14
[task 2024-03-06T02:06:59.679Z] 02:06:59 INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13
[task 2024-03-06T02:06:59.680Z] 02:06:59 INFO -
[task 2024-03-06T02:06:59.680Z] 02:06:59 INFO - Buffered messages finished
[task 2024-03-06T02:06:59.681Z] 02:06:59 INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_tabs_update_url.js | Test timed out -
[task 2024-03-06T02:06:59.681Z] 02:06:59 INFO - GECKO(9928) | Completed ShutdownLeaks collections in process 9928
[task 2024-03-06T02:06:59.682Z] 02:06:59 INFO - TEST-START | Shutdown
[task 2024-03-06T02:06:59.683Z] 02:06:59 INFO - Browser Chrome Test Summary
[task 2024-03-06T02:06:59.683Z] 02:06:59 INFO - Passed: 10308
[task 2024-03-06T02:06:59.684Z] 02:06:59 INFO - Failed: 1
[task 2024-03-06T02:06:59.684Z] 02:06:59 INFO - Todo: 0
[task 2024-03-06T02:06:59.685Z] 02:06:59 INFO - Mode: e10s
[task 2024-03-06T02:06:59.686Z] 02:06:59 INFO - *** End BrowserChrome Test Results ***
[task 2024-03-06T02:06:59.686Z] 02:06:59 INFO - GECKO(9928) | console.error: (new TypeError("URL constructor: null is not a valid URL.", "resource:///modules/ProcessHangMonitor.sys.mjs", 364))
[task 2024-03-06T02:06:59.902Z] 02:06:59 INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-03-06T02:06:59.902Z] 02:06:59 INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_tabs_update_url.js | Uncaught exception received from previously timed out test bound test_update_reload - AbortError: Actor 'SpecialPowers' destroyed before query 'SPUnloadExtension' was resolved
[task 2024-03-06T02:06:59.902Z] 02:06:59 INFO - Stack trace:
[task 2024-03-06T02:06:59.902Z] 02:06:59 INFO - unregisterActor@resource://testing-common/SpecialPowersParent.sys.mjs:200:17
[task 2024-03-06T02:06:59.902Z] 02:06:59 INFO - onShutdown@jar:file:///tmp/tmp6_mj2j6b.mozrunner/extensions/special-powers@mozilla.org.xpi!/api.js:64:25
[task 2024-03-06T02:06:59.902Z] 02:06:59 INFO - ExtensionAPI/<@resource://gre/modules/ExtensionCommon.sys.mjs:364:14
[task 2024-03-06T02:06:59.902Z] 02:06:59 INFO - wrapper@resource://gre/modules/ExtensionCommon.sys.mjs:308:14
[task 2024-03-06T02:06:59.902Z] 02:06:59 INFO - emit@resource://gre/modules/ExtensionCommon.sys.mjs:335:32
[task 2024-03-06T02:06:59.902Z] 02:06:59 INFO - emit@resource://gre/modules/Extension.sys.mjs:3028:25
[task 2024-03-06T02:06:59.902Z] 02:06:59 INFO - shutdown@resource://gre/modules/Extension.sys.mjs:3801:10
[task 2024-03-06T02:06:59.902Z] 02:06:59 INFO - shutdown@resource://gre/modules/Extension.sys.mjs:2725:39
[task 2024-03-06T02:06:59.902Z] 02:06:59 INFO - callBootstrapMethod@resource://gre/modules/addons/XPIProvider.sys.mjs:1850:33
[task 2024-03-06T02:06:59.902Z] 02:06:59 INFO - _shutdown@resource://gre/modules/addons/XPIProvider.sys.mjs:1998:17
[task 2024-03-06T02:06:59.903Z] 02:06:59 INFO - async*shutdown@resource://gre/modules/addons/XPIProvider.sys.mjs:1991:33
[task 2024-03-06T02:06:59.903Z] 02:06:59 INFO - startup/<@resource://gre/modules/addons/XPIProvider.sys.mjs:2664:33
[task 2024-03-06T02:06:59.903Z] 02:06:59 INFO - async*trigger@resource://gre/modules/AsyncShutdown.sys.mjs:755:23
[task 2024-03-06T02:06:59.903Z] 02:06:59 INFO - _wait@resource://gre/modules/AsyncShutdown.sys.mjs:915:15
[task 2024-03-06T02:06:59.903Z] 02:06:59 INFO - wait@resource://gre/modules/AsyncShutdown.sys.mjs:896:34
[task 2024-03-06T02:06:59.903Z] 02:06:59 INFO - observe@resource://gre/modules/AsyncShutdown.sys.mjs:555:10
[task 2024-03-06T02:06:59.903Z] 02:06:59 INFO - testsFinished@chrome://mochikit/content/browser-harness.xhtml:340:28
[task 2024-03-06T02:06:59.903Z] 02:06:59 INFO - Tester_finish@chrome://mochikit/content/browser-test.js:556:10
[task 2024-03-06T02:06:59.903Z] 02:06:59 INFO - timeoutFn@chrome://mochikit/content/browser-test.js:1448:20
[task 2024-03-06T02:06:59.903Z] 02:06:59 INFO - setTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1387:45
[task 2024-03-06T02:06:59.903Z] 02:06:59 INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1128:14
[task 2024-03-06T02:06:59.903Z] 02:06:59 INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13
[task 2024-03-06T02:07:00.184Z] 02:07:00 INFO - GECKO(9928) | 1709690820183 Marionette TRACE Received observer notification quit-application
[task 2024-03-06T02:07:00.185Z] 02:07:00 INFO - GECKO(9928) | 1709690820184 Marionette INFO Stopped listening on port 2828
[task 2024-03-06T02:07:00.198Z] 02:07:00 INFO - GECKO(9928) | 1709690820197 Marionette DEBUG Marionette stopped listening
[task 2024-03-06T02:07:15.187Z] 02:07:15 INFO - GECKO(9928) | console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.sys.mjs", 370))
[task 2024-03-06T02:07:17.980Z] 02:07:17 INFO - GECKO(9928) | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"ContentParent: id=7b6400b9c300","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3890,"stack":""}] Barrier: profile-before-change
[task 2024-03-06T02:13:08.982Z] 02:13:08 INFO - GECKO(9928) | FATAL ERROR: AsyncShutdown timeout in profile-before-change Conditions: [{"name":"ContentParent: id=7b6400b9c300","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3890,"stack":""}] At least one completion condition failed to complete within a reasonable amount of time. Causing a crash to ensure that we do not leave the user with an unresponsive process draining resources.
[task 2024-03-06T02:13:08.982Z] 02:13:08 INFO - GECKO(9928) | WARNING: No crash reporter available
[task 2024-03-06T02:13:08.982Z] 02:13:08 INFO - GECKO(9928) | [Parent 9928, Main Thread] ###!!! ABORT: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3890
[task 2024-03-06T02:13:08.983Z] 02:13:08 INFO - GECKO(9928) | ThreadSanitizer:DEADLYSIGNAL
[task 2024-03-06T02:13:08.984Z] 02:13:08 INFO - GECKO(9928) | ==9928==ERROR: ThreadSanitizer: SEGV on unknown address 0x000000000000 (pc 0x7f6350b17df4 bp 0x7ffea9c26a48 sp 0x7ffea9c26a40 T9928)
[task 2024-03-06T02:13:08.984Z] 02:13:08 INFO - GECKO(9928) | ==9928==The signal is caused by a WRITE memory access.
[task 2024-03-06T02:13:08.984Z] 02:13:08 INFO - GECKO(9928) | ==9928==Hint: address points to the zero page.
[task 2024-03-06T02:13:09.037Z] 02:13:09 INFO - GECKO(9928) | #0 MOZ_Crash /builds/worker/workspace/obj-build/dist/include/mozilla/Assertions.h:301:3 (libxul.so+0x2fc4df4) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.037Z] 02:13:09 INFO - GECKO(9928) | #1 Abort /builds/worker/checkouts/gecko/xpcom/base/nsDebugImpl.cpp:513:3 (libxul.so+0x2fc4df4)
[task 2024-03-06T02:13:09.037Z] 02:13:09 INFO - GECKO(9928) | #2 NS_DebugBreak /builds/worker/checkouts/gecko/xpcom/base/nsDebugImpl.cpp:470:7 (libxul.so+0x2fc4df4)
[task 2024-03-06T02:13:09.037Z] 02:13:09 INFO - GECKO(9928) | #3 nsDebugImpl::Abort(char const*, int) /builds/worker/checkouts/gecko/xpcom/base/nsDebugImpl.cpp:129:3 (libxul.so+0x2fc4f56) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.038Z] 02:13:09 INFO - GECKO(9928) | #4 NS_InvokeByIndex /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcinvoke_asm_x86_64_unix.S:101 (libxul.so+0x311eb95) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.038Z] 02:13:09 INFO - GECKO(9928) | #5 Invoke /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNative.cpp:1621:10 (libxul.so+0x3d61a6c) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.038Z] 02:13:09 INFO - GECKO(9928) | #6 Call /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNative.cpp:1174:19 (libxul.so+0x3d61a6c)
[task 2024-03-06T02:13:09.038Z] 02:13:09 INFO - GECKO(9928) | #7 XPCWrappedNative::CallMethod(XPCCallContext&, XPCWrappedNative::CallMode) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNative.cpp:1120:23 (libxul.so+0x3d61a6c)
[task 2024-03-06T02:13:09.038Z] 02:13:09 INFO - GECKO(9928) | #8 XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNativeJSOps.cpp:966:10 (libxul.so+0x3d63e52) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.038Z] 02:13:09 INFO - GECKO(9928) | #9 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:480:13 (libxul.so+0x979e4fd) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.039Z] 02:13:09 INFO - GECKO(9928) | #10 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:574:12 (libxul.so+0x979e4fd)
[task 2024-03-06T02:13:09.039Z] 02:13:09 INFO - GECKO(9928) | #11 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:641:10 (libxul.so+0x97aed5c) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.040Z] 02:13:09 INFO - GECKO(9928) | #12 CallFromStack /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:646:10 (libxul.so+0x97aed5c)
[task 2024-03-06T02:13:09.041Z] 02:13:09 INFO - GECKO(9928) | #13 js::Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:3061:16 (libxul.so+0x97aed5c)
[task 2024-03-06T02:13:09.042Z] 02:13:09 INFO - GECKO(9928) | #14 MaybeEnterInterpreterTrampoline /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:394:10 (libxul.so+0x979ddc0) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.042Z] 02:13:09 INFO - GECKO(9928) | #15 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:452:13 (libxul.so+0x979ddc0)
[task 2024-03-06T02:13:09.043Z] 02:13:09 INFO - GECKO(9928) | #16 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:606:13 (libxul.so+0x979e572) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.044Z] 02:13:09 INFO - GECKO(9928) | #17 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:641:10 (libxul.so+0x979f037) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.045Z] 02:13:09 INFO - GECKO(9928) | #18 js::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:673:8 (libxul.so+0x979f037)
[task 2024-03-06T02:13:09.045Z] 02:13:09 INFO - GECKO(9928) | #19 Call /builds/worker/checkouts/gecko/js/src/vm/Interpreter.h:116:10 (libxul.so+0x99ad301) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.046Z] 02:13:09 INFO - GECKO(9928) | #20 PromiseReactionJob(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/src/builtin/Promise.cpp:2256:10 (libxul.so+0x99ad301)
[task 2024-03-06T02:13:09.046Z] 02:13:09 INFO - GECKO(9928) | #21 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:480:13 (libxul.so+0x979e4fd) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.047Z] 02:13:09 INFO - GECKO(9928) | #22 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:574:12 (libxul.so+0x979e4fd)
[task 2024-03-06T02:13:09.048Z] 02:13:09 INFO - GECKO(9928) | #23 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:641:10 (libxul.so+0x979f037) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.048Z] 02:13:09 INFO - GECKO(9928) | #24 js::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:673:8 (libxul.so+0x979f037)
[task 2024-03-06T02:13:09.049Z] 02:13:09 INFO - GECKO(9928) | #25 JS::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/CallAndConstruct.cpp:119:10 (libxul.so+0x9865e92) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.049Z] 02:13:09 INFO - GECKO(9928) | #26 mozilla::dom::PromiseJobCallback::Call(mozilla::dom::BindingCallContext&, JS::Handle<JS::Value>, mozilla::ErrorResult&) /builds/worker/workspace/obj-build/dom/bindings/./PromiseBinding.cpp:83:8 (libxul.so+0x4f06305) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.050Z] 02:13:09 INFO - GECKO(9928) | #27 Call /builds/worker/workspace/obj-build/dist/include/mozilla/dom/PromiseBinding.h:198:12 (libxul.so+0x2ff97a7) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.050Z] 02:13:09 INFO - GECKO(9928) | #28 Call /builds/worker/workspace/obj-build/dist/include/mozilla/dom/PromiseBinding.h:211:12 (libxul.so+0x2ff97a7)
[task 2024-03-06T02:13:09.051Z] 02:13:09 INFO - GECKO(9928) | #29 mozilla::PromiseJobRunnable::Run(mozilla::AutoSlowOperation&) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:210:18 (libxul.so+0x2ff97a7)
[task 2024-03-06T02:13:09.051Z] 02:13:09 INFO - GECKO(9928) | #30 mozilla::CycleCollectedJSContext::PerformMicroTaskCheckPoint(bool) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:712:17 (libxul.so+0x2fe6de7) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.052Z] 02:13:09 INFO - GECKO(9928) | #31 mozilla::CycleCollectedJSContext::AfterProcessTask(unsigned int) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:499:3 (libxul.so+0x2fe76c7) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.053Z] 02:13:09 INFO - GECKO(9928) | #32 XPCJSContext::AfterProcessTask(unsigned int) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCJSContext.cpp:1439:28 (libxul.so+0x3d2861c) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.053Z] 02:13:09 INFO - GECKO(9928) | #33 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1237:24 (libxul.so+0x30f4d16) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.054Z] 02:13:09 INFO - GECKO(9928) | #34 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:480:10 (libxul.so+0x30fac74) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.055Z] 02:13:09 INFO - GECKO(9928) | #35 SpinEventLoopUntil<(mozilla::ProcessFailureBehavior)1, (lambda at /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:691:61)> /builds/worker/workspace/obj-build/dist/include/mozilla/SpinEventLoopUntil.h:176:25 (libxul.so+0x30fa450) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.056Z] 02:13:09 INFO - GECKO(9928) | #36 nsThreadManager::SpinEventLoopUntilInternal(nsTSubstring<char> const&, nsINestedEventLoopCondition*, mozilla::ShutdownPhase) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:691:8 (libxul.so+0x30fa450)
[task 2024-03-06T02:13:09.056Z] 02:13:09 INFO - GECKO(9928) | #37 nsThreadManager::SpinEventLoopUntil(nsTSubstring<char> const&, nsINestedEventLoopCondition*) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:652:10 (libxul.so+0x30fa070) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.057Z] 02:13:09 INFO - GECKO(9928) | #38 NS_InvokeByIndex /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcinvoke_asm_x86_64_unix.S:101 (libxul.so+0x311eb95) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.058Z] 02:13:09 INFO - GECKO(9928) | #39 Invoke /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNative.cpp:1621:10 (libxul.so+0x3d61a6c) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.058Z] 02:13:09 INFO - GECKO(9928) | #40 Call /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNative.cpp:1174:19 (libxul.so+0x3d61a6c)
[task 2024-03-06T02:13:09.059Z] 02:13:09 INFO - GECKO(9928) | #41 XPCWrappedNative::CallMethod(XPCCallContext&, XPCWrappedNative::CallMode) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNative.cpp:1120:23 (libxul.so+0x3d61a6c)
[task 2024-03-06T02:13:09.060Z] 02:13:09 INFO - GECKO(9928) | #42 XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNativeJSOps.cpp:966:10 (libxul.so+0x3d63e52) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.060Z] 02:13:09 INFO - GECKO(9928) | #43 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:480:13 (libxul.so+0x979e4fd) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.061Z] 02:13:09 INFO - GECKO(9928) | #44 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:574:12 (libxul.so+0x979e4fd)
[task 2024-03-06T02:13:09.062Z] 02:13:09 INFO - GECKO(9928) | #45 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:641:10 (libxul.so+0x97aed5c) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.062Z] 02:13:09 INFO - GECKO(9928) | #46 CallFromStack /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:646:10 (libxul.so+0x97aed5c)
[task 2024-03-06T02:13:09.063Z] 02:13:09 INFO - GECKO(9928) | #47 js::Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:3061:16 (libxul.so+0x97aed5c)
[task 2024-03-06T02:13:09.064Z] 02:13:09 INFO - GECKO(9928) | #48 MaybeEnterInterpreterTrampoline /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:394:10 (libxul.so+0x979ddc0) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.065Z] 02:13:09 INFO - GECKO(9928) | #49 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:452:13 (libxul.so+0x979ddc0)
[task 2024-03-06T02:13:09.065Z] 02:13:09 INFO - GECKO(9928) | #50 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:606:13 (libxul.so+0x979e572) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.066Z] 02:13:09 INFO - GECKO(9928) | #51 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:641:10 (libxul.so+0x979f037) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.067Z] 02:13:09 INFO - GECKO(9928) | #52 js::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:673:8 (libxul.so+0x979f037)
[task 2024-03-06T02:13:09.067Z] 02:13:09 INFO - GECKO(9928) | #53 JS_CallFunctionValue(JSContext*, JS::Handle<JSObject*>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/CallAndConstruct.cpp:55:10 (libxul.so+0x98651bf) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.068Z] 02:13:09 INFO - GECKO(9928) | #54 nsXPCWrappedJS::CallMethod(unsigned short, nsXPTMethodInfo const*, nsXPTCMiniVariant*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedJSClass.cpp:918:17 (libxul.so+0x3d59bc8) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.069Z] 02:13:09 INFO - GECKO(9928) | #55 PrepareAndDispatch /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcstubs_x86_64_linux.cpp:115:37 (libxul.so+0x311fb74) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.069Z] 02:13:09 INFO - GECKO(9928) | #56 SharedStub xptcstubs_x86_64_linux.cpp (libxul.so+0x311ee92) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.070Z] 02:13:09 INFO - GECKO(9928) | #57 NotifyObservers /builds/worker/checkouts/gecko/xpcom/ds/nsObserverList.cpp:71:19 (libxul.so+0x3056b95) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.071Z] 02:13:09 INFO - GECKO(9928) | #58 nsObserverService::NotifyObservers(nsISupports*, char const*, char16_t const*) /builds/worker/checkouts/gecko/xpcom/ds/nsObserverService.cpp:288:19 (libxul.so+0x3056b95)
[task 2024-03-06T02:13:09.071Z] 02:13:09 INFO - GECKO(9928) | #59 mozilla::AppShutdown::AdvanceShutdownPhaseInternal(mozilla::ShutdownPhase, bool, char16_t const*, nsCOMPtr<nsISupports> const&) /builds/worker/checkouts/gecko/xpcom/base/AppShutdown.cpp:433:21 (libxul.so+0x2fd07bc) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.071Z] 02:13:09 INFO - GECKO(9928) | #60 mozilla::AppShutdown::AdvanceShutdownPhase(mozilla::ShutdownPhase, char16_t const*, nsCOMPtr<nsISupports> const&) /builds/worker/checkouts/gecko/xpcom/base/AppShutdown.cpp:456:3 (libxul.so+0x2fd0a87) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.071Z] 02:13:09 INFO - GECKO(9928) | #61 nsXREDirProvider::DoShutdown() /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:879:5 (libxul.so+0x9637d41) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.071Z] 02:13:09 INFO - GECKO(9928) | #62 ScopedXPCOMStartup::~ScopedXPCOMStartup() /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:1966:26 (libxul.so+0x961db58) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.071Z] 02:13:09 INFO - GECKO(9928) | #63 operator() /builds/worker/workspace/obj-build/dist/include/mozilla/UniquePtr.h:460:5 (libxul.so+0x9629e9f) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.071Z] 02:13:09 INFO - GECKO(9928) | #64 reset /builds/worker/workspace/obj-build/dist/include/mozilla/UniquePtr.h:302:7 (libxul.so+0x9629e9f)
[task 2024-03-06T02:13:09.071Z] 02:13:09 INFO - GECKO(9928) | #65 mozilla::UniquePtr<ScopedXPCOMStartup, mozilla::DefaultDelete<ScopedXPCOMStartup>>::operator=(std::nullptr_t) /builds/worker/workspace/obj-build/dist/include/mozilla/UniquePtr.h:272:5 (libxul.so+0x9629e9f)
[task 2024-03-06T02:13:09.071Z] 02:13:09 INFO - GECKO(9928) | #66 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5961:16 (libxul.so+0x9629acd) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.071Z] 02:13:09 INFO - GECKO(9928) | #67 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5997:21 (libxul.so+0x962a181) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.071Z] 02:13:09 INFO - GECKO(9928) | #68 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:45:12 (libxul.so+0x9638de2) (BuildId: 38cf2d3d5224ad15910b783657cf7cea1acd67c1)
[task 2024-03-06T02:13:09.071Z] 02:13:09 INFO - GECKO(9928) | #69 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:227:22 (firefox-bin+0x14e740) (BuildId: 7b7343e18cd4f48ab2919eb88dae7b85b5ef0307)
[task 2024-03-06T02:13:09.071Z] 02:13:09 INFO - GECKO(9928) | #70 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:445:16 (firefox-bin+0x14e740)
[task 2024-03-06T02:13:09.071Z] 02:13:09 INFO - GECKO(9928) | #71 __libc_start_main /tmp/glibc/csu/../csu/libc-start.c:310 (libc.so.6+0x21b96) (BuildId: 3247775c25514939b9a5ffa9d3be63f3a4cb9641)
[task 2024-03-06T02:13:09.071Z] 02:13:09 INFO - GECKO(9928) | #72 _start <null> (firefox-bin+0x95728) (BuildId: 7b7343e18cd4f48ab2919eb88dae7b85b5ef0307)
[task 2024-03-06T02:13:09.071Z] 02:13:09 INFO - GECKO(9928) | ThreadSanitizer can not provide additional info.
[task 2024-03-06T02:13:09.071Z] 02:13:09 INFO - GECKO(9928) | SUMMARY: ThreadSanitizer: SEGV /builds/worker/workspace/obj-build/dist/include/mozilla/Assertions.h:301:3 in MOZ_Crash
[task 2024-03-06T02:13:09.071Z] 02:13:09 INFO - GECKO(9928) | ==9928==ABORTING
[task 2024-03-06T02:19:19.056Z] 02:19:19 INFO - TEST-UNEXPECTED-TIMEOUT | browser/components/extensions/test/browser/browser_ext_tabs_update_url.js | application timed out after 370 seconds with no output
[task 2024-03-06T02:19:19.056Z] 02:19:19 INFO - TEST-INFO took 865417ms
[task 2024-03-06T02:19:19.056Z] 02:19:19 INFO - Buffered messages finished
[task 2024-03-06T02:19:19.057Z] 02:19:19 WARNING - Force-terminating active process(es).
[task 2024-03-06T02:19:19.057Z] 02:19:19 INFO - Determining child pids from psutil...
[task 2024-03-06T02:19:19.070Z] 02:19:19 INFO - []
[task 2024-03-06T02:19:19.070Z] 02:19:19 INFO - ==> process 9928 launched child process 10002
[task 2024-03-06T02:19:19.071Z] 02:19:19 INFO - ==> process 9928 launched child process 10032
<...>
[task 2024-03-06T02:20:49.580Z] 02:20:49 INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-03-06T02:20:49.580Z] 02:20:49 INFO - Can't trigger Breakpad, just killing process
[task 2024-03-06T02:20:49.585Z] 02:20:49 INFO - psutil found pid 9928 dead
[task 2024-03-06T02:20:49.586Z] 02:20:49 INFO - psutil found pid 9928 dead
[task 2024-03-06T02:37:29.586Z] 02:37:29 INFO - Automation Error: mozharness timed out after 1000 seconds running ['/builds/worker/workspace/build/venv/bin/python', '-u', '/builds/worker/workspace/build/tests/mochitest/runtests.py', 'browser/base/content/test/protectionsUI/browser.toml', 'browser/components/extensions/test/browser/browser.toml', 'browser/components/translations/tests/browser/browser.toml', 'dom/fetch/tests/browser.toml', 'image/test/browser/browser_sandbox_headless.toml', 'toolkit/components/enterprisepolicies/tests/browser/browser.toml', 'toolkit/components/windowwatcher/test/browser.toml', '--setpref=layout.css.stylo-threads=4', '--setpref=gfx.webrender.software=true', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--setpref=layers.d3d11.enable-blacklist=false', '--setpref=javascript.options.mem.gc_parallel_marking=true', '--appname=/builds/worker/workspace/build/application/firefox/firefox', '--utility-path=tests/bin', '--extra-profile-file=tests/bin/plugins', '--symbols-path=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/OTtkHM0FRY6Ohdjm4jzeUw/artifacts/public/build/target.crashreporter-symbols.zip', '--certificate-path=tests/certs', '--setpref=webgl.force-enabled=true', '--quiet', '--log-errorsummary=/builds/worker/workspace/build/blobber_upload_dir/mochitest-browser-chrome_errorsummary.log', '--use-test-media-devices', '--screenshot-on-fail', '--cleanup-crashes', '--marionette-startup-timeout=180', '--sandbox-read-whitelist=/builds/worker/workspace/build', '--log-raw=-', '--flavor=browser', '--chunk-by-runtime']
[task 2024-03-06T02:37:29.589Z] 02:37:29 INFO - Return code: -9
[task 2024-03-06T02:37:29.589Z] 02:37:29 WARNING - Got 3 unexpected statuses
[task 2024-03-06T02:37:29.589Z] 02:37:29 ERROR - No suite end message was emitted by this harness.
[task 2024-03-06T02:37:29.590Z] 02:37:29 INFO - TinderboxPrint: mochitest-mochitest-browser-chrome<br/>573/<em class="testfail">3</em>/0
[task 2024-03-06T02:37:29.590Z] 02:37:29 WARNING - setting return code to 2
[task 2024-03-06T02:37:29.591Z] 02:37:29 INFO - The mochitest suite: mochitest-browser-chrome ran with return status: FAILURE
[task 2024-03-06T02:37:29.591Z] 02:37:29 INFO - Running post-action listener: _package_coverage_data
[task 2024-03-06T02:37:29.591Z] 02:37:29 INFO - Running post-action listener: _resource_record_post_action
[task 2024-03-06T02:37:29.592Z] 02:37:29 INFO - Running post-action listener: process_java_coverage_data
[task 2024-03-06T02:37:29.592Z] 02:37:29 INFO - [mozharness: 2024-03-06 02:37:29.590173Z] Finished run-tests step (success)
[task 2024-03-06T02:37:29.593Z] 02:37:29 INFO - [mozharness: 2024-03-06 02:37:29.590282Z] Running uninstall step.
[task 2024-03-06T02:37:29.593Z] 02:37:29 INFO - Running pre-action listener: _resource_record_pre_action
[task 2024-03-06T02:37:29.594Z] 02:37:29 INFO - Running main action method: uninstall
[task 2024-03-06T02:37:29.594Z] 02:37:29 INFO - Skipping uninstall for non-MSIX test
[task 2024-03-06T02:37:29.594Z] 02:37:29 INFO - Running post-action listener: _resource_record_post_action
[task 2024-03-06T02:37:29.595Z] 02:37:29 INFO - [mozharness: 2024-03-06 02:37:29.590638Z] Finished uninstall step (success)
[task 2024-03-06T02:37:29.595Z] 02:37:29 INFO - Running post-run listener: _resource_record_post_run
[task 2024-03-06T02:37:31.191Z] 02:37:31 INFO - Validating Perfherder data against /builds/worker/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2024-03-06T02:37:31.194Z] 02:37:31 INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "mochitest.mochitest-browser-chrome.overall", "extraOptions": ["e10s", "taskcluster-projects/887720501152/machineTypes/n2-standard-4"], "subtests": [{"name": "cpu_percent", "value": 42.010377543037286}, {"name": "io_write_bytes", "value": 3882516480}, {"name": "io.read_bytes", "value": 24526848}, {"name": "io_write_time", "value": 2450540}, {"name": "io_read_time", "value": 188}]}, {"name": "mochitest.mochitest-browser-chrome.start-pulseaudio", "subtests": [{"name": "time", "value": 0.01737887499984936}, {"name": "cpu_percent", "value": 0}]}, {"name": "mochitest.mochitest-browser-chrome.install", "subtests": [{"name": "time", "value": 38.18811946200003}, {"name": "cpu_percent", "value": 25.433398950131235}]}, {"name": "mochitest.mochitest-browser-chrome.stage-files", "subtests": [{"name": "time", "value": 0.0005006490009691333}, {"name": "cpu_percent", "value": 0}]}, {"name": "mochitest.mochitest-browser-chrome.run-tests", "subtests": [{"name": "time", "value": 4306.330680933001}, {"name": "cpu_percent", "value": 42.157519329449414}]}, {"name": "mochitest.mochitest-browser-chrome.uninstall", "subtests": [{"name": "time", "value": 0.00023799599875928834}, {"name": "cpu_percent", "value": 0}]}]}
[task 2024-03-06T02:37:31.195Z] 02:37:31 INFO - Total resource usage - Wall time: 4346s; CPU: Can't collect data; Read bytes: 24526848; Write bytes: 3882516480; Read time: 188; Write time: 2450540
[task 2024-03-06T02:37:31.195Z] 02:37:31 INFO - TinderboxPrint: I/O read bytes / time<br/>24,526,848 / 188
[task 2024-03-06T02:37:31.196Z] 02:37:31 INFO - TinderboxPrint: I/O write bytes / time<br/>3,882,516,480 / 2,450,540
[task 2024-03-06T02:37:31.196Z] 02:37:31 INFO - TinderboxPrint: CPU idle<br/>10,033.5 (57.9%)
[task 2024-03-06T02:37:31.196Z] 02:37:31 INFO - TinderboxPrint: CPU system<br/>1,948.2 (11.2%)
[task 2024-03-06T02:37:31.196Z] 02:37:31 INFO - TinderboxPrint: CPU user<br/>5,322.4 (30.7%)
[task 2024-03-06T02:37:31.196Z] 02:37:31 INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2024-03-06T02:37:31.201Z] 02:37:31 INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-03-06T02:37:31.208Z] 02:37:31 INFO - install - Wall time: 38s; CPU: 25%; Read bytes: 0; Write bytes: 2034896896; Read time: 0; Write time: 1994932
[task 2024-03-06T02:37:31.213Z] 02:37:31 INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-03-06T02:37:31.426Z] 02:37:31 INFO - run-tests - Wall time: 4306s; CPU: 42%; Read bytes: 24526848; Write bytes: 1847619584; Read time: 188; Write time: 455608
[task 2024-03-06T02:37:31.447Z] 02:37:31 INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-03-06T02:37:38.423Z] 02:37:38 WARNING - returning nonzero exit status 2
[task 2024-03-06T02:37:38.730Z] cleanup
[task 2024-03-06T02:37:38.730Z] + cleanup
[task 2024-03-06T02:37:38.730Z] + local rv=2
[task 2024-03-06T02:37:38.731Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2024-03-06T02:37:38.731Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2024-03-06T02:37:38.732Z] + '[' ']'
[task 2024-03-06T02:37:38.732Z] + true
[task 2024-03-06T02:37:38.732Z] + cleanup_xvfb
[task 2024-03-06T02:37:38.732Z] ++ pidof Xvfb
[task 2024-03-06T02:37:38.745Z] + local xvfb_pid=50
[task 2024-03-06T02:37:38.745Z] + local vnc=false
[task 2024-03-06T02:37:38.745Z] + local interactive=false
[task 2024-03-06T02:37:38.745Z] + '[' -n 50 ']'
[task 2024-03-06T02:37:38.745Z] + [[ false == false ]]
[task 2024-03-06T02:37:38.745Z] + [[ false == false ]]
[task 2024-03-06T02:37:38.745Z] + kill 50
[task 2024-03-06T02:37:38.759Z] + screen -XS xvfb quit
[task 2024-03-06T02:37:38.763Z] + exit 2
[taskcluster 2024-03-06 02:37:39.184Z] === Task Finished ===
[taskcluster 2024-03-06 02:37:42.214Z] Unsuccessful task run with exit code: 2 completed in 4443.055 seconds
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•2 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•