1642986 - Intermittent xpcshell *contentscript | Test timed out TEST-UNEXPECTED-TIMEOUT | toolkit/components/extensions/test/xpcshell/test_ext_contentscript_api_injection.js | Test timed out

Status: RESOLVED INCOMPLETE

(WebExtensions :: General, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-06-03T12:48:52.176Z] 12:48:52     INFO -  TEST-START | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_api_injection.js
[task 2020-06-03T12:53:52.176Z] 12:53:52  WARNING -  TEST-UNEXPECTED-TIMEOUT | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_api_injection.js | Test timed out
[task 2020-06-03T12:53:52.176Z] 12:53:52     INFO -  TEST-INFO took 300000ms
[task 2020-06-03T12:53:52.176Z] 12:53:52     INFO -  >>>>>>>
[task 2020-06-03T12:53:52.180Z] 12:53:52     INFO -  PID 22459 | [CodeCoverage] Setting handlers for process 22459.
[task 2020-06-03T12:53:52.181Z] 12:53:52     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2020-06-03T12:53:52.181Z] 12:53:52     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2020-06-03T12:53:52.181Z] 12:53:52     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2020-06-03T12:53:52.181Z] 12:53:52     INFO -  running event loop
[task 2020-06-03T12:53:52.181Z] 12:53:52     INFO -  xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_api_injection.js | Starting check_remote
[task 2020-06-03T12:53:52.181Z] 12:53:52     INFO -  (xpcshell/head.js) | test check_remote pending (2)
[task 2020-06-03T12:53:52.181Z] 12:53:52     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_api_injection.js | check_remote - [check_remote : 1] useRemoteWebExtensions matches - true == true
[task 2020-06-03T12:53:52.181Z] 12:53:52     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_api_injection.js | check_remote - [check_remote : 1] testing from extension process - false == false
[task 2020-06-03T12:53:52.181Z] 12:53:52     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2020-06-03T12:53:52.181Z] 12:53:52     INFO -  (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2020-06-03T12:53:52.181Z] 12:53:52     INFO -  (xpcshell/head.js) | test check_remote finished (2)
[task 2020-06-03T12:53:52.181Z] 12:53:52     INFO -  xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_api_injection.js | Starting test_contentscript_api_injection
[task 2020-06-03T12:53:52.181Z] 12:53:52     INFO -  (xpcshell/head.js) | test test_contentscript_api_injection pending (2)
[task 2020-06-03T12:53:52.181Z] 12:53:52     INFO -  "Extension attached"
[task 2020-06-03T12:53:52.182Z] 12:53:52     INFO -  (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2020-06-03T12:53:52.182Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/js/src/jit/Unified_cpp_js_src_jit9.gcda:Merge mismatch for function 57
[task 2020-06-03T12:53:52.182Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/toolkit/components/places/Unified_cpp_components_places0.gcda:Merge mismatch for function 35
[task 2020-06-03T12:53:52.182Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/toolkit/components/extensions/Unified_cpp_extensions0.gcda:Merge mismatch for function 40
[task 2020-06-03T12:53:52.182Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/gfx/sfntly/cpp/src/Unified_cpp_gfx_sfntly_cpp_src1.gcda:Merge mismatch for function 47
[task 2020-06-03T12:53:52.182Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/media/libdav1d/8bd_cdef_tmpl.gcda:Merge mismatch for function 0
[task 2020-06-03T12:53:52.182Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/layout/printing/Unified_cpp_layout_printing0.gcda:Merge mismatch for function 436
[task 2020-06-03T12:53:52.182Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/dom/xslt/xslt/Unified_cpp_dom_xslt_xslt0.gcda:Merge mismatch for function 528
[task 2020-06-03T12:53:52.182Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/dom/media/platforms/Unified_cpp_dom_media_platforms0.gcda:Merge mismatch for function 36
[task 2020-06-03T12:53:52.182Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/dom/jsurl/Unified_cpp_dom_jsurl0.gcda:Merge mismatch for function 40
[task 2020-06-03T12:53:52.183Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/gfx/angle/targets/translator/ArrayBoundsClamper.gcda:Not a gcov data file
[task 2020-06-03T12:53:52.183Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/gfx/angle/targets/translator/util.gcda:Merge mismatch for function 0
[task 2020-06-03T12:53:52.183Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/gfx/angle/targets/translator/IntermNodePatternMatcher.gcda:Merge mismatch for function 0
[task 2020-06-03T12:53:52.183Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/dom/bindings/UnifiedBindings1.gcda:Merge mismatch for function 0
[task 2020-06-03T12:53:52.183Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/ipc/ipdl/UnifiedProtocols12.gcda:Merge mismatch for function 43
[task 2020-06-03T12:53:52.183Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/netwerk/base/Unified_cpp_netwerk_base0.gcda:Merge mismatch for function 43
[task 2020-06-03T12:53:52.183Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/security/apps/Unified_cpp_security_apps0.gcda:Merge mismatch for function 0
[task 2020-06-03T12:53:52.183Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/js/src/jit/Unified_cpp_js_src_jit9.gcda:Merge mismatch for function 57
[task 2020-06-03T12:53:52.183Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/toolkit/components/places/Unified_cpp_components_places0.gcda:Merge mismatch for function 35
[task 2020-06-03T12:53:52.183Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/toolkit/components/extensions/Unified_cpp_extensions0.gcda:Merge mismatch for function 40
[task 2020-06-03T12:53:52.183Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/gfx/sfntly/cpp/src/Unified_cpp_gfx_sfntly_cpp_src1.gcda:Merge mismatch for function 47
[task 2020-06-03T12:53:52.183Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/media/libdav1d/8bd_cdef_tmpl.gcda:Merge mismatch for function 0
[task 2020-06-03T12:53:52.184Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/layout/printing/Unified_cpp_layout_printing0.gcda:Merge mismatch for function 436
[task 2020-06-03T12:53:52.184Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/dom/xslt/xslt/Unified_cpp_dom_xslt_xslt0.gcda:Merge mismatch for function 528
[task 2020-06-03T12:53:52.184Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/dom/media/platforms/Unified_cpp_dom_media_platforms0.gcda:Merge mismatch for function 36
[task 2020-06-03T12:53:52.184Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/dom/jsurl/Unified_cpp_dom_jsurl0.gcda:Merge mismatch for function 40
[task 2020-06-03T12:53:52.184Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/gfx/angle/targets/translator/ArrayBoundsClamper.gcda:Not a gcov data file
[task 2020-06-03T12:53:52.184Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/gfx/angle/targets/translator/util.gcda:Merge mismatch for function 0
[task 2020-06-03T12:53:52.184Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/gfx/angle/targets/translator/IntermNodePatternMatcher.gcda:Merge mismatch for function 0
[task 2020-06-03T12:53:52.185Z] 12:53:52     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "NetworkError when attempting to fetch resource." {file: "/builds/worker/workspace/build/tests/xpcshell/head.js" line: 248}]
[task 2020-06-03T12:53:52.185Z] 12:53:52     INFO -  _do_main@/builds/worker/workspace/build/tests/xpcshell/head.js:248:6
[task 2020-06-03T12:53:52.185Z] 12:53:52     INFO -  _execute_test@/builds/worker/workspace/build/tests/xpcshell/head.js:577:5
[task 2020-06-03T12:53:52.185Z] 12:53:52     INFO -  @-e:1:1
[task 2020-06-03T12:53:52.185Z] 12:53:52     INFO -  "
[task 2020-06-03T12:53:52.185Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/dom/bindings/UnifiedBindings1.gcda:Merge mismatch for function 0
[task 2020-06-03T12:53:52.185Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/ipc/ipdl/UnifiedProtocols12.gcda:Merge mismatch for function 43
[task 2020-06-03T12:53:52.185Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/netwerk/base/Unified_cpp_netwerk_base0.gcda:Merge mismatch for function 43
[task 2020-06-03T12:53:52.185Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/security/apps/Unified_cpp_security_apps0.gcda:Merge mismatch for function 0
[task 2020-06-03T12:53:52.185Z] 12:53:52     INFO -  PID 22459 | [CodeCoverage] Setting handlers for process 22495.
[task 2020-06-03T12:53:52.186Z] 12:53:52     INFO -  PID 22459 | JavaScript error: http://example.com/data/file_privilege_escalation.html, line 10: Error: WebExt Privilege Escalation: typeof(browser) = undefined
[task 2020-06-03T12:53:52.186Z] 12:53:52     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 2020-06-03T12:53:52.186Z] 12:53:52     INFO -  @http://example.com/data/file_privilege_escalation.html:10:11
[task 2020-06-03T12:53:52.186Z] 12:53:52     INFO -  "
[task 2020-06-03T12:53:52.186Z] 12:53:52     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_api_injection.js | test_contentscript_api_injection - [test_contentscript_api_injection : 55] Document does not have `browser` APIs. - true == true
[task 2020-06-03T12:53:52.186Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/js/src/jit/Unified_cpp_js_src_jit9.gcda:Merge mismatch for function 57
[task 2020-06-03T12:53:52.186Z] 12:53:52     INFO -  (xpcshell/head.js) | test run_next_test 2 pending (2)
[task 2020-06-03T12:53:52.186Z] 12:53:52     INFO -  (xpcshell/head.js) | test test_contentscript_api_injection finished (2)
[task 2020-06-03T12:53:52.187Z] 12:53:52     INFO -  (xpcshell/head.js) | test run_next_test 2 finished (1)
[task 2020-06-03T12:53:52.187Z] 12:53:52     INFO -  exiting test
[task 2020-06-03T12:53:52.187Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/toolkit/components/places/Unified_cpp_components_places0.gcda:Merge mismatch for function 35
[task 2020-06-03T12:53:52.187Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/toolkit/components/extensions/Unified_cpp_extensions0.gcda:Merge mismatch for function 40
[task 2020-06-03T12:53:52.187Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/security/nss/lib/ckfw/ckfw_nssckfw/wrap.gcda:Not a gcov data file
[task 2020-06-03T12:53:52.187Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/security/nss/lib/ckfw/ckfw_nssckfw/mechanism.gcda:Merge mismatch for function 0
[task 2020-06-03T12:53:52.187Z] 12:53:52     INFO -  PID 22459 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-06-03T12:53:52.187Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/js/src/jit/Unified_cpp_js_src_jit9.gcda:Merge mismatch for function 57
[task 2020-06-03T12:53:52.188Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/toolkit/components/places/Unified_cpp_components_places0.gcda:Merge mismatch for function 35
[task 2020-06-03T12:53:52.188Z] 12:53:52     INFO -  PID 22459 | profiling:/tmp/tmpirpsUo/toolkit/components/extensions/Unified_cpp_extensions0.gcda:Merge mismatch for function 40
[task 2020-06-03T12:53:52.188Z] 12:53:52     INFO -  <<<<<<<
[task 2020-06-03T12:53:52.188Z] 12:53:52     INFO -  xpcshell return code: None

Comment 1

[Intermittent Failures Robot]

2 failures in 4949 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=1642986&startday=2020-06-01&endday=2020-06-07&tree=all

Comment 2

[Intermittent Failures Robot]

1 failures in 5252 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=1642986&startday=2020-06-22&endday=2020-06-28&tree=all

Comment 3

[BugBot [:suhaib / :marco]]

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

Comment 4

[Cristian Brindusan [:cbrindusan]]

New occurrence:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=315336752&repo=mozilla-central&lineNumber=2550

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 4

Platform breakdown:

• linux1804-64-ccov: 4

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

Comment 6

[Intermittent Failures Robot]

1 failures in 3760 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=1642986&startday=2020-09-14&endday=2020-09-20&tree=all

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 3

Platform breakdown:

• linux1804-64-ccov: 3

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

Comment 8

[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=1642986&startday=2020-10-05&endday=2020-10-11&tree=all

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 5

Platform breakdown:

• linux1804-64-ccov: 5

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

Comment 10

[BugBot [:suhaib / :marco]]

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