1723853 - Intermittent [tier 2] editor/libeditor/tests/test_bug551704.html | Timed out while polling clipboard for initialized data, got: abc

Status: NEW

(Core :: DOM: Editor, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2021-08-03T21:46:36.344Z] 21:46:36     INFO - TEST-START | editor/libeditor/tests/test_bug551704.html
[task 2021-08-03T21:46:36.345Z] 21:46:36     INFO - GECKO(6708) | [Parent 2812, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:931
[task 2021-08-03T21:46:36.346Z] 21:46:36     INFO - GECKO(6708) | [Parent 2812, Main Thread] WARNING: IPDL resolver dropped without being called!: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:959
[task 2021-08-03T21:46:36.347Z] 21:46:36     INFO - GECKO(6708) | [Parent 2812, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:931
[task 2021-08-03T21:46:36.380Z] 21:46:36     INFO - GECKO(6708) | [Parent 2812, Main Thread] WARNING: WebProgress Ignored: no longer current window global: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:2956
[task 2021-08-03T21:46:36.390Z] 21:46:36     INFO - GECKO(6708) | [Child 2056, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-08-03T21:46:36.401Z] 21:46:36     INFO - GECKO(6708) | [Child 2056, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-08-03T21:46:36.445Z] 21:46:36     INFO - GECKO(6708) | [Child 5524, Main Thread] WARNING: '!aChild->IsContent()', file /builds/worker/workspace/obj-build/dist/include/mozilla/EditorDOMPoint.h:460
[task 2021-08-03T21:46:36.486Z] 21:46:36     INFO - GECKO(6708) | [Child 5524, Main Thread] WARNING: '!aChild->IsContent()', file /builds/worker/workspace/obj-build/dist/include/mozilla/EditorDOMPoint.h:460
[task 2021-08-03T21:46:36.554Z] 21:46:36     INFO - GECKO(6708) | [Child 5524, Main Thread] WARNING: '!editingHost', file /builds/worker/checkouts/gecko/editor/libeditor/HTMLEditor.cpp:755
[task 2021-08-03T21:46:36.555Z] 21:46:36     INFO - GECKO(6708) | [Child 5524, Main Thread] WARNING: mozInlineSpellCHecker::UpdateCurrentDictionary() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/EditorBase.cpp:5603
[task 2021-08-03T21:46:36.595Z] 21:46:36     INFO - GECKO(6708) | [Child 5524, Main Thread] WARNING: '!editingHost', file /builds/worker/checkouts/gecko/editor/libeditor/HTMLEditor.cpp:755
[task 2021-08-03T21:46:36.601Z] 21:46:36     INFO - GECKO(6708) | [Child 5524, Main Thread] WARNING: mozInlineSpellCHecker::UpdateCurrentDictionary() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/EditorBase.cpp:5603
[task 2021-08-03T21:46:36.644Z] 21:46:36     INFO - GECKO(6708) | [Child 5524, Main Thread] WARNING: '!editingHost', file /builds/worker/checkouts/gecko/editor/libeditor/HTMLEditor.cpp:755
[task 2021-08-03T21:46:36.648Z] 21:46:36     INFO - GECKO(6708) | [Child 5524, Main Thread] WARNING: mozInlineSpellCHecker::UpdateCurrentDictionary() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/EditorBase.cpp:5603
[task 2021-08-03T21:46:36.679Z] 21:46:36     INFO - GECKO(6708) | [Child 5524, Main Thread] WARNING: '!editingHost', file /builds/worker/checkouts/gecko/editor/libeditor/HTMLEditor.cpp:755
[task 2021-08-03T21:46:36.692Z] 21:46:36     INFO - GECKO(6708) | [Child 5524, Main Thread] WARNING: mozInlineSpellCHecker::UpdateCurrentDictionary() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/EditorBase.cpp:5603
[task 2021-08-03T21:46:36.735Z] 21:46:36     INFO - GECKO(6708) | [Child 5524, Main Thread] WARNING: '!editingHost', file /builds/worker/checkouts/gecko/editor/libeditor/HTMLEditor.cpp:755
[task 2021-08-03T21:46:36.736Z] 21:46:36     INFO - GECKO(6708) | [Child 5524, Main Thread] WARNING: mozInlineSpellCHecker::UpdateCurrentDictionary() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/EditorBase.cpp:5603
[task 2021-08-03T21:46:37.179Z] 21:46:37     INFO - GECKO(6708) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_162802540385615\AppData\Local\Temp\tmpst23devb.mozrunner\runtests_leaks_tab_pid5116.log
[task 2021-08-03T21:46:37.183Z] 21:46:37     INFO - GECKO(6708) | [5116, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:250
[task 2021-08-03T21:46:37.199Z] 21:46:37     INFO - GECKO(6708) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_162802540385615\AppData\Local\Temp\tmpst23devb.mozrunner\runtests_leaks_tab_pid8820.log
[task 2021-08-03T21:46:37.200Z] 21:46:37     INFO - GECKO(6708) | [8820, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:250
[task 2021-08-03T21:46:37.712Z] 21:46:37     INFO - GECKO(6708) | [Parent 2812, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:931
[task 2021-08-03T21:46:37.715Z] 21:46:37     INFO - GECKO(6708) | [Parent 2812, Main Thread] WARNING: IPDL resolver dropped without being called!: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:959
[task 2021-08-03T21:46:37.716Z] 21:46:37     INFO - GECKO(6708) | [Parent 2812, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:931
[task 2021-08-03T21:46:39.048Z] 21:46:39     INFO - GECKO(6708) | [Parent 2812, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:931
[task 2021-08-03T21:46:39.054Z] 21:46:39     INFO - GECKO(6708) | [Parent 2812, Main Thread] WARNING: IPDL resolver dropped without being called!: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:959
[task 2021-08-03T21:46:39.055Z] 21:46:39     INFO - GECKO(6708) | [Parent 2812, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:931
[task 2021-08-03T21:46:40.425Z] 21:46:40     INFO - GECKO(6708) | [Parent 2812, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:931
[task 2021-08-03T21:46:40.430Z] 21:46:40     INFO - GECKO(6708) | [Parent 2812, Main Thread] WARNING: IPDL resolver dropped without being called!: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:959
[task 2021-08-03T21:46:40.436Z] 21:46:40     INFO - GECKO(6708) | [Parent 2812, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:931
[task 2021-08-03T21:46:41.743Z] 21:46:41     INFO - GECKO(6708) | [Parent 2812, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:931
[task 2021-08-03T21:46:41.745Z] 21:46:41     INFO - GECKO(6708) | [Parent 2812, Main Thread] WARNING: IPDL resolver dropped without being called!: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:959
[task 2021-08-03T21:46:41.746Z] 21:46:41     INFO - GECKO(6708) | [Parent 2812, Main Thread] WARNING: Not resolving response because actor is dead.: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:931
[task 2021-08-03T21:46:42.436Z] 21:46:42     INFO - TEST-INFO | started process screenshot
[task 2021-08-03T21:46:42.516Z] 21:46:42     INFO - TEST-INFO | screenshot: exit 0
[task 2021-08-03T21:46:42.527Z] 21:46:42     INFO - Buffered messages logged at 21:46:36
[task 2021-08-03T21:46:42.527Z] 21:46:42     INFO - must wait for load
[task 2021-08-03T21:46:42.528Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | No BR node should be injected for preformatted editable fields 
[task 2021-08-03T21:46:42.528Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | There should be no range in the selection initially 
[task 2021-08-03T21:46:42.529Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | There should be a single range in the selection after setting designMode 
[task 2021-08-03T21:46:42.529Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | The range should be collapsed 
[task 2021-08-03T21:46:42.530Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | The range should start on the text 
[task 2021-08-03T21:46:42.530Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | The start offset should be zero 
[task 2021-08-03T21:46:42.531Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | The expected HTML after editing should be correct 
[task 2021-08-03T21:46:42.532Z] 21:46:42     INFO - Initializing clipboard with "waitForClipboard-known-value-0.6509025503538405"...
[task 2021-08-03T21:46:42.532Z] 21:46:42     INFO - Succeeded initializing clipboard, start requested things...
[task 2021-08-03T21:46:42.533Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | Clipboard has the given value: 'abc
[task 2021-08-03T21:46:42.533Z] 21:46:42     INFO - def' 
[task 2021-08-03T21:46:42.534Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | The expected text should be copied to the clipboard 
[task 2021-08-03T21:46:42.534Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | The expected HTML after editing should be correct 
[task 2021-08-03T21:46:42.535Z] 21:46:42     INFO - Initializing clipboard with "waitForClipboard-known-value-0.04978062380945558"...
[task 2021-08-03T21:46:42.535Z] 21:46:42     INFO - Succeeded initializing clipboard, start requested things...
[task 2021-08-03T21:46:42.536Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | Clipboard has the given value: 'abc
[task 2021-08-03T21:46:42.536Z] 21:46:42     INFO - def' 
[task 2021-08-03T21:46:42.537Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | The expected text should be copied to the clipboard 
[task 2021-08-03T21:46:42.537Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | The expected HTML after editing should be correct 
[task 2021-08-03T21:46:42.538Z] 21:46:42     INFO - Initializing clipboard with "waitForClipboard-known-value-0.17772516120099124"...
[task 2021-08-03T21:46:42.538Z] 21:46:42     INFO - Succeeded initializing clipboard, start requested things...
[task 2021-08-03T21:46:42.539Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | Clipboard has the given value: 'abc
[task 2021-08-03T21:46:42.539Z] 21:46:42     INFO - def' 
[task 2021-08-03T21:46:42.540Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | The expected text should be copied to the clipboard 
[task 2021-08-03T21:46:42.540Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | The expected HTML after editing should be correct 
[task 2021-08-03T21:46:42.540Z] 21:46:42     INFO - Initializing clipboard with "waitForClipboard-known-value-0.23033426167807602"...
[task 2021-08-03T21:46:42.541Z] 21:46:42     INFO - Succeeded initializing clipboard, start requested things...
[task 2021-08-03T21:46:42.541Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | Clipboard has the given value: 'abc
[task 2021-08-03T21:46:42.541Z] 21:46:42     INFO - def' 
[task 2021-08-03T21:46:42.542Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | The expected text should be copied to the clipboard 
[task 2021-08-03T21:46:42.542Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | The expected HTML after editing should be correct 
[task 2021-08-03T21:46:42.543Z] 21:46:42     INFO - Initializing clipboard with "waitForClipboard-known-value-0.8695523954932168"...
[task 2021-08-03T21:46:42.543Z] 21:46:42     INFO - Succeeded initializing clipboard, start requested things...
[task 2021-08-03T21:46:42.544Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | Clipboard has the given value: 'abc
[task 2021-08-03T21:46:42.544Z] 21:46:42     INFO - def' 
[task 2021-08-03T21:46:42.544Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | The expected text should be copied to the clipboard 
[task 2021-08-03T21:46:42.545Z] 21:46:42     INFO - TEST-PASS | editor/libeditor/tests/test_bug551704.html | The expected HTML after editing should be correct 
[task 2021-08-03T21:46:42.545Z] 21:46:42     INFO - Initializing clipboard with "waitForClipboard-known-value-0.5533803982265924"...
[task 2021-08-03T21:46:42.546Z] 21:46:42     INFO - Buffered messages finished
[task 2021-08-03T21:46:42.546Z] 21:46:42     INFO - TEST-UNEXPECTED-FAIL | editor/libeditor/tests/test_bug551704.html | Timed out while polling clipboard for initialized data, got: abc
[task 2021-08-03T21:46:42.546Z] 21:46:42     INFO - def 
[task 2021-08-03T21:46:42.547Z] 21:46:42     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2021-08-03T21:46:42.547Z] 21:46:42     INFO -     putAndVerify@SimpleTest/SimpleTest.js:1215:16
[task 2021-08-03T21:46:42.548Z] 21:46:42     INFO - GECKO(6708) | MEMORY STAT | vsize 2103806MB | vsizeMaxContiguous 110200111MB | residentFast 75MB | heapAllocated 5MB
[task 2021-08-03T21:46:42.548Z] 21:46:42     INFO - TEST-OK | editor/libeditor/tests/test_bug551704.html | took 6129ms
[task 2021-08-03T21:46:42.549Z] 21:46:42     INFO - GECKO(6708) | [Parent 2812, Main Thread] WARNING: IPDL resolver dropped without being called!: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:959
[task 2021-08-03T21:46:42.550Z] 21:46:42     INFO - GECKO(6708) | [Parent 2812, Main Thread] WARNING: Received `load` event on unbridged BrowserParent!: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:3863
[task 2021-08-03T21:46:42.550Z] 21:46:42     INFO - GECKO(6708) | [Parent 2812, Main Thread] WARNING: Received `load` event on unbridged BrowserParent!: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:3863
[task 2021-08-03T21:46:42.551Z] 21:46:42     INFO - GECKO(6708) | [Parent 2812, Main Thread] WARNING: WebProgress Ignored: no longer current window global: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:2956
[task 2021-08-03T21:46:42.551Z] 21:46:42     INFO - GECKO(6708) | [Child 5524, Main Thread] WARNING: SendNotifyIMEFocus got rejected.: file /builds/worker/checkouts/gecko/widget/PuppetWidget.cpp:796

Comment 1

[Intermittent Failures Robot]

2 failures in 2829 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• windows10-64-2004-qr: 1
  • debug: 1
• windows10-64-2004-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1723853&startday=2021-08-02&endday=2021-08-08&tree=all