Closed Bug 1457776 Opened 7 years ago Closed 7 years ago

Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_prefs_enforce.py TestEnforcePreferences.test_change_preference | DatabaseError: file is encrypted or is not a database

Categories

(Testing :: Marionette Client and Harness, defect, P5)

Product:
Testing
Component:
Marionette Client and Harness
Version:
Version 3
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: archaeopteryx [at] coole-files.de https://treeherder.mozilla.org/logviewer.html#?job_id=176168143&repo=try https://queue.taskcluster.net/v1/task/ePuVF9UJTiWYWSsKom_ebA/runs/0/artifacts/public/logs/live_backing.log Seen for a central-as-beta simulation. [task 2018-04-28T19:37:48.992Z] 19:37:48 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_prefs_enforce.py TestEnforcePreferences.test_change_preference [task 2018-04-28T19:37:48.995Z] 19:37:48 INFO - 1524944268987 Marionette TRACE 81 <- [1,42,null,{}] [task 2018-04-28T19:37:48.997Z] 19:37:48 INFO - 1524944268994 Marionette DEBUG Closed connection 81 [task 2018-04-28T19:37:48.999Z] 19:37:48 INFO - 1524944268996 Marionette DEBUG Accepted connection 82 from 127.0.0.1:33282 [task 2018-04-28T19:37:49.002Z] 19:37:49 INFO - 1524944268999 Marionette TRACE 82 -> [0,1,"WebDriver:NewSession",{}] [task 2018-04-28T19:37:49.004Z] 19:37:49 INFO - 1524944269001 Marionette DEBUG Register listener.js for window 2147483649 [task 2018-04-28T19:37:49.013Z] 19:37:49 INFO - 1524944269006 Marionette TRACE 82 <- [1,1,null,{"sessionId":"6a6f375b-e4fb-4156-8a7e-0735d5a4960e","capabilities":{"browserName":"firefox","browserVersion":"61.0" ... ssID":6176,"moz:profile":"/tmp/tmpIj872B.mozrunner","moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}] [task 2018-04-28T19:37:49.014Z] 19:37:49 INFO - 1524944269007 Marionette TRACE 82 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}] [task 2018-04-28T19:37:49.014Z] 19:37:49 INFO - 1524944269008 Marionette TRACE 82 <- [1,2,null,{}] [task 2018-04-28T19:37:49.016Z] 19:37:49 INFO - 1524944269009 Marionette TRACE 82 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}] [task 2018-04-28T19:37:49.017Z] 19:37:49 INFO - 1524944269009 Marionette TRACE 82 <- [1,3,null,{}] [task 2018-04-28T19:37:49.019Z] 19:37:49 INFO - 1524944269011 Marionette TRACE 82 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}] [task 2018-04-28T19:37:49.019Z] 19:37:49 INFO - 1524944269011 Marionette TRACE 82 <- [1,4,null,{}] [task 2018-04-28T19:37:49.038Z] 19:37:49 INFO - 1524944269021 Marionette TRACE 82 -> [0,5,"Marionette:GetContext",{}] [task 2018-04-28T19:37:49.039Z] 19:37:49 INFO - 1524944269021 Marionette TRACE 82 <- [1,5,null,{"value":"content"}] [task 2018-04-28T19:37:49.042Z] 19:37:49 INFO - 1524944269022 Marionette TRACE 82 -> [0,6,"Marionette:SetContext",{"value":"chrome"}] [task 2018-04-28T19:37:49.045Z] 19:37:49 INFO - 1524944269022 Marionette TRACE 82 <- [1,6,null,{}] [task 2018-04-28T19:37:49.047Z] 19:37:49 INFO - 1524944269024 Marionette TRACE 82 -> [0,7,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"../../venv/lib/python2.7/site-pa ... case prefInterface.PREF_INVALID:\n return false;\n }","sandbox":"default","line":990}] [task 2018-04-28T19:37:49.050Z] 19:37:49 INFO - 1524944269026 Marionette TRACE 82 <- [1,7,null,{"value":false}] [task 2018-04-28T19:37:49.052Z] 19:37:49 INFO - 1524944269027 Marionette TRACE 82 -> [0,8,"Marionette:SetContext",{"value":"content"}] [task 2018-04-28T19:37:49.053Z] 19:37:49 INFO - 1524944269027 Marionette TRACE 82 <- [1,8,null,{}] [task 2018-04-28T19:37:49.054Z] 19:37:49 INFO - 1524944269029 Marionette TRACE 82 -> [0,9,"Marionette:GetContext",{}] [task 2018-04-28T19:37:49.055Z] 19:37:49 INFO - 1524944269030 Marionette TRACE 82 <- [1,9,null,{"value":"content"}] [task 2018-04-28T19:37:49.057Z] 19:37:49 INFO - 1524944269031 Marionette TRACE 82 -> [0,10,"WebDriver:DeleteSession",{}] [task 2018-04-28T19:37:49.059Z] 19:37:49 INFO - 1524944269032 Marionette TRACE 82 <- [1,10,null,{}] [task 2018-04-28T19:37:49.100Z] 19:37:49 INFO - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_prefs_enforce.py TestEnforcePreferences.test_change_preference | DatabaseError: file is encrypted or is not a database [task 2018-04-28T19:37:49.100Z] 19:37:49 INFO - Traceback (most recent call last): [task 2018-04-28T19:37:49.101Z] 19:37:49 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 140, in run [task 2018-04-28T19:37:49.101Z] 19:37:49 INFO - self.setUp() [task 2018-04-28T19:37:49.103Z] 19:37:49 INFO - File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_prefs_enforce.py", line 18, in setUp [task 2018-04-28T19:37:49.104Z] 19:37:49 INFO - "marionette.test.string": "testing", [task 2018-04-28T19:37:49.105Z] 19:37:49 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _ [task 2018-04-28T19:37:49.105Z] 19:37:49 INFO - return func(*args, **kwargs) [task 2018-04-28T19:37:49.107Z] 19:37:49 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 998, in enforce_gecko_prefs [task 2018-04-28T19:37:49.107Z] 19:37:49 INFO - self.instance.restart(prefs) [task 2018-04-28T19:37:49.109Z] 19:37:49 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/geckoinstance.py", line 367, in restart [task 2018-04-28T19:37:49.109Z] 19:37:49 INFO - self.close(clean=clean) [task 2018-04-28T19:37:49.110Z] 19:37:49 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/geckoinstance.py", line 352, in close [task 2018-04-28T19:37:49.110Z] 19:37:49 INFO - self.profile.cleanup() [task 2018-04-28T19:37:49.111Z] 19:37:49 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/mozprofile/profile.py", line 212, in cleanup [task 2018-04-28T19:37:49.111Z] 19:37:49 INFO - self.permissions.clean_db() [task 2018-04-28T19:37:49.112Z] 19:37:49 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/mozprofile/permissions.py", line 416, in clean_db [task 2018-04-28T19:37:49.113Z] 19:37:49 INFO - cursor.execute("DROP TABLE IF EXISTS moz_hosts") [task 2018-04-28T19:37:49.113Z] 19:37:49 INFO - TEST-INFO took 105ms
This failure happened because mozprofile tried to clean-up the database `permissions.sqlite`, especially when dropping the table `moz_hosts`. Nothing in our test actually triggers those server locations, so I wonder why this code path got executed. We would need more samples.
Product: Testing → Remote Protocol
Moving bug to Testing::Marionette Client and Harness component per bug 1815831.
Component: Marionette → Marionette Client and Harness
Product: Remote Protocol → Testing
You need to log in before you can comment on or make changes to this bug.