1633042 - Intermittent remote/test/browser/page/browser_navigate.js | Uncaught exception - [Exception... "(null)" nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)" location: "<unknown>" data: no]

Status: RESOLVED FIXED

(Remote Protocol :: Agent, defect, P1)

Description

[Treeherder Bug Filer]

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

---

[task 2020-04-24T22:35:13.491Z] 22:35:13     INFO - TEST-OK | remote/test/browser/page/browser_lifecycleEvent.js | took 4992ms
[task 2020-04-24T22:35:13.526Z] 22:35:13     INFO - checking window state
[task 2020-04-24T22:35:13.526Z] 22:35:13     INFO - TEST-START | remote/test/browser/page/browser_navigate.js
[task 2020-04-24T22:35:14.046Z] 22:35:14     INFO - GECKO(1837) | 1587767714009	RemoteAgent	TRACE	(connection {1c3b7ba3-14d1-5c46-9a81-2d2f6141ab8f})-> {"id":1,"method":"Target.setDiscoverTargets","params":{"discover":true}}
[task 2020-04-24T22:35:14.049Z] 22:35:14     INFO - GECKO(1837) | 1587767714009	RemoteAgent	TRACE	<-(connection {1c3b7ba3-14d1-5c46-9a81-2d2f6141ab8f}) {"method":"Target.targetCreated","params":{"targetInfo":{"browserContextId":null,"targetId":188,"type":"page","url":"about:blank"}}}
[task 2020-04-24T22:35:14.051Z] 22:35:14     INFO - GECKO(1837) | 1587767714009	RemoteAgent	TRACE	<-(connection {1c3b7ba3-14d1-5c46-9a81-2d2f6141ab8f}) {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"81cd37a3-e19c-6c4f-a2d0-f319bd40ff77","type":"browser"}}}
[task 2020-04-24T22:35:14.052Z] 22:35:14     INFO - GECKO(1837) | 1587767714010	RemoteAgent	TRACE	<-(connection {1c3b7ba3-14d1-5c46-9a81-2d2f6141ab8f}) {"method":"Target.targetCreated","params":{"targetInfo":{"browserContextId":null,"targetId":191,"type":"page","url":"about:blank"}}}
[task 2020-04-24T22:35:14.052Z] 22:35:14     INFO - GECKO(1837) | 1587767714010	RemoteAgent	TRACE	<-(connection {1c3b7ba3-14d1-5c46-9a81-2d2f6141ab8f}) {"id":1}
[task 2020-04-24T22:35:14.052Z] 22:35:14     INFO - GECKO(1837) | 1587767714011	RemoteAgent	TRACE	(connection {1c3b7ba3-14d1-5c46-9a81-2d2f6141ab8f})-> {"id":2,"method":"Page.enable","params":{}}
[task 2020-04-24T22:35:14.052Z] 22:35:14     INFO - GECKO(1837) | 1587767714012	RemoteAgent	TRACE	<-(connection {1c3b7ba3-14d1-5c46-9a81-2d2f6141ab8f}) {"id":2}
[task 2020-04-24T22:35:14.052Z] 22:35:14     INFO - GECKO(1837) | 1587767714013	RemoteAgent	TRACE	(connection {1c3b7ba3-14d1-5c46-9a81-2d2f6141ab8f})-> {"id":3,"method":"Page.navigate","params":{"url":"http://example.com/browser/remote/test/browser/page/doc_empty.html"}}
[task 2020-04-24T22:35:14.052Z] 22:35:14     INFO - GECKO(1837) | 1587767714018	RemoteAgent	TRACE	<-(connection {1c3b7ba3-14d1-5c46-9a81-2d2f6141ab8f}) {"id":3,"result":{"frameId":"191"}}
[task 2020-04-24T22:35:14.052Z] 22:35:14     INFO - GECKO(1837) | 1587767714018	RemoteAgent	TRACE	<-(connection {1c3b7ba3-14d1-5c46-9a81-2d2f6141ab8f}) {"method":"Page.frameStartedLoading","params":{"frameId":"191"}}
[task 2020-04-24T22:35:14.052Z] 22:35:14     INFO - GECKO(1837) | 1587767714021	RemoteAgent	TRACE	<-(connection {1c3b7ba3-14d1-5c46-9a81-2d2f6141ab8f}) {"method":"Page.frameNavigated","params":{"frame":{"id":"191","parentId":null,"url":"http://example.com/browser/remote/test/browser/page/doc_empty.html"}}}
[task 2020-04-24T22:35:14.052Z] 22:35:14     INFO - GECKO(1837) | 1587767714034	RemoteAgent	TRACE	<-(connection {1c3b7ba3-14d1-5c46-9a81-2d2f6141ab8f}) {"method":"Page.domContentEventFired","params":{"timestamp":1587767714025}}
[task 2020-04-24T22:35:14.052Z] 22:35:14     INFO - GECKO(1837) | 1587767714034	RemoteAgent	TRACE	<-(connection {1c3b7ba3-14d1-5c46-9a81-2d2f6141ab8f}) {"method":"Page.loadEventFired","params":{"timestamp":1587767714025}}
[task 2020-04-24T22:35:14.054Z] 22:35:14     INFO - GECKO(1837) | 1587767714035	RemoteAgent	TRACE	<-(connection {1c3b7ba3-14d1-5c46-9a81-2d2f6141ab8f}) {"method":"Page.navigatedWithinDocument","params":{"frameId":"191","url":"http://example.com/browser/remote/test/browser/page/doc_empty.html"}}
[task 2020-04-24T22:35:14.054Z] 22:35:14     INFO - GECKO(1837) | 1587767714035	RemoteAgent	TRACE	<-(connection {1c3b7ba3-14d1-5c46-9a81-2d2f6141ab8f}) {"method":"Page.frameStoppedLoading","params":{"frameId":"191"}}
[task 2020-04-24T22:35:14.054Z] 22:35:14     INFO - GECKO(1837) | 1587767714045	RemoteAgent	TRACE	<-(connection {1c3b7ba3-14d1-5c46-9a81-2d2f6141ab8f}) {"method":"Target.targetDestroyed","params":{"targetId":191}}
[task 2020-04-24T22:35:14.096Z] 22:35:14     INFO - GECKO(1837) | 1587767714066	RemoteAgent	TRACE	<-(connection {1c3b7ba3-14d1-5c46-9a81-2d2f6141ab8f}) {"method":"Target.targetDestroyed","params":{"targetId":188}}
[task 2020-04-24T22:35:14.096Z] 22:35:14     INFO - GECKO(1837) | 1587767714066	RemoteAgent	TRACE	<-(connection {1c3b7ba3-14d1-5c46-9a81-2d2f6141ab8f}) {"method":"Target.targetDestroyed","params":{"targetId":"81cd37a3-e19c-6c4f-a2d0-f319bd40ff77"}}
[task 2020-04-24T22:35:14.597Z] 22:35:14     INFO - GECKO(1837) | 1587767714570	RemoteAgent	TRACE	(connection {a93c0e70-1d51-6246-a959-7cdf4522b069})-> {"id":1,"method":"Target.setDiscoverTargets","params":{"discover":true}}
[task 2020-04-24T22:35:14.597Z] 22:35:14     INFO - GECKO(1837) | 1587767714570	RemoteAgent	TRACE	<-(connection {a93c0e70-1d51-6246-a959-7cdf4522b069}) {"method":"Target.targetCreated","params":{"targetInfo":{"browserContextId":null,"targetId":188,"type":"page","url":"about:blank"}}}
[task 2020-04-24T22:35:14.597Z] 22:35:14     INFO - GECKO(1837) | 1587767714570	RemoteAgent	TRACE	<-(connection {a93c0e70-1d51-6246-a959-7cdf4522b069}) {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"0eb8b7ab-26fc-544c-b61e-f13b8ed208df","type":"browser"}}}
[task 2020-04-24T22:35:14.597Z] 22:35:14     INFO - GECKO(1837) | 1587767714570	RemoteAgent	TRACE	<-(connection {a93c0e70-1d51-6246-a959-7cdf4522b069}) {"method":"Target.targetCreated","params":{"targetInfo":{"browserContextId":null,"targetId":194,"type":"page","url":"about:blank"}}}
[task 2020-04-24T22:35:14.598Z] 22:35:14     INFO - GECKO(1837) | 1587767714571	RemoteAgent	TRACE	<-(connection {a93c0e70-1d51-6246-a959-7cdf4522b069}) {"id":1}
[task 2020-04-24T22:35:14.598Z] 22:35:14     INFO - GECKO(1837) | 1587767714571	RemoteAgent	TRACE	(connection {a93c0e70-1d51-6246-a959-7cdf4522b069})-> {"id":2,"method":"Page.enable","params":{}}
[task 2020-04-24T22:35:14.598Z] 22:35:14     INFO - GECKO(1837) | 1587767714572	RemoteAgent	TRACE	<-(connection {a93c0e70-1d51-6246-a959-7cdf4522b069}) {"id":2}
[task 2020-04-24T22:35:14.598Z] 22:35:14     INFO - GECKO(1837) | 1587767714573	RemoteAgent	TRACE	(connection {a93c0e70-1d51-6246-a959-7cdf4522b069})-> {"id":3,"method":"Page.navigate","params":{"url":"http://example.com/browser/remote/test/browser/page/doc_empty.html"}}
[task 2020-04-24T22:35:14.598Z] 22:35:14     INFO - GECKO(1837) | 1587767714578	RemoteAgent	TRACE	<-(connection {a93c0e70-1d51-6246-a959-7cdf4522b069}) {"id":3,"result":{"frameId":"194"}}
<...>
[task 2020-04-24T22:35:19.291Z] 22:35:19     INFO - TEST-PASS | remote/test/browser/page/browser_navigate.js | Page.navigate returns frameId - 
[task 2020-04-24T22:35:19.295Z] 22:35:19     INFO - TEST-FAIL | remote/test/browser/page/browser_navigate.js | Page.navigate returns loaderId - 
[task 2020-04-24T22:35:19.295Z] 22:35:19     INFO - TEST-PASS | remote/test/browser/page/browser_navigate.js | Failed navigation returns errorText - 
[task 2020-04-24T22:35:19.295Z] 22:35:19     INFO - CDP client closed
[task 2020-04-24T22:35:19.296Z] 22:35:19     INFO - CDP server stopped
[task 2020-04-24T22:35:19.296Z] 22:35:19     INFO - Leaving test bound testUnknownCertificate
[task 2020-04-24T22:35:19.296Z] 22:35:19     INFO - Entering test bound testNotFound
[task 2020-04-24T22:35:19.296Z] 22:35:19     INFO - CDP server started
[task 2020-04-24T22:35:19.296Z] 22:35:19     INFO - CDP client instantiated
[task 2020-04-24T22:35:19.296Z] 22:35:19     INFO - TEST-PASS | remote/test/browser/page/browser_navigate.js | Page.navigate returns frameId - 
[task 2020-04-24T22:35:19.297Z] 22:35:19     INFO - TEST-FAIL | remote/test/browser/page/browser_navigate.js | Page.navigate returns loaderId - 
[task 2020-04-24T22:35:19.297Z] 22:35:19     INFO - TEST-PASS | remote/test/browser/page/browser_navigate.js | No errorText on a 404 - 
[task 2020-04-24T22:35:19.297Z] 22:35:19     INFO - CDP client closed
[task 2020-04-24T22:35:19.297Z] 22:35:19     INFO - CDP server stopped
[task 2020-04-24T22:35:19.297Z] 22:35:19     INFO - Leaving test bound testNotFound
[task 2020-04-24T22:35:19.297Z] 22:35:19     INFO - Entering test bound testInvalidURL
[task 2020-04-24T22:35:19.298Z] 22:35:19     INFO - CDP server started
[task 2020-04-24T22:35:19.298Z] 22:35:19     INFO - Buffered messages logged at 22:35:18
[task 2020-04-24T22:35:19.298Z] 22:35:19     INFO - CDP client instantiated
[task 2020-04-24T22:35:19.298Z] 22:35:19     INFO - TEST-PASS | remote/test/browser/page/browser_navigate.js | Invalid url blah.com causes error - 
[task 2020-04-24T22:35:19.298Z] 22:35:19     INFO - TEST-PASS | remote/test/browser/page/browser_navigate.js | Invalid url foo causes error - 
[task 2020-04-24T22:35:19.298Z] 22:35:19     INFO - TEST-PASS | remote/test/browser/page/browser_navigate.js | Invalid url https
[task 2020-04-24T22:35:19.298Z] 22:35:19     INFO - // causes error - 
[task 2020-04-24T22:35:19.299Z] 22:35:19     INFO - TEST-PASS | remote/test/browser/page/browser_navigate.js | Invalid url http causes error - 
[task 2020-04-24T22:35:19.299Z] 22:35:19     INFO - TEST-PASS | remote/test/browser/page/browser_navigate.js | Invalid url  causes error - 
[task 2020-04-24T22:35:19.299Z] 22:35:19     INFO - TEST-PASS | remote/test/browser/page/browser_navigate.js | Invalid url 2 causes error - 
[task 2020-04-24T22:35:19.299Z] 22:35:19     INFO - TEST-PASS | remote/test/browser/page/browser_navigate.js | Invalid url [object Object] causes error - 
[task 2020-04-24T22:35:19.301Z] 22:35:19     INFO - TEST-PASS | remote/test/browser/page/browser_navigate.js | Invalid url true causes error - 
[task 2020-04-24T22:35:19.302Z] 22:35:19     INFO - CDP client closed
[task 2020-04-24T22:35:19.302Z] 22:35:19     INFO - CDP server stopped
[task 2020-04-24T22:35:19.302Z] 22:35:19     INFO - Leaving test bound testInvalidURL
[task 2020-04-24T22:35:19.302Z] 22:35:19     INFO - Entering test bound testDataURL
[task 2020-04-24T22:35:19.302Z] 22:35:19     INFO - CDP server started
[task 2020-04-24T22:35:19.303Z] 22:35:19     INFO - CDP client instantiated
[task 2020-04-24T22:35:19.303Z] 22:35:19     INFO - TEST-PASS | remote/test/browser/page/browser_navigate.js | Page.navigate returns expected frameId - 
[task 2020-04-24T22:35:19.303Z] 22:35:19     INFO - TEST-PASS | remote/test/browser/page/browser_navigate.js | No errorText on a successful navigation - 
[task 2020-04-24T22:35:19.303Z] 22:35:19     INFO - TEST-FAIL | remote/test/browser/page/browser_navigate.js | Page.navigate returns loaderId - 
[task 2020-04-24T22:35:19.303Z] 22:35:19     INFO - TEST-PASS | remote/test/browser/page/browser_navigate.js | Expected URL loaded - 
[task 2020-04-24T22:35:19.303Z] 22:35:19     INFO - CDP client closed
[task 2020-04-24T22:35:19.303Z] 22:35:19     INFO - CDP server stopped
[task 2020-04-24T22:35:19.304Z] 22:35:19     INFO - Leaving test bound testDataURL
[task 2020-04-24T22:35:19.304Z] 22:35:19     INFO - Entering test bound testAbout
[task 2020-04-24T22:35:19.304Z] 22:35:19     INFO - CDP server started
[task 2020-04-24T22:35:19.304Z] 22:35:19     INFO - Buffered messages logged at 22:35:19
[task 2020-04-24T22:35:19.304Z] 22:35:19     INFO - CDP client instantiated
[task 2020-04-24T22:35:19.312Z] 22:35:19     INFO - TEST-FAIL | remote/test/browser/page/browser_navigate.js | Page.navigate returns loaderId - 
[task 2020-04-24T22:35:19.312Z] 22:35:19     INFO - TEST-PASS | remote/test/browser/page/browser_navigate.js | No errorText on a successful navigation - 
[task 2020-04-24T22:35:19.312Z] 22:35:19     INFO - CDP client closed
[task 2020-04-24T22:35:19.312Z] 22:35:19     INFO - CDP server stopped
[task 2020-04-24T22:35:19.312Z] 22:35:19     INFO - Buffered messages finished
[task 2020-04-24T22:35:19.312Z] 22:35:19     INFO - TEST-UNEXPECTED-FAIL | remote/test/browser/page/browser_navigate.js | Uncaught exception - [Exception... "(null)"  nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)"  location: "<unknown>"  data: no]
[task 2020-04-24T22:35:19.313Z] 22:35:19     INFO - Leaving test bound testAbout
[task 2020-04-24T22:35:19.313Z] 22:35:19     INFO - GECKO(1837) | MEMORY STAT | vsize 7845MB | residentFast 492MB | heapAllocated 213MB
[task 2020-04-24T22:35:19.313Z] 22:35:19     INFO - TEST-OK | remote/test/browser/page/browser_navigate.js | took 5635ms
[task 2020-04-24T22:35:19.313Z] 22:35:19     INFO - checking window state
[task 2020-04-24T22:35:19.313Z] 22:35:19     INFO - TEST-START | remote/test/browser/page/browser_navigateToHistoryEntry.js

