1639467 - Intermittent remote/test/browser/page/browser_frameAttached.js | Got no frame attached event - Got 1, expected 0

Reporter

Description

•

5 years ago

treeherder

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

Henrik Skupin [:whimboo][⌚️UTC+2]

Assignee

Comment 1

•

5 years ago

This is a new test as landed with the patch on bug 1599413. As it looks like there is still a race with loadURL():

  await Page.enable();

  info("Navigate to a page with iframes");
  await loadURL(DOC_IFRAME_MULTI);

  await runFrameAttachedTest(client, 0, async () => {
    info("Navigate to a page without an iframe");
    await loadURL(DOC);
  });

Or maybe this is just the frameAttached event lingering in the queue because the page events were enabled before calling loadURL(). But then I wonder why it is such a low intermittent.

Blocks: 1549512

Depends on: 1599413

Comment hidden (Intermittent Failures Robot)

Henrik Skupin [:whimboo][⌚️UTC+2]

Assignee

Comment 3

•

4 years ago

This is a failure that happens only for opt builds on linux1804 in CI. There are no failures on other platforms nor builds.

OS: Unspecified → Linux

Hardware: Unspecified → x86_64

Henrik Skupin [:whimboo][⌚️UTC+2]

Assignee

Comment 4

•

4 years ago

This might be related to bug 1641839.

Depends on: 1641839

Comment hidden (Intermittent Failures Robot)

Atila Butkovits

Comment 6

•

4 years ago

There were 24 failures in the past 7 days on linux1804-64-shippable-qr opt, linux1804-64-shippable opt and linux1804-64-qr opt.

Recent failure log:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=305251094&repo=autoland&lineNumber=4865

