Closed Bug 1532814 Opened 5 years ago Closed 5 years ago

Intermittent testing\firefox-ui\tests\functional\security\test_security_notification.py TestSecurityNotification.test_secure_website | TimeoutException: Timed out after 5.1 seconds

Categories

(Testing :: Firefox UI Tests, defect, P5)

Product:
Testing
Component:
Firefox UI Tests
Version:
Version 3
Type:
defect

Tracking

(firefox-esr68 wontfix, firefox71 wontfix)

Status:
RESOLVED INVALID
Milestone:
mozilla71
Tracking Flags:
Tracking Status
firefox-esr68 --- wontfix
firefox71 --- wontfix

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, regression)

#[markdown(off)]
Filed by: shindli [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=232007794&repo=mozilla-central

https://queue.taskcluster.net/v1/task/B2FY8lfyRu-D7pth_jtiIQ/runs/0/artifacts/public/logs/live_backing.log

23:13:39 INFO - 1551827619868 Marionette DEBUG 15 <- [1,121,null,{"value":"verifiedDomain"}]
23:13:39 INFO - 1551827619965 Marionette DEBUG 15 -> [0,122,"WebDriver:GetElementProperty",{"id":"68383fdd-6ff3-4b1a-ae79-89cd814dc919","name":"className"}]
23:13:39 INFO - 1551827619966 Marionette DEBUG 15 <- [1,122,null,{"value":"verifiedDomain"}]
23:13:40 INFO - 1551827620064 Marionette DEBUG 15 -> [0,123,"WebDriver:GetElementProperty",{"id":"68383fdd-6ff3-4b1a-ae79-89cd814dc919","name":"className"}]
23:13:40 INFO - 1551827620066 Marionette DEBUG 15 <- [1,123,null,{"value":"verifiedDomain"}]
23:13:40 INFO - 1551827620165 Marionette DEBUG 15 -> [0,124,"WebDriver:GetElementProperty",{"id":"68383fdd-6ff3-4b1a-ae79-89cd814dc919","name":"className"}]
23:13:40 INFO - 1551827620168 Marionette DEBUG 15 <- [1,124,null,{"value":"verifiedDomain"}]
23:13:40 INFO - 1551827620266 Marionette DEBUG 15 -> [0,125,"Marionette:GetContext",{}]
23:13:40 INFO - 1551827620268 Marionette DEBUG 15 <- [1,125,null,{"value":"chrome"}]
23:13:40 INFO - 1551827620271 Marionette DEBUG 15 -> [0,126,"Marionette:SetContext",{"value":"chrome"}]
23:13:40 INFO - 1551827620272 Marionette DEBUG 15 <- [1,126,null,{"value":null}]
23:13:40 INFO - 1551827620274 Marionette DEBUG 15 -> [0,127,"WebDriver:TakeScreenshot",{"highlights":null,"full":true,"hash":false,"id":null,"scroll":true}]
23:13:40 INFO - [Parent 6920, Main Thread] WARNING: Remote iframe not rendered: file z:/build/build/src/layout/ipc/RenderFrame.cpp, line 260
23:13:40 INFO - 1551827620320 Marionette DEBUG 15 <- [1,127,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABAAAAAMHCAYAAACnvyW1AAAgAElEQVR4nOzdf4zcdZ348f3nm1y+lzsvkvO8L/qVE5HgXdvzG9GgX75 ... AgAAAAAGBAAAAAAMCAAAAAAIABAQAAAAADAgAAAAAGBAAAAAAMCAAAAAAYEAAAAAAwIAAAAABgQAAAAADAgAAAAACAgQDoa3oYUMdIEwAAAABJRU5ErkJggg=="}]
23:13:40 INFO - 1551827620324 Marionette DEBUG 15 -> [0,128,"Marionette:SetContext",{"value":"chrome"}]
23:13:40 INFO - 1551827620325 Marionette DEBUG 15 <- [1,128,null,{"value":null}]
23:13:40 INFO - 1551827620328 Marionette DEBUG 15 -> [0,129,"Marionette:GetContext",{}]
23:13:40 INFO - 1551827620329 Marionette DEBUG 15 <- [1,129,null,{"value":"chrome"}]
23:13:40 INFO - 1551827620331 Marionette DEBUG 15 -> [0,130,"Marionette:SetContext",{"value":"content"}]
23:13:40 INFO - 1551827620332 Marionette DEBUG 15 <- [1,130,null,{"value":null}]
23:13:40 INFO - 1551827620335 Marionette DEBUG 15 -> [0,131,"WebDriver:GetPageSource",{}]
23:13:40 INFO - 1551827620342 Marionette DEBUG 15 <- [1,131,null,{"value":"<html><head>\n <meta name=\"viewport\" content=\"width=device-width, initial-scale=1\">\n <link rel=\ ... org/wiki/Extended_Validation_Certificate\">Extended Validation</a>) digital certificate.\n \n</div>\n\n\n\n</body></html>"}]
23:13:40 INFO - 1551827620344 Marionette DEBUG 15 -> [0,132,"Marionette:SetContext",{"value":"chrome"}]
23:13:40 INFO - 1551827620345 Marionette DEBUG 15 <- [1,132,null,{"value":null}]
23:13:40 INFO - TEST-UNEXPECTED-ERROR | testing\firefox-ui\tests\functional\security\test_security_notification.py TestSecurityNotification.test_secure_website | TimeoutException: Timed out after 5.1 seconds
23:13:40 INFO - Traceback (most recent call last):
23:13:40 INFO - File "Z:\task_1551826997\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
23:13:40 INFO - testMethod()
23:13:40 INFO - File "Z:\task_1551826997\build\tests\firefox-ui\tests\testing\firefox-ui\tests\functional\security\test_security_notification.py", line 52, in test_secure_website
23:13:40 INFO - Wait(self.marionette).until(lambda _: (
23:13:40 INFO - File "Z:\task_1551826997\build\venv\lib\site-packages\marionette_driver\wait.py", line 154, in until
23:13:40 INFO - cause=last_exc)
23:13:40 INFO - TEST-INFO took 7124ms
23:13:40 INFO - 1551827620352 Marionette DEBUG 15 -> [0,133,"Marionette:SetContext",{"value":"chrome"}]
23:13:40 INFO - 1551827620353 Marionette DEBUG 15 <- [1,133,null,{"value":null}]
23:13:40 INFO - 1551827620356 Marionette DEBUG 15 -> [0,134,"WebDriver:GetWindowHandles",{}]
23:13:40 INFO - 1551827620357 Marionette DEBUG 15 <- [1,134,null,["36507222017"]]
23:13:40 INFO - 1551827620359 Marionette DEBUG 15 -> [0,135,"WebDriver:GetChromeWindowHandles",{}]

We are going to remove this test via bug 1573393. Once done this bug can be closed.

Depends on: 1573393
Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INVALID

This is still happening.

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=267968593&repo=mozilla-esr68&lineNumber=7070

[task 2019-09-23T14:08:20.582Z] 14:08:20 INFO - TEST-START | testing/firefox-ui/tests/functional/security/test_security_notification.py TestSecurityNotification.test_secure_website
[task 2019-09-23T14:08:20.589Z] 14:08:20 INFO - 1569247700582 Marionette DEBUG Closed connection 12
[task 2019-09-23T14:08:20.590Z] 14:08:20 INFO - 1569247700583 Marionette DEBUG Accepted connection 13 from 127.0.0.1:38792
[task 2019-09-23T14:08:20.590Z] 14:08:20 INFO - 1569247700584 Marionette DEBUG 13 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2019-09-23T14:08:20.591Z] 14:08:20 INFO - 1569247700587 Marionette TRACE [21474836481] Frame script loaded
[task 2019-09-23T14:08:20.591Z] 14:08:20 INFO - 1569247700588 Marionette TRACE [21474836481] Frame script registered
[task 2019-09-23T14:08:20.593Z] 14:08:20 INFO - 1569247700592 Marionette DEBUG 13 <- [1,1,null,{"sessionId":"e50a67c4-8876-4c7c-a889-a163d90f7c8c","capabilities":{"browserName":"firefox","browserVersion":"68.2. ... ld/tmpBunU30.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2019-09-23T14:08:20.596Z] 14:08:20 INFO - 1569247700594 Marionette DEBUG 13 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2019-09-23T14:08:20.597Z] 14:08:20 INFO - 1569247700595 Marionette DEBUG 13 <- [1,2,null,{"value":null}]
...
[task 2019-09-23T14:08:26.776Z] 14:08:26 INFO - 1569247706771 Marionette DEBUG 13 -> [0,120,"WebDriver:GetElementProperty",{"id":"ae9aa05a-9e92-497d-aa37-499c196b1ac5","name":"className"}]
[task 2019-09-23T14:08:26.776Z] 14:08:26 INFO - 1569247706771 Marionette DEBUG 13 <- [1,120,null,{"value":"verifiedDomain"}]
[task 2019-09-23T14:08:26.876Z] 14:08:26 INFO - 1569247706871 Marionette DEBUG 13 -> [0,121,"Marionette:GetContext",{}]
[task 2019-09-23T14:08:26.877Z] 14:08:26 INFO - 1569247706871 Marionette DEBUG 13 <- [1,121,null,{"value":"chrome"}]
[task 2019-09-23T14:08:26.878Z] 14:08:26 INFO - 1569247706872 Marionette DEBUG 13 -> [0,122,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-09-23T14:08:26.879Z] 14:08:26 INFO - 1569247706873 Marionette DEBUG 13 <- [1,122,null,{"value":null}]
[task 2019-09-23T14:08:26.879Z] 14:08:26 INFO - 1569247706874 Marionette DEBUG 13 -> [0,123,"WebDriver:TakeScreenshot",{"highlights":null,"full":true,"hash":false,"id":null,"scroll":true}]
[task 2019-09-23T14:08:26.883Z] 14:08:26 INFO - [Parent 860, Main Thread] WARNING: Remote iframe not rendered: file /builds/worker/workspace/build/src/layout/ipc/RenderFrame.cpp, line 255
[task 2019-09-23T14:08:26.926Z] 14:08:26 INFO - 1569247706922 Marionette DEBUG 13 <- [1,123,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABQAAAAQQCAYAAAC9RfbYAAAgAElEQVR4nOzdbYxchXnocX+sWvVFqZqml+SW3oRUUBCpmqaRkt606lU ... ACAAAAwJgABAAAAIAxAQgAAAAAYwIQAAAAAMYEIAAAAACMCUAAAAAAGBOAAAAAADAmAAEAAABgTAACAAAAwJgABAAAAICxAKUiwjseNTG/AAAAAElFTkSuQmCC"}]
[task 2019-09-23T14:08:26.930Z] 14:08:26 INFO - 1569247706924 Marionette DEBUG 13 -> [0,124,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-09-23T14:08:26.931Z] 14:08:26 INFO - 1569247706925 Marionette DEBUG 13 <- [1,124,null,{"value":null}]
[task 2019-09-23T14:08:26.932Z] 14:08:26 INFO - 1569247706927 Marionette DEBUG 13 -> [0,125,"Marionette:GetContext",{}]
[task 2019-09-23T14:08:26.933Z] 14:08:26 INFO - 1569247706927 Marionette DEBUG 13 <- [1,125,null,{"value":"chrome"}]
[task 2019-09-23T14:08:26.933Z] 14:08:26 INFO - 1569247706929 Marionette DEBUG 13 -> [0,126,"Marionette:SetContext",{"value":"content"}]
[task 2019-09-23T14:08:26.934Z] 14:08:26 INFO - 1569247706929 Marionette DEBUG 13 <- [1,126,null,{"value":null}]
[task 2019-09-23T14:08:26.938Z] 14:08:26 INFO - 1569247706930 Marionette DEBUG 13 -> [0,127,"WebDriver:GetPageSource",{}]
[task 2019-09-23T14:08:26.939Z] 14:08:26 INFO - 1569247706936 Marionette DEBUG 13 <- [1,127,null,{"value":"<html><head>\n <meta name="viewport" content="width=device-width, initial-scale=1">\n <link rel=\ ... org/wiki/Extended_Validation_Certificate">Extended Validation</a>) digital certificate.\n \n</div>\n\n\n\n</body></html>"}]
[task 2019-09-23T14:08:26.939Z] 14:08:26 INFO - 1569247706937 Marionette DEBUG 13 -> [0,128,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-09-23T14:08:26.956Z] 14:08:26 INFO - TEST-UNEXPECTED-ERROR | testing/firefox-ui/tests/functional/security/test_security_notification.py TestSecurityNotification.test_secure_website | TimeoutException: Timed out after 5.1 seconds
[task 2019-09-23T14:08:26.956Z] 14:08:26 INFO - Traceback (most recent call last):
[task 2019-09-23T14:08:26.956Z] 14:08:26 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 159, in run
[task 2019-09-23T14:08:26.956Z] 14:08:26 INFO - testMethod()
[task 2019-09-23T14:08:26.957Z] 14:08:26 INFO - File "/builds/worker/workspace/build/tests/firefox-ui/tests/testing/firefox-ui/tests/functional/security/test_security_notification.py", line 52, in test_secure_website
[task 2019-09-23T14:08:26.958Z] 14:08:26 INFO - Wait(self.marionette).until(lambda _: (
[task 2019-09-23T14:08:26.958Z] 14:08:26 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/wait.py", line 154, in until
[task 2019-09-23T14:08:26.958Z] 14:08:26 INFO - cause=last_exc)
[task 2019-09-23T14:08:26.958Z] 14:08:26 INFO - TEST-INFO took 6360ms

Status: RESOLVED → REOPENED
Resolution: INVALID → ---

(In reply to Bogdan Tara[:bogdan_tara] from comment #26)

This is still happening.

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=267968593&repo=mozilla-esr68&lineNumber=7070

No, this is esr68 but not mozilla-central where this failure happened. On mozilla-central this test doesn't exist anymore.

Status: REOPENED → RESOLVED
Closed: 5 years ago → 5 years ago
status-firefox71: --- → wontfix
status-firefox-esr68: --- → wontfix
Flags: needinfo?(btara)
Resolution: --- → INVALID
Target Milestone: --- → mozilla71
See Also: → 1583261

I logged bug 1583261 for the esr68 failure.

Sorry for the error.

Flags: needinfo?(btara)
You need to log in before you can comment on or make changes to this bug.