Skip to content

Instantly share code, notes, and snippets.

@Gankra

Gankra/log.txt

Created April 4, 2018 20:55
Show Gist options
  • Save Gankra/b66248717e61879d12fb75cbf67a3aaf to your computer and use it in GitHub Desktop.
Save Gankra/b66248717e61879d12fb75cbf67a3aaf to your computer and use it in GitHub Desktop.
Download ZIP
Raw
log.txt
$ ./mach talos-test --activeTests tp5o_scroll --geckoProfile
16:46:08 INFO - MultiFileLogger online at 20180404 16:46:08 in c:\gecko
16:46:08 INFO - Run as ./mach talos-test --activeTests tp5o_scroll --geckoProfile
16:46:08 INFO - Dumping config to c:/gecko\testing\mozharness\logs\localconfig.json.
16:46:08 INFO - {'append_to_log': False,
16:46:08 INFO - u'base_work_dir': u'c:/gecko\\testing\\mozharness',
16:46:08 INFO - u'binary_path': u'c:/gecko/obj-i686-pc-mingw32\\dist\\bin\\firefox.exe',
16:46:08 INFO - 'code_coverage': False,
16:46:08 INFO - u'default_actions': (u'populate-webroot',
16:46:08 INFO - u'create-virtualenv',
16:46:08 INFO - u'setup-mitmproxy',
16:46:08 INFO - u'run-tests'),
16:46:08 INFO - 'disable_ccov_upload': False,
16:46:08 INFO - 'e10s': False,
16:46:08 INFO - 'enable_webrender': False,
16:46:08 INFO - u'exes': {u'python': u'c:\\mozilla-build\\python\\python2.7.exe',
16:46:09 INFO - u'virtualenv': (u'c:\\mozilla-build\\python\\python2.7.exe',
16:46:09 INFO - u'c:/gecko\\third_party\\python\\virtualenv\\virtualenv.py')},
16:46:09 INFO - 'gecko_profile': False,
16:46:09 INFO - 'gecko_profile_interval': 0,
16:46:09 INFO - 'jsd_code_coverage': False,
16:46:09 INFO - 'log_level': 'info',
16:46:09 INFO - u'log_name': u'talos',
16:46:09 INFO - 'log_to_console': True,
16:46:09 INFO - u'obj_path': u'c:/gecko/obj-i686-pc-mingw32',
16:46:09 INFO - 'opt_config_files': (),
16:46:09 INFO - 'pip_index': True,
16:46:09 INFO - u'pypi_url': u'http://pypi.python.org/simple',
16:46:09 INFO - u'python3_manifest': {u'win32': u'python3.manifest',
16:46:09 INFO - u'win64': u'python3_x64.manifest'},
16:46:09 INFO - u'repo_path': u'c:/gecko',
16:46:09 INFO - u'run_local': True,
16:46:09 INFO - 'system_bits': '32',
16:46:09 INFO - u'talos_extra_options': (u'--develop',
16:46:09 INFO - u'--activeTests',
16:46:09 INFO - u'tp5o_scroll',
16:46:09 INFO - u'--geckoProfile'),
16:46:09 INFO - u'talos_json': u'c:/gecko\\testing\\talos\\talos.json',
16:46:09 INFO - u'title': u'DESKTOP-M6UNB64',
16:46:09 INFO - 'use_talos_json': False,
16:46:09 INFO - 'verify': 'False',
16:46:09 INFO - u'virtualenv_path': u'c:/gecko/obj-i686-pc-mingw32\\testing\\talos-venv',
16:46:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
16:46:09 INFO - 'work_dir': 'build'}
16:46:09 INFO - [mozharness: 2018-04-04 20:46:09.157000Z] Skipping clobber step.
16:46:09 INFO - [mozharness: 2018-04-04 20:46:09.157000Z] Skipping read-buildbot-config step.
16:46:09 INFO - [mozharness: 2018-04-04 20:46:09.157000Z] Skipping download-and-extract step.
16:46:09 INFO - [mozharness: 2018-04-04 20:46:09.157000Z] Running populate-webroot step.
16:46:09 INFO - Running pre-action listener: _resource_record_pre_action
16:46:09 INFO - Running main action method: populate_webroot
16:46:09 INFO - {u'suites': {u'chromez-e10s': {u'tests': [u'about_preferences_basic',
16:46:09 INFO - u'tresize']},
16:46:09 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
16:46:09 INFO - u'flex-e10s': {u'tests': [u'tart_flex', u'ts_paint_flex']},
16:46:09 INFO - u'g1-e10s': {u'pagesets_name': u'tp5n.zip',
16:46:09 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
16:46:09 INFO - u'g2-e10s': {u'pagesets_name': u'tp5n.zip',
16:46:09 INFO - u'tests': [u'damp', u'tps']},
16:46:09 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
16:46:09 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video',
16:46:09 INFO - u'glvideo',
16:46:09 INFO - u'displaylist_mutate',
16:46:09 INFO - u'rasterflood_svg',
16:46:09 INFO - u'rasterflood_gradient']},
16:46:09 INFO - u'g5-e10s': {u'pagesets_name': u'tp5n.zip',
16:46:09 INFO - u'tests': [u'ts_paint_webext', u'tp5o_webext']},
16:46:09 INFO - u'h1-e10s': {u'tests': [u'ts_paint_heavy']},
16:46:09 INFO - u'h2-e10s': {u'mitmproxy_recording_set': u'mitmproxy-recording-set-win10.zip',
16:46:09 INFO - u'mitmproxy_release_bin_linux64': u'mitmproxy-2.0.2-linux.tar.gz',
16:46:09 INFO - u'mitmproxy_release_bin_osx': u'mitmproxy-2.0.2-osx.tar.gz',
16:46:09 INFO - u'talos_options': [u'--mitmproxy',
16:46:09 INFO - u'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
16:46:09 INFO - u'--firstNonBlankPaint'],
16:46:09 INFO - u'tests': [u'tp6_google_heavy',
16:46:09 INFO - u'tp6_youtube_heavy',
16:46:09 INFO - u'tp6_amazon_heavy',
16:46:09 INFO - u'tp6_facebook_heavy']},
16:46:09 INFO - u'motionmark-e10s': {u'benchmark_zip': u'jetstream.zip',
16:46:09 INFO - u'tests': [u'motionmark_animometer',
16:46:09 INFO - u'motionmark_htmlsuite',
16:46:09 INFO - u'JetStream',
16:46:09 INFO - u'ARES6']},
16:46:09 INFO - u'other-e10s': {u'tests': [u'a11yr',
16:46:09 INFO - u'ts_paint',
16:46:09 INFO - u'tpaint',
16:46:09 INFO - u'sessionrestore',
16:46:09 INFO - u'sessionrestore_many_windows',
16:46:09 INFO - u'sessionrestore_no_auto_restore',
16:46:09 INFO - u'tabpaint',
16:46:09 INFO - u'cpstartup']},
16:46:09 INFO - u'perf-reftest-e10s': {u'tests': [u'perf_reftest']},
16:46:09 INFO - u'perf-reftest-singletons-e10s': {u'tests': [u'perf_reftest_singletons']},
16:46:09 INFO - u'speedometer-e10s': {u'tests': [u'speedometer', u'stylebench']},
16:46:09 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
16:46:09 INFO - u'tsvgr_opacity',
16:46:09 INFO - u'tart',
16:46:09 INFO - u'tscrollx',
16:46:09 INFO - u'tsvg_static']},
16:46:09 INFO - u'tp5o-e10s': {u'pagesets_name': u'tp5n.zip',
16:46:09 INFO - u'tests': [u'tp5o']},
16:46:09 INFO - u'tp6-e10s': {u'mitmproxy_recording_set': u'mitmproxy-recording-set-win10.zip',
16:46:09 INFO - u'mitmproxy_release_bin_linux64': u'mitmproxy-2.0.2-linux.tar.gz',
16:46:09 INFO - u'mitmproxy_release_bin_osx': u'mitmproxy-2.0.2-osx.tar.gz',
16:46:09 INFO - u'talos_options': [u'--mitmproxy',
16:46:09 INFO - u'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
16:46:09 INFO - u'--firstNonBlankPaint'],
16:46:09 INFO - u'tests': [u'tp6_google',
16:46:09 INFO - u'tp6_youtube',
16:46:09 INFO - u'tp6_amazon',
16:46:09 INFO - u'tp6_facebook']},
16:46:09 INFO - u'tp6-stylo-threads-e10s': {u'mitmproxy_recording_set': u'mitmproxy-recording-set-win10.zip',
16:46:09 INFO - u'mitmproxy_release_bin_linux64': u'mitmproxy-2.0.2-linux.tar.gz',
16:46:09 INFO - u'mitmproxy_release_bin_osx': u'mitmproxy-2.0.2-osx.tar.gz',
16:46:09 INFO - u'talos_options': [u'--stylo-threads=1',
16:46:09 INFO - u'--mitmproxy',
16:46:09 INFO - u'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
16:46:09 INFO - u'--firstNonBlankPaint'],
16:46:09 INFO - u'tests': [u'tp6_google',
16:46:09 INFO - u'tp6_youtube',
16:46:09 INFO - u'tp6_amazon',
16:46:09 INFO - u'tp6_facebook']},
16:46:09 INFO - u'xperf-e10s': {u'pagesets_name': u'tp5n.zip',
16:46:09 INFO - u'talos_options': [u'--xperf_path',
16:46:09 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
16:46:09 INFO - u'tests': [u'tp5n']}}}
16:46:09 INFO - Downloading {'name': u'tp5n.zip', 'manifest': 'tp5n-pageset.manifest'} with tooltool...
16:46:09 INFO - {'name': u'tp5n.zip', 'manifest': 'tp5n-pageset.manifest'} already available
16:46:09 INFO - Running post-action listener: _resource_record_post_action
16:46:09 INFO - [mozharness: 2018-04-04 20:46:09.516000Z] Finished populate-webroot step (success)
16:46:09 INFO - [mozharness: 2018-04-04 20:46:09.516000Z] Running create-virtualenv step.
16:46:09 INFO - Running pre-action listener: _resource_record_pre_action
16:46:09 INFO - Running main action method: create_virtualenv
16:46:09 INFO - Virtualenv already exists, skipping creation
16:46:09 INFO - Running post-action listener: _resource_record_post_action
16:46:09 INFO - Running post-action listener: _start_resource_monitoring
16:46:09 INFO - Starting resource monitoring.
16:46:09 INFO - [mozharness: 2018-04-04 20:46:09.594000Z] Finished create-virtualenv step (success)
16:46:09 INFO - [mozharness: 2018-04-04 20:46:09.594000Z] Skipping install step.
16:46:09 INFO - [mozharness: 2018-04-04 20:46:09.594000Z] Running setup-mitmproxy step.
16:46:09 INFO - Running pre-action listener: _resource_record_pre_action
16:46:09 INFO - Running main action method: setup_mitmproxy
16:46:09 INFO - Skipping: mitmproxy is not required
16:46:09 INFO - Running post-action listener: _resource_record_post_action
16:46:09 INFO - [mozharness: 2018-04-04 20:46:09.610000Z] Finished setup-mitmproxy step (success)
16:46:09 INFO - [mozharness: 2018-04-04 20:46:09.610000Z] Running run-tests step.
16:46:09 INFO - Running pre-action listener: _resource_record_pre_action
16:46:09 INFO - Running pre-action listener: _set_gcov_prefix
16:46:09 INFO - Running main action method: run_tests
16:46:09 INFO - Running command: [u'c:\\gecko\\obj-i686-pc-mingw32\\testing\\talos-venv\\Scripts\\python', '--version']
16:46:09 INFO - Copy/paste: c:\gecko\obj-i686-pc-mingw32\testing\talos-venv\Scripts\python --version
16:46:09 INFO - Python 2.7.13
16:46:09 INFO - Return code: 0
16:46:09 INFO - ENV: MOZ_UPLOAD_DIR is now c:/gecko\testing\mozharness\build\blobber_upload_dir
16:46:09 INFO - ENV: RUST_BACKTRACE is now full
16:46:09 INFO - ENV: MINIDUMP_SAVE_PATH is now c:/gecko\testing\mozharness\build\blobber_upload_dir
16:46:09 INFO - Running command: [u'c:\\gecko\\obj-i686-pc-mingw32\\testing\\talos-venv\\Scripts\\python', u'c:/gecko\\testing\\talos\\talos\\run_tests.py', '--executablePath', u'c:/gecko/obj-i686-pc-mingw32\\dist\\bin\\firefox', '--title', u'DESKTOP-M6UNB64', u'--develop', u'--activeTests', u'tp5o_scroll', u'--geckoProfile', '--log-tbpl-level=debug'] in c:/gecko\testing\mozharness\build
16:46:09 INFO - Copy/paste: c:\gecko\obj-i686-pc-mingw32\testing\talos-venv\Scripts\python c:/gecko\testing\talos\talos\run_tests.py --executablePath c:/gecko/obj-i686-pc-mingw32\dist\bin\firefox --title DESKTOP-M6UNB64 --develop --activeTests tp5o_scroll --geckoProfile --log-tbpl-level=debug
16:46:09 INFO - Using env: {'!::': '::\\',
16:46:09 INFO - '!EXITCODE': '00000001',
16:46:09 INFO - 'ALLUSERSPROFILE': 'C:\\ProgramData',
16:46:09 INFO - 'APPDATA': 'C:\\Users\\Alexis Beingessner\\AppData\\Roaming',
16:46:09 INFO - 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey',
16:46:09 INFO - 'CHOCOLATEYLASTPATHUPDATE': 'Wed Apr 4 13:02:47 2018',
16:46:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
16:46:09 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
16:46:09 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
16:46:09 INFO - 'COMPUTERNAME': 'DESKTOP-M6UNB64',
16:46:09 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
16:46:09 INFO - 'EDITOR': 'emacs.exe',
16:46:09 INFO - 'FPS_BROWSER_APP_PROFILE_STRING': 'Internet Explorer',
16:46:09 INFO - 'FPS_BROWSER_USER_PROFILE_STRING': 'Default',
16:46:09 INFO - 'FSHARPINSTALLDIR': 'C:\\Program Files (x86)\\Microsoft SDKs\\F#\\10.1\\Framework\\v4.0\\',
16:46:09 INFO - 'HISTFILE': 'C:/mozilla-build/msys/home/Alexis Beingessner/.bash_history',
16:46:09 INFO - 'HOME': 'c:/Users/Alexis Beingessner',
16:46:09 INFO - 'HOMEDRIVE': 'C:',
16:46:09 INFO - 'HOMEPATH': '\\',
16:46:09 INFO - 'HOSTTYPE': 'i686',
16:46:09 INFO - 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc',
16:46:09 INFO - 'LLVMKEY': 'HKLM\\SOFTWARE\\Wow6432Node\\LLVM\\LLVM',
16:46:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\Alexis Beingessner\\AppData\\Local',
16:46:09 INFO - 'LOGNAME': 'Alexis Beingessner',
16:46:09 INFO - 'LOGONSERVER': '\\\\DESKTOP-M6UNB64',
16:46:09 INFO - 'MACHTYPE': 'i686-pc-msys',
16:46:09 INFO - 'MACH_STDOUT_ISATTY': '1',
16:46:09 INFO - 'MAKE_MODE': 'unix',
16:46:09 INFO - 'MINIDUMP_SAVE_PATH': u'c:/gecko\\testing\\mozharness\\build\\blobber_upload_dir',
16:46:09 INFO - 'MOZILLABUILD': 'C:\\mozilla-build\\',
16:46:09 INFO - 'MOZ_DEVELOPER_OBJ_DIR': u'c:/gecko/obj-i686-pc-mingw32',
16:46:09 INFO - 'MOZ_DEVELOPER_REPO_DIR': u'c:/gecko',
16:46:09 INFO - 'MOZ_UPLOAD_DIR': u'c:/gecko\\testing\\mozharness\\build\\blobber_upload_dir',
16:46:09 INFO - 'MSYSTEM': 'MINGW32',
16:46:09 INFO - 'NUMBER_OF_PROCESSORS': '8',
16:46:09 INFO - 'ONEDRIVE': 'C:\\Users\\Alexis Beingessner\\OneDrive',
16:46:09 INFO - 'OS': 'Windows_NT',
16:46:09 INFO - 'OSTYPE': 'msys',
16:46:09 INFO - 'PATH': u'c:\\gecko\\obj-i686-pc-mingw32\\testing\\talos-venv\\Scripts;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\7zip;c:\\mozilla-build\\info-zip;c:\\mozilla-build\\kdiff3;c:\\mozilla-build\\mozmake;c:\\mozilla-build\\node-v8.1.4-win-x64;c:\\mozilla-build\\nsis-3.01;c:\\mozilla-build\\python;c:\\mozilla-build\\python\\Scripts;c:\\mozilla-build\\python3;c:\\mozilla-build\\python3\\Scripts;c:\\mozilla-build\\upx394w;c:\\mozilla-build\\watchman;c:\\mozilla-build\\wget;c:\\mozilla-build\\yasm;.;C:\\mozilla-build\\msys\\local\\bin;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;c:\\WINDOWS\\System32;c:\\WINDOWS;c:\\WINDOWS\\System32\\Wbem;c:\\Users\\Alexis Beingessner\\.cargo\\bin',
16:46:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
16:46:09 INFO - 'PROCESSOR_ARCHITECTURE': 'AMD64',
16:46:09 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 158 Stepping 9, GenuineIntel',
16:46:09 INFO - 'PROCESSOR_LEVEL': '6',
16:46:09 INFO - 'PROCESSOR_REVISION': '9e09',
16:46:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
16:46:09 INFO - 'PROGRAMFILES': 'C:\\Program Files',
16:46:09 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
16:46:10 INFO - 'PROGRAMW6432': 'C:\\Program Files',
16:46:10 INFO - 'PROMPT': '$P$G',
16:46:10 INFO - 'PSMODULEPATH': 'C:\\Program Files\\WindowsPowerShell\\Modules;C:\\WINDOWS\\system32\\WindowsPowerShell\\v1.0\\Modules',
16:46:10 INFO - 'PUBLIC': 'C:\\Users\\Public',
16:46:10 INFO - 'PWD': 'c:/gecko',
16:46:10 INFO - 'PYTHONPATH': u'c:/gecko\\testing\\talos',
16:46:10 INFO - 'RUST_BACKTRACE': 'full',
16:46:10 INFO - 'SCRIPTSPATH': 'c:\\gecko\\testing\\mozharness',
16:46:10 INFO - 'SESSIONNAME': 'Console',
16:46:10 INFO - 'SHELL': 'C:/mozilla-build/msys/bin/sh',
16:46:10 INFO - 'SHLVL': '1',
16:46:10 INFO - 'SSH_AGENT_PID': '3424',
16:46:10 INFO - 'SSH_AUTH_SOCK': 'C:/Users/ALEXIS~1/AppData/Local/Temp/ssh-JJETDJ2192/agent.2192',
16:46:10 INFO - 'STYLO_FORCE_ENABLED': '1',
16:46:10 INFO - 'SYSTEMDRIVE': 'C:',
16:46:10 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
16:46:10 INFO - 'TEMP': 'C:/Users/ALEXIS~1/AppData/Local/Temp',
16:46:10 INFO - 'TERM': 'cygwin',
16:46:10 INFO - 'TMP': 'C:/Users/ALEXIS~1/AppData/Local/Temp',
16:46:10 INFO - 'TVT': 'C:\\Program Files (x86)\\Lenovo',
16:46:10 INFO - 'USERDOMAIN': 'DESKTOP-M6UNB64',
16:46:10 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'DESKTOP-M6UNB64',
16:46:10 INFO - 'USERNAME': 'Alexis Beingessner',
16:46:10 INFO - 'USERPROFILE': 'C:\\Users\\Alexis Beingessner',
16:46:10 INFO - 'WIN64': '1',
16:46:10 INFO - 'WINCURVERKEY': 'HKLM\\SOFTWARE\\Microsoft\\Windows\\CurrentVersion',
16:46:10 INFO - 'WINDIR': 'C:\\WINDOWS'}
16:46:10 INFO - Calling [u'c:\\gecko\\obj-i686-pc-mingw32\\testing\\talos-venv\\Scripts\\python', u'c:/gecko\\testing\\talos\\talos\\run_tests.py', '--executablePath', u'c:/gecko/obj-i686-pc-mingw32\\dist\\bin\\firefox', '--title', u'DESKTOP-M6UNB64', u'--develop', u'--activeTests', u'tp5o_scroll', u'--geckoProfile', '--log-tbpl-level=debug'] with output_timeout 3600
16:46:10 INFO - mozversion application_buildid: 20180404111809
16:46:10 INFO - mozversion application_display_name: Nightly
16:46:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
16:46:10 INFO - mozversion application_name: Firefox
16:46:10 INFO - mozversion application_remotingname: firefox
16:46:10 INFO - mozversion application_vendor: Mozilla
16:46:10 INFO - mozversion application_version: 61.0a1
16:46:10 INFO - mozversion platform_buildid: 20180404111809
16:46:10 INFO - mozversion platform_version: 61.0a1
16:46:10 INFO - using testdate: 1522874770
16:46:10 INFO - actual date: 1522874770
16:46:10 INFO - starting webserver on 'localhost:51216'
16:46:10 INFO - SUITE-START | Running 1 tests
16:46:10 INFO - TEST-START | tp5o_scroll
16:46:10 INFO - Gecko profiling is enabled so talos is reducing the number of cycles, please disregard reported numbers
16:46:10 INFO - Initialising browser for tp5o_scroll test...
16:46:10 INFO - Cloning profile located at c:\gecko\testing\talos\talos\base_profile
16:46:10 INFO - Installing Add-ons:
16:46:10 INFO - ['c:\\gecko\\testing\\talos\\talos/talos-powers', 'c:\\gecko\\testing\\talos\\talos/pageloader']
16:46:10 INFO - Application command: c:\gecko\obj-i686-pc-mingw32\dist\bin\firefox --no-remote http://localhost:51216/getInfo.html -profile c:\users\alexis~1\appdata\local\temp\tmpjfiolz\profile
16:46:10 INFO - TEST-INFO | started process 10508 (c:\gecko\obj-i686-pc-mingw32\dist\bin\firefox --no-remote http://localhost:51216/getInfo.html)
16:46:16 INFO - TEST-INFO | 10508: exit 0
16:46:16 INFO - Clearing archive c:/gecko\testing\mozharness\build\blobber_upload_dir\profile_tp5o_scroll.zip
16:46:16 INFO - Activating Gecko Profiling. Temp. profile dir: c:\users\alexis~1\appdata\local\temp\tmpn9s8sx, interval: 2, entries: 2000000
16:46:16 INFO - Browser initialized.
16:46:16 INFO - Running cycle 1/1 for tp5o_scroll test...
16:46:16 INFO - Using env: {'!::': '::\\',
16:46:16 INFO - '!EXITCODE': '00000001',
16:46:16 INFO - 'ALLUSERSPROFILE': 'C:\\ProgramData',
16:46:16 INFO - 'APPDATA': 'C:\\Users\\Alexis Beingessner\\AppData\\Roaming',
16:46:16 INFO - 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey',
16:46:16 INFO - 'CHOCOLATEYLASTPATHUPDATE': 'Wed Apr 4 13:02:47 2018',
16:46:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
16:46:16 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
16:46:16 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
16:46:16 INFO - 'COMPUTERNAME': 'DESKTOP-M6UNB64',
16:46:16 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
16:46:16 INFO - 'EDITOR': 'emacs.exe',
16:46:16 INFO - 'FPS_BROWSER_APP_PROFILE_STRING': 'Internet Explorer',
16:46:16 INFO - 'FPS_BROWSER_USER_PROFILE_STRING': 'Default',
16:46:16 INFO - 'FSHARPINSTALLDIR': 'C:\\Program Files (x86)\\Microsoft SDKs\\F#\\10.1\\Framework\\v4.0\\',
16:46:16 INFO - 'HISTFILE': 'C:/mozilla-build/msys/home/Alexis Beingessner/.bash_history',
16:46:16 INFO - 'HOME': 'c:/Users/Alexis Beingessner',
16:46:16 INFO - 'HOMEDRIVE': 'C:',
16:46:16 INFO - 'HOMEPATH': '\\',
16:46:16 INFO - 'HOSTTYPE': 'i686',
16:46:16 INFO - 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc',
16:46:16 INFO - 'JSGC_DISABLE_POISONING': '1',
16:46:16 INFO - 'LD_LIBRARY_PATH': 'c:\\gecko\\obj-i686-pc-mingw32\\dist\\bin',
16:46:16 INFO - 'LLVMKEY': 'HKLM\\SOFTWARE\\Wow6432Node\\LLVM\\LLVM',
16:46:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\Alexis Beingessner\\AppData\\Local',
16:46:16 INFO - 'LOGNAME': 'Alexis Beingessner',
16:46:16 INFO - 'LOGONSERVER': '\\\\DESKTOP-M6UNB64',
16:46:16 INFO - 'MACHTYPE': 'i686-pc-msys',
16:46:16 INFO - 'MACH_STDOUT_ISATTY': '1',
16:46:16 INFO - 'MAKE_MODE': 'unix',
16:46:16 INFO - 'MINIDUMP_SAVE_PATH': 'c:/gecko\\testing\\mozharness\\build\\blobber_upload_dir',
16:46:16 INFO - 'MOZILLABUILD': 'C:\\mozilla-build\\',
16:46:16 INFO - 'MOZ_CRASHREPORTER': '1',
16:46:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:46:16 INFO - 'MOZ_DEVELOPER_OBJ_DIR': 'c:/gecko/obj-i686-pc-mingw32',
16:46:16 INFO - 'MOZ_DEVELOPER_REPO_DIR': 'c:/gecko',
16:46:16 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
16:46:16 INFO - 'MOZ_UPLOAD_DIR': 'c:/gecko\\testing\\mozharness\\build\\blobber_upload_dir',
16:46:16 INFO - 'MSYSTEM': 'MINGW32',
16:46:16 INFO - 'NO_EM_RESTART': '1',
16:46:16 INFO - 'NUMBER_OF_PROCESSORS': '8',
16:46:16 INFO - 'ONEDRIVE': 'C:\\Users\\Alexis Beingessner\\OneDrive',
16:46:16 INFO - 'OS': 'Windows_NT',
16:46:16 INFO - 'OSTYPE': 'msys',
16:46:16 INFO - 'PATH': 'c:\\gecko\\obj-i686-pc-mingw32\\testing\\talos-venv\\Scripts;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\7zip;c:\\mozilla-build\\info-zip;c:\\mozilla-build\\kdiff3;c:\\mozilla-build\\mozmake;c:\\mozilla-build\\node-v8.1.4-win-x64;c:\\mozilla-build\\nsis-3.01;c:\\mozilla-build\\python;c:\\mozilla-build\\python\\Scripts;c:\\mozilla-build\\python3;c:\\mozilla-build\\python3\\Scripts;c:\\mozilla-build\\upx394w;c:\\mozilla-build\\watchman;c:\\mozilla-build\\wget;c:\\mozilla-build\\yasm;.;C:\\mozilla-build\\msys\\local\\bin;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;c:\\WINDOWS\\System32;c:\\WINDOWS;c:\\WINDOWS\\System32\\Wbem;c:\\Users\\Alexis Beingessner\\.cargo\\bin',
16:46:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
16:46:16 INFO - 'PROCESSOR_ARCHITECTURE': 'AMD64',
16:46:16 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 158 Stepping 9, GenuineIntel',
16:46:16 INFO - 'PROCESSOR_LEVEL': '6',
16:46:16 INFO - 'PROCESSOR_REVISION': '9e09',
16:46:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
16:46:16 INFO - 'PROGRAMFILES': 'C:\\Program Files',
16:46:16 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
16:46:16 INFO - 'PROGRAMW6432': 'C:\\Program Files',
16:46:16 INFO - 'PROMPT': '$P$G',
16:46:16 INFO - 'PSMODULEPATH': 'C:\\Program Files\\WindowsPowerShell\\Modules;C:\\WINDOWS\\system32\\WindowsPowerShell\\v1.0\\Modules',
16:46:16 INFO - 'PUBLIC': 'C:\\Users\\Public',
16:46:16 INFO - 'PWD': 'c:/gecko',
16:46:16 INFO - 'PYTHONPATH': 'c:/gecko\\testing\\talos',
16:46:16 INFO - 'RUST_BACKTRACE': 'full',
16:46:16 INFO - 'SCRIPTSPATH': 'c:\\gecko\\testing\\mozharness',
16:46:16 INFO - 'SESSIONNAME': 'Console',
16:46:16 INFO - 'SHELL': 'C:/mozilla-build/msys/bin/sh',
16:46:16 INFO - 'SHLVL': '1',
16:46:16 INFO - 'SSH_AGENT_PID': '3424',
16:46:16 INFO - 'SSH_AUTH_SOCK': 'C:/Users/ALEXIS~1/AppData/Local/Temp/ssh-JJETDJ2192/agent.2192',
16:46:16 INFO - 'STYLO_FORCE_ENABLED': '1',
16:46:16 INFO - 'SYSTEMDRIVE': 'C:',
16:46:16 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
16:46:16 INFO - 'TEMP': 'C:/Users/ALEXIS~1/AppData/Local/Temp',
16:46:16 INFO - 'TERM': 'cygwin',
16:46:16 INFO - 'TMP': 'C:/Users/ALEXIS~1/AppData/Local/Temp',
16:46:16 INFO - 'TPPROFILINGINFO': '{"gecko_profile_entries": 2000000, "gecko_profile_threads": "GeckoMain,Compositor", "gecko_profile_dir": "c:\\\\users\\\\alexis~1\\\\appdata\\\\local\\\\temp\\\\tmpn9s8sx", "gecko_profile_interval": 2}',
16:46:16 INFO - 'TVT': 'C:\\Program Files (x86)\\Lenovo',
16:46:16 INFO - 'USERDOMAIN': 'DESKTOP-M6UNB64',
16:46:16 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'DESKTOP-M6UNB64',
16:46:16 INFO - 'USERNAME': 'Alexis Beingessner',
16:46:16 INFO - 'USERPROFILE': 'C:\\Users\\Alexis Beingessner',
16:46:16 INFO - 'WIN64': '1',
16:46:16 INFO - 'WINCURVERKEY': 'HKLM\\SOFTWARE\\Microsoft\\Windows\\CurrentVersion',
16:46:16 INFO - 'WINDIR': 'C:\\WINDOWS'}
16:46:16 INFO - TEST-INFO | started process 7100 (c:\gecko\obj-i686-pc-mingw32\dist\bin\firefox --no-remote -profile c:\users\alexis~1\appdata\local\temp\tmpjfiolz\profile)
16:46:16 INFO - PID 7100 | WebRender - OpenGL version new OpenGL ES 3.0 (ANGLE 2.1.0.27cef491162b)
16:46:16 INFO - PID 7100 | [GFX1-]: WR: ERROR: Invalid window dimensions 292x0. Please call api.set_window_size()
16:46:16 INFO - PID 7100 | [GFX1-]: WR: ERROR: Invalid window dimensions 292x0. Please call api.set_window_size()
16:46:17 INFO - PID 7100 | WebRender - OpenGL version new OpenGL ES 3.0 (ANGLE 2.1.0.27cef491162b)
16:46:17 INFO - PID 7100 | WARN: rx::HLSLCompiler::compileToBinary(228):
16:46:17 INFO - PID 7100 | C:\fakepath(739,32-48): warning X3556: integer divides may be much slower, try using uints if possible.
16:46:17 INFO - PID 7100 | C:\fakepath(740,25-41): warning X3556: integer modulus may be much slower, try using uints if possible.
16:46:17 INFO - PID 7100 | C:\fakepath(650,20-34): warning X3556: integer modulus may be much slower, try using uints if possible.
16:46:17 INFO - PID 7100 | C:\fakepath(650,39-53): warning X3556: integer divides may be much slower, try using uints if possible.
16:46:17 INFO - PID 7100 |
16:46:18 INFO - PID 7100 | WARN: rx::HLSLCompiler::compileToBinary(228):
16:46:18 INFO - PID 7100 | C:\fakepath(733,32-48): warning X3556: integer divides may be much slower, try using uints if possible.
16:46:18 INFO - PID 7100 | C:\fakepath(734,25-41): warning X3556: integer modulus may be much slower, try using uints if possible.
16:46:18 INFO - PID 7100 | C:\fakepath(644,20-34): warning X3556: integer modulus may be much slower, try using uints if possible.
16:46:18 INFO - PID 7100 | C:\fakepath(644,39-53): warning X3556: integer divides may be much slower, try using uints if possible.
16:46:18 INFO - PID 7100 |
16:46:18 INFO - PID 7100 | WARN: rx::HLSLCompiler::compileToBinary(228):
16:46:18 INFO - PID 7100 | C:\fakepath(584,20-34): warning X3556: integer modulus may be much slower, try using uints if possible.
16:46:18 INFO - PID 7100 | C:\fakepath(584,39-53): warning X3556: integer divides may be much slower, try using uints if possible.
16:46:18 INFO - PID 7100 |
16:46:18 INFO - PID 7100 | WARN: rx::HLSLCompiler::compileToBinary(228):
16:46:18 INFO - PID 7100 | C:\fakepath(511,20-34): warning X3556: integer modulus may be much slower, try using uints if possible.
16:46:18 INFO - PID 7100 | C:\fakepath(511,39-53): warning X3556: integer divides may be much slower, try using uints if possible.
16:46:18 INFO - PID 7100 |
16:46:18 INFO - PID 7100 | WARN: rx::HLSLCompiler::compileToBinary(228):
16:46:18 INFO - PID 7100 | C:\fakepath(506,20-34): warning X3556: integer modulus may be much slower, try using uints if possible.
16:46:18 INFO - PID 7100 | C:\fakepath(506,39-53): warning X3556: integer divides may be much slower, try using uints if possible.
16:46:18 INFO - PID 7100 |
16:46:18 INFO - PID 7100 | WARN: rx::HLSLCompiler::compileToBinary(228):
16:46:18 INFO - PID 7100 | C:\fakepath(534,20-34): warning X3556: integer modulus may be much slower, try using uints if possible.
16:46:18 INFO - PID 7100 | C:\fakepath(534,39-53): warning X3556: integer divides may be much slower, try using uints if possible.
16:46:18 INFO - PID 7100 |
16:46:19 INFO - PID 7100 | WARN: rx::HLSLCompiler::compileToBinary(228):
16:46:19 INFO - PID 7100 | C:\fakepath(764,32-48): warning X3556: integer divides may be much slower, try using uints if possible.
16:46:19 INFO - PID 7100 | C:\fakepath(765,25-41): warning X3556: integer modulus may be much slower, try using uints if possible.
16:46:19 INFO - PID 7100 | C:\fakepath(650,20-34): warning X3556: integer modulus may be much slower, try using uints if possible.
16:46:19 INFO - PID 7100 | C:\fakepath(650,39-53): warning X3556: integer divides may be much slower, try using uints if possible.
16:46:19 INFO - PID 7100 | C:\fakepath(739,55-70): warning X3556: integer divides may be much slower, try using uints if possible.
16:46:19 INFO - PID 7100 | C:\fakepath(741,58-73): warning X3556: integer modulus may be much slower, try using uints if possible.
16:46:19 INFO - PID 7100 |
16:46:19 INFO - PID 7100 | WARN: rx::HLSLCompiler::compileToBinary(228):
16:46:19 INFO - PID 7100 | C:\fakepath(547,20-34): warning X3556: integer modulus may be much slower, try using uints if possible.
16:46:19 INFO - PID 7100 | C:\fakepath(547,39-53): warning X3556: integer divides may be much slower, try using uints if possible.
16:46:19 INFO - PID 7100 |
16:46:19 INFO - PID 7100 | WARN: rx::HLSLCompiler::compileToBinary(228):
16:46:19 INFO - PID 7100 | C:\fakepath(706,32-48): warning X3556: integer divides may be much slower, try using uints if possible.
16:46:19 INFO - PID 7100 | C:\fakepath(707,25-41): warning X3556: integer modulus may be much slower, try using uints if possible.
16:46:19 INFO - PID 7100 | C:\fakepath(620,20-34): warning X3556: integer modulus may be much slower, try using uints if possible.
16:46:19 INFO - PID 7100 | C:\fakepath(620,39-53): warning X3556: integer divides may be much slower, try using uints if possible.
16:46:19 INFO - PID 7100 |
16:46:22 INFO - PID 7100 | Cycle 1(1): loaded http://localhost:51216/tests/tp5n/163.com/www.163.com/index.html (next: http://localhost:51216/tests/tp5n/163.com/www.163.com/index.html)
16:46:38 INFO - PID 7100 | Cycle 1(2): loaded http://localhost:51216/tests/tp5n/163.com/www.163.com/index.html (next: http://localhost:51216/tests/tp5n/163.com/www.163.com/index.html)
16:46:45 INFO - Terminating psutil.Process(pid=7100L, name='firefox.exe', started='16:46:16')
16:46:45 INFO - TEST-INFO | 7100: exit 1
16:46:45 INFO - TEST-UNEXPECTED-ERROR | tp5o_scroll | Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))]
16:46:45 ERROR - Traceback (most recent call last):
16:46:45 INFO - File "c:/gecko\testing\talos\talos\run_tests.py", line 296, in run_tests
16:46:45 INFO - talos_results.add(mytest.runTest(browser_config, test))
16:46:45 INFO - File "c:\gecko\testing\talos\talos\ttest.py", line 63, in runTest
16:46:45 INFO - return self._runTest(browser_config, test_config, setup)
16:46:45 INFO - File "c:\gecko\testing\talos\talos\ttest.py", line 270, in _runTest
16:46:45 INFO - else None)
16:46:45 INFO - File "c:\gecko\testing\talos\talos\results.py", line 95, in add
16:46:45 INFO - global_counters=self.global_counters
16:46:45 INFO - File "c:\gecko\testing\talos\talos\results.py", line 326, in __init__
16:46:45 INFO - self.parse()
16:46:45 INFO - File "c:\gecko\testing\talos\talos\results.py", line 353, in parse
16:46:45 INFO - % self.report_tokens)
16:46:45 INFO - File "c:\gecko\testing\talos\talos\results.py", line 337, in error
16:46:45 INFO - raise utils.TalosError(message)
16:46:45 INFO - TalosError: Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))]
16:46:45 INFO - TEST-INFO took 34895ms
16:46:45 INFO - SUITE-END | took 34s
16:46:45 ERROR - Return code: 2
16:46:45 WARNING - setting return code to 2
16:46:45 ERROR - # TBPL FAILURE #
16:46:45 INFO - Running post-action listener: _package_coverage_data
16:46:45 INFO - Running post-action listener: _resource_record_post_action
16:46:45 INFO - [mozharness: 2018-04-04 20:46:45.989000Z] Finished run-tests step (success)
16:46:45 INFO - Running post-run listener: _resource_record_post_run
16:46:46 INFO - Total resource usage - Wall time: 36s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
16:46:46 INFO - TinderboxPrint: I/O read bytes / time<br/>0 / 0
16:46:46 INFO - TinderboxPrint: I/O write bytes / time<br/>0 / 0
16:46:46 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
16:46:46 INFO - run-tests - Wall time: 36s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
16:46:46 INFO - Running post-run listener: _upload_blobber_files
16:46:46 WARNING - Blob upload gear skipped. Missing cmdline options.
16:46:46 INFO - Running post-run listener: copy_logs_to_upload_dir
16:46:46 INFO - Copying logs to upload dir...
16:46:46 INFO - Using _rmtree_windows ...
16:46:46 INFO - Running command: del /F /Q "c:\gecko\testing\mozharness\build\upload\logs\localconfig.json"
16:46:46 INFO - Return code: 0
16:46:46 INFO - Using _rmtree_windows ...
16:46:46 INFO - Running command: del /F /Q "c:\gecko\testing\mozharness\build\upload\logs\talos_info.log"
16:46:46 INFO - Return code: 0
16:46:46 INFO - Using _rmtree_windows ...
16:46:46 INFO - Running command: del /F /Q "c:\gecko\testing\mozharness\build\upload\logs\talos_raw.log"
16:46:46 INFO - Return code: 0
16:46:46 INFO - Using _rmtree_windows ...
16:46:46 INFO - Running command: del /F /Q "c:\gecko\testing\mozharness\build\upload\logs\talos_warning.log"
16:46:46 INFO - Return code: 0
16:46:46 INFO - Using _rmtree_windows ...
16:46:46 INFO - Running command: del /F /Q "c:\gecko\testing\mozharness\build\upload\logs\talos_critical.log"
16:46:46 INFO - Return code: 0
16:46:46 INFO - Using _rmtree_windows ...
16:46:46 INFO - Running command: del /F /Q "c:\gecko\testing\mozharness\build\upload\logs\talos_error.log"
16:46:46 INFO - Return code: 0
16:46:46 INFO - Using _rmtree_windows ...
16:46:46 INFO - Running command: del /F /Q "c:\gecko\testing\mozharness\build\upload\logs\talos_fatal.log"
16:46:46 INFO - Return code: 0
16:46:46 INFO - Copying logs to upload dir...
16:46:46 INFO - Using _rmtree_windows ...
16:46:46 INFO - Running command: del /F /Q "c:\gecko\testing\mozharness\build\upload\logs\localconfig.json"
16:46:46 INFO - Return code: 0
16:46:46 INFO - Using _rmtree_windows ...
16:46:46 INFO - Running command: del /F /Q "c:\gecko\testing\mozharness\build\upload\logs\talos_info.log"
16:46:46 INFO - Return code: 0
16:46:46 INFO - Using _rmtree_windows ...
16:46:46 INFO - Running command: del /F /Q "c:\gecko\testing\mozharness\build\upload\logs\talos_raw.log"
16:46:46 INFO - Return code: 0
16:46:46 INFO - Using _rmtree_windows ...
16:46:46 INFO - Running command: del /F /Q "c:\gecko\testing\mozharness\build\upload\logs\talos_warning.log"
16:46:46 INFO - Return code: 0
16:46:46 INFO - Using _rmtree_windows ...
16:46:46 INFO - Running command: del /F /Q "c:\gecko\testing\mozharness\build\upload\logs\talos_critical.log"
16:46:46 INFO - Return code: 0
16:46:46 INFO - Using _rmtree_windows ...
16:46:46 INFO - Running command: del /F /Q "c:\gecko\testing\mozharness\build\upload\logs\talos_error.log"
16:46:46 INFO - Return code: 0
16:46:46 INFO - Using _rmtree_windows ...
16:46:46 INFO - Running command: del /F /Q "c:\gecko\testing\mozharness\build\upload\logs\talos_fatal.log"
16:46:46 INFO - Return code: 0
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment