1524740 - Intermittent /tests/gfx/layers/apz/test/mochitest/test_group_pointerevents.html logged result after SimpleTest.finish(): helper_bug1502010_unconsumed_pan.html | Got 191 pointermove events

Status: RESOLVED INCOMPLETE

(Core :: Panning and Zooming, defect, P5)

Description

[Treeherder Bug Filer]

#[markdown(off)]
Filed by: rgurzau [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=225596788&repo=autoland

https://queue.taskcluster.net/v1/task/YeGGT8pRSESLf8zwoCZhBQ/runs/0/artifacts/public/logs/live_backing.log

[task 2019-02-02T02:22:07.729Z] 02:22:07 INFO - TEST-START | gfx/layers/apz/test/mochitest/test_group_pointerevents.html
[task 2019-02-02T02:22:07.892Z] 02:22:07 INFO - GECKO(2721) | ++DOMWINDOW == 18 (0xe653d800) [pid = 2806] [serial = 117] [outer = 0xf706eff0]
[task 2019-02-02T02:22:08.074Z] 02:22:08 INFO - GECKO(2721) | ++DOCSHELL 0xe72b3c00 == 6 [pid = 2806] [id = {d2a55281-02a0-4201-b721-40ced8054fdc}]
[task 2019-02-02T02:22:08.075Z] 02:22:08 INFO - GECKO(2721) | ++DOMWINDOW == 19 (0xe641a410) [pid = 2806] [serial = 118] [outer = (nil)]
[task 2019-02-02T02:22:08.120Z] 02:22:08 INFO - GECKO(2721) | ++DOMWINDOW == 20 (0xe72b4800) [pid = 2806] [serial = 119] [outer = 0xe641a410]
[task 2019-02-02T02:22:08.265Z] 02:22:08 INFO - GECKO(2721) | ++DOMWINDOW == 21 (0xe72be000) [pid = 2806] [serial = 120] [outer = 0xe641a410]
[task 2019-02-02T02:22:08.852Z] 02:22:08 INFO - GECKO(2721) | Parent process: flushed APZ repaints, waiting for callback...
[task 2019-02-02T02:22:08.974Z] 02:22:08 INFO - GECKO(2721) | Flushed APZ repaints, waiting for callback...
[task 2019-02-02T02:22:09.723Z] 02:22:09 INFO - GECKO(2721) | ++DOCSHELL 0xe6540000 == 7 [pid = 2806] [id = {34b8eb85-7058-4009-be30-25609fcb2c92}]
[task 2019-02-02T02:22:09.725Z] 02:22:09 INFO - GECKO(2721) | ++DOMWINDOW == 22 (0xe641a670) [pid = 2806] [serial = 121] [outer = (nil)]
[task 2019-02-02T02:22:09.783Z] 02:22:09 INFO - GECKO(2721) | ++DOMWINDOW == 23 (0xe72b7400) [pid = 2806] [serial = 122] [outer = 0xe641a670]
[task 2019-02-02T02:22:09.864Z] 02:22:09 INFO - GECKO(2721) | ++DOMWINDOW == 24 (0xe72c1800) [pid = 2806] [serial = 123] [outer = 0xe641a670]
[task 2019-02-02T02:22:10.386Z] 02:22:10 INFO - GECKO(2721) | Parent process: flushed APZ repaints, waiting for callback...
[task 2019-02-02T02:22:10.447Z] 02:22:10 INFO - GECKO(2721) | Flushed APZ repaints, waiting for callback...
[task 2019-02-02T02:22:10.584Z] 02:22:10 INFO - GECKO(2721) | [Child 2806, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/RangeBoundary.h, line 79
[task 2019-02-02T02:22:11.290Z] 02:22:11 INFO - GECKO(2721) | ++DOCSHELL 0xe72b7800 == 8 [pid = 2806] [id = {6a8748c2-7a03-4f44-84e3-c84a9efa71b8}]
[task 2019-02-02T02:22:11.291Z] 02:22:11 INFO - GECKO(2721) | ++DOMWINDOW == 25 (0xe641a7a0) [pid = 2806] [serial = 124] [outer = (nil)]
[task 2019-02-02T02:22:11.318Z] 02:22:11 INFO - GECKO(2721) | ++DOMWINDOW == 26 (0xe72b9400) [pid = 2806] [serial = 125] [outer = 0xe641a7a0]
[task 2019-02-02T02:22:11.399Z] 02:22:11 INFO - GECKO(2721) | ++DOMWINDOW == 27 (0xe7cb2800) [pid = 2806] [serial = 126] [outer = 0xe641a7a0]
[task 2019-02-02T02:22:12.015Z] 02:22:12 INFO - GECKO(2721) | Parent process: flushed APZ repaints, waiting for callback...
[task 2019-02-02T02:22:12.112Z] 02:22:12 INFO - GECKO(2721) | Flushed APZ repaints, waiting for callback...
[task 2019-02-02T02:22:13.146Z] 02:22:13 INFO - GECKO(2721) | --DOMWINDOW == 26 (0xe6540800) [pid = 2806] [serial = 112] [outer = (nil)] [url = about:blank]
[task 2019-02-02T02:22:13.147Z] 02:22:13 INFO - GECKO(2721) | --DOMWINDOW == 25 (0xe72ba000) [pid = 2806] [serial = 109] [outer = (nil)] [url = about:blank]
[task 2019-02-02T02:22:13.147Z] 02:22:13 INFO - GECKO(2721) | --DOMWINDOW == 24 (0xe83d2400) [pid = 2806] [serial = 102] [outer = (nil)] [url = about:blank]
[task 2019-02-02T02:22:13.148Z] 02:22:13 INFO - GECKO(2721) | --DOMWINDOW == 23 (0xe83d6400) [pid = 2806] [serial = 105] [outer = (nil)] [url = about:blank]
[task 2019-02-02T02:22:13.149Z] 02:22:13 INFO - GECKO(2721) | --DOMWINDOW == 22 (0xe72bc400) [pid = 2806] [serial = 106] [outer = (nil)] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html]
[task 2019-02-02T02:22:13.151Z] 02:22:13 INFO - GECKO(2721) | --DOMWINDOW == 21 (0xe6536000) [pid = 2806] [serial = 72] [outer = (nil)] [url = http://mochi.test:8888/tests/gfx/layers/apz/test/mochitest/test_group_mouseevents.html]
[task 2019-02-02T02:22:14.766Z] 02:22:14 INFO - GECKO(2721) | ++DOCSHELL 0xe72b8800 == 9 [pid = 2806] [id = {0e08bde4-3878-4064-ac30-bcf5068f3ba6}]
[task 2019-02-02T02:22:14.766Z] 02:22:14 INFO - GECKO(2721) | ++DOMWINDOW == 22 (0xe641a8d0) [pid = 2806] [serial = 127] [outer = (nil)]
[task 2019-02-02T02:22:14.956Z] 02:22:14 INFO - GECKO(2721) | ++DOMWINDOW == 23 (0xe72ba800) [pid = 2806] [serial = 128] [outer = 0xe641a8d0]
[task 2019-02-02T02:22:15.032Z] 02:22:15 INFO - GECKO(2721) | ++DOMWINDOW == 24 (0xe72c2c00) [pid = 2806] [serial = 129] [outer = 0xe641a8d0]
[task 2019-02-02T02:22:15.282Z] 02:22:15 INFO - GECKO(2721) | --DOCSHELL 0xe72b7c00 == 8 [pid = 2806] [id = {c39f05da-5746-4701-85bf-1d517ea91262}]
[task 2019-02-02T02:22:15.283Z] 02:22:15 INFO - GECKO(2721) | --DOMWINDOW == 23 (0xe641a2e0) [pid = 2806] [serial = 114] [outer = (nil)] [url = http://mochi.test:8888/tests/gfx/layers/apz/test/mochitest/helper_override_root.html]
[task 2019-02-02T02:22:15.284Z] 02:22:15 INFO - GECKO(2721) | --DOCSHELL 0xe6540c00 == 7 [pid = 2806] [id = {b60d2ed7-453a-42c5-b8a0-7b36a21d1119}]
[task 2019-02-02T02:22:15.285Z] 02:22:15 INFO - GECKO(2721) | --DOMWINDOW == 22 (0xe641a080) [pid = 2806] [serial = 108] [outer = (nil)] [url = http://mochi.test:8888/tests/gfx/layers/apz/test/mochitest/helper_override_root.html]
[task 2019-02-02T02:22:15.509Z] 02:22:15 INFO - GECKO(2721) | Parent process: flushed APZ repaints, waiting for callback...
[task 2019-02-02T02:22:15.577Z] 02:22:15 INFO - GECKO(2721) | Flushed APZ repaints, waiting for callback...
[task 2019-02-02T02:22:16.301Z] 02:22:16 INFO - GECKO(2721) | Flushed APZ repaints, waiting for callback...
[task 2019-02-02T02:22:16.342Z] 02:22:16 INFO - GECKO(2721) | MEMORY STAT | vsize 448MB | residentFast 150MB | heapAllocated 14MB
[task 2019-02-02T02:22:16.508Z] 02:22:16 INFO - TEST-OK | gfx/layers/apz/test/mochitest/test_group_pointerevents.html | took 8777ms
[task 2019-02-02T02:22:16.558Z] 02:22:16 ERROR - /tests/gfx/layers/apz/test/mochitest/test_group_pointerevents.html logged result after SimpleTest.finish(): helper_bug1502010_unconsumed_pan.html | Got 191 pointermove events
[task 2019-02-02T02:22:16.560Z] 02:22:16 ERROR - /tests/gfx/layers/apz/test/mochitest/test_group_pointerevents.html logged result after SimpleTest.finish(): helper_bug1502010_unconsumed_pan.html | Document didn't y-scroll
[task 2019-02-02T02:22:16.562Z] 02:22:16 ERROR - /tests/gfx/layers/apz/test/mochitest/test_group_pointerevents.html logged result after SimpleTest.finish(): helper_bug1502010_unconsumed_pan.html | Document didn't x-scroll
[task 2019-02-02T02:22:16.564Z] 02:22:16 INFO - GECKO(2721) | ++DOMWINDOW == 23 (0xe72b6000) [pid = 2806] [serial = 130] [outer = 0xf706eff0]

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1524740&startday=2019-01-28&endday=2019-02-03&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2
• mozilla-inbound: 1

Platform breakdown:

• linux32: 1
• osx-10-10: 1
• linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1524740&startday=2019-02-04&endday=2019-02-10&tree=all

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1524740&startday=2019-02-11&endday=2019-02-17&tree=all

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1524740&startday=2019-02-25&endday=2019-03-03&tree=all

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 2
• autoland: 1

Platform breakdown:

• linux32: 1
• linux64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1524740&startday=2019-03-04&endday=2019-03-10&tree=all

Comment 6

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 7

[Cristina Coroiu [:ccoroiu]]

Recent failure log:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=238802856&repo=mozilla-beta&lineNumber=5321

14:15:58 INFO - TEST-OK | gfx/layers/apz/test/mochitest/test_group_pointerevents.html | took 1057ms
14:15:58 ERROR - /tests/gfx/layers/apz/test/mochitest/test_group_pointerevents.html logged result after SimpleTest.finish(): helper_bug1502010_unconsumed_pan.html | Got 193 pointermove events
14:15:58 ERROR - /tests/gfx/layers/apz/test/mochitest/test_group_pointerevents.html logged result after SimpleTest.finish(): helper_bug1502010_unconsumed_pan.html | Document didn't y-scroll
14:15:58 ERROR - /tests/gfx/layers/apz/test/mochitest/test_group_pointerevents.html logged result after SimpleTest.finish(): helper_bug1502010_unconsumed_pan.html | Document didn't x-scroll
14:15:58 INFO - TEST-START | gfx/layers/apz/test/mochitest/test_group_touchevents-2.html
14:15:58 INFO - GECKO(1051) | Parent process: flushed APZ repaints, waiting for callback...
14:15:58 INFO - GECKO(1051) | Flushed APZ repaints, waiting for callback...
14:15:58 INFO - GECKO(1051) | Parent process: flushed APZ repaints, waiting for callback...
14:15:58 INFO - GECKO(1051) | Flushed APZ repaints, waiting for callback...
14:15:58 INFO - GECKO(1051) | Finished synthesizing touch-start, waiting for events...
14:15:59 INFO - GECKO(1051) | Finished synthesizing touch-end, doing an APZ flush to see if any more unexpected events come through...
14:15:59 INFO - GECKO(1051) | Flushed APZ repaints, waiting for callback...
14:15:59 INFO - GECKO(1051) | Done APZ flush, ending test...
14:16:02 INFO - GECKO(1051) | Parent process: flushed APZ repaints, waiting for callback...
14:16:02 INFO - GECKO(1051) | Flushed APZ repaints, waiting for callback...
14:16:02 INFO - GECKO(1051) | Finished synthesizing touch-start, waiting for events...
14:16:03 INFO - GECKO(1051) | Finished synthesizing touch-end to clear state; finishing test...
14:16:06 INFO - GECKO(1051) | Parent process: flushed APZ repaints, waiting for callback...
14:16:06 INFO - GECKO(1051) | Flushed APZ repaints, waiting for callback...
14:16:06 INFO - GECKO(1051) | Finished synthesizing touch-start, waiting for events...
14:16:06 INFO - GECKO(1051) | Finished synthesizing touch-end to clear state; finishing test...
14:16:09 INFO - GECKO(1051) | MEMORY STAT | vsize 4168MB | residentFast 162MB | heapAllocated 18MB

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform breakdown:

• macosx64-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1524740&startday=2019-04-08&endday=2019-04-14&tree=all

Comment 9

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup