1866171 - Intermittent Wd TEST-UNEXPECTED-TIMEOUT | waittask specs Frame.waitForSelector should survive cross-process navigation (waittask.spec.js) | expected PASS

Status: RESOLVED INCOMPLETE

(Remote Protocol :: WebDriver BiDi, defect, P5)

Description

[Treeherder Bug Filer]

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

---

PID 656 | 1700692323137	RemoteAgent	DEBUG	WebDriverBiDiConnection 8fdd4448-b114-46f2-af0b-946de6ec39ed <- {"type":"event","method":"network.responseStarted","params":{"context":"6060355b-d30e-49a8-908d-a27087620d83","isBlocked":false,"navigation":null,"redirectCount":0,"request":{"bodySize":null,"cookies":[],"headers":[{"name":"Host","value":{"type":"string","value":"127.0.0.1:43879"}},{"name":"User-Agent","value":{"type":"string","value":"Mozilla/5.0 (X11; Linux x86_64; rv:122.0) Gecko/20100101 Firefox/122.0"}},{"name":"Accept","value":{"type":"string","value":"image/avif,image/webp,*/*"}},{"name":"Accept-Language","value":{"type":"string","value":"en-US,en;q=0.5"}},{"name":"Accept-Encoding","value":{"type":"string","value":"gzip, deflate, br"}},{"name":"Referer","value":{"type":"string","value":"http://127.0.0.1:43879/grid.html"}},{"name":"Connection","value":{"type":"string","value":"keep-alive"}},{"name":"Sec-Fetch-Dest","value":{"type":"string","value":"image"}},{"name":"Sec-Fetch-Mode","value":{"type":"string","value":"no-cors"}},{"name":"Sec-Fetch-Site","value":{"type":"string","value":"same-origin"}}],"headersSize":0,"method":"GET","request":"1151","timings":{"timeOrigin":0,"requestTime":1700692323073949,"redirectStart":0,"redirectEnd":0,"fetchStart":1700692323126278,"dnsStart":0,"dnsEnd":0,"connectStart":0,"connectEnd":0,"tlsStart":0,"tlsEnd":0,"requestStart":0,"responseStart":0,"responseEnd":0},"url":"http://127.0.0.1:43879/favicon.ico"},"timestamp":1700692323137,"response":{"bodySize":0,"content":{"size":null},"headersSize":0,"url":"http://127.0.0.1:43879/favicon.ico","bytesReceived":0,"fromCache":true,"headers":[{"name":"Cache-Control","value":{"type":"string","value":"no-cache, no-store"}},{"name":"Content-Type","value":{"type":"string","value":"image/vnd.microsoft.icon"}},{"name":"Date","value":{"type":"string","value":"Wed, 22 Nov 2023 22:26:20 GMT"}},{"name":"Connection","value":{"type":"string","value":"keep-alive"}},{"name":"Content-Length","value":{"type":"string","value":"70"}}],"mimeType":"image/vnd.microsoft.icon","protocol":"http/1.1","status":200,"statusText":"OK"}}}
[task 2023-11-22T22:32:03.138Z] PID 656 | 1700692323138	RemoteAgent	TRACE	[652] NavigationListener onStateChange, stateFlags: 131088, status: 0, isStart: false, isStop: true, isNetwork: false, isBindingAborted: false, targetURI: http://127.0.0.1:43879/grid.html
[task 2023-11-22T22:32:03.140Z] PID 656 | 1700692323139	RemoteAgent	TRACE	[6060355b-d30e-49a8-908d-a27087620d83] Navigation finished for url: http://127.0.0.1:43879/grid.html (add13108-4bfd-4754-b6eb-a9d5abd5a81f)
[task 2023-11-22T22:32:03.140Z] PID 656 | 1700692323140	RemoteAgent	DEBUG	WebDriverBiDiConnection 8fdd4448-b114-46f2-af0b-946de6ec39ed <- {"type":"event","method":"browsingContext.load","params":{"context":"6060355b-d30e-49a8-908d-a27087620d83","timestamp":1700692323139,"url":"http://127.0.0.1:43879/grid.html","navigation":"add13108-4bfd-4754-b6eb-a9d5abd5a81f"}}
[task 2023-11-22T22:32:03.142Z] PID 656 | 1700692323141	RemoteAgent	TRACE	[652] ProgressListener Check loading state: isStart=0 isStop=16
[task 2023-11-22T22:32:03.142Z] PID 656 | 1700692323141	RemoteAgent	TRACE	[652] ProgressListener state=stop: http://127.0.0.1:43879/grid.html
[task 2023-11-22T22:32:03.142Z] PID 656 | 1700692323141	RemoteAgent	TRACE	[652] ProgressListener Stop: has error=false
[task 2023-11-22T22:32:03.142Z] PID 656 | 1700692323142	RemoteAgent	DEBUG	WebDriverBiDiConnection 8fdd4448-b114-46f2-af0b-946de6ec39ed <- {"type":"success","id":17870,"result":{"navigation":"add13108-4bfd-4754-b6eb-a9d5abd5a81f","url":"http://127.0.0.1:43879/grid.html"}}
[task 2023-11-22T22:32:03.143Z] PID 656 | 1700692323143	RemoteAgent	DEBUG	WebDriverBiDiConnection 8fdd4448-b114-46f2-af0b-946de6ec39ed <- {"type":"event","method":"network.responseCompleted","params":{"context":"6060355b-d30e-49a8-908d-a27087620d83","isBlocked":false,"navigation":null,"redirectCount":0,"request":{"bodySize":null,"cookies":[],"headers":[{"name":"Host","value":{"type":"string","value":"127.0.0.1:43879"}},{"name":"User-Agent","value":{"type":"string","value":"Mozilla/5.0 (X11; Linux x86_64; rv:122.0) Gecko/20100101 Firefox/122.0"}},{"name":"Accept","value":{"type":"string","value":"image/avif,image/webp,*/*"}},{"name":"Accept-Language","value":{"type":"string","value":"en-US,en;q=0.5"}},{"name":"Accept-Encoding","value":{"type":"string","value":"gzip, deflate, br"}},{"name":"Referer","value":{"type":"string","value":"http://127.0.0.1:43879/grid.html"}},{"name":"Connection","value":{"type":"string","value":"keep-alive"}},{"name":"Sec-Fetch-Dest","value":{"type":"string","value":"image"}},{"name":"Sec-Fetch-Mode","value":{"type":"string","value":"no-cors"}},{"name":"Sec-Fetch-Site","value":{"type":"string","value":"same-origin"}}],"headersSize":0,"method":"GET","request":"1151","timings":{"timeOrigin":0,"requestTime":1700692323073949,"redirectStart":0,"redirectEnd":0,"fetchStart":1700692323126278,"dnsStart":0,"dnsEnd":0,"connectStart":0,"connectEnd":0,"tlsStart":0,"tlsEnd":0,"requestStart":0,"responseStart":0,"responseEnd":0},"url":"http://127.0.0.1:43879/favicon.ico"},"timestamp":1700692323142,"response":{"bodySize":70,"content":{"size":70},"headersSize":0,"url":"http://127.0.0.1:43879/favicon.ico","bytesReceived":70,"fromCache":true,"headers":[{"name":"Cache-Control","value":{"type":"string","value":"no-cache, no-store"}},{"name":"Content-Type","value":{"type":"string","value":"image/vnd.microsoft.icon"}},{"name":"Date","value":{"type":"string","value":"Wed, 22 Nov 2023 22:26:20 GMT"}},{"name":"Connection","value":{"type":"string","value":"keep-alive"}},{"name":"Content-Length","value":{"type":"string","value":"70"}}],"mimeType":"image/vnd.microsoft.icon","protocol":"http/1.1","status":200,"statusText":"OK"}}}
[task 2023-11-22T22:32:22.609Z] TEST-UNEXPECTED-TIMEOUT | waittask specs Frame.waitForSelector should survive cross-process navigation (waittask.spec.js) | expected PASS
[task 2023-11-22T22:32:22.609Z] TEST-INFO took 20002ms
[task 2023-11-22T22:32:22.610Z] PID 656 | ["fail",{"title":"should survive cross-process navigation","fullTitle":"waittask specs Frame.waitForSelector should survive cross-process navigation","file":"/builds/worker/checkouts/gecko/remote/test/puppeteer/test/build/waittask.spec.js","duration":20004,"currentRetry":0,"err":"Timeout of 20000ms exceeded. For async tests and hooks, ensure \"done()\" is called; if returning a Promise, ensure it resolves. (/builds/worker/checkouts/gecko/remote/test/puppeteer/test/build/waittask.spec.js)","stack":"Error: Timeout of 20000ms exceeded. For async tests and hooks, ensure \"done()\" is called; if returning a Promise, ensure it resolves. (/builds/worker/checkouts/gecko/remote/test/puppeteer/test/build/waittask.spec.js)\n    at createTimeoutError (/builds/worker/checkouts/gecko/remote/test/puppeteer/node_modules/mocha/lib/errors.js:498:15)\n    at Test.Runnable._timeoutError (/builds/worker/checkouts/gecko/remote/test/puppeteer/node_modules/mocha/lib/runnable.js:431:10)\n    at Timeout.<anonymous> (/builds/worker/checkouts/gecko/remote/test/puppeteer/node_modules/mocha/lib/runnable.js:246:24)\n    at listOnTimeout (node:internal/timers:559:17)\n    at processTimers (node:internal/timers:502:7)"}]
[task 2023-11-22T22:32:22.610Z] TEST-START | waittask specs Frame.waitForSelector should wait for element to be visible (display) (waittask.spec.js)
[task 2023-11-22T22:32:22.611Z] PID 656 | ["test-start",{"title":"should wait for element to be visible (display)","fullTitle":"waittask specs Frame.waitForSelector should wait for element to be visible (display)","file":"/builds/worker/checkouts/gecko/remote/test/puppeteer/test/build/waittask.spec.js","currentRetry":0}]
[task 2023-11-22T22:32:22.611Z] PID 656 | 1700692342611	RemoteAgent	DEBUG	WebDriverBiDiConnection 8fdd4448-b114-46f2-af0b-946de6ec39ed -> {"id":17877,"method":"browsingContext.close","params":{"context":"6060355b-d30e-49a8-908d-a27087620d83"}}
[task 2023-11-22T22:32:22.612Z] PID 656 | 1700692342611	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2023-11-22T22:32:22.612Z] PID 656 | 1700692342611	RemoteAgent	TRACE	Received command browsingContext.close for destination ROOT

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3

Platform and build breakdown:

• linux1804-64: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1866171&startday=2023-11-20&endday=2023-11-26&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4
• mozilla-central: 2

Platform and build breakdown:

• linux1804-64: 6
  • opt: 6

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1866171&startday=2023-11-27&endday=2023-12-03&tree=all

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3

Platform and build breakdown:

• linux1804-64: 3
  • opt: 3

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

Comment 4

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

It's an intermittent failure that only occurs with the WebDriver Bidi protodol enabled.

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3

Platform and build breakdown:

• linux1804-64: 3
  • opt: 3

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

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• pine: 1
• autoland: 6
• mozilla-beta: 2
• try: 2
• mozilla-central: 2

Platform and build breakdown:

• linux1804-64: 13
  • opt: 13

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

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 3
• autoland: 3

Platform and build breakdown:

• linux1804-64: 6
  • opt: 6

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1866171&startday=2023-12-25&endday=2023-12-31&tree=all

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 5
• mozilla-central: 2

Platform and build breakdown:

• linux1804-64: 7
  • opt: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1866171&startday=2024-01-01&endday=2024-01-07&tree=all

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1
• mozilla-central: 3
• autoland: 2

Platform and build breakdown:

• linux1804-64: 6
  • opt: 6

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1866171&startday=2024-01-08&endday=2024-01-14&tree=all

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 5
• mozilla-central: 1
• pine: 1

Platform and build breakdown:

• linux1804-64: 7
  • opt: 7

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

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

• linux1804-64: 1
  • opt: 1

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

Comment 12

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1
• mozilla-beta: 3

Platform and build breakdown:

• linux1804-64: 4
  • opt: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1866171&startday=2024-01-29&endday=2024-02-04&tree=all

Comment 13

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 2

Platform and build breakdown:

• linux1804-64: 2
  • opt: 2

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

Comment 14

[Intermittent Failures Robot]

1 failures in 4198 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: 1
  • opt: 1

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