Closed Bug 1694327 Opened 4 years ago Closed 4 years ago

Intermittent toolkit/components/extensions/test/xpcshell/test_ext_contentscript_unregister_during_loadContentScript.js | xpcshell return code: 1

Categories

(WebExtensions :: General, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INVALID

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=330861698&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/OBifxs7ARJu2_yboWYmHXA/runs/0/artifacts/public/logs/live_backing.log


...
[task 2021-02-23T06:36:35.044Z] 06:36:35     INFO -  TEST-PASS | toolkit/components/telemetry/tests/unit/test_bug1555798.js | took 915ms
[task 2021-02-23T06:36:35.316Z] 06:36:35     INFO -  TEST-START | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_unregister_during_loadContentScript.js
[taskcluster:error] Aborting task...
[task 2021-02-23T06:40:20.053Z] 06:40:20  WARNING -  TEST-UNEXPECTED-FAIL | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_unregister_during_loadContentScript.js | xpcshell return code: 1
[task 2021-02-23T06:40:20.053Z] 06:40:20     INFO -  TEST-INFO took 224734ms
[task 2021-02-23T06:40:20.053Z] 06:40:20     INFO -  >>>>>>>
[task 2021-02-23T06:40:20.053Z] 06:40:20     INFO -  PID 532 | [Parent 532, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-02-23T06:40:20.053Z] 06:40:20     INFO -  PID 532 | [Parent 532, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-02-23T06:40:20.054Z] 06:40:20     INFO -  PID 532 | [Parent 532, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-02-23T06:40:20.054Z] 06:40:20     INFO -  PID 532 | [Parent 532, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-02-23T06:40:20.054Z] 06:40:20     INFO -  PID 532 | [Parent 532, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-02-23T06:40:20.054Z] 06:40:20     INFO -  PID 532 | [Parent 532, Main Thread] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file /builds/worker/checkouts/gecko/toolkit/crashreporter/nsExceptionHandler.cpp:2896
[task 2021-02-23T06:40:20.054Z] 06:40:20     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2021-02-23T06:40:20.054Z] 06:40:20     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2021-02-23T06:40:20.054Z] 06:40:20     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2021-02-23T06:40:20.054Z] 06:40:20     INFO -  running event loop
[task 2021-02-23T06:40:20.054Z] 06:40:20     INFO -  PID 532 | [Parent 532, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, kKnownEsrVersion) failed with result 0x80004002 (NS_NOINTERFACE): file /builds/worker/checkouts/gecko/toolkit/components/resistfingerprinting/nsRFPService.cpp:553
[task 2021-02-23T06:40:20.054Z] 06:40:20     INFO -  xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_unregister_during_loadContentScript.js | Starting check_remote
[task 2021-02-23T06:40:20.054Z] 06:40:20     INFO -  (xpcshell/head.js) | test check_remote pending (2)
[task 2021-02-23T06:40:20.054Z] 06:40:20     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_unregister_during_loadContentScript.js | check_remote - [check_remote : 1] useRemoteWebExtensions matches - true == true
[task 2021-02-23T06:40:20.054Z] 06:40:20     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_unregister_during_loadContentScript.js | check_remote - [check_remote : 1] testing from extension process - false == false
[task 2021-02-23T06:40:20.055Z] 06:40:20     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2021-02-23T06:40:20.055Z] 06:40:20     INFO -  (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2021-02-23T06:40:20.055Z] 06:40:20     INFO -  (xpcshell/head.js) | test check_remote finished (2)
[task 2021-02-23T06:40:20.055Z] 06:40:20     INFO -  xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_unregister_during_loadContentScript.js | Starting content_script_unregistered_during_loadContentScript
[task 2021-02-23T06:40:20.055Z] 06:40:20     INFO -  (xpcshell/head.js) | test content_script_unregistered_during_loadContentScript pending (2)
[task 2021-02-23T06:40:20.055Z] 06:40:20     INFO -  "Extension attached"
[task 2021-02-23T06:40:20.055Z] 06:40:20     INFO -  (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2021-02-23T06:40:20.055Z] 06:40:20     INFO -  PID 532 | [2021-02-23T06:36:37Z WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2021-02-23T06:40:20.055Z] 06:40:20     INFO -  PID 532 | [Parent 532, Main Thread] WARNING: Couldn't get the user appdata directory, crash dumps will go in an unusual location: file /builds/worker/checkouts/gecko/toolkit/crashreporter/nsExceptionHandler.cpp:2955
[task 2021-02-23T06:40:20.055Z] 06:40:20     INFO -  PID 532 | [Parent 532, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4254
[task 2021-02-23T06:40:20.055Z] 06:40:20     INFO -  PID 532 | DLL blocklist was unable to intercept AppInit DLLs.
[task 2021-02-23T06:40:20.055Z] 06:40:20     INFO -  PID 532 | [3480, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2021-02-23T06:40:20.055Z] 06:40:20     INFO -  PID 532 | [3480, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2021-02-23T06:40:20.056Z] 06:40:20     INFO -  PID 532 | DLL blocklist was unable to intercept AppInit DLLs.
[task 2021-02-23T06:40:20.056Z] 06:40:20     INFO -  PID 532 | [17920, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2021-02-23T06:40:20.056Z] 06:40:20     INFO -  PID 532 | [17920, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2021-02-23T06:40:20.056Z] 06:40:20     INFO -  PID 532 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2021-02-23T06:40:20.056Z] 06:40:20     INFO -  PID 532 | [Child 17920, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:377
[task 2021-02-23T06:40:20.056Z] 06:40:20     INFO -  PID 532 | [Parent 532, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/checkouts/gecko/widget/PuppetWidget.cpp:980
[task 2021-02-23T06:40:20.056Z] 06:40:20     INFO -  PID 532 | [Parent 532, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileWin.cpp:3324
[task 2021-02-23T06:40:20.056Z] 06:40:20     INFO -  PID 532 | [Parent 532, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileWin.cpp:3324
[task 2021-02-23T06:40:20.056Z] 06:40:20     INFO -  PID 532 | [Parent 532, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileWin.cpp:3324
[task 2021-02-23T06:40:20.056Z] 06:40:20     INFO -  PID 532 | [Parent 532, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileWin.cpp:3324
[task 2021-02-23T06:40:20.056Z] 06:40:20     INFO -  PID 532 | [Parent 532, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/checkouts/gecko/dom/media/gmp/GMPServiceParent.cpp:1566
[task 2021-02-23T06:40:20.056Z] 06:40:20     INFO -  PID 532 | [Child 17920, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2697
[task 2021-02-23T06:40:20.056Z] 06:40:20     INFO -  PID 532 | [Child 17920, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, kKnownEsrVersion) failed with result 0x80070057 (NS_ERROR_ILLEGAL_VALUE): file /builds/worker/checkouts/gecko/toolkit/components/resistfingerprinting/nsRFPService.cpp:563
[task 2021-02-23T06:40:20.056Z] 06:40:20     INFO -  "Wait for all the content scripts to be executed"
[task 2021-02-23T06:40:20.057Z] 06:40:20     INFO -  "Reload the webpage"
[task 2021-02-23T06:40:20.057Z] 06:40:20     INFO -  PID 532 | [Child 17920, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/startupcache/StartupCacheUtils.cpp:197
[task 2021-02-23T06:40:20.057Z] 06:40:20     INFO -  PID 532 | Couldn't convert chrome URL: chrome://mochikit/content/tests/SimpleTest/EventUtils.js
[task 2021-02-23T06:40:20.057Z] 06:40:20     INFO -  PID 532 | DLL blocklist was unable to intercept AppInit DLLs.
[task 2021-02-23T06:40:20.057Z] 06:40:20     INFO -  PID 532 | [9872, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2021-02-23T06:40:20.057Z] 06:40:20     INFO -  PID 532 | [9872, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2021-02-23T06:40:20.057Z] 06:40:20     INFO -  PID 532 | [Child 17920, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-02-23T06:40:20.057Z] 06:40:20     INFO -  "Wait for all the content scripts to be executed again"
[task 2021-02-23T06:40:20.057Z] 06:40:20     INFO -  "No crash triggered as expected"
[task 2021-02-23T06:40:20.057Z] 06:40:20     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_unregister_during_loadContentScript.js | content_script_unregistered_during_loadContentScript - [content_script_unregistered_during_loadContentScript : 83] All content scripts unregistered as expected - {"initial":10,"final":0} deepEqual {"initial":10,"final":0}
[task 2021-02-23T06:40:20.057Z] 06:40:20     INFO -  PID 532 | [Child 17920, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-02-23T06:40:20.057Z] 06:40:20     INFO -  (xpcshell/head.js) | test run_next_test 2 pending (2)
[task 2021-02-23T06:40:20.057Z] 06:40:20     INFO -  (xpcshell/head.js) | test content_script_unregistered_during_loadContentScript finished (2)
[task 2021-02-23T06:40:20.058Z] 06:40:20     INFO -  (xpcshell/head.js) | test run_next_test 2 finished (1)
[task 2021-02-23T06:40:20.058Z] 06:40:20     INFO -  exiting test
[task 2021-02-23T06:40:20.058Z] 06:40:20     INFO -  PID 532 | [Parent 532, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:224
[task 2021-02-23T06:40:20.058Z] 06:40:20     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "NetworkError when attempting to fetch resource." {file: "Z:\\task_1614058731\\build\\tests\\xpcshell\\head.js" line: 665}]
[task 2021-02-23T06:40:20.058Z] 06:40:20     INFO -  _execute_test@Z:\\task_1614058731\\build\\tests\\xpcshell\\head.js:665:19
[task 2021-02-23T06:40:20.058Z] 06:40:20     INFO -  @-e:1:1
[task 2021-02-23T06:40:20.058Z] 06:40:20     INFO -  "
[task 2021-02-23T06:40:20.058Z] 06:40:20     INFO -  PID 532 | [Child 9872, RemoteLzyStream] WARNING: '!backgroundActor', file /builds/worker/checkouts/gecko/dom/localstorage/LSObject.cpp:268
[task 2021-02-23T06:40:20.058Z] 06:40:20     INFO -  PID 532 | [Child 9872, RemVidChild] ###!!! ASSERTION: Failed to start Background channel: 'bgActor', file /builds/worker/checkouts/gecko/dom/media/ipc/RemoteDecoderManagerChild.cpp:91
[task 2021-02-23T06:40:20.058Z] 06:40:20     INFO -  PID 532 | [Parent 532, IPDL Background] WARNING: idb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3653
[task 2021-02-23T06:40:20.058Z] 06:40:20     INFO -  PID 532 | [Parent 532, IPDL Background] WARNING: cache: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3653
[task 2021-02-23T06:40:20.058Z] 06:40:20     INFO -  PID 532 | [Parent 532, IPDL Background] WARNING: sdb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3653
[task 2021-02-23T06:40:20.059Z] 06:40:20     INFO -  PID 532 | [Parent 532, IPDL Background] WARNING: ls: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3653
[task 2021-02-23T06:40:20.059Z] 06:40:20     INFO -  PID 532 | [Parent 532, IPDL Background] WARNING: idb: '0.015000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3653
[task 2021-02-23T06:40:20.059Z] 06:40:20     INFO -  PID 532 | [Parent 532, IPDL Background] WARNING: cache: '0.015000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3653
[task 2021-02-23T06:40:20.059Z] 06:40:20     INFO -  PID 532 | [Parent 532, IPDL Background] WARNING: sdb: '0.015000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3653
[task 2021-02-23T06:40:20.059Z] 06:40:20     INFO -  PID 532 | [Parent 532, IPDL Background] WARNING: ls: '0.015000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3653
[task 2021-02-23T06:40:20.059Z] 06:40:20     INFO -  PID 532 | [Child 17920, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3365
[task 2021-02-23T06:40:20.059Z] 06:40:20     INFO -  PID 532 | [Child 17920, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4380
[task 2021-02-23T06:40:20.059Z] 06:40:20     INFO -  Initializing stack-fixing for the first stack frame, this may take a while...
[taskcluster 2021-02-23T06:40:20.401Z] SUCCESS: The process with PID 3480 (child process of PID 532) has been terminated.
[taskcluster 2021-02-23T06:40:20.401Z] SUCCESS: The process with PID 9872 (child process of PID 532) has been terminated.
[taskcluster 2021-02-23T06:40:20.401Z] SUCCESS: The process with PID 6008 (child process of PID 10176) has been terminated.
[taskcluster 2021-02-23T06:40:20.401Z] SUCCESS: The process with PID 532 (child process of PID 10176) has been terminated.
[taskcluster 2021-02-23T06:40:20.401Z] SUCCESS: The process with PID 8108 (child process of PID 10532) has been terminated.
[taskcluster 2021-02-23T06:40:20.401Z] SUCCESS: The process with PID 10176 (child process of PID 10532) has been terminated.
[taskcluster 2021-02-23T06:40:20.401Z] SUCCESS: The process with PID 10532 (child process of PID 11048) has been terminated.
[taskcluster 2021-02-23T06:40:20.401Z] SUCCESS: The process with PID 4764 (child process of PID 11148) has been terminated.
[taskcluster 2021-02-23T06:40:20.401Z] SUCCESS: The process with PID 11048 (child process of PID 11148) has been terminated.
[taskcluster 2021-02-23T06:40:20.401Z] SUCCESS: The process with PID 11148 (child process of PID 10708) has been terminated.
[taskcluster 2021-02-23T06:40:20.401Z] 
[taskcluster 2021-02-23T06:40:20.401Z] === Task Finished ===
[taskcluster 2021-02-23T06:40:20.401Z] Task Duration: 45m5.943851s
[taskcluster 2021-02-23T06:40:20.543Z] Uploading artifact public/logs/localconfig.json from file logs\localconfig.json with content encoding "gzip", mime type "application/octet-stream" and expiry 2022-02-23T05:52:38.752Z
[taskcluster 2021-02-23T06:40:20.683Z] Uploading artifact public/test_info/13427132-7ee2-4249-9023-a81f1c4f6563.dmp from file build\blobber_upload_dir\13427132-7ee2-4249-9023-a81f1c4f6563.dmp with content encoding "gzip", mime type "application/octet-stream" and expiry 2022-02-23T05:52:38.752Z
[taskcluster 2021-02-23T06:40:20.861Z] Uploading artifact public/test_info/13427132-7ee2-4249-9023-a81f1c4f6563.extra from file build\blobber_upload_dir\13427132-7ee2-4249-9023-a81f1c4f6563.extra with content encoding "gzip", mime type "application/octet-stream" and expiry 2022-02-23T05:52:38.752Z
[taskcluster 2021-02-23T06:40:20.931Z] Uploading artifact public/test_info/system-info.log from file build\blobber_upload_dir\system-info.log with content encoding "gzip", mime type "text/plain" and expiry 2022-02-23T05:52:38.752Z
[taskcluster 2021-02-23T06:40:20.997Z] Uploading artifact public/test_info/xpcshell_errorsummary.log from file build\blobber_upload_dir\xpcshell_errorsummary.log with content encoding "gzip", mime type "text/plain" and expiry 2022-02-23T05:52:38.752Z
[taskcluster 2021-02-23T06:40:21.085Z] Uploading artifact public/test_info/xpcshell_raw.log from file build\blobber_upload_dir\xpcshell_raw.log with content encoding "gzip", mime type "text/plain" and expiry 2022-02-23T05:52:38.752Z
[taskcluster 2021-02-23T06:40:21.189Z] Uploading redirect artifact public/logs/live.log to URL https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/OBifxs7ARJu2_yboWYmHXA/runs/0/artifacts/public/logs/live_backing.log with mime type "text/plain; charset=utf-8" and expiry 2022-02-23T05:52:38.752Z
[taskcluster:error] Task aborted - max run time exceeded```

Test failure while task got terminated for hitting the run time limit.

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INVALID
You need to log in before you can comment on or make changes to this bug.