Open Bug 1442837 Opened 3 years ago Updated 9 months ago

Intermittent browser/base/content/test/plugins/browser_CTP_crashreporting.js | Uncaught exception - Timed out waiting for plugin binding to be in success state - timed out after 200 tries.

Categories

(Core :: Plug-ins, defect, P5)

defect

Tracking

()

REOPENED

People

(Reporter: intermittent-bug-filer, Assigned: apavel)

Details

(Keywords: intermittent-failure, leave-open, Whiteboard: [stockwell disabled][retriggered])

Attachments

(1 file, 1 obsolete file)

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: REOPENED → RESOLVED
Closed: 3 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
Reoccurred here: https://treeherder.mozilla.org/logviewer.html#?job_id=211504983&repo=autoland&lineNumber=3569

19:05:11     INFO - TEST-INFO | screenshot: exit 0
19:05:11     INFO - Buffered messages logged at 19:04:49
19:05:11     INFO - Entering test bound setup
19:05:11     INFO - Leaving test bound setup
19:05:11     INFO - Entering test bound 
19:05:11     INFO - Buffered messages logged at 19:04:50
19:05:11     INFO - TEST-PASS | browser/base/content/test/plugins/browser_CTP_crashreporting.js | Plugin should not be activated - 
19:05:11     INFO - Buffered messages finished
19:05:11     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/plugins/browser_CTP_crashreporting.js | Uncaught exception - Timed out waiting for plugin binding to be in success state - timed out after 200 tries.
19:05:11     INFO - Leaving test bound
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

There are 21 total failures in the last 7 days and 61 in the last 30, on windows10-64-ccov, windows10-64, windows7-32.

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

