builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-063 starttime: 1483410181.27 results: success (0) buildid: 20170102163019 builduid: 94efecb7cbde427f9e823b00a98900ac revision: 4284001e0ba8c1c1d02301f15ca596b55f971d37 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-02 18:23:01.267147) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-02 18:23:01.267620) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-02 18:23:01.267906) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-02 18:23:01.282596) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:23:01.282918) ========= 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=769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-02 18:23:04-- 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% 23.2M=0.001s 2017-01-02 18:23:04 (23.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137678 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:23:01.432824) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:23:01.433243) ========= 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=769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013076 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:23:01.483781) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:23:01.484149) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4284001e0ba8c1c1d02301f15ca596b55f971d37 --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 4284001e0ba8c1c1d02301f15ca596b55f971d37 --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=769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-02 18:23:04,671 truncating revision to first 12 chars 2017-01-02 18:23:04,671 Setting DEBUG logging. 2017-01-02 18:23:04,671 attempt 1/10 2017-01-02 18:23:04,671 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/4284001e0ba8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-02 18:23:04,970 unpacking tar archive at: mozilla-beta-4284001e0ba8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.643556 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:23:02.145090) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-02 18:23:02.145636) ========= script_repo_revision: 4284001e0ba8c1c1d02301f15ca596b55f971d37 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-02 18:23:02.146181) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-02 18:23:02.146465) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-02 18:23:02.156307) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2017-01-02 18:23:02.156691) ========= /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=769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:23:05 INFO - MultiFileLogger online at 20170102 18:23:05 in /builds/slave/test 18:23:05 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 18:23:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:23:05 INFO - {'append_to_log': False, 18:23:05 INFO - 'base_work_dir': '/builds/slave/test', 18:23:05 INFO - 'blob_upload_branch': 'Mozilla-Beta', 18:23:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:23:05 INFO - 'branch': 'Mozilla-Beta', 18:23:05 INFO - 'buildbot_json_path': 'buildprops.json', 18:23:05 INFO - 'config_files': ('talos/linux_config.py',), 18:23:05 INFO - 'default_actions': ('clobber', 18:23:05 INFO - 'read-buildbot-config', 18:23:05 INFO - 'download-and-extract', 18:23:05 INFO - 'populate-webroot', 18:23:05 INFO - 'create-virtualenv', 18:23:05 INFO - 'install', 18:23:05 INFO - 'run-tests'), 18:23:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:23:05 INFO - 'download_minidump_stackwalk': True, 18:23:05 INFO - 'download_symbols': 'ondemand', 18:23:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:23:05 INFO - 'tooltool.py': '/tools/tooltool.py', 18:23:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:23:05 INFO - '/tools/misc-python/virtualenv.py')}, 18:23:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:23:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:23:05 INFO - 'installer_path': 'installer.exe', 18:23:05 INFO - 'log_level': 'info', 18:23:05 INFO - 'log_name': 'talos', 18:23:05 INFO - 'log_to_console': True, 18:23:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:23:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:23:05 INFO - 'opt_config_files': (), 18:23:05 INFO - 'pip_index': False, 18:23:05 INFO - 'sps_profile': False, 18:23:05 INFO - 'sps_profile_interval': 0, 18:23:05 INFO - 'suite': 'dromaeojs', 18:23:05 INFO - 'system_bits': '32', 18:23:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:23:05 INFO - 'title': 'talos-linux64-ix-063', 18:23:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:23:05 INFO - 'use_talos_json': True, 18:23:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:23:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:23:05 INFO - 'work_dir': 'build'} 18:23:05 INFO - [mozharness: 2017-01-03 02:23:05.442298Z] Running clobber step. 18:23:05 INFO - Running pre-action listener: _resource_record_pre_action 18:23:05 INFO - Running main action method: clobber 18:23:05 INFO - rmtree: /builds/slave/test/build 18:23:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:23:05 INFO - Running post-action listener: _resource_record_post_action 18:23:05 INFO - [mozharness: 2017-01-03 02:23:05.899257Z] Finished clobber step (success) 18:23:05 INFO - [mozharness: 2017-01-03 02:23:05.899359Z] Running read-buildbot-config step. 18:23:05 INFO - Running pre-action listener: _resource_record_pre_action 18:23:05 INFO - Running main action method: read_buildbot_config 18:23:05 INFO - Using buildbot properties: 18:23:05 INFO - { 18:23:05 INFO - "project": "", 18:23:05 INFO - "product": "firefox", 18:23:05 INFO - "script_repo_revision": "production", 18:23:05 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 18:23:05 INFO - "repository": "", 18:23:05 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 18:23:05 INFO - "buildid": "20170102163019", 18:23:05 INFO - "pgo_build": "True", 18:23:05 INFO - "basedir": "/builds/slave/test", 18:23:05 INFO - "buildnumber": 43, 18:23:05 INFO - "slavename": "talos-linux64-ix-063", 18:23:05 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:23:05 INFO - "platform": "ubuntu64_hw", 18:23:05 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 18:23:05 INFO - "branch": "mozilla-beta", 18:23:05 INFO - "repo_path": "releases/mozilla-beta", 18:23:05 INFO - "revision": "4284001e0ba8c1c1d02301f15ca596b55f971d37", 18:23:05 INFO - "stage_platform": "linux64-pgo", 18:23:05 INFO - "builduid": "94efecb7cbde427f9e823b00a98900ac", 18:23:05 INFO - "slavebuilddir": "test" 18:23:05 INFO - } 18:23:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ei2bZukxTK2E6jBqXUGwBw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2. 18:23:05 INFO - Running post-action listener: _resource_record_post_action 18:23:05 INFO - [mozharness: 2017-01-03 02:23:05.902076Z] Finished read-buildbot-config step (success) 18:23:05 INFO - [mozharness: 2017-01-03 02:23:05.902164Z] Running download-and-extract step. 18:23:05 INFO - Running pre-action listener: _resource_record_pre_action 18:23:05 INFO - Running main action method: download_and_extract 18:23:05 INFO - mkdir: /builds/slave/test/build/tests 18:23:05 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')]} 18:23:05 INFO - trying https://queue.taskcluster.net/v1/task/Ei2bZukxTK2E6jBqXUGwBw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 18:23:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Ei2bZukxTK2E6jBqXUGwBw/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 18:23:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ei2bZukxTK2E6jBqXUGwBw/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 18:23:06 INFO - Downloaded 1327 bytes. 18:23:06 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 18:23:06 INFO - Using the following test package requirements: 18:23:06 INFO - {u'common': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 18:23:06 INFO - u'cppunittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 18:23:06 INFO - u'firefox-51.0.en-US.linux-x86_64.cppunittest.tests.zip'], 18:23:06 INFO - u'gtest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 18:23:06 INFO - u'firefox-51.0.en-US.linux-x86_64.gtest.tests.zip'], 18:23:06 INFO - u'jittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 18:23:06 INFO - u'jsshell-linux-x86_64.zip'], 18:23:06 INFO - u'mochitest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 18:23:06 INFO - u'firefox-51.0.en-US.linux-x86_64.mochitest.tests.zip'], 18:23:06 INFO - u'mozbase': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 18:23:06 INFO - u'reftest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 18:23:06 INFO - u'firefox-51.0.en-US.linux-x86_64.reftest.tests.zip'], 18:23:06 INFO - u'talos': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 18:23:06 INFO - u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'], 18:23:06 INFO - u'web-platform': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 18:23:06 INFO - u'firefox-51.0.en-US.linux-x86_64.web-platform.tests.zip'], 18:23:06 INFO - u'xpcshell': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 18:23:06 INFO - u'firefox-51.0.en-US.linux-x86_64.xpcshell.tests.zip']} 18:23:06 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:23:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ei2bZukxTK2E6jBqXUGwBw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 18:23:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ei2bZukxTK2E6jBqXUGwBw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:23:06 INFO - Fetch https://queue.taskcluster.net/v1/task/Ei2bZukxTK2E6jBqXUGwBw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 18:23:07 INFO - Expected file size: 21969820 18:23:11 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 18:23:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ei2bZukxTK2E6jBqXUGwBw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 18:23:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ei2bZukxTK2E6jBqXUGwBw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:23:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Ei2bZukxTK2E6jBqXUGwBw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 18:23:11 INFO - Expected file size: 21969820 18:23:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ei2bZukxTK2E6jBqXUGwBw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip 18:23:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ei2bZukxTK2E6jBqXUGwBw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:23:16 INFO - Fetch https://queue.taskcluster.net/v1/task/Ei2bZukxTK2E6jBqXUGwBw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip into memory 18:23:17 INFO - Expected file size: 14414089 18:23: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')]} 18:23:17 INFO - trying https://queue.taskcluster.net/v1/task/Ei2bZukxTK2E6jBqXUGwBw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 18:23:17 INFO - Downloading https://queue.taskcluster.net/v1/task/Ei2bZukxTK2E6jBqXUGwBw/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 18:23:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ei2bZukxTK2E6jBqXUGwBw/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 18:23:18 INFO - Downloaded 56954958 bytes. 18:23:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ei2bZukxTK2E6jBqXUGwBw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 18:23:18 INFO - mkdir: /builds/slave/test/properties 18:23:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:23:18 INFO - Writing to file /builds/slave/test/properties/build_url 18:23:18 INFO - Contents: 18:23:18 INFO - build_url:https://queue.taskcluster.net/v1/task/Ei2bZukxTK2E6jBqXUGwBw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 18:23:19 INFO - Running post-action listener: _resource_record_post_action 18:23:19 INFO - Running post-action listener: set_extra_try_arguments 18:23:19 INFO - [mozharness: 2017-01-03 02:23:19.296929Z] Finished download-and-extract step (success) 18:23:19 INFO - [mozharness: 2017-01-03 02:23:19.297156Z] Running populate-webroot step. 18:23:19 INFO - Running pre-action listener: _resource_record_pre_action 18:23:19 INFO - Running main action method: populate_webroot 18:23:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:23:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:23:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:23:19 INFO - '--tppagecycles', 18:23:19 INFO - '7'], 18:23:19 INFO - 'tests': ['tsvgm']}}, 18:23:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:23:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:23:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:23:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:23:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:23:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:23:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:19 INFO - 'talos_options': ['--disable-e10s'], 18:23:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:23:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:23:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:19 INFO - 'talos_options': ['--disable-e10s'], 18:23:19 INFO - 'tests': ['damp', 'tps']}, 18:23:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:19 INFO - 'tests': ['damp', 'tps']}, 18:23:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:23:19 INFO - 'tests': ['dromaeo_dom']}, 18:23:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:23:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:23:19 INFO - 'tests': ['basic_compositor_video']}, 18:23:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:23:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:23:19 INFO - 'tests': ['a11yr', 18:23:19 INFO - 'ts_paint', 18:23:19 INFO - 'tpaint', 18:23:19 INFO - 'sessionrestore', 18:23:19 INFO - 'sessionrestore_no_auto_restore', 18:23:19 INFO - 'tabpaint']}, 18:23:19 INFO - 'other-e10s': {'tests': ['a11yr', 18:23:19 INFO - 'ts_paint', 18:23:19 INFO - 'tpaint', 18:23:19 INFO - 'sessionrestore', 18:23:19 INFO - 'sessionrestore_no_auto_restore', 18:23:19 INFO - 'tabpaint']}, 18:23:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:23:19 INFO - 'ts_paint', 18:23:19 INFO - 'tpaint', 18:23:19 INFO - 'sessionrestore', 18:23:19 INFO - 'sessionrestore_no_auto_restore', 18:23:19 INFO - 'tabpaint']}, 18:23:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:23:19 INFO - 'ts_paint', 18:23:19 INFO - 'tpaint', 18:23:19 INFO - 'sessionrestore', 18:23:19 INFO - 'sessionrestore_no_auto_restore', 18:23:19 INFO - 'tabpaint']}, 18:23:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:23:19 INFO - 'tests': ['a11yr', 18:23:19 INFO - 'ts_paint', 18:23:19 INFO - 'tpaint', 18:23:19 INFO - 'sessionrestore', 18:23:19 INFO - 'sessionrestore_no_auto_restore', 18:23:19 INFO - 'tabpaint']}, 18:23:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:23:19 INFO - 'tests': ['a11yr', 18:23:19 INFO - 'ts_paint', 18:23:19 INFO - 'tpaint', 18:23:19 INFO - 'sessionrestore', 18:23:19 INFO - 'sessionrestore_no_auto_restore', 18:23:19 INFO - 'tabpaint']}, 18:23:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:23:19 INFO - 'tests': ['tsvgx', 18:23:19 INFO - 'tsvgr_opacity', 18:23:19 INFO - 'tart', 18:23:19 INFO - 'tscrollx', 18:23:19 INFO - 'cart']}, 18:23:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:23:19 INFO - 'tsvgr_opacity', 18:23:19 INFO - 'tart', 18:23:19 INFO - 'tscrollx', 18:23:19 INFO - 'cart']}, 18:23:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:19 INFO - 'talos_options': ['--disable-e10s'], 18:23:19 INFO - 'tests': ['tp5o']}, 18:23:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:19 INFO - 'tests': ['tp5o']}, 18:23:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:19 INFO - 'talos_options': ['--disable-e10s', 18:23:19 INFO - '--xperf_path', 18:23:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:23:19 INFO - 'tests': ['tp5n']}, 18:23:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:19 INFO - 'talos_options': ['--xperf_path', 18:23:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:23:19 INFO - 'tests': ['tp5n']}}, 18:23:19 INFO - 'talos.zip': {'path': '', 18:23:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:23:19 INFO - Running post-action listener: _resource_record_post_action 18:23:19 INFO - [mozharness: 2017-01-03 02:23:19.319506Z] Finished populate-webroot step (success) 18:23:19 INFO - [mozharness: 2017-01-03 02:23:19.319611Z] Running create-virtualenv step. 18:23:19 INFO - Running pre-action listener: _resource_record_pre_action 18:23:19 INFO - Running main action method: create_virtualenv 18:23:19 INFO - Creating virtualenv /builds/slave/test/build/venv 18:23:19 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 18:23:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:23:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:23:19 INFO - Using real prefix '/usr' 18:23:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:23:19 INFO - Installing distribute.............................................................................................................................................................................................done. 18:23:21 INFO - Installing pip.................done. 18:23:21 INFO - Return code: 0 18:23:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:23:21 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')]} 18:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:21 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')]} 18:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:21 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 0x7fd174023f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2614ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2611f60>, '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 0x2612d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26131d0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', '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 18:23:21 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 18:23:21 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 18:23:21 INFO - Using env: {'DISPLAY': ':0', 18:23:21 INFO - 'HOME': '/home/cltbld', 18:23:21 INFO - 'LANG': 'en_US.UTF-8', 18:23:21 INFO - 'LANGUAGE': 'en_US:en', 18:23:21 INFO - 'LOGNAME': 'cltbld', 18:23:21 INFO - 'MAIL': '/var/mail/cltbld', 18:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:21 INFO - 'MOZ_NO_REMOTE': '1', 18:23:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:21 INFO - 'NO_EM_RESTART': '1', 18:23:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:21 INFO - 'PWD': '/builds/slave/test', 18:23:21 INFO - 'SHELL': '/bin/bash', 18:23:21 INFO - 'SHLVL': '1', 18:23:21 INFO - 'TERM': 'linux', 18:23:21 INFO - 'TMOUT': '86400', 18:23:21 INFO - 'USER': 'cltbld', 18:23:21 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', 18:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:21 INFO - '_': '/tools/buildbot/bin/python'} 18:23:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:22 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 18:23:22 INFO - Cleaning up... 18:23:22 INFO - Return code: 0 18:23:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:23: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')]} 18:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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')]} 18:23:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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', '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 0x7fd174023f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2614ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2611f60>, '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 0x2612d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26131d0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', '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 18:23: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', 'psutil>=3.1.1'] in /builds/slave/test/build 18:23: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 psutil>=3.1.1 18:23:22 INFO - Using env: {'DISPLAY': ':0', 18:23:22 INFO - 'HOME': '/home/cltbld', 18:23:22 INFO - 'LANG': 'en_US.UTF-8', 18:23:22 INFO - 'LANGUAGE': 'en_US:en', 18:23:22 INFO - 'LOGNAME': 'cltbld', 18:23:22 INFO - 'MAIL': '/var/mail/cltbld', 18:23:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:22 INFO - 'MOZ_NO_REMOTE': '1', 18:23:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:22 INFO - 'NO_EM_RESTART': '1', 18:23:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:22 INFO - 'PWD': '/builds/slave/test', 18:23:22 INFO - 'SHELL': '/bin/bash', 18:23:22 INFO - 'SHLVL': '1', 18:23:22 INFO - 'TERM': 'linux', 18:23:22 INFO - 'TMOUT': '86400', 18:23:22 INFO - 'USER': 'cltbld', 18:23:22 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', 18:23:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:22 INFO - '_': '/tools/buildbot/bin/python'} 18:23:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:22 INFO - Downloading/unpacking psutil>=3.1.1 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:23:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:23:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:23:24 INFO - Installing collected packages: psutil 18:23:24 INFO - Running setup.py install for psutil 18:23:24 INFO - building 'psutil._psutil_linux' extension 18:23:24 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 18:23:24 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 18:23:24 INFO - building 'psutil._psutil_posix' extension 18:23:24 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 18:23:24 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 18:23:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:23:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:23:24 INFO - Successfully installed psutil 18:23:24 INFO - Cleaning up... 18:23:25 INFO - Return code: 0 18:23:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:23: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')]} 18:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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')]} 18:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd174023f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2614ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2611f60>, '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 0x2612d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26131d0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', '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 18:23: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:23: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 mozsystemmonitor==0.3 18:23:25 INFO - Using env: {'DISPLAY': ':0', 18:23:25 INFO - 'HOME': '/home/cltbld', 18:23:25 INFO - 'LANG': 'en_US.UTF-8', 18:23:25 INFO - 'LANGUAGE': 'en_US:en', 18:23:25 INFO - 'LOGNAME': 'cltbld', 18:23:25 INFO - 'MAIL': '/var/mail/cltbld', 18:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:25 INFO - 'MOZ_NO_REMOTE': '1', 18:23:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:25 INFO - 'NO_EM_RESTART': '1', 18:23:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:25 INFO - 'PWD': '/builds/slave/test', 18:23:25 INFO - 'SHELL': '/bin/bash', 18:23:25 INFO - 'SHLVL': '1', 18:23:25 INFO - 'TERM': 'linux', 18:23:25 INFO - 'TMOUT': '86400', 18:23:25 INFO - 'USER': 'cltbld', 18:23:25 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', 18:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:25 INFO - '_': '/tools/buildbot/bin/python'} 18:23:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:23:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:23:27 INFO - Installing collected packages: mozsystemmonitor 18:23:27 INFO - Running setup.py install for mozsystemmonitor 18:23:27 INFO - Successfully installed mozsystemmonitor 18:23:27 INFO - Cleaning up... 18:23:27 INFO - Return code: 0 18:23:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:23:27 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')]} 18:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:27 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')]} 18:23:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:27 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 0x7fd174023f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2614ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2611f60>, '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 0x2612d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26131d0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', '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 18:23:27 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 18:23:27 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 18:23:27 INFO - Using env: {'DISPLAY': ':0', 18:23:27 INFO - 'HOME': '/home/cltbld', 18:23:27 INFO - 'LANG': 'en_US.UTF-8', 18:23:27 INFO - 'LANGUAGE': 'en_US:en', 18:23:27 INFO - 'LOGNAME': 'cltbld', 18:23:27 INFO - 'MAIL': '/var/mail/cltbld', 18:23:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:27 INFO - 'MOZ_NO_REMOTE': '1', 18:23:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:27 INFO - 'NO_EM_RESTART': '1', 18:23:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:27 INFO - 'PWD': '/builds/slave/test', 18:23:27 INFO - 'SHELL': '/bin/bash', 18:23:27 INFO - 'SHLVL': '1', 18:23:27 INFO - 'TERM': 'linux', 18:23:27 INFO - 'TMOUT': '86400', 18:23:27 INFO - 'USER': 'cltbld', 18:23:27 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', 18:23:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:27 INFO - '_': '/tools/buildbot/bin/python'} 18:23:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:27 INFO - Downloading/unpacking jsonschema==2.5.1 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:23:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:23:29 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 18:23:29 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 18:23:29 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 18:23:29 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 18:23:29 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 18:23:29 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 18:23:29 INFO - Downloading functools32-3.2.3-2.tar.gz 18:23:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:23:29 INFO - warning: no files found matching '*.txt' 18:23:29 INFO - no previously-included directories found matching 'build' 18:23:29 INFO - no previously-included directories found matching 'dist' 18:23:29 INFO - no previously-included directories found matching '.git*' 18:23:29 INFO - Installing collected packages: jsonschema, functools32 18:23:29 INFO - Running setup.py install for functools32 18:23:30 INFO - warning: no files found matching '*.txt' 18:23:30 INFO - no previously-included directories found matching 'build' 18:23:30 INFO - no previously-included directories found matching 'dist' 18:23:30 INFO - no previously-included directories found matching '.git*' 18:23:30 INFO - Successfully installed jsonschema functools32 18:23:30 INFO - Cleaning up... 18:23:30 INFO - Return code: 0 18:23:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:23:30 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')]} 18:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:30 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')]} 18:23:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:30 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 0x7fd174023f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2614ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2611f60>, '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 0x2612d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26131d0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', '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 18:23:30 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 18:23:30 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 18:23:30 INFO - Using env: {'DISPLAY': ':0', 18:23:30 INFO - 'HOME': '/home/cltbld', 18:23:30 INFO - 'LANG': 'en_US.UTF-8', 18:23:30 INFO - 'LANGUAGE': 'en_US:en', 18:23:30 INFO - 'LOGNAME': 'cltbld', 18:23:30 INFO - 'MAIL': '/var/mail/cltbld', 18:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:30 INFO - 'MOZ_NO_REMOTE': '1', 18:23:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:30 INFO - 'NO_EM_RESTART': '1', 18:23:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:30 INFO - 'PWD': '/builds/slave/test', 18:23:30 INFO - 'SHELL': '/bin/bash', 18:23:30 INFO - 'SHLVL': '1', 18:23:30 INFO - 'TERM': 'linux', 18:23:30 INFO - 'TMOUT': '86400', 18:23:30 INFO - 'USER': 'cltbld', 18:23:30 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', 18:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:30 INFO - '_': '/tools/buildbot/bin/python'} 18:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:23:30 INFO - Cleaning up... 18:23:30 INFO - Return code: 0 18:23:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:23:30 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')]} 18:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:30 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')]} 18:23:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:30 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 0x7fd174023f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2614ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2611f60>, '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 0x2612d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26131d0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', '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 18:23:30 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 18:23:30 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 18:23:30 INFO - Using env: {'DISPLAY': ':0', 18:23:30 INFO - 'HOME': '/home/cltbld', 18:23:30 INFO - 'LANG': 'en_US.UTF-8', 18:23:30 INFO - 'LANGUAGE': 'en_US:en', 18:23:30 INFO - 'LOGNAME': 'cltbld', 18:23:30 INFO - 'MAIL': '/var/mail/cltbld', 18:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:30 INFO - 'MOZ_NO_REMOTE': '1', 18:23:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:30 INFO - 'NO_EM_RESTART': '1', 18:23:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:30 INFO - 'PWD': '/builds/slave/test', 18:23:30 INFO - 'SHELL': '/bin/bash', 18:23:30 INFO - 'SHLVL': '1', 18:23:30 INFO - 'TERM': 'linux', 18:23:30 INFO - 'TMOUT': '86400', 18:23:30 INFO - 'USER': 'cltbld', 18:23:30 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', 18:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:30 INFO - '_': '/tools/buildbot/bin/python'} 18:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:30 INFO - Downloading/unpacking blobuploader==1.2.4 18:23:30 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 18:23:30 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 18:23:30 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 18:23:30 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 18:23:30 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 18:23:30 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 18:23:32 INFO - Downloading blobuploader-1.2.4.tar.gz 18:23:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:23:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:23:32 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 18:23:32 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 18:23:32 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 18:23:32 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 18:23:32 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 18:23:32 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 18:23:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:23:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23:33 INFO - Downloading docopt-0.6.1.tar.gz 18:23:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:23:33 INFO - Installing collected packages: blobuploader, requests, docopt 18:23:33 INFO - Running setup.py install for blobuploader 18:23:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:23:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:23:33 INFO - Running setup.py install for requests 18:23:33 INFO - Running setup.py install for docopt 18:23:33 INFO - Successfully installed blobuploader requests docopt 18:23:33 INFO - Cleaning up... 18:23:33 INFO - Return code: 0 18:23:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:23:33 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')]} 18:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:33 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')]} 18:23:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:33 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 0x7fd174023f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2614ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2611f60>, '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 0x2612d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26131d0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', '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 18:23:33 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 18:23:33 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 18:23:33 INFO - Using env: {'DISPLAY': ':0', 18:23:33 INFO - 'HOME': '/home/cltbld', 18:23:33 INFO - 'LANG': 'en_US.UTF-8', 18:23:33 INFO - 'LANGUAGE': 'en_US:en', 18:23:33 INFO - 'LOGNAME': 'cltbld', 18:23:33 INFO - 'MAIL': '/var/mail/cltbld', 18:23:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:33 INFO - 'MOZ_NO_REMOTE': '1', 18:23:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:33 INFO - 'NO_EM_RESTART': '1', 18:23:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:33 INFO - 'PWD': '/builds/slave/test', 18:23:33 INFO - 'SHELL': '/bin/bash', 18:23:33 INFO - 'SHLVL': '1', 18:23:33 INFO - 'TERM': 'linux', 18:23:33 INFO - 'TMOUT': '86400', 18:23:33 INFO - 'USER': 'cltbld', 18:23:33 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', 18:23:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:33 INFO - '_': '/tools/buildbot/bin/python'} 18:23:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:23:34 INFO - Running setup.py (path:/tmp/pip-r4gEcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:23:34 INFO - Running setup.py (path:/tmp/pip-FprqkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:23:34 INFO - Running setup.py (path:/tmp/pip-cNJKNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:23:34 INFO - Running setup.py (path:/tmp/pip-6w_HeM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:23:34 INFO - Running setup.py (path:/tmp/pip-zqM_Bp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:23:34 INFO - Running setup.py (path:/tmp/pip-FOTZkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:23:34 INFO - Running setup.py (path:/tmp/pip-lPVgy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:23:34 INFO - Running setup.py (path:/tmp/pip-t1AGin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:23:34 INFO - Running setup.py (path:/tmp/pip-C1ljx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:23:34 INFO - Running setup.py (path:/tmp/pip-SBBVVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:23:34 INFO - Running setup.py (path:/tmp/pip-MaiXBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:23:35 INFO - Running setup.py (path:/tmp/pip-bU1TYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:23:35 INFO - Running setup.py (path:/tmp/pip-IL30H1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:23:35 INFO - Running setup.py (path:/tmp/pip-MXaSD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:23:35 INFO - Running setup.py (path:/tmp/pip-EBNWKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:23:35 INFO - Running setup.py (path:/tmp/pip-yq6mTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:23:35 INFO - Running setup.py (path:/tmp/pip-q9pADo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:23:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:23:35 INFO - Running setup.py install for manifestparser 18:23:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:23:35 INFO - Running setup.py install for mozcrash 18:23:35 INFO - Running setup.py install for mozdebug 18:23:35 INFO - Running setup.py install for mozdevice 18:23:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:23:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:23:35 INFO - Running setup.py install for mozfile 18:23:35 INFO - Running setup.py install for mozhttpd 18:23:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:23:36 INFO - Running setup.py install for mozinfo 18:23:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:23:36 INFO - Running setup.py install for mozInstall 18:23:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:23:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:23:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:23:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:23:36 INFO - Running setup.py install for mozleak 18:23:36 INFO - Running setup.py install for mozlog 18:23:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:23:36 INFO - Running setup.py install for moznetwork 18:23:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:23:36 INFO - Running setup.py install for mozprocess 18:23:36 INFO - Running setup.py install for mozprofile 18:23:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:23:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:23:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:23:36 INFO - Running setup.py install for mozrunner 18:23:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:23:36 INFO - Running setup.py install for mozscreenshot 18:23:37 INFO - Running setup.py install for moztest 18:23:37 INFO - Running setup.py install for mozversion 18:23:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:23:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:23:37 INFO - Cleaning up... 18:23:37 INFO - Return code: 0 18:23:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:23:37 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')]} 18:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:37 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')]} 18:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:37 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 0x7fd174023f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2614ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2611f60>, '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 0x2612d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26131d0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', '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 18:23:37 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 18:23:37 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 18:23:37 INFO - Using env: {'DISPLAY': ':0', 18:23:37 INFO - 'HOME': '/home/cltbld', 18:23:37 INFO - 'LANG': 'en_US.UTF-8', 18:23:37 INFO - 'LANGUAGE': 'en_US:en', 18:23:37 INFO - 'LOGNAME': 'cltbld', 18:23:37 INFO - 'MAIL': '/var/mail/cltbld', 18:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:37 INFO - 'MOZ_NO_REMOTE': '1', 18:23:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:37 INFO - 'NO_EM_RESTART': '1', 18:23:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:37 INFO - 'PWD': '/builds/slave/test', 18:23:37 INFO - 'SHELL': '/bin/bash', 18:23:37 INFO - 'SHLVL': '1', 18:23:37 INFO - 'TERM': 'linux', 18:23:37 INFO - 'TMOUT': '86400', 18:23:37 INFO - 'USER': 'cltbld', 18:23:37 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', 18:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:37 INFO - '_': '/tools/buildbot/bin/python'} 18:23:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:23:37 INFO - Running setup.py (path:/tmp/pip-1kR3U_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:23:37 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)) 18:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:23:37 INFO - Running setup.py (path:/tmp/pip-35GdVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:23:37 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)) 18:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:23:37 INFO - Running setup.py (path:/tmp/pip-VcK3IF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:23:37 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)) 18:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:23:37 INFO - Running setup.py (path:/tmp/pip-rX3uVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:23:37 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)) 18:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:23:37 INFO - Running setup.py (path:/tmp/pip-vXeBCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:23:37 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)) 18:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:23:37 INFO - Running setup.py (path:/tmp/pip-daeAun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:23:37 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)) 18:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:23:37 INFO - Running setup.py (path:/tmp/pip-L6cENR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:23:37 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)) 18:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:23:37 INFO - Running setup.py (path:/tmp/pip-TrIxnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:23:38 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)) 18:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:23:38 INFO - Running setup.py (path:/tmp/pip-tVOnNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:23:38 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)) 18:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:23:38 INFO - Running setup.py (path:/tmp/pip-DK652h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:23:38 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)) 18:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:23:38 INFO - Running setup.py (path:/tmp/pip-FRQBAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:23:38 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)) 18:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:23:38 INFO - Running setup.py (path:/tmp/pip-Ynt17g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:23:38 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)) 18:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:23:38 INFO - Running setup.py (path:/tmp/pip-lNArQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:23:38 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)) 18:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:23:38 INFO - Running setup.py (path:/tmp/pip-8sFJkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:23:38 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)) 18:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:23:38 INFO - Running setup.py (path:/tmp/pip-OY5ofh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:23:38 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)) 18:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:23:38 INFO - Running setup.py (path:/tmp/pip-01Ykoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:23:38 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)) 18:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:23:38 INFO - Running setup.py (path:/tmp/pip-Ku_3iN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:23:38 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)) 18:23:38 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)) 18:23:38 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)) 18:23:38 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)) 18:23:38 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)) 18:23:38 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)) 18:23:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:23:38 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 18:23:38 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 18:23:38 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 18:23:38 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 18:23:38 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 18:23:38 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 18:23:40 INFO - Downloading blessings-1.6.tar.gz 18:23:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:23:40 INFO - Installing collected packages: blessings 18:23:40 INFO - Running setup.py install for blessings 18:23:40 INFO - Successfully installed blessings 18:23:40 INFO - Cleaning up... 18:23:40 INFO - Return code: 0 18:23:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:23:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:23:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:23:41 INFO - Reading from file tmpfile_stdout 18:23:41 INFO - Current package versions: 18:23:41 INFO - argparse == 1.2.1 18:23:41 INFO - blessings == 1.6 18:23:41 INFO - blobuploader == 1.2.4 18:23:41 INFO - docopt == 0.6.1 18:23:41 INFO - functools32 == 3.2.3-2 18:23:41 INFO - jsonschema == 2.5.1 18:23:41 INFO - manifestparser == 1.1 18:23:41 INFO - mozInstall == 1.12 18:23:41 INFO - mozcrash == 0.17 18:23:41 INFO - mozdebug == 0.1 18:23:41 INFO - mozdevice == 0.48 18:23:41 INFO - mozfile == 1.2 18:23:41 INFO - mozhttpd == 0.7 18:23:41 INFO - mozinfo == 0.9 18:23:41 INFO - mozleak == 0.1 18:23:41 INFO - mozlog == 3.4 18:23:41 INFO - moznetwork == 0.27 18:23:41 INFO - mozprocess == 0.23 18:23:41 INFO - mozprofile == 0.28 18:23:41 INFO - mozrunner == 6.12 18:23:41 INFO - mozscreenshot == 0.1 18:23:41 INFO - mozsystemmonitor == 0.3 18:23:41 INFO - moztest == 0.7 18:23:41 INFO - mozversion == 1.4 18:23:41 INFO - psutil == 3.1.1 18:23:41 INFO - requests == 1.2.3 18:23:41 INFO - wsgiref == 0.1.2 18:23:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:23:41 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')]} 18:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:41 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')]} 18:23:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:41 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 0x7fd174023f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2614ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2611f60>, '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 0x2612d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26131d0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', '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 18:23:41 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 18:23:41 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 18:23:41 INFO - Using env: {'DISPLAY': ':0', 18:23:41 INFO - 'HOME': '/home/cltbld', 18:23:41 INFO - 'LANG': 'en_US.UTF-8', 18:23:41 INFO - 'LANGUAGE': 'en_US:en', 18:23:41 INFO - 'LOGNAME': 'cltbld', 18:23:41 INFO - 'MAIL': '/var/mail/cltbld', 18:23:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:41 INFO - 'MOZ_NO_REMOTE': '1', 18:23:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:41 INFO - 'NO_EM_RESTART': '1', 18:23:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:41 INFO - 'PWD': '/builds/slave/test', 18:23:41 INFO - 'SHELL': '/bin/bash', 18:23:41 INFO - 'SHLVL': '1', 18:23:41 INFO - 'TERM': 'linux', 18:23:41 INFO - 'TMOUT': '86400', 18:23:41 INFO - 'USER': 'cltbld', 18:23:41 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', 18:23:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:41 INFO - '_': '/tools/buildbot/bin/python'} 18:23:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:41 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)) 18:23:41 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)) 18:23:41 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)) 18:23:41 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)) 18:23:41 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)) 18:23:41 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)) 18:23:41 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)) 18:23:41 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)) 18:23:41 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)) 18:23:41 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)) 18:23:41 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)) 18:23:41 INFO - Cleaning up... 18:23:41 INFO - Return code: 0 18:23:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:23:41 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')]} 18:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:41 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')]} 18:23:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:41 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 0x7fd174023f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2614ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2611f60>, '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 0x2612d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26131d0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', '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 18:23:41 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 18:23:41 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 18:23:41 INFO - Using env: {'DISPLAY': ':0', 18:23:41 INFO - 'HOME': '/home/cltbld', 18:23:41 INFO - 'LANG': 'en_US.UTF-8', 18:23:41 INFO - 'LANGUAGE': 'en_US:en', 18:23:41 INFO - 'LOGNAME': 'cltbld', 18:23:41 INFO - 'MAIL': '/var/mail/cltbld', 18:23:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:41 INFO - 'MOZ_NO_REMOTE': '1', 18:23:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:41 INFO - 'NO_EM_RESTART': '1', 18:23:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:41 INFO - 'PWD': '/builds/slave/test', 18:23:41 INFO - 'SHELL': '/bin/bash', 18:23:41 INFO - 'SHLVL': '1', 18:23:41 INFO - 'TERM': 'linux', 18:23:41 INFO - 'TMOUT': '86400', 18:23:41 INFO - 'USER': 'cltbld', 18:23:41 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', 18:23:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:41 INFO - '_': '/tools/buildbot/bin/python'} 18:23:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:23:41 INFO - Cleaning up... 18:23:41 INFO - Return code: 0 18:23:41 INFO - Running post-action listener: _resource_record_post_action 18:23:41 INFO - Running post-action listener: _start_resource_monitoring 18:23:41 INFO - Starting resource monitoring. 18:23:41 INFO - [mozharness: 2017-01-03 02:23:41.657983Z] Finished create-virtualenv step (success) 18:23:41 INFO - [mozharness: 2017-01-03 02:23:41.658434Z] Running install step. 18:23:41 INFO - Running pre-action listener: _resource_record_pre_action 18:23:41 INFO - Running main action method: install 18:23:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:23:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:23:41 INFO - Reading from file tmpfile_stdout 18:23:41 INFO - Detecting whether we're running mozinstall >=1.0... 18:23:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:23:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:23:41 INFO - Reading from file tmpfile_stdout 18:23:41 INFO - Output received: 18:23:41 INFO - Usage: mozinstall [options] installer 18:23:41 INFO - Options: 18:23:41 INFO - -h, --help show this help message and exit 18:23:41 INFO - -d DEST, --destination=DEST 18:23:41 INFO - Directory to install application into. [default: 18:23:41 INFO - "/builds/slave/test"] 18:23:41 INFO - --app=APP Application being installed. [default: firefox] 18:23:41 INFO - mkdir: /builds/slave/test/build/application 18:23:41 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'] 18:23:41 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 18:23:55 INFO - Reading from file tmpfile_stdout 18:23:55 INFO - Output received: 18:23:55 INFO - /builds/slave/test/build/application/firefox/firefox 18:23:55 INFO - Running post-action listener: _resource_record_post_action 18:23:55 INFO - [mozharness: 2017-01-03 02:23:55.466753Z] Finished install step (success) 18:23:55 INFO - [mozharness: 2017-01-03 02:23:55.466997Z] Running run-tests step. 18:23:55 INFO - Running pre-action listener: _resource_record_pre_action 18:23:55 INFO - Running main action method: run_tests 18:23:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:23:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:23:55 INFO - Python 2.7.3 18:23:55 INFO - Return code: 0 18:23:55 INFO - grabbing minidump binary from tooltool 18:23:55 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')]} 18:23:55 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 0x2611f60>, '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 0x2612d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26131d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:23:55 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 18:23:55 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 18:23:55 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 18:23:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:23:55 INFO - Return code: 0 18:23:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:23:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:23:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:23:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:23:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:23:55 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/Ei2bZukxTK2E6jBqXUGwBw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--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 18:23:55 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/Ei2bZukxTK2E6jBqXUGwBw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-063 --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 18:23:55 INFO - Using env: {'DISPLAY': ':0', 18:23:55 INFO - 'HOME': '/home/cltbld', 18:23:55 INFO - 'LANG': 'en_US.UTF-8', 18:23:55 INFO - 'LANGUAGE': 'en_US:en', 18:23:55 INFO - 'LOGNAME': 'cltbld', 18:23:55 INFO - 'MAIL': '/var/mail/cltbld', 18:23:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:23:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:23:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:55 INFO - 'MOZ_NO_REMOTE': '1', 18:23:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:23:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:55 INFO - 'NO_EM_RESTART': '1', 18:23:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:55 INFO - 'PWD': '/builds/slave/test', 18:23:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:23:55 INFO - 'SHELL': '/bin/bash', 18:23:55 INFO - 'SHLVL': '1', 18:23:55 INFO - 'TERM': 'linux', 18:23:55 INFO - 'TMOUT': '86400', 18:23:55 INFO - 'USER': 'cltbld', 18:23:55 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079', 18:23:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:55 INFO - '_': '/tools/buildbot/bin/python'} 18:23:55 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/Ei2bZukxTK2E6jBqXUGwBw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--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 18:23:55 INFO - mozversion application_buildid: 20170102163019 18:23:55 INFO - mozversion application_changeset: 4284001e0ba8c1c1d02301f15ca596b55f971d37 18:23:55 INFO - mozversion application_display_name: Firefox 18:23:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:23:55 INFO - mozversion application_name: Firefox 18:23:55 INFO - mozversion application_remotingname: firefox 18:23:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:23:55 INFO - mozversion application_vendor: Mozilla 18:23:55 INFO - mozversion application_version: 51.0 18:23:55 INFO - mozversion platform_buildid: 20170102163019 18:23:55 INFO - mozversion platform_changeset: 4284001e0ba8c1c1d02301f15ca596b55f971d37 18:23:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:23:55 INFO - mozversion platform_version: 51.0 18:23:55 INFO - using testdate: 1483410235 18:23:55 INFO - actual date: 1483410235 18:23:55 INFO - starting webserver on 'localhost:42467' 18:23:55 INFO - SUITE-START | Running 2 tests 18:23:55 INFO - TEST-START | dromaeo_css 18:23:55 INFO - operating with platform_type : linux_ 18:23:55 INFO - Initialising browser for dromaeo_css test... 18:23:55 INFO - TEST-INFO | started process 3749 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQul72Y/profile http://localhost:42467/getInfo.html) 18:23:59 INFO - PROCESS | 3749 | __metrics Screen width/height:1600/1200 18:23:59 INFO - PROCESS | 3749 | colorDepth:24 18:23:59 INFO - PROCESS | 3749 | Browser inner width/height: 1024/697 18:23:59 INFO - PROCESS | 3749 | __metrics 18:24:00 INFO - PROCESS | 3749 | 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" 18:24:00 INFO - PROCESS | 3749 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:24:00 INFO - PROCESS | 3749 | console.error: PushService: 18:24:00 INFO - PROCESS | 3749 | stateChangeProcessEnqueue: Error transitioning state 18:24:00 INFO - PROCESS | 3749 | UnknownError 18:24:01 INFO - TEST-INFO | 3749: exit 0 18:24:01 INFO - Browser initialized. 18:24:01 INFO - Running cycle 1/1 for dromaeo_css test... 18:24:01 INFO - TEST-INFO | started process 3841 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQul72Y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:24:13 INFO - PROCESS | 3841 | RSS: Main: 143425536 18:24:13 INFO - PROCESS | 3841 | 18:25:04 INFO - PROCESS | 3841 | 1483410304444 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] 18:27:13 INFO - PROCESS | 3841 | Cycle 1(1): loaded http://localhost:42467/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42467/tests/dromaeo/cssquery-ext.html) 18:27:14 INFO - PROCESS | 3841 | RSS: Main: 245735424 18:27:14 INFO - PROCESS | 3841 | 18:30:17 INFO - PROCESS | 3841 | Cycle 1(1): loaded http://localhost:42467/tests/dromaeo/cssquery-ext.html (next: http://localhost:42467/tests/dromaeo/cssquery-jquery.html) 18:30:17 INFO - PROCESS | 3841 | RSS: Main: 231989248 18:30:17 INFO - PROCESS | 3841 | 18:33:19 INFO - PROCESS | 3841 | Cycle 1(1): loaded http://localhost:42467/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42467/tests/dromaeo/cssquery-mootools.html) 18:33:20 INFO - PROCESS | 3841 | RSS: Main: 179331072 18:33:20 INFO - PROCESS | 3841 | 18:36:22 INFO - PROCESS | 3841 | Cycle 1(1): loaded http://localhost:42467/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42467/tests/dromaeo/cssquery-prototype.html) 18:36:22 INFO - PROCESS | 3841 | RSS: Main: 175820800 18:36:22 INFO - PROCESS | 3841 | 18:39:25 INFO - PROCESS | 3841 | Cycle 1(1): loaded http://localhost:42467/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42467/tests/dromaeo/cssquery-yui.html) 18:39:25 INFO - PROCESS | 3841 | RSS: Main: 178192384 18:39:25 INFO - PROCESS | 3841 | 18:42:28 INFO - PROCESS | 3841 | Cycle 1(1): loaded http://localhost:42467/tests/dromaeo/cssquery-yui.html (next: http://localhost:42467/tests/dromaeo/cssquery-dojo.html) 18:42:28 INFO - PROCESS | 3841 | RSS: Main: 258334720 18:42:28 INFO - PROCESS | 3841 | 18:42:28 INFO - PROCESS | 3841 | __start_tp_report 18:42:28 INFO - PROCESS | 3841 | _x_x_mozilla_page_load 18:42:28 INFO - PROCESS | 3841 | _x_x_mozilla_page_load_details 18:42:28 INFO - PROCESS | 3841 | |i|pagename|runs| 18:42:28 INFO - PROCESS | 3841 | |0;dojo.html;3147.213196700825;3189.505626627873;3191.7287030602383;3216.823074730889;3221.951670724938;5691.3739488656265;5798.681072541009;5804.245448091753;5817.883642327161;5837.328707198668;5687.601867869251;5697.857553561163;5740.225069615601;5740.942590574093;5747.741351639176;5175.767090480928;5199.194124910645;5200.297959775438;5265.9473405266035;5285.524302812746;5146.536811686948;5273.657212281209;5285.101532739424;5285.497877701612;5288.524032837044;5120.308758317636;5154.639175257734;5170.819021334254;5200.349956255468;5203.271541984106;5227.86930326743;5227.973860130694;5306.283651707009;5316.255724198615;5356.464353564613;5004.899902001939;5150.356205474316;5225.817096401608;5279.1289437243195;5293.073712100367;5530.640508366983;5566.359868615121;5612.410696876851;5620.4098569650005;5649.802256921029;6049.848753781147;6091.02543593023;6198.876022479525;6203.751849925998;6205.0692396140485;6002.309734380558;6222.688865556704;6232.252129744415;6246.437820596123;6248.96875515624;5664.12206108058;5664.54683625309;5666.263385759866;5706.971465142731;5727.1695604138;6250.093736408196;6257.74969001244;6261.060840873905;6261.937380626136;6292.244930608264;5744.0522313817755;6019.367966363557;6027.945109605801;6033.849153771103;6040.7885724000325;5869;6073.392660733891;6108.7670647404;6113.5327720388195;6189.96905015472;6124.203853499017;6164.907526387108;6169.96915015422;6177.69111544421;6187.535934804908;6187.659678717624;6189.35011823761;6197.163382943335;6199.5970261933735;6201.410865967726;4610.031893302377;4632.795473176998;4642.837500687459;4659.161350956792;4710.88222794426;4141.503019637663;4146.751194928314;4165.000399904001;4178.997040710268;4183.477065366829;5819.330776960619;5819.418058194146;5825.03886858672;5828.242328497268;5831.825045248649;5844.853878653068;5911.408859114054;6131.193168498116;6141.263048434216;6194.628322300587;5945.613535120204;5946.167536544887;5946.43508866657;5968.671723055274;5984.760609575568;5392.245085688007;5395.379531353866;5398.352197736218;5401.108817045144;5406.135018397038;6073.848153796102;6115.301188036035;6188.133661287513;6376.3623637635865;6460.483161347081;6127.387261273837;6231.844203894939;6242.968785156045;6243.531735119794;6243.938530449926;6374.649394283359;6387.041943708295;6389.073584330339;6393.840153996187;6397.1363865879375;5839.737211825393;5962.463378295801;6116.877662446637;6117;6118.969405152946;6099.512039036955;6114.175715401414;6140.539459540463;6211.378862113753;6212.9068063978175;6049.576529643059;6055.848603784941;6065.150878876872;6066.696665166635;6068.423499767515;5859.648421094748;5943.197668314722;6002.039673652194;6009.729562169626;6013.699315034143;5793.015187418233;5840.591158618856;5861.7655293787775;5864.970675146597;5958.136070269873;5998.310194327533;5999.70001499932;5999.91000135007;6003.969980150246;6007.48936340416;5745.3105627324985;5828.446297601721;5840;5847.44449277318;5851.385604511465 18:42:28 INFO - PROCESS | 3841 | |1;ext.html;593.098490294752;622.2532960447463;652.9183852018498;670.5507310102231;679.5039621076611;36199.819000904994;47101.528984710145;47971.0405791884;48027.27959080617;48111.51888481114;49396.259056114184;49902.50097499024;49940.50059499404;49950.75024624881;49965.25052124212;8460.619272132768;8656.740297791077;9453.054694530561;9598.376105553152;9600.903990960105;11502.942485287562;11843.170978031541;11847.052235821157;11849.526018959274;11854.703632409173;11652.359120248382;11661.358625275607;11742.706432339217;11764.058875289998;11774.352410617412;11415.315081095161;11458.828117578243;11480.770384592344;11484.885151148465;11485.310881347104;11421.885781142166;11459.312441253469;11476.885231147666;11488.253263537843;11491.942540287246;46023.53976460226;46409;46600;46654.76672616649;46716.766416168044;3801;3808.733388662795;3809.047738065484;3844.154286057063;3900.668443182333;33168.3366332672;35438.11404714903;36010.45984310238;36475.08812279688;36479.4528082079;4694.554017368345;4731.905361892777;4759.524047595248;4882.609391248727;5014.799408023675;372394.13802931085;372516;372683;374243;376275;40207;41261.587384126375;68093;68740;68932.655336724;43219;43390.56609433929;43403;43430.56569434265;43465.56534434615;17873.374431894823;17987.370442034466;17996.730049049274;18005.369812056517;18024.36914707979;12644.367781610883;12906.161099528501;13399;13775.862241377457;14516.274186290855;9911.306208565475;9947;9951.751206219902;10008;10030.247731420173;3754.436834474796;3848.942265866014;3849.7690138591215;3850.9037274067814;3875.2830726315724;1065.9520321585546;1068.770214403907;1096.0738176241064;1108.3848464102339;1131.5247596009697;29803.40393192124;34174.14564635854;35303.11742206466;35570.46644300337;35606;1069.0485467933545;1088.1240601315858;1167.5329868052847;1170.2568868768394;1199.6401079676061;15531.912766106265;15595.610109747347;15677.843221567866;15727.921360393353;15785.842141578667;556249;559131;559211;559860;562856;65340.01989970154;65712;65789.34210657928;65860.67069664621;65887;1317.3544962968076;1348.7504811609883;1406.4514839212716;1455.163281113364;1467.5597320803715;27222;31037.844810776704;31061.689383106783;31195.84402077975;31436.842815785774;56323.71838140783;57165.714171428874;57230.7138464305;57319;57353.13970290365;438401;439074;443503;444194;445052;28278.15165545037;29273.268168295963;29274.121776346743;30100.247493811952;32991.67008329982;45026;49698.75150624224;51931.480685194176;53276.73361633167;53538.46461535335;42577.54560845408;43224;44655;45244.32133517934;46146.307805382276;44114;45233;45408.54591454043;45778;46208;43595.56404436042;43740.562594374926;43756;43881;44009.33985990148;36844.26311473809;39055.41416878806;39350.212995739355;39465.210695786496;39710;25827.870860645577;26755.866220668773;31035.84482077575;31791.682083179796;32280.576360865496 18:42:28 INFO - PROCESS | 3841 | |2;jquery.html;413.58847946293423;426.4200687065588;426.64801538730546;427.17128770185826;437.8445651793613;20309.593808123827;20421.183152673875;20516.28193013245;20574.279900203495;21015;19388.612227755435;20169.798302016974;20248.797512024874;20700.171993120257;20864.061117249723;5771.480566749002;6052.455279024887;6059.848503787419;6068.0291153415465;6079.939200607982;5848.970755146218;5967.552433567478;5993.160957465958;6010.909836352458;6073.058675905242;5969.044952807551;5978.432048955342;5979.820605381846;5980.461758441739;5981.222441082653;5751.942480575182;5936.940630593682;5980.581359304851;6006.309274433453;6019.939800602026;5420.674759514442;5428.267183930158;5431.8098866539485;5431.809886653988;5433.347998240197;24586;24625.507489850104;24649.38376540583;24785.504289914283;24835.379115522075;2515.861627610481;2518.4333524956924;2552.4767422678306;2583.9612405813928;2716.2657320375984;10637.414942178177;15335.846641533553;15426.305816238297;15615.453459128876;15788.605284867856;2010.5275260313815;2068.1210485543734;2097.2345094040716;2110.504031552584;2133.4133113393787;89952.55023724904;93298.53350733203;94628.52685736596;94827;97419;11838;11940.52237910474;12098.395080246128;12203;12261.080418968613;17472.73790893112;17551.385701500385;17597.296108155788;17627.030513321635;17648.911755441397;8151.429399941947;8509.489430634181;8560.400771945904;8598.527081010605;8739.60671769772;8473.23740863313;8510.404271701045;8520.8721869172;8652.394332396672;8723.651053957898;5175.741212939338;5276.472352764769;5285.577153827761;5303.655262407931;5322;2034.9287774927807;2116.4920419099303;2122.787721227865;2154.493693981913;2174.1086154676823;928.3084102343797;980.9509524523745;998.9700308990742;1030.3972176276811;1057.2070946789909;19851;20219.797802021792;20519.794802052085;20605.587888242153;21080.367588972356;1013.5287091502487;1021.7854250607455;1051.4007016000805;1054.7679510507676;1085.3216739537788;10024.699259022242;11568;11662.591809286634;11775.88224117765;11835.408229588485;374469;378925;379778;469300;476319;98370;100652;103642.44536331961;104940;105286.47356763168;1050.9054185123375;1073.4042606353548;1080.621782376162;1105.7125147461718;1121.4056550028497;18679.626407472046;18978.905105474936;19282.421527354203;19328.323508677127;19523.21907123756;43303.566964329955;43501.3474797872;43585.56414435942;44328.55671443373;45028.32457513206;62086.689566551875;62141.378586213556;62495.687521562104;62991;64519.35480645134;9148.496832674267;9547;9550.761230969003;9578.090081442251;9617;8386.622601982803;12444.502219911465;13720.519781807589;14097.154170749787;14628.926855365655;11189.832152517556;11639.88360116388;11810.704732381415;12136.635900922987;12673.049521285757;11874;11884.049276058067;11920.940395297968;12062.51749929993;12281.324527151093;11760.76478470443;11990.280583165038;12129.939350303192;12150.878491215328;12275.631731048083;9005.55911054228;9112.589933452879;9123;9422;9615.375000624937;11194.66416007521;11555.364454955059;11855.407229638657;12076;12177.634670959886 18:42:28 INFO - PROCESS | 3841 | |3;mootools.html;1413.9222342771143;1494.9850501494982;1524.1997951075687;1664.4257731082773;1809.2220345251544;8252.917470825298;8866.423682460643;9245.907540924589;9267.860982085274;9276.860847087282;8974.955125224382;9366.625334986593;9419.293552983536;9422.481763503005;9445.102715242057;2470.987645061781;2518.9874050629815;2521.218422289087;2523.2934778262115;2526.759957804006;2374.6556749271344;2469.394998225437;2471.0115953618515;2472.085328428484;2476.6285057241466;2424.987875060622;2436.561418944589;2514.823962322638;2517.471331020488;2518.6977562692505;2345.2495201535517;2389.832711710181;2395.47299594089;2429.5748244057218;2435.050330371139;2377.9643305350432;2382.5830479665988;2385.797207237387;2386.2841147655636;2388.976110238893;9150.633974641007;9324.393914395543;9345.76635584109;9405;9556.474393908333;5323.414424413311;5529.060059789846;5529.972350138264;5531.419200983879;5536.307961504812;3518.384282750534;3528.1708798432346;3529.75291729579;3573.6605022522817;3594.245208506217;1200.2318516149671;1220.2495465288873;1227.7605866855968;1239.1016513027982;1252.4927404401233;13340.266285354302;13524.188548687012;13530.120542164728;13553.457861685521;13590.93204533971;4706.552877476634;4860.416749989953;4893.804247830119;4981.203007518852;5027.673201241908;11438.828417573743;11454.312741235386;11456.083513318914;11466.94266528662;11475.139364547693;5592.468715472024;5593.440655934374;5600.047991841397;5601.60788744784;5603.327600687943;4287.828486860491;4612.884677883079;4617.0996655651825;4705.411823522059;5135.024345374367;4152.8131234094535;4228.661707063489;4229.725067870579;4278.657707383464;4290.935635965463;1318.4594316330258;1341.7316536692701;1344.717609302038;1358.959231223065;1363.4069179906771;1003.8193125237527;1008.7074748322905;1023.5956797065118;1028.0233777911014;1055.2402270365324;6249.6875156242;6572.80281591553;6576.177977752781;6786.253512113663;6994.2656021117045;937.5171786529943;957.8706874571983;968.6706519783166;1012.8936461671418;1028.92797504174;4144.7513149211145;4346.500152482505;4523.909521809545;4600.631949444037;4617.953820461752;11928.880711192951;12436.129470937129;12466.81299780504;12467.314297713712;12479.625611231677;11333.433328333715;12359.752804943852;12363.876361236453;12373.257604543756;12373.319467429377;8532.189442003;8803.911960880438;9031.05173956725;9045.68340108093;9054.81890362189;6502.934970650422;6611.768588099393;6622.768203112868;6634.203895532567;6793.252742198255;10610.204234682275;11010.229283950046;11199.60801371948;11218.2147249695;11382.94308528452;10051.69844904654;11193.216474846682;11439.370834604177;11503.53985840557;11576.768464630828;7867.449278550446;8472.534010629475;8765.386422950332;8873.245774109273;9101.226395756436;8892.244159246537;8955.59699813497;8964.32767542424;9137.131972462605;9174.816503670023;8029;8088.797780055545;8131.2681858633005;8199.508029518249;8416.579171041545;8147.9185208147155;8198.795030124294;8425.536595487307;8442.957785211034;8467.83064338722;7548.811279717831;7700.422468314787;7714.421418393531;7715.961420192863;7719.652615632198;5421.457854214547;5484.314460692413;5511.641743286674;5548.861278467909;5669.659820410789;5756.913646295393;5832.737526811388;5900.144479050599;5915.822525324247;5933.881322373442 18:42:28 INFO - PROCESS | 3841 | |4;prototype.html;343.7164339419979;352.07931259755736;354.11647938393696;359.24379181822246;368.02473445369765;5553.22254884316;5582.302212223472;5588.245586845769;5593.272874526311;5631.774729010845;5484.533814625752;5532.806351777688;5558.86102847429;5576.832695019146;5583.664980101197;3814.2943555442193;4125.7524548527035;4232.814811852671;4258.829646814139;4299.742015479082;4254.553271906436;4267.253230684618;4287.164003019412;4287.892802679927;4290.570942905716;4266.274733295331;4267.4025636410925;4287.20686672965;4296.806643701041;4302.548232435596;4200.831966721327;4281.464816897903;4281.828726850922;4295.248331541983;4298.871033868989;3818.2172654606015;3819.5034645495907;3828.617138286177;3830.885073447801;3878.5151856017997;5357.946420535784;5362.410134885159;5386.73066346685;5411.187252270454;5473.534749546293;4803.663743537953;4926.778294976734;4972.154733695244;4981.178105612569;4982.035688765179;5715.428457154293;5718.142278658193;5718.571107166979;5757.107648314476;5777.133429985536;4198.160367926396;4210.536840947493;4216.409702641633;4232.449781528412;4256.723312984646;5901.23283973085;5924.555658325599;5952.494038006775;6063.636181829104;6066.149824303586;4305.966568023714;4312.822024877417;4313.8293354384505;4342.891427714269;4378.978105109518;4402.8679139625865;4411.845585404495;4414.757188354607;4421.801018954155;4422.668299877522;4780.426348838092;4908.337374454474;4913.533214344631;4957.702537847741;4983.9252411213865;5741.425857414225;5753.367129615739;5756.481916627524;5765.913511297334;5805.419458054161;1488.776683497484;1510.124128005754;1518.9392424302905;1529.07490967965;1531.9540413787604;951.286535098676;989.1443901025561;994.4182653147844;997.8403455447094;1015.9441230732233;4862.343583616237;4897.3878265216845;4898.92223710783;4920.704757714549;4940.876478088005;5105.15764898795;5147.819826306061;5153.7066005627885;5162.251473536391;5171.379434467888;4552.429411852905;4818.373611430562;4869.878253043631;4911.803527858917;4934.790976210779;2733.8697191715373;2737.466194092133;2764.3641962348775;2765.6542932133484;2765.6819465761696;5204.672808433825;5220.556252718486;5233.357154636884;5239.266502689627;5257.399775196703;5183.729986153341;5216.295800067019;5690.317161940594;6102.359252278536;6115.4801841843055;5852.502537284294;5926.288845338587;5998.400159983967;6067.211262535842;6154.5384096192965;5959.76160953557;5968.373320801427;5986.551008674454;6004.337354213839;6007.849803754941;5684.573656975661;5897.882042359214;5904.704764761831;5906.080523829686;5950.910736338872;6164.321924588202;6173.777440121463;6175.938240617536;6180.381961803783;6185.567010309235;4391.494978077562;4431.667624928079;4436.689431739747;4474.843380481668;4485.910281794411;5838.824835254949;5883.235029491221;5920.25352521016;5947.197128387612;5950.821475355746;5699.116636921284;5729.885402292014;5755.251817263729;5768.53851691872;5782.653040817564;5685.20407142992;5766.452187042224;5771.451712087345;5820.573959379979;5838.737256823538;5735.4551317624755;5746.482816546531;5757.136429535603;5761.337446193574;5763.423657634203;5828.679422631796;5847.239858818326;5848.853778655568;5861.208736820645;5865.560082993878;5502.202180683858;5664.830055098354;5701.971490142523;5711.885762284648;5717.685527295872 18:42:28 INFO - PROCESS | 3841 | |5;yui.html;805.4764403137963;806.0488623424361;818.9058258300297;864.2955990867443;886.4504007515342;15177.772333415007;17396.652066958653;17567.560810979718;17585.91207043963;17586.208620612073;16047.438339658114;16373.83626163738;16520.173991300446;16545.338186472556;16552.58618534537;839.6221700234888;857.0572370392549;858.5878778186474;858.7337925243163;859.4928991894812;866.2680035370103;870.29071306885;870.9782255443633;872.8647059705725;873.5981448533682;902.3052249767675;906.7461110888929;906.7506435730194;908.2733812949647;908.6819613135383;902.4675441489553;903.2773780975226;904.276578737011;904.760238536785;905.6830109461702;659.2385794407419;660.2374257732326;661.0150875195972;661.2792056658266;661.3849120318102;15417.460388886335;15423.383064677399;15435.3825846966;15454.1500217488;15469.690606187925;14313.427462901576;14354.856451435457;14429.062110962754;14538.491152809707;14595.489157879529;12526.436310366056;12608.873911260862;12639.557615483414;12706.301153436558;13056.543020994219;4138.296489596776;4141.275276826544;4176.0603864130635;4189.560096189917;4226.535081141072;55818.162727559116;57837.71081144567;73205.26794732038;73280;82566;12611.810822837664;12692.555760548337;12762.744745105047;12924.483020679067;13066.34668266583;23197.420064498187;23256.65115023276;23280.30159095196;23444.413889652897;23661.408464788175;9218.21645160169;9235;9246.86129708055;9419.199368053654;9510.28672849539;4842.6368022398465;4850.781714822841;4920.8769780755765;5001.9499805002215;5045.823396181116;3511.2977404519197;3613.2050948791225;3654.7624404413627;3656.1225305926387;3657.7256705747177;644.2880616918331;645.867597142594;649.7595889520908;657.6777379084215;662.718344703499;768.7539987204153;808.8417505079994;822.6627082895984;828.3994104274353;829.7510746775943;10651.84022239667;10732.463376831127;11102.944485277521;11157;11231.26996745209;567.7501899164355;602.7920367473215;625.0748891640397;636.9458546106201;676.6921050921819;5092.312537807423;5123.692578445305;5243.475652434726;5271.209318602164;5278.181881808326;126643.36678316549;131137.34431327783;132144.339278303;133263.3336833329;138868.30565847107;78541.60729196317;110081;115659.42170289264;117202;120782;92281.53859230794;94383.52808235915;95520.52239738757;97737;100443.49778251062;10285.382877027403;11263.324200547993;11341.092712583139;11430.14273929442;11596.710082248012;55367;56746;57040;57141.714291428274;57975;78418.6079069601;81243.59378203072;84769.15230847859;85276;85781.57109214414;12412.689682757642;12653.620391388273;12954.870451295366;13007.934960325516;13316.800247996482;11997;12193.573224937085;16328.101954392147;16345.67308653844;16433.589160271087;12502.062345324319;12511.499540018662;13172.209667419986;13232.536861209812;13381.464741410235;14803.481878134213;15363.923180384027;15575.688486229985;15738.842611574195;15826.28781704803;13028.218306901616;13144.737105258033;13186.142900711424;13219.801702974271;13225.80161297562;12358.752824943631;12687;12746;12832.807507887575;13634.727305454035;12705;13150.21098734079;13279.867201327863;13369.398377072861;13498.797518037041 18:42:28 INFO - PROCESS | 3841 | __end_tp_report 18:42:28 INFO - PROCESS | 3841 | __start_cc_report 18:42:28 INFO - PROCESS | 3841 | _x_x_mozilla_cycle_collect,1048 18:42:28 INFO - PROCESS | 3841 | __end_cc_report 18:42:28 INFO - PROCESS | 3841 | __startTimestamp1483411348387__endTimestamp 18:42:28 INFO - PROCESS | 3841 | 18:42:28 INFO - PROCESS | 3841 | ------- Summary: start ------- 18:42:28 INFO - PROCESS | 3841 | Number of tests: 6 18:42:28 INFO - PROCESS | 3841 | 18:42:28 INFO - PROCESS | 3841 | [#0] dojo.html Cycles:165 Average:5694.45 Median:5864.97 stddev:663.64 (11.3%) stddev-sans-first:634.88 18:42:28 INFO - PROCESS | 3841 | Values: 3147.2 3189.5 3191.7 3216.8 3222.0 5691.4 5798.7 5804.2 5817.9 5837.3 5687.6 5697.9 5740.2 5740.9 5747.7 5175.8 5199.2 5200.3 5265.9 5285.5 5146.5 5273.7 5285.1 5285.5 5288.5 5120.3 5154.6 5170.8 5200.3 5203.3 5227.9 5228.0 5306.3 5316.3 5356.5 5004.9 5150.4 5225.8 5279.1 5293.1 5530.6 5566.4 5612.4 5620.4 5649.8 6049.8 6091.0 6198.9 6203.8 6205.1 6002.3 6222.7 6232.3 6246.4 6249.0 5664.1 5664.5 5666.3 5707.0 5727.2 6250.1 6257.7 6261.1 6261.9 6292.2 5744.1 6019.4 6027.9 6033.8 6040.8 5869.0 6073.4 6108.8 6113.5 6190.0 6124.2 6164.9 6170.0 6177.7 6187.5 6187.7 6189.4 6197.2 6199.6 6201.4 4610.0 4632.8 4642.8 4659.2 4710.9 4141.5 4146.8 4165.0 4179.0 4183.5 5819.3 5819.4 5825.0 5828.2 5831.8 5844.9 5911.4 6131.2 6141.3 6194.6 5945.6 5946.2 5946.4 5968.7 5984.8 5392.2 5395.4 5398.4 5401.1 5406.1 6073.8 6115.3 6188.1 6376.4 6460.5 6127.4 6231.8 6243.0 6243.5 6243.9 6374.6 6387.0 6389.1 6393.8 6397.1 5839.7 5962.5 6116.9 6117.0 6119.0 6099.5 6114.2 6140.5 6211.4 6212.9 6049.6 6055.8 6065.2 6066.7 6068.4 5859.6 5943.2 6002.0 6009.7 6013.7 5793.0 5840.6 5861.8 5865.0 5958.1 5998.3 5999.7 5999.9 6004.0 6007.5 5745.3 5828.4 5840.0 5847.4 5851.4 18:42:28 INFO - PROCESS | 3841 | 18:42:28 INFO - PROCESS | 3841 | [#1] ext.html Cycles:180 Average:62571.24 Median:3850.34 stddev:123207.58 (3199.9%) stddev-sans-first:123465.33 18:42:28 INFO - PROCESS | 3841 | Values: 593.1 622.3 652.9 670.6 679.5 36199.8 47101.5 47971.0 48027.3 48111.5 49396.3 49902.5 49940.5 49950.8 49965.3 8460.6 8656.7 9453.1 9598.4 9600.9 11502.9 11843.2 11847.1 11849.5 11854.7 11652.4 11661.4 11742.7 11764.1 11774.4 11415.3 11458.8 11480.8 11484.9 11485.3 11421.9 11459.3 11476.9 11488.3 11491.9 46023.5 46409.0 46600.0 46654.8 46716.8 3801.0 3808.7 3809.0 3844.2 3900.7 33168.3 35438.1 36010.5 36475.1 36479.5 4694.6 4731.9 4759.5 4882.6 5014.8 372394.1 372516.0 372683.0 374243.0 376275.0 40207.0 41261.6 68093.0 68740.0 68932.7 43219.0 43390.6 43403.0 43430.6 43465.6 17873.4 17987.4 17996.7 18005.4 18024.4 12644.4 12906.2 13399.0 13775.9 14516.3 9911.3 9947.0 9951.8 10008.0 10030.2 3754.4 3848.9 3849.8 3850.9 3875.3 1066.0 1068.8 1096.1 1108.4 1131.5 29803.4 34174.1 35303.1 35570.5 35606.0 1069.0 1088.1 1167.5 1170.3 1199.6 15531.9 15595.6 15677.8 15727.9 15785.8 556249.0 559131.0 559211.0 559860.0 562856.0 65340.0 65712.0 65789.3 65860.7 65887.0 1317.4 1348.8 1406.5 1455.2 1467.6 27222.0 31037.8 31061.7 31195.8 31436.8 56323.7 57165.7 57230.7 57319.0 57353.1 438401.0 439074.0 443503.0 444194.0 445052.0 28278.2 29273.3 29274.1 30100.2 32991.7 45026.0 49698.8 51931.5 53276.7 53538.5 42577.5 43224.0 44655.0 45244.3 46146.3 44114.0 45233.0 45408.5 45778.0 46208.0 43595.6 43740.6 43756.0 43881.0 44009.3 36844.3 39055.4 39350.2 39465.2 39710.0 25827.9 26755.9 31035.8 31791.7 32280.6 18:42:28 INFO - PROCESS | 3841 | 18:42:28 INFO - PROCESS | 3841 | [#2] jquery.html Cycles:180 Average:28362.49 Median:24605.75 stddev:70043.59 (284.7%) stddev-sans-first:70208.64 18:42:28 INFO - PROCESS | 3841 | Values: 413.6 426.4 426.6 427.2 437.8 20309.6 20421.2 20516.3 20574.3 21015.0 19388.6 20169.8 20248.8 20700.2 20864.1 5771.5 6052.5 6059.8 6068.0 6079.9 5849.0 5967.6 5993.2 6010.9 6073.1 5969.0 5978.4 5979.8 5980.5 5981.2 5751.9 5936.9 5980.6 6006.3 6019.9 5420.7 5428.3 5431.8 5431.8 5433.3 24586.0 24625.5 24649.4 24785.5 24835.4 2515.9 2518.4 2552.5 2584.0 2716.3 10637.4 15335.8 15426.3 15615.5 15788.6 2010.5 2068.1 2097.2 2110.5 2133.4 89952.6 93298.5 94628.5 94827.0 97419.0 11838.0 11940.5 12098.4 12203.0 12261.1 17472.7 17551.4 17597.3 17627.0 17648.9 8151.4 8509.5 8560.4 8598.5 8739.6 8473.2 8510.4 8520.9 8652.4 8723.7 5175.7 5276.5 5285.6 5303.7 5322.0 2034.9 2116.5 2122.8 2154.5 2174.1 928.3 981.0 999.0 1030.4 1057.2 19851.0 20219.8 20519.8 20605.6 21080.4 1013.5 1021.8 1051.4 1054.8 1085.3 10024.7 11568.0 11662.6 11775.9 11835.4 374469.0 378925.0 379778.0 469300.0 476319.0 98370.0 100652.0 103642.4 104940.0 105286.5 1050.9 1073.4 1080.6 1105.7 1121.4 18679.6 18978.9 19282.4 19328.3 19523.2 43303.6 43501.3 43585.6 44328.6 45028.3 62086.7 62141.4 62495.7 62991.0 64519.4 9148.5 9547.0 9550.8 9578.1 9617.0 8386.6 12444.5 13720.5 14097.2 14628.9 11189.8 11639.9 11810.7 12136.6 12673.0 11874.0 11884.0 11920.9 12062.5 12281.3 11760.8 11990.3 12129.9 12150.9 12275.6 9005.6 9112.6 9123.0 9422.0 9615.4 11194.7 11555.4 11855.4 12076.0 12177.6 18:42:28 INFO - PROCESS | 3841 | 18:42:28 INFO - PROCESS | 3841 | [#3] mootools.html Cycles:180 Average:6279.11 Median:4562.27 stddev:3650.53 (80.0%) stddev-sans-first:3642.46 18:42:28 INFO - PROCESS | 3841 | Values: 1413.9 1495.0 1524.2 1664.4 1809.2 8252.9 8866.4 9245.9 9267.9 9276.9 8975.0 9366.6 9419.3 9422.5 9445.1 2471.0 2519.0 2521.2 2523.3 2526.8 2374.7 2469.4 2471.0 2472.1 2476.6 2425.0 2436.6 2514.8 2517.5 2518.7 2345.2 2389.8 2395.5 2429.6 2435.1 2378.0 2382.6 2385.8 2386.3 2389.0 9150.6 9324.4 9345.8 9405.0 9556.5 5323.4 5529.1 5530.0 5531.4 5536.3 3518.4 3528.2 3529.8 3573.7 3594.2 1200.2 1220.2 1227.8 1239.1 1252.5 13340.3 13524.2 13530.1 13553.5 13590.9 4706.6 4860.4 4893.8 4981.2 5027.7 11438.8 11454.3 11456.1 11466.9 11475.1 5592.5 5593.4 5600.0 5601.6 5603.3 4287.8 4612.9 4617.1 4705.4 5135.0 4152.8 4228.7 4229.7 4278.7 4290.9 1318.5 1341.7 1344.7 1359.0 1363.4 1003.8 1008.7 1023.6 1028.0 1055.2 6249.7 6572.8 6576.2 6786.3 6994.3 937.5 957.9 968.7 1012.9 1028.9 4144.8 4346.5 4523.9 4600.6 4618.0 11928.9 12436.1 12466.8 12467.3 12479.6 11333.4 12359.8 12363.9 12373.3 12373.3 8532.2 8803.9 9031.1 9045.7 9054.8 6502.9 6611.8 6622.8 6634.2 6793.3 10610.2 11010.2 11199.6 11218.2 11382.9 10051.7 11193.2 11439.4 11503.5 11576.8 7867.4 8472.5 8765.4 8873.2 9101.2 8892.2 8955.6 8964.3 9137.1 9174.8 8029.0 8088.8 8131.3 8199.5 8416.6 8147.9 8198.8 8425.5 8443.0 8467.8 7548.8 7700.4 7714.4 7716.0 7719.7 5421.5 5484.3 5511.6 5548.9 5669.7 5756.9 5832.7 5900.1 5915.8 5933.9 18:42:28 INFO - PROCESS | 3841 | 18:42:28 INFO - PROCESS | 3841 | [#4] prototype.html Cycles:180 Average:4783.03 Median:5236.31 stddev:1398.92 (26.7%) stddev-sans-first:1362.59 18:42:28 INFO - PROCESS | 3841 | Values: 343.7 352.1 354.1 359.2 368.0 5553.2 5582.3 5588.2 5593.3 5631.8 5484.5 5532.8 5558.9 5576.8 5583.7 3814.3 4125.8 4232.8 4258.8 4299.7 4254.6 4267.3 4287.2 4287.9 4290.6 4266.3 4267.4 4287.2 4296.8 4302.5 4200.8 4281.5 4281.8 4295.2 4298.9 3818.2 3819.5 3828.6 3830.9 3878.5 5357.9 5362.4 5386.7 5411.2 5473.5 4803.7 4926.8 4972.2 4981.2 4982.0 5715.4 5718.1 5718.6 5757.1 5777.1 4198.2 4210.5 4216.4 4232.4 4256.7 5901.2 5924.6 5952.5 6063.6 6066.1 4306.0 4312.8 4313.8 4342.9 4379.0 4402.9 4411.8 4414.8 4421.8 4422.7 4780.4 4908.3 4913.5 4957.7 4983.9 5741.4 5753.4 5756.5 5765.9 5805.4 1488.8 1510.1 1518.9 1529.1 1532.0 951.3 989.1 994.4 997.8 1015.9 4862.3 4897.4 4898.9 4920.7 4940.9 5105.2 5147.8 5153.7 5162.3 5171.4 4552.4 4818.4 4869.9 4911.8 4934.8 2733.9 2737.5 2764.4 2765.7 2765.7 5204.7 5220.6 5233.4 5239.3 5257.4 5183.7 5216.3 5690.3 6102.4 6115.5 5852.5 5926.3 5998.4 6067.2 6154.5 5959.8 5968.4 5986.6 6004.3 6007.8 5684.6 5897.9 5904.7 5906.1 5950.9 6164.3 6173.8 6175.9 6180.4 6185.6 4391.5 4431.7 4436.7 4474.8 4485.9 5838.8 5883.2 5920.3 5947.2 5950.8 5699.1 5729.9 5755.3 5768.5 5782.7 5685.2 5766.5 5771.5 5820.6 5838.7 5735.5 5746.5 5757.1 5761.3 5763.4 5828.7 5847.2 5848.9 5861.2 5865.6 5502.2 5664.8 5702.0 5711.9 5717.7 18:42:28 INFO - PROCESS | 3841 | 18:42:28 INFO - PROCESS | 3841 | [#5] yui.html Cycles:180 Average:22482.47 Median:3562.25 stddev:33065.35 (928.2%) stddev-sans-first:33118.05 18:42:28 INFO - PROCESS | 3841 | Values: 805.5 806.0 818.9 864.3 886.5 15177.8 17396.7 17567.6 17585.9 17586.2 16047.4 16373.8 16520.2 16545.3 16552.6 839.6 857.1 858.6 858.7 859.5 866.3 870.3 871.0 872.9 873.6 902.3 906.7 906.8 908.3 908.7 902.5 903.3 904.3 904.8 905.7 659.2 660.2 661.0 661.3 661.4 15417.5 15423.4 15435.4 15454.2 15469.7 14313.4 14354.9 14429.1 14538.5 14595.5 12526.4 12608.9 12639.6 12706.3 13056.5 4138.3 4141.3 4176.1 4189.6 4226.5 55818.2 57837.7 73205.3 73280.0 82566.0 12611.8 12692.6 12762.7 12924.5 13066.3 23197.4 23256.7 23280.3 23444.4 23661.4 9218.2 9235.0 9246.9 9419.2 9510.3 4842.6 4850.8 4920.9 5001.9 5045.8 3511.3 3613.2 3654.8 3656.1 3657.7 644.3 645.9 649.8 657.7 662.7 768.8 808.8 822.7 828.4 829.8 10651.8 10732.5 11102.9 11157.0 11231.3 567.8 602.8 625.1 636.9 676.7 5092.3 5123.7 5243.5 5271.2 5278.2 126643.4 131137.3 132144.3 133263.3 138868.3 78541.6 110081.0 115659.4 117202.0 120782.0 92281.5 94383.5 95520.5 97737.0 100443.5 10285.4 11263.3 11341.1 11430.1 11596.7 55367.0 56746.0 57040.0 57141.7 57975.0 78418.6 81243.6 84769.2 85276.0 85781.6 12412.7 12653.6 12954.9 13007.9 13316.8 11997.0 12193.6 16328.1 16345.7 16433.6 12502.1 12511.5 13172.2 13232.5 13381.5 14803.5 15363.9 15575.7 15738.8 15826.3 13028.2 13144.7 13186.1 13219.8 13225.8 12358.8 12687.0 12746.0 12832.8 13634.7 12705.0 13150.2 13279.9 13369.4 13498.8 18:42:28 INFO - PROCESS | 3841 | -------- Summary: end -------- 18:42:28 INFO - PROCESS | 3841 | 18:42:29 INFO - TEST-INFO | 3841: exit 0 18:42:29 INFO - TEST-OK | dromaeo_css | took 1113320ms 18:42:29 INFO - TEST-START | kraken 18:42:29 INFO - operating with platform_type : linux_ 18:42:29 INFO - Initialising browser for kraken test... 18:42:29 INFO - TEST-INFO | started process 4145 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfboQ6a/profile http://localhost:42467/getInfo.html) 18:42:33 INFO - PROCESS | 4145 | __metrics Screen width/height:1600/1200 18:42:33 INFO - PROCESS | 4145 | colorDepth:24 18:42:33 INFO - PROCESS | 4145 | Browser inner width/height: 1024/697 18:42:33 INFO - PROCESS | 4145 | __metrics 18:42:33 INFO - PROCESS | 4145 | 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" 18:42:33 INFO - PROCESS | 4145 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:42:34 INFO - PROCESS | 4145 | console.error: PushService: 18:42:34 INFO - PROCESS | 4145 | stateChangeProcessEnqueue: Error transitioning state 18:42:34 INFO - PROCESS | 4145 | UnknownError 18:42:34 INFO - TEST-INFO | 4145: exit 0 18:42:34 INFO - Browser initialized. 18:42:34 INFO - Running cycle 1/1 for kraken test... 18:42:34 INFO - TEST-INFO | started process 4233 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfboQ6a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:42:46 INFO - PROCESS | 4233 | RSS: Main: 142651392 18:42:46 INFO - PROCESS | 4233 | 18:42:48 INFO - PROCESS | 4233 | Cycle 1(1): loaded http://localhost:42467/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42467/tests/kraken/driver.html?testName=audio-beat-detection) 18:42:49 INFO - PROCESS | 4233 | RSS: Main: 186257408 18:42:49 INFO - PROCESS | 4233 | 18:42:52 INFO - PROCESS | 4233 | Cycle 1(1): loaded http://localhost:42467/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42467/tests/kraken/driver.html?testName=audio-dft) 18:42:52 INFO - PROCESS | 4233 | RSS: Main: 174264320 18:42:52 INFO - PROCESS | 4233 | 18:42:55 INFO - PROCESS | 4233 | Cycle 1(1): loaded http://localhost:42467/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42467/tests/kraken/driver.html?testName=audio-fft) 18:42:56 INFO - PROCESS | 4233 | RSS: Main: 265379840 18:42:56 INFO - PROCESS | 4233 | 18:42:58 INFO - PROCESS | 4233 | Cycle 1(1): loaded http://localhost:42467/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42467/tests/kraken/driver.html?testName=audio-oscillator) 18:42:58 INFO - PROCESS | 4233 | RSS: Main: 171937792 18:42:58 INFO - PROCESS | 4233 | 18:43:01 INFO - PROCESS | 4233 | Cycle 1(1): loaded http://localhost:42467/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42467/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:43:01 INFO - PROCESS | 4233 | RSS: Main: 173309952 18:43:01 INFO - PROCESS | 4233 | 18:43:04 INFO - PROCESS | 4233 | Cycle 1(1): loaded http://localhost:42467/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42467/tests/kraken/driver.html?testName=imaging-darkroom) 18:43:05 INFO - PROCESS | 4233 | RSS: Main: 209534976 18:43:05 INFO - PROCESS | 4233 | 18:43:08 INFO - PROCESS | 4233 | Cycle 1(1): loaded http://localhost:42467/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42467/tests/kraken/driver.html?testName=imaging-desaturate) 18:43:09 INFO - PROCESS | 4233 | RSS: Main: 208633856 18:43:09 INFO - PROCESS | 4233 | 18:43:11 INFO - PROCESS | 4233 | Cycle 1(1): loaded http://localhost:42467/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42467/tests/kraken/driver.html?testName=json-parse-financial) 18:43:12 INFO - PROCESS | 4233 | RSS: Main: 207925248 18:43:12 INFO - PROCESS | 4233 | 18:43:13 INFO - PROCESS | 4233 | Cycle 1(1): loaded http://localhost:42467/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42467/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:43:14 INFO - PROCESS | 4233 | RSS: Main: 169832448 18:43:14 INFO - PROCESS | 4233 | 18:43:16 INFO - PROCESS | 4233 | Cycle 1(1): loaded http://localhost:42467/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42467/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:43:16 INFO - PROCESS | 4233 | RSS: Main: 177643520 18:43:16 INFO - PROCESS | 4233 | 18:43:18 INFO - PROCESS | 4233 | Cycle 1(1): loaded http://localhost:42467/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42467/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:43:19 INFO - PROCESS | 4233 | RSS: Main: 186982400 18:43:19 INFO - PROCESS | 4233 | 18:43:21 INFO - PROCESS | 4233 | Cycle 1(1): loaded http://localhost:42467/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42467/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:43:22 INFO - PROCESS | 4233 | RSS: Main: 194736128 18:43:22 INFO - PROCESS | 4233 | 18:43:25 INFO - PROCESS | 4233 | Cycle 1(1): loaded http://localhost:42467/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42467/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:43:25 INFO - PROCESS | 4233 | RSS: Main: 191246336 18:43:25 INFO - PROCESS | 4233 | 18:43:27 INFO - PROCESS | 4233 | Cycle 1(1): loaded http://localhost:42467/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42467/tests/kraken/driver.html?testName=ai-astar) 18:43:27 INFO - PROCESS | 4233 | RSS: Main: 243527680 18:43:27 INFO - PROCESS | 4233 | 18:43:27 INFO - PROCESS | 4233 | __start_tp_report 18:43:27 INFO - PROCESS | 4233 | _x_x_mozilla_page_load 18:43:27 INFO - PROCESS | 4233 | _x_x_mozilla_page_load_details 18:43:27 INFO - PROCESS | 4233 | |i|pagename|runs| 18:43:27 INFO - PROCESS | 4233 | |0;ai-astar;111;112;110;109;112;112;111;117;109;110 18:43:27 INFO - PROCESS | 4233 | |1;audio-beat-detection;145;115;116;145;117;124;115;117;156;116 18:43:27 INFO - PROCESS | 4233 | |2;audio-dft;163;163;161;162;161;161;160;161;161;160 18:43:27 INFO - PROCESS | 4233 | |3;audio-fft;76;76;76;107;75;75;75;76;107;74 18:43:27 INFO - PROCESS | 4233 | |4;audio-oscillator;82;81;119;81;82;82;100;82;81;113 18:43:27 INFO - PROCESS | 4233 | |5;imaging-gaussian-blur;118;117;117;116;117;116;117;117;116;116 18:43:27 INFO - PROCESS | 4233 | |6;imaging-darkroom;146;147;147;147;148;149;148;147;148;146 18:43:27 INFO - PROCESS | 4233 | |7;imaging-desaturate;95;95;96;95;95;95;95;95;96;95 18:43:27 INFO - PROCESS | 4233 | |8;json-parse-financial;49;50;50;49;50;50;49;50;50;50 18:43:27 INFO - PROCESS | 4233 | |9;json-stringify-tinderbox;57;57;56;57;57;57;57;57;57;57 18:43:27 INFO - PROCESS | 4233 | |10;stanford-crypto-aes;74;74;73;73;78;73;73;75;73;73 18:43:27 INFO - PROCESS | 4233 | |11;stanford-crypto-ccm;124;125;119;128;119;121;120;120;167;127 18:43:27 INFO - PROCESS | 4233 | |12;stanford-crypto-pbkdf2;155;151;150;150;153;152;150;153;151;154 18:43:27 INFO - PROCESS | 4233 | |13;stanford-crypto-sha256-iterative;61;63;63;63;61;61;62;60;61;62 18:43:27 INFO - PROCESS | 4233 | __end_tp_report 18:43:27 INFO - PROCESS | 4233 | __start_cc_report 18:43:27 INFO - PROCESS | 4233 | _x_x_mozilla_cycle_collect,1684 18:43:27 INFO - PROCESS | 4233 | __end_cc_report 18:43:27 INFO - PROCESS | 4233 | __startTimestamp1483411407662__endTimestamp 18:43:27 INFO - PROCESS | 4233 | 18:43:27 INFO - PROCESS | 4233 | ------- Summary: start ------- 18:43:27 INFO - PROCESS | 4233 | Number of tests: 14 18:43:27 INFO - PROCESS | 4233 | 18:43:27 INFO - PROCESS | 4233 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.50 stddev:2.31 (2.1%) stddev-sans-first:2.45 18:43:27 INFO - PROCESS | 4233 | Values: 111.0 112.0 110.0 109.0 112.0 112.0 111.0 117.0 109.0 110.0 18:43:27 INFO - PROCESS | 4233 | 18:43:27 INFO - PROCESS | 4233 | [#1] audio-beat-detection Cycles:10 Average:126.60 Median:120.50 stddev:15.73 (13.1%) stddev-sans-first:15.21 18:43:27 INFO - PROCESS | 4233 | Values: 145.0 115.0 116.0 145.0 117.0 124.0 115.0 117.0 156.0 116.0 18:43:27 INFO - PROCESS | 4233 | 18:43:27 INFO - PROCESS | 4233 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:0.93 18:43:27 INFO - PROCESS | 4233 | Values: 163.0 163.0 161.0 162.0 161.0 161.0 160.0 161.0 161.0 160.0 18:43:27 INFO - PROCESS | 4233 | 18:43:27 INFO - PROCESS | 4233 | [#3] audio-fft Cycles:10 Average:81.70 Median:75.50 stddev:13.35 (17.7%) stddev-sans-first:14.00 18:43:27 INFO - PROCESS | 4233 | Values: 76.0 76.0 76.0 107.0 75.0 75.0 75.0 76.0 107.0 74.0 18:43:27 INFO - PROCESS | 4233 | 18:43:27 INFO - PROCESS | 4233 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:81.50 stddev:14.79 (18.1%) stddev-sans-first:15.38 18:43:27 INFO - PROCESS | 4233 | Values: 82.0 81.0 119.0 81.0 82.0 82.0 100.0 82.0 81.0 113.0 18:43:27 INFO - PROCESS | 4233 | 18:43:27 INFO - PROCESS | 4233 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.53 18:43:27 INFO - PROCESS | 4233 | Values: 118.0 117.0 117.0 116.0 117.0 116.0 117.0 117.0 116.0 116.0 18:43:27 INFO - PROCESS | 4233 | 18:43:27 INFO - PROCESS | 4233 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.50 stddev:0.95 (0.6%) stddev-sans-first:0.88 18:43:27 INFO - PROCESS | 4233 | Values: 146.0 147.0 147.0 147.0 148.0 149.0 148.0 147.0 148.0 146.0 18:43:27 INFO - PROCESS | 4233 | 18:43:27 INFO - PROCESS | 4233 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:43:27 INFO - PROCESS | 4233 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 18:43:27 INFO - PROCESS | 4233 | 18:43:27 INFO - PROCESS | 4233 | [#8] json-parse-financial Cycles:10 Average:49.70 Median:50.00 stddev:0.48 (1.0%) stddev-sans-first:0.44 18:43:27 INFO - PROCESS | 4233 | Values: 49.0 50.0 50.0 49.0 50.0 50.0 49.0 50.0 50.0 50.0 18:43:27 INFO - PROCESS | 4233 | 18:43:27 INFO - PROCESS | 4233 | [#9] json-stringify-tinderbox Cycles:10 Average:56.90 Median:57.00 stddev:0.32 (0.6%) stddev-sans-first:0.33 18:43:27 INFO - PROCESS | 4233 | Values: 57.0 57.0 56.0 57.0 57.0 57.0 57.0 57.0 57.0 57.0 18:43:27 INFO - PROCESS | 4233 | 18:43:27 INFO - PROCESS | 4233 | [#10] stanford-crypto-aes Cycles:10 Average:73.90 Median:73.50 stddev:1.60 (2.2%) stddev-sans-first:1.69 18:43:27 INFO - PROCESS | 4233 | Values: 74.0 74.0 73.0 73.0 78.0 73.0 73.0 75.0 73.0 73.0 18:43:27 INFO - PROCESS | 4233 | 18:43:27 INFO - PROCESS | 4233 | [#11] stanford-crypto-ccm Cycles:10 Average:127.00 Median:124.50 stddev:14.44 (11.6%) stddev-sans-first:15.27 18:43:27 INFO - PROCESS | 4233 | Values: 124.0 125.0 119.0 128.0 119.0 121.0 120.0 120.0 167.0 127.0 18:43:27 INFO - PROCESS | 4233 | 18:43:27 INFO - PROCESS | 4233 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.90 Median:152.50 stddev:1.79 (1.2%) stddev-sans-first:1.51 18:43:27 INFO - PROCESS | 4233 | Values: 155.0 151.0 150.0 150.0 153.0 152.0 150.0 153.0 151.0 154.0 18:43:27 INFO - PROCESS | 4233 | 18:43:27 INFO - PROCESS | 4233 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:62.00 stddev:1.06 (1.7%) stddev-sans-first:1.09 18:43:27 INFO - PROCESS | 4233 | Values: 61.0 63.0 63.0 63.0 61.0 61.0 62.0 60.0 61.0 62.0 18:43:27 INFO - PROCESS | 4233 | -------- Summary: end -------- 18:43:27 INFO - PROCESS | 4233 | 18:43:28 INFO - TEST-INFO | 4233: exit 0 18:43:28 INFO - TEST-OK | kraken | took 59614ms 18:43:28 INFO - SUITE-END | took 1172s 18:43:28 INFO - Completed test suite (00:19:33) 18:43:28 INFO - javascript benchmark 18:43:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3147.213196700825, 3189.505626627873, 3191.7287030602383, 3216.823074730889, 3221.951670724938, 5691.3739488656265, 5798.681072541009, 5804.245448091753, 5817.883642327161, 5837.328707198668, 5687.601867869251, 5697.857553561163, 5740.225069615601, 5740.942590574093, 5747.741351639176, 5175.767090480928, 5199.194124910645, 5200.297959775438, 5265.9473405266035, 5285.524302812746, 5146.536811686948, 5273.657212281209, 5285.101532739424, 5285.497877701612, 5288.524032837044, 5120.308758317636, 5154.639175257734, 5170.819021334254, 5200.349956255468, 5203.271541984106, 5227.86930326743, 5227.973860130694, 5306.283651707009, 5316.255724198615, 5356.464353564613, 5004.899902001939, 5150.356205474316, 5225.817096401608, 5279.1289437243195, 5293.073712100367, 5530.640508366983, 5566.359868615121, 5612.410696876851, 5620.4098569650005, 5649.802256921029, 6049.848753781147, 6091.02543593023, 6198.876022479525, 6203.751849925998, 6205.0692396140485, 6002.309734380558, 6222.688865556704, 6232.252129744415, 6246.437820596123, 6248.96875515624, 5664.12206108058, 5664.54683625309, 5666.263385759866, 5706.971465142731, 5727.1695604138, 6250.093736408196, 6257.74969001244, 6261.060840873905, 6261.937380626136, 6292.244930608264, 5744.0522313817755, 6019.367966363557, 6027.945109605801, 6033.849153771103, 6040.7885724000325, 5869.0, 6073.392660733891, 6108.7670647404, 6113.5327720388195, 6189.96905015472, 6124.203853499017, 6164.907526387108, 6169.96915015422, 6177.69111544421, 6187.535934804908, 6187.659678717624, 6189.35011823761, 6197.163382943335, 6199.5970261933735, 6201.410865967726, 4610.031893302377, 4632.795473176998, 4642.837500687459, 4659.161350956792, 4710.88222794426, 4141.503019637663, 4146.751194928314, 4165.000399904001, 4178.997040710268, 4183.477065366829, 5819.330776960619, 5819.418058194146, 5825.03886858672, 5828.242328497268, 5831.825045248649, 5844.853878653068, 5911.408859114054, 6131.193168498116, 6141.263048434216, 6194.628322300587, 5945.613535120204, 5946.167536544887, 5946.43508866657, 5968.671723055274, 5984.760609575568, 5392.245085688007, 5395.379531353866, 5398.352197736218, 5401.108817045144, 5406.135018397038, 6073.848153796102, 6115.301188036035, 6188.133661287513, 6376.3623637635865, 6460.483161347081, 6127.387261273837, 6231.844203894939, 6242.968785156045, 6243.531735119794, 6243.938530449926, 6374.649394283359, 6387.041943708295, 6389.073584330339, 6393.840153996187, 6397.1363865879375, 5839.737211825393, 5962.463378295801, 6116.877662446637, 6117.0, 6118.969405152946, 6099.512039036955, 6114.175715401414, 6140.539459540463, 6211.378862113753, 6212.9068063978175, 6049.576529643059, 6055.848603784941, 6065.150878876872, 6066.696665166635, 6068.423499767515, 5859.648421094748, 5943.197668314722, 6002.039673652194, 6009.729562169626, 6013.699315034143, 5793.015187418233, 5840.591158618856, 5861.7655293787775, 5864.970675146597, 5958.136070269873, 5998.310194327533, 5999.70001499932, 5999.91000135007, 6003.969980150246, 6007.48936340416, 5745.3105627324985, 5828.446297601721, 5840.0, 5847.44449277318, 5851.385604511465], "value": 5647.682468690905, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.098490294752, 622.2532960447463, 652.9183852018498, 670.5507310102231, 679.5039621076611, 36199.819000904994, 47101.528984710145, 47971.0405791884, 48027.27959080617, 48111.51888481114, 49396.259056114184, 49902.50097499024, 49940.50059499404, 49950.75024624881, 49965.25052124212, 8460.619272132768, 8656.740297791077, 9453.054694530561, 9598.376105553152, 9600.903990960105, 11502.942485287562, 11843.170978031541, 11847.052235821157, 11849.526018959274, 11854.703632409173, 11652.359120248382, 11661.358625275607, 11742.706432339217, 11764.058875289998, 11774.352410617412, 11415.315081095161, 11458.828117578243, 11480.770384592344, 11484.885151148465, 11485.310881347104, 11421.885781142166, 11459.312441253469, 11476.885231147666, 11488.253263537843, 11491.942540287246, 46023.53976460226, 46409.0, 46600.0, 46654.76672616649, 46716.766416168044, 3801.0, 3808.733388662795, 3809.047738065484, 3844.154286057063, 3900.668443182333, 33168.3366332672, 35438.11404714903, 36010.45984310238, 36475.08812279688, 36479.4528082079, 4694.554017368345, 4731.905361892777, 4759.524047595248, 4882.609391248727, 5014.799408023675, 372394.13802931085, 372516.0, 372683.0, 374243.0, 376275.0, 40207.0, 41261.587384126375, 68093.0, 68740.0, 68932.655336724, 43219.0, 43390.56609433929, 43403.0, 43430.56569434265, 43465.56534434615, 17873.374431894823, 17987.370442034466, 17996.730049049274, 18005.369812056517, 18024.36914707979, 12644.367781610883, 12906.161099528501, 13399.0, 13775.862241377457, 14516.274186290855, 9911.306208565475, 9947.0, 9951.751206219902, 10008.0, 10030.247731420173, 3754.436834474796, 3848.942265866014, 3849.7690138591215, 3850.9037274067814, 3875.2830726315724, 1065.9520321585546, 1068.770214403907, 1096.0738176241064, 1108.3848464102339, 1131.5247596009697, 29803.40393192124, 34174.14564635854, 35303.11742206466, 35570.46644300337, 35606.0, 1069.0485467933545, 1088.1240601315858, 1167.5329868052847, 1170.2568868768394, 1199.6401079676061, 15531.912766106265, 15595.610109747347, 15677.843221567866, 15727.921360393353, 15785.842141578667, 556249.0, 559131.0, 559211.0, 559860.0, 562856.0, 65340.01989970154, 65712.0, 65789.34210657928, 65860.67069664621, 65887.0, 1317.3544962968076, 1348.7504811609883, 1406.4514839212716, 1455.163281113364, 1467.5597320803715, 27222.0, 31037.844810776704, 31061.689383106783, 31195.84402077975, 31436.842815785774, 56323.71838140783, 57165.714171428874, 57230.7138464305, 57319.0, 57353.13970290365, 438401.0, 439074.0, 443503.0, 444194.0, 445052.0, 28278.15165545037, 29273.268168295963, 29274.121776346743, 30100.247493811952, 32991.67008329982, 45026.0, 49698.75150624224, 51931.480685194176, 53276.73361633167, 53538.46461535335, 42577.54560845408, 43224.0, 44655.0, 45244.32133517934, 46146.307805382276, 44114.0, 45233.0, 45408.54591454043, 45778.0, 46208.0, 43595.56404436042, 43740.562594374926, 43756.0, 43881.0, 44009.33985990148, 36844.26311473809, 39055.41416878806, 39350.212995739355, 39465.210695786496, 39710.0, 25827.870860645577, 26755.866220668773, 31035.84482077575, 31791.682083179796, 32280.576360865496], "value": 20748.281151122457, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.58847946293423, 426.4200687065588, 426.64801538730546, 427.17128770185826, 437.8445651793613, 20309.593808123827, 20421.183152673875, 20516.28193013245, 20574.279900203495, 21015.0, 19388.612227755435, 20169.798302016974, 20248.797512024874, 20700.171993120257, 20864.061117249723, 5771.480566749002, 6052.455279024887, 6059.848503787419, 6068.0291153415465, 6079.939200607982, 5848.970755146218, 5967.552433567478, 5993.160957465958, 6010.909836352458, 6073.058675905242, 5969.044952807551, 5978.432048955342, 5979.820605381846, 5980.461758441739, 5981.222441082653, 5751.942480575182, 5936.940630593682, 5980.581359304851, 6006.309274433453, 6019.939800602026, 5420.674759514442, 5428.267183930158, 5431.8098866539485, 5431.809886653988, 5433.347998240197, 24586.0, 24625.507489850104, 24649.38376540583, 24785.504289914283, 24835.379115522075, 2515.861627610481, 2518.4333524956924, 2552.4767422678306, 2583.9612405813928, 2716.2657320375984, 10637.414942178177, 15335.846641533553, 15426.305816238297, 15615.453459128876, 15788.605284867856, 2010.5275260313815, 2068.1210485543734, 2097.2345094040716, 2110.504031552584, 2133.4133113393787, 89952.55023724904, 93298.53350733203, 94628.52685736596, 94827.0, 97419.0, 11838.0, 11940.52237910474, 12098.395080246128, 12203.0, 12261.080418968613, 17472.73790893112, 17551.385701500385, 17597.296108155788, 17627.030513321635, 17648.911755441397, 8151.429399941947, 8509.489430634181, 8560.400771945904, 8598.527081010605, 8739.60671769772, 8473.23740863313, 8510.404271701045, 8520.8721869172, 8652.394332396672, 8723.651053957898, 5175.741212939338, 5276.472352764769, 5285.577153827761, 5303.655262407931, 5322.0, 2034.9287774927807, 2116.4920419099303, 2122.787721227865, 2154.493693981913, 2174.1086154676823, 928.3084102343797, 980.9509524523745, 998.9700308990742, 1030.3972176276811, 1057.2070946789909, 19851.0, 20219.797802021792, 20519.794802052085, 20605.587888242153, 21080.367588972356, 1013.5287091502487, 1021.7854250607455, 1051.4007016000805, 1054.7679510507676, 1085.3216739537788, 10024.699259022242, 11568.0, 11662.591809286634, 11775.88224117765, 11835.408229588485, 374469.0, 378925.0, 379778.0, 469300.0, 476319.0, 98370.0, 100652.0, 103642.44536331961, 104940.0, 105286.47356763168, 1050.9054185123375, 1073.4042606353548, 1080.621782376162, 1105.7125147461718, 1121.4056550028497, 18679.626407472046, 18978.905105474936, 19282.421527354203, 19328.323508677127, 19523.21907123756, 43303.566964329955, 43501.3474797872, 43585.56414435942, 44328.55671443373, 45028.32457513206, 62086.689566551875, 62141.378586213556, 62495.687521562104, 62991.0, 64519.35480645134, 9148.496832674267, 9547.0, 9550.761230969003, 9578.090081442251, 9617.0, 8386.622601982803, 12444.502219911465, 13720.519781807589, 14097.154170749787, 14628.926855365655, 11189.832152517556, 11639.88360116388, 11810.704732381415, 12136.635900922987, 12673.049521285757, 11874.0, 11884.049276058067, 11920.940395297968, 12062.51749929993, 12281.324527151093, 11760.76478470443, 11990.280583165038, 12129.939350303192, 12150.878491215328, 12275.631731048083, 9005.55911054228, 9112.589933452879, 9123.0, 9422.0, 9615.375000624937, 11194.66416007521, 11555.364454955059, 11855.407229638657, 12076.0, 12177.634670959886], "value": 9821.090864350914, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1413.9222342771143, 1494.9850501494982, 1524.1997951075687, 1664.4257731082773, 1809.2220345251544, 8252.917470825298, 8866.423682460643, 9245.907540924589, 9267.860982085274, 9276.860847087282, 8974.955125224382, 9366.625334986593, 9419.293552983536, 9422.481763503005, 9445.102715242057, 2470.987645061781, 2518.9874050629815, 2521.218422289087, 2523.2934778262115, 2526.759957804006, 2374.6556749271344, 2469.394998225437, 2471.0115953618515, 2472.085328428484, 2476.6285057241466, 2424.987875060622, 2436.561418944589, 2514.823962322638, 2517.471331020488, 2518.6977562692505, 2345.2495201535517, 2389.832711710181, 2395.47299594089, 2429.5748244057218, 2435.050330371139, 2377.9643305350432, 2382.5830479665988, 2385.797207237387, 2386.2841147655636, 2388.976110238893, 9150.633974641007, 9324.393914395543, 9345.76635584109, 9405.0, 9556.474393908333, 5323.414424413311, 5529.060059789846, 5529.972350138264, 5531.419200983879, 5536.307961504812, 3518.384282750534, 3528.1708798432346, 3529.75291729579, 3573.6605022522817, 3594.245208506217, 1200.2318516149671, 1220.2495465288873, 1227.7605866855968, 1239.1016513027982, 1252.4927404401233, 13340.266285354302, 13524.188548687012, 13530.120542164728, 13553.457861685521, 13590.93204533971, 4706.552877476634, 4860.416749989953, 4893.804247830119, 4981.203007518852, 5027.673201241908, 11438.828417573743, 11454.312741235386, 11456.083513318914, 11466.94266528662, 11475.139364547693, 5592.468715472024, 5593.440655934374, 5600.047991841397, 5601.60788744784, 5603.327600687943, 4287.828486860491, 4612.884677883079, 4617.0996655651825, 4705.411823522059, 5135.024345374367, 4152.8131234094535, 4228.661707063489, 4229.725067870579, 4278.657707383464, 4290.935635965463, 1318.4594316330258, 1341.7316536692701, 1344.717609302038, 1358.959231223065, 1363.4069179906771, 1003.8193125237527, 1008.7074748322905, 1023.5956797065118, 1028.0233777911014, 1055.2402270365324, 6249.6875156242, 6572.80281591553, 6576.177977752781, 6786.253512113663, 6994.2656021117045, 937.5171786529943, 957.8706874571983, 968.6706519783166, 1012.8936461671418, 1028.92797504174, 4144.7513149211145, 4346.500152482505, 4523.909521809545, 4600.631949444037, 4617.953820461752, 11928.880711192951, 12436.129470937129, 12466.81299780504, 12467.314297713712, 12479.625611231677, 11333.433328333715, 12359.752804943852, 12363.876361236453, 12373.257604543756, 12373.319467429377, 8532.189442003, 8803.911960880438, 9031.05173956725, 9045.68340108093, 9054.81890362189, 6502.934970650422, 6611.768588099393, 6622.768203112868, 6634.203895532567, 6793.252742198255, 10610.204234682275, 11010.229283950046, 11199.60801371948, 11218.2147249695, 11382.94308528452, 10051.69844904654, 11193.216474846682, 11439.370834604177, 11503.53985840557, 11576.768464630828, 7867.449278550446, 8472.534010629475, 8765.386422950332, 8873.245774109273, 9101.226395756436, 8892.244159246537, 8955.59699813497, 8964.32767542424, 9137.131972462605, 9174.816503670023, 8029.0, 8088.797780055545, 8131.2681858633005, 8199.508029518249, 8416.579171041545, 8147.9185208147155, 8198.795030124294, 8425.536595487307, 8442.957785211034, 8467.83064338722, 7548.811279717831, 7700.422468314787, 7714.421418393531, 7715.961420192863, 7719.652615632198, 5421.457854214547, 5484.314460692413, 5511.641743286674, 5548.861278467909, 5669.659820410789, 5756.913646295393, 5832.737526811388, 5900.144479050599, 5915.822525324247, 5933.881322373442], "value": 4979.932663447903, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.7164339419979, 352.07931259755736, 354.11647938393696, 359.24379181822246, 368.02473445369765, 5553.22254884316, 5582.302212223472, 5588.245586845769, 5593.272874526311, 5631.774729010845, 5484.533814625752, 5532.806351777688, 5558.86102847429, 5576.832695019146, 5583.664980101197, 3814.2943555442193, 4125.7524548527035, 4232.814811852671, 4258.829646814139, 4299.742015479082, 4254.553271906436, 4267.253230684618, 4287.164003019412, 4287.892802679927, 4290.570942905716, 4266.274733295331, 4267.4025636410925, 4287.20686672965, 4296.806643701041, 4302.548232435596, 4200.831966721327, 4281.464816897903, 4281.828726850922, 4295.248331541983, 4298.871033868989, 3818.2172654606015, 3819.5034645495907, 3828.617138286177, 3830.885073447801, 3878.5151856017997, 5357.946420535784, 5362.410134885159, 5386.73066346685, 5411.187252270454, 5473.534749546293, 4803.663743537953, 4926.778294976734, 4972.154733695244, 4981.178105612569, 4982.035688765179, 5715.428457154293, 5718.142278658193, 5718.571107166979, 5757.107648314476, 5777.133429985536, 4198.160367926396, 4210.536840947493, 4216.409702641633, 4232.449781528412, 4256.723312984646, 5901.23283973085, 5924.555658325599, 5952.494038006775, 6063.636181829104, 6066.149824303586, 4305.966568023714, 4312.822024877417, 4313.8293354384505, 4342.891427714269, 4378.978105109518, 4402.8679139625865, 4411.845585404495, 4414.757188354607, 4421.801018954155, 4422.668299877522, 4780.426348838092, 4908.337374454474, 4913.533214344631, 4957.702537847741, 4983.9252411213865, 5741.425857414225, 5753.367129615739, 5756.481916627524, 5765.913511297334, 5805.419458054161, 1488.776683497484, 1510.124128005754, 1518.9392424302905, 1529.07490967965, 1531.9540413787604, 951.286535098676, 989.1443901025561, 994.4182653147844, 997.8403455447094, 1015.9441230732233, 4862.343583616237, 4897.3878265216845, 4898.92223710783, 4920.704757714549, 4940.876478088005, 5105.15764898795, 5147.819826306061, 5153.7066005627885, 5162.251473536391, 5171.379434467888, 4552.429411852905, 4818.373611430562, 4869.878253043631, 4911.803527858917, 4934.790976210779, 2733.8697191715373, 2737.466194092133, 2764.3641962348775, 2765.6542932133484, 2765.6819465761696, 5204.672808433825, 5220.556252718486, 5233.357154636884, 5239.266502689627, 5257.399775196703, 5183.729986153341, 5216.295800067019, 5690.317161940594, 6102.359252278536, 6115.4801841843055, 5852.502537284294, 5926.288845338587, 5998.400159983967, 6067.211262535842, 6154.5384096192965, 5959.76160953557, 5968.373320801427, 5986.551008674454, 6004.337354213839, 6007.849803754941, 5684.573656975661, 5897.882042359214, 5904.704764761831, 5906.080523829686, 5950.910736338872, 6164.321924588202, 6173.777440121463, 6175.938240617536, 6180.381961803783, 6185.567010309235, 4391.494978077562, 4431.667624928079, 4436.689431739747, 4474.843380481668, 4485.910281794411, 5838.824835254949, 5883.235029491221, 5920.25352521016, 5947.197128387612, 5950.821475355746, 5699.116636921284, 5729.885402292014, 5755.251817263729, 5768.53851691872, 5782.653040817564, 5685.20407142992, 5766.452187042224, 5771.451712087345, 5820.573959379979, 5838.737256823538, 5735.4551317624755, 5746.482816546531, 5757.136429535603, 5761.337446193574, 5763.423657634203, 5828.679422631796, 5847.239858818326, 5848.853778655568, 5861.208736820645, 5865.560082993878, 5502.202180683858, 5664.830055098354, 5701.971490142523, 5711.885762284648, 5717.685527295872], "value": 4344.416936089432, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [805.4764403137963, 806.0488623424361, 818.9058258300297, 864.2955990867443, 886.4504007515342, 15177.772333415007, 17396.652066958653, 17567.560810979718, 17585.91207043963, 17586.208620612073, 16047.438339658114, 16373.83626163738, 16520.173991300446, 16545.338186472556, 16552.58618534537, 839.6221700234888, 857.0572370392549, 858.5878778186474, 858.7337925243163, 859.4928991894812, 866.2680035370103, 870.29071306885, 870.9782255443633, 872.8647059705725, 873.5981448533682, 902.3052249767675, 906.7461110888929, 906.7506435730194, 908.2733812949647, 908.6819613135383, 902.4675441489553, 903.2773780975226, 904.276578737011, 904.760238536785, 905.6830109461702, 659.2385794407419, 660.2374257732326, 661.0150875195972, 661.2792056658266, 661.3849120318102, 15417.460388886335, 15423.383064677399, 15435.3825846966, 15454.1500217488, 15469.690606187925, 14313.427462901576, 14354.856451435457, 14429.062110962754, 14538.491152809707, 14595.489157879529, 12526.436310366056, 12608.873911260862, 12639.557615483414, 12706.301153436558, 13056.543020994219, 4138.296489596776, 4141.275276826544, 4176.0603864130635, 4189.560096189917, 4226.535081141072, 55818.162727559116, 57837.71081144567, 73205.26794732038, 73280.0, 82566.0, 12611.810822837664, 12692.555760548337, 12762.744745105047, 12924.483020679067, 13066.34668266583, 23197.420064498187, 23256.65115023276, 23280.30159095196, 23444.413889652897, 23661.408464788175, 9218.21645160169, 9235.0, 9246.86129708055, 9419.199368053654, 9510.28672849539, 4842.6368022398465, 4850.781714822841, 4920.8769780755765, 5001.9499805002215, 5045.823396181116, 3511.2977404519197, 3613.2050948791225, 3654.7624404413627, 3656.1225305926387, 3657.7256705747177, 644.2880616918331, 645.867597142594, 649.7595889520908, 657.6777379084215, 662.718344703499, 768.7539987204153, 808.8417505079994, 822.6627082895984, 828.3994104274353, 829.7510746775943, 10651.84022239667, 10732.463376831127, 11102.944485277521, 11157.0, 11231.26996745209, 567.7501899164355, 602.7920367473215, 625.0748891640397, 636.9458546106201, 676.6921050921819, 5092.312537807423, 5123.692578445305, 5243.475652434726, 5271.209318602164, 5278.181881808326, 126643.36678316549, 131137.34431327783, 132144.339278303, 133263.3336833329, 138868.30565847107, 78541.60729196317, 110081.0, 115659.42170289264, 117202.0, 120782.0, 92281.53859230794, 94383.52808235915, 95520.52239738757, 97737.0, 100443.49778251062, 10285.382877027403, 11263.324200547993, 11341.092712583139, 11430.14273929442, 11596.710082248012, 55367.0, 56746.0, 57040.0, 57141.714291428274, 57975.0, 78418.6079069601, 81243.59378203072, 84769.15230847859, 85276.0, 85781.57109214414, 12412.689682757642, 12653.620391388273, 12954.870451295366, 13007.934960325516, 13316.800247996482, 11997.0, 12193.573224937085, 16328.101954392147, 16345.67308653844, 16433.589160271087, 12502.062345324319, 12511.499540018662, 13172.209667419986, 13232.536861209812, 13381.464741410235, 14803.481878134213, 15363.923180384027, 15575.688486229985, 15738.842611574195, 15826.28781704803, 13028.218306901616, 13144.737105258033, 13186.142900711424, 13219.801702974271, 13225.80161297562, 12358.752824943631, 12687.0, 12746.0, 12832.807507887575, 13634.727305454035, 12705.0, 13150.21098734079, 13279.867201327863, 13369.398377072861, 13498.797518037041], "value": 8070.451079286573, "unit": "score"}], "name": "dromaeo_css", "value": 7653.344145329011, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 110.0, 109.0, 112.0, 112.0, 111.0, 117.0, 109.0, 110.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 115.0, 116.0, 145.0, 117.0, 124.0, 115.0, 117.0, 156.0, 116.0], "value": 126.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 161.0, 162.0, 161.0, 161.0, 160.0, 161.0, 161.0, 160.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 107.0, 75.0, 75.0, 75.0, 76.0, 107.0, 74.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 119.0, 81.0, 82.0, 82.0, 100.0, 82.0, 81.0, 113.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 116.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 147.0, 148.0, 149.0, 148.0, 147.0, 148.0, 146.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 50.0, 49.0, 50.0, 50.0, 49.0, 50.0, 50.0, 50.0], "value": 49.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 56.0, 57.0, 57.0, 57.0, 57.0, 57.0, 57.0, 57.0], "value": 56.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 73.0, 78.0, 73.0, 73.0, 75.0, 73.0, 73.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 125.0, 119.0, 128.0, 119.0, 121.0, 120.0, 120.0, 167.0, 127.0], "value": 127.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 151.0, 150.0, 150.0, 153.0, 152.0, 150.0, 153.0, 151.0, 154.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 63.0, 63.0, 61.0, 61.0, 62.0, 60.0, 61.0, 62.0], "value": 61.7, "unit": "score"}], "name": "kraken", "value": 1451.5000000000005, "alertThreshold": 2.0}]} 18:43:28 INFO - Return code: 0 18:43:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:43:28 INFO - # TBPL SUCCESS # 18:43:28 INFO - Running post-action listener: _resource_record_post_action 18:43:28 INFO - [mozharness: 2017-01-03 02:43:28.965736Z] Finished run-tests step (success) 18:43:28 INFO - Running post-run listener: _resource_record_post_run 18:43:29 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 320557056; Read time: 8; Write time: 169096 18:43:29 INFO - TinderboxPrint: CPU usage
12.9% 18:43:29 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 18:43:29 INFO - TinderboxPrint: I/O write bytes / time
320,557,056 / 169,096 18:43:29 INFO - TinderboxPrint: CPU idle
8,249.6 (87.0%) 18:43:29 INFO - TinderboxPrint: CPU user
1,184.9 (12.5%) 18:43:29 INFO - TinderboxPrint: Swap in / out
0 / 0 18:43:29 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 96735232; Read time: 0; Write time: 67572 18:43:29 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 219426816; Read time: 8; Write time: 101184 18:43:29 INFO - Running post-run listener: _upload_blobber_files 18:43:29 INFO - Blob upload gear active. 18:43:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:43:29 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 18:43:29 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'] 18:43:29 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 18:43:30 INFO - (blobuploader) - INFO - Open directory for files ... 18:43:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:43:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:43:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:43:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:43:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:43:30 INFO - (blobuploader) - INFO - Done attempting. 18:43:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:43:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:43:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:43:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:43:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:43:31 INFO - (blobuploader) - INFO - Done attempting. 18:43:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:43:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:43:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:43:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:43:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:43:35 INFO - (blobuploader) - INFO - Done attempting. 18:43:35 INFO - (blobuploader) - INFO - Iteration through files over. 18:43:35 INFO - Return code: 0 18:43:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:43:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:43:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/616a30b9cb6b0c1d9bc7c128208ad21994b203a7e19db9d20981dd7a88630e882aa24fcdadf6f0ca5c8e0daf1c227081f4d9cc7c7fff09421c9c168582debadb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed659de655fb868e3f747d7d19d43a6ab82ec67fef7a10dc3f212552ae4d92407683c226a9c4c1a932ff082de284d3cada8cb625f73382d12d904be623cddc48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/088b55a5e1e0e6021ca94bc4ff949ce849603cf3d97c3829e88152aecf0e096f45254921742a325d707db8148bbcae4e489e2316109319caef7fa5a553cbb3c6"} 18:43:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:43:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:43:35 INFO - Contents: 18:43:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/616a30b9cb6b0c1d9bc7c128208ad21994b203a7e19db9d20981dd7a88630e882aa24fcdadf6f0ca5c8e0daf1c227081f4d9cc7c7fff09421c9c168582debadb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed659de655fb868e3f747d7d19d43a6ab82ec67fef7a10dc3f212552ae4d92407683c226a9c4c1a932ff082de284d3cada8cb625f73382d12d904be623cddc48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/088b55a5e1e0e6021ca94bc4ff949ce849603cf3d97c3829e88152aecf0e096f45254921742a325d707db8148bbcae4e489e2316109319caef7fa5a553cbb3c6"} 18:43:35 INFO - Running post-run listener: copy_logs_to_upload_dir 18:43:35 INFO - Copying logs to upload dir... 18:43:35 INFO - mkdir: /builds/slave/test/build/upload/logs 18:43:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1229.961699 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2017-01-02 18:43:32.123679) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-02 18:43:32.127532) ========= 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=769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079 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/616a30b9cb6b0c1d9bc7c128208ad21994b203a7e19db9d20981dd7a88630e882aa24fcdadf6f0ca5c8e0daf1c227081f4d9cc7c7fff09421c9c168582debadb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed659de655fb868e3f747d7d19d43a6ab82ec67fef7a10dc3f212552ae4d92407683c226a9c4c1a932ff082de284d3cada8cb625f73382d12d904be623cddc48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/088b55a5e1e0e6021ca94bc4ff949ce849603cf3d97c3829e88152aecf0e096f45254921742a325d707db8148bbcae4e489e2316109319caef7fa5a553cbb3c6"} build_url:https://queue.taskcluster.net/v1/task/Ei2bZukxTK2E6jBqXUGwBw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011474 build_url: 'https://queue.taskcluster.net/v1/task/Ei2bZukxTK2E6jBqXUGwBw/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/616a30b9cb6b0c1d9bc7c128208ad21994b203a7e19db9d20981dd7a88630e882aa24fcdadf6f0ca5c8e0daf1c227081f4d9cc7c7fff09421c9c168582debadb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed659de655fb868e3f747d7d19d43a6ab82ec67fef7a10dc3f212552ae4d92407683c226a9c4c1a932ff082de284d3cada8cb625f73382d12d904be623cddc48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/088b55a5e1e0e6021ca94bc4ff949ce849603cf3d97c3829e88152aecf0e096f45254921742a325d707db8148bbcae4e489e2316109319caef7fa5a553cbb3c6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-02 18:43:32.153292) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:43:32.153653) ========= 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=769a0cfe1e83e11e6f11f5a3000001a8-1483410141.187072-1953256079 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004566 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:43:32.238405) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-02 18:43:32.238745) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-02 18:43:32.239074) ========= ========= Total master_lag: 0.17 =========