1694292 - Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/take_screenshot/iframe.py | expected OK

Status: RESOLVED DUPLICATE of bug 1676582

(Remote Protocol :: Marionette, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2021-02-22T23:48:58.693Z] 23:48:58     INFO - TEST-START | /webdriver/tests/take_screenshot/iframe.py
[task 2021-02-22T23:48:58.945Z] 23:48:58     INFO - STDOUT: ============================= test session starts =============================
[task 2021-02-22T23:48:58.946Z] 23:48:58     INFO - STDOUT: platform win32 -- Python 3.6.5, pytest-unknown, py-1.5.2, pluggy-unknown -- Z:\task_1614035177\build\venv\Scripts\python.exe
[task 2021-02-22T23:48:58.947Z] 23:48:58     INFO - STDOUT: rootdir: Z:\task_1614035177\build
[task 2021-02-22T23:48:58.947Z] 23:48:58     INFO - STDOUT: collecting ... 
[task 2021-02-22T23:48:59.002Z] 23:48:59     INFO - STDOUT: collected 3 items
[task 2021-02-22T23:48:59.003Z] 23:48:59     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/take_screenshot/iframe.py::test_always_captures_top_browsing_context 
[task 2021-02-22T23:48:59.018Z] 23:48:59     INFO - PID 3692 | 1614037739004	webdriver::server	DEBUG	-> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"binary": "Z:\\task_1614035177\\build\\application\\firefox\\firefox.exe", "prefs": {"network.dns.localDomains": "op62.web-platform.test,op40.web-platform.test,op74.web-platform.test,op26.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www.not-web-platform.test,op49.web-platform.test,op15.not-web-platform.test,op95.web-platform.test,www1.xn--lve-6lad.web-platform.test,op9.not-web-platform.test,op45.web-platform.test,op59.not-web-platform.test,op38.not-web-platform.test,op12.web-platform.test,op20.web-platform.test,op47.web-platform.test,www1.www2.web-platform.test,www2.www.not-web-platform.test,op67.not-web-platform.test,op48.web-platform.test,op46.web-platform.test,op22.not-web-platform.test,www1.www1.web-platform.test,op61.web-platform.test,op35.not-web-platform.test,www1.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--n8j6ds53lwwkrqhv28a.web-platform.test,op41.not-web-platform.test,www1.www.web-platform.test,xn--lve-6lad.www2.not-web-platform.test,op52.not-web-platform.test,www.www2.not-web-platform.test,op96.web-platform.test,op56.web-platform.test,op67.web-platform.test,op10.web-platform.test,op43.web-platform.test,op98.not-web-platform.test,www2.www2.not-web-platform.test,op74.not-web-platform.test,op84.web-platform.test,op33.web-platform.test,op42.web-platform.test,xn--lve-6lad.xn--lve-6lad.web-platform.test,op22.web-platform.test,www.xn--lve-6lad.web-platform.test,op5.not-web-platform.test,op2.web-platform.test,op3.not-web-platform.test,op81.web-platform.test,op17.not-web-platform.test,op18.web-platform.test,op65.not-web-platform.test,op58.not-web-platform.test,op27.web-platform.test,op9.web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,www2.www1.not-web-platform.test,op75.not-web-platform.test,op35.web-platform.test,www.www2.web-platform.test,op54.web-platform.test,op73.not-web-platform.test,op69.not-web-platform.test,op51.not-web-platform.test,op2.not-web-platform.test,op36.web-platform.test,op45.not-web-platform.test,www2.web-platform.test,op99.not-web-platform.test,op91.web-platform.test,op30.web-platform.test,op44.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www1.not-web-platform.test,op7.not-web-platform.test,op96.not-web-platform.test,www.www.web-platform.test,op21.web-platform.test,op48.not-web-platform.test,op11.web-platform.test,op70.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,op11.not-web-platform.test,op47.not-web-platform.test,op14.web-platform.test,op21.not-web-platform.test,op83.not-web-platform.test,op79.web-platform.test,op34.web-platform.test,op38.web-platform.test,web-platform.test,op44.not-web-platform.test,www.xn--n8j6ds53lwwkrqhv28a.web-platform.test,www2.xn--lve-6lad.web-platform.test,op72.not-web-platform.test,op88.not-web-platform.test,op88.web-platform.test,op33.not-web-platform.test,www.xn--lve-6lad.not-web-platform.test,op43.not-web-platform.test,op6.not-web-platform.test,www2.not-web-platform.test,op97.web-platform.test,op55.web-platform.test,op8.not-web-platform.test,www2.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,op39.not-web-platform.test,op12.not-web-platform.test,op19.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www1.web-platform.test,www.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,op84.not-web-platform.test,op51.web-platform.test,op87.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.www2.not-web-platform.test,www1.web-platform.test,op13.not-web-platform.test,op20.not-web-platform.test,xn--lve-6lad.www.not-web-platform.test,op85.not-web-platform.test,op50.web-platform.test,op80.not-web-platform.test,op78.not-web-platform.test,op27.not-web-platform.test,op71.not-web-platform.test,op92.not-web-platform.test,op19.not-web-platform.test,op29.not-web-platform.test,xn--lve-6lad.not-web-platform.test,op16.not-web-platform.test,op89.not-web-platform.test,op94.web-platform.test,xn--lve-6lad.xn--n8j6ds53lwwkrqhv28a.web-platform.test,www.not-web-platform.test,op77.web-platform.test,op55.not-web-platform.test,op97.not-web-platform.test,op72.web-platform.test,www2.xn--lve-6lad.not-web-platform.test,op81.not-web-platform.test,op30.not-web-platform.test,op4.web-platform.test,op6.web-platform.test,op41.web-platform.test,op42.not-web-platform.test,op76.web-platform.test,op25.web-platform.test,op93.not-web-platform.test,op40.not-web-platform.test,xn--lve-6lad.xn--lve-6lad.not-web-platform.test,op10.not-web-platform.test,op7.web-platform.test,op61.not-web-platform.test,op90.web-platform.test,xn--lve-6lad.www2.web-platform.test,op91.not-web-platform.test,op52.web-platform.test,op99.web-platform.test,op13.web-platform.test,xn--lve-6lad.web-platform.test,op86.web-platform.test,op58.web-platform.test,op46.not-web-platform.test,www.web-platform.test,op24.not-web-platform.test,www.www1.web-platform.test,op63.not-web-platform.test,op56.not-web-platform.test,op64.web-platform.test,op39.web-platform.test,op64.not-web-platform.test,op28.not-web-platform.test,op98.web-platform.test,op92.web-platform.test,op32.not-web-platform.test,www2.www1.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www.web-platform.test,op66.web-platform.test,op85.web-platform.test,op53.not-web-platform.test,op31.web-platform.test,www1.xn--lve-6lad.not-web-platform.test,op77.not-web-platform.test,op93.web-platform.test,op80.web-platform.test,op82.web-platform.test,op69.web-platform.test,not-web-platform.test,op62.not-web-platform.test,op82.not-web-platform.test,op76.not-web-platform.test,op26.not-web-platform.test,xn--lve-6lad.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,op65.web-platform.test,op29.web-platform.test,www1.xn--n8j6ds53lwwkrqhv28a.web-platform.test,op79.not-web-platform.test,op17.web-platform.test,op73.web-platform.test,op37.not-web-platform.test,op23.not-web-platform.test,op57.web-platform.test,op59.web-platform.test,www.www1.not-web-platform.test,op78.web-platform.test,op15.web-platform.test,www1.www1.not-web-platform.test,op83.web-platform.test,op34.not-web-platform.test,op68.web-platform.test,op53.web-platform.test,op4.not-web-platform.test,op1.web-platform.test,op1.not-web-platform.test,op90.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.www2.web-platform.test,op94.not-web-platform.test,op36.not-web-platform.test,op49.not-web-platform.test,op28.web-platform.test,xn--n8j6ds53lwwkrqhv28a.web-platform.test,op60.not-web-platform.test,op3.web-platform.test,op37.web-platform.test,op23.web-platform.test,op14.not-web-platform.test,op75.web-platform.test,op18.not-web-platform.test,www.www.not-web-platform.test,op8.web-platform.test,www2.www.web-platform.test,op66.not-web-platform.test,op68.not-web-platform.test,op63.web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--lve-6lad.web-platform.test,op54.not-web-platform.test,op86.not-web-platform.test,op89.web-platform.test,op50.not-web-platform.test,www1.www2.not-web-platform.test,www1.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,op71.web-platform.test,op31.not-web-platform.test,op5.web-platform.test,op87.web-platform.test,op16.web-platform.test,op57.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--lve-6lad.not-web-platform.test,op60.web-platform.test,op32.web-platform.test,op24.web-platform.test,op70.web-platform.test,xn--lve-6lad.www1.not-web-platform.test,op25.not-web-platform.test,www2.www2.web-platform.test,xn--lve-6lad.www1.web-platform.test,xn--lve-6lad.www.web-platform.test,www2.xn--n8j6ds53lwwkrqhv28a.web-platform.test,op95.not-web-platform.test,www1.www.not-web-platform.test", "toolkit.asyncshutdown.log": true, "media.peerconnection.mtransport_process": false, "network.process.enabled": false}}}}}
[task 2021-02-22T23:48:59.019Z] 23:48:59     INFO - PID 3692 | 1614037739007	geckodriver::capabilities	DEBUG	Trying to read firefox version from ini files
[task 2021-02-22T23:48:59.020Z] 23:48:59     INFO - PID 3692 | 1614037739008	geckodriver::capabilities	DEBUG	Found version 88.0a1
[task 2021-02-22T23:48:59.021Z] 23:48:59     INFO - PID 3692 | 1614037739011	mozrunner::runner	INFO	Running command: "Z:\\task_1614035177\\build\\application\\firefox\\firefox.exe" "--marionette" "-foreground" "-no-remote" "-profile" "C:\\Users\\task_1614035177\\AppData\\Local\\Temp\\rust_mozprofilepcpIh5"
[task 2021-02-22T23:48:59.022Z] 23:48:59     INFO - PID 3692 | 1614037739013	geckodriver::marionette	DEBUG	Waiting 60s to connect to browser on 127.0.0.1:2828
[task 2021-02-22T23:48:59.186Z] 23:48:59     INFO - PID 3692 | DEBUG: Adding blocker OS.File: flush pending requests, warn about unclosed files, shut down service. for phase xpcom-will-shutdown
[task 2021-02-22T23:48:59.187Z] 23:48:59     INFO - PID 3692 | DEBUG: Adding blocker OS.File: flush I/O queued before profileBeforeChange for phase profile-before-change
[task 2021-02-22T23:48:59.188Z] 23:48:59     INFO - PID 3692 | DEBUG: Adding blocker AddonManager: shutting down. for phase profile-before-change
[task 2021-02-22T23:48:59.238Z] 23:48:59     INFO - PID 3692 | DEBUG: Adding blocker Flush WebExtension StartupCache for phase profile-before-change
[task 2021-02-22T23:48:59.251Z] 23:48:59     INFO - PID 3692 | DEBUG: Adding blocker ClientManagerService: start destroying IPC actors early for phase xpcom-will-shutdown
[task 2021-02-22T23:48:59.376Z] 23:48:59     INFO - PID 3692 | DEBUG: Adding blocker JSON store: writing data for phase AddonManager: Waiting for providers to shut down.
[task 2021-02-22T23:48:59.378Z] 23:48:59     INFO - PID 3692 | DEBUG: Adding blocker Update add-on blocklist state into add-on DB for phase AddonManager: Waiting to start provider shutdown.
[task 2021-02-22T23:48:59.379Z] 23:48:59     INFO - PID 3692 | DEBUG: Completed blocker Update add-on blocklist state into add-on DB for phase AddonManager: Waiting to start provider shutdown.
[task 2021-02-22T23:48:59.384Z] 23:48:59     INFO - PID 3692 | DEBUG: Adding blocker XPIProvider shutdown for phase quit-application-granted
[task 2021-02-22T23:48:59.385Z] 23:48:59     INFO - PID 3692 | DEBUG: Adding blocker XPIProvider for phase AddonManager: Waiting for providers to shut down.
[task 2021-02-22T23:48:59.386Z] 23:48:59     INFO - PID 3692 | DEBUG: Adding blocker PluginProvider for phase AddonManager: Waiting for providers to shut down.
[task 2021-02-22T23:48:59.387Z] 23:48:59     INFO - PID 3692 | DEBUG: Adding blocker ServiceWorkerRegistrar: Flushing data for phase profile-before-change
[task 2021-02-22T23:48:59.388Z] 23:48:59     INFO - PID 3692 | DEBUG: Adding blocker CrashMonitor: Writing notifications to file after receiving profile-before-change for phase OS.File: Waiting for clients before profileBeforeChange
[task 2021-02-22T23:48:59.405Z] 23:48:59     INFO - PID 3692 | DEBUG: Adding blocker TelemetryController: shutting down for phase profile-before-change-telemetry
[task 2021-02-22T23:48:59.429Z] 23:48:59     INFO - PID 3692 | DEBUG: Adding blocker EnvironmentAddonBuilder for phase AddonManager: Waiting to start provider shutdown.
[task 2021-02-22T23:48:59.439Z] 23:48:59     INFO - PID 3692 | DEBUG: Adding blocker RemoteSettingsClient - finish IDB access. for phase profile-before-change
[task 2021-02-22T23:48:59.443Z] 23:48:59     INFO - PID 3692 | 1614037739442	Marionette	TRACE	Marionette enabled
[task 2021-02-22T23:49:28.705Z] 23:49:28     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/take_screenshot/iframe.py | expected OK
[task 2021-02-22T23:49:28.705Z] 23:49:28     INFO - TEST-INFO took 30013ms
[task 2021-02-22T23:49:28.876Z] 23:49:28     INFO - Closing logging queue
[task 2021-02-22T23:49:28.876Z] 23:49:28     INFO - queue closed
[task 2021-02-22T23:49:28.878Z] 23:49:28     INFO - Starting runner
[task 2021-02-22T23:49:29.392Z] 23:49:29     INFO - PID 10740 | 1614037769390	geckodriver	INFO	Listening on 127.0.0.1:54230
[task 2021-02-22T23:49:29.889Z] 23:49:29     INFO - WebDriver HTTP server listening at http://127.0.0.1:54230/
[task 2021-02-22T23:49:29.889Z] 23:49:29     INFO - TEST-START | /webdriver/tests/take_screenshot/screenshot.py```

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• windows10-64-shippable: 1
  • opt: 1

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

Comment 3

[BMO Automation]

Moving bug to Remote Protocol::Marionette component per bug 1815831.