builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-065 starttime: 1484546694.92 results: success (0) buildid: 20170115200922 builduid: e9e060c574f54eefa518a58a811c7e6c revision: e1b44c560c28fd2605bbec4f5bfaa7592379a6c9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-15 22:04:54.919541) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-15 22:04:54.920279) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-15 22:04:54.920554) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-15 22:04:54.934874) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-15 22:04:54.935146) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-15 22:04:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.5M=0s 2017-01-15 22:04:56 (45.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.065375 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-15 22:04:55.013936) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-15 22:04:55.014250) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013794 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-15 22:04:55.064199) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-15 22:04:55.064511) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e1b44c560c28fd2605bbec4f5bfaa7592379a6c9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e1b44c560c28fd2605bbec4f5bfaa7592379a6c9 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-15 22:04:56,832 truncating revision to first 12 chars 2017-01-15 22:04:56,832 Setting DEBUG logging. 2017-01-15 22:04:56,833 attempt 1/10 2017-01-15 22:04:56,833 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/e1b44c560c28?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-15 22:04:57,275 unpacking tar archive at: mozilla-beta-e1b44c560c28/testing/mozharness/ program finished with exit code 0 elapsedTime=0.800527 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-15 22:04:55.877661) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-15 22:04:55.877937) ========= script_repo_revision: e1b44c560c28fd2605bbec4f5bfaa7592379a6c9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-15 22:04:55.878298) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-15 22:04:55.878561) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-15 22:04:55.886590) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2017-01-15 22:04:55.886852) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:04:57 INFO - MultiFileLogger online at 20170115 22:04:57 in /builds/slave/test 22:04:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 22:04:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:04:57 INFO - {'append_to_log': False, 22:04:57 INFO - 'base_work_dir': '/builds/slave/test', 22:04:57 INFO - 'blob_upload_branch': 'Mozilla-Beta', 22:04:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:04:57 INFO - 'branch': 'Mozilla-Beta', 22:04:57 INFO - 'buildbot_json_path': 'buildprops.json', 22:04:57 INFO - 'config_files': ('talos/linux_config.py',), 22:04:57 INFO - 'default_actions': ('clobber', 22:04:57 INFO - 'read-buildbot-config', 22:04:57 INFO - 'download-and-extract', 22:04:57 INFO - 'populate-webroot', 22:04:57 INFO - 'create-virtualenv', 22:04:57 INFO - 'install', 22:04:57 INFO - 'run-tests'), 22:04:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:04:57 INFO - 'download_minidump_stackwalk': True, 22:04:57 INFO - 'download_symbols': 'ondemand', 22:04:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:04:57 INFO - 'tooltool.py': '/tools/tooltool.py', 22:04:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:04:57 INFO - '/tools/misc-python/virtualenv.py')}, 22:04:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:04:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:04:57 INFO - 'installer_path': 'installer.exe', 22:04:57 INFO - 'log_level': 'info', 22:04:57 INFO - 'log_name': 'talos', 22:04:57 INFO - 'log_to_console': True, 22:04:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:04:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:04:57 INFO - 'opt_config_files': (), 22:04:57 INFO - 'pip_index': False, 22:04:57 INFO - 'sps_profile': False, 22:04:57 INFO - 'sps_profile_interval': 0, 22:04:57 INFO - 'suite': 'dromaeojs', 22:04:57 INFO - 'system_bits': '32', 22:04:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:04:57 INFO - 'title': 'talos-linux64-ix-065', 22:04:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:04:57 INFO - 'use_talos_json': True, 22:04:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:04:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:04:57 INFO - 'work_dir': 'build'} 22:04:57 INFO - [mozharness: 2017-01-16 06:04:57.748891Z] Running clobber step. 22:04:57 INFO - Running pre-action listener: _resource_record_pre_action 22:04:57 INFO - Running main action method: clobber 22:04:57 INFO - rmtree: /builds/slave/test/build 22:04:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:04:58 INFO - Running post-action listener: _resource_record_post_action 22:04:58 INFO - [mozharness: 2017-01-16 06:04:58.720080Z] Finished clobber step (success) 22:04:58 INFO - [mozharness: 2017-01-16 06:04:58.720179Z] Running read-buildbot-config step. 22:04:58 INFO - Running pre-action listener: _resource_record_pre_action 22:04:58 INFO - Running main action method: read_buildbot_config 22:04:58 INFO - Using buildbot properties: 22:04:58 INFO - { 22:04:58 INFO - "project": "", 22:04:58 INFO - "product": "firefox", 22:04:58 INFO - "script_repo_revision": "production", 22:04:58 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 22:04:58 INFO - "repository": "", 22:04:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 22:04:58 INFO - "buildid": "20170115200922", 22:04:58 INFO - "pgo_build": "True", 22:04:58 INFO - "basedir": "/builds/slave/test", 22:04:58 INFO - "buildnumber": 98, 22:04:58 INFO - "slavename": "talos-linux64-ix-065", 22:04:58 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:04:58 INFO - "platform": "ubuntu64_hw", 22:04:58 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 22:04:58 INFO - "branch": "mozilla-beta", 22:04:58 INFO - "repo_path": "releases/mozilla-beta", 22:04:58 INFO - "revision": "e1b44c560c28fd2605bbec4f5bfaa7592379a6c9", 22:04:58 INFO - "stage_platform": "linux64-pgo", 22:04:58 INFO - "builduid": "e9e060c574f54eefa518a58a811c7e6c", 22:04:58 INFO - "slavebuilddir": "test" 22:04:58 INFO - } 22:04:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2. 22:04:58 INFO - Running post-action listener: _resource_record_post_action 22:04:58 INFO - [mozharness: 2017-01-16 06:04:58.722649Z] Finished read-buildbot-config step (success) 22:04:58 INFO - [mozharness: 2017-01-16 06:04:58.722739Z] Running download-and-extract step. 22:04:58 INFO - Running pre-action listener: _resource_record_pre_action 22:04:58 INFO - Running main action method: download_and_extract 22:04:58 INFO - mkdir: /builds/slave/test/build/tests 22:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:04:58 INFO - trying https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 22:04:58 INFO - Downloading https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 22:04:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:04:59 INFO - Downloaded 1327 bytes. 22:04:59 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 22:04:59 INFO - Using the following test package requirements: 22:04:59 INFO - {u'common': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 22:04:59 INFO - u'cppunittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 22:04:59 INFO - u'firefox-51.0.en-US.linux-x86_64.cppunittest.tests.zip'], 22:04:59 INFO - u'gtest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 22:04:59 INFO - u'firefox-51.0.en-US.linux-x86_64.gtest.tests.zip'], 22:04:59 INFO - u'jittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 22:04:59 INFO - u'jsshell-linux-x86_64.zip'], 22:04:59 INFO - u'mochitest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 22:04:59 INFO - u'firefox-51.0.en-US.linux-x86_64.mochitest.tests.zip'], 22:04:59 INFO - u'mozbase': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 22:04:59 INFO - u'reftest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 22:04:59 INFO - u'firefox-51.0.en-US.linux-x86_64.reftest.tests.zip'], 22:04:59 INFO - u'talos': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 22:04:59 INFO - u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'], 22:04:59 INFO - u'web-platform': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 22:04:59 INFO - u'firefox-51.0.en-US.linux-x86_64.web-platform.tests.zip'], 22:04:59 INFO - u'xpcshell': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 22:04:59 INFO - u'firefox-51.0.en-US.linux-x86_64.xpcshell.tests.zip']} 22:04:59 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:04:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 22:04:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:04:59 INFO - Fetch https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 22:04:59 INFO - Expected file size: 21972392 22:05:04 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:05:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 22:05:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:05:04 INFO - Fetch https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 22:05:04 INFO - Expected file size: 21972392 22:05:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip 22:05:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:05:09 INFO - Fetch https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip into memory 22:05:10 INFO - Expected file size: 14414089 22:05:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:11 INFO - trying https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 22:05:11 INFO - Downloading https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 22:05:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:05:13 INFO - Downloaded 57052906 bytes. 22:05:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 22:05:13 INFO - mkdir: /builds/slave/test/properties 22:05:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:05:13 INFO - Writing to file /builds/slave/test/properties/build_url 22:05:13 INFO - Contents: 22:05:13 INFO - build_url:https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 22:05:14 INFO - Running post-action listener: _resource_record_post_action 22:05:14 INFO - Running post-action listener: set_extra_try_arguments 22:05:14 INFO - [mozharness: 2017-01-16 06:05:14.443378Z] Finished download-and-extract step (success) 22:05:14 INFO - [mozharness: 2017-01-16 06:05:14.443611Z] Running populate-webroot step. 22:05:14 INFO - Running pre-action listener: _resource_record_pre_action 22:05:14 INFO - Running main action method: populate_webroot 22:05:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:05:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:05:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:05:14 INFO - '--tppagecycles', 22:05:14 INFO - '7'], 22:05:14 INFO - 'tests': ['tsvgm']}}, 22:05:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:05:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:05:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:05:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:05:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:05:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:05:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:14 INFO - 'talos_options': ['--disable-e10s'], 22:05:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:05:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:05:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:14 INFO - 'talos_options': ['--disable-e10s'], 22:05:14 INFO - 'tests': ['damp', 'tps']}, 22:05:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:14 INFO - 'tests': ['damp', 'tps']}, 22:05:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:05:14 INFO - 'tests': ['dromaeo_dom']}, 22:05:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:05:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:05:14 INFO - 'tests': ['basic_compositor_video']}, 22:05:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:05:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:05:14 INFO - 'tests': ['a11yr', 22:05:14 INFO - 'ts_paint', 22:05:14 INFO - 'tpaint', 22:05:14 INFO - 'sessionrestore', 22:05:14 INFO - 'sessionrestore_no_auto_restore', 22:05:14 INFO - 'tabpaint']}, 22:05:14 INFO - 'other-e10s': {'tests': ['a11yr', 22:05:14 INFO - 'ts_paint', 22:05:14 INFO - 'tpaint', 22:05:14 INFO - 'sessionrestore', 22:05:14 INFO - 'sessionrestore_no_auto_restore', 22:05:14 INFO - 'tabpaint']}, 22:05:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:05:14 INFO - 'ts_paint', 22:05:14 INFO - 'tpaint', 22:05:14 INFO - 'sessionrestore', 22:05:14 INFO - 'sessionrestore_no_auto_restore', 22:05:14 INFO - 'tabpaint']}, 22:05:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:05:14 INFO - 'ts_paint', 22:05:14 INFO - 'tpaint', 22:05:14 INFO - 'sessionrestore', 22:05:14 INFO - 'sessionrestore_no_auto_restore', 22:05:14 INFO - 'tabpaint']}, 22:05:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:05:14 INFO - 'tests': ['a11yr', 22:05:14 INFO - 'ts_paint', 22:05:14 INFO - 'tpaint', 22:05:14 INFO - 'sessionrestore', 22:05:14 INFO - 'sessionrestore_no_auto_restore', 22:05:14 INFO - 'tabpaint']}, 22:05:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:05:14 INFO - 'tests': ['a11yr', 22:05:14 INFO - 'ts_paint', 22:05:14 INFO - 'tpaint', 22:05:14 INFO - 'sessionrestore', 22:05:14 INFO - 'sessionrestore_no_auto_restore', 22:05:14 INFO - 'tabpaint']}, 22:05:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:05:14 INFO - 'tests': ['tsvgx', 22:05:14 INFO - 'tsvgr_opacity', 22:05:14 INFO - 'tart', 22:05:14 INFO - 'tscrollx', 22:05:14 INFO - 'cart']}, 22:05:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:05:14 INFO - 'tsvgr_opacity', 22:05:14 INFO - 'tart', 22:05:14 INFO - 'tscrollx', 22:05:14 INFO - 'cart']}, 22:05:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:14 INFO - 'talos_options': ['--disable-e10s'], 22:05:14 INFO - 'tests': ['tp5o']}, 22:05:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:14 INFO - 'tests': ['tp5o']}, 22:05:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:14 INFO - 'talos_options': ['--disable-e10s', 22:05:14 INFO - '--xperf_path', 22:05:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:05:14 INFO - 'tests': ['tp5n']}, 22:05:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:14 INFO - 'talos_options': ['--xperf_path', 22:05:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:05:14 INFO - 'tests': ['tp5n']}}, 22:05:14 INFO - 'talos.zip': {'path': '', 22:05:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:05:14 INFO - Running post-action listener: _resource_record_post_action 22:05:14 INFO - [mozharness: 2017-01-16 06:05:14.461391Z] Finished populate-webroot step (success) 22:05:14 INFO - [mozharness: 2017-01-16 06:05:14.461486Z] Running create-virtualenv step. 22:05:14 INFO - Running pre-action listener: _resource_record_pre_action 22:05:14 INFO - Running main action method: create_virtualenv 22:05:14 INFO - Creating virtualenv /builds/slave/test/build/venv 22:05:14 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:05:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:05:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:05:14 INFO - Using real prefix '/usr' 22:05:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:05:15 INFO - Installing distribute.............................................................................................................................................................................................done. 22:05:17 INFO - Installing pip.................done. 22:05:17 INFO - Return code: 0 22:05:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:05:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc34a25f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2528ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2525f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25271d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:05:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:05:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:05:17 INFO - Using env: {'DISPLAY': ':0', 22:05:17 INFO - 'HOME': '/home/cltbld', 22:05:17 INFO - 'LANG': 'en_US.UTF-8', 22:05:17 INFO - 'LANGUAGE': 'en_US:en', 22:05:17 INFO - 'LOGNAME': 'cltbld', 22:05:17 INFO - 'MAIL': '/var/mail/cltbld', 22:05:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:17 INFO - 'MOZ_NO_REMOTE': '1', 22:05:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:17 INFO - 'NO_EM_RESTART': '1', 22:05:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:17 INFO - 'PWD': '/builds/slave/test', 22:05:17 INFO - 'SHELL': '/bin/bash', 22:05:17 INFO - 'SHLVL': '1', 22:05:17 INFO - 'TERM': 'linux', 22:05:17 INFO - 'TMOUT': '86400', 22:05:17 INFO - 'USER': 'cltbld', 22:05:17 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 22:05:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:17 INFO - '_': '/tools/buildbot/bin/python'} 22:05:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:17 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:05:17 INFO - Cleaning up... 22:05:17 INFO - Return code: 0 22:05:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:05:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc34a25f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2528ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2525f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25271d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:05:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:05:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:05:17 INFO - Using env: {'DISPLAY': ':0', 22:05:17 INFO - 'HOME': '/home/cltbld', 22:05:17 INFO - 'LANG': 'en_US.UTF-8', 22:05:17 INFO - 'LANGUAGE': 'en_US:en', 22:05:17 INFO - 'LOGNAME': 'cltbld', 22:05:17 INFO - 'MAIL': '/var/mail/cltbld', 22:05:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:17 INFO - 'MOZ_NO_REMOTE': '1', 22:05:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:17 INFO - 'NO_EM_RESTART': '1', 22:05:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:17 INFO - 'PWD': '/builds/slave/test', 22:05:17 INFO - 'SHELL': '/bin/bash', 22:05:17 INFO - 'SHLVL': '1', 22:05:17 INFO - 'TERM': 'linux', 22:05:17 INFO - 'TMOUT': '86400', 22:05:17 INFO - 'USER': 'cltbld', 22:05:17 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 22:05:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:17 INFO - '_': '/tools/buildbot/bin/python'} 22:05:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:17 INFO - Downloading/unpacking psutil>=3.1.1 22:05:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:05:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:05:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:05:19 INFO - Installing collected packages: psutil 22:05:19 INFO - Running setup.py install for psutil 22:05:19 INFO - building 'psutil._psutil_linux' extension 22:05:19 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:05:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:05:19 INFO - building 'psutil._psutil_posix' extension 22:05:19 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:05:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:05:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:05:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:05:20 INFO - Successfully installed psutil 22:05:20 INFO - Cleaning up... 22:05:20 INFO - Return code: 0 22:05:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:05:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc34a25f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2528ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2525f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25271d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:05:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:05:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:05:20 INFO - Using env: {'DISPLAY': ':0', 22:05:20 INFO - 'HOME': '/home/cltbld', 22:05:20 INFO - 'LANG': 'en_US.UTF-8', 22:05:20 INFO - 'LANGUAGE': 'en_US:en', 22:05:20 INFO - 'LOGNAME': 'cltbld', 22:05:20 INFO - 'MAIL': '/var/mail/cltbld', 22:05:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:20 INFO - 'MOZ_NO_REMOTE': '1', 22:05:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:20 INFO - 'NO_EM_RESTART': '1', 22:05:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:20 INFO - 'PWD': '/builds/slave/test', 22:05:20 INFO - 'SHELL': '/bin/bash', 22:05:20 INFO - 'SHLVL': '1', 22:05:20 INFO - 'TERM': 'linux', 22:05:20 INFO - 'TMOUT': '86400', 22:05:20 INFO - 'USER': 'cltbld', 22:05:20 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 22:05:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:20 INFO - '_': '/tools/buildbot/bin/python'} 22:05:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:05:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:05:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:05:22 INFO - Installing collected packages: mozsystemmonitor 22:05:22 INFO - Running setup.py install for mozsystemmonitor 22:05:22 INFO - Successfully installed mozsystemmonitor 22:05:22 INFO - Cleaning up... 22:05:22 INFO - Return code: 0 22:05:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:05:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc34a25f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2528ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2525f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25271d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:05:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:05:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:05:22 INFO - Using env: {'DISPLAY': ':0', 22:05:22 INFO - 'HOME': '/home/cltbld', 22:05:22 INFO - 'LANG': 'en_US.UTF-8', 22:05:22 INFO - 'LANGUAGE': 'en_US:en', 22:05:22 INFO - 'LOGNAME': 'cltbld', 22:05:22 INFO - 'MAIL': '/var/mail/cltbld', 22:05:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:22 INFO - 'MOZ_NO_REMOTE': '1', 22:05:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:22 INFO - 'NO_EM_RESTART': '1', 22:05:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:22 INFO - 'PWD': '/builds/slave/test', 22:05:22 INFO - 'SHELL': '/bin/bash', 22:05:22 INFO - 'SHLVL': '1', 22:05:22 INFO - 'TERM': 'linux', 22:05:22 INFO - 'TMOUT': '86400', 22:05:22 INFO - 'USER': 'cltbld', 22:05:22 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 22:05:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:22 INFO - '_': '/tools/buildbot/bin/python'} 22:05:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:22 INFO - Downloading/unpacking jsonschema==2.5.1 22:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:05:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:24 INFO - Downloading functools32-3.2.3-2.tar.gz 22:05:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:05:25 INFO - warning: no files found matching '*.txt' 22:05:25 INFO - no previously-included directories found matching 'build' 22:05:25 INFO - no previously-included directories found matching 'dist' 22:05:25 INFO - no previously-included directories found matching '.git*' 22:05:25 INFO - Installing collected packages: jsonschema, functools32 22:05:25 INFO - Running setup.py install for functools32 22:05:25 INFO - warning: no files found matching '*.txt' 22:05:25 INFO - no previously-included directories found matching 'build' 22:05:25 INFO - no previously-included directories found matching 'dist' 22:05:25 INFO - no previously-included directories found matching '.git*' 22:05:25 INFO - Successfully installed jsonschema functools32 22:05:25 INFO - Cleaning up... 22:05:25 INFO - Return code: 0 22:05:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:05:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc34a25f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2528ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2525f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25271d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:05:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:05:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:05:25 INFO - Using env: {'DISPLAY': ':0', 22:05:25 INFO - 'HOME': '/home/cltbld', 22:05:25 INFO - 'LANG': 'en_US.UTF-8', 22:05:25 INFO - 'LANGUAGE': 'en_US:en', 22:05:25 INFO - 'LOGNAME': 'cltbld', 22:05:25 INFO - 'MAIL': '/var/mail/cltbld', 22:05:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:25 INFO - 'MOZ_NO_REMOTE': '1', 22:05:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:25 INFO - 'NO_EM_RESTART': '1', 22:05:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:25 INFO - 'PWD': '/builds/slave/test', 22:05:25 INFO - 'SHELL': '/bin/bash', 22:05:25 INFO - 'SHLVL': '1', 22:05:25 INFO - 'TERM': 'linux', 22:05:25 INFO - 'TMOUT': '86400', 22:05:25 INFO - 'USER': 'cltbld', 22:05:25 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 22:05:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:25 INFO - '_': '/tools/buildbot/bin/python'} 22:05:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:05:25 INFO - Cleaning up... 22:05:25 INFO - Return code: 0 22:05:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:05:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc34a25f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2528ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2525f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25271d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:05:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:05:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:05:25 INFO - Using env: {'DISPLAY': ':0', 22:05:25 INFO - 'HOME': '/home/cltbld', 22:05:25 INFO - 'LANG': 'en_US.UTF-8', 22:05:25 INFO - 'LANGUAGE': 'en_US:en', 22:05:25 INFO - 'LOGNAME': 'cltbld', 22:05:25 INFO - 'MAIL': '/var/mail/cltbld', 22:05:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:25 INFO - 'MOZ_NO_REMOTE': '1', 22:05:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:25 INFO - 'NO_EM_RESTART': '1', 22:05:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:25 INFO - 'PWD': '/builds/slave/test', 22:05:25 INFO - 'SHELL': '/bin/bash', 22:05:25 INFO - 'SHLVL': '1', 22:05:25 INFO - 'TERM': 'linux', 22:05:25 INFO - 'TMOUT': '86400', 22:05:25 INFO - 'USER': 'cltbld', 22:05:25 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 22:05:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:25 INFO - '_': '/tools/buildbot/bin/python'} 22:05:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:25 INFO - Downloading/unpacking blobuploader==1.2.4 22:05:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:27 INFO - Downloading blobuploader-1.2.4.tar.gz 22:05:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:05:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:05:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:28 INFO - Downloading docopt-0.6.1.tar.gz 22:05:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:05:28 INFO - Installing collected packages: blobuploader, requests, docopt 22:05:28 INFO - Running setup.py install for blobuploader 22:05:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:05:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:05:28 INFO - Running setup.py install for requests 22:05:28 INFO - Running setup.py install for docopt 22:05:29 INFO - Successfully installed blobuploader requests docopt 22:05:29 INFO - Cleaning up... 22:05:29 INFO - Return code: 0 22:05:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc34a25f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2528ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2525f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25271d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:05:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:05:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:05:29 INFO - Using env: {'DISPLAY': ':0', 22:05:29 INFO - 'HOME': '/home/cltbld', 22:05:29 INFO - 'LANG': 'en_US.UTF-8', 22:05:29 INFO - 'LANGUAGE': 'en_US:en', 22:05:29 INFO - 'LOGNAME': 'cltbld', 22:05:29 INFO - 'MAIL': '/var/mail/cltbld', 22:05:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:29 INFO - 'MOZ_NO_REMOTE': '1', 22:05:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:29 INFO - 'NO_EM_RESTART': '1', 22:05:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:29 INFO - 'PWD': '/builds/slave/test', 22:05:29 INFO - 'SHELL': '/bin/bash', 22:05:29 INFO - 'SHLVL': '1', 22:05:29 INFO - 'TERM': 'linux', 22:05:29 INFO - 'TMOUT': '86400', 22:05:29 INFO - 'USER': 'cltbld', 22:05:29 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 22:05:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:29 INFO - '_': '/tools/buildbot/bin/python'} 22:05:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:05:29 INFO - Running setup.py (path:/tmp/pip-Ub1koC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:05:29 INFO - Running setup.py (path:/tmp/pip-H5nFRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:05:29 INFO - Running setup.py (path:/tmp/pip-DVGTn7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:05:29 INFO - Running setup.py (path:/tmp/pip-5N3U_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:05:29 INFO - Running setup.py (path:/tmp/pip-yui0vx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:05:29 INFO - Running setup.py (path:/tmp/pip-zd3vep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:05:29 INFO - Running setup.py (path:/tmp/pip-zVtdtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:05:29 INFO - Running setup.py (path:/tmp/pip-soIrT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:05:29 INFO - Running setup.py (path:/tmp/pip-vof_rl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:05:29 INFO - Running setup.py (path:/tmp/pip-GFecaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:05:30 INFO - Running setup.py (path:/tmp/pip-MycxFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:05:30 INFO - Running setup.py (path:/tmp/pip-A_X0Bo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:05:30 INFO - Running setup.py (path:/tmp/pip-tOfJ7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:05:30 INFO - Running setup.py (path:/tmp/pip-ZHizuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:05:30 INFO - Running setup.py (path:/tmp/pip-SYySLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:05:30 INFO - Running setup.py (path:/tmp/pip-RKafDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:05:30 INFO - Running setup.py (path:/tmp/pip-4BYruM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:05:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:05:30 INFO - Running setup.py install for manifestparser 22:05:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:05:30 INFO - Running setup.py install for mozcrash 22:05:30 INFO - Running setup.py install for mozdebug 22:05:30 INFO - Running setup.py install for mozdevice 22:05:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:05:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:05:30 INFO - Running setup.py install for mozfile 22:05:31 INFO - Running setup.py install for mozhttpd 22:05:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:05:31 INFO - Running setup.py install for mozinfo 22:05:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:05:31 INFO - Running setup.py install for mozInstall 22:05:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:05:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:05:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:05:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:05:31 INFO - Running setup.py install for mozleak 22:05:31 INFO - Running setup.py install for mozlog 22:05:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:05:31 INFO - Running setup.py install for moznetwork 22:05:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:05:31 INFO - Running setup.py install for mozprocess 22:05:31 INFO - Running setup.py install for mozprofile 22:05:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:05:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:05:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:05:31 INFO - Running setup.py install for mozrunner 22:05:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:05:32 INFO - Running setup.py install for mozscreenshot 22:05:32 INFO - Running setup.py install for moztest 22:05:32 INFO - Running setup.py install for mozversion 22:05:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:05:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:05:32 INFO - Cleaning up... 22:05:32 INFO - Return code: 0 22:05:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:05:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc34a25f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2528ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2525f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25271d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:05:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:05:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:05:32 INFO - Using env: {'DISPLAY': ':0', 22:05:32 INFO - 'HOME': '/home/cltbld', 22:05:32 INFO - 'LANG': 'en_US.UTF-8', 22:05:32 INFO - 'LANGUAGE': 'en_US:en', 22:05:32 INFO - 'LOGNAME': 'cltbld', 22:05:32 INFO - 'MAIL': '/var/mail/cltbld', 22:05:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:32 INFO - 'MOZ_NO_REMOTE': '1', 22:05:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:32 INFO - 'NO_EM_RESTART': '1', 22:05:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:32 INFO - 'PWD': '/builds/slave/test', 22:05:32 INFO - 'SHELL': '/bin/bash', 22:05:32 INFO - 'SHLVL': '1', 22:05:32 INFO - 'TERM': 'linux', 22:05:32 INFO - 'TMOUT': '86400', 22:05:32 INFO - 'USER': 'cltbld', 22:05:32 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 22:05:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:32 INFO - '_': '/tools/buildbot/bin/python'} 22:05:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:05:32 INFO - Running setup.py (path:/tmp/pip-gFJsX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:05:32 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:05:32 INFO - Running setup.py (path:/tmp/pip-cUjrKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:05:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:05:32 INFO - Running setup.py (path:/tmp/pip-8zSajV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:05:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:05:32 INFO - Running setup.py (path:/tmp/pip-IMZ1zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:05:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:05:32 INFO - Running setup.py (path:/tmp/pip-rCUf4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:05:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:05:32 INFO - Running setup.py (path:/tmp/pip-s33k7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:05:33 INFO - Running setup.py (path:/tmp/pip-qlYvsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:05:33 INFO - Running setup.py (path:/tmp/pip-SJrnAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:05:33 INFO - Running setup.py (path:/tmp/pip-AS0vcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:05:33 INFO - Running setup.py (path:/tmp/pip-cMTAQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:05:33 INFO - Running setup.py (path:/tmp/pip-HW0g1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:05:33 INFO - Running setup.py (path:/tmp/pip-CIe1KV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:05:33 INFO - Running setup.py (path:/tmp/pip-sidt6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:05:33 INFO - Running setup.py (path:/tmp/pip-ZaIFXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:05:33 INFO - Running setup.py (path:/tmp/pip-8DzmLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:05:33 INFO - Running setup.py (path:/tmp/pip-bc0VB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:05:33 INFO - Running setup.py (path:/tmp/pip-6L1PN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:05:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:05:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:05:35 INFO - Downloading blessings-1.6.tar.gz 22:05:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:05:35 INFO - Installing collected packages: blessings 22:05:35 INFO - Running setup.py install for blessings 22:05:35 INFO - Successfully installed blessings 22:05:35 INFO - Cleaning up... 22:05:36 INFO - Return code: 0 22:05:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:05:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:05:36 INFO - Reading from file tmpfile_stdout 22:05:36 INFO - Current package versions: 22:05:36 INFO - argparse == 1.2.1 22:05:36 INFO - blessings == 1.6 22:05:36 INFO - blobuploader == 1.2.4 22:05:36 INFO - docopt == 0.6.1 22:05:36 INFO - functools32 == 3.2.3-2 22:05:36 INFO - jsonschema == 2.5.1 22:05:36 INFO - manifestparser == 1.1 22:05:36 INFO - mozInstall == 1.12 22:05:36 INFO - mozcrash == 0.17 22:05:36 INFO - mozdebug == 0.1 22:05:36 INFO - mozdevice == 0.48 22:05:36 INFO - mozfile == 1.2 22:05:36 INFO - mozhttpd == 0.7 22:05:36 INFO - mozinfo == 0.9 22:05:36 INFO - mozleak == 0.1 22:05:36 INFO - mozlog == 3.4 22:05:36 INFO - moznetwork == 0.27 22:05:36 INFO - mozprocess == 0.23 22:05:36 INFO - mozprofile == 0.28 22:05:36 INFO - mozrunner == 6.12 22:05:36 INFO - mozscreenshot == 0.1 22:05:36 INFO - mozsystemmonitor == 0.3 22:05:36 INFO - moztest == 0.7 22:05:36 INFO - mozversion == 1.4 22:05:36 INFO - psutil == 3.1.1 22:05:36 INFO - requests == 1.2.3 22:05:36 INFO - wsgiref == 0.1.2 22:05:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc34a25f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2528ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2525f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25271d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:05:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:05:36 INFO - Using env: {'DISPLAY': ':0', 22:05:36 INFO - 'HOME': '/home/cltbld', 22:05:36 INFO - 'LANG': 'en_US.UTF-8', 22:05:36 INFO - 'LANGUAGE': 'en_US:en', 22:05:36 INFO - 'LOGNAME': 'cltbld', 22:05:36 INFO - 'MAIL': '/var/mail/cltbld', 22:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:36 INFO - 'MOZ_NO_REMOTE': '1', 22:05:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:36 INFO - 'NO_EM_RESTART': '1', 22:05:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:36 INFO - 'PWD': '/builds/slave/test', 22:05:36 INFO - 'SHELL': '/bin/bash', 22:05:36 INFO - 'SHLVL': '1', 22:05:36 INFO - 'TERM': 'linux', 22:05:36 INFO - 'TMOUT': '86400', 22:05:36 INFO - 'USER': 'cltbld', 22:05:36 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 22:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:36 INFO - '_': '/tools/buildbot/bin/python'} 22:05:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:05:36 INFO - Cleaning up... 22:05:36 INFO - Return code: 0 22:05:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc34a25f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2528ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2525f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25271d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:05:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:05:36 INFO - Using env: {'DISPLAY': ':0', 22:05:36 INFO - 'HOME': '/home/cltbld', 22:05:36 INFO - 'LANG': 'en_US.UTF-8', 22:05:36 INFO - 'LANGUAGE': 'en_US:en', 22:05:36 INFO - 'LOGNAME': 'cltbld', 22:05:36 INFO - 'MAIL': '/var/mail/cltbld', 22:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:36 INFO - 'MOZ_NO_REMOTE': '1', 22:05:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:36 INFO - 'NO_EM_RESTART': '1', 22:05:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:36 INFO - 'PWD': '/builds/slave/test', 22:05:36 INFO - 'SHELL': '/bin/bash', 22:05:36 INFO - 'SHLVL': '1', 22:05:36 INFO - 'TERM': 'linux', 22:05:36 INFO - 'TMOUT': '86400', 22:05:36 INFO - 'USER': 'cltbld', 22:05:36 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 22:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:36 INFO - '_': '/tools/buildbot/bin/python'} 22:05:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:05:36 INFO - Cleaning up... 22:05:36 INFO - Return code: 0 22:05:36 INFO - Running post-action listener: _resource_record_post_action 22:05:36 INFO - Running post-action listener: _start_resource_monitoring 22:05:36 INFO - Starting resource monitoring. 22:05:36 INFO - [mozharness: 2017-01-16 06:05:36.752870Z] Finished create-virtualenv step (success) 22:05:36 INFO - [mozharness: 2017-01-16 06:05:36.753369Z] Running install step. 22:05:36 INFO - Running pre-action listener: _resource_record_pre_action 22:05:36 INFO - Running main action method: install 22:05:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:05:36 INFO - Reading from file tmpfile_stdout 22:05:36 INFO - Detecting whether we're running mozinstall >=1.0... 22:05:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:05:37 INFO - Reading from file tmpfile_stdout 22:05:37 INFO - Output received: 22:05:37 INFO - Usage: mozinstall [options] installer 22:05:37 INFO - Options: 22:05:37 INFO - -h, --help show this help message and exit 22:05:37 INFO - -d DEST, --destination=DEST 22:05:37 INFO - Directory to install application into. [default: 22:05:37 INFO - "/builds/slave/test"] 22:05:37 INFO - --app=APP Application being installed. [default: firefox] 22:05:37 INFO - mkdir: /builds/slave/test/build/application 22:05:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:05:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:05:51 INFO - Reading from file tmpfile_stdout 22:05:51 INFO - Output received: 22:05:51 INFO - /builds/slave/test/build/application/firefox/firefox 22:05:51 INFO - Running post-action listener: _resource_record_post_action 22:05:51 INFO - [mozharness: 2017-01-16 06:05:51.131595Z] Finished install step (success) 22:05:51 INFO - [mozharness: 2017-01-16 06:05:51.131844Z] Running run-tests step. 22:05:51 INFO - Running pre-action listener: _resource_record_pre_action 22:05:51 INFO - Running main action method: run_tests 22:05:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:05:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:05:51 INFO - Python 2.7.3 22:05:51 INFO - Return code: 0 22:05:51 INFO - grabbing minidump binary from tooltool 22:05:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:51 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2525f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25271d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:05:51 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:05:51 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:05:51 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:05:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:05:51 INFO - Return code: 0 22:05:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:05:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:05:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:05:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:05:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:05:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:05:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-065 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:05:51 INFO - Using env: {'DISPLAY': ':0', 22:05:51 INFO - 'HOME': '/home/cltbld', 22:05:51 INFO - 'LANG': 'en_US.UTF-8', 22:05:51 INFO - 'LANGUAGE': 'en_US:en', 22:05:51 INFO - 'LOGNAME': 'cltbld', 22:05:51 INFO - 'MAIL': '/var/mail/cltbld', 22:05:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:05:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:05:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:51 INFO - 'MOZ_NO_REMOTE': '1', 22:05:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:05:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:51 INFO - 'NO_EM_RESTART': '1', 22:05:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:51 INFO - 'PWD': '/builds/slave/test', 22:05:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:05:51 INFO - 'SHELL': '/bin/bash', 22:05:51 INFO - 'SHLVL': '1', 22:05:51 INFO - 'TERM': 'linux', 22:05:51 INFO - 'TMOUT': '86400', 22:05:51 INFO - 'USER': 'cltbld', 22:05:51 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497', 22:05:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:51 INFO - '_': '/tools/buildbot/bin/python'} 22:05:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:05:51 INFO - mozversion application_buildid: 20170115200922 22:05:51 INFO - mozversion application_changeset: e1b44c560c28fd2605bbec4f5bfaa7592379a6c9 22:05:51 INFO - mozversion application_display_name: Firefox 22:05:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:05:51 INFO - mozversion application_name: Firefox 22:05:51 INFO - mozversion application_remotingname: firefox 22:05:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 22:05:51 INFO - mozversion application_vendor: Mozilla 22:05:51 INFO - mozversion application_version: 51.0 22:05:51 INFO - mozversion platform_buildid: 20170115200922 22:05:51 INFO - mozversion platform_changeset: e1b44c560c28fd2605bbec4f5bfaa7592379a6c9 22:05:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 22:05:51 INFO - mozversion platform_version: 51.0 22:05:51 INFO - using testdate: 1484546751 22:05:51 INFO - actual date: 1484546751 22:05:51 INFO - starting webserver on 'localhost:40021' 22:05:51 INFO - SUITE-START | Running 2 tests 22:05:51 INFO - TEST-START | dromaeo_css 22:05:51 INFO - operating with platform_type : linux_ 22:05:51 INFO - Initialising browser for dromaeo_css test... 22:05:51 INFO - TEST-INFO | started process 3373 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSdaGyt/profile http://localhost:40021/getInfo.html) 22:05:55 INFO - PROCESS | 3373 | __metrics Screen width/height:1600/1200 22:05:55 INFO - PROCESS | 3373 | colorDepth:24 22:05:55 INFO - PROCESS | 3373 | Browser inner width/height: 1024/697 22:05:55 INFO - PROCESS | 3373 | __metrics 22:05:55 INFO - PROCESS | 3373 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:05:55 INFO - PROCESS | 3373 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:05:56 INFO - PROCESS | 3373 | console.error: PushService: 22:05:56 INFO - PROCESS | 3373 | stateChangeProcessEnqueue: Error transitioning state 22:05:56 INFO - PROCESS | 3373 | UnknownError 22:05:56 INFO - TEST-INFO | 3373: exit 0 22:05:56 INFO - Browser initialized. 22:05:56 INFO - Running cycle 1/1 for dromaeo_css test... 22:05:56 INFO - TEST-INFO | started process 3466 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSdaGyt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:06:08 INFO - PROCESS | 3466 | RSS: Main: 143847424 22:06:08 INFO - PROCESS | 3466 | 22:06:59 INFO - PROCESS | 3466 | 1484546819878 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:09:08 INFO - PROCESS | 3466 | Cycle 1(1): loaded http://localhost:40021/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40021/tests/dromaeo/cssquery-ext.html) 22:09:09 INFO - PROCESS | 3466 | RSS: Main: 239742976 22:09:09 INFO - PROCESS | 3466 | 22:12:12 INFO - PROCESS | 3466 | Cycle 1(1): loaded http://localhost:40021/tests/dromaeo/cssquery-ext.html (next: http://localhost:40021/tests/dromaeo/cssquery-jquery.html) 22:12:12 INFO - PROCESS | 3466 | RSS: Main: 227479552 22:12:12 INFO - PROCESS | 3466 | 22:15:14 INFO - PROCESS | 3466 | Cycle 1(1): loaded http://localhost:40021/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40021/tests/dromaeo/cssquery-mootools.html) 22:15:15 INFO - PROCESS | 3466 | RSS: Main: 219656192 22:15:15 INFO - PROCESS | 3466 | 22:18:17 INFO - PROCESS | 3466 | Cycle 1(1): loaded http://localhost:40021/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40021/tests/dromaeo/cssquery-prototype.html) 22:18:17 INFO - PROCESS | 3466 | RSS: Main: 175452160 22:18:17 INFO - PROCESS | 3466 | 22:21:20 INFO - PROCESS | 3466 | Cycle 1(1): loaded http://localhost:40021/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40021/tests/dromaeo/cssquery-yui.html) 22:21:20 INFO - PROCESS | 3466 | RSS: Main: 176394240 22:21:20 INFO - PROCESS | 3466 | 22:24:23 INFO - PROCESS | 3466 | Cycle 1(1): loaded http://localhost:40021/tests/dromaeo/cssquery-yui.html (next: http://localhost:40021/tests/dromaeo/cssquery-dojo.html) 22:24:23 INFO - PROCESS | 3466 | RSS: Main: 266330112 22:24:23 INFO - PROCESS | 3466 | 22:24:23 INFO - PROCESS | 3466 | __start_tp_report 22:24:23 INFO - PROCESS | 3466 | _x_x_mozilla_page_load 22:24:23 INFO - PROCESS | 3466 | _x_x_mozilla_page_load_details 22:24:23 INFO - PROCESS | 3466 | |i|pagename|runs| 22:24:23 INFO - PROCESS | 3466 | |0;dojo.html;3117.1895307220125;3130.0296907958527;3160.652328243893;3170.080676603785;3198.3123628419885;5579.302587176598;5641.323041235052;5644.633098848582;5678.375378708339;5695.544356451484;5641.5204707599905;5686.3744988051285;5690.345610254822;5696.458836410545;5727.599068065237;5140.537351638342;5182.533571978521;5205.9219111713355;5222.764975576109;5271.710055946922;5063.873403164914;5260.237265596486;5264.236685680574;5273.080921893735;5301.602379821509;5046.470120637336;5086.92369614456;5117.48825117489;5145.922811157836;5147.742612869341;5146.356705411824;5160.8709782255555;5175.378954525443;5237;5237.711925844077;5091.0072535855525;5125.538701516844;5197.66215196011;5208.661437006583;5241.73791310433;5410.188471729272;5451.373092094381;5537.335519737617;5563.13771365439;5620.5503559715125;5996.340402555716;6005.1892994445625;6101.969490152521;6115.724792384353;6125.785597504066;6129.632222066645;6142.232220972379;6147.600405973598;6149.754009839601;6175.536834737412;5440;5445.455454454563;5450.482204190595;5564.805231816867;5589.8602534936135;6189.319174890758;6226.003839385677;6227.252729672468;6233.470155036782;6234.096056071935;5968.641881487125;5970.76116955317;5978.013627751459;5978.970105149447;5979.252593425822;5827.65034097947;5906.675132867647;5921.230240068763;5974.551908606785;5986.341502434729;5983.790567330123;5991.850203744942;6009.879802403927;6014.398560143951;6041.24484439445;5951.702414879239;6009.729562169713;6011.5190784737115;6021.849453763603;6031.125486804389;4507.977460112678;4551;4560.521145279764;4598.701084429568;4631.51369106239;4072.816723247461;4098.569650186747;4124.216398884202;4142.378643203483;4147.294959856771;5861.062230043172;5864.354920958691;5865.35481097079;5865.472107510344;5869.119632055226;5900.350961394243;5956.970215148896;6009.098635204667;6175.2589689237575;6179.567430279837;5644.604877658525;5861.882762344814;5862.8534286642325;5873.177755114288;5896.852578685567;5213.7393130343335;5247.370315562157;5257.237700533401;5259;5259.47405259471;6016.819495415145;6036.275646922221;6214.4407003369915;6270.2475702915945;6275.152854364694;6029.095635654687;6071.423214794588;6095.116208149705;6142.389484469172;6146.754129834757;6300.0234963580715;6302.842428939313;6306.369363063657;6307.716152773044;6308.589941653778;5758.913616295675;6003.939960600338;6004.159417681441;6005.729742161526;6008.669523176267;5825.417458254141;5995.46040856325;6017.097435384728;6050.90923636137;6140.074827791203;5968.4031596841705;6015.72929218195;6018.789342372996;6022.427869352405;6048.4858787003595;5946.07835785454;5982.042873140276;5989.101634754822;5995.100734889801;6000.819975400745;5615.634983726045;5723.484886360247;5783.7397317119985;5800;5839.503642190291;5933.258342707161;5934.881302374015;5945.227120474311;5962.9105563415715;6003;5700.743466543932;5807.796727114531;5812.651240925558;5813.09896965971;5829.59192856496 22:24:23 INFO - PROCESS | 3466 | |1;ext.html;594.943480369365;618.1500436899264;641.9935800641993;650.4146268358478;654.090813768861;34922.82538587307;37509.249815003684;44156.558434415645;45434.77282613591;45528.3170752439;46590.534094659044;46981.530184698226;47023.76488117563;47052.29421558679;47084;8331.12523185066;9261.120193581615;9385.108414700593;9389.530523473799;9394.530273486333;8336.791580210482;8617.181367770052;10891.67324980252;11141.554337826517;11146.164037697165;11183.944080279587;11245.55017799287;11247;11251.43742812856;11258.211925165242;11026.117910567176;11103.777924441547;11104.389258590774;11108.222424430292;11121.221514493947;10614.416207108607;10665.626703065433;10665.84001239982;10668.839967400496;10675.199360047949;44365.33451998223;44373.55626443759;44622.55377446216;44701.32948005782;44723.77638111821;3503.2818272254126;3546.5744110706623;3862.9613703862883;4102.446169767103;4423.248047831877;34377.886592306226;34397.363745545685;34611.82694086539;35126.473102903474;35138.6486135138;4602.792874320664;4662.254039353687;4737.407824021997;4737.976310118462;4838.298446725205;264348;265130;265449.67275163694;265623;266123;61845.07232391518;62030;62208;62276.37723622796;62284.68857655773;36902.261954760754;38530.2293954125;38554.80722596425;40582;66411.66794166095;17432.477025688997;17642.823571764377;17651.646967060587;17655.558611034827;17659.470215893285;11326.433678316216;11516.1362897783;13176.934115329363;13236.07347485686;13246.337683115806;18531.351402700842;18661.440156795317;19538.865221338754;20994.580108397746;21037.84291863962;7263.527870688388;7582.128055273714;7589.468737188454;7649.541027538366;7693.576853273124;901.6123067081087;975.4196253229317;1071.4482041748506;1205.1323047405854;1214.7548762518313;33601.83199083989;35166.648333516336;35569.822150889595;36688.899333020054;36790.8160459196;1077.628218264713;1087.152021423291;1099.7030801683661;1134.2910680824484;1204.4278967490548;15268.31292591836;17606.735898961524;17706.645867082843;18737.156827942774;19303.131359088875;482885;486970;488432;488526;491149;57416.71291643515;57589.42410575841;57655.71172144113;57666.7116664414;57728.71135644379;1242.3850194153772;1284.9550265740654;1355.7424089422977;1359.4426285223208;1364.7065880835669;31974.840125799223;33670.831645841616;33839.3232135351;34129.31741365109;34493.82753086218;51529;58251;59793.402065978786;59806.40193598008;64618.67690661517;521131;523361;524674;524827;528055;39174.60825391824;39873;43468.347974781034;43745;44903;50459.74770126126;50753.746231268604;51184.23223651574;51320.230196547825;51547;42570.14859702851;42784.35823462713;42802.57197427986;43232.35151472793;44312.7784361089;42759.572404276805;43737;44274.778626106665;44482.332765009196;44902.77548612236;43462.56537434712;44202.778986104866;44843;44899;45092.54907451015;35774;38786.22427551377;38892.80553597327;39048.41427378535;39667.40498892461;29228.707712922598;29326.853365733034;29420.55869162007;29480.55779163357;29751.70248297489 22:24:23 INFO - PROCESS | 3466 | |2;jquery.html;411.7262020756197;416.98749037528864;424.44185895547355;431.47791172681065;449.40678304637873;19043;19404.126814293337;20759.481012974684;20944.16223351068;20962.37112886614;19610.70583941242;19889.900550497263;20656.586868262624;20714.68927966078;20927.790722092774;5662.688552129642;5924.614900031505;5935.525157987372;5936.64380137191;5939.109133629947;5660.010470421056;5731.971340143315;5771.163181338703;5807.2450581424355;5849.356570777211;5804.622699524517;5820.330661973885;5821.359650438449;5821.621594596338;5821.6507009579345;5681.119426488901;5762.078067509199;5810.5932584719085;5824.592278540504;5852.473277405012;5306.8407947761625;5316.840494785162;5324.653897496651;5325.0148722486465;5328.20076988451;23949.521009579712;23959.88020059906;24135.15526956548;24572.877135614206;24778.50442991148;2357.634566642173;2371.537550177716;2396.8681722505257;2467.605183170702;3192.696693814084;10363.274570780048;10619.41593212373;10857.891421085846;10903.454827258685;10981.560737570568;1964.3321270768008;2080.3446914222122;2082.1671331467533;2107.5363420047565;2117.195465723031;56814.43185568174;68123;73889.63055184744;84367.15632843654;84796.57601712014;11176.329420234812;11507.136964727679;11784.526934133233;12190;12217;17303.221355039055;17309.134543272787;17339.91330043367;17341.7398739019;17349.7397539037;8577.05652378238;8635.136486351315;8918.1973622373;8967.820643587222;9058.139476749699;7543.585102819288;7637.618119094023;7912.881306780403;8154.429189956646;8190.426670133033;5071.87320316995;5177.896442071137;5207.661502002434;5232.529072383504;5287.180487024518;2046.079264331057;2151.7310336207975;2157.4498502881665;2176.5320456102013;2190.1567896359697;908.4503875155435;950.6150009246267;969.970900872975;994.7761753605381;1100.372787511127;20090;20235.696464552748;20245.08897099634;20364.18543258253;20424.795752042588;966.7196513011288;971.7910649210454;993.8857031441332;1047.7537778622018;1050.9474526273655;10816.134709223243;11289;11331.773364532828;11337.716557086138;11437.768025830495;419990;428476;466410.3345134564;473672;479609;58267.70866145642;71736.64131679309;91463.54268228749;93911.53044234736;94166.52916735373;1008.4755926918108;1060.538665680416;1077.5151181968147;1090.0571006079683;1096.2874131814226;17654.646907062044;17960.461186164437;18053.548661283574;18220.90889545544;18556.979366134452;41754.37368439415;42106.789466052476;42392.788036060854;42616;42752.57247427486;55989.44010559842;60306.39693603183;60907.39092609017;61971.380286198364;63027;8678.869816952625;8763.25512331459;8803.735887923373;9040.864387034331;9210.171084602418;9648.372855764354;12191.146619736533;12905.09664323526;13114.344282785632;13181.670458238237;10557.63048293306;10978.341299522055;10995.34027958325;11200.607978720705;11311.434428278388;10947;11283.435828208392;11336.433178341214;11617.6514704559;11649.941750291533;10413.739656508647;11125.165612578927;11172.273802202832;11176.497057632263;11547.538098476303;7551.169371369255;7773.92226077732;7919.247671471201;8130.902328185856;9159.49622770754;10733.463326833784;10734.624288149877;10775.461226938465;10845.566177352817;11220.438978051228 22:24:23 INFO - PROCESS | 3466 | |3;mootools.html;1391.5129704603385;1524.8856335774817;1541.0907564536922;1657.9917100414496;1808.9819101808987;8135.430519863612;9202;9618.038196180378;9622.951885240582;9740.366876153063;9544;9569.904300956989;9597.136257736802;9613;9640.85538716918;2485.8259921805434;2511.1211076123395;2513.8114641401876;2514.585093459577;2514.924552263435;2423.9394015149674;2539.4032402385424;2539.6825396825398;2542.1356738708837;2543.834650747705;2500.737422570631;2519.6220566915017;2519.8110141739353;2520.6723125993594;2521.2436269119285;2373.347329484397;2415.830891837572;2424.866632335221;2432.525657496789;2440.1459489178824;2369.099742098009;2377.5839228135005;2417.576924038304;2426.235735743253;2427.9271621851194;9436.197923176538;9640.517974101336;9690.127888489998;9707.854382184272;9709.854352184722;5707.800226992077;5842.766289348421;5847.912281315784;5848.619839710405;5850.853728656818;3590.5152804371337;3617.7829330240006;3655.9086022849374;3662.633736626343;3676.522052133232;1116.2967330581682;1138.9259698119595;1146.266389510714;1157.8379026936236;1161.3322339654733;13476.528321508798;13510.662233444144;13545.322733863366;13557.118787278796;13619.659508512366;4821.445533763662;4865.7323847188745;4891.290762839369;4893.192623217201;5049.495050494921;10515.211359148094;10518.631847885288;10524.31591946521;10530.631427899987;10534.104601108991;5629.8592535186945;5642.85892852682;5663.66018038919;5666.858328541737;5670.688112153871;4372.519022907478;4422.535633758409;4541.568550987651;4584.862454126382;5298.629095963245;4046.907335019528;4097.7746223957965;4169.791510424528;4186.832526698898;4189.392538081961;1254.0406588959454;1262.103906226571;1269.695273134441;1278.1564167649492;1281.6027031620042;996.1582462818907;997.7904640025531;1002.2983911262;1006.436395618456;1033.0805583031108;6120.969395152996;6181.262830111991;6264.436200741955;6392.552521323462;6559.8360040998405;502.7436007636132;503.07183246910125;505.6031015652695;512.2955935588566;512.5848063068926;4304.655627549789;4344.587264209895;4345.804438800198;4408.603225709701;4442.422485076984;11822.290662560274;12399.194052386747;12426.62720118398;12429.254244745343;12434.253944763163;11591.942040289914;12139.878601213875;12158.452869620889;12579.308138052495;12602.432890519949;8870.64517419296;9032.909670903207;9042.547872606343;9056.637734490547;9088.091190880992;6319.96840015797;6477.319881412573;6552.803415897531;6741.662916854236;6909.170899492093;10958.01377875995;11223.943880280873;11255.831162532404;11362.091032717197;11452.083833293484;9596.99231580674;10227.642032528825;11324.490397932277;11325.546978120783;11457.140714446616;8270.37972152103;8426.325893928593;8640.82718345617;8640.913590864011;8643.135686431306;9159.587818548314;9162.312826537904;9178.173964343241;9247.0752924707;9270.72187834366;8326.750197494084;8374.2881855043;8382.916170838458;8532.360072994426;8620.61207245663;8132.512049277062;8133.8373232536205;8272.83454330922;8309.459885107448;8489.575521223791;7262.745803896838;7483.88774168377;7505.962470187614;7544;7547.811304717426;5316.122839731401;5358.571314294925;5366.221897824872;5377.354717433933;5485.204645326326;5377.919331210113;5487.945120548743;5523.613347065667;5552.888942221052;5553.08374118266 22:24:23 INFO - PROCESS | 3466 | |4;prototype.html;347.7600455685578;361.28105070908884;362.4671732553148;364.43330620884524;366.84042441537935;5408.837734867955;5444.482774136456;5496.4503549645015;5505.779768809243;5539.390667026624;5094.6433749637545;5354.625176237665;5384;5430.945690543093;5432.483914028171;3834.48234488344;4223.345381465862;4341.869743907688;4349.282368409209;4360.781960901958;4349.217140914634;4352.847650332239;4353.586409291112;4361.476622805252;4364.192624364503;4351.934720979188;4361.847335343264;4404.801783919731;4416.58042485965;4434;4205.401947260037;4330.263855144602;4374.168907907488;4381.846635367747;4407.757573333465;3860.6332398422105;3882.417637354391;3891.7081218908697;3901.1807520420743;3912.7456715313697;5328.866778330534;5339.893202135974;5340.946590534084;5399.811006614789;5403.945960540384;4667.579917807379;4923.75381230937;4955.306257124005;4999.975000124976;5026.924596131061;5685.829425117253;5699.68651724155;5704.600677952545;5737.39757325479;5764.855878603068;4197.433346498215;4224.767637779921;4228.154369126187;4231.576842315774;4237.660987121054;5867.12510869675;5969.641821490724;6007.038873780218;6049.758009679652;6064.149849570695;4310.6335961443;4313;4317.676174286878;4332.566743325642;4350.260455722535;4401.2297847877135;4405.7797110144365;4407.515173330931;4412.808541693725;4414.492333381702;4703.341532185496;4862;4896.779644915988;4985.401751789808;5004.924926126111;5733.426657334233;5757.424257574209;5758.88482230353;5761.308642962871;5769.461436967721;1495.588713103905;1521.73369660309;1523.878089752817;1531.8238402583845;1537.108477083289;981.5926390547843;985.5072463768116;1003.6386900715737;1025.2003437318904;1039.6744151207272;5542.861428464321;5563.276774019432;5567.888642227133;5574.637648552831;5591.6365436246515;5862.296524417097;5866.677332746654;5878.412158784087;5888.587798854039;5892.322382926019;5263.157894736823;5460.453954604587;5531.838313954035;5547.1679248113105;5587.658961849127;3105.4720697481484;3127.358891427252;3132.6397464291445;3135.8588863501195;3137.905862824119;5809.331926828384;5999.520038396918;6004.819855404345;6024.9214021162625;6046.94629212245;5748.568857335633;5885.293764748258;5920.881582368328;5956.791512297049;5973.850653733692;5846.619969702041;5934.1692163097205;6016.518678505709;6058.666773327421;6064.696765161724;5913.319968203712;5923.289205295392;5936.8812623748145;5972.910406343822;5975.940240597538;5729.570282228933;5747.706765977621;5789.985779507455;5794.652320860761;5832.591718579659;6039.728212230372;6061.272647282354;6065.272167339771;6065.363136870565;6133.815985520441;4182.895427614334;4318.546552612056;4325.2430824604935;4346.195953748566;4363.47638283408;5862.67755273447;5867.474456641218;5888.17565540816;5892.35184129754;5908.261467316585;5756.136579512939;5800.7099645018425;5818.418158184148;5843.211166492469;5883.911741323798;5477.97314993455;5725.454127385499;5767;5793.217915581343;5820.447057529528;5744.310682717934;5771.365149833431;5774.7690092397515;5787.565932554991;5789.334226563999;5609.467100625434;5837.22731702438;5843.590948633555;5912.260967379078;5947.078202878561;5635.746391412478;5657.773689052392;5678.574106942078;5713.885722285448;5716.142578613241 22:24:23 INFO - PROCESS | 3466 | |5;yui.html;789.5144486141025;802.5064585280054;816.9468984516006;825.2531459029578;857.6097875466661;16688.582785430353;17115.40096096635;17723.73414398785;17823.465296041122;17906.37327693531;16348;16428.425005124795;16487.83512164878;16502.75245871313;16508.422205222818;733.2337707096098;752.1801236652049;755.9962200189019;882.7969566999578;897.443584977315;750.2385079144673;862.2412277196058;888.7822483491522;889.4574309671095;897.6588896219428;933.2254228989922;934.2152591822868;935.5322338830584;935.5696379665362;935.8642996765465;931.8648795924621;932.9766755831124;933.4399360383783;934.2479304160148;934.5047125023748;684.8082536889679;685.2633419074515;685.8388278754488;685.869684759894;685.917689877218;14760.26198690072;14897.255137243095;14958.252087395695;14963.925180374137;14974.850251497563;16627.522312282003;16778.785122397363;16969;17237.39669111575;17631.823681763148;11881.108916831272;11896.286222826571;11918.880811191864;12027.699307517294;12432.937835310857;3983.143624120828;4075.409065685459;4113.8971525711795;4129.236091323115;4214.304639734441;82941;86254.56872715596;87753.99999999936;87794.56102719446;87975.12024879798;12476.625701228977;12494.250344979331;12496.437660305126;12723.491060357483;12903.741925161445;22934.541309174056;22953.311400658007;22969.310920672408;23151.305460836204;25556.74443255581;8872.645094196288;9070.229030532346;9142.497162655985;9170.266378689688;9441.197498212729;4956.900861982812;5036.521530454601;5068.721220332917;5185.248139019821;5271.525562699376;3379.847906844198;3476.66971637694;3492.1793378556017;3501.072215862798;3506.50908872758;634.238913304037;637.9744810207633;641.2689533931322;643.9871202575922;657.7927952694886;791.3194652598761;796.3231253434534;817.7097130518699;823.3372135431035;823.6499487717696;10690.305130166515;10738;11066.31957357134;11394.715132121597;11448.484818183202;724.2069933422894;829.7054545636333;831.5509624802539;832.3757182113415;838.1032295443938;5453.672779633234;5472.179173123984;5527.917081243784;5573.972130139324;5608.66348019112;121118;122873;125339.3733031329;125986.37006814907;129420.35289823679;90765;92235;105414.47292763641;107889.4605526983;107932;59798.10302845461;88014;88908.5554572236;92035;93851;10706.464676766287;10776.622818201326;10776.892231077902;10916.12670986335;10989.780204396027;50720.74639626778;51176.74411627918;51615.48384516257;52786;53026.73486632542;74592;78475.21524784679;80093;80835;82584.17415826005;13643.181409115485;13654.180749155083;13788.379522921694;13845.861541384456;14331.211783352015;14883.702325953636;15833.841661583238;15933;16107.355705771637;16994.320227190772;14946.476873309382;15080.095194288378;15086.773698394753;15318.38726450974;15355.769663454834;15727.213639317759;15955.840441595436;16097.75853362177;16272.43046493367;17593.560160995567;15426.537203883901;15864.206789660702;16430.424935127212;16938.49184524466;16941.830581694026;11832.70418239551;12029.819552706891;12464.189827661174;12679.80980285278;13138.802917956047;11037.503312351124;12822.487100516248;12840.145367650644;13082.672933176747;13184.274864882524 22:24:23 INFO - PROCESS | 3466 | __end_tp_report 22:24:23 INFO - PROCESS | 3466 | __start_cc_report 22:24:23 INFO - PROCESS | 3466 | _x_x_mozilla_cycle_collect,889 22:24:23 INFO - PROCESS | 3466 | __end_cc_report 22:24:23 INFO - PROCESS | 3466 | __startTimestamp1484547863591__endTimestamp 22:24:23 INFO - PROCESS | 3466 | 22:24:23 INFO - PROCESS | 3466 | ------- Summary: start ------- 22:24:23 INFO - PROCESS | 3466 | Number of tests: 6 22:24:23 INFO - PROCESS | 3466 | 22:24:23 INFO - PROCESS | 3466 | [#0] dojo.html Cycles:165 Average:5620.16 Median:5864.35 stddev:653.90 (11.2%) stddev-sans-first:625.73 22:24:23 INFO - PROCESS | 3466 | Values: 3117.2 3130.0 3160.7 3170.1 3198.3 5579.3 5641.3 5644.6 5678.4 5695.5 5641.5 5686.4 5690.3 5696.5 5727.6 5140.5 5182.5 5205.9 5222.8 5271.7 5063.9 5260.2 5264.2 5273.1 5301.6 5046.5 5086.9 5117.5 5145.9 5147.7 5146.4 5160.9 5175.4 5237.0 5237.7 5091.0 5125.5 5197.7 5208.7 5241.7 5410.2 5451.4 5537.3 5563.1 5620.6 5996.3 6005.2 6102.0 6115.7 6125.8 6129.6 6142.2 6147.6 6149.8 6175.5 5440.0 5445.5 5450.5 5564.8 5589.9 6189.3 6226.0 6227.3 6233.5 6234.1 5968.6 5970.8 5978.0 5979.0 5979.3 5827.7 5906.7 5921.2 5974.6 5986.3 5983.8 5991.9 6009.9 6014.4 6041.2 5951.7 6009.7 6011.5 6021.8 6031.1 4508.0 4551.0 4560.5 4598.7 4631.5 4072.8 4098.6 4124.2 4142.4 4147.3 5861.1 5864.4 5865.4 5865.5 5869.1 5900.4 5957.0 6009.1 6175.3 6179.6 5644.6 5861.9 5862.9 5873.2 5896.9 5213.7 5247.4 5257.2 5259.0 5259.5 6016.8 6036.3 6214.4 6270.2 6275.2 6029.1 6071.4 6095.1 6142.4 6146.8 6300.0 6302.8 6306.4 6307.7 6308.6 5758.9 6003.9 6004.2 6005.7 6008.7 5825.4 5995.5 6017.1 6050.9 6140.1 5968.4 6015.7 6018.8 6022.4 6048.5 5946.1 5982.0 5989.1 5995.1 6000.8 5615.6 5723.5 5783.7 5800.0 5839.5 5933.3 5934.9 5945.2 5962.9 6003.0 5700.7 5807.8 5812.7 5813.1 5829.6 22:24:23 INFO - PROCESS | 3466 | 22:24:23 INFO - PROCESS | 3466 | [#1] ext.html Cycles:180 Average:60274.87 Median:40227.50 stddev:116871.49 (290.5%) stddev-sans-first:117113.45 22:24:23 INFO - PROCESS | 3466 | Values: 594.9 618.2 642.0 650.4 654.1 34922.8 37509.2 44156.6 45434.8 45528.3 46590.5 46981.5 47023.8 47052.3 47084.0 8331.1 9261.1 9385.1 9389.5 9394.5 8336.8 8617.2 10891.7 11141.6 11146.2 11183.9 11245.6 11247.0 11251.4 11258.2 11026.1 11103.8 11104.4 11108.2 11121.2 10614.4 10665.6 10665.8 10668.8 10675.2 44365.3 44373.6 44622.6 44701.3 44723.8 3503.3 3546.6 3863.0 4102.4 4423.2 34377.9 34397.4 34611.8 35126.5 35138.6 4602.8 4662.3 4737.4 4738.0 4838.3 264348.0 265130.0 265449.7 265623.0 266123.0 61845.1 62030.0 62208.0 62276.4 62284.7 36902.3 38530.2 38554.8 40582.0 66411.7 17432.5 17642.8 17651.6 17655.6 17659.5 11326.4 11516.1 13176.9 13236.1 13246.3 18531.4 18661.4 19538.9 20994.6 21037.8 7263.5 7582.1 7589.5 7649.5 7693.6 901.6 975.4 1071.4 1205.1 1214.8 33601.8 35166.6 35569.8 36688.9 36790.8 1077.6 1087.2 1099.7 1134.3 1204.4 15268.3 17606.7 17706.6 18737.2 19303.1 482885.0 486970.0 488432.0 488526.0 491149.0 57416.7 57589.4 57655.7 57666.7 57728.7 1242.4 1285.0 1355.7 1359.4 1364.7 31974.8 33670.8 33839.3 34129.3 34493.8 51529.0 58251.0 59793.4 59806.4 64618.7 521131.0 523361.0 524674.0 524827.0 528055.0 39174.6 39873.0 43468.3 43745.0 44903.0 50459.7 50753.7 51184.2 51320.2 51547.0 42570.1 42784.4 42802.6 43232.4 44312.8 42759.6 43737.0 44274.8 44482.3 44902.8 43462.6 44202.8 44843.0 44899.0 45092.5 35774.0 38786.2 38892.8 39048.4 39667.4 29228.7 29326.9 29420.6 29480.6 29751.7 22:24:23 INFO - PROCESS | 3466 | 22:24:23 INFO - PROCESS | 3466 | [#2] jquery.html Cycles:180 Average:27791.55 Median:13985.60 stddev:74826.43 (535.0%) stddev-sans-first:75008.10 22:24:23 INFO - PROCESS | 3466 | Values: 411.7 417.0 424.4 431.5 449.4 19043.0 19404.1 20759.5 20944.2 20962.4 19610.7 19889.9 20656.6 20714.7 20927.8 5662.7 5924.6 5935.5 5936.6 5939.1 5660.0 5732.0 5771.2 5807.2 5849.4 5804.6 5820.3 5821.4 5821.6 5821.7 5681.1 5762.1 5810.6 5824.6 5852.5 5306.8 5316.8 5324.7 5325.0 5328.2 23949.5 23959.9 24135.2 24572.9 24778.5 2357.6 2371.5 2396.9 2467.6 3192.7 10363.3 10619.4 10857.9 10903.5 10981.6 1964.3 2080.3 2082.2 2107.5 2117.2 56814.4 68123.0 73889.6 84367.2 84796.6 11176.3 11507.1 11784.5 12190.0 12217.0 17303.2 17309.1 17339.9 17341.7 17349.7 8577.1 8635.1 8918.2 8967.8 9058.1 7543.6 7637.6 7912.9 8154.4 8190.4 5071.9 5177.9 5207.7 5232.5 5287.2 2046.1 2151.7 2157.4 2176.5 2190.2 908.5 950.6 970.0 994.8 1100.4 20090.0 20235.7 20245.1 20364.2 20424.8 966.7 971.8 993.9 1047.8 1050.9 10816.1 11289.0 11331.8 11337.7 11437.8 419990.0 428476.0 466410.3 473672.0 479609.0 58267.7 71736.6 91463.5 93911.5 94166.5 1008.5 1060.5 1077.5 1090.1 1096.3 17654.6 17960.5 18053.5 18220.9 18557.0 41754.4 42106.8 42392.8 42616.0 42752.6 55989.4 60306.4 60907.4 61971.4 63027.0 8678.9 8763.3 8803.7 9040.9 9210.2 9648.4 12191.1 12905.1 13114.3 13181.7 10557.6 10978.3 10995.3 11200.6 11311.4 10947.0 11283.4 11336.4 11617.7 11649.9 10413.7 11125.2 11172.3 11176.5 11547.5 7551.2 7773.9 7919.2 8130.9 9159.5 10733.5 10734.6 10775.5 10845.6 11220.4 22:24:23 INFO - PROCESS | 3466 | 22:24:23 INFO - PROCESS | 3466 | [#3] mootools.html Cycles:180 Average:6246.50 Median:4703.15 stddev:3661.61 (77.9%) stddev-sans-first:3653.71 22:24:23 INFO - PROCESS | 3466 | Values: 1391.5 1524.9 1541.1 1658.0 1809.0 8135.4 9202.0 9618.0 9623.0 9740.4 9544.0 9569.9 9597.1 9613.0 9640.9 2485.8 2511.1 2513.8 2514.6 2514.9 2423.9 2539.4 2539.7 2542.1 2543.8 2500.7 2519.6 2519.8 2520.7 2521.2 2373.3 2415.8 2424.9 2432.5 2440.1 2369.1 2377.6 2417.6 2426.2 2427.9 9436.2 9640.5 9690.1 9707.9 9709.9 5707.8 5842.8 5847.9 5848.6 5850.9 3590.5 3617.8 3655.9 3662.6 3676.5 1116.3 1138.9 1146.3 1157.8 1161.3 13476.5 13510.7 13545.3 13557.1 13619.7 4821.4 4865.7 4891.3 4893.2 5049.5 10515.2 10518.6 10524.3 10530.6 10534.1 5629.9 5642.9 5663.7 5666.9 5670.7 4372.5 4422.5 4541.6 4584.9 5298.6 4046.9 4097.8 4169.8 4186.8 4189.4 1254.0 1262.1 1269.7 1278.2 1281.6 996.2 997.8 1002.3 1006.4 1033.1 6121.0 6181.3 6264.4 6392.6 6559.8 502.7 503.1 505.6 512.3 512.6 4304.7 4344.6 4345.8 4408.6 4442.4 11822.3 12399.2 12426.6 12429.3 12434.3 11591.9 12139.9 12158.5 12579.3 12602.4 8870.6 9032.9 9042.5 9056.6 9088.1 6320.0 6477.3 6552.8 6741.7 6909.2 10958.0 11223.9 11255.8 11362.1 11452.1 9597.0 10227.6 11324.5 11325.5 11457.1 8270.4 8426.3 8640.8 8640.9 8643.1 9159.6 9162.3 9178.2 9247.1 9270.7 8326.8 8374.3 8382.9 8532.4 8620.6 8132.5 8133.8 8272.8 8309.5 8489.6 7262.7 7483.9 7506.0 7544.0 7547.8 5316.1 5358.6 5366.2 5377.4 5485.2 5377.9 5487.9 5523.6 5552.9 5553.1 22:24:23 INFO - PROCESS | 3466 | 22:24:23 INFO - PROCESS | 3466 | [#4] prototype.html Cycles:180 Average:4867.21 Median:5535.61 stddev:1403.67 (25.4%) stddev-sans-first:1366.01 22:24:23 INFO - PROCESS | 3466 | Values: 347.8 361.3 362.5 364.4 366.8 5408.8 5444.5 5496.5 5505.8 5539.4 5094.6 5354.6 5384.0 5430.9 5432.5 3834.5 4223.3 4341.9 4349.3 4360.8 4349.2 4352.8 4353.6 4361.5 4364.2 4351.9 4361.8 4404.8 4416.6 4434.0 4205.4 4330.3 4374.2 4381.8 4407.8 3860.6 3882.4 3891.7 3901.2 3912.7 5328.9 5339.9 5340.9 5399.8 5403.9 4667.6 4923.8 4955.3 5000.0 5026.9 5685.8 5699.7 5704.6 5737.4 5764.9 4197.4 4224.8 4228.2 4231.6 4237.7 5867.1 5969.6 6007.0 6049.8 6064.1 4310.6 4313.0 4317.7 4332.6 4350.3 4401.2 4405.8 4407.5 4412.8 4414.5 4703.3 4862.0 4896.8 4985.4 5004.9 5733.4 5757.4 5758.9 5761.3 5769.5 1495.6 1521.7 1523.9 1531.8 1537.1 981.6 985.5 1003.6 1025.2 1039.7 5542.9 5563.3 5567.9 5574.6 5591.6 5862.3 5866.7 5878.4 5888.6 5892.3 5263.2 5460.5 5531.8 5547.2 5587.7 3105.5 3127.4 3132.6 3135.9 3137.9 5809.3 5999.5 6004.8 6024.9 6046.9 5748.6 5885.3 5920.9 5956.8 5973.9 5846.6 5934.2 6016.5 6058.7 6064.7 5913.3 5923.3 5936.9 5972.9 5975.9 5729.6 5747.7 5790.0 5794.7 5832.6 6039.7 6061.3 6065.3 6065.4 6133.8 4182.9 4318.5 4325.2 4346.2 4363.5 5862.7 5867.5 5888.2 5892.4 5908.3 5756.1 5800.7 5818.4 5843.2 5883.9 5478.0 5725.5 5767.0 5793.2 5820.4 5744.3 5771.4 5774.8 5787.6 5789.3 5609.5 5837.2 5843.6 5912.3 5947.1 5635.7 5657.8 5678.6 5713.9 5716.1 22:24:23 INFO - PROCESS | 3466 | 22:24:23 INFO - PROCESS | 3466 | [#5] yui.html Cycles:180 Average:22256.88 Median:3496.63 stddev:31572.28 (902.9%) stddev-sans-first:31619.70 22:24:23 INFO - PROCESS | 3466 | Values: 789.5 802.5 816.9 825.3 857.6 16688.6 17115.4 17723.7 17823.5 17906.4 16348.0 16428.4 16487.8 16502.8 16508.4 733.2 752.2 756.0 882.8 897.4 750.2 862.2 888.8 889.5 897.7 933.2 934.2 935.5 935.6 935.9 931.9 933.0 933.4 934.2 934.5 684.8 685.3 685.8 685.9 685.9 14760.3 14897.3 14958.3 14963.9 14974.9 16627.5 16778.8 16969.0 17237.4 17631.8 11881.1 11896.3 11918.9 12027.7 12432.9 3983.1 4075.4 4113.9 4129.2 4214.3 82941.0 86254.6 87754.0 87794.6 87975.1 12476.6 12494.3 12496.4 12723.5 12903.7 22934.5 22953.3 22969.3 23151.3 25556.7 8872.6 9070.2 9142.5 9170.3 9441.2 4956.9 5036.5 5068.7 5185.2 5271.5 3379.8 3476.7 3492.2 3501.1 3506.5 634.2 638.0 641.3 644.0 657.8 791.3 796.3 817.7 823.3 823.6 10690.3 10738.0 11066.3 11394.7 11448.5 724.2 829.7 831.6 832.4 838.1 5453.7 5472.2 5527.9 5574.0 5608.7 121118.0 122873.0 125339.4 125986.4 129420.4 90765.0 92235.0 105414.5 107889.5 107932.0 59798.1 88014.0 88908.6 92035.0 93851.0 10706.5 10776.6 10776.9 10916.1 10989.8 50720.7 51176.7 51615.5 52786.0 53026.7 74592.0 78475.2 80093.0 80835.0 82584.2 13643.2 13654.2 13788.4 13845.9 14331.2 14883.7 15833.8 15933.0 16107.4 16994.3 14946.5 15080.1 15086.8 15318.4 15355.8 15727.2 15955.8 16097.8 16272.4 17593.6 15426.5 15864.2 16430.4 16938.5 16941.8 11832.7 12029.8 12464.2 12679.8 13138.8 11037.5 12822.5 12840.1 13082.7 13184.3 22:24:23 INFO - PROCESS | 3466 | -------- Summary: end -------- 22:24:23 INFO - PROCESS | 3466 | 22:24:24 INFO - TEST-INFO | 3466: exit 0 22:24:24 INFO - TEST-OK | dromaeo_css | took 1112898ms 22:24:24 INFO - TEST-START | kraken 22:24:24 INFO - operating with platform_type : linux_ 22:24:24 INFO - Initialising browser for kraken test... 22:24:24 INFO - TEST-INFO | started process 3773 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphBuINY/profile http://localhost:40021/getInfo.html) 22:24:28 INFO - PROCESS | 3773 | __metrics Screen width/height:1600/1200 22:24:28 INFO - PROCESS | 3773 | colorDepth:24 22:24:28 INFO - PROCESS | 3773 | Browser inner width/height: 1024/697 22:24:28 INFO - PROCESS | 3773 | __metrics 22:24:28 INFO - PROCESS | 3773 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:24:28 INFO - PROCESS | 3773 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:24:29 INFO - PROCESS | 3773 | console.error: PushService: 22:24:29 INFO - PROCESS | 3773 | stateChangeProcessEnqueue: Error transitioning state 22:24:29 INFO - PROCESS | 3773 | UnknownError 22:24:29 INFO - TEST-INFO | 3773: exit 0 22:24:29 INFO - Browser initialized. 22:24:29 INFO - Running cycle 1/1 for kraken test... 22:24:29 INFO - TEST-INFO | started process 3868 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphBuINY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:24:41 INFO - PROCESS | 3868 | RSS: Main: 143548416 22:24:41 INFO - PROCESS | 3868 | 22:24:43 INFO - PROCESS | 3868 | Cycle 1(1): loaded http://localhost:40021/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40021/tests/kraken/driver.html?testName=audio-beat-detection) 22:24:44 INFO - PROCESS | 3868 | RSS: Main: 186929152 22:24:44 INFO - PROCESS | 3868 | 22:24:47 INFO - PROCESS | 3868 | Cycle 1(1): loaded http://localhost:40021/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40021/tests/kraken/driver.html?testName=audio-dft) 22:24:47 INFO - PROCESS | 3868 | RSS: Main: 174198784 22:24:47 INFO - PROCESS | 3868 | 22:24:50 INFO - PROCESS | 3868 | Cycle 1(1): loaded http://localhost:40021/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40021/tests/kraken/driver.html?testName=audio-fft) 22:24:51 INFO - PROCESS | 3868 | RSS: Main: 265908224 22:24:51 INFO - PROCESS | 3868 | 22:24:53 INFO - PROCESS | 3868 | Cycle 1(1): loaded http://localhost:40021/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40021/tests/kraken/driver.html?testName=audio-oscillator) 22:24:53 INFO - PROCESS | 3868 | RSS: Main: 172535808 22:24:53 INFO - PROCESS | 3868 | 22:24:55 INFO - PROCESS | 3868 | Cycle 1(1): loaded http://localhost:40021/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40021/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:24:56 INFO - PROCESS | 3868 | RSS: Main: 174002176 22:24:56 INFO - PROCESS | 3868 | 22:24:59 INFO - PROCESS | 3868 | Cycle 1(1): loaded http://localhost:40021/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40021/tests/kraken/driver.html?testName=imaging-darkroom) 22:25:00 INFO - PROCESS | 3868 | RSS: Main: 209645568 22:25:00 INFO - PROCESS | 3868 | 22:25:03 INFO - PROCESS | 3868 | Cycle 1(1): loaded http://localhost:40021/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40021/tests/kraken/driver.html?testName=imaging-desaturate) 22:25:03 INFO - PROCESS | 3868 | RSS: Main: 208502784 22:25:03 INFO - PROCESS | 3868 | 22:25:06 INFO - PROCESS | 3868 | Cycle 1(1): loaded http://localhost:40021/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40021/tests/kraken/driver.html?testName=json-parse-financial) 22:25:07 INFO - PROCESS | 3868 | RSS: Main: 208715776 22:25:07 INFO - PROCESS | 3868 | 22:25:08 INFO - PROCESS | 3868 | Cycle 1(1): loaded http://localhost:40021/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40021/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:25:09 INFO - PROCESS | 3868 | RSS: Main: 170856448 22:25:09 INFO - PROCESS | 3868 | 22:25:11 INFO - PROCESS | 3868 | Cycle 1(1): loaded http://localhost:40021/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40021/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:25:11 INFO - PROCESS | 3868 | RSS: Main: 177532928 22:25:11 INFO - PROCESS | 3868 | 22:25:13 INFO - PROCESS | 3868 | Cycle 1(1): loaded http://localhost:40021/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40021/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:25:14 INFO - PROCESS | 3868 | RSS: Main: 187228160 22:25:14 INFO - PROCESS | 3868 | 22:25:16 INFO - PROCESS | 3868 | Cycle 1(1): loaded http://localhost:40021/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40021/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:25:17 INFO - PROCESS | 3868 | RSS: Main: 197120000 22:25:17 INFO - PROCESS | 3868 | 22:25:20 INFO - PROCESS | 3868 | Cycle 1(1): loaded http://localhost:40021/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40021/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:25:20 INFO - PROCESS | 3868 | RSS: Main: 192389120 22:25:20 INFO - PROCESS | 3868 | 22:25:22 INFO - PROCESS | 3868 | Cycle 1(1): loaded http://localhost:40021/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40021/tests/kraken/driver.html?testName=ai-astar) 22:25:22 INFO - PROCESS | 3868 | RSS: Main: 243933184 22:25:22 INFO - PROCESS | 3868 | 22:25:22 INFO - PROCESS | 3868 | __start_tp_report 22:25:22 INFO - PROCESS | 3868 | _x_x_mozilla_page_load 22:25:22 INFO - PROCESS | 3868 | _x_x_mozilla_page_load_details 22:25:22 INFO - PROCESS | 3868 | |i|pagename|runs| 22:25:22 INFO - PROCESS | 3868 | |0;ai-astar;110;110;112;112;111;113;109;109;112;110 22:25:22 INFO - PROCESS | 3868 | |1;audio-beat-detection;147;119;117;147;117;118;116;118;141;119 22:25:22 INFO - PROCESS | 3868 | |2;audio-dft;161;162;161;160;160;162;162;161;161;159 22:25:22 INFO - PROCESS | 3868 | |3;audio-fft;74;75;76;106;76;76;76;75;99;77 22:25:22 INFO - PROCESS | 3868 | |4;audio-oscillator;81;81;124;83;81;81;101;82;82;116 22:25:22 INFO - PROCESS | 3868 | |5;imaging-gaussian-blur;116;117;117;117;117;116;117;118;116;117 22:25:22 INFO - PROCESS | 3868 | |6;imaging-darkroom;145;146;146;146;145;146;145;147;148;145 22:25:22 INFO - PROCESS | 3868 | |7;imaging-desaturate;95;95;95;95;95;95;95;94;94;95 22:25:22 INFO - PROCESS | 3868 | |8;json-parse-financial;49;50;52;49;49;55;50;49;49;51 22:25:22 INFO - PROCESS | 3868 | |9;json-stringify-tinderbox;57;58;57;57;58;58;57;57;62;58 22:25:22 INFO - PROCESS | 3868 | |10;stanford-crypto-aes;72;73;80;74;75;78;73;72;74;74 22:25:22 INFO - PROCESS | 3868 | |11;stanford-crypto-ccm;136;125;127;123;118;117;121;121;159;123 22:25:22 INFO - PROCESS | 3868 | |12;stanford-crypto-pbkdf2;154;156;159;155;155;155;155;158;153;155 22:25:22 INFO - PROCESS | 3868 | |13;stanford-crypto-sha256-iterative;68;63;65;62;63;63;61;63;63;62 22:25:22 INFO - PROCESS | 3868 | __end_tp_report 22:25:22 INFO - PROCESS | 3868 | __start_cc_report 22:25:22 INFO - PROCESS | 3868 | _x_x_mozilla_cycle_collect,1594 22:25:22 INFO - PROCESS | 3868 | __end_cc_report 22:25:22 INFO - PROCESS | 3868 | __startTimestamp1484547922494__endTimestamp 22:25:22 INFO - PROCESS | 3868 | 22:25:22 INFO - PROCESS | 3868 | ------- Summary: start ------- 22:25:22 INFO - PROCESS | 3868 | Number of tests: 14 22:25:22 INFO - PROCESS | 3868 | 22:25:22 INFO - PROCESS | 3868 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.50 stddev:1.40 (1.3%) stddev-sans-first:1.45 22:25:22 INFO - PROCESS | 3868 | Values: 110.0 110.0 112.0 112.0 111.0 113.0 109.0 109.0 112.0 110.0 22:25:22 INFO - PROCESS | 3868 | 22:25:22 INFO - PROCESS | 3868 | [#1] audio-beat-detection Cycles:10 Average:125.90 Median:119.00 stddev:13.31 (11.2%) stddev-sans-first:11.73 22:25:22 INFO - PROCESS | 3868 | Values: 147.0 119.0 117.0 147.0 117.0 118.0 116.0 118.0 141.0 119.0 22:25:22 INFO - PROCESS | 3868 | 22:25:22 INFO - PROCESS | 3868 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 22:25:22 INFO - PROCESS | 3868 | Values: 161.0 162.0 161.0 160.0 160.0 162.0 162.0 161.0 161.0 159.0 22:25:22 INFO - PROCESS | 3868 | 22:25:22 INFO - PROCESS | 3868 | [#3] audio-fft Cycles:10 Average:81.00 Median:76.00 stddev:11.48 (15.1%) stddev-sans-first:11.89 22:25:22 INFO - PROCESS | 3868 | Values: 74.0 75.0 76.0 106.0 76.0 76.0 76.0 75.0 99.0 77.0 22:25:22 INFO - PROCESS | 3868 | 22:25:22 INFO - PROCESS | 3868 | [#4] audio-oscillator Cycles:10 Average:91.20 Median:81.00 stddev:16.46 (20.3%) stddev-sans-first:17.04 22:25:22 INFO - PROCESS | 3868 | Values: 81.0 81.0 124.0 83.0 81.0 81.0 101.0 82.0 82.0 116.0 22:25:22 INFO - PROCESS | 3868 | 22:25:22 INFO - PROCESS | 3868 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.60 22:25:22 INFO - PROCESS | 3868 | Values: 116.0 117.0 117.0 117.0 117.0 116.0 117.0 118.0 116.0 117.0 22:25:22 INFO - PROCESS | 3868 | 22:25:22 INFO - PROCESS | 3868 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.99 (0.7%) stddev-sans-first:1.00 22:25:22 INFO - PROCESS | 3868 | Values: 145.0 146.0 146.0 146.0 145.0 146.0 145.0 147.0 148.0 145.0 22:25:22 INFO - PROCESS | 3868 | 22:25:22 INFO - PROCESS | 3868 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:25:22 INFO - PROCESS | 3868 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 94.0 95.0 22:25:22 INFO - PROCESS | 3868 | 22:25:22 INFO - PROCESS | 3868 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.00 stddev:1.95 (3.9%) stddev-sans-first:2.01 22:25:22 INFO - PROCESS | 3868 | Values: 49.0 50.0 52.0 49.0 49.0 55.0 50.0 49.0 49.0 51.0 22:25:22 INFO - PROCESS | 3868 | 22:25:22 INFO - PROCESS | 3868 | [#9] json-stringify-tinderbox Cycles:10 Average:57.90 Median:58.00 stddev:1.52 (2.6%) stddev-sans-first:1.58 22:25:22 INFO - PROCESS | 3868 | Values: 57.0 58.0 57.0 57.0 58.0 58.0 57.0 57.0 62.0 58.0 22:25:22 INFO - PROCESS | 3868 | 22:25:22 INFO - PROCESS | 3868 | [#10] stanford-crypto-aes Cycles:10 Average:74.50 Median:74.00 stddev:2.59 (3.5%) stddev-sans-first:2.59 22:25:22 INFO - PROCESS | 3868 | Values: 72.0 73.0 80.0 74.0 75.0 78.0 73.0 72.0 74.0 74.0 22:25:22 INFO - PROCESS | 3868 | 22:25:22 INFO - PROCESS | 3868 | [#11] stanford-crypto-ccm Cycles:10 Average:127.00 Median:124.00 stddev:12.45 (10.0%) stddev-sans-first:12.77 22:25:22 INFO - PROCESS | 3868 | Values: 136.0 125.0 127.0 123.0 118.0 117.0 121.0 121.0 159.0 123.0 22:25:22 INFO - PROCESS | 3868 | 22:25:22 INFO - PROCESS | 3868 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.50 Median:155.00 stddev:1.78 (1.1%) stddev-sans-first:1.80 22:25:22 INFO - PROCESS | 3868 | Values: 154.0 156.0 159.0 155.0 155.0 155.0 155.0 158.0 153.0 155.0 22:25:22 INFO - PROCESS | 3868 | 22:25:22 INFO - PROCESS | 3868 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:63.00 stddev:1.95 (3.1%) stddev-sans-first:1.09 22:25:22 INFO - PROCESS | 3868 | Values: 68.0 63.0 65.0 62.0 63.0 63.0 61.0 63.0 63.0 62.0 22:25:22 INFO - PROCESS | 3868 | -------- Summary: end -------- 22:25:22 INFO - PROCESS | 3868 | 22:25:23 INFO - TEST-INFO | 3868: exit 0 22:25:23 INFO - TEST-OK | kraken | took 59116ms 22:25:23 INFO - SUITE-END | took 1172s 22:25:23 INFO - Completed test suite (00:19:32) 22:25:23 INFO - javascript benchmark 22:25:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3117.1895307220125, 3130.0296907958527, 3160.652328243893, 3170.080676603785, 3198.3123628419885, 5579.302587176598, 5641.323041235052, 5644.633098848582, 5678.375378708339, 5695.544356451484, 5641.5204707599905, 5686.3744988051285, 5690.345610254822, 5696.458836410545, 5727.599068065237, 5140.537351638342, 5182.533571978521, 5205.9219111713355, 5222.764975576109, 5271.710055946922, 5063.873403164914, 5260.237265596486, 5264.236685680574, 5273.080921893735, 5301.602379821509, 5046.470120637336, 5086.92369614456, 5117.48825117489, 5145.922811157836, 5147.742612869341, 5146.356705411824, 5160.8709782255555, 5175.378954525443, 5237.0, 5237.711925844077, 5091.0072535855525, 5125.538701516844, 5197.66215196011, 5208.661437006583, 5241.73791310433, 5410.188471729272, 5451.373092094381, 5537.335519737617, 5563.13771365439, 5620.5503559715125, 5996.340402555716, 6005.1892994445625, 6101.969490152521, 6115.724792384353, 6125.785597504066, 6129.632222066645, 6142.232220972379, 6147.600405973598, 6149.754009839601, 6175.536834737412, 5440.0, 5445.455454454563, 5450.482204190595, 5564.805231816867, 5589.8602534936135, 6189.319174890758, 6226.003839385677, 6227.252729672468, 6233.470155036782, 6234.096056071935, 5968.641881487125, 5970.76116955317, 5978.013627751459, 5978.970105149447, 5979.252593425822, 5827.65034097947, 5906.675132867647, 5921.230240068763, 5974.551908606785, 5986.341502434729, 5983.790567330123, 5991.850203744942, 6009.879802403927, 6014.398560143951, 6041.24484439445, 5951.702414879239, 6009.729562169713, 6011.5190784737115, 6021.849453763603, 6031.125486804389, 4507.977460112678, 4551.0, 4560.521145279764, 4598.701084429568, 4631.51369106239, 4072.816723247461, 4098.569650186747, 4124.216398884202, 4142.378643203483, 4147.294959856771, 5861.062230043172, 5864.354920958691, 5865.35481097079, 5865.472107510344, 5869.119632055226, 5900.350961394243, 5956.970215148896, 6009.098635204667, 6175.2589689237575, 6179.567430279837, 5644.604877658525, 5861.882762344814, 5862.8534286642325, 5873.177755114288, 5896.852578685567, 5213.7393130343335, 5247.370315562157, 5257.237700533401, 5259.0, 5259.47405259471, 6016.819495415145, 6036.275646922221, 6214.4407003369915, 6270.2475702915945, 6275.152854364694, 6029.095635654687, 6071.423214794588, 6095.116208149705, 6142.389484469172, 6146.754129834757, 6300.0234963580715, 6302.842428939313, 6306.369363063657, 6307.716152773044, 6308.589941653778, 5758.913616295675, 6003.939960600338, 6004.159417681441, 6005.729742161526, 6008.669523176267, 5825.417458254141, 5995.46040856325, 6017.097435384728, 6050.90923636137, 6140.074827791203, 5968.4031596841705, 6015.72929218195, 6018.789342372996, 6022.427869352405, 6048.4858787003595, 5946.07835785454, 5982.042873140276, 5989.101634754822, 5995.100734889801, 6000.819975400745, 5615.634983726045, 5723.484886360247, 5783.7397317119985, 5800.0, 5839.503642190291, 5933.258342707161, 5934.881302374015, 5945.227120474311, 5962.9105563415715, 6003.0, 5700.743466543932, 5807.796727114531, 5812.651240925558, 5813.09896965971, 5829.59192856496], "value": 5574.110109997129, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.943480369365, 618.1500436899264, 641.9935800641993, 650.4146268358478, 654.090813768861, 34922.82538587307, 37509.249815003684, 44156.558434415645, 45434.77282613591, 45528.3170752439, 46590.534094659044, 46981.530184698226, 47023.76488117563, 47052.29421558679, 47084.0, 8331.12523185066, 9261.120193581615, 9385.108414700593, 9389.530523473799, 9394.530273486333, 8336.791580210482, 8617.181367770052, 10891.67324980252, 11141.554337826517, 11146.164037697165, 11183.944080279587, 11245.55017799287, 11247.0, 11251.43742812856, 11258.211925165242, 11026.117910567176, 11103.777924441547, 11104.389258590774, 11108.222424430292, 11121.221514493947, 10614.416207108607, 10665.626703065433, 10665.84001239982, 10668.839967400496, 10675.199360047949, 44365.33451998223, 44373.55626443759, 44622.55377446216, 44701.32948005782, 44723.77638111821, 3503.2818272254126, 3546.5744110706623, 3862.9613703862883, 4102.446169767103, 4423.248047831877, 34377.886592306226, 34397.363745545685, 34611.82694086539, 35126.473102903474, 35138.6486135138, 4602.792874320664, 4662.254039353687, 4737.407824021997, 4737.976310118462, 4838.298446725205, 264348.0, 265130.0, 265449.67275163694, 265623.0, 266123.0, 61845.07232391518, 62030.0, 62208.0, 62276.37723622796, 62284.68857655773, 36902.261954760754, 38530.2293954125, 38554.80722596425, 40582.0, 66411.66794166095, 17432.477025688997, 17642.823571764377, 17651.646967060587, 17655.558611034827, 17659.470215893285, 11326.433678316216, 11516.1362897783, 13176.934115329363, 13236.07347485686, 13246.337683115806, 18531.351402700842, 18661.440156795317, 19538.865221338754, 20994.580108397746, 21037.84291863962, 7263.527870688388, 7582.128055273714, 7589.468737188454, 7649.541027538366, 7693.576853273124, 901.6123067081087, 975.4196253229317, 1071.4482041748506, 1205.1323047405854, 1214.7548762518313, 33601.83199083989, 35166.648333516336, 35569.822150889595, 36688.899333020054, 36790.8160459196, 1077.628218264713, 1087.152021423291, 1099.7030801683661, 1134.2910680824484, 1204.4278967490548, 15268.31292591836, 17606.735898961524, 17706.645867082843, 18737.156827942774, 19303.131359088875, 482885.0, 486970.0, 488432.0, 488526.0, 491149.0, 57416.71291643515, 57589.42410575841, 57655.71172144113, 57666.7116664414, 57728.71135644379, 1242.3850194153772, 1284.9550265740654, 1355.7424089422977, 1359.4426285223208, 1364.7065880835669, 31974.840125799223, 33670.831645841616, 33839.3232135351, 34129.31741365109, 34493.82753086218, 51529.0, 58251.0, 59793.402065978786, 59806.40193598008, 64618.67690661517, 521131.0, 523361.0, 524674.0, 524827.0, 528055.0, 39174.60825391824, 39873.0, 43468.347974781034, 43745.0, 44903.0, 50459.74770126126, 50753.746231268604, 51184.23223651574, 51320.230196547825, 51547.0, 42570.14859702851, 42784.35823462713, 42802.57197427986, 43232.35151472793, 44312.7784361089, 42759.572404276805, 43737.0, 44274.778626106665, 44482.332765009196, 44902.77548612236, 43462.56537434712, 44202.778986104866, 44843.0, 44899.0, 45092.54907451015, 35774.0, 38786.22427551377, 38892.80553597327, 39048.41427378535, 39667.40498892461, 29228.707712922598, 29326.853365733034, 29420.55869162007, 29480.55779163357, 29751.70248297489], "value": 21256.550801118527, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.7262020756197, 416.98749037528864, 424.44185895547355, 431.47791172681065, 449.40678304637873, 19043.0, 19404.126814293337, 20759.481012974684, 20944.16223351068, 20962.37112886614, 19610.70583941242, 19889.900550497263, 20656.586868262624, 20714.68927966078, 20927.790722092774, 5662.688552129642, 5924.614900031505, 5935.525157987372, 5936.64380137191, 5939.109133629947, 5660.010470421056, 5731.971340143315, 5771.163181338703, 5807.2450581424355, 5849.356570777211, 5804.622699524517, 5820.330661973885, 5821.359650438449, 5821.621594596338, 5821.6507009579345, 5681.119426488901, 5762.078067509199, 5810.5932584719085, 5824.592278540504, 5852.473277405012, 5306.8407947761625, 5316.840494785162, 5324.653897496651, 5325.0148722486465, 5328.20076988451, 23949.521009579712, 23959.88020059906, 24135.15526956548, 24572.877135614206, 24778.50442991148, 2357.634566642173, 2371.537550177716, 2396.8681722505257, 2467.605183170702, 3192.696693814084, 10363.274570780048, 10619.41593212373, 10857.891421085846, 10903.454827258685, 10981.560737570568, 1964.3321270768008, 2080.3446914222122, 2082.1671331467533, 2107.5363420047565, 2117.195465723031, 56814.43185568174, 68123.0, 73889.63055184744, 84367.15632843654, 84796.57601712014, 11176.329420234812, 11507.136964727679, 11784.526934133233, 12190.0, 12217.0, 17303.221355039055, 17309.134543272787, 17339.91330043367, 17341.7398739019, 17349.7397539037, 8577.05652378238, 8635.136486351315, 8918.1973622373, 8967.820643587222, 9058.139476749699, 7543.585102819288, 7637.618119094023, 7912.881306780403, 8154.429189956646, 8190.426670133033, 5071.87320316995, 5177.896442071137, 5207.661502002434, 5232.529072383504, 5287.180487024518, 2046.079264331057, 2151.7310336207975, 2157.4498502881665, 2176.5320456102013, 2190.1567896359697, 908.4503875155435, 950.6150009246267, 969.970900872975, 994.7761753605381, 1100.372787511127, 20090.0, 20235.696464552748, 20245.08897099634, 20364.18543258253, 20424.795752042588, 966.7196513011288, 971.7910649210454, 993.8857031441332, 1047.7537778622018, 1050.9474526273655, 10816.134709223243, 11289.0, 11331.773364532828, 11337.716557086138, 11437.768025830495, 419990.0, 428476.0, 466410.3345134564, 473672.0, 479609.0, 58267.70866145642, 71736.64131679309, 91463.54268228749, 93911.53044234736, 94166.52916735373, 1008.4755926918108, 1060.538665680416, 1077.5151181968147, 1090.0571006079683, 1096.2874131814226, 17654.646907062044, 17960.461186164437, 18053.548661283574, 18220.90889545544, 18556.979366134452, 41754.37368439415, 42106.789466052476, 42392.788036060854, 42616.0, 42752.57247427486, 55989.44010559842, 60306.39693603183, 60907.39092609017, 61971.380286198364, 63027.0, 8678.869816952625, 8763.25512331459, 8803.735887923373, 9040.864387034331, 9210.171084602418, 9648.372855764354, 12191.146619736533, 12905.09664323526, 13114.344282785632, 13181.670458238237, 10557.63048293306, 10978.341299522055, 10995.34027958325, 11200.607978720705, 11311.434428278388, 10947.0, 11283.435828208392, 11336.433178341214, 11617.6514704559, 11649.941750291533, 10413.739656508647, 11125.165612578927, 11172.273802202832, 11176.497057632263, 11547.538098476303, 7551.169371369255, 7773.92226077732, 7919.247671471201, 8130.902328185856, 9159.49622770754, 10733.463326833784, 10734.624288149877, 10775.461226938465, 10845.566177352817, 11220.438978051228], "value": 9366.791670908893, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1391.5129704603385, 1524.8856335774817, 1541.0907564536922, 1657.9917100414496, 1808.9819101808987, 8135.430519863612, 9202.0, 9618.038196180378, 9622.951885240582, 9740.366876153063, 9544.0, 9569.904300956989, 9597.136257736802, 9613.0, 9640.85538716918, 2485.8259921805434, 2511.1211076123395, 2513.8114641401876, 2514.585093459577, 2514.924552263435, 2423.9394015149674, 2539.4032402385424, 2539.6825396825398, 2542.1356738708837, 2543.834650747705, 2500.737422570631, 2519.6220566915017, 2519.8110141739353, 2520.6723125993594, 2521.2436269119285, 2373.347329484397, 2415.830891837572, 2424.866632335221, 2432.525657496789, 2440.1459489178824, 2369.099742098009, 2377.5839228135005, 2417.576924038304, 2426.235735743253, 2427.9271621851194, 9436.197923176538, 9640.517974101336, 9690.127888489998, 9707.854382184272, 9709.854352184722, 5707.800226992077, 5842.766289348421, 5847.912281315784, 5848.619839710405, 5850.853728656818, 3590.5152804371337, 3617.7829330240006, 3655.9086022849374, 3662.633736626343, 3676.522052133232, 1116.2967330581682, 1138.9259698119595, 1146.266389510714, 1157.8379026936236, 1161.3322339654733, 13476.528321508798, 13510.662233444144, 13545.322733863366, 13557.118787278796, 13619.659508512366, 4821.445533763662, 4865.7323847188745, 4891.290762839369, 4893.192623217201, 5049.495050494921, 10515.211359148094, 10518.631847885288, 10524.31591946521, 10530.631427899987, 10534.104601108991, 5629.8592535186945, 5642.85892852682, 5663.66018038919, 5666.858328541737, 5670.688112153871, 4372.519022907478, 4422.535633758409, 4541.568550987651, 4584.862454126382, 5298.629095963245, 4046.907335019528, 4097.7746223957965, 4169.791510424528, 4186.832526698898, 4189.392538081961, 1254.0406588959454, 1262.103906226571, 1269.695273134441, 1278.1564167649492, 1281.6027031620042, 996.1582462818907, 997.7904640025531, 1002.2983911262, 1006.436395618456, 1033.0805583031108, 6120.969395152996, 6181.262830111991, 6264.436200741955, 6392.552521323462, 6559.8360040998405, 502.7436007636132, 503.07183246910125, 505.6031015652695, 512.2955935588566, 512.5848063068926, 4304.655627549789, 4344.587264209895, 4345.804438800198, 4408.603225709701, 4442.422485076984, 11822.290662560274, 12399.194052386747, 12426.62720118398, 12429.254244745343, 12434.253944763163, 11591.942040289914, 12139.878601213875, 12158.452869620889, 12579.308138052495, 12602.432890519949, 8870.64517419296, 9032.909670903207, 9042.547872606343, 9056.637734490547, 9088.091190880992, 6319.96840015797, 6477.319881412573, 6552.803415897531, 6741.662916854236, 6909.170899492093, 10958.01377875995, 11223.943880280873, 11255.831162532404, 11362.091032717197, 11452.083833293484, 9596.99231580674, 10227.642032528825, 11324.490397932277, 11325.546978120783, 11457.140714446616, 8270.37972152103, 8426.325893928593, 8640.82718345617, 8640.913590864011, 8643.135686431306, 9159.587818548314, 9162.312826537904, 9178.173964343241, 9247.0752924707, 9270.72187834366, 8326.750197494084, 8374.2881855043, 8382.916170838458, 8532.360072994426, 8620.61207245663, 8132.512049277062, 8133.8373232536205, 8272.83454330922, 8309.459885107448, 8489.575521223791, 7262.745803896838, 7483.88774168377, 7505.962470187614, 7544.0, 7547.811304717426, 5316.122839731401, 5358.571314294925, 5366.221897824872, 5377.354717433933, 5485.204645326326, 5377.919331210113, 5487.945120548743, 5523.613347065667, 5552.888942221052, 5553.08374118266], "value": 4865.079423433789, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.7600455685578, 361.28105070908884, 362.4671732553148, 364.43330620884524, 366.84042441537935, 5408.837734867955, 5444.482774136456, 5496.4503549645015, 5505.779768809243, 5539.390667026624, 5094.6433749637545, 5354.625176237665, 5384.0, 5430.945690543093, 5432.483914028171, 3834.48234488344, 4223.345381465862, 4341.869743907688, 4349.282368409209, 4360.781960901958, 4349.217140914634, 4352.847650332239, 4353.586409291112, 4361.476622805252, 4364.192624364503, 4351.934720979188, 4361.847335343264, 4404.801783919731, 4416.58042485965, 4434.0, 4205.401947260037, 4330.263855144602, 4374.168907907488, 4381.846635367747, 4407.757573333465, 3860.6332398422105, 3882.417637354391, 3891.7081218908697, 3901.1807520420743, 3912.7456715313697, 5328.866778330534, 5339.893202135974, 5340.946590534084, 5399.811006614789, 5403.945960540384, 4667.579917807379, 4923.75381230937, 4955.306257124005, 4999.975000124976, 5026.924596131061, 5685.829425117253, 5699.68651724155, 5704.600677952545, 5737.39757325479, 5764.855878603068, 4197.433346498215, 4224.767637779921, 4228.154369126187, 4231.576842315774, 4237.660987121054, 5867.12510869675, 5969.641821490724, 6007.038873780218, 6049.758009679652, 6064.149849570695, 4310.6335961443, 4313.0, 4317.676174286878, 4332.566743325642, 4350.260455722535, 4401.2297847877135, 4405.7797110144365, 4407.515173330931, 4412.808541693725, 4414.492333381702, 4703.341532185496, 4862.0, 4896.779644915988, 4985.401751789808, 5004.924926126111, 5733.426657334233, 5757.424257574209, 5758.88482230353, 5761.308642962871, 5769.461436967721, 1495.588713103905, 1521.73369660309, 1523.878089752817, 1531.8238402583845, 1537.108477083289, 981.5926390547843, 985.5072463768116, 1003.6386900715737, 1025.2003437318904, 1039.6744151207272, 5542.861428464321, 5563.276774019432, 5567.888642227133, 5574.637648552831, 5591.6365436246515, 5862.296524417097, 5866.677332746654, 5878.412158784087, 5888.587798854039, 5892.322382926019, 5263.157894736823, 5460.453954604587, 5531.838313954035, 5547.1679248113105, 5587.658961849127, 3105.4720697481484, 3127.358891427252, 3132.6397464291445, 3135.8588863501195, 3137.905862824119, 5809.331926828384, 5999.520038396918, 6004.819855404345, 6024.9214021162625, 6046.94629212245, 5748.568857335633, 5885.293764748258, 5920.881582368328, 5956.791512297049, 5973.850653733692, 5846.619969702041, 5934.1692163097205, 6016.518678505709, 6058.666773327421, 6064.696765161724, 5913.319968203712, 5923.289205295392, 5936.8812623748145, 5972.910406343822, 5975.940240597538, 5729.570282228933, 5747.706765977621, 5789.985779507455, 5794.652320860761, 5832.591718579659, 6039.728212230372, 6061.272647282354, 6065.272167339771, 6065.363136870565, 6133.815985520441, 4182.895427614334, 4318.546552612056, 4325.2430824604935, 4346.195953748566, 4363.47638283408, 5862.67755273447, 5867.474456641218, 5888.17565540816, 5892.35184129754, 5908.261467316585, 5756.136579512939, 5800.7099645018425, 5818.418158184148, 5843.211166492469, 5883.911741323798, 5477.97314993455, 5725.454127385499, 5767.0, 5793.217915581343, 5820.447057529528, 5744.310682717934, 5771.365149833431, 5774.7690092397515, 5787.565932554991, 5789.334226563999, 5609.467100625434, 5837.22731702438, 5843.590948633555, 5912.260967379078, 5947.078202878561, 5635.746391412478, 5657.773689052392, 5678.574106942078, 5713.885722285448, 5716.142578613241], "value": 4425.773931268589, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [789.5144486141025, 802.5064585280054, 816.9468984516006, 825.2531459029578, 857.6097875466661, 16688.582785430353, 17115.40096096635, 17723.73414398785, 17823.465296041122, 17906.37327693531, 16348.0, 16428.425005124795, 16487.83512164878, 16502.75245871313, 16508.422205222818, 733.2337707096098, 752.1801236652049, 755.9962200189019, 882.7969566999578, 897.443584977315, 750.2385079144673, 862.2412277196058, 888.7822483491522, 889.4574309671095, 897.6588896219428, 933.2254228989922, 934.2152591822868, 935.5322338830584, 935.5696379665362, 935.8642996765465, 931.8648795924621, 932.9766755831124, 933.4399360383783, 934.2479304160148, 934.5047125023748, 684.8082536889679, 685.2633419074515, 685.8388278754488, 685.869684759894, 685.917689877218, 14760.26198690072, 14897.255137243095, 14958.252087395695, 14963.925180374137, 14974.850251497563, 16627.522312282003, 16778.785122397363, 16969.0, 17237.39669111575, 17631.823681763148, 11881.108916831272, 11896.286222826571, 11918.880811191864, 12027.699307517294, 12432.937835310857, 3983.143624120828, 4075.409065685459, 4113.8971525711795, 4129.236091323115, 4214.304639734441, 82941.0, 86254.56872715596, 87753.99999999936, 87794.56102719446, 87975.12024879798, 12476.625701228977, 12494.250344979331, 12496.437660305126, 12723.491060357483, 12903.741925161445, 22934.541309174056, 22953.311400658007, 22969.310920672408, 23151.305460836204, 25556.74443255581, 8872.645094196288, 9070.229030532346, 9142.497162655985, 9170.266378689688, 9441.197498212729, 4956.900861982812, 5036.521530454601, 5068.721220332917, 5185.248139019821, 5271.525562699376, 3379.847906844198, 3476.66971637694, 3492.1793378556017, 3501.072215862798, 3506.50908872758, 634.238913304037, 637.9744810207633, 641.2689533931322, 643.9871202575922, 657.7927952694886, 791.3194652598761, 796.3231253434534, 817.7097130518699, 823.3372135431035, 823.6499487717696, 10690.305130166515, 10738.0, 11066.31957357134, 11394.715132121597, 11448.484818183202, 724.2069933422894, 829.7054545636333, 831.5509624802539, 832.3757182113415, 838.1032295443938, 5453.672779633234, 5472.179173123984, 5527.917081243784, 5573.972130139324, 5608.66348019112, 121118.0, 122873.0, 125339.3733031329, 125986.37006814907, 129420.35289823679, 90765.0, 92235.0, 105414.47292763641, 107889.4605526983, 107932.0, 59798.10302845461, 88014.0, 88908.5554572236, 92035.0, 93851.0, 10706.464676766287, 10776.622818201326, 10776.892231077902, 10916.12670986335, 10989.780204396027, 50720.74639626778, 51176.74411627918, 51615.48384516257, 52786.0, 53026.73486632542, 74592.0, 78475.21524784679, 80093.0, 80835.0, 82584.17415826005, 13643.181409115485, 13654.180749155083, 13788.379522921694, 13845.861541384456, 14331.211783352015, 14883.702325953636, 15833.841661583238, 15933.0, 16107.355705771637, 16994.320227190772, 14946.476873309382, 15080.095194288378, 15086.773698394753, 15318.38726450974, 15355.769663454834, 15727.213639317759, 15955.840441595436, 16097.75853362177, 16272.43046493367, 17593.560160995567, 15426.537203883901, 15864.206789660702, 16430.424935127212, 16938.49184524466, 16941.830581694026, 11832.70418239551, 12029.819552706891, 12464.189827661174, 12679.80980285278, 13138.802917956047, 11037.503312351124, 12822.487100516248, 12840.145367650644, 13082.672933176747, 13184.274864882524], "value": 8223.670822350066, "unit": "score"}], "name": "dromaeo_css", "value": 7625.0238670081935, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 112.0, 112.0, 111.0, 113.0, 109.0, 109.0, 112.0, 110.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 119.0, 117.0, 147.0, 117.0, 118.0, 116.0, 118.0, 141.0, 119.0], "value": 125.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 160.0, 160.0, 162.0, 162.0, 161.0, 161.0, 159.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 106.0, 76.0, 76.0, 76.0, 75.0, 99.0, 77.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 124.0, 83.0, 81.0, 81.0, 101.0, 82.0, 82.0, 116.0], "value": 91.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 118.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 147.0, 148.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 52.0, 49.0, 49.0, 55.0, 50.0, 49.0, 49.0, 51.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 57.0, 57.0, 58.0, 58.0, 57.0, 57.0, 62.0, 58.0], "value": 57.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 80.0, 74.0, 75.0, 78.0, 73.0, 72.0, 74.0, 74.0], "value": 74.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 125.0, 127.0, 123.0, 118.0, 117.0, 121.0, 121.0, 159.0, 123.0], "value": 127.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 156.0, 159.0, 155.0, 155.0, 155.0, 155.0, 158.0, 153.0, 155.0], "value": 155.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 63.0, 65.0, 62.0, 63.0, 63.0, 61.0, 63.0, 63.0, 62.0], "value": 63.3, "unit": "score"}], "name": "kraken", "value": 1455.8, "alertThreshold": 2.0}]} 22:25:23 INFO - Return code: 0 22:25:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:25:23 INFO - # TBPL SUCCESS # 22:25:23 INFO - Running post-action listener: _resource_record_post_action 22:25:23 INFO - [mozharness: 2017-01-16 06:25:23.829886Z] Finished run-tests step (success) 22:25:23 INFO - Running post-run listener: _resource_record_post_run 22:25:23 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 320778240; Read time: 84; Write time: 131708 22:25:23 INFO - TinderboxPrint: CPU usage
12.9% 22:25:23 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 84 22:25:23 INFO - TinderboxPrint: I/O write bytes / time
320,778,240 / 131,708 22:25:23 INFO - TinderboxPrint: CPU idle
8,252.4 (87.1%) 22:25:23 INFO - TinderboxPrint: CPU user
1,182.3 (12.5%) 22:25:23 INFO - TinderboxPrint: Swap in / out
0 / 0 22:25:23 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 100429824; Read time: 0; Write time: 50728 22:25:23 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 219779072; Read time: 0; Write time: 80980 22:25:24 INFO - Running post-run listener: _upload_blobber_files 22:25:24 INFO - Blob upload gear active. 22:25:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:25:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:25:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:25:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:25:24 INFO - (blobuploader) - INFO - Open directory for files ... 22:25:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:25:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:25:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:25:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:25:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:25:28 INFO - (blobuploader) - INFO - Done attempting. 22:25:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:25:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:25:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:25:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:25:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:25:29 INFO - (blobuploader) - INFO - Done attempting. 22:25:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:25:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:25:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:25:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:25:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:25:30 INFO - (blobuploader) - INFO - Done attempting. 22:25:30 INFO - (blobuploader) - INFO - Iteration through files over. 22:25:30 INFO - Return code: 0 22:25:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:25:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:25:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e22b01feb3d2e861cca9385d39630d9bce0700599e1bb56511faa8d551ec4da4633bde89217ed5de8e4523b607b79978e923fb920ea41d94957d67e177554e76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8e61727ffec105abaf646c85bc2be102a8472929ac03fc1e9919327c0877c5a749e947c6f2fd1e8919500f1a2e3de12a79e238ec31a99f87d15726f021e2efbf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f752fe31c3e3de3ac873a46bd4b8b396eb606320204ddb7fca2e51596dd9ff8c1fcbf8de2068daa672db839e72c48620a1465fbec0c8c5ff5832373bd1a70678"} 22:25:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:25:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:25:30 INFO - Contents: 22:25:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e22b01feb3d2e861cca9385d39630d9bce0700599e1bb56511faa8d551ec4da4633bde89217ed5de8e4523b607b79978e923fb920ea41d94957d67e177554e76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8e61727ffec105abaf646c85bc2be102a8472929ac03fc1e9919327c0877c5a749e947c6f2fd1e8919500f1a2e3de12a79e238ec31a99f87d15726f021e2efbf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f752fe31c3e3de3ac873a46bd4b8b396eb606320204ddb7fca2e51596dd9ff8c1fcbf8de2068daa672db839e72c48620a1465fbec0c8c5ff5832373bd1a70678"} 22:25:30 INFO - Running post-run listener: copy_logs_to_upload_dir 22:25:30 INFO - Copying logs to upload dir... 22:25:30 INFO - mkdir: /builds/slave/test/build/upload/logs 22:25:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.088924 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2017-01-15 22:25:28.992904) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-15 22:25:28.997540) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e22b01feb3d2e861cca9385d39630d9bce0700599e1bb56511faa8d551ec4da4633bde89217ed5de8e4523b607b79978e923fb920ea41d94957d67e177554e76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8e61727ffec105abaf646c85bc2be102a8472929ac03fc1e9919327c0877c5a749e947c6f2fd1e8919500f1a2e3de12a79e238ec31a99f87d15726f021e2efbf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f752fe31c3e3de3ac873a46bd4b8b396eb606320204ddb7fca2e51596dd9ff8c1fcbf8de2068daa672db839e72c48620a1465fbec0c8c5ff5832373bd1a70678"} build_url:https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011317 build_url: 'https://queue.taskcluster.net/v1/task/P4IJG8TnRruOPvoSl7lasQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e22b01feb3d2e861cca9385d39630d9bce0700599e1bb56511faa8d551ec4da4633bde89217ed5de8e4523b607b79978e923fb920ea41d94957d67e177554e76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8e61727ffec105abaf646c85bc2be102a8472929ac03fc1e9919327c0877c5a749e947c6f2fd1e8919500f1a2e3de12a79e238ec31a99f87d15726f021e2efbf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f752fe31c3e3de3ac873a46bd4b8b396eb606320204ddb7fca2e51596dd9ff8c1fcbf8de2068daa672db839e72c48620a1465fbec0c8c5ff5832373bd1a70678"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-15 22:25:29.022168) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-15 22:25:29.022489) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=523ca41ca1e0623de333ad52000001bf-1484543141.217904-1271837497 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004501 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-15 22:25:29.104652) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-15 22:25:29.104958) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-15 22:25:29.105225) ========= ========= Total master_lag: 0.17 =========