1677887 - Intermittent remote/cdp/test/browser/browser_agent.js | remote-listening observer notified - Didn't expect undefined, but got it | Uncaught exception - at chrome://*/browser_agent.js:66 - TypeError: can't access property "port", boundURL is undefined

Status: RESOLVED INCOMPLETE

(Remote Protocol :: CDP, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-11-17T23:51:59.524Z] 23:51:59     INFO - TEST-START | remote/test/browser/browser_agent.js
[task 2020-11-17T23:51:59.589Z] 23:51:59     INFO - GECKO(1513) | !!! could not start server on port 39713: [Exception... "Component returned failure code: 0x804b0036 (NS_ERROR_SOCKET_ADDRESS_IN_USE) [nsIServerSocket.init]"  nsresult: "0x804b0036 (NS_ERROR_SOCKET_ADDRESS_IN_USE)"  location: "JS frame :: chrome://remote/content/server/HTTPD.jsm :: _start :: line 567"  data: no]
[task 2020-11-17T23:51:59.596Z] 23:51:59     INFO - GECKO(1513) | 1605657119590	RemoteAgent	ERROR	unable to stop listener: [Exception... "Unexpected error"  nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)"  location: "JS frame :: chrome://remote/content/server/HTTPD.jsm :: stop :: line 621"  data: no] Stack trace: stop()@HTTPD.jsm:621
[task 2020-11-17T23:51:59.596Z] 23:51:59     INFO - GECKO(1513) | close()@RemoteAgent.jsm:127
[task 2020-11-17T23:51:59.597Z] 23:51:59     INFO - GECKO(1513) | asyncListen()@RemoteAgent.jsm:108
[task 2020-11-17T23:51:59.598Z] 23:51:59     INFO - GECKO(1513) | 1605657119591	RemoteAgent	ERROR	Unable to start remote agent: : [Exception... "Component is not available"  nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)"  location: "JS frame :: chrome://remote/content/server/HTTPD.jsm :: _start :: line 612"  data: no] Stack trace: _start()@HTTPD.jsm:612
[task 2020-11-17T23:51:59.598Z] 23:51:59     INFO - GECKO(1513) | asyncListen()@RemoteAgent.jsm:101
[task 2020-11-17T23:51:59.599Z] 23:51:59     INFO - TEST-INFO | started process screentopng
[task 2020-11-17T23:52:00.120Z] 23:52:00     INFO - TEST-INFO | screentopng: exit 0
[task 2020-11-17T23:52:00.121Z] 23:52:00     INFO - Buffered messages logged at 23:51:59
[task 2020-11-17T23:52:00.121Z] 23:52:00     INFO - Entering test bound debuggerAddress
[task 2020-11-17T23:52:00.122Z] 23:52:00     INFO - TEST-PASS | remote/test/browser/browser_agent.js | debuggerAddress set - 
[task 2020-11-17T23:52:00.122Z] 23:52:00     INFO - Leaving test bound debuggerAddress
[task 2020-11-17T23:52:00.123Z] 23:52:00     INFO - Entering test bound listening
[task 2020-11-17T23:52:00.124Z] 23:52:00     INFO - TEST-PASS | remote/test/browser/browser_agent.js | Agent is not listening - 
[task 2020-11-17T23:52:00.124Z] 23:52:00     INFO - TEST-PASS | remote/test/browser/browser_agent.js | Agent is listening - 
[task 2020-11-17T23:52:00.125Z] 23:52:00     INFO - Leaving test bound listening
[task 2020-11-17T23:52:00.125Z] 23:52:00     INFO - Entering test bound listen
[task 2020-11-17T23:52:00.126Z] 23:52:00     INFO - Buffered messages finished
[task 2020-11-17T23:52:00.126Z] 23:52:00     INFO - TEST-UNEXPECTED-FAIL | remote/test/browser/browser_agent.js | remote-listening observer notified - Didn't expect undefined, but got it
[task 2020-11-17T23:52:00.126Z] 23:52:00     INFO - Stack trace:
[task 2020-11-17T23:52:00.126Z] 23:52:00     INFO - chrome://mochikit/content/browser-test.js:test_isnot:1341
[task 2020-11-17T23:52:00.126Z] 23:52:00     INFO - chrome://mochitests/content/browser/remote/test/browser/browser_agent.js:listen:64
[task 2020-11-17T23:52:00.127Z] 23:52:00     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-11-17T23:52:00.127Z] 23:52:00     INFO - TEST-UNEXPECTED-FAIL | remote/test/browser/browser_agent.js | Uncaught exception - at chrome://mochitests/content/browser/remote/test/browser/browser_agent.js:66 - TypeError: can't access property "port", boundURL is undefined
[task 2020-11-17T23:52:00.127Z] 23:52:00     INFO - Stack trace:
[task 2020-11-17T23:52:00.128Z] 23:52:00     INFO - listen@chrome://mochitests/content/browser/remote/test/browser/browser_agent.js:66:1
[task 2020-11-17T23:52:00.129Z] 23:52:00     INFO - Leaving test bound listen
[task 2020-11-17T23:52:00.130Z] 23:52:00     INFO - Entering test bound listenWhenDisabled
[task 2020-11-17T23:52:00.131Z] 23:52:00     INFO - TEST-PASS | remote/test/browser/browser_agent.js | undefined assertion name - 
[task 2020-11-17T23:52:00.133Z] 23:52:00     INFO - TEST-PASS | remote/test/browser/browser_agent.js | undefined assertion name - 
[task 2020-11-17T23:52:00.134Z] 23:52:00     INFO - Leaving test bound listenWhenDisabled
[task 2020-11-17T23:52:00.134Z] 23:52:00     INFO - Entering test bound listenTakesString
[task 2020-11-17T23:52:00.134Z] 23:52:00     INFO - Leaving test bound listenTakesString
[task 2020-11-17T23:52:00.134Z] 23:52:00     INFO - Entering test bound listenNonURL
[task 2020-11-17T23:52:00.134Z] 23:52:00     INFO - TEST-PASS | remote/test/browser/browser_agent.js | undefined assertion name - 
[task 2020-11-17T23:52:00.134Z] 23:52:00     INFO - Leaving test bound listenNonURL
[task 2020-11-17T23:52:00.135Z] 23:52:00     INFO - Entering test bound listenRestrictedToLoopbackDevice
[task 2020-11-17T23:52:00.135Z] 23:52:00     INFO - TEST-PASS | remote/test/browser/browser_agent.js | undefined assertion name - 
[task 2020-11-17T23:52:00.135Z] 23:52:00     INFO - TEST-PASS | remote/test/browser/browser_agent.js | undefined assertion name - 
[task 2020-11-17T23:52:00.135Z] 23:52:00     INFO - Leaving test bound listenRestrictedToLoopbackDevice
[task 2020-11-17T23:52:00.135Z] 23:52:00     INFO - Entering test bound listenNonLoopbackDevice
[task 2020-11-17T23:52:00.135Z] 23:52:00     INFO - Leaving test bound listenNonLoopbackDevice
[task 2020-11-17T23:52:00.135Z] 23:52:00     INFO - Entering test bound test_close
[task 2020-11-17T23:52:00.136Z] 23:52:00     INFO - Leaving test bound test_close
[task 2020-11-17T23:52:00.136Z] 23:52:00     INFO - GECKO(1513) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2020-11-17T23:52:00.136Z] 23:52:00     INFO - GECKO(1513) | MEMORY STAT | vsize 2950MB | residentFast 296MB | heapAllocated 110MB
[task 2020-11-17T23:52:00.136Z] 23:52:00     INFO - TEST-OK | remote/test/browser/browser_agent.js | took 145ms```

Comment 1

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

boundURL is being constructed when the remote-listening notification is received. But that isn't getting send because the Remote Agent could not be initialized after the previous call to close the connection for the former test failed:

[task 2020-11-17T23:51:59.596Z] 23:51:59     INFO - GECKO(1513) | 1605657119590	RemoteAgent	ERROR	unable to stop listener: [Exception... "Unexpected error"  nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)"  location: "JS frame :: chrome://remote/content/server/HTTPD.jsm :: stop :: line 621"  data: no] Stack trace: stop()@HTTPD.jsm:621
[task 2020-11-17T23:51:59.596Z] 23:51:59     INFO - GECKO(1513) | close()@RemoteAgent.jsm:127

That means that close() doesn't correctly return exceptions, which would make the test failure message better observable.

It actually requires a fix for bug 1598832.

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 2

Platform breakdown:

• linux1804-64-shippable: 2

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• linux1804-64: 2

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

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux1804-64: 1

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

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64: 1
  • opt: 1

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

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-release: 1

Platform and build breakdown:

• linux1804-64-shippable: 1
  • opt: 1

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

Comment 7

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

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