Comment 1

[Alexandru Michis [:malexandru]]

Maja, TV's show this is a regression from Bug 1599257, could you please take a look?
https://treeherder.mozilla.org/#/jobs?repo=autoland&resultStatus=testfailed%2Cbusted%2Cexception%2Crunnable&revision=a1152a9c5dc53f54cb578b8a64289905a883100a&selectedJob=299314372

Comment 2

[Maja Frydrychowicz :impossibus (was :maja_zf) (needinfo me)]

Attachment: Bug 1633042 - [remote] Compare frame id after load event

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 25
• mozilla-central: 1

Platform breakdown:

• macosx1014-64: 6
• linux1804-64-shippable: 3
• windows7-32-shippable: 1
• macosx1014-64-shippable: 10
• linux1804-64: 2
• windows7-32: 1
• linux1804-64-qr: 2
• windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1633042&startday=2020-04-20&endday=2020-04-26&tree=all

Comment 4

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/ad1798b8b351
[remote] Compare frame id after load event r=remote-protocol-reviewers,whimboo

Comment 5

[Stefan Hindli [:stefan_hindli]]

https://hg.mozilla.org/mozilla-central/rev/ad1798b8b351

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4
• mozilla-central: 1

Platform breakdown:

• macosx1014-64: 3
• macosx1014-64-shippable: 2

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