[task 2020-06-05T18:32:41.593Z] 18:32:41 [task 2020-06-05T18:32:42.138Z] 18:32:42 [task 2020-06-05T18:32:42.138Z] 18:32:42 [task 2020-06-05T18:32:42.139Z] 18:32:42 [task 2020-06-05T18:32:42.141Z] 18:32:42 [task 2020-06-05T18:32:42.141Z] 18:32:42 [task 2020-06-05T18:32:43.251Z] 18:32:43 [task 2020-06-05T18:32:43.268Z] 18:32:43 [task 2020-06-05T18:32:43.268Z] 18:32:43 [task 2020-06-05T18:32:43.711Z] 18:32:43 [task 2020-06-05T18:32:43.714Z] 18:32:43 [task 2020-06-05T18:32:43.715Z] 18:32:43 [task 2020-06-05T18:32:43.718Z] 18:32:43 [task 2020-06-05T18:32:43.719Z] 18:32:43 [task 2020-06-05T18:32:43.719Z] 18:32:43 [task 2020-06-05T18:32:43.720Z] 18:32:43 [task 2020-06-05T18:32:43.756Z] 18:32:43 [task 2020-06-05T18:32:43.756Z] 18:32:43 [task 2020-06-05T18:32:44.791Z] 18:32:44 [task 2020-06-05T18:32:44.813Z] 18:32:44 [task 2020-06-05T18:32:44.814Z] 18:32:44 [task 2020-06-05T18:32:45.260Z] 18:32:45 [task 2020-06-05T18:32:45.261Z] 18:32:45 [task 2020-06-05T18:32:45.261Z] 18:32:45 [task 2020-06-05T18:32:45.262Z] 18:32:45 [task 2020-06-05T18:32:45.262Z] 18:32:45 [task 2020-06-05T18:32:45.265Z] 18:32:45 [task 2020-06-05T18:32:45.265Z] 18:32:45 [task 2020-06-05T18:32:45.282Z] 18:32:45 [task 2020-06-05T18:32:45.282Z] 18:32:45 [task 2020-06-05T18:32:45.282Z] 18:32:45 [task 2020-06-05T18:32:45.282Z] 18:32:45 [task 2020-06-05T18:32:45.282Z] 18:32:45 [task 2020-06-05T18:32:45.289Z] 18:32:45 [task 2020-06-05T18:32:45.291Z] 18:32:45 [task 2020-06-05T18:32:45.320Z] 18:32:45 [task 2020-06-05T18:32:45.320Z] 18:32:45 [task 2020-06-05T18:32:45.320Z] 18:32:45 [task 2020-06-05T18:32:45.361Z] 18:32:45 [task 2020-06-05T18:32:45.361Z] 18:32:45 [task 2020-06-05T18:32:45.381Z] 18:32:45 [task 2020-06-05T18:32:45.389Z] 18:32:45 [task 2020-06-05T18:32:45.389Z] 18:32:45 [task 2020-06-05T18:32:45.389Z] 18:32:45 [task 2020-06-05T18:32:45.406Z] 18:32:45 [task 2020-06-05T18:32:45.894Z] 18:32:45 [task 2020-06-05T18:32:45.894Z] 18:32:45 [task 2020-06-05T18:32:45.894Z] 18:32:45 [task 2020-06-05T18:32:45.895Z] 18:32:45 [task 2020-06-05T18:32:45.895Z] 18:32:45 [task 2020-06-05T18:32:45.895Z] 18:32:45 [task 2020-06-05T18:32:45.896Z] 18:32:45 [task 2020-06-05T18:32:45.897Z] 18:32:45 [task 2020-06-05T18:32:45.897Z] 18:32:45 [task 2020-06-05T18:32:45.897Z] 18:32:45 [task 2020-06-05T18:32:45.897Z] 18:32:45 [task 2020-06-05T18:32:45.898Z] 18:32:45 [task 2020-06-05T18:32:45.898Z] 18:32:45 [task 2020-06-05T18:32:45.898Z] 18:32:45 [task 2020-06-05T18:32:45.899Z] 18:32:45 [task 2020-06-05T18:32:45.899Z] 18:32:45 [task 2020-06-05T18:32:45.900Z] 18:32:45 [task 2020-06-05T18:32:45.900Z] 18:32:45 [task 2020-06-05T18:32:45.901Z] 18:32:45 [task 2020-06-05T18:32:45.901Z] 18:32:45 [task 2020-06-05T18:32:45.901Z] 18:32:45 [task 2020-06-05T18:32:45.902Z] 18:32:45 [task 2020-06-05T18:32:45.902Z] 18:32:45 [task 2020-06-05T18:32:45.902Z] 18:32:45 [task 2020-06-05T18:32:45.902Z] 18:32:45 [task 2020-06-05T18:32:45.903Z] 18:32:45 [task 2020-06-05T18:32:45.903Z] 18:32:45 [task 2020-06-05T18:32:45.903Z] 18:32:45 [task 2020-06-05T18:32:45.903Z] 18:32:45 [task 2020-06-05T18:32:45.903Z] 18:32:45 INFO - TEST-START | remote/test/browser/page/browser_frameAttached.js
INFO - GECKO(4548) | 1591381962129 RemoteAgent TRACE (connection {90096353-8f3d-4651-81a1-98f2a913152b})-> {"id":1,"method":"Target.setDiscoverTargets","params":{"discover":true}}
INFO - GECKO(4548) | 1591381962130 RemoteAgent TRACE <-(connection {90096353-8f3d-4651-81a1-98f2a913152b}) {"method":"Target.targetCreated","params":{"targetInfo":{"browserContextId":null,"targetId":"8e96eb14-1992-4c0a-957d-46c494c7f113","type":"page","url":"about:blank"}}}
INFO - GECKO(4548) | 1591381962130 RemoteAgent TRACE <-(connection {90096353-8f3d-4651-81a1-98f2a913152b}) {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"27145947-1776-467b-aa61-63170046fe35","type":"browser"}}}
INFO - GECKO(4548) | 1591381962130 RemoteAgent TRACE <-(connection {90096353-8f3d-4651-81a1-98f2a913152b}) {"method":"Target.targetCreated","params":{"targetInfo":{"browserContextId":null,"targetId":"b2ef8fe1-398a-4aad-9594-c0398183def5","type":"page","url":"about:blank"}}}
INFO - GECKO(4548) | 1591381962130 RemoteAgent TRACE <-(connection {90096353-8f3d-4651-81a1-98f2a913152b}) {"id":1}
INFO - GECKO(4548) | 1591381963246 RemoteAgent TRACE <-(connection {90096353-8f3d-4651-81a1-98f2a913152b}) {"method":"Target.targetDestroyed","params":{"targetId":"b2ef8fe1-398a-4aad-9594-c0398183def5"}}
INFO - GECKO(4548) | 1591381963260 RemoteAgent TRACE <-(connection {90096353-8f3d-4651-81a1-98f2a913152b}) {"method":"Target.targetDestroyed","params":{"targetId":"8e96eb14-1992-4c0a-957d-46c494c7f113"}}
INFO - GECKO(4548) | 1591381963260 RemoteAgent TRACE <-(connection {90096353-8f3d-4651-81a1-98f2a913152b}) {"method":"Target.targetDestroyed","params":{"targetId":"27145947-1776-467b-aa61-63170046fe35"}}
INFO - GECKO(4548) | 1591381963709 RemoteAgent TRACE (connection {70611d08-d449-4b7a-9155-38c7d19775fb})-> {"id":1,"method":"Target.setDiscoverTargets","params":{"discover":true}}
INFO - GECKO(4548) | 1591381963709 RemoteAgent TRACE <-(connection {70611d08-d449-4b7a-9155-38c7d19775fb}) {"method":"Target.targetCreated","params":{"targetInfo":{"browserContextId":null,"targetId":"98cd04c9-96b0-4402-ae76-ff622c71d083","type":"page","url":"about:blank"}}}
INFO - GECKO(4548) | 1591381963710 RemoteAgent TRACE <-(connection {70611d08-d449-4b7a-9155-38c7d19775fb}) {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"8063426b-d9d4-4513-9bbd-b864216dccbc","type":"browser"}}}
INFO - GECKO(4548) | 1591381963710 RemoteAgent TRACE <-(connection {70611d08-d449-4b7a-9155-38c7d19775fb}) {"method":"Target.targetCreated","params":{"targetInfo":{"browserContextId":null,"targetId":"03696858-222e-4c2f-a354-b29111ff2070","type":"page","url":"about:blank"}}}
INFO - GECKO(4548) | 1591381963710 RemoteAgent TRACE <-(connection {70611d08-d449-4b7a-9155-38c7d19775fb}) {"id":1}
INFO - GECKO(4548) | 1591381963712 RemoteAgent TRACE (connection {70611d08-d449-4b7a-9155-38c7d19775fb})-> {"id":2,"method":"Page.enable","params":{}}
INFO - GECKO(4548) | 1591381963714 RemoteAgent TRACE <-(connection {70611d08-d449-4b7a-9155-38c7d19775fb}) {"id":2}
INFO - GECKO(4548) | 1591381963750 RemoteAgent TRACE (connection {70611d08-d449-4b7a-9155-38c7d19775fb})-> {"id":3,"method":"Page.disable","params":{}}
INFO - GECKO(4548) | 1591381963751 RemoteAgent TRACE <-(connection {70611d08-d449-4b7a-9155-38c7d19775fb}) {"id":3}
INFO - GECKO(4548) | 1591381964789 RemoteAgent TRACE <-(connection {70611d08-d449-4b7a-9155-38c7d19775fb}) {"method":"Target.targetDestroyed","params":{"targetId":"03696858-222e-4c2f-a354-b29111ff2070"}}
INFO - GECKO(4548) | 1591381964804 RemoteAgent TRACE <-(connection {70611d08-d449-4b7a-9155-38c7d19775fb}) {"method":"Target.targetDestroyed","params":{"targetId":"98cd04c9-96b0-4402-ae76-ff622c71d083"}}
INFO - GECKO(4548) | 1591381964804 RemoteAgent TRACE <-(connection {70611d08-d449-4b7a-9155-38c7d19775fb}) {"method":"Target.targetDestroyed","params":{"targetId":"8063426b-d9d4-4513-9bbd-b864216dccbc"}}
INFO - GECKO(4548) | 1591381965255 RemoteAgent TRACE (connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597})-> {"id":1,"method":"Target.setDiscoverTargets","params":{"discover":true}}
INFO - GECKO(4548) | 1591381965256 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"method":"Target.targetCreated","params":{"targetInfo":{"browserContextId":null,"targetId":"0f1f01b2-030e-42b3-bae1-5dc264c094c5","type":"page","url":"about:blank"}}}
INFO - GECKO(4548) | 1591381965256 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"6c364f51-f5dd-4b35-afcb-da47c115bd06","type":"browser"}}}
INFO - GECKO(4548) | 1591381965256 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"method":"Target.targetCreated","params":{"targetInfo":{"browserContextId":null,"targetId":"cd6d20c2-6512-472a-bf31-bfb2c985ace2","type":"page","url":"about:blank"}}}
INFO - GECKO(4548) | 1591381965256 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"id":1}
INFO - GECKO(4548) | 1591381965257 RemoteAgent TRACE (connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597})-> {"id":2,"method":"Page.enable","params":{}}
INFO - GECKO(4548) | 1591381965258 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"id":2}
INFO - GECKO(4548) | 1591381965271 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"method":"Page.frameStartedLoading","params":{"frameId":"117"}}
INFO - GECKO(4548) | 1591381965272 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"method":"Page.frameNavigated","params":{"frame":{"id":"117","parentId":null,"url":"data:text/html;charset=utf-8,%3C!doctype ... %3D'data%3Atext%2Fhtml%2Cfoo'%3E%3C%2Fiframe%3E%0A%20%20%3Ciframe%20src%3D'data%3Atext%2Fhtml%2Cbar'%3E%3C%2Fiframe%3E%0A"}}}
INFO - GECKO(4548) | 1591381965275 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"method":"Page.frameAttached","params":{"frameId":"2147483651","parentFrameId":"117","stack":null}}
INFO - GECKO(4548) | 1591381965276 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"method":"Page.frameAttached","params":{"frameId":"2147483652","parentFrameId":"117","stack":null}}
INFO - GECKO(4548) | 1591381965276 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"method":"Page.domContentEventFired","params":{"timestamp":1591381965.274}}
INFO - GECKO(4548) | 1591381965281 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"method":"Page.frameNavigated","params":{"frame":{"id":"2147483651","parentId":null,"url":"data:text/html,foo"}}}
INFO - GECKO(4548) | 1591381965284 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"method":"Page.frameNavigated","params":{"frame":{"id":"2147483652","parentId":null,"url":"data:text/html,bar"}}}
INFO - GECKO(4548) | 1591381965315 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"method":"Page.loadEventFired","params":{"timestamp":1591381965.292}}
INFO - GECKO(4548) | 1591381965316 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"method":"Page.navigatedWithinDocument","params":{"frameId":"117","url":"data:text/html;charset=utf-8,%3C!doctype%20html%3E% ... c%3D'data%3Atext%2Fhtml%2Cfoo'%3E%3C%2Fiframe%3E%0A%20%20%3Ciframe%20src%3D'data%3Atext%2Fhtml%2Cbar'%3E%3C%2Fiframe%3E%0A"}}
INFO - GECKO(4548) | 1591381965317 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"method":"Page.frameStoppedLoading","params":{"frameId":"117"}}
INFO - GECKO(4548) | 1591381965352 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"method":"Page.frameStartedLoading","params":{"frameId":"117"}}
INFO - GECKO(4548) | 1591381965353 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"method":"Page.frameNavigated","params":{"frame":{"id":"117","parentId":null,"url":"data:text/html;charset=utf-8,%3C!doctype%20html%3E%0A%3Cmeta%20charset%3Dutf-8%3E%0A%3Cdiv%3Efoo%3C%2Fdiv%3E"}}}
INFO - GECKO(4548) | 1591381965375 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"method":"Page.domContentEventFired","params":{"timestamp":1591381965.362}}
INFO - GECKO(4548) | 1591381965383 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"method":"Page.loadEventFired","params":{"timestamp":1591381965.364}}
INFO - GECKO(4548) | 1591381965384 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"method":"Page.navigatedWithinDocument","params":{"frameId":"117","url":"data:text/html;charset=utf-8,%3C!doctype%20html%3E%0A%3Cmeta%20charset%3Dutf-8%3E%0A%3Cdiv%3Efoo%3C%2Fdiv%3E"}}
INFO - GECKO(4548) | 1591381965385 RemoteAgent TRACE <-(connection {75920357-0c0d-4e4c-8c8d-5574d5d7a597}) {"method":"Page.frameStoppedLoading","params":{"frameId":"117"}}
INFO - TEST-INFO | started process screentopng
INFO - TEST-INFO | screentopng: exit 0
INFO - Buffered messages logged at 18:32:41
INFO - Entering test bound noEventWhenPageDomainDisabled
INFO - CDP server started
INFO - Buffered messages logged at 18:32:42
INFO - CDP client instantiated
INFO - Navigate to a page with an iframe
INFO - Buffered messages logged at 18:32:43
INFO - TEST-PASS | remote/test/browser/page/browser_frameAttached.js | Got no frame attached event -
INFO - CDP client closed
INFO - CDP server stopped
INFO - Leaving test bound noEventWhenPageDomainDisabled
INFO - Entering test bound noEventAfterPageDomainDisabled
INFO - CDP server started
INFO - CDP client instantiated
INFO - Navigate to a page with an iframe
INFO - Buffered messages logged at 18:32:44
INFO - TEST-PASS | remote/test/browser/page/browser_frameAttached.js | Got no frame attached event -
INFO - CDP client closed
INFO - CDP server stopped
INFO - Leaving test bound noEventAfterPageDomainDisabled
INFO - Entering test bound noEventWhenNavigatingWithNoFrames
INFO - CDP server started
INFO - Buffered messages logged at 18:32:45
INFO - CDP client instantiated
INFO - Navigate to a page with iframes
INFO - Received Page.frameAttached for frame id 2147483651
INFO - Navigate to a page without an iframe
INFO - Buffered messages finished
INFO - TEST-UNEXPECTED-FAIL | remote/test/browser/page/browser_frameAttached.js | Got no frame attached event - Got 1, expected 0

