Closed Bug 1632540 Opened 6 years ago Closed 6 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /mediacapture-record/MediaRecorder-bitrate.https.html | TestRunner hit external timeout (this may indicate a hang)

Categories

(Core :: Audio/Video: Recording, defect, P5)

Product:
Core
Component:
Audio/Video: Recording
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2020-04-23T12:48:29.308Z] 12:48:29 INFO - TEST-START | /mediacapture-record/MediaRecorder-bitrate.https.html
[task 2020-04-23T12:48:29.308Z] 12:48:29 INFO - Clearing pref dom.security.featurePolicy.enabled
[task 2020-04-23T12:48:29.309Z] 12:48:29 INFO - Clearing pref dom.security.featurePolicy.header.enabled
[task 2020-04-23T12:48:29.309Z] 12:48:29 INFO - Clearing pref media.navigator.permission.disabled
[task 2020-04-23T12:48:29.309Z] 12:48:29 INFO - Clearing pref media.navigator.streams.fake
[task 2020-04-23T12:48:29.312Z] 12:48:29 INFO - Clearing pref dom.security.featurePolicy.webidl.enabled
[task 2020-04-23T12:48:29.359Z] 12:48:29 INFO - Setting pref dom.security.featurePolicy.enabled (true)
[task 2020-04-23T12:48:29.362Z] 12:48:29 INFO - Setting pref dom.security.featurePolicy.header.enabled (true)
[task 2020-04-23T12:48:29.408Z] 12:48:29 INFO - Setting pref media.navigator.permission.disabled (true)
[task 2020-04-23T12:48:29.443Z] 12:48:29 INFO - Setting pref media.navigator.streams.fake (true)
[task 2020-04-23T12:48:29.443Z] 12:48:29 INFO - Setting pref dom.security.featurePolicy.webidl.enabled (true)
[task 2020-04-23T12:48:29.488Z] 12:48:29 INFO - Closing window 23
[task 2020-04-23T12:48:29.537Z] 12:48:29 INFO - PID 4484 | [Parent 4484, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp, line 1511
[task 2020-04-23T12:48:29.743Z] 12:48:29 INFO - PID 4484 | [Parent 4484, Main Thread] WARNING: '!mName', file /builds/worker/checkouts/gecko/editor/libeditor/EditAggregateTransaction.cpp, line 92
[task 2020-04-23T12:48:29.743Z] 12:48:29 INFO - PID 4484 | [Parent 4484, Main Thread] WARNING: EditAggregationTransaction::GetName() failed: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 217
[task 2020-04-23T12:48:29.744Z] 12:48:29 INFO - PID 4484 | [Parent 4484, Main Thread] WARNING: nsIAbsorbingTransaction::GetTxnName() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 188
[task 2020-04-23T12:48:30.192Z] 12:48:30 INFO - PID 4484 | [4507, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-04-23T12:48:30.193Z] 12:48:30 INFO - PID 4484 | [4507, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-04-23T12:48:30.193Z] 12:48:30 INFO - PID 4484 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/pn/nsh1xd293s16h6yswvhkj1b0000017/T/tmpfjL4rk/runtests_leaks_2324_tab_pid4507.log
[task 2020-04-23T12:48:30.193Z] 12:48:30 INFO - PID 4484 | [4507, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-04-23T12:48:30.193Z] 12:48:30 INFO - PID 4484 | [4507, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-04-23T12:48:30.445Z] 12:48:30 INFO - PID 4484 | [Parent 4484, Main Thread] WARNING: '!mName', file /builds/worker/checkouts/gecko/editor/libeditor/EditAggregateTransaction.cpp, line 92
[task 2020-04-23T12:48:30.445Z] 12:48:30 INFO - PID 4484 | [Parent 4484, Main Thread] WARNING: EditAggregationTransaction::GetName() failed: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 217
[task 2020-04-23T12:48:30.446Z] 12:48:30 INFO - PID 4484 | [Parent 4484, Main Thread] WARNING: nsIAbsorbingTransaction::GetTxnName() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 188
[task 2020-04-23T12:48:30.446Z] 12:48:30 INFO - PID 4484 | [Parent 4484, Main Thread] WARNING: '!mName', file /builds/worker/checkouts/gecko/editor/libeditor/EditAggregateTransaction.cpp, line 92
[task 2020-04-23T12:48:30.446Z] 12:48:30 INFO - PID 4484 | [Parent 4484, Main Thread] WARNING: EditAggregationTransaction::GetName() failed: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 217
[task 2020-04-23T12:48:30.446Z] 12:48:30 INFO - PID 4484 | [Parent 4484, Main Thread] WARNING: nsIAbsorbingTransaction::GetTxnName() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 188
[task 2020-04-23T12:48:30.483Z] 12:48:30 INFO - PID 4484 | [Parent 4484, Main Thread] WARNING: '!mName', file /builds/worker/checkouts/gecko/editor/libeditor/EditAggregateTransaction.cpp, line 92
[task 2020-04-23T12:48:30.483Z] 12:48:30 INFO - PID 4484 | [Parent 4484, Main Thread] WARNING: EditAggregationTransaction::GetName() failed: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 217
[task 2020-04-23T12:48:30.483Z] 12:48:30 INFO - PID 4484 | [Parent 4484, Main Thread] WARNING: nsIAbsorbingTransaction::GetTxnName() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 188
[task 2020-04-23T12:48:51.045Z] 12:48:51 INFO - PID 4484 | 2020-04-23 12:48:51.027 firefox[4484:141293] Persistent UI failed to open file file:///Users/cltbld/Library/Saved%20Application%20State/org.mozilla.nightlydebug.savedState/window_1.data: No such file or directory (2)
[task 2020-04-23T12:49:14.405Z] 12:49:14 INFO - Got timeout in harness
[task 2020-04-23T12:49:14.405Z] 12:49:14 INFO - TEST-UNEXPECTED-TIMEOUT | /mediacapture-record/MediaRecorder-bitrate.https.html | TestRunner hit external timeout (this may indicate a hang)
[task 2020-04-23T12:49:14.405Z] 12:49:14 INFO - TEST-INFO took 45144ms
[task 2020-04-23T12:50:28.088Z] 12:50:28 INFO - Browser exited with return code -15
[task 2020-04-23T12:50:28.088Z] 12:50:28 INFO - PROCESS LEAKS /var/folders/pn/nsh1xd293s16h6yswvhkj1b0000017/T/tmpfjL4rk/runtests_leaks_2324.log
[task 2020-04-23T12:50:28.088Z] 12:50:28 INFO - leakcheck | Processing log file /var/folders/pn/nsh1xd293s16h6yswvhkj1b0000017/T/tmpfjL4rk/runtests_leaks_2324.log for scope /mediacapture-record
[task 2020-04-23T12:50:28.088Z] 12:50:28 INFO - TEST-INFO | leakcheck | default process: leak threshold set at 2100 bytes
[task 2020-04-23T12:50:28.089Z] 12:50:28 INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2020-04-23T12:50:28.089Z] 12:50:28 INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2020-04-23T12:50:28.089Z] 12:50:28 INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 0 bytes
[task 2020-04-23T12:50:28.089Z] 12:50:28 INFO - TEST-INFO | leakcheck | plugin process: leak threshold set at 0 bytes
[task 2020-04-23T12:50:28.089Z] 12:50:28 INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 400 bytes
[task 2020-04-23T12:50:28.089Z] 12:50:28 INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2020-04-23T12:50:28.089Z] 12:50:28 INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 10000 bytes
[task 2020-04-23T12:50:28.089Z] 12:50:28 INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2020-04-23T12:50:28.089Z] 12:50:28 INFO - leakcheck | Processing leak log file /var/folders/pn/nsh1xd293s16h6yswvhkj1b0000017/T/tmpfjL4rk/runtests_leaks_2324_tab_pid4493.log
[task 2020-04-23T12:50:28.089Z] 12:50:28 INFO - TEST-INFO | leakcheck | tab ignoring missing output line for total leaks
[task 2020-04-23T12:50:28.089Z] 12:50:28 INFO - leakcheck | Processing leak log file /var/folders/pn/nsh1xd293s16h6yswvhkj1b0000017/T/tmpfjL4rk/runtests_leaks_2324_tab_pid4491.log
[task 2020-04-23T12:50:28.090Z] 12:50:28 INFO - TEST-INFO | leakcheck | tab ignoring missing output line for total leaks
[task 2020-04-23T12:50:28.090Z] 12:50:28 INFO - leakcheck | Processing leak log file /var/folders/pn/nsh1xd293s16h6yswvhkj1b0000017/T/tmpfjL4rk/runtests_leaks_2324_tab_pid4502.log
[task 2020-04-23T12:50:28.090Z] 12:50:28 INFO - TEST-INFO | leakcheck | tab ignoring missing output line for total leaks
[task 2020-04-23T12:50:28.090Z] 12:50:28 INFO - leakcheck | Processing leak log file /var/folders/pn/nsh1xd293s16h6yswvhkj1b0000017/T/tmpfjL4rk/runtests_leaks_2324.log
[task 2020-04-23T12:50:28.090Z] 12:50:28 INFO - TEST-UNEXPECTED-FAIL | leakcheck | default missing output line for total leaks!
[task 2020-04-23T12:50:28.090Z] 12:50:28 INFO - leakcheck | Processing leak log file /var/folders/pn/nsh1xd293s16h6yswvhkj1b0000017/T/tmpfjL4rk/runtests_leaks_2324_tab_pid4500.log
[task 2020-04-23T12:50:28.090Z] 12:50:28 INFO - TEST-INFO | leakcheck | tab ignoring missing output line for total leaks
[task 2020-04-23T12:50:28.090Z] 12:50:28 INFO - leakcheck | Processing leak log file /var/folders/pn/nsh1xd293s16h6yswvhkj1b0000017/T/tmpfjL4rk/runtests_leaks_2324_tab_pid4507.log
[task 2020-04-23T12:50:28.090Z] 12:50:28 INFO - TEST-INFO | leakcheck | tab ignoring missing output line for total leaks
[task 2020-04-23T12:50:28.090Z] 12:50:28 INFO - Browser not responding, setting status to CRASH
[task 2020-04-23T12:50:28.091Z] 12:50:28 WARNING - Command left in command_queue during cleanup: 'test_ended', (<wptrunner.wpttest.TestharnessTest /mediacapture-record/MediaRecorder-bitrate.https.html>, (<wptrunner.wpttest.TestharnessResult CRASH>, []))
[task 2020-04-23T12:50:28.091Z] 12:50:28 INFO - IOError on command, setting status to CRASH
[task 2020-04-23T12:50:28.091Z] 12:50:28 INFO - Closing logging queue
[task 2020-04-23T12:50:28.091Z] 12:50:28 INFO - queue closed
[task 2020-04-23T12:50:28.138Z] 12:50:28 INFO - Application command: /Users/cltbld/tasks/task_1587644770/build/application/Firefox NightlyDebug.app/Contents/MacOS/firefox --marionette about:blank -foreground -profile /var/folders/pn/nsh1xd293s16h6yswvhkj1b0000017/T/tmpwtfVZQ
[task 2020-04-23T12:50:28.143Z] 12:50:28 INFO - Starting runner

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

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