Closed
Bug 1738474
Opened 4 years ago
Closed 4 years ago
Intermittent motionmark_webgl | psutil.NoSuchProcess process no longer exists (pid=5640)
Categories
(Testing :: Talos, defect, P5)
Testing
Talos
Tracking
(Not tracked)
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: abutkovits [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=356460579&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Kj0vUS1DRZ6FdE3OhURNZA/runs/0/artifacts/public/logs/live_backing.log
[task 2021-10-29T16:44:30.013Z] 16:44:30 INFO - TEST-START | motionmark_webgl
[task 2021-10-29T16:44:30.014Z] 16:44:30 INFO - Initialising browser for motionmark_webgl test...
[task 2021-10-29T16:44:30.014Z] 16:44:30 INFO - Cloning profile located at C:\Users\task_1635514986\build\tests\talos\talos\base_profile
[task 2021-10-29T16:44:30.016Z] 16:44:30 INFO - Merging profile: C:\Users\task_1635514986\build\tests\talos\talos\profile_data\base
[task 2021-10-29T16:44:30.018Z] 16:44:30 INFO - Merging profile: C:\Users\task_1635514986\build\tests\talos\talos\profile_data\common
[task 2021-10-29T16:44:30.040Z] 16:44:30 INFO - Merging profile: C:\Users\task_1635514986\build\tests\talos\talos\profile_data\perf
[task 2021-10-29T16:44:30.047Z] 16:44:30 INFO - Installing Add-ons:
[task 2021-10-29T16:44:30.047Z] 16:44:30 INFO - ['C:\\Users\\task_1635514986\\build\\tests\\talos\\talos/talos-powers', 'C:\\Users\\task_1635514986\\build\\tests\\talos\\talos/pageloader']
[task 2021-10-29T16:44:30.077Z] 16:44:30 INFO - Installing Webextensions:
[task 2021-10-29T16:44:30.079Z] 16:44:30 INFO - Application command: C:\Users\task_1635514986\build\application\firefox\firefox -wait-for-browser -no-deelevate http://127.0.0.1:49800/getInfo.html --wait-for-browser -profile C:\Users\task_1635514986\AppData\Local\Temp\tmpooi3o29g\profile
[task 2021-10-29T16:44:30.082Z] 16:44:30 INFO - TEST-INFO | started process 4172 (C:\Users\task_1635514986\build\application\firefox\firefox -wait-for-browser -no-deelevate http://127.0.0.1:49800/getInfo.html)
[task 2021-10-29T16:44:41.652Z] 16:44:41 INFO - console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_1635514986\\AppData\\Local\\Temp\\tmpooi3o29g\\profile\\search.json.mozlz4", (void 0)))
[task 2021-10-29T16:44:43.620Z] 16:44:43 INFO - __metrics
[task 2021-10-29T16:44:43.621Z] 16:44:43 INFO - Screen width/height: 1536/864 (scaled from 1920/1080)
[task 2021-10-29T16:44:43.621Z] 16:44:43 INFO - colorDepth: 24
[task 2021-10-29T16:44:43.621Z] 16:44:43 INFO - Browser inner width/height: 1283/725
[task 2021-10-29T16:44:43.621Z] 16:44:43 INFO - __metrics
[task 2021-10-29T16:44:43.781Z] 16:44:43 INFO - JavaScript warning: resource://gre/modules/Troubleshoot.jsm, line 689: WebGL context was lost.
[task 2021-10-29T16:44:43.814Z] 16:44:43 INFO - JavaScript warning: resource://gre/modules/Troubleshoot.jsm, line 689: WebGL context was lost.
[task 2021-10-29T16:44:44.609Z] 16:44:44 INFO - [GFX1-]: Failed to connect WebRenderBridgeChild.
[task 2021-10-29T16:44:44.891Z] 16:44:44 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x39009B,name=PContent::Msg_InitBackground) Channel closing: too late to send/recv, messages will be lost
[task 2021-10-29T16:44:44.892Z] 16:44:44 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x39009B,name=PContent::Msg_InitBackground) Channel closing: too late to send/recv, messages will be lost
[task 2021-10-29T16:44:45.361Z] 16:44:45 INFO - console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2021-10-29T16:44:45.510Z] 16:44:45 INFO - JavaScript error: resource://gre/modules/JSONFile.jsm, line 186: Error: Data is not ready.
[task 2021-10-29T16:44:47.330Z] 16:44:47 INFO - console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2021-10-29T16:44:47.333Z] 16:44:47 INFO - console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2021-10-29T16:44:47.336Z] 16:44:47 INFO - console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2021-10-29T16:44:47.339Z] 16:44:47 INFO - console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2021-10-29T16:44:47.342Z] 16:44:47 INFO - console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2021-10-29T16:44:47.346Z] 16:44:47 INFO - console.error: "Could not load engine google@search.mozilla.org: [Exception... \"AddonManager is not initialized\" nsresult: \"0xc1f30001 (NS_ERROR_NOT_INITIALIZED)\" location: \"JS frame :: resource://gre/modules/AddonManager.jsm :: installBuiltinAddon :: line 2541\" data: no]"
[task 2021-10-29T16:44:47.349Z] 16:44:47 INFO - console.error: "Could not load engine amazondotcom@search.mozilla.org: [Exception... \"AddonManager is not initialized\" nsresult: \"0xc1f30001 (NS_ERROR_NOT_INITIALIZED)\" location: \"JS frame :: resource://gre/modules/AddonManager.jsm :: installBuiltinAddon :: line 2541\" data: no]"
[task 2021-10-29T16:44:47.350Z] 16:44:47 INFO - console.error: "Could not load engine wikipedia@search.mozilla.org: [Exception... \"AddonManager is not initialized\" nsresult: \"0xc1f30001 (NS_ERROR_NOT_INITIALIZED)\" location: \"JS frame :: resource://gre/modules/AddonManager.jsm :: installBuiltinAddon :: line 2541\" data: no]"
[task 2021-10-29T16:44:47.350Z] 16:44:47 INFO - console.error: "Could not load engine bing@search.mozilla.org: [Exception... \"AddonManager is not initialized\" nsresult: \"0xc1f30001 (NS_ERROR_NOT_INITIALIZED)\" location: \"JS frame :: resource://gre/modules/AddonManager.jsm :: installBuiltinAddon :: line 2541\" data: no]"
[task 2021-10-29T16:44:47.352Z] 16:44:47 INFO - console.error: "Could not load engine ddg@search.mozilla.org: [Exception... \"AddonManager is not initialized\" nsresult: \"0xc1f30001 (NS_ERROR_NOT_INITIALIZED)\" location: \"JS frame :: resource://gre/modules/AddonManager.jsm :: installBuiltinAddon :: line 2541\" data: no]"
[task 2021-10-29T16:44:47.353Z] 16:44:47 INFO - console.error: "Could not load engine ebay@search.mozilla.org: [Exception... \"AddonManager is not initialized\" nsresult: \"0xc1f30001 (NS_ERROR_NOT_INITIALIZED)\" location: \"JS frame :: resource://gre/modules/AddonManager.jsm :: installBuiltinAddon :: line 2541\" data: no]"
[task 2021-10-29T16:44:47.354Z] 16:44:47 INFO - console.warn: SearchService: "_init: abandoning init due to shutting down"
[task 2021-10-29T16:44:47.354Z] 16:44:47 INFO - JavaScript error: , line 0: uncaught exception: 2147500036
[task 2021-10-29T16:44:47.354Z] 16:44:47 INFO - JavaScript error: , line 0: uncaught exception: 2147500036
[task 2021-10-29T16:44:47.355Z] 16:44:47 INFO - JavaScript error: , line 0: uncaught exception: 2147500036
[task 2021-10-29T16:44:47.355Z] 16:44:47 INFO - JavaScript error: , line 0: uncaught exception: 2147500036
[task 2021-10-29T16:44:47.355Z] 16:44:47 INFO - JavaScript error: , line 0: uncaught exception: 2147500036
[task 2021-10-29T16:44:47.355Z] 16:44:47 INFO - JavaScript error: , line 0: uncaught exception: 2147500036
[task 2021-10-29T16:44:47.361Z] 16:44:47 INFO - JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 718: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "DoHController: clear state and remove observers"
[task 2021-10-29T16:44:47.565Z] 16:44:47 INFO - JavaScript error: resource:///modules/Discovery.jsm, line 104: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsICookieManager.remove]
[task 2021-10-29T16:44:47.996Z] 16:44:47 INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-10-29T16:44:48.267Z] 16:44:48 INFO - TEST-INFO | 4172: exit 0
[task 2021-10-29T16:44:49.191Z] 16:44:49 INFO - Browser initialized.
[task 2021-10-29T16:44:49.191Z] 16:44:49 INFO - Fission enabled: False
[task 2021-10-29T16:44:49.440Z] 16:44:49 INFO - Running cycle 1/1 for motionmark_webgl test...
[task 2021-10-29T16:44:49.442Z] 16:44:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
[task 2021-10-29T16:44:49.442Z] 16:44:49 INFO - 'APPDATA': 'C:\\Users\\task_1635514986\\AppData\\Roaming',
[task 2021-10-29T16:44:49.442Z] 16:44:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
[task 2021-10-29T16:44:49.443Z] 16:44:49 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
[task 2021-10-29T16:44:49.443Z] 16:44:49 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
[task 2021-10-29T16:44:49.443Z] 16:44:49 INFO - 'COMPUTERNAME': 'ACER-003',
[task 2021-10-29T16:44:49.444Z] 16:44:49 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
[task 2021-10-29T16:44:49.444Z] 16:44:49 INFO - 'DRIVERDATA': 'C:\\Windows\\System32\\Drivers\\DriverData',
[task 2021-10-29T16:44:49.444Z] 16:44:49 INFO - 'EXTRA_MOZHARNESS_CONFIG': '{"installer_url": '
[task 2021-10-29T16:44:49.445Z] 16:44:49 INFO - '"https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ePd9ZZAkT_CrapgaFZP9Ag/artifacts/public/build/target.zip", '
[task 2021-10-29T16:44:49.445Z] 16:44:49 INFO - '"test_packages_url": '
[task 2021-10-29T16:44:49.446Z] 16:44:49 INFO - '"https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ePd9ZZAkT_CrapgaFZP9Ag/artifacts/public/build/target.test_packages.json"}',
[task 2021-10-29T16:44:49.446Z] 16:44:49 INFO - 'GECKO_HEAD_REPOSITORY': 'https://hg.mozilla.org/integration/autoland',
[task 2021-10-29T16:44:49.447Z] 16:44:49 INFO - 'GECKO_HEAD_REV': 'c97b176528631e697dc1c1191b1f6b4dc1e0726a',
[task 2021-10-29T16:44:49.447Z] 16:44:49 INFO - 'HG_CACHE': 'C:\\tooltool-cache',
[task 2021-10-29T16:44:49.447Z] 16:44:49 INFO - 'HOMEDRIVE': 'C:',
[task 2021-10-29T16:44:49.448Z] 16:44:49 INFO - 'HOMEPATH': '\\Users\\task_1635514986',
[task 2021-10-29T16:44:49.448Z] 16:44:49 INFO - 'LD_LIBRARY_PATH': 'C:\\Users\\task_1635514986\\build\\application\\firefox',
[task 2021-10-29T16:44:49.449Z] 16:44:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\task_1635514986\\AppData\\Local',
[task 2021-10-29T16:44:49.449Z] 16:44:49 INFO - 'LOGONSERVER': '\\\\ACER-003',
[task 2021-10-29T16:44:49.449Z] 16:44:49 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\Users\\task_1635514986\\build\\blobber_upload_dir',
[task 2021-10-29T16:44:49.450Z] 16:44:49 INFO - 'MINIDUMP_STACKWALK': 'C:/Users/task_1635514986/fetches\\minidump_stackwalk\\minidump_stackwalk.exe',
[task 2021-10-29T16:44:49.450Z] 16:44:49 INFO - 'MOZILLABUILD': 'C:\\mozilla-build',
[task 2021-10-29T16:44:49.451Z] 16:44:49 INFO - 'MOZ_ACCELERATED': '1',
[task 2021-10-29T16:44:49.451Z] 16:44:49 INFO - 'MOZ_AUTOMATION': '1',
[task 2021-10-29T16:44:49.451Z] 16:44:49 INFO - 'MOZ_CRASHREPORTER': '1',
[task 2021-10-29T16:44:49.452Z] 16:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
[task 2021-10-29T16:44:49.452Z] 16:44:49 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
[task 2021-10-29T16:44:49.452Z] 16:44:49 INFO - 'MOZ_FETCHES': '[{"artifact": "public/build/fix-stacks.tar.zst", "extract": '
[task 2021-10-29T16:44:49.453Z] 16:44:49 INFO - 'true, "task": "S0_NWp4dR1KFRew-kHkEPA"}, {"artifact": '
[task 2021-10-29T16:44:49.453Z] 16:44:49 INFO - '"public/build/minidump_stackwalk.tar.zst", "extract": true, '
[task 2021-10-29T16:44:49.454Z] 16:44:49 INFO - '"task": "Egm-Cz33SUuCsVm7_1SAXA"}]',
[task 2021-10-29T16:44:49.454Z] 16:44:49 INFO - 'MOZ_FETCHES_DIR': 'C:/Users/task_1635514986/fetches',
[task 2021-10-29T16:44:49.454Z] 16:44:49 INFO - 'MOZ_SCM_LEVEL': '3',
[task 2021-10-29T16:44:49.455Z] 16:44:49 INFO - 'MOZ_UPLOAD_DIR': 'C:\\Users\\task_1635514986\\build\\blobber_upload_dir',
[task 2021-10-29T16:44:49.455Z] 16:44:49 INFO - 'MOZ_USE_PAGELOADER': '1',
[task 2021-10-29T16:44:49.455Z] 16:44:49 INFO - 'MOZ_WEBRENDER': '1',
[task 2021-10-29T16:44:49.456Z] 16:44:49 INFO - 'NO_EM_RESTART': '1',
[task 2021-10-29T16:44:49.456Z] 16:44:49 INFO - 'NUMBER_OF_PROCESSORS': '4',
[task 2021-10-29T16:44:49.456Z] 16:44:49 INFO - 'ONEDRIVE': 'C:\\Users\\task_1635514986\\OneDrive',
[task 2021-10-29T16:44:49.457Z] 16:44:49 INFO - 'OS': 'Windows_NT',
[task 2021-10-29T16:44:49.457Z] 16:44:49 INFO - 'PATH': 'C:\\Users\\task_1635514986\\build\\venv\\Scripts;C:\\WINDOWS\\system32;C:\\WINDOWS;C:\\WINDOWS\\System32\\Wbem;C:\\WINDOWS\\System32\\WindowsPowerShell\\v1.0\\;C:\\WINDOWS\\System32\\OpenSSH\\;C:\\Program '
[task 2021-10-29T16:44:49.458Z] 16:44:49 INFO - 'Files\\Git\\cmd;C:\\Program Files\\Puppet '
[task 2021-10-29T16:44:49.458Z] 16:44:49 INFO - 'Labs\\Puppet\\bin;C:\\Program Files '
[task 2021-10-29T16:44:49.458Z] 16:44:49 INFO - '(x86)\\GNU\\GnuPG\\pub;C:\\Program Files\\Mercurial\\;C:\\Program '
[task 2021-10-29T16:44:49.459Z] 16:44:49 INFO - '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 '
[task 2021-10-29T16:44:49.459Z] 16:44:49 INFO - 'Files (x86)\\Windows Kits\\10\\Windows Performance '
[task 2021-10-29T16:44:49.460Z] 16:44:49 INFO - 'Toolkit\\;C:\\Users\\task_1635514986\\AppData\\Local\\Microsoft\\WindowsApps',
[task 2021-10-29T16:44:49.460Z] 16:44:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
[task 2021-10-29T16:44:49.460Z] 16:44:49 INFO - 'PIP_DOWNLOAD_CACHE': 'C:\\pip-cache',
[task 2021-10-29T16:44:49.461Z] 16:44:49 INFO - 'PROCESSOR_ARCHITECTURE': 'AMD64',
[task 2021-10-29T16:44:49.461Z] 16:44:49 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 142 Stepping 9, GenuineIntel',
[task 2021-10-29T16:44:49.461Z] 16:44:49 INFO - 'PROCESSOR_LEVEL': '6',
[task 2021-10-29T16:44:49.462Z] 16:44:49 INFO - 'PROCESSOR_REVISION': '8e09',
[task 2021-10-29T16:44:49.462Z] 16:44:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
[task 2021-10-29T16:44:49.462Z] 16:44:49 INFO - 'PROGRAMFILES': 'C:\\Program Files',
[task 2021-10-29T16:44:49.462Z] 16:44:49 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
[task 2021-10-29T16:44:49.463Z] 16:44:49 INFO - 'PROGRAMW6432': 'C:\\Program Files',
[task 2021-10-29T16:44:49.463Z] 16:44:49 INFO - 'PROMPT': '$P$G',
[task 2021-10-29T16:44:49.464Z] 16:44:49 INFO - 'PSMODULEPATH': '%ProgramFiles%\\WindowsPowerShell\\Modules;C:\\WINDOWS\\system32\\WindowsPowerShell\\v1.0\\Modules',
[task 2021-10-29T16:44:49.464Z] 16:44:49 INFO - 'PUBLIC': 'C:\\Users\\Public',
[task 2021-10-29T16:44:49.464Z] 16:44:49 INFO - 'PYTHON': 'c:\\mozilla-build\\python3\\python3.exe',
[task 2021-10-29T16:44:49.465Z] 16:44:49 INFO - 'PYTHONPATH': 'C:\\Users\\task_1635514986\\build\\tests\\talos',
[task 2021-10-29T16:44:49.465Z] 16:44:49 INFO - 'RUST_BACKTRACE': 'full',
[task 2021-10-29T16:44:49.465Z] 16:44:49 INFO - 'SCCACHE_DISABLE': '1',
[task 2021-10-29T16:44:49.465Z] 16:44:49 INFO - 'STYLO_FORCE_ENABLED': '1',
[task 2021-10-29T16:44:49.466Z] 16:44:49 INFO - 'SYSTEMDRIVE': 'C:',
[task 2021-10-29T16:44:49.466Z] 16:44:49 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
[task 2021-10-29T16:44:49.466Z] 16:44:49 INFO - 'TASKCLUSTER_ROOT_URL': 'https://firefox-ci-tc.services.mozilla.com',
[task 2021-10-29T16:44:49.467Z] 16:44:49 INFO - 'TASK_ID': 'Kj0vUS1DRZ6FdE3OhURNZA',
[task 2021-10-29T16:44:49.467Z] 16:44:49 INFO - 'TASK_STOP_TIME': '1635522208',
[task 2021-10-29T16:44:49.467Z] 16:44:49 INFO - 'TEMP': 'C:\\Users\\task_1635514986\\AppData\\Local\\Temp',
[task 2021-10-29T16:44:49.468Z] 16:44:49 INFO - 'TMP': 'C:\\Users\\task_1635514986\\AppData\\Local\\Temp',
[task 2021-10-29T16:44:49.468Z] 16:44:49 INFO - 'TOOLTOOL_CACHE': 'C:\\builds\\tooltool_cache',
[task 2021-10-29T16:44:49.468Z] 16:44:49 INFO - 'USERDOMAIN': 'ACER-003',
[task 2021-10-29T16:44:49.469Z] 16:44:49 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'ACER-003',
[task 2021-10-29T16:44:49.469Z] 16:44:49 INFO - 'USERNAME': 'task_1635514986',
[task 2021-10-29T16:44:49.469Z] 16:44:49 INFO - 'USERPROFILE': 'C:\\Users\\task_1635514986',
[task 2021-10-29T16:44:49.470Z] 16:44:49 INFO - 'VIRTUAL_ENV': 'C:\\Users\\task_1635514986\\build\\venv',
[task 2021-10-29T16:44:49.470Z] 16:44:49 INFO - 'WINDIR': 'C:\\WINDOWS'}
[task 2021-10-29T16:44:49.471Z] 16:44:49 INFO - TEST-INFO | started process 5640 (C:\Users\task_1635514986\build\application\firefox\firefox -wait-for-browser -no-deelevate -profile C:\Users\task_1635514986\AppData\Local\Temp\tmpooi3o29g\profile)
[task 2021-10-29T16:44:50.162Z] 16:44:50 INFO - PID 5640 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_1635514986\\AppData\\Local\\Temp\\tmpooi3o29g\\profile\\search.json.mozlz4", (void 0)))
[task 2021-10-29T16:45:09.566Z] 16:45:09 INFO - PID 5640 | Cycle 1(1): loaded http://127.0.0.1:49800/tests/webkit/PerformanceTests/MotionMark/developer.html?test-interval=15&display=minimal&tiles=big&controller=fixed&frame-rate=50&kalman-process-error=1&kalman-measurement-error=4&time-measurement=performance&suite-name=3DGraphics&test-name=WebGL&complexity=30000 (next: http://127.0.0.1:49800/tests/webkit/PerformanceTests/MotionMark/developer.html?test-interval=15&display=minimal&tiles=big&controller=fixed&frame-rate=50&kalman-process-error=1&kalman-measurement-error=4&time-measurement=performance&suite-name=3DGraphics&test-name=WebGL&complexity=30000)
[task 2021-10-29T16:45:25.648Z] 16:45:25 INFO - PID 5640 | Cycle 1(2): loaded http://127.0.0.1:49800/tests/webkit/PerformanceTests/MotionMark/developer.html?test-interval=15&display=minimal&tiles=big&controller=fixed&frame-rate=50&kalman-process-error=1&kalman-measurement-error=4&time-measurement=performance&suite-name=3DGraphics&test-name=WebGL&complexity=30000 (next: http://127.0.0.1:49800/tests/webkit/PerformanceTests/MotionMark/developer.html?test-interval=15&display=minimal&tiles=big&controller=fixed&frame-rate=50&kalman-process-error=1&kalman-measurement-error=4&time-measurement=performance&suite-name=3DGraphics&test-name=WebGL&complexity=30000)
[task 2021-10-29T16:45:42.096Z] 16:45:42 INFO - PID 5640 | Cycle 1(3): loaded http://127.0.0.1:49800/tests/webkit/PerformanceTests/MotionMark/developer.html?test-interval=15&display=minimal&tiles=big&controller=fixed&frame-rate=50&kalman-process-error=1&kalman-measurement-error=4&time-measurement=performance&suite-name=3DGraphics&test-name=WebGL&complexity=30000 (next: http://127.0.0.1:49800/tests/webkit/PerformanceTests/MotionMark/developer.html?test-interval=15&display=minimal&tiles=big&controller=fixed&frame-rate=50&kalman-process-error=1&kalman-measurement-error=4&time-measurement=performance&suite-name=3DGraphics&test-name=WebGL&complexity=30000)
[task 2021-10-29T16:45:58.179Z] 16:45:58 INFO - PID 5640 | Cycle 1(4): loaded http://127.0.0.1:49800/tests/webkit/PerformanceTests/MotionMark/developer.html?test-interval=15&display=minimal&tiles=big&controller=fixed&frame-rate=50&kalman-process-error=1&kalman-measurement-error=4&time-measurement=performance&suite-name=3DGraphics&test-name=WebGL&complexity=30000 (next: http://127.0.0.1:49800/tests/webkit/PerformanceTests/MotionMark/developer.html?test-interval=15&display=minimal&tiles=big&controller=fixed&frame-rate=50&kalman-process-error=1&kalman-measurement-error=4&time-measurement=performance&suite-name=3DGraphics&test-name=WebGL&complexity=30000)
[task 2021-10-29T16:46:19.058Z] 16:46:19 INFO - PID 5640 | Cycle 1(5): loaded http://127.0.0.1:49800/tests/webkit/PerformanceTests/MotionMark/developer.html?test-interval=15&display=minimal&tiles=big&controller=fixed&frame-rate=50&kalman-process-error=1&kalman-measurement-error=4&time-measurement=performance&suite-name=3DGraphics&test-name=WebGL&complexity=30000 (next: http://127.0.0.1:49800/tests/webkit/PerformanceTests/MotionMark/developer.html?test-interval=15&display=minimal&tiles=big&controller=fixed&frame-rate=50&kalman-process-error=1&kalman-measurement-error=4&time-measurement=performance&suite-name=3DGraphics&test-name=WebGL&complexity=30000)
[task 2021-10-29T16:46:19.723Z] 16:46:19 INFO - PID 5640 | __start_tp_report
[task 2021-10-29T16:46:19.949Z] 16:46:19 INFO - PID 5640 | _x_x_mozilla_page_load
[task 2021-10-29T16:46:19.950Z] 16:46:19 INFO - PID 5640 | _x_x_mozilla_page_load_details
[task 2021-10-29T16:46:19.950Z] 16:46:19 INFO - PID 5640 | |i|pagename|runs|
[task 2021-10-29T16:46:19.951Z] 16:46:19 INFO - PID 5640 | |0;3DGraphics-WebGL;7.950102750067476;8.411428327287341;8.689165813346024;8.74526187052473;8.558048978784061
[task 2021-10-29T16:46:19.951Z] 16:46:19 INFO - PID 5640 | __end_tp_report
[task 2021-10-29T16:46:19.951Z] 16:46:19 INFO - PID 5640 | __start_cc_report
[task 2021-10-29T16:46:19.952Z] 16:46:19 INFO - PID 5640 | _x_x_mozilla_cycle_collect,99.55272590583627
[task 2021-10-29T16:46:19.952Z] 16:46:19 INFO - PID 5640 | __end_cc_report
[task 2021-10-29T16:46:19.952Z] 16:46:19 INFO - PID 5640 | __startTimestamp88997.05813709892__endTimestamp
[task 2021-10-29T16:46:19.952Z] 16:46:19 INFO - PID 5640 |
[task 2021-10-29T16:46:19.953Z] 16:46:19 INFO - PID 5640 | ------- Summary: start -------
[task 2021-10-29T16:46:19.953Z] 16:46:19 INFO - PID 5640 | Number of tests: 1
[task 2021-10-29T16:46:19.953Z] 16:46:19 INFO - PID 5640 |
[task 2021-10-29T16:46:19.954Z] 16:46:19 INFO - PID 5640 | [#0] 3DGraphics-WebGL Cycles:5 Average:8.47 Median:8.56 stddev:0.32 (3.7%) stddev-sans-first:0.15
[task 2021-10-29T16:46:19.954Z] 16:46:19 INFO - PID 5640 | Values: 8.0 8.4 8.7 8.7 8.6
[task 2021-10-29T16:46:19.954Z] 16:46:19 INFO - PID 5640 | -------- Summary: end --------
[task 2021-10-29T16:46:19.954Z] 16:46:19 INFO - PID 5640 |
[task 2021-10-29T16:46:25.240Z] 16:46:25 INFO - PID 5640 | [Parent 3344, IPC I/O Parent] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc:167
[task 2021-10-29T16:46:34.392Z] 16:46:34 INFO - PID 5640 | console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.jsm", 446))
[task 2021-10-29T16:46:57.961Z] 16:46:57 INFO - Browser shutdown timed out after 20 seconds, killing process.
[task 2021-10-29T16:47:06.969Z] 16:47:06 INFO - mozcrash checking C:\Users\task_1635514986\AppData\Local\Temp\tmpooi3o29g\profile\minidumps for minidumps...
[task 2021-10-29T16:47:06.969Z] 16:47:06 INFO - TEST-UNEXPECTED-ERROR | motionmark_webgl | psutil.NoSuchProcess process no longer exists (pid=5640)
[task 2021-10-29T16:47:06.969Z] 16:47:06 ERROR - Traceback (most recent call last):
[task 2021-10-29T16:47:06.970Z] 16:47:06 INFO - File "C:\Users\task_1635514986\build\tests\talos\talos\run_tests.py", line 336, in run_tests
[task 2021-10-29T16:47:06.970Z] 16:47:06 INFO - talos_results.add(mytest.runTest(browser_config, test))
[task 2021-10-29T16:47:06.971Z] 16:47:06 INFO - File "C:\Users\task_1635514986\build\tests\talos\talos\ttest.py", line 65, in runTest
[task 2021-10-29T16:47:06.971Z] 16:47:06 INFO - return self._runTest(browser_config, test_config, setup)
[task 2021-10-29T16:47:06.971Z] 16:47:06 INFO - File "C:\Users\task_1635514986\build\tests\talos\talos\ttest.py", line 215, in _runTest
[task 2021-10-29T16:47:06.971Z] 16:47:06 INFO - debugger_args=browser_config["debugger_args"],
[task 2021-10-29T16:47:06.972Z] 16:47:06 INFO - File "C:\Users\task_1635514986\build\tests\talos\talos\talos_process.py", line 183, in run_browser
[task 2021-10-29T16:47:06.972Z] 16:47:06 INFO - kill_and_get_minidump(context, minidump_dir)
[task 2021-10-29T16:47:06.973Z] 16:47:06 INFO - File "C:\Users\task_1635514986\build\tests\talos\talos\talos_process.py", line 267, in kill_and_get_minidump
[task 2021-10-29T16:47:06.973Z] 16:47:06 INFO - kids = context.process.children()
[task 2021-10-29T16:47:06.973Z] 16:47:06 INFO - File "C:\Users\task_1635514986\build\venv\lib\site-packages\psutil\__init__.py", line 291, in wrapper
[task 2021-10-29T16:47:07.499Z] 16:47:07 INFO - raise NoSuchProcess(self.pid, self._name)
[task 2021-10-29T16:47:07.499Z] 16:47:07 INFO - psutil.NoSuchProcess: psutil.NoSuchProcess process no longer exists (pid=5640)
[task 2021-10-29T16:47:07.499Z] 16:47:07 INFO - TEST-INFO took 156757ms
[task 2021-10-29T16:47:07.500Z] 16:47:07 INFO - SUITE-END | took 430s
[task 2021-10-29T16:48:04.763Z] 16:48:04 INFO - Exception ignored in: <bound method ProcessHandlerMixin.Process.__del__ of <mozprocess.processhandler.ProcessHandlerMixin.Process object at 0x000001B5D6493F60>>
[task 2021-10-29T16:48:04.764Z] 16:48:04 ERROR - Traceback (most recent call last):
[task 2021-10-29T16:48:04.764Z] 16:48:04 INFO - File "C:\Users\task_1635514986\build\venv\lib\site-packages\mozprocess\processhandler.py", line 192, in __del__
[task 2021-10-29T16:48:04.764Z] 16:48:04 INFO - self._internal_poll(_deadstate=_maxint)
[task 2021-10-29T16:48:04.765Z] 16:48:04 INFO - File "c:\mozilla-build\python3\lib\subprocess.py", line 1035, in _internal_poll
[task 2021-10-29T16:48:04.765Z] 16:48:04 INFO - if _WaitForSingleObject(self._handle, 0) == _WAIT_OBJECT_0:
[task 2021-10-29T16:48:04.765Z] 16:48:04 ERROR - TypeError: WaitForSingleObject() argument 1 must be int, not AutoHANDLE
[task 2021-10-29T16:48:05.410Z] 16:48:05 ERROR - Return code: 2
[task 2021-10-29T16:48:05.410Z] 16:48:05 WARNING - setting return code to 2
[task 2021-10-29T16:48:05.410Z] 16:48:05 ERROR - # TBPL FAILURE #
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•