Closed Bug 1702803 Opened 4 years ago Closed 3 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /css/css-animations/nested-scale-animations.html | expected PASS

Categories

(Core :: CSS Transitions and Animations, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: apavel [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=335286260&repo=mozilla-beta
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Y0rjhHghQ-667UF8jg1UKw/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Y0rjhHghQ-667UF8jg1UKw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1


[task 2021-04-02T14:19:41.197Z] 14:19:41     INFO - TEST-START | /css/css-animations/nested-scale-animations.html
[task 2021-04-02T14:19:41.199Z] 14:19:41     INFO - PID 8028 | 1617373181198	Marionette	INFO	Testing http://web-platform.test:8000/css/css-animations/nested-scale-animations.html == http://web-platform.test:8000/css/css-animations/nested-scale-animations-ref.html
[task 2021-04-02T14:20:21.227Z] 14:20:21     INFO - PID 8028 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-04-02T14:20:21.239Z] 14:20:21     INFO - PID 8028 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-04-02T14:20:21.240Z] 14:20:21     INFO - PID 8028 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-04-02T14:20:21.246Z] 14:20:21  WARNING - Failed to get assertion count; window was closed
[task 2021-04-02T14:20:21.253Z] 14:20:21  WARNING - Failed to get assertion count; window was closed
[task 2021-04-02T14:20:21.256Z] 14:20:21     INFO - TEST-UNEXPECTED-TIMEOUT | /css/css-animations/nested-scale-animations.html | expected PASS
[task 2021-04-02T14:20:21.256Z] 14:20:21     INFO - TEST-INFO took 40058ms
[task 2021-04-02T14:20:21.275Z] 14:20:21     INFO - PID 8028 | 1617373221274	Marionette	INFO	Stopped listening on port 48556
[task 2021-04-02T14:20:21.319Z] 14:20:21     INFO - PID 8028 | [Child 8175, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3365
[task 2021-04-02T14:20:21.321Z] 14:20:21     INFO - PID 8028 | [Child 8123, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-04-02T14:20:21.321Z] 14:20:21     INFO - PID 8028 | [Child 8123, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-04-02T14:20:21.332Z] 14:20:21     INFO - PID 8028 | [Child 8123, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-04-02T14:20:21.341Z] 14:20:21     INFO - PID 8028 | [Child 8175, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-04-02T14:20:21.343Z] 14:20:21     INFO - PID 8028 | [Child 8123, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-04-02T14:20:21.348Z] 14:20:21     INFO - PID 8028 | [Child 8123, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-04-02T14:20:21.349Z] 14:20:21     INFO - PID 8028 | [Child 8123, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-04-02T14:20:21.473Z] 14:20:21     INFO - PID 8028 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-04-02T14:20:21.485Z] 14:20:21     INFO - PID 8028 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-04-02T14:20:21.487Z] 14:20:21     INFO - PID 8028 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-04-02T14:20:21.570Z] 14:20:21     INFO - PID 8028 | [Child 8102, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3365
[task 2021-04-02T14:20:21.591Z] 14:20:21     INFO - PID 8028 | [Child 8102, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-04-02T14:20:21.664Z] 14:20:21     INFO - PID 8028 | [Child 8123, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3365
[task 2021-04-02T14:20:21.687Z] 14:20:21     INFO - PID 8028 | [Child 8123, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-04-02T14:20:21.872Z] 14:20:21     INFO - PID 8028 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-04-02T14:20:21.896Z] 14:20:21     INFO - PID 8028 | [Child 8225, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3365
[task 2021-04-02T14:20:21.899Z] 14:20:21     INFO - PID 8028 | [Parent 8028, IPDL Background] WARNING: idb: '0.000002s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3688
[task 2021-04-02T14:20:21.900Z] 14:20:21     INFO - PID 8028 | [Parent 8028, IPDL Background] WARNING: idb: '0.000084s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3688
[task 2021-04-02T14:20:21.903Z] 14:20:21     INFO - PID 8028 | [Parent 8028, IPDL Background] WARNING: idb: '0.000094s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3688
[task 2021-04-02T14:20:21.903Z] 14:20:21     INFO - PID 8028 | [Parent 8028, IPDL Background] WARNING: idb: '0.000506s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3688
[task 2021-04-02T14:20:21.903Z] 14:20:21     INFO - PID 8028 | [Parent 8028, IPDL Background] WARNING: idb: '0.000522s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3688
[task 2021-04-02T14:20:21.903Z] 14:20:21     INFO - PID 8028 | [Parent 8028, IPDL Background] WARNING: cache: '0.000543s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3688
[task 2021-04-02T14:20:21.903Z] 14:20:21     INFO - PID 8028 | [Parent 8028, IPDL Background] WARNING: sdb: '0.000550s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3688
[task 2021-04-02T14:20:21.903Z] 14:20:21     INFO - PID 8028 | [Parent 8028, IPDL Background] WARNING: idb: '0.000617s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3688
[task 2021-04-02T14:20:21.904Z] 14:20:21     INFO - PID 8028 | [Parent 8028, IPDL Background] WARNING: idb: '0.000625s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3688
[task 2021-04-02T14:20:21.904Z] 14:20:21     INFO - PID 8028 | [Parent 8028, IPDL Background] WARNING: idb: '0.002787s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3688
[task 2021-04-02T14:20:21.905Z] 14:20:21     INFO - PID 8028 | [Parent 8028, IPDL Background] WARNING: idb: '0.002884s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3688
[task 2021-04-02T14:20:21.905Z] 14:20:21     INFO - PID 8028 | [Parent 8028, IPDL Background] WARNING: idb: '0.002897s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3688
[task 2021-04-02T14:20:21.906Z] 14:20:21     INFO - PID 8028 | [Parent 8028, IPDL Background] WARNING: idb: '0.002919s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3688
[task 2021-04-02T14:20:21.907Z] 14:20:21     INFO - PID 8028 | [Parent 8028, IPDL Background] WARNING: cache: '0.002956s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3688
[task 2021-04-02T14:20:21.907Z] 14:20:21     INFO - PID 8028 | [Parent 8028, IPDL Background] WARNING: sdb: '0.002963s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3688
[task 2021-04-02T14:20:21.913Z] 14:20:21     INFO - PID 8028 | [Child 8225, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-04-02T14:20:22.058Z] 14:20:22     INFO - PID 8028 | [Parent 8028, Main Thread] WARNING: NS_ENSURE_TRUE(mDB) failed: file /builds/worker/checkouts/gecko/netwerk/cache/nsDiskCacheDeviceSQL.cpp:1279
[task 2021-04-02T14:20:22.853Z] 14:20:22     INFO - PID 8028 | [Parent 8028, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3365
[task 2021-04-02T14:20:22.892Z] 14:20:22     INFO - PID 8028 | [Parent 8028, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-04-02T14:20:22.913Z] 14:20:22     INFO - Browser exited with return code 0```
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=340310842&repo=mozilla-release&lineNumber=4613

[task 2021-05-20T12:59:49.661Z] 12:59:49 INFO - TEST-START | /css/css-animations/nested-scale-animations.html
[task 2021-05-20T12:59:49.664Z] 12:59:49 INFO - PID 4644 | 1621515589663 Marionette INFO Testing http://web-platform.test:8000/css/css-animations/nested-scale-animations.html == http://web-platform.test:8000/css/css-animations/nested-scale-animations-ref.html
[task 2021-05-20T13:00:29.691Z] 13:00:29 INFO - TEST-UNEXPECTED-TIMEOUT | /css/css-animations/nested-scale-animations.html | expected PASS
[task 2021-05-20T13:00:29.692Z] 13:00:29 INFO - TEST-INFO took 40029ms
[task 2021-05-20T13:00:29.695Z] 13:00:29 INFO - PID 4644 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-05-20T13:00:29.698Z] 13:00:29 INFO - PID 4644 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-05-20T13:00:29.711Z] 13:00:29 INFO - PID 4644 | 1621515629710 Marionette INFO Stopped listening on port 42634
[task 2021-05-20T13:00:29.904Z] 13:00:29 INFO - PID 4644 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-05-20T13:00:29.911Z] 13:00:29 INFO - PID 4644 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-05-20T13:00:30.008Z] 13:00:30 INFO - PID 4644 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-05-20T13:00:31.021Z] 13:00:31 INFO - PID 4644 | -----------------------------------------------------
[task 2021-05-20T13:00:31.022Z] 13:00:31 INFO - PID 4644 | Suppressions used:
[task 2021-05-20T13:00:31.023Z] 13:00:31 INFO - PID 4644 | count bytes template
[task 2021-05-20T13:00:31.024Z] 13:00:31 INFO - PID 4644 | 13 416 nsComponentManagerImpl
[task 2021-05-20T13:00:31.025Z] 13:00:31 INFO - PID 4644 | 2 288 libfontconfig.so
[task 2021-05-20T13:00:31.026Z] 13:00:31 INFO - PID 4644 | -----------------------------------------------------
[task 2021-05-20T13:00:31.085Z] 13:00:31 INFO - PID 4644 | -----------------------------------------------------
[task 2021-05-20T13:00:31.086Z] 13:00:31 INFO - PID 4644 | Suppressions used:
[task 2021-05-20T13:00:31.087Z] 13:00:31 INFO - PID 4644 | count bytes template
[task 2021-05-20T13:00:31.088Z] 13:00:31 INFO - PID 4644 | 13 416 nsComponentManagerImpl
[task 2021-05-20T13:00:31.088Z] 13:00:31 INFO - PID 4644 | 2 288 libfontconfig.so
[task 2021-05-20T13:00:31.089Z] 13:00:31 INFO - PID 4644 | -----------------------------------------------------
[task 2021-05-20T13:00:31.152Z] 13:00:31 INFO - PID 4644 | -----------------------------------------------------
[task 2021-05-20T13:00:31.153Z] 13:00:31 INFO - PID 4644 | Suppressions used:
[task 2021-05-20T13:00:31.155Z] 13:00:31 INFO - PID 4644 | count bytes template
[task 2021-05-20T13:00:31.155Z] 13:00:31 INFO - PID 4644 | 13 416 nsComponentManagerImpl
[task 2021-05-20T13:00:31.155Z] 13:00:31 INFO - PID 4644 | 2 288 libfontconfig.so
[task 2021-05-20T13:00:31.155Z] 13:00:31 INFO - PID 4644 | -----------------------------------------------------
[task 2021-05-20T13:00:31.180Z] 13:00:31 INFO - PID 4644 | -----------------------------------------------------
[task 2021-05-20T13:00:31.180Z] 13:00:31 INFO - PID 4644 | Suppressions used:
[task 2021-05-20T13:00:31.180Z] 13:00:31 INFO - PID 4644 | count bytes template
[task 2021-05-20T13:00:31.180Z] 13:00:31 INFO - PID 4644 | 13 416 nsComponentManagerImpl
[task 2021-05-20T13:00:31.180Z] 13:00:31 INFO - PID 4644 | 2 288 libfontconfig.so
[task 2021-05-20T13:00:31.180Z] 13:00:31 INFO - PID 4644 | -----------------------------------------------------
[task 2021-05-20T13:00:32.510Z] 13:00:32 INFO - PID 4644 | -----------------------------------------------------
[task 2021-05-20T13:00:32.511Z] 13:00:32 INFO - PID 4644 | Suppressions used:
[task 2021-05-20T13:00:32.512Z] 13:00:32 INFO - PID 4644 | count bytes template
[task 2021-05-20T13:00:32.513Z] 13:00:32 INFO - PID 4644 | 10 312 nsComponentManagerImpl
[task 2021-05-20T13:00:32.513Z] 13:00:32 INFO - PID 4644 | 633 18083 libfontconfig.so
[task 2021-05-20T13:00:32.513Z] 13:00:32 INFO - PID 4644 | 2 384 mozJSComponentLoader
[task 2021-05-20T13:00:32.513Z] 13:00:32 INFO - PID 4644 | -----------------------------------------------------
[task 2021-05-20T13:00:32.574Z] 13:00:32 INFO - Browser exited with return code 0

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