1676702 - Intermittent editor/libeditor/tests/test_cut_copy_password.html | Timed out while polling clipboard for requested data, got: waitForClipboard-known-value-0.10851663164183323

Status: RESOLVED FIXED

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

Description

[Treeherder Bug Filer]

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

---

[task 2020-11-11T17:15:10.867Z] 17:15:10     INFO - TEST-START | editor/libeditor/tests/test_cut_copy_password.html
[task 2020-11-11T17:15:36.358Z] 17:15:36     INFO - TEST-INFO | started process screenshot
[task 2020-11-11T17:15:36.448Z] 17:15:36     INFO - TEST-INFO | screenshot: exit 0
[task 2020-11-11T17:15:36.449Z] 17:15:36     INFO - Buffered messages logged at 17:15:10
[task 2020-11-11T17:15:36.449Z] 17:15:36     INFO - must wait for load
[task 2020-11-11T17:15:36.449Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Trying to copy masked password... 
[task 2020-11-11T17:15:36.450Z] 17:15:36     INFO - Initializing clipboard with "waitForClipboard-known-value-0.006311119207081162"...
[task 2020-11-11T17:15:36.450Z] 17:15:36     INFO - Succeeded initializing clipboard, start requested things...
[task 2020-11-11T17:15:36.450Z] 17:15:36     INFO - Buffered messages logged at 17:15:16
[task 2020-11-11T17:15:36.450Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Timed out while polling clipboard for requested data, got: waitForClipboard-known-value-0.006311119207081162 
[task 2020-11-11T17:15:36.451Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Copying masked password shouldn't copy raw value into the clipboard 
[task 2020-11-11T17:15:36.451Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Copying masked password shouldn't copy masked value into the clipboard 
[task 2020-11-11T17:15:36.452Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Trying to cut masked password... 
[task 2020-11-11T17:15:36.452Z] 17:15:36     INFO - Initializing clipboard with "waitForClipboard-known-value-0.39932074602308254"...
[task 2020-11-11T17:15:36.453Z] 17:15:36     INFO - Succeeded initializing clipboard, start requested things...
[task 2020-11-11T17:15:36.453Z] 17:15:36     INFO - Buffered messages logged at 17:15:21
[task 2020-11-11T17:15:36.454Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Timed out while polling clipboard for requested data, got: waitForClipboard-known-value-0.39932074602308254 
[task 2020-11-11T17:15:36.454Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Cutting masked password shouldn't copy raw value into the clipboard 
[task 2020-11-11T17:15:36.454Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Cutting masked password shouldn't copy masked value into the clipboard 
[task 2020-11-11T17:15:36.454Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Cutting masked password shouldn't modify the value 
[task 2020-11-11T17:15:36.455Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Trying to copy partially masked password... 
[task 2020-11-11T17:15:36.455Z] 17:15:36     INFO - Initializing clipboard with "waitForClipboard-known-value-0.8448542607820275"...
[task 2020-11-11T17:15:36.455Z] 17:15:36     INFO - Succeeded initializing clipboard, start requested things...
[task 2020-11-11T17:15:36.455Z] 17:15:36     INFO - Buffered messages logged at 17:15:26
[task 2020-11-11T17:15:36.456Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Timed out while polling clipboard for requested data, got: waitForClipboard-known-value-0.8448542607820275 
[task 2020-11-11T17:15:36.456Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Copying partially masked password shouldn't copy raw value into the clipboard 
[task 2020-11-11T17:15:36.456Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Copying partially masked password shouldn't copy partially masked value into the clipboard 
[task 2020-11-11T17:15:36.457Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Copying partially masked password shouldn't copy masked value into the clipboard 
[task 2020-11-11T17:15:36.457Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Trying to cut partially masked password... 
[task 2020-11-11T17:15:36.457Z] 17:15:36     INFO - Initializing clipboard with "waitForClipboard-known-value-0.7889237534774878"...
[task 2020-11-11T17:15:36.457Z] 17:15:36     INFO - Succeeded initializing clipboard, start requested things...
[task 2020-11-11T17:15:36.458Z] 17:15:36     INFO - Buffered messages logged at 17:15:31
[task 2020-11-11T17:15:36.458Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Timed out while polling clipboard for requested data, got: waitForClipboard-known-value-0.7889237534774878 
[task 2020-11-11T17:15:36.458Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Cutting partially masked password shouldn't copy raw value into the clipboard 
[task 2020-11-11T17:15:36.458Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Cutting partially masked password shouldn't copy partially masked value into the clipboard 
[task 2020-11-11T17:15:36.458Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Cutting partially masked password shouldn't copy masked value into the clipboard 
[task 2020-11-11T17:15:36.458Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Cutting partially masked password shouldn't modify the value 
[task 2020-11-11T17:15:36.458Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Trying to copy unmasked password... 
[task 2020-11-11T17:15:36.459Z] 17:15:36     INFO - Initializing clipboard with "waitForClipboard-known-value-0.10851663164183323"...
[task 2020-11-11T17:15:36.459Z] 17:15:36     INFO - Succeeded initializing clipboard, start requested things...
[task 2020-11-11T17:15:36.459Z] 17:15:36     INFO - Buffered messages finished
[task 2020-11-11T17:15:36.459Z] 17:15:36     INFO - TEST-UNEXPECTED-FAIL | editor/libeditor/tests/test_cut_copy_password.html | Timed out while polling clipboard for requested data, got: waitForClipboard-known-value-0.10851663164183323 
[task 2020-11-11T17:15:36.459Z] 17:15:36     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2020-11-11T17:15:36.459Z] 17:15:36     INFO -     putAndVerify@SimpleTest/SimpleTest.js:1329:16
[task 2020-11-11T17:15:36.459Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Copying unmasked password shouldn't modify the value 
[task 2020-11-11T17:15:36.460Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Trying to cut unmasked password... 
[task 2020-11-11T17:15:36.460Z] 17:15:36     INFO - Initializing clipboard with "waitForClipboard-known-value-0.4221332783722296"...
[task 2020-11-11T17:15:36.460Z] 17:15:36     INFO - Succeeded initializing clipboard, start requested things...
[task 2020-11-11T17:15:36.460Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Clipboard has the given value: '23' 
[task 2020-11-11T17:15:36.460Z] 17:15:36     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Cutting unmasked password should modify the value 
[task 2020-11-11T17:15:36.460Z] 17:15:36     INFO - GECKO(9188) | MEMORY STAT | vsize 2104101MB | vsizeMaxContiguous 68164029MB | residentFast 106MB | heapAllocated 10MB
[task 2020-11-11T17:15:36.460Z] 17:15:36     INFO - TEST-OK | editor/libeditor/tests/test_cut_copy_password.html | took 25537ms```

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 8

Platform breakdown:

• windows10-64-qr: 7
• windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676702&startday=2020-11-09&endday=2020-11-15&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3

Platform breakdown:

• windows10-64: 2
• windows10-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676702&startday=2020-11-16&endday=2020-11-22&tree=all

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows10-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676702&startday=2020-11-23&endday=2020-11-29&tree=all

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 5

Platform breakdown:

• windows10-64-qr: 4
• windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676702&startday=2020-11-30&endday=2020-12-06&tree=all

Comment 5

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 6

[Natalia Csoregi [:nataliaCs]]

https://treeherder.mozilla.org/logviewer?job_id=334278108&repo=autoland&lineNumber=7358

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4

Platform and build breakdown:

• windows10-64: 2
  • opt: 2
• windows10-64-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676702&startday=2021-03-22&endday=2021-03-28&tree=all

Comment 8

[Intermittent Failures Robot]

12 failures in 3125 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 12

Platform and build breakdown:

• windows10-64-qr: 3
  • opt: 3
• windows10-64: 9
  • opt: 9

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676702&startday=2021-03-29&endday=2021-04-04&tree=all

Comment 9

[Intermittent Failures Robot]

13 failures in 3854 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 13

Platform and build breakdown:

• windows10-64-qr: 8
  • opt: 8
• windows10-64: 5
  • opt: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676702&startday=2021-04-05&endday=2021-04-11&tree=all

Comment 10

[Intermittent Failures Robot]

21 failures in 4255 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 19
• try: 2

Platform and build breakdown:

• windows10-64: 13
  • opt: 13
• windows10-64-qr: 3
  • opt: 3
• linux1804-64: 5
  • debug: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676702&startday=2021-04-12&endday=2021-04-18&tree=all

Comment 11

[Masayuki Nakano [:masayuki] (he/him)(JST, +0900)]

When this occurs, unmasked range is accidentally removed. It seems that this is a bug of editor.

[task 2021-04-20T06:30:08.969Z] 06:30:08     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Trying to cut masked password... 
[task 2021-04-20T06:30:08.969Z] 06:30:08     INFO - Initializing clipboard with "waitForClipboard-known-value-0.505336838147413"...
[task 2021-04-20T06:30:08.969Z] 06:30:08     INFO - Succeeded initializing clipboard, start requested things...
[task 2021-04-20T06:30:08.969Z] 06:30:08     INFO - Buffered messages logged at 06:29:53
[task 2021-04-20T06:30:08.969Z] 06:30:08     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Timed out while polling clipboard for requested data, got: waitForClipboard-known-value-0.505336838147413 
[task 2021-04-20T06:30:08.969Z] 06:30:08     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Cutting masked password shouldn't copy raw value into the clipboard 
[task 2021-04-20T06:30:08.969Z] 06:30:08     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Cutting masked password shouldn't copy masked value into the clipboard 
[task 2021-04-20T06:30:08.970Z] 06:30:08     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Cutting masked password shouldn't modify the value 
[task 2021-04-20T06:30:08.970Z] 06:30:08     INFO - Editor unmasked range: 2 - 4
[task 2021-04-20T06:30:08.970Z] 06:30:08     INFO - Editor unmasked range: 2 - 4
[task 2021-04-20T06:30:08.970Z] 06:30:08     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Trying to copy partially masked password... 
[task 2021-04-20T06:30:08.970Z] 06:30:08     INFO - Initializing clipboard with "waitForClipboard-known-value-0.22163652095860376"...
[task 2021-04-20T06:30:08.970Z] 06:30:08     INFO - Succeeded initializing clipboard, start requested things...
[task 2021-04-20T06:30:08.970Z] 06:30:08     INFO - Buffered messages logged at 06:29:58
[task 2021-04-20T06:30:08.970Z] 06:30:08     INFO - TEST-PASS | editor/libeditor/tests/test_cut_copy_password.html | Timed out while polling clipboard for requested data, got: waitForClipboard-known-value-0.22163652095860376 
[task 2021-04-20T06:30:08.970Z] 06:30:08     INFO - Editor unmasked range: 0 - 0

Comment 12

[Masayuki Nakano [:masayuki] (he/him)(JST, +0900)]

Hmm, the re-masking is occurred by unexpected reflow which caused destroying the frame of the password field.

[task 2021-04-20T08:02:04.131Z] 08:02:04     INFO - GECKO(8104) | #01: NS_DebugBreak(unsigned int, char const*, char const*, char const*, int) [xpcom/base/nsDebugImpl.cpp:431]
[task 2021-04-20T08:02:04.132Z] 08:02:04     INFO - GECKO(8104) | #02: mozilla::TextEditor::SetUnmaskRangeInternal(unsigned int, unsigned int, unsigned int, bool, bool) [editor/libeditor/TextEditor.cpp:1754]
[task 2021-04-20T08:02:04.132Z] 08:02:04     INFO - GECKO(8104) | #03: mozilla::EditorBase::PreDestroy(bool) [editor/libeditor/EditorBase.cpp:534]
[task 2021-04-20T08:02:04.133Z] 08:02:04     INFO - GECKO(8104) | #04: mozilla::TextControlState::UnbindFromFrame(nsTextControlFrame*) [dom/html/TextControlState.cpp:2407]
[task 2021-04-20T08:02:04.133Z] 08:02:04     INFO - GECKO(8104) | #05: nsTextControlFrame::DestroyFrom(nsIFrame*, mozilla::layout::PostFrameDestroyData&) [layout/forms/nsTextControlFrame.cpp:147]
[task 2021-04-20T08:02:04.133Z] 08:02:04     INFO - GECKO(8104) | #06: static nsLineBox::DeleteLineList(nsPresContext*, nsLineList&, nsIFrame*, nsFrameList*, mozilla::layout::PostFrameDestroyData&) [layout/generic/nsLineBox.cpp:387]
[task 2021-04-20T08:02:04.134Z] 08:02:04     INFO - GECKO(8104) | #07: nsBlockFrame::DestroyFrom(nsIFrame*, mozilla::layout::PostFrameDestroyData&) [layout/generic/nsBlockFrame.cpp:444]
[task 2021-04-20T08:02:04.134Z] 08:02:04     INFO - GECKO(8104) | #08: static nsLineBox::DeleteLineList(nsPresContext*, nsLineList&, nsIFrame*, nsFrameList*, mozilla::layout::PostFrameDestroyData&) [layout/generic/nsLineBox.cpp:387]
[task 2021-04-20T08:02:04.134Z] 08:02:04     INFO - GECKO(8104) | #09: nsBlockFrame::DestroyFrom(nsIFrame*, mozilla::layout::PostFrameDestroyData&) [layout/generic/nsBlockFrame.cpp:444]
[task 2021-04-20T08:02:04.135Z] 08:02:04     INFO - GECKO(8104) | #10: nsContainerFrame::DestroyFrom(nsIFrame*, mozilla::layout::PostFrameDestroyData&) [layout/generic/nsContainerFrame.cpp:227]
[task 2021-04-20T08:02:04.135Z] 08:02:04     INFO - GECKO(8104) | #11: nsCanvasFrame::DestroyFrom(nsIFrame*, mozilla::layout::PostFrameDestroyData&) [layout/generic/nsCanvasFrame.cpp:232]
[task 2021-04-20T08:02:04.135Z] 08:02:04     INFO - GECKO(8104) | #12: nsContainerFrame::DestroyFrom(nsIFrame*, mozilla::layout::PostFrameDestroyData&) [layout/generic/nsContainerFrame.cpp:227]
[task 2021-04-20T08:02:04.136Z] 08:02:04     INFO - GECKO(8104) | #13: nsContainerFrame::RemoveFrame(mozilla::layout::FrameChildListID, nsIFrame*) [layout/generic/nsContainerFrame.cpp:181]
[task 2021-04-20T08:02:04.136Z] 08:02:04     INFO - GECKO(8104) | #14: nsCSSFrameConstructor::ContentRemoved(nsIContent*, nsIContent*, nsCSSFrameConstructor::RemoveFlags) [layout/base/nsCSSFrameConstructor.cpp:7513]
[task 2021-04-20T08:02:04.137Z] 08:02:04     INFO - GECKO(8104) | #15: nsCSSFrameConstructor::RecreateFramesForContent(nsIContent*, nsCSSFrameConstructor::InsertionKind) [layout/base/nsCSSFrameConstructor.cpp:8481]
[task 2021-04-20T08:02:04.137Z] 08:02:04     INFO - GECKO(8104) | #16: mozilla::RestyleManager::ProcessRestyledFrames(nsStyleChangeList&) [layout/base/RestyleManager.cpp:1503]
[task 2021-04-20T08:02:04.138Z] 08:02:04     INFO - GECKO(8104) | #17: mozilla::RestyleManager::DoProcessPendingRestyles(mozilla::ServoTraversalFlags) [layout/base/RestyleManager.cpp:3049]
[task 2021-04-20T08:02:04.138Z] 08:02:04     INFO - GECKO(8104) | #18: mozilla::PresShell::DoFlushPendingNotifications(mozilla::ChangesToFlush) [layout/base/PresShell.cpp:4202]
[task 2021-04-20T08:02:04.138Z] 08:02:04     INFO - GECKO(8104) | #19: nsRefreshDriver::Tick(mozilla::layers::BaseTransactionId<mozilla::VsyncIdType>, mozilla::TimeStamp) [layout/base/nsRefreshDriver.cpp:2188]
[task 2021-04-20T08:02:04.139Z] 08:02:04     INFO - GECKO(8104) | #20: mozilla::detail::RunnableFunction<`lambda at /builds/worker/checkouts/gecko/layout/base/nsRefreshDriver.cpp:1456:15'>::Run() [xpcom/threads/nsThreadUtils.h:535]
[task 2021-04-20T08:02:04.139Z] 08:02:04     INFO - GECKO(8104) | #21: mozilla::RunnableTask::Run() [xpcom/threads/TaskController.cpp:474]
[task 2021-04-20T08:02:04.139Z] 08:02:04     INFO - GECKO(8104) | #22: mozilla::TaskController::DoExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex &> const&) [xpcom/threads/TaskController.cpp:757]
[task 2021-04-20T08:02:04.139Z] 08:02:04     INFO - GECKO(8104) | #23: mozilla::TaskController::ExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex &> const&) [xpcom/threads/TaskController.cpp:612]
[task 2021-04-20T08:02:04.139Z] 08:02:04     INFO - GECKO(8104) | #24: mozilla::TaskController::ProcessPendingMTTask(bool) [xpcom/threads/TaskController.cpp:396]
[task 2021-04-20T08:02:04.139Z] 08:02:04     INFO - GECKO(8104) | #25: mozilla::detail::RunnableFunction<`lambda at /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:135:7'>::Run() [xpcom/threads/nsThreadUtils.h:535]
[task 2021-04-20T08:02:04.140Z] 08:02:04     INFO - GECKO(8104) | #26: nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:1163]
[task 2021-04-20T08:02:04.140Z] 08:02:04     INFO - GECKO(8104) | #27: NS_ProcessNextEvent(nsIThread*, bool) [xpcom/threads/nsThreadUtils.cpp:548]
[task 2021-04-20T08:02:04.140Z] 08:02:04     INFO - GECKO(8104) | #28: mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:87]
[task 2021-04-20T08:02:04.140Z] 08:02:04     INFO - GECKO(8104) | #29: MessageLoop::RunHandler() [ipc/chromium/src/base/message_loop.cc:329]
[task 2021-04-20T08:02:04.140Z] 08:02:04     INFO - GECKO(8104) | #30: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:311]
[task 2021-04-20T08:02:04.141Z] 08:02:04     INFO - GECKO(8104) | #31: nsBaseAppShell::Run() [widget/nsBaseAppShell.cpp:139]
[task 2021-04-20T08:02:04.141Z] 08:02:04     INFO - GECKO(8104) | #32: nsAppShell::Run() [widget/windows/nsAppShell.cpp:602]
[task 2021-04-20T08:02:04.141Z] 08:02:04     INFO - GECKO(8104) | #33: XRE_RunAppShell() [toolkit/xre/nsEmbedFunctions.cpp:906]
[task 2021-04-20T08:02:04.142Z] 08:02:04     INFO - GECKO(8104) | #34: mozilla::ipc::MessagePumpForChildProcess::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:237]
[task 2021-04-20T08:02:04.142Z] 08:02:04     INFO - GECKO(8104) | #35: MessageLoop::RunHandler() [ipc/chromium/src/base/message_loop.cc:329]
[task 2021-04-20T08:02:04.142Z] 08:02:04     INFO - GECKO(8104) | #36: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:311]
[task 2021-04-20T08:02:04.142Z] 08:02:04     INFO - GECKO(8104) | #37: XRE_InitChildProcess(int, char**, XREChildData const*) [toolkit/xre/nsEmbedFunctions.cpp:742]
[task 2021-04-20T08:02:04.142Z] 08:02:04     INFO - GECKO(8104) | #38: NS_internal_main(int, char**, char**) [browser/app/nsBrowserApp.cpp:309]
[task 2021-04-20T08:02:04.142Z] 08:02:04     INFO - GECKO(8104) | #39: wmain(int, wchar_t**) [toolkit/xre/nsWindowsWMain.cpp:131]
[task 2021-04-20T08:02:04.142Z] 08:02:04     INFO - GECKO(8104) | #40: __scrt_common_main_seh() [/builds/worker/workspace/obj-build/browser/app/f:/dd/vctools/crt/vcstartup/src/startup/exe_common.inl:288]
[task 2021-04-20T08:02:04.142Z] 08:02:04     INFO - GECKO(8104) | #41: BaseThreadInitThunk [C:\Windows\System32\KERNEL32.DLL + 0x13034]
[task 2021-04-20T08:02:04.142Z] 08:02:04     INFO - GECKO(8104) | #42: RtlUserThreadStart [C:\Windows\SYSTEM32\ntdll.dll + 0x71461]
[task 2021-04-20T08:02:04.143Z] 08:02:04     INFO - GECKO(8104) | [Child 912, Main Thread] [Child 9724, MaWARin Thread] WARNING: '!aContenNING: Nt->GetCoS_ENSURE_TRUE(mPmposedDoc()', file /builds/worker/resShcehell) failckouts/ged: file /builds/workecko/laer/checkouts/gecyout/base/nsCSSFramko/layout/geeConstructneric/nsFraomreSele.cpp:8427
[task 2021-04-20T08:02:04.143Z] 08:02:04     INFO - GECKO(8104) | ction.cpp:1591
[task 2021-04-20T08:02:04.168Z] 08:02:04     INFO - GECKO(8104) | [Child 9724, Main Thread] WARNING: '!aContent->GetComposedDoc()', file /builds/worker/checkouts/gecko/layout/base/nsCSSFrameConstructor.cpp:8427
[task 2021-04-20T08:02:04.168Z] 08:02:04     INFO - GECKO(8104) | [Child 9724, Main Thread] WARNING: '!aContent->GetComposedDoc()', file /builds/worker/checkouts/gecko/layout/base/nsCSSFrameConstructor.cpp:8427

Comment 13

[Intermittent Failures Robot]

11 failures in 3224 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 9
• try: 2

Platform and build breakdown:

• windows10-64: 8
  • opt: 8
• windows10-64-qr: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676702&startday=2021-04-19&endday=2021-04-25&tree=all

Comment 14

[Intermittent Failures Robot]

12 failures in 3706 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 9
• mozilla-central: 3

Platform and build breakdown:

• windows10-64: 6
  • opt: 6
• windows10-64-qr: 3
  • opt: 3
• windows10-64-ccov-qr: 3
  • opt: 3

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

Comment 15

[Intermittent Failures Robot]

15 failures in 3279 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 11
• mozilla-central: 4

Platform and build breakdown:

• windows10-64: 10
  • opt: 10
• windows10-64-ccov-qr: 4
  • opt: 4
• windows10-64-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676702&startday=2021-05-03&endday=2021-05-09&tree=all

Comment 16

[Intermittent Failures Robot]

12 failures in 3061 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 11
• mozilla-central: 1

Platform and build breakdown:

• windows10-64: 8
  • opt: 8
• windows10-64-qr: 3
  • opt: 3
• windows10-64-ccov-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676702&startday=2021-05-17&endday=2021-05-23&tree=all

Comment 17

[Intermittent Failures Robot]

26 failures in 2908 pushes (0.009 failures/push) were associated with this bug in the last 7 days.

This is the #50 most frequent failure this week.

Repository breakdown:

• autoland: 26

Platform and build breakdown:

• windows10-64: 19
  • opt: 19
• windows10-64-qr: 7
  • opt: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676702&startday=2021-05-24&endday=2021-05-30&tree=all

Comment 18

[Intermittent Failures Robot]

23 failures in 3065 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 18
• mozilla-central: 5

Platform and build breakdown:

• windows10-64: 13
  • opt: 13
• windows10-64-qr: 5
  • opt: 5
• windows10-64-ccov-qr: 5
  • opt: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676702&startday=2021-05-31&endday=2021-06-06&tree=all

Comment 19

[Intermittent Failures Robot]

26 failures in 3502 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 16
• autoland: 10

Platform and build breakdown:

• windows10-64-ccov-qr: 16
  • opt: 16
• windows10-64-qr: 6
  • opt: 6
• windows10-64: 4
  • opt: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676702&startday=2021-06-07&endday=2021-06-13&tree=all

Comment 20

[Intermittent Failures Robot]

19 failures in 2888 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 9
• autoland: 10

Platform and build breakdown:

• windows10-64-ccov-qr: 9
  • opt: 9
• windows10-64-qr: 7
  • opt: 7
• windows10-64: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676702&startday=2021-06-14&endday=2021-06-20&tree=all

Comment 21

[Masayuki Nakano [:masayuki] (he/him)(JST, +0900)]

As far as my investigation, this requires a big change of editor module design. The root cause is, TextEditor instance is recreated if the corresponding <input> element is recreated. At this time, the unmasked range stored in TextEditor is lost. So, there are 2 problems:

• Unexpected global reflow happens during the test randomly.
• TextEditor's unmasked range should be stored in <input> element or somewhere.

From point of view of the purpose of the feature, user must not want a password filed masked when global reflow occurs e.g., by font list updates. So, the second approach is the right fix. In other words, this is not a bug of the test, this detects an actual bug of the feature.

Comment 22

[Masayuki Nakano [:masayuki] (he/him)(JST, +0900)]

Attachment: Bug 1676702 - part 0: Add automated tests for keeping unmasked range during reflows r=m_kato!

Comment 23

[Masayuki Nakano [:masayuki] (he/him)(JST, +0900)]

Attachment: Bug 1676702 - part 1: Make `TextEditor` store password masking data in a struct r=m_kato!

The intermittent failure is caused by unexpected global reflow during the test.
If <input type="password"> is reframed, TextEditor instance is recreated.
Then, the unmasked range of the editor stored by TextEditor is also lost.
Therefore, unmasked range is randomly lost by unexpected reflow.

If the global reflow is occurred by font list update etc which are not expected
by users, unmasked password field is unexpectedly masked. So, this is a real
bug of TextEditor.

So, unmasked range needs to be stored outside TextEditor for making its
lifetime longer than TextEditor. For doing it, first, TextEditor should
access password mask data via a pointer to a struct. Then, fortunately,
we need to allocate it only when the TextEditor is a password editor.

Depends on D118755

Comment 24

[Masayuki Nakano [:masayuki] (he/him)(JST, +0900)]

Attachment: Bug 1676702 - part 2: Make `TextControlState` initialize `TextEditor` with `PasswordMaskData` r=m_kato!

During a TextControlState alive, PasswordMaskData should be alive too.
Otherwise, we cannot keep unmasked range at reframing.

Depends on D118756

Comment 25

[Masayuki Nakano [:masayuki] (he/him)(JST, +0900)]

Attachment: Bug 1676702 - part 3: Make `TextControlState` store unmasked range while it does not have `TextEditor` r=m_kato!

TextControlState is alive during reframing, but TextEditor is not so.
Therefore, TextControlState should take the PasswordMaskData before
TextEditor is destroyed. And if TextEditor is recreated, and the value
hasn't been modified, unmasked range should be restored in the new editor.

Depends on D118757

Comment 26

[Masayuki Nakano [:masayuki] (he/him)(JST, +0900)]

Test result: https://treeherder.mozilla.org/jobs?repo=try&revision=a87a6dc75dee126ffadc48ed04781c0bf98045e1

Comment 27

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/09fb0d56a64f
part 0: Add automated tests for keeping unmasked range during reflows r=m_kato
https://hg.mozilla.org/integration/autoland/rev/e526321a76ff
part 1: Make `TextEditor` store password masking data in a struct r=m_kato
https://hg.mozilla.org/integration/autoland/rev/e85703ef518d
part 2: Make `TextControlState` initialize `TextEditor` with `PasswordMaskData` r=m_kato
https://hg.mozilla.org/integration/autoland/rev/9faf557a1ba3
part 3: Make `TextControlState` store unmasked range while it does not have `TextEditor` r=m_kato

Comment 28

[Intermittent Failures Robot]

11 failures in 3115 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 3
• mozilla-central: 7
• try: 1

Platform and build breakdown:

• windows10-64: 2
  • opt: 2
• windows10-64-ccov-qr: 7
  • opt: 7
• windows10-64-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1676702&startday=2021-06-21&endday=2021-06-27&tree=all

Comment 29

[Sandor Molnar[:smolnar]]

https://hg.mozilla.org/mozilla-central/rev/09fb0d56a64f
https://hg.mozilla.org/mozilla-central/rev/e526321a76ff
https://hg.mozilla.org/mozilla-central/rev/e85703ef518d
https://hg.mozilla.org/mozilla-central/rev/9faf557a1ba3