01:34:53 INFO - TEST-START | browser/base/content/test/plugins/browser_CTP_crashreporting.js
01:34:53 INFO - GECKO(5136) | [NPAPI 3684, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 332
01:34:53 INFO - GECKO(5136) | [Parent 5268, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 332
01:34:55 INFO - GECKO(5136) | ###!!! [Parent][MessageChannel::Call] Error: Channel error: cannot send/recv
01:34:55 INFO - GECKO(5136) | [Parent 5268, Gecko_IOThread] WARNING: file z:/build/build/src/ipc/chromium/src/base/process_util_win.cc, line 166
01:35:15 INFO - TEST-INFO | started process screenshot
01:35:15 INFO - TEST-INFO | screenshot: exit 0
01:35:15 INFO - Buffered messages logged at 01:34:53
01:35:15 INFO - Entering test bound setup
01:35:15 INFO - Leaving test bound setup
01:35:15 INFO - Entering test bound
01:35:15 INFO - Buffered messages logged at 01:34:54
01:35:15 INFO - TEST-PASS | browser/base/content/test/plugins/browser_CTP_crashreporting.js | Plugin should not be activated -
01:35:15 INFO - Buffered messages finished
01:35:15 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/plugins/browser_CTP_crashreporting.js | Uncaught exception - Timed out waiting for plugin binding to be in success state - timed out after 200 tries.
01:35:15 INFO - Leaving test bound
01:35:15 INFO - Entering test bound
01:35:15 INFO - TEST-PASS | browser/base/content/test/plugins/browser_CTP_crashreporting.js | Plugin should be activated from previous test -
01:35:15 INFO - GECKO(5136) | ###!!! [Parent][MessageChannel::Call] Error: Channel error: cannot send/recv
01:35:15 INFO - GECKO(5136) | [Parent 5268, Gecko_IOThread] WARNING: file z:/build/build/src/ipc/chromium/src/base/process_util_win.cc, line 166
01:35:15 INFO - TEST-PASS | browser/base/content/test/plugins/browser_CTP_crashreporting.js | Successfully got the plugin-crashed notification bar -
01:35:15 INFO - TEST-PASS | browser/base/content/test/plugins/browser_CTP_crashreporting.js | Should have two buttons. -
01:35:38 INFO - Not taking screenshot here: see the one that was previously logged
01:35:38 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/plugins/browser_CTP_crashreporting.js | Test timed out -
01:35:38 INFO - removing permission:http://127.0.0.1:8888 plugin:nptest
01:35:38 INFO -
01:35:38 INFO - this test left a pending crash report; could not delete Z:\task_1546995480\AppData\Roaming\Mozilla\Firefox\Crash Reports\pending\dca9bc13-e2ed-4bba-9cc4-858c4b6c3985.dmp
01:35:38 INFO - this test left a pending crash report; deleted Z:\task_1546995480\AppData\Roaming\Mozilla\Firefox\Crash Reports\pending\dca9bc13-e2ed-4bba-9cc4-858c4b6c3985.extra
01:35:38 INFO - this test left a pending crash report; could not delete Z:\task_1546995480\AppData\Roaming\Mozilla\Firefox\Crash Reports\pending\e6e2e696-3acc-43ee-bb4e-bbb025d04ef1.dmp
01:35:38 INFO - this test left a pending crash report; deleted Z:\task_1546995480\AppData\Roaming\Mozilla\Firefox\Crash Reports\pending\e6e2e696-3acc-43ee-bb4e-bbb025d04ef1.extra
01:35:38 INFO - GECKO(5136) | MEMORY STAT | vsize 698MB | vsizeMaxContiguous 494MB | residentFast 202MB | heapAllocated 75MB
01:35:38 INFO - TEST-OK | browser/base/content/test/plugins/browser_CTP_crashreporting.js | took 45027ms
01:35:38 INFO - Not taking screenshot here: see the one that was previously logged
01:35:38 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/plugins/browser_CTP_crashreporting.js | Found a tab after previous test timed out: http://127.0.0.1:8888/browser/browser/base/content/test/plugins/plugin_small.html -
01:35:38 INFO - Not taking screenshot here: see the one that was previously logged
01:35:38 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/plugins/browser_CTP_crashreporting.js | Found a tab after previous test timed out: http://127.0.0.1:8888/browser/browser/base/content/test/plugins/plugin_big.html -
01:35:38 INFO - checking window state

Jim can you take a look at this or assign someone?

Flags: needinfo?(jmathies)
Whiteboard: [stockwell needswork:owner]

Plugin related test failure. Not too concerned about this considering flash will be desabled by default this year. Will try to find an owner but this falls pretty low on the priority list. Disabling the test would be ok I think.

Flags: needinfo?(jmathies)
Attached patch disabled on window (obsolete) — Splinter Review

Test disabled on windows based on the above comment.

Assignee: nobody → apavel
Attachment #9035850 - Flags: review?(jmaher)
Comment on attachment 9035850 [details] [diff] [review]
disabled on window

Review of attachment 9035850 [details] [diff] [review]:
-----------------------------------------------------------------

::: browser/base/content/test/plugins/browser.ini
@@ +56,4 @@
>  skip-if = toolkit == "gtk3"   # fails intermittently on Linux (bug 909342)
>  tags = blocklist
>  [browser_CTP_crashreporting.js]
> +skip-if = !crashreporter || verify && os == 'win' # bug 1442837

the && means we will only skip on test-verify for windows, we need an || instead.
Attachment #9035850 - Flags: review?(jmaher) → review-
Attachment #9035850 - Attachment is obsolete: true
Attachment #9035889 - Flags: review?(jmaher)
Attachment #9035889 - Flags: review?(jmaher) → review+
Whiteboard: [stockwell needswork:owner] → [stockwell disabled]

This failure started to appear again in this interval:
https://treeherder.mozilla.org/#/jobs?repo=autoland&group_state=expanded&searchStr=linux%2Cx64%2Cdebug%2Cmochitests%2Cwith%2Cfission%2Cenabled%2Ctest-linux64%2Fdebug-mochitest-browser-chrome-fis-e10s&tochange=3d9a64be67bc645e0b69667bee0f23a589448812&fromchange=3a7b90a6ba2cc27dd87d4d809d5e400edc58c6ac&selectedJob=279534808

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

[task 2019-12-02T15:26:03.599Z] 15:26:03 INFO - TEST-INFO | started process screentopng
[task 2019-12-02T15:26:04.022Z] 15:26:04 INFO - TEST-INFO | screentopng: exit 0
[task 2019-12-02T15:26:04.023Z] 15:26:04 INFO - Buffered messages logged at 15:25:41
[task 2019-12-02T15:26:04.024Z] 15:26:04 INFO - Entering test bound setup
[task 2019-12-02T15:26:04.024Z] 15:26:04 INFO - Leaving test bound setup
[task 2019-12-02T15:26:04.025Z] 15:26:04 INFO - Entering test bound
[task 2019-12-02T15:26:04.025Z] 15:26:04 INFO - Buffered messages logged at 15:25:42
[task 2019-12-02T15:26:04.025Z] 15:26:04 INFO - TEST-PASS | browser/base/content/test/plugins/browser_CTP_crashreporting.js | Plugin should not be activated -
[task 2019-12-02T15:26:04.025Z] 15:26:04 INFO - Buffered messages finished
[task 2019-12-02T15:26:04.025Z] 15:26:04 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/plugins/browser_CTP_crashreporting.js | Uncaught exception - Timed out waiting for plugin binding to be in success state - timed out after 200 tries.
[task 2019-12-02T15:26:04.025Z] 15:26:04 INFO - Leaving test bound
[task 2019-12-02T15:26:04.025Z] 15:26:04 INFO - Entering test bound
[task 2019-12-02T15:26:04.025Z] 15:26:04 INFO - GECKO(1922) | ++DOCSHELL 0x7f62e06e1000 == 2 [pid = 2086] [id = {9f4020c6-4196-48c5-8a8d-4bddc2d24a6a}]
[task 2019-12-02T15:26:04.026Z] 15:26:04 INFO - GECKO(1922) | ++DOMWINDOW == 3 (0x7f62de368980) [pid = 2086] [serial = 4] [outer = (nil)]
[task 2019-12-02T15:26:04.026Z] 15:26:04 INFO - GECKO(1922) | ++DOMWINDOW == 4 (0x7f62de388800) [pid = 2086] [serial = 5] [outer = 0x7f62de368980]
[task 2019-12-02T15:26:04.026Z] 15:26:04 INFO - GECKO(1922) | [Parent 1922, Main Thread] WARNING: we only accept nsIURI interface type, patch welcome: file /builds/worker/workspace/build/src/dom/ipc/PropertyBagUtils.cpp, line 112
[task 2019-12-02T15:26:04.027Z] 15:26:04 INFO - GECKO(1922) | ++DOMWINDOW == 5 (0x7f62de38f400) [pid = 2086] [serial = 6] [outer = 0x7f62de368980]
[task 2019-12-02T15:26:04.028Z] 15:26:04 INFO - GECKO(1922) | For application/x-test found plugin libnptest.so
[task 2019-12-02T15:26:04.045Z] 15:26:04 INFO - GECKO(1922) | [2151, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-12-02T15:26:04.045Z] 15:26:04 INFO - GECKO(1922) | [2151, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-12-02T15:26:04.045Z] 15:26:04 INFO - GECKO(1922) | [2151, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-12-02T15:26:04.045Z] 15:26:04 INFO - GECKO(1922) | [2151, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-12-02T15:26:04.045Z] 15:26:04 INFO - GECKO(1922) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpnql6H1.mozrunner/runtests_leaks_plugin_pid2151.log
[task 2019-12-02T15:26:04.045Z] 15:26:04 INFO - GECKO(1922) | [2151, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-12-02T15:26:04.045Z] 15:26:04 INFO - GECKO(1922) | [2151, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-12-02T15:26:04.045Z] 15:26:04 INFO - GECKO(1922) | LoadPlugin() /tmp/tmpnql6H1.mozrunner/plugins/libnptest.so returned 7f29829c8ee0
[task 2019-12-02T15:26:04.045Z] 15:26:04 INFO - GECKO(1922) | [NPAPI 2151, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 4359
[task 2019-12-02T15:26:04.046Z] 15:26:04 INFO - GECKO(1922) | [NPAPI 2151, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 4359
[task 2019-12-02T15:26:04.046Z] 15:26:04 INFO - GECKO(1922) | [NPAPI 2151, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 4359
[task 2019-12-02T15:26:04.046Z] 15:26:04 INFO - GECKO(1922) | [NPAPI 2151, Main Thread] WARNING: '!mInitialized', file /builds/worker/workspace/build/src/xpcom/threads/nsThreadManager.cpp, line 541
[task 2019-12-02T15:26:04.046Z] 15:26:04 INFO - GECKO(1922) | [NPAPI 2151, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/xpcom/threads/nsThreadUtils.cpp, line 151
[task 2019-12-02T15:26:04.149Z] 15:26:04 INFO - TEST-PASS | browser/base/content/test/plugins/browser_CTP_crashreporting.js | Plugin should be activated from previous test -

Jim,
could you redirect this to someone who can take a look at it?

Flags: needinfo?(jmathies)
Whiteboard: [stockwell disabled] → [stockwell disabled][retriggered]

There are 32 total failures in the last 7 days on linux64 debug.

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

No failures since December 7th.

Flags: needinfo?(jmathies)
You need to log in before you can comment on or make changes to this bug.