Closed
Bug 1642923
Opened 4 years ago
Closed 4 years ago
Intermittent sessionrestore_many_windows | timeout
Categories
(Firefox :: Session Restore, defect, P5)
Firefox
Session Restore
Tracking
()
RESOLVED
FIXED
People
(Reporter: intermittent-bug-filer, Assigned: mozbugz)
References
Details
(Keywords: intermittent-failure)
Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=304864707&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ctqbhPTnRz2WuWxstiguMQ/runs/0/artifacts/public/logs/live_backing.log
...
[task 2020-06-03T06:01:37.907Z] 06:01:37 INFO - profiler Error opening file c:\users\task_1591156499\appdata\local\temp\tmpkgw6py\igc64.pdb\1F1E0B84155B42A8BAB202B6B9FF160B1\igc64.nmsym: [Errno 2] No such file or directory: u'c:\\users\\task_1591156499\\appdata\\local\\temp\\tmpkgw6py\\igc64.pdb\\1F1E0B84155B42A8BAB202B6B9FF160B1\\igc64.nmsym'
[task 2020-06-03T06:01:37.907Z] 06:01:37 INFO - profiler No matching sym files, tried ['FIREFOX', 'WINDOWS']
[task 2020-06-03T06:01:37.907Z] 06:01:37 INFO - profiler Forwarding 508 PCs for symbolication
[task 2020-06-03T06:01:42.176Z] 06:01:42 INFO - Adding profile profile_sessionrestore_many_windows\startup\cycle_1.profile to archive C:\Users\task_1591156499\build\blobber_upload_dir\profile_sessionrestore_many_windows.zip
[task 2020-06-03T06:01:42.960Z] 06:01:42 INFO - mozcrash checking c:\users\task_1591156499\appdata\local\temp\tmpow479f\profile\minidumps for minidumps...
[task 2020-06-03T06:01:42.960Z] 06:01:42 INFO - Running cycle 3/10 for sessionrestore_many_windows test...
[task 2020-06-03T06:01:42.960Z] 06:01:42 INFO - Reinstalling C:\Users\task_1591156499\build\tests\talos\talos/startup_test/sessionrestore/profile-manywindows\sessionstore.jsonlz4 on top of c:\users\task_1591156499\appdata\local\temp\tmpow479f\profile\sessionstore.jsonlz4
[task 2020-06-03T06:01:42.960Z] 06:01:42 INFO - Reinstalling C:\Users\task_1591156499\build\tests\talos\talos/startup_test/sessionrestore/profile-manywindows\sessionstore.js on top of c:\users\task_1591156499\appdata\local\temp\tmpow479f\profile\sessionstore.js
[task 2020-06-03T06:01:42.960Z] 06:01:42 INFO - Reinstalling C:\Users\task_1591156499\build\tests\talos\talos/startup_test/sessionrestore/profile-manywindows\sessionCheckpoints.json on top of c:\users\task_1591156499\appdata\local\temp\tmpow479f\profile\sessionCheckpoints.json
[task 2020-06-03T06:01:42.963Z] 06:01:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
[task 2020-06-03T06:01:42.965Z] 06:01:42 INFO - 'APPDATA': 'C:\\Users\\task_1591156499\\AppData\\Roaming',
[task 2020-06-03T06:01:42.966Z] 06:01:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
[task 2020-06-03T06:01:42.966Z] 06:01:42 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
[task 2020-06-03T06:01:42.966Z] 06:01:42 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
[task 2020-06-03T06:01:42.967Z] 06:01:42 INFO - 'COMPUTERNAME': 'T-W1064-MS-210',
[task 2020-06-03T06:01:42.967Z] 06:01:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
[task 2020-06-03T06:01:42.968Z] 06:01:42 INFO - 'EXTRA_MOZHARNESS_CONFIG': '{"installer_url": "https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/VjYMe0lJSTu1844q10zXeA/artifacts/public/build/target.zip", "test_packages_url": "https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/VjYMe0lJSTu1844q10zXeA/artifacts/public/build/target.test_packages.json"}',
[task 2020-06-03T06:01:42.968Z] 06:01:42 INFO - 'GECKO_HEAD_REPOSITORY': 'https://hg.mozilla.org/mozilla-central',
[task 2020-06-03T06:01:42.969Z] 06:01:42 INFO - 'GECKO_HEAD_REV': '3609aa746c291a437f02e467f94149c6fd0ddb0a',
[task 2020-06-03T06:01:42.969Z] 06:01:42 INFO - 'HG_CACHE': 'C:\\tooltool-cache',
[task 2020-06-03T06:01:42.969Z] 06:01:42 INFO - 'HOMEDRIVE': 'C:',
[task 2020-06-03T06:01:42.969Z] 06:01:42 INFO - 'HOMEPATH': '\\Users\\task_1591156499',
[task 2020-06-03T06:01:42.969Z] 06:01:42 INFO - 'JSGC_DISABLE_POISONING': '1',
[task 2020-06-03T06:01:42.969Z] 06:01:42 INFO - 'LD_LIBRARY_PATH': 'C:\\Users\\task_1591156499\\build\\application\\firefox',
[task 2020-06-03T06:01:42.970Z] 06:01:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\task_1591156499\\AppData\\Local',
[task 2020-06-03T06:01:42.970Z] 06:01:42 INFO - 'LOGONSERVER': '\\\\T-W1064-MS-210',
[task 2020-06-03T06:01:42.970Z] 06:01:42 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\Users\\task_1591156499\\build\\blobber_upload_dir',
[task 2020-06-03T06:01:42.971Z] 06:01:42 INFO - 'MINIDUMP_STACKWALK': 'C:/Users/task_1591156499/fetches\\minidump_stackwalk\\minidump_stackwalk.exe',
[task 2020-06-03T06:01:42.971Z] 06:01:42 INFO - 'MOZILLABUILD': 'C:\\mozilla-build',
[task 2020-06-03T06:01:42.972Z] 06:01:42 INFO - 'MOZ_AUTOMATION': '1',
[task 2020-06-03T06:01:42.972Z] 06:01:42 INFO - 'MOZ_CRASHREPORTER': '1',
[task 2020-06-03T06:01:42.972Z] 06:01:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
[task 2020-06-03T06:01:42.972Z] 06:01:42 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
[task 2020-06-03T06:01:42.972Z] 06:01:42 INFO - 'MOZ_FETCHES': '[{"artifact": "public/build/fix-stacks.tar.bz2", "extract": true, "task": "PVQ6u1jITNGxE6MEkXXpNQ"}, {"artifact": "public/build/minidump_stackwalk.tar.xz", "extract": true, "task": "DBbq4OcdQwmcpa3_mVjjJg"}]',
[task 2020-06-03T06:01:42.972Z] 06:01:42 INFO - 'MOZ_FETCHES_DIR': 'C:/Users/task_1591156499/fetches',
[task 2020-06-03T06:01:42.972Z] 06:01:42 INFO - 'MOZ_PROFILER_STARTUP': '1',
[task 2020-06-03T06:01:42.972Z] 06:01:42 INFO - 'MOZ_PROFILER_STARTUP_ENTRIES': '10000000',
[task 2020-06-03T06:01:42.972Z] 06:01:42 INFO - 'MOZ_PROFILER_STARTUP_FILTERS': 'GeckoMain,Compositor',
[task 2020-06-03T06:01:42.972Z] 06:01:42 INFO - 'MOZ_PROFILER_STARTUP_INTERVAL': '1',
[task 2020-06-03T06:01:42.973Z] 06:01:42 INFO - 'MOZ_SCM_LEVEL': '3',
[task 2020-06-03T06:01:42.973Z] 06:01:42 INFO - 'MOZ_UPLOAD_DIR': 'C:\\Users\\task_1591156499\\build\\blobber_upload_dir',
[task 2020-06-03T06:01:42.973Z] 06:01:42 INFO - 'MOZ_WEBRENDER': '0',
[task 2020-06-03T06:01:42.973Z] 06:01:42 INFO - 'NO_EM_RESTART': '1',
[task 2020-06-03T06:01:42.973Z] 06:01:42 INFO - 'NUMBER_OF_PROCESSORS': '8',
[task 2020-06-03T06:01:42.973Z] 06:01:42 INFO - 'ONEDRIVE': 'C:\\Users\\task_1591156499\\OneDrive',
[task 2020-06-03T06:01:42.973Z] 06:01:42 INFO - 'OS': 'Windows_NT',
[task 2020-06-03T06:01:42.973Z] 06:01:42 INFO - 'PATH': 'C:\\Users\\task_1591156499\\build\\venv\\Scripts;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\Program Files\\Puppet Labs\\Puppet\\bin;C:\\Program Files\\Mellanox\\MLNX_VPI\\IB\\Tools;C:\\Program Files\\Mellanox\\MLNX_CIMProvider\\lib\\mft;C:\\Program Files\\Git\\cmd;C:\\Program Files (x86)\\GNU\\GnuPG\\pub;C:\\Program Files\\Mercurial\\;C:\\Program Files\\Mercurial;C:\\mozilla-build\\bin;C:\\mozilla-build\\kdiff;C:\\mozilla-build\\moztools-x64\\bin;C:\\mozilla-build\\mozmake;C:\\mozilla-build\\nsis-3.01;C:\\mozilla-build\\python;C:\\mozilla-build\\python\\Scripts;C:\\mozilla-build\\python3;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\msys\\local\\bin;C:\\Program Files (x86)\\Windows Kits\\10\\Windows Performance Toolkit\\;C:\\Users\\task_1591156499\\AppData\\Local\\Microsoft\\WindowsApps',
[task 2020-06-03T06:01:42.973Z] 06:01:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
[task 2020-06-03T06:01:42.973Z] 06:01:42 INFO - 'PIP_DOWNLOAD_CACHE': 'C:\\pip-cache',
[task 2020-06-03T06:01:42.973Z] 06:01:42 INFO - 'PROCESSOR_ARCHITECTURE': 'AMD64',
[task 2020-06-03T06:01:42.973Z] 06:01:42 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 94 Stepping 3, GenuineIntel',
[task 2020-06-03T06:01:42.973Z] 06:01:42 INFO - 'PROCESSOR_LEVEL': '6',
[task 2020-06-03T06:01:42.973Z] 06:01:42 INFO - 'PROCESSOR_REVISION': '5e03',
[task 2020-06-03T06:01:42.974Z] 06:01:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
[task 2020-06-03T06:01:42.974Z] 06:01:42 INFO - 'PROGRAMFILES': 'C:\\Program Files',
[task 2020-06-03T06:01:42.974Z] 06:01:42 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
[task 2020-06-03T06:01:42.974Z] 06:01:42 INFO - 'PROGRAMW6432': 'C:\\Program Files',
[task 2020-06-03T06:01:42.974Z] 06:01:42 INFO - 'PROMPT': '$P$G',
[task 2020-06-03T06:01:42.974Z] 06:01:42 INFO - 'PSMODULEPATH': '%ProgramFiles%\\WindowsPowerShell\\Modules;C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules;C:\\Program Files\\Mellanox\\MLNX_VPI\\Tools\\WMI\\Modules;C:\\Program Files\\Mellanox\\MLNX_CIMProvider\\WMI\\Modules',
[task 2020-06-03T06:01:42.974Z] 06:01:42 INFO - 'PUBLIC': 'C:\\Users\\Public',
[task 2020-06-03T06:01:42.974Z] 06:01:42 INFO - 'PYTHONPATH': 'C:\\Users\\task_1591156499\\build\\tests\\talos',
[task 2020-06-03T06:01:42.974Z] 06:01:42 INFO - 'RUST_BACKTRACE': 'full',
[task 2020-06-03T06:01:42.974Z] 06:01:42 INFO - 'SCCACHE_DISABLE': '1',
[task 2020-06-03T06:01:42.974Z] 06:01:42 INFO - 'STYLO_FORCE_ENABLED': '1',
[task 2020-06-03T06:01:42.974Z] 06:01:42 INFO - 'SYSTEMDRIVE': 'C:',
[task 2020-06-03T06:01:42.974Z] 06:01:42 INFO - 'SYSTEMROOT': 'C:\\windows',
[task 2020-06-03T06:01:42.974Z] 06:01:42 INFO - 'TASKCLUSTER_ROOT_URL': 'https://firefox-ci-tc.services.mozilla.com',
[task 2020-06-03T06:01:42.975Z] 06:01:42 INFO - 'TASK_ID': 'ctqbhPTnRz2WuWxstiguMQ',
[task 2020-06-03T06:01:42.975Z] 06:01:42 INFO - 'TASK_STOP_TIME': '1591163702',
[task 2020-06-03T06:01:42.975Z] 06:01:42 INFO - 'TEMP': 'C:\\Users\\task_1591156499\\AppData\\Local\\Temp',
[task 2020-06-03T06:01:42.975Z] 06:01:42 INFO - 'TMP': 'C:\\Users\\task_1591156499\\AppData\\Local\\Temp',
[task 2020-06-03T06:01:42.975Z] 06:01:42 INFO - 'TOOLTOOL_CACHE': 'C:\\builds\\tooltool_cache',
[task 2020-06-03T06:01:42.975Z] 06:01:42 INFO - 'TPPROFILINGINFO': '{"gecko_profile_entries": 10000000, "gecko_profile_threads": "GeckoMain,Compositor", "gecko_profile_dir": "c:\\\\users\\\\task_1591156499\\\\appdata\\\\local\\\\temp\\\\tmphocyfd", "gecko_profile_interval": 1}',
[task 2020-06-03T06:01:42.975Z] 06:01:42 INFO - 'USERDOMAIN': 'T-W1064-MS-210',
[task 2020-06-03T06:01:42.975Z] 06:01:42 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W1064-MS-210',
[task 2020-06-03T06:01:42.975Z] 06:01:42 INFO - 'USERNAME': 'task_1591156499',
[task 2020-06-03T06:01:42.975Z] 06:01:42 INFO - 'USERPROFILE': 'C:\\Users\\task_1591156499',
[task 2020-06-03T06:01:42.975Z] 06:01:42 INFO - 'WINDIR': 'C:\\windows'}
[task 2020-06-03T06:01:42.975Z] 06:01:42 INFO - TEST-INFO | started process 3712 (C:\Users\task_1591156499\build\application\firefox\firefox -wait-for-browser -no-deelevate -profile c:\users\task_1591156499\appdata\local\temp\tmpow479f\profile about:home?gecko_profile_entries=10000000&gecko_profile_threads=GeckoMain%2CCompositor&gecko_profile_dir=c%3A%5Cusers%5Ctask_1591156499%5Cappdata%5Clocal%5Ctemp%5Ctmphocyfd&gecko_profile_interval=1 about:home?gecko_profile_entries=10000000&gecko_profile_threads=GeckoMain%2CCompositor&gecko_profile_dir=c%3A%5Cusers%5Ctask_1591156499%5Cappdata%5Clocal%5Ctemp%5Ctmphocyfd&gecko_profile_interval=1)
[task 2020-06-03T06:21:42.971Z] 06:21:42 INFO - Timeout waiting for test completion; killing browser...
[task 2020-06-03T06:21:42.971Z] 06:21:42 INFO - Launcher process psutil.Process(pid=3712L, name='firefox.exe', started='06:01:42') detected. Killing parent process psutil.Process(pid=5680, name='firefox.exe', started='06:01:42') instead.
[task 2020-06-03T06:21:42.971Z] 06:21:42 INFO - Killing psutil.Process(pid=5680, name='firefox.exe', started='06:01:42') and writing a minidump file
[task 2020-06-03T06:21:42.971Z] 06:21:42 INFO - mozcrash Writing a dump to c:\users\task_1591156499\appdata\local\temp\tmpow479f\profile\minidumps\8d869f26-8951-44d1-a6ed-eef97bc2084f.dmp for [5680]
[task 2020-06-03T06:21:42.971Z] 06:21:42 INFO - mozcrash unable to create minidump file for pid 5680: 3
[task 2020-06-03T06:21:43.112Z] 06:21:43 INFO - mozcrash checking c:\users\task_1591156499\appdata\local\temp\tmpow479f\profile\minidumps for minidumps...
[task 2020-06-03T06:21:43.112Z] 06:21:43 INFO - TEST-UNEXPECTED-ERROR | sessionrestore_many_windows | timeout
[task 2020-06-03T06:21:43.112Z] 06:21:43 ERROR - Traceback (most recent call last):
[task 2020-06-03T06:21:43.112Z] 06:21:43 INFO - File "C:\Users\task_1591156499\build\tests\talos\talos\run_tests.py", line 306, in run_tests
[task 2020-06-03T06:21:43.112Z] 06:21:43 INFO - talos_results.add(mytest.runTest(browser_config, test))
[task 2020-06-03T06:21:43.112Z] 06:21:43 INFO - File "C:\Users\task_1591156499\build\tests\talos\talos\ttest.py", line 64, in runTest
[task 2020-06-03T06:21:43.112Z] 06:21:43 INFO - return self._runTest(browser_config, test_config, setup)
[task 2020-06-03T06:21:43.112Z] 06:21:43 INFO - File "C:\Users\task_1591156499\build\tests\talos\talos\ttest.py", line 216, in _runTest
[task 2020-06-03T06:21:43.113Z] 06:21:43 INFO - debugger_args=browser_config['debugger_args']
[task 2020-06-03T06:21:43.113Z] 06:21:43 INFO - File "C:\Users\task_1591156499\build\tests\talos\talos\talos_process.py", line 155, in run_browser
[task 2020-06-03T06:21:43.113Z] 06:21:43 INFO - raise TalosError("timeout")
[task 2020-06-03T06:21:43.113Z] 06:21:43 INFO - TalosError: timeout
[task 2020-06-03T06:21:43.113Z] 06:21:43 INFO - TEST-INFO took 1247205ms
[task 2020-06-03T06:21:43.114Z] 06:21:43 INFO - SUITE-END | took 1247s
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
|
Assignee | |
Comment 4•4 years ago
|
||
This may be due to Base Profiler issues, which will get fixed in bug 1630448.
Depends on: 1630448
|
|
Assignee | |
Comment 5•4 years ago
|
||
It hasn't happened since bug 1630448 landed, marking this bug fixed.
Assignee: nobody → gsquelart
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → FIXED
You need to log in
before you can comment on or make changes to this bug.
Description
•