Whimboo, could you take a look, please?

Flags: needinfo?(hskupin)

Whiteboard: [stockwell needswork:owner]

Comment hidden (Intermittent Failures Robot)

Henrik Skupin [:whimboo][⌚️UTC+2]

Assignee

Comment 8

•

4 years ago

On try we don't have shippable builds available but I hope that running the opt builds will give us an idea if my suggested solution will help here:

https://treeherder.mozilla.org/#/jobs?repo=try&revision=dcf741460ec310b818e43bc81b2acba025a2b907

Flags: needinfo?(hskupin)

Henrik Skupin [:whimboo][⌚️UTC+2]

Assignee

Comment 9

•

4 years ago

Attached file Bug 1639467 - [remote] Enable page events after loading initial page in browser_frameAttached.js. — Details

Phabricator Automation

Updated

•

4 years ago

Assignee: nobody → hskupin

Status: NEW → ASSIGNED

Henrik Skupin [:whimboo][⌚️UTC+2]

Assignee

Updated

•

4 years ago

Priority: P5 → P1

Pulsebot

Comment 10

•

4 years ago

Pushed by hskupin@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/21693836e4ec [remote] Enable page events after loading initial page in browser_frameAttached.js. r=remote-protocol-reviewers,maja_zf

Bogdan Tara[:bogdan_tara | bogdant]

Comment 11

•

4 years ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/21693836e4ec

Status: ASSIGNED → RESOLVED

Closed: 4 years ago

status-firefox79: --- → fixed

Resolution: --- → FIXED

Target Milestone: --- → Firefox 79

Comment hidden (Intermittent Failures Robot)

Bugzilla

Intermittent remote/test/browser/page/browser_frameAttached.js | Got no frame attached event - Got 1, expected 0

Categories

(Remote Protocol :: Agent, defect, P1)

Tracking

(firefox79 fixed)

People

(Reporter: intermittent-bug-filer, Assigned: whimboo)

References

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell unknown])

Crash Data

Security

(public)

User Story

Attachments

(1 file)

Description

Comment 1

Comment 2

Comment 3

Comment 4

Comment 5

Comment 6

Comment 7

Comment 8

Comment 9

Updated

Updated

Comment 10

Comment 11

Comment 12

Attachment

General

Description

File Name

Content Type