1565905 - Intermittent TEST-UNEXPECTED-TIMEOUT | toolkit/components/extensions/test/xpcshell/test_ext_background_api_injection.js | Test timed out

Status: RESOLVED INCOMPLETE

(WebExtensions :: General, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: aiakab [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=256415926&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/VowXgXR7Rf-00QgnrDiq0w/runs/0/artifacts/public/logs/live_backing.log

---

[task 2019-07-14T11:21:08.896Z] 11:21:08 INFO - TEST-START | xpcshell-e10s.ini:toolkit/components/extensions/test/xpcshell/test_ext_contexts_gc.js
[task 2019-07-14T11:21:18.416Z] 11:21:18 INFO - TEST-PASS | xpcshell-e10s.ini:toolkit/components/extensions/test/xpcshell/test_ext_contexts_gc.js | took 9523ms
[task 2019-07-14T11:21:18.432Z] 11:21:18 INFO - TEST-START | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_api_permissions.js
[task 2019-07-14T11:21:24.353Z] 11:21:24 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_api_permissions.js | took 5922ms
[task 2019-07-14T11:21:24.356Z] 11:21:24 INFO - TEST-START | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_background_api_injection.js
[task 2019-07-14T11:26:24.364Z] 11:26:24 WARNING - TEST-UNEXPECTED-TIMEOUT | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_background_api_injection.js | Test timed out
[task 2019-07-14T11:26:24.364Z] 11:26:24 INFO - TEST-INFO took 300001ms
[task 2019-07-14T11:26:24.364Z] 11:26:24 INFO - >>>>>>>
[task 2019-07-14T11:26:24.364Z] 11:26:24 INFO - PID 2717 | WARN 2019-07-14T11:21:24Z: audio_thread_priority::rt_linux: Could not make thread real-time.
[task 2019-07-14T11:26:24.365Z] 11:26:24 INFO - PID 2717 | [CodeCoverage] Setting handlers for process 2717.
[task 2019-07-14T11:26:24.367Z] 11:26:24 INFO - PID 2717 | [2717, Main Thread] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file /builds/worker/workspace/build/src/toolkit/crashreporter/nsExceptionHandler.cpp, line 2555
[task 2019-07-14T11:26:24.367Z] 11:26:24 INFO - PID 2717 | [2717, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, kKnownEsrVersion) failed with result 0x80004002: file /builds/worker/workspace/build/src/toolkit/components/resistfingerprinting/nsRFPService.cpp, line 662
[task 2019-07-14T11:26:24.367Z] 11:26:24 INFO - PID 2717 | [2717, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file /builds/worker/workspace/build/src/extensions/permissions/nsPermissionManager.cpp, line 2903
[task 2019-07-14T11:26:24.367Z] 11:26:24 INFO - (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2019-07-14T11:26:24.367Z] 11:26:24 INFO - (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2019-07-14T11:26:24.368Z] 11:26:24 INFO - (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2019-07-14T11:26:24.368Z] 11:26:24 INFO - running event loop
[task 2019-07-14T11:26:24.368Z] 11:26:24 INFO - xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_background_api_injection.js | Starting check_remote
[task 2019-07-14T11:26:24.368Z] 11:26:24 INFO - (xpcshell/head.js) | test check_remote pending (2)
[task 2019-07-14T11:26:24.368Z] 11:26:24 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_background_api_injection.js | check_remote - [check_remote : 1] useRemoteWebExtensions matches - true == true
[task 2019-07-14T11:26:24.369Z] 11:26:24 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_background_api_injection.js | check_remote - [check_remote : 1] testing from extension process - false == false
[task 2019-07-14T11:26:24.369Z] 11:26:24 INFO - (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2019-07-14T11:26:24.369Z] 11:26:24 INFO - (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2019-07-14T11:26:24.369Z] 11:26:24 INFO - (xpcshell/head.js) | test check_remote finished (2)
[task 2019-07-14T11:26:24.369Z] 11:26:24 INFO - xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_background_api_injection.js | Starting testBackgroundWindow
[task 2019-07-14T11:26:24.370Z] 11:26:24 INFO - (xpcshell/head.js) | test testBackgroundWindow pending (2)
[task 2019-07-14T11:26:24.370Z] 11:26:24 INFO - "Extension attached"
[task 2019-07-14T11:26:24.370Z] 11:26:24 INFO - (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2019-07-14T11:26:24.370Z] 11:26:24 INFO - PID 2717 | ++DOCSHELL 0x7f82f2cc1000 == 1 [pid = 2717] [id = {970b79b8-d425-4369-9a92-77c76894d923}]
[task 2019-07-14T11:26:24.370Z] 11:26:24 INFO - PID 2717 | ++DOMWINDOW == 1 (0x7f82f08c4d40) [pid = 2717] [serial = 1] [outer = (nil)]
[task 2019-07-14T11:26:24.370Z] 11:26:24 INFO - PID 2717 | ++DOMWINDOW == 2 (0x7f82efd67000) [pid = 2717] [serial = 2] [outer = 0x7f82f08c4d40]
[task 2019-07-14T11:26:24.370Z] 11:26:24 INFO - PID 2717 | [2717, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 374
[task 2019-07-14T11:26:24.370Z] 11:26:24 INFO - PID 2717 | ++DOMWINDOW == 3 (0x7f82ef92f400) [pid = 2717] [serial = 3] [outer = 0x7f82f08c4d40]
[task 2019-07-14T11:26:24.370Z] 11:26:24 INFO - PID 2717 | [Parent 2717, Main Thread] WARNING: Couldn't get the user appdata directory, crash dumps will go in an unusual location: file /builds/worker/workspace/build/src/toolkit/crashreporter/nsExceptionHandler.cpp, line 2625
[task 2019-07-14T11:26:24.370Z] 11:26:24 INFO - PID 2717 | profiling:/tmp/tmpNgzUtw/dom/fetch/Unified_cpp_dom_fetch0.gcda:Merge mismatch for function 50
[task 2019-07-14T11:26:24.371Z] 11:26:24 INFO - PID 2717 | profiling:/tmp/tmpNgzUtw/dom/bindings/UnifiedBindings18.gcda:Merge mismatch for function 2115
[task 2019-07-14T11:26:24.371Z] 11:26:24 INFO - PID 2717 | profiling:/tmp/tmpNgzUtw/xpcom/base/Unified_cpp_xpcom_base1.gcda:Merge mismatch for function 49
[task 2019-07-14T11:26:24.371Z] 11:26:24 INFO - PID 2717 | LoadPlugin() /tmp/xpc-plugins-DkUkGb/libnpthirdtest.so returned 7f82efd53910
[task 2019-07-14T11:26:24.371Z] 11:26:24 INFO - PID 2717 | LoadPlugin() /tmp/xpc-plugins-DkUkGb/libnpswftest.so returned 7f82efd53b20
[task 2019-07-14T11:26:24.371Z] 11:26:24 INFO - PID 2717 | LoadPlugin() /tmp/xpc-plugins-DkUkGb/libnpsecondtest.so returned 7f82efd53d00
[task 2019-07-14T11:26:24.371Z] 11:26:24 INFO - PID 2717 | LoadPlugin() /tmp/xpc-plugins-DkUkGb/libnptest.so returned 7f82efdee9a0
[task 2019-07-14T11:26:24.371Z] 11:26:24 INFO - PID 2717 | [Parent 2717, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/workspace/build/src/dom/media/gmp/GMPServiceParent.cpp, line 1571
[task 2019-07-14T11:26:24.372Z] 11:26:24 INFO - PID 2717 | [Parent 2717, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/workspace/build/src/widget/PuppetWidget.cpp, line 1096
[task 2019-07-14T11:26:24.372Z] 11:26:24 INFO - PID 2717 | [2755, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
[task 2019-07-14T11:26:24.372Z] 11:26:24 INFO - PID 2717 | [2755, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
[task 2019-07-14T11:26:24.372Z] 11:26:24 INFO - PID 2717 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-07-14T11:26:24.372Z] 11:26:24 INFO - PID 2717 | [CodeCoverage] Setting handlers for process 2755.
[task 2019-07-14T11:26:24.372Z] 11:26:24 INFO - PID 2717 | ++DOCSHELL 0x7f9ffff20000 == 1 [pid = 2755] [id = {ef25e9dd-d628-4005-8f6e-d2451821e615}]
[task 2019-07-14T11:26:24.372Z] 11:26:24 INFO - PID 2717 | ++DOMWINDOW == 1 (0x7fa001d13d40) [pid = 2755] [serial = 1] [outer = (nil)]
[task 2019-07-14T11:26:24.373Z] 11:26:24 INFO - PID 2717 | [Child 2755, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/workspace/build/src/dom/ipc/BrowserChild.cpp, line 2614
[task 2019-07-14T11:26:24.373Z] 11:26:24 INFO - PID 2717 | [Child 2755, Main Thread] WARNING: No active window: file /builds/worker/workspace/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
[task 2019-07-14T11:26:24.373Z] 11:26:24 INFO - PID 2717 | ++DOMWINDOW == 2 (0x7fa00001a400) [pid = 2755] [serial = 2] [outer = 0x7fa001d13d40]
[task 2019-07-14T11:26:24.373Z] 11:26:24 INFO - PID 2717 | [Child 2755, Main Thread] WARNING: NS_ENSURE_SUCCESS(mStatus, *this) failed with result 0x80004005: file /builds/worker/workspace/build/src/obj-firefox/dist/include/nsIURIMutator.h, line 489
[task 2019-07-14T11:26:24.373Z] 11:26:24 INFO - PID 2717 | ++DOMWINDOW == 3 (0x7fa00027d000) [pid = 2755] [serial = 3] [outer = 0x7fa001d13d40]
[task 2019-07-14T11:26:24.373Z] 11:26:24 INFO - "background script executed"
[task 2019-07-14T11:26:24.373Z] 11:26:24 INFO - PID 2717 | [Child 2755, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, kKnownEsrVersion) failed with result 0x80070057: file /builds/worker/workspace/build/src/toolkit/components/resistfingerprinting/nsRFPService.cpp, line 672
[task 2019-07-14T11:26:24.374Z] 11:26:24 INFO - PID 2717 | [Parent 2717, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/netwerk/cache/nsCacheService.cpp, line 169
[task 2019-07-14T11:26:24.374Z] 11:26:24 INFO - PID 2717 | JavaScript strict warning: resource://services-common/kinto-http-client.js, line 1892: ReferenceError: reference to undefined property "initializer"
[task 2019-07-14T11:26:24.374Z] 11:26:24 INFO - PID 2717 | JavaScript strict warning: resource://services-common/kinto-http-client.js, line 1201: ReferenceError: reference to undefined property "initializer"
[task 2019-07-14T11:26:24.374Z] 11:26:24 INFO - PID 2717 | JavaScript strict warning: resource://services-common/kinto-http-client.js, line 327: ReferenceError: reference to undefined property "initializer"
[task 2019-07-14T11:26:24.374Z] 11:26:24 INFO - PID 2717 | [Parent 2717, Main Thread] WARNING: '!workerClassifier', file /builds/worker/workspace/build/src/netwerk/url-classifier/AsyncUrlChannelClassifier.cpp, line 785
[task 2019-07-14T11:26:24.374Z] 11:26:24 INFO - PID 2717 | [Parent 2717, URL Classifier] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/toolkit/components/url-classifier/LookupCache.cpp, line 883
[task 2019-07-14T11:26:24.374Z] 11:26:24 INFO - "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "initializer"" {file: "resource://services-common/kinto-http-client.js" line: 1892}]"
[task 2019-07-14T11:26:24.375Z] 11:26:24 INFO - "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "initializer"" {file: "resource://services-common/kinto-http-client.js" line: 1201}]"
[task 2019-07-14T11:26:24.375Z] 11:26:24 INFO - "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "initializer"" {file: "resource://services-common/kinto-http-client.js" line: 327}]"
[task 2019-07-14T11:26:24.375Z] 11:26:24 INFO - PID 2717 | ++DOMWINDOW == 4 (0x7fa000282c00) [pid = 2755] [serial = 4] [outer = 0x7fa001d13d40]
[task 2019-07-14T11:26:24.375Z] 11:26:24 INFO - PID 2717 | JavaScript error: http://example.com/data/file_privilege_escalation.html, line 10: Error: WebExt Privilege Escalation: typeof(browser) = undefined
[task 2019-07-14T11:26:24.375Z] 11:26:24 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "Error: WebExt Privilege Escalation: typeof(browser) = undefined" {file: "http://example.com/data/file_privilege_escalation.html" line: 10}]
[task 2019-07-14T11:26:24.375Z] 11:26:24 INFO - @http://example.com/data/file_privilege_escalation.html:10:11
[task 2019-07-14T11:26:24.375Z] 11:26:24 INFO - "
[task 2019-07-14T11:26:24.375Z] 11:26:24 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_background_api_injection.js | testBackgroundWindow - [testBackgroundWindow : 27] Document does not have browser APIs. - true == true

Comment 1

[Intermittent Failures Robot]

1 failures in 4710 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux64-ccov: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1565905&startday=2019-07-08&endday=2019-07-14&tree=all

Comment 2

[Firefox Bug Husbandry Bot]

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

Comment 3

[Alexandru Michis [:malexandru]]

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=266968515&repo=autoland&lineNumber=2917

Comment 4

[Intermittent Failures Robot]

40 failures in 4350 pushes (0.009 failures/push) were associated with this bug in the last 7 days.

This is the #37 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 25
• mozilla-central: 6
• mozilla-inbound: 5
• try: 2
• mozilla-beta: 2

Platform breakdown:

• linux64-qr: 1
• windows10-64: 14
• windows7-32: 13
• windows10-64-shippable: 10
• linux64-shippable-qr: 1
• linux64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1565905&startday=2019-09-16&endday=2019-09-22&tree=all

Comment 5

[Intermittent Failures Robot]

5 failures in 4530 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2
• mozilla-beta: 1
• mozilla-inbound: 1
• mozilla-release: 1

Platform breakdown:

• linux64-shippable-qr: 1
• windows7-32: 2
• windows10-64: 1
• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1565905&startday=2019-09-23&endday=2019-09-29&tree=all

Comment 6

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 7

[Intermittent Failures Robot]

1 failures in 3364 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux1804-64-ccov: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1565905&startday=2020-02-03&endday=2020-02-09&tree=all

Comment 8

[Cristina Coroiu [:ccoroiu]]

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=312474319&repo=mozilla-central&lineNumber=2594

Comment 9

[Intermittent Failures Robot]

1 failures in 4618 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux1804-64-ccov: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1565905&startday=2020-08-03&endday=2020-08-09&tree=all

Comment 10

[Intermittent Failures Robot]

1 failures in 4100 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux1804-64-ccov: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1565905&startday=2020-08-17&endday=2020-08-23&tree=all

Comment 11

[Intermittent Failures Robot]

1 failures in 3410 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux1804-64-ccov: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1565905&startday=2020-09-07&endday=2020-09-13&tree=all

Comment 12

[Intermittent Failures Robot]

2 failures in 4019 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 2

Platform breakdown:

• linux1804-64-ccov: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1565905&startday=2020-09-21&endday=2020-09-27&tree=all

Comment 13

[Intermittent Failures Robot]

1 failures in 4130 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux1804-64-ccov: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1565905&startday=2020-10-05&endday=2020-10-11&tree=all

Comment 14

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.