Closed Bug 1691161 Opened 5 years ago Closed 4 years ago

Categories

(Core :: Layout: Images, Video, and HTML Frames, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=329057772&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FcxMCZKkTdW_kPMdlXQR3g/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/FcxMCZKkTdW_kPMdlXQR3g/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1


[task 2021-02-05T22:26:54.854Z] 22:26:54     INFO - TEST-PASS | /css/css-backgrounds/background-size-030.html | took 842ms
[task 2021-02-05T22:26:54.856Z] 22:26:54     INFO - TEST-START | /css/css-backgrounds/background-size-031.html
[task 2021-02-05T22:26:54.861Z] 22:26:54     INFO - PID 2008 | 1612564014859	Marionette	INFO	Testing http://web-platform.test:8000/css/css-backgrounds/background-size-031.html == http://web-platform.test:8000/css/css-backgrounds/reference/background-size-031-ref.xht
[task 2021-02-05T22:26:54.866Z] 22:26:54     INFO - PID 8184 | [Parent 4724, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4381
[task 2021-02-05T22:26:54.921Z] 22:26:54     INFO - Browser exited with return code 0
[task 2021-02-05T22:26:54.922Z] 22:26:54     INFO - PROCESS LEAKS C:\Users\task_1612562931\AppData\Local\Temp\tmpfz81svjs\runtests_leaks_2212.log
[task 2021-02-05T22:26:54.923Z] 22:26:54     INFO - leakcheck | Processing log file C:\Users\task_1612562931\AppData\Local\Temp\tmpfz81svjs\runtests_leaks_2212.log for scope /css/css-flexbox/order
[task 2021-02-05T22:26:54.924Z] 22:26:54     INFO - TEST-INFO | leakcheck | default process: leak threshold set at 16000 bytes
[task 2021-02-05T22:26:54.925Z] 22:26:54     INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2021-02-05T22:26:54.925Z] 22:26:54     INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2021-02-05T22:26:54.926Z] 22:26:54     INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 10000 bytes
[task 2021-02-05T22:26:54.927Z] 22:26:54     INFO - TEST-INFO | leakcheck | plugin process: leak threshold set at 0 bytes
[task 2021-02-05T22:26:54.928Z] 22:26:54     INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 0 bytes
[task 2021-02-05T22:26:54.929Z] 22:26:54     INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2021-02-05T22:26:54.930Z] 22:26:54     INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 10000 bytes
[task 2021-02-05T22:26:54.931Z] 22:26:54     INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2021-02-05T22:26:54.932Z] 22:26:54     INFO - leakcheck | Processing leak log file C:\Users\task_1612562931\AppData\Local\Temp\tmpfz81svjs\runtests_leaks_2212.log
[task 2021-02-05T22:26:54.932Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.933Z] 22:26:54     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, default process 4724
[task 2021-02-05T22:26:54.934Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.934Z] 22:26:54     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-02-05T22:26:54.935Z] 22:26:54     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-02-05T22:26:54.936Z] 22:26:54     INFO -    0 |TOTAL                                 |       41        0|  826479        0|
[task 2021-02-05T22:26:54.941Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.941Z] 22:26:54     INFO - nsTraceRefcnt::DumpStatistics: 1892 entries
[task 2021-02-05T22:26:54.942Z] 22:26:54     INFO - TEST-PASS | leakcheck | default no leaks detected!
[task 2021-02-05T22:26:54.944Z] 22:26:54     INFO - leakcheck | Processing leak log file C:\Users\task_1612562931\AppData\Local\Temp\tmpfz81svjs\runtests_leaks_2212_gpu_pid6032.log
[task 2021-02-05T22:26:54.944Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.945Z] 22:26:54     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, gpu process 6032
[task 2021-02-05T22:26:54.946Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.946Z] 22:26:54     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-02-05T22:26:54.947Z] 22:26:54     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-02-05T22:26:54.948Z] 22:26:54     INFO -    0 |TOTAL                                 |       48        0|   16529        0|
[task 2021-02-05T22:26:54.949Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.950Z] 22:26:54     INFO - nsTraceRefcnt::DumpStatistics: 357 entries
[task 2021-02-05T22:26:54.950Z] 22:26:54     INFO - TEST-PASS | leakcheck | gpu no leaks detected!
[task 2021-02-05T22:26:54.951Z] 22:26:54     INFO - leakcheck | Processing leak log file C:\Users\task_1612562931\AppData\Local\Temp\tmpfz81svjs\runtests_leaks_2212_tab_pid116.log
[task 2021-02-05T22:26:54.952Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.953Z] 22:26:54     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 116
[task 2021-02-05T22:26:54.953Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.954Z] 22:26:54     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-02-05T22:26:54.955Z] 22:26:54     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-02-05T22:26:54.956Z] 22:26:54     INFO -    0 |TOTAL                                 |       37        0|   12800        0|
[task 2021-02-05T22:26:54.956Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.957Z] 22:26:54     INFO - nsTraceRefcnt::DumpStatistics: 377 entries
[task 2021-02-05T22:26:54.958Z] 22:26:54     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2021-02-05T22:26:54.959Z] 22:26:54     INFO - leakcheck | Processing leak log file C:\Users\task_1612562931\AppData\Local\Temp\tmpfz81svjs\runtests_leaks_2212_tab_pid5132.log
[task 2021-02-05T22:26:54.960Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.961Z] 22:26:54     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 5132
[task 2021-02-05T22:26:54.961Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.962Z] 22:26:54     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-02-05T22:26:54.963Z] 22:26:54     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-02-05T22:26:54.964Z] 22:26:54     INFO -    0 |TOTAL                                 |       40        0|  106133        0|
[task 2021-02-05T22:26:54.964Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.965Z] 22:26:54     INFO - nsTraceRefcnt::DumpStatistics: 832 entries
[task 2021-02-05T22:26:54.966Z] 22:26:54     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2021-02-05T22:26:54.967Z] 22:26:54     INFO - leakcheck | Processing leak log file C:\Users\task_1612562931\AppData\Local\Temp\tmpfz81svjs\runtests_leaks_2212_tab_pid5880.log
[task 2021-02-05T22:26:54.968Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.969Z] 22:26:54     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 5880
[task 2021-02-05T22:26:54.969Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.970Z] 22:26:54     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-02-05T22:26:54.971Z] 22:26:54     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-02-05T22:26:54.972Z] 22:26:54     INFO -    0 |TOTAL                                 |       40        0|   96691        0|
[task 2021-02-05T22:26:54.974Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.975Z] 22:26:54     INFO - nsTraceRefcnt::DumpStatistics: 1054 entries
[task 2021-02-05T22:26:54.976Z] 22:26:54     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2021-02-05T22:26:54.977Z] 22:26:54     INFO - leakcheck | Processing leak log file C:\Users\task_1612562931\AppData\Local\Temp\tmpfz81svjs\runtests_leaks_2212_tab_pid6876.log
[task 2021-02-05T22:26:54.978Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.978Z] 22:26:54     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 6876
[task 2021-02-05T22:26:54.979Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.980Z] 22:26:54     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-02-05T22:26:54.980Z] 22:26:54     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-02-05T22:26:54.981Z] 22:26:54     INFO -    0 |TOTAL                                 |       37        0|   14684        0|
[task 2021-02-05T22:26:54.982Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.983Z] 22:26:54     INFO - nsTraceRefcnt::DumpStatistics: 402 entries
[task 2021-02-05T22:26:54.984Z] 22:26:54     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2021-02-05T22:26:54.985Z] 22:26:54     INFO - leakcheck | Processing leak log file C:\Users\task_1612562931\AppData\Local\Temp\tmpfz81svjs\runtests_leaks_2212_tab_pid9884.log
[task 2021-02-05T22:26:54.986Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.986Z] 22:26:54     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 9884
[task 2021-02-05T22:26:54.987Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.988Z] 22:26:54     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-02-05T22:26:54.989Z] 22:26:54     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-02-05T22:26:54.990Z] 22:26:54     INFO -    0 |TOTAL                                 |       40        0|   35537        0|
[task 2021-02-05T22:26:54.991Z] 22:26:54     INFO - 
[task 2021-02-05T22:26:54.991Z] 22:26:54     INFO - nsTraceRefcnt::DumpStatistics: 747 entries
[task 2021-02-05T22:26:54.992Z] 22:26:54     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2021-02-05T22:26:54.997Z] 22:26:54     INFO - Closing logging queue
[task 2021-02-05T22:26:54.997Z] 22:26:54     INFO - queue closed
[task 2021-02-05T22:26:55.163Z] 22:26:55     INFO - Application command: Z:\task_1612562931\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_1612562931\AppData\Local\Temp\tmpf3syhg9u
[task 2021-02-05T22:26:55.168Z] 22:26:55     INFO - Starting runner
[task 2021-02-05T22:26:55.267Z] 22:26:55     INFO - PID 9752 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_1612562931\AppData\Local\Temp\tmpf3syhg9u\runtests_leaks_2212.log
[task 2021-02-05T22:26:55.268Z] 22:26:55     INFO - PID 9752 | [10508, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-02-05T22:26:56.837Z] 22:26:56     INFO - PID 9752 | [2021-02-05T22:26:56Z WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2021-02-05T22:26:56.878Z] 22:26:56     INFO - PID 2008 | 1612564016876	Marionette	INFO	No differences allowed
[task 2021-02-05T22:26:57.041Z] 22:26:57     INFO - TEST-UNEXPECTED-FAIL | /css/css-backgrounds/background-size-031.html | Testing http://web-platform.test:8000/css/css-backgrounds/background-size-031.html == http://web-platform.test:8000/css/css-backgrounds/reference/background-size-031-ref.xht
[task 2021-02-05T22:26:57.041Z] 22:26:57     INFO - Found 2331 pixels different, maximum difference per channel 23
[task 2021-02-05T22:26:57.042Z] 22:26:57     INFO - REFTEST   IMAGE 1 (TEST):
[task 2021-02-05T22:26:57.043Z] 22:26:57     INFO - REFTEST   IMAGE 2 (REFERENCE):
[task 2021-02-05T22:26:57.043Z] 22:26:57     INFO - TEST-INFO took 2127ms
[task 2021-02-05T22:26:57.083Z] 22:26:57     INFO - PID 2008 | [GPU 6080, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:370
[task 2021-02-05T22:26:57.161Z] 22:26:57     INFO - PID 2008 | 1612564017160	Marionette	INFO	Stopped listening on port 55539
[task 2021-02-05T22:26:57.268Z] 22:26:57     INFO - PID 2008 | [Child 4140, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-02-05T22:26:57.269Z] 22:26:57     INFO - PID 2008 | [Child 4140, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-02-05T22:26:57.283Z] 22:26:57     INFO - PID 2008 | [Child 4140, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-02-05T22:26:57.295Z] 22:26:57     INFO - PID 2008 | [Child 4140, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-02-05T22:26:57.297Z] 22:26:57     INFO - PID 2008 | [Child 4140, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-02-05T22:26:57.474Z] 22:26:57     INFO - PID 9752 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_1612562931\AppData\Local\Temp\tmpf3syhg9u\runtests_leaks_2212_gpu_pid6008.log
[task 2021-02-05T22:26:57.475Z] 22:26:57     INFO - PID 9752 | [6008, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-02-05T22:26:57.496Z] 22:26:57     INFO - PID 2008 | [GPU 6080, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:370
[task 2021-02-05T22:26:57.576Z] 22:26:57     INFO - PID 2008 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-02-05T22:26:57.665Z] 22:26:57     INFO - PID 2008 | [2021-02-05T22:26:57Z WARN  xulstore::persist] tried to remove key that isn't in the store
[task 2021-02-05T22:26:57.666Z] 22:26:57     INFO - PID 2008 | [2021-02-05T22:26:57Z WARN  xulstore::persist] tried to remove key that isn't in the store
[task 2021-02-05T22:26:57.818Z] 22:26:57     INFO - PID 2008 | [Child 4140, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3365
[task 2021-02-05T22:26:57.844Z] 22:26:57     INFO - PID 2008 | [Child 4140, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4381
[task 2021-02-05T22:26:57.969Z] 22:26:57     INFO - PID 9752 | [Parent 10508, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4258
[task 2021-02-05T22:26:57.977Z] 22:26:57     INFO - PID 9752 | [Parent 10508, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4258
[task 2021-02-05T22:26:58.118Z] 22:26:58     INFO - PID 9752 | [GPU 6008, Renderer] WARNING: Failed to bind API to GL!: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderEGL.cpp:654
[task 2021-02-05T22:26:58.118Z] 22:26:58     INFO - PID 9752 | [GPU 6008, Renderer] WARNING: Failed to create GLContext from PBuffer: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderEGL.cpp:1195
[task 2021-02-05T22:26:58.119Z] 22:26:58     INFO - PID 9752 | [GPU 6008, Renderer] WARNING: Failed to create EGLContext with khr_rbab_attribs: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderEGL.cpp:752
[task 2021-02-05T22:26:58.119Z] 22:26:58     INFO - PID 9752 | Initializing context 0000015E0C863000 surface 0000015E0AB802E0 on display 0000015E0ABA4800
[task 2021-02-05T22:26:58.121Z] 22:26:58     INFO - PID 9752 | [2021-02-05T22:26:58Z WARN  webrender_bindings::bindings] Could not find symbol "glBlendBarrierKHR" by glcontext
[task 2021-02-05T22:26:58.123Z] 22:26:58     INFO - PID 9752 | [2021-02-05T22:26:58Z WARN  webrender_bindings::bindings] Could not find symbol "glTextureStorage1DEXT" by glcontext
[task 2021-02-05T22:26:58.124Z] 22:26:58     INFO - PID 9752 | [2021-02-05T22:26:58Z WARN  webrender_bindings::bindings] Could not find symbol "glTextureStorage2DEXT" by glcontext
[task 2021-02-05T22:26:58.126Z] 22:26:58     INFO - PID 9752 | [2021-02-05T22:26:58Z WARN  webrender_bindings::bindings] Could not find symbol "glTextureStorage3DEXT" by glcontext
[task 2021-02-05T22:26:58.376Z] 22:26:58     INFO - PID 9752 | [Parent 10508, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1298
[task 2021-02-05T22:26:58.881Z] 22:26:58     INFO - PID 2008 | [Parent 2912, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/che
[task 2021-02-05T22:26:58.882Z] 22:26:58     INFO - PID 2008 | ###!!! [Parent][MessageChannel] Error: (msgtype=0xA20001,name=PTexture::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2021-02-05T22:26:58.883Z] 22:26:58     INFO - PID 2008 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost```
Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 5 years ago → 5 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 5 years ago → 4 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.