1609023 - Intermittent TEST-UNEXPECTED-TIMEOUT | /dom/events/webkit-animation-iteration-event.html | webkitAnimationIteration event listener is case sensitive - Test timed out

Status: RESOLVED DUPLICATE of bug 1608980

(Core :: DOM: Core & HTML, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-01-14T01:37:36.311Z] 01:37:36 INFO - TEST-START | /dom/events/webkit-animation-iteration-event.html
[task 2020-01-14T01:37:36.327Z] 01:37:36 INFO - Closing window 8589934619
[task 2020-01-14T01:37:36.732Z] 01:37:36 INFO - PID 9841 | [Parent 9841, Main Thread] WARNING: we only accept nsIURI interface type, patch welcome: file /builds/worker/workspace/build/src/dom/ipc/PropertyBagUtils.cpp, line 112
[task 2020-01-14T01:37:36.732Z] 01:37:36 INFO - PID 9841 | [Child 10033, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-14T01:38:07.005Z] 01:38:07 INFO -
[task 2020-01-14T01:38:07.005Z] 01:38:07 INFO - TEST-PASS | /dom/events/webkit-animation-iteration-event.html | dispatchEvent of a webkitAnimationIteration event does not trigger an unprefixed event handler or listener
[task 2020-01-14T01:38:07.005Z] 01:38:07 INFO - TEST-PASS | /dom/events/webkit-animation-iteration-event.html | dispatchEvent of an animationiteration event does not trigger a prefixed event handler or listener
[task 2020-01-14T01:38:07.005Z] 01:38:07 INFO - TEST-PASS | /dom/events/webkit-animation-iteration-event.html | onwebkitanimationiteration event handler should trigger for an animation
[task 2020-01-14T01:38:07.005Z] 01:38:07 INFO - TEST-PASS | /dom/events/webkit-animation-iteration-event.html | onwebkitanimationiteration event handler should not trigger if an unprefixed event handler also exists
[task 2020-01-14T01:38:07.005Z] 01:38:07 INFO - TEST-PASS | /dom/events/webkit-animation-iteration-event.html | onwebkitanimationiteration event handler should not trigger if an unprefixed listener also exists
[task 2020-01-14T01:38:07.005Z] 01:38:07 INFO - TEST-PASS | /dom/events/webkit-animation-iteration-event.html | event types for prefixed and unprefixed animationiteration event handlers should be named appropriately
[task 2020-01-14T01:38:07.006Z] 01:38:07 INFO - TEST-PASS | /dom/events/webkit-animation-iteration-event.html | webkitAnimationIteration event listener should trigger for an animation
[task 2020-01-14T01:38:07.006Z] 01:38:07 INFO - TEST-PASS | /dom/events/webkit-animation-iteration-event.html | webkitAnimationIteration event listener should not trigger if an unprefixed listener also exists
[task 2020-01-14T01:38:07.006Z] 01:38:07 INFO - TEST-PASS | /dom/events/webkit-animation-iteration-event.html | webkitAnimationIteration event listener should not trigger if an unprefixed event handler also exists
[task 2020-01-14T01:38:07.006Z] 01:38:07 INFO - TEST-PASS | /dom/events/webkit-animation-iteration-event.html | event types for prefixed and unprefixed animationiteration event listeners should be named appropriately
[task 2020-01-14T01:38:07.006Z] 01:38:07 INFO - TEST-UNEXPECTED-TIMEOUT | /dom/events/webkit-animation-iteration-event.html | webkitAnimationIteration event listener is case sensitive - Test timed out
[task 2020-01-14T01:38:07.007Z] 01:38:07 INFO - TEST-UNEXPECTED-TIMEOUT | /dom/events/webkit-animation-iteration-event.html | expected OK
[task 2020-01-14T01:38:07.007Z] 01:38:07 INFO - TEST-INFO took 30695ms
[task 2020-01-14T01:38:07.035Z] 01:38:07 INFO - PID 9841 | 1578965887030 Marionette INFO Stopped listening on port 36281
[task 2020-01-14T01:38:07.275Z] 01:38:07 INFO - PID 9841 | JavaScript error: chrome://marionette/content/listener.js, line 378: SecurityError: Permission denied to access property "windowUtils" on cross-origin object
[task 2020-01-14T01:38:07.275Z] 01:38:07 INFO - PID 9841 | [GPU 9886, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/workspace/build/src/gfx/layers/apz/src/APZUpdater.cpp, line 429
[task 2020-01-14T01:38:07.332Z] 01:38:07 INFO - PID 9841 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-01-14T01:38:07.333Z] 01:38:07 INFO - PID 9841 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-01-14T01:38:07.354Z] 01:38:07 INFO - PID 9841 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-01-14T01:38:07.354Z] 01:38:07 INFO - PID 9841 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-01-14T01:38:07.354Z] 01:38:07 INFO - PID 9841 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-01-14T01:38:07.355Z] 01:38:07 INFO - PID 9841 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-01-14T01:38:07.452Z] 01:38:07 INFO - PID 9841 | [2020-01-14T01:38:07Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-01-14T01:38:07.452Z] 01:38:07 INFO - PID 9841 | [2020-01-14T01:38:07Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-01-14T01:38:07.654Z] 01:38:07 INFO - PID 9841 | [Child 9929, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3362
[task 2020-01-14T01:38:07.669Z] 01:38:07 INFO - PID 9841 | [Child 10033, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3362
[task 2020-01-14T01:38:07.709Z] 01:38:07 INFO - PID 9841 | nsStringStats
[task 2020-01-14T01:38:07.709Z] 01:38:07 INFO - PID 9841 | => mAllocCount: 24249
[task 2020-01-14T01:38:07.709Z] 01:38:07 INFO - PID 9841 | => mReallocCount: 0
[task 2020-01-14T01:38:07.709Z] 01:38:07 INFO - PID 9841 | => mFreeCount: 24249
[task 2020-01-14T01:38:07.710Z] 01:38:07 INFO - PID 9841 | => mShareCount: 11985
[task 2020-01-14T01:38:07.710Z] 01:38:07 INFO - PID 9841 | => mAdoptCount: 450
[task 2020-01-14T01:38:07.710Z] 01:38:07 INFO - PID 9841 | => mAdoptFreeCount: 486
[task 2020-01-14T01:38:07.710Z] 01:38:07 INFO - PID 9841 | => Process ID: 9929, Thread ID: 139865214232384
[task 2020-01-14T01:38:07.726Z] 01:38:07 INFO - PID 9841 | nsStringStats
[task 2020-01-14T01:38:07.726Z] 01:38:07 INFO - PID 9841 | => mAllocCount: 17375
[task 2020-01-14T01:38:07.727Z] 01:38:07 INFO - PID 9841 | => mReallocCount: 0
[task 2020-01-14T01:38:07.727Z] 01:38:07 INFO - PID 9841 | => mFreeCount: 17375
[task 2020-01-14T01:38:07.727Z] 01:38:07 INFO - PID 9841 | => mShareCount: 32467
[task 2020-01-14T01:38:07.727Z] 01:38:07 INFO - PID 9841 | => mAdoptCount: 754
[task 2020-01-14T01:38:07.728Z] 01:38:07 INFO - PID 9841 | => mAdoptFreeCount: 804
[task 2020-01-14T01:38:07.728Z] 01:38:07 INFO - PID 9841 | => Process ID: 10033, Thread ID: 140169327269696
[task 2020-01-14T01:38:07.744Z] 01:38:07 INFO - PID 9841 | [Child 9952, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3362
[task 2020-01-14T01:38:07.780Z] 01:38:07 INFO - PID 9841 | nsStringStats
[task 2020-01-14T01:38:07.780Z] 01:38:07 INFO - PID 9841 | => mAllocCount: 12951
[task 2020-01-14T01:38:07.780Z] 01:38:07 INFO - PID 9841 | => mReallocCount: 0
[task 2020-01-14T01:38:07.780Z] 01:38:07 INFO - PID 9841 | => mFreeCount: 12951
[task 2020-01-14T01:38:07.781Z] 01:38:07 INFO - PID 9841 | => mShareCount: 17045
[task 2020-01-14T01:38:07.781Z] 01:38:07 INFO - PID 9841 | => mAdoptCount: 586
[task 2020-01-14T01:38:07.781Z] 01:38:07 INFO - PID 9841 | => mAdoptFreeCount: 594
[task 2020-01-14T01:38:07.782Z] 01:38:07 INFO - PID 9841 | => Process ID: 9952, Thread ID: 140142610192192
[task 2020-01-14T01:38:07.923Z] 01:38:07 INFO - PID 9841 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-01-14T01:38:08.020Z] 01:38:08 INFO - PID 9841 | nsStringStats
[task 2020-01-14T01:38:08.020Z] 01:38:08 INFO - PID 9841 | => mAllocCount: 5651
[task 2020-01-14T01:38:08.020Z] 01:38:08 INFO - PID 9841 | => mReallocCount: 0
[task 2020-01-14T01:38:08.020Z] 01:38:08 INFO - PID 9841 | => mFreeCount: 5651
[task 2020-01-14T01:38:08.021Z] 01:38:08 INFO - PID 9841 | => mShareCount: 3062
[task 2020-01-14T01:38:08.021Z] 01:38:08 INFO - PID 9841 | => mAdoptCount: 72
[task 2020-01-14T01:38:08.021Z] 01:38:08 INFO - PID 9841 | => mAdoptFreeCount: 72
[task 2020-01-14T01:38:08.022Z] 01:38:08 INFO - PID 9841 | => Process ID: 10006, Thread ID: 140554881333056
[task 2020-01-14T01:38:08.259Z] 01:38:08 INFO - PID 9841 | [Parent 9841, Main Thread] WARNING: NS_ENSURE_TRUE(mDB) failed: file /builds/worker/workspace/build/src/netwerk/cache/nsDiskCacheDeviceSQL.cpp, line 1284
[task 2020-01-14T01:38:08.259Z] 01:38:08 INFO - PID 9841 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-01-14T01:38:08.663Z] 01:38:08 INFO - PID 9841 | [Parent 9841, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/js/xpconnect/loader/mozJSComponentLoader.cpp, line 228
[task 2020-01-14T01:38:08.663Z] 01:38:08 INFO - PID 9841 | [Parent 9841, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/js/xpconnect/loader/mozJSComponentLoader.cpp, line 255
[task 2020-01-14T01:38:08.663Z] 01:38:08 INFO - PID 9841 | nsStringStats
[task 2020-01-14T01:38:08.664Z] 01:38:08 INFO - PID 9841 | => mAllocCount: 1444
[task 2020-01-14T01:38:08.664Z] 01:38:08 INFO - PID 9841 | => mReallocCount: 0
[task 2020-01-14T01:38:08.664Z] 01:38:08 INFO - PID 9841 | => mFreeCount: 1444
[task 2020-01-14T01:38:08.664Z] 01:38:08 INFO - PID 9841 | => mShareCount: 993
[task 2020-01-14T01:38:08.664Z] 01:38:08 INFO - PID 9841 | => mAdoptCount: 0
[task 2020-01-14T01:38:08.665Z] 01:38:08 INFO - PID 9841 | => mAdoptFreeCount: 0
[task 2020-01-14T01:38:08.665Z] 01:38:08 INFO - PID 9841 | => Process ID: 9886, Thread ID: 139847179798336
[task 2020-01-14T01:38:09.567Z] 01:38:09 INFO - PID 9841 | nsStringStats
[task 2020-01-14T01:38:09.568Z] 01:38:09 INFO - PID 9841 | => mAllocCount: 147172
[task 2020-01-14T01:38:09.568Z] 01:38:09 INFO - PID 9841 | => mReallocCount: 0
[task 2020-01-14T01:38:09.569Z] 01:38:09 INFO - PID 9841 | => mFreeCount: 147172
[task 2020-01-14T01:38:09.569Z] 01:38:09 INFO - PID 9841 | => mShareCount: 126146
[task 2020-01-14T01:38:09.569Z] 01:38:09 INFO - PID 9841 | => mAdoptCount: 2736
[task 2020-01-14T01:38:09.570Z] 01:38:09 INFO - PID 9841 | => mAdoptFreeCount: 2922
[task 2020-01-14T01:38:09.570Z] 01:38:09 INFO - PID 9841 | => Process ID: 9841, Thread ID: 140338685822784
[task 2020-01-14T01:38:09.618Z] 01:38:09 INFO - Browser exited with return code 0
[task 2020-01-14T01:38:09.620Z] 01:38:09 INFO - PROCESS LEAKS /tmp/tmpE0Rt81.mozrunner/runtests_leaks_865.log
[task 2020-01-14T01:38:09.621Z] 01:38:09 INFO - leakcheck | Processing log file /tmp/tmpE0Rt81.mozrunner/runtests_leaks_865.log for scope /dom/events
[task 2020-01-14T01:38:09.621Z] 01:38:09 INFO - TEST-INFO | leakcheck | default process: leak threshold set at 16000 bytes
[task 2020-01-14T01:38:09.622Z] 01:38:09 INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2020-01-14T01:38:09.623Z] 01:38:09 INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2020-01-14T01:38:09.623Z] 01:38:09 INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 10000 bytes
[task 2020-01-14T01:38:09.624Z] 01:38:09 INFO - TEST-INFO | leakcheck | plugin process: leak threshold set at 0 bytes
[task 2020-01-14T01:38:09.624Z] 01:38:09 INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 0 bytes
[task 2020-01-14T01:38:09.624Z] 01:38:09 INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2020-01-14T01:38:09.624Z] 01:38:09 INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 10000 bytes
[task 2020-01-14T01:38:09.625Z] 01:38:09 INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 6
• mozilla-central: 1
• try: 2

Platform breakdown:

• linux64-qr: 7
• linux1804-64-qr: 2

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

Comment 2

[Boris Zbarsky [:bzbarsky]]

Fixed by bug 1608980.