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

Status: RESOLVED DUPLICATE of bug 1582053

(WebExtensions :: General, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=266961503&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/R5IKlS4mT1eZ03Hq6DmtLQ/runs/0/artifacts/public/logs/live_backing.log

---

[task 2019-09-17T01:54:23.305Z] 01:54:23 INFO - TEST-START | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_teardown.js
[task 2019-09-17T01:59:23.307Z] 01:59:23 WARNING - TEST-UNEXPECTED-TIMEOUT | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_teardown.js | Test timed out
[task 2019-09-17T01:59:23.308Z] 01:59:23 INFO - TEST-INFO took 300001ms
[task 2019-09-17T01:59:23.308Z] 01:59:23 INFO - >>>>>>>
[task 2019-09-17T01:59:23.309Z] 01:59:23 INFO - (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2019-09-17T01:59:23.310Z] 01:59:23 INFO - (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2019-09-17T01:59:23.311Z] 01:59:23 INFO - (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2019-09-17T01:59:23.312Z] 01:59:23 INFO - running event loop
[task 2019-09-17T01:59:23.312Z] 01:59:23 INFO - xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_teardown.js | Starting check_remote
[task 2019-09-17T01:59:23.313Z] 01:59:23 INFO - (xpcshell/head.js) | test check_remote pending (2)
[task 2019-09-17T01:59:23.314Z] 01:59:23 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_teardown.js | check_remote - [check_remote : 1] useRemoteWebExtensions matches - true == true
[task 2019-09-17T01:59:23.315Z] 01:59:23 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_teardown.js | check_remote - [check_remote : 1] testing from extension process - false == false
[task 2019-09-17T01:59:23.315Z] 01:59:23 INFO - (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2019-09-17T01:59:23.316Z] 01:59:23 INFO - (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2019-09-17T01:59:23.316Z] 01:59:23 INFO - (xpcshell/head.js) | test check_remote finished (2)
[task 2019-09-17T01:59:23.316Z] 01:59:23 INFO - xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_teardown.js | Starting test_contentscript_reload_and_unload
[task 2019-09-17T01:59:23.317Z] 01:59:23 INFO - (xpcshell/head.js) | test test_contentscript_reload_and_unload pending (2)
[task 2019-09-17T01:59:23.317Z] 01:59:23 INFO - "Extension attached"
[task 2019-09-17T01:59:23.318Z] 01:59:23 INFO - (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2019-09-17T01:59:23.319Z] 01:59:23 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_teardown.js | test_contentscript_reload_and_unload - [test_contentscript_reload_and_unload : 54] ExtensionContext state change after loading a content script - 1 == 1
[task 2019-09-17T01:59:23.319Z] 01:59:23 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_teardown.js | test_contentscript_reload_and_unload - [test_contentscript_reload_and_unload : 59] Create ExtensionContext for content script - "load" == "load"
[task 2019-09-17T01:59:23.320Z] 01:59:23 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_teardown.js | test_contentscript_reload_and_unload - [test_contentscript_reload_and_unload : 64] ExtensionContext URL = page - "http://example.com/data/file_sample.html" == "http://example.com/data/file_sample.html"
[task 2019-09-17T01:59:23.320Z] 01:59:23 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_teardown.js | test_contentscript_reload_and_unload - [test_contentscript_reload_and_unload : 72] ExtensionContext state changes after reloading a content script - 2 == 2
[task 2019-09-17T01:59:23.320Z] 01:59:23 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_teardown.js | test_contentscript_reload_and_unload - [test_contentscript_reload_and_unload : 77] Unload old ExtensionContext - "unload" == "unload"
[task 2019-09-17T01:59:23.321Z] 01:59:23 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_teardown.js | test_contentscript_reload_and_unload - [test_contentscript_reload_and_unload : 78] ExtensionContext URL = page - "http://example.com/data/file_sample.html" == "http://example.com/data/file_sample.html"
[task 2019-09-17T01:59:23.321Z] 01:59:23 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_teardown.js | test_contentscript_reload_and_unload - [test_contentscript_reload_and_unload : 79] Create new ExtensionContext for content script - "load" == "load"
[task 2019-09-17T01:59:23.321Z] 01:59:23 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_teardown.js | test_contentscript_reload_and_unload - [test_contentscript_reload_and_unload : 84] ExtensionContext URL = page - "http://example.com/data/file_sample.html" == "http://example.com/data/file_sample.html"
[task 2019-09-17T01:59:23.322Z] 01:59:23 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_teardown.js | test_contentscript_reload_and_unload - [test_contentscript_reload_and_unload : 89] ExtensionContext state change after unloading a content script - 1 == 1
[task 2019-09-17T01:59:23.322Z] 01:59:23 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_teardown.js | test_contentscript_reload_and_unload - [test_contentscript_reload_and_unload : 94] Unload ExtensionContext after closing the tab with the content script - "unload" == "unload"
[task 2019-09-17T01:59:23.322Z] 01:59:23 INFO - TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_teardown.js | test_contentscript_reload_and_unload - [test_contentscript_reload_and_unload : 99] ExtensionContext URL = page - "http://example.com/data/file_sample.html" == "http://example.com/data/file_sample.html"
[task 2019-09-17T01:59:23.323Z] 01:59:23 INFO - (xpcshell/head.js) | test run_next_test 2 pending (2)
[task 2019-09-17T01:59:23.324Z] 01:59:23 INFO - (xpcshell/head.js) | test test_contentscript_reload_and_unload finished (2)
[task 2019-09-17T01:59:23.324Z] 01:59:23 INFO - (xpcshell/head.js) | test run_next_test 2 finished (1)
[task 2019-09-17T01:59:23.325Z] 01:59:23 INFO - exiting test
[task 2019-09-17T01:59:23.325Z] 01:59:23 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "IndexedDB UnknownErr: ActorsParent.cpp:12577"]"
[task 2019-09-17T01:59:23.326Z] 01:59:23 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "IndexedDB UnknownErr: ActorsParent.cpp:12577"]"
[task 2019-09-17T01:59:23.326Z] 01:59:23 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "IndexedDB UnknownErr: ActorsParent.cpp:12577"]"
[task 2019-09-17T01:59:23.327Z] 01:59:23 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "IndexedDB UnknownErr: ActorsParent.cpp:12577"]"
[task 2019-09-17T01:59:23.327Z] 01:59:23 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "IndexedDB UnknownErr: ActorsParent.cpp:12577"]"
[task 2019-09-17T01:59:23.328Z] 01:59:23 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "IndexedDB UnknownErr: IDBDatabase.cpp:536"]"
[task 2019-09-17T01:59:23.331Z] 01:59:23 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "IndexedDB UnknownErr: IDBDatabase.cpp:536"]"
[task 2019-09-17T01:59:23.331Z] 01:59:23 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "IndexedDB UnknownErr: IDBDatabase.cpp:536"]"
[task 2019-09-17T01:59:23.332Z] 01:59:23 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "IndexedDB UnknownErr: IDBDatabase.cpp:536"]"
[task 2019-09-17T01:59:23.333Z] 01:59:23 INFO - PID 558 | ###!!! [Parent][DispatchAsyncMessage] Error: PBackground::Msg_PBackgroundIDBFactoryConstructor Value error: message was deserialized, but contained an illegal value
[task 2019-09-17T01:59:23.334Z] 01:59:23 INFO - PID 558 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-09-17T01:59:23.335Z] 01:59:23 INFO - PID 558 | [Parent 558, Gecko_IOThread] WARNING: pipe error: Broken pipe: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 728
[task 2019-09-17T01:59:23.335Z] 01:59:23 INFO - <<<<<<<
[task 2019-09-17T01:59:23.336Z] 01:59:23 INFO - xpcshell return code: None
[task 2019-09-17T01:59:23.337Z] 01:59:23 INFO - xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_contentscript_teardown.js | Process still running after test!
[task 2019-09-17T01:59:23.337Z] 01:59:23 INFO - >>>>>>>
[task 2019-09-17T01:59:23.339Z] 01:59:23 INFO - PID 558 | ExceptionHandler::GenerateDump cloned child 653
[task 2019-09-17T01:59:23.340Z] 01:59:23 INFO - PID 558 | ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2019-09-17T01:59:23.340Z] 01:59:23 INFO - <<<<<<<
[task 2019-09-17T01:59:23.341Z] 01:59:23 INFO - Failed to remove directory: /tmp/xpc-plugins-CqRXa1. Waiting.
[task 2019-09-17T01:59:23.349Z] 01:59:23 INFO - TEST-START | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_proxy_socks.js

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4
• mozilla-central: 3
• try: 1
• mozilla-beta: 1
• mozilla-inbound: 1

Platform breakdown:

• linux64-shippable-qr: 1
• windows7-32-shippable: 6
• windows7-32: 1
• windows10-64: 1
• windows7-32-devedition: 1

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