builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1475710316.77 results: success (0) buildid: 20161005135000 builduid: 82443e5d3c9e4715a1b12f9c78dcdadb revision: e666c7433e2fb5bd3f49a1e011170feca4571bd2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 16:31:56.765885) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 16:31:56.766264) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 16:31:56.766534) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 16:31:56.830164) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 16:31:56.830434) ========= 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=62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-05 16:31:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 104M=0s 2016-10-05 16:31:56 (104 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.101302 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 16:31:56.968116) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 16:31:56.968388) ========= 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=62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015581 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 16:31:57.042964) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 16:31:57.043318) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e666c7433e2fb5bd3f49a1e011170feca4571bd2 --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-aurora --rev e666c7433e2fb5bd3f49a1e011170feca4571bd2 --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=62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-05 16:31:57,130 truncating revision to first 12 chars 2016-10-05 16:31:57,130 Setting DEBUG logging. 2016-10-05 16:31:57,130 attempt 1/10 2016-10-05 16:31:57,130 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e666c7433e2f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-05 16:31:57,470 unpacking tar archive at: mozilla-aurora-e666c7433e2f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.657928 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 16:31:57.723290) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 16:31:57.723557) ========= script_repo_revision: e666c7433e2fb5bd3f49a1e011170feca4571bd2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 16:31:57.724309) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 16:31:57.724574) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 16:31:57.742244) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-10-05 16:31:57.742765) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:31:57 INFO - MultiFileLogger online at 20161005 16:31:57 in /builds/slave/test 16:31:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 16:31:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:31:57 INFO - {'append_to_log': False, 16:31:57 INFO - 'base_work_dir': '/builds/slave/test', 16:31:57 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:31:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:31:57 INFO - 'branch': 'Mozilla-Aurora', 16:31:57 INFO - 'buildbot_json_path': 'buildprops.json', 16:31:57 INFO - 'config_files': ('talos/linux_config.py',), 16:31:57 INFO - 'default_actions': ('clobber', 16:31:57 INFO - 'read-buildbot-config', 16:31:57 INFO - 'download-and-extract', 16:31:57 INFO - 'populate-webroot', 16:31:57 INFO - 'create-virtualenv', 16:31:57 INFO - 'install', 16:31:57 INFO - 'run-tests'), 16:31:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:31:57 INFO - 'download_minidump_stackwalk': True, 16:31:57 INFO - 'download_symbols': 'ondemand', 16:31:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:31:57 INFO - 'tooltool.py': '/tools/tooltool.py', 16:31:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:31:57 INFO - '/tools/misc-python/virtualenv.py')}, 16:31:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:31:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:31:57 INFO - 'installer_path': 'installer.exe', 16:31:57 INFO - 'log_level': 'info', 16:31:57 INFO - 'log_name': 'talos', 16:31:57 INFO - 'log_to_console': True, 16:31:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:31:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:31:57 INFO - 'opt_config_files': (), 16:31:57 INFO - 'pip_index': False, 16:31:57 INFO - 'sps_profile': False, 16:31:57 INFO - 'sps_profile_interval': 0, 16:31:57 INFO - 'suite': 'dromaeojs', 16:31:57 INFO - 'system_bits': '32', 16:31:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:31:57 INFO - 'title': 'talos-linux64-ix-018', 16:31:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:31:57 INFO - 'use_talos_json': True, 16:31:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:31:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:31:57 INFO - 'work_dir': 'build'} 16:31:57 INFO - [mozharness: 2016-10-05 23:31:57.926114Z] Running clobber step. 16:31:57 INFO - Running pre-action listener: _resource_record_pre_action 16:31:57 INFO - Running main action method: clobber 16:31:57 INFO - rmtree: /builds/slave/test/build 16:31:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:31:58 INFO - Running post-action listener: _resource_record_post_action 16:31:58 INFO - [mozharness: 2016-10-05 23:31:58.902018Z] Finished clobber step (success) 16:31:58 INFO - [mozharness: 2016-10-05 23:31:58.902118Z] Running read-buildbot-config step. 16:31:58 INFO - Running pre-action listener: _resource_record_pre_action 16:31:58 INFO - Running main action method: read_buildbot_config 16:31:58 INFO - Using buildbot properties: 16:31:58 INFO - { 16:31:58 INFO - "project": "", 16:31:58 INFO - "product": "firefox", 16:31:58 INFO - "script_repo_revision": "production", 16:31:58 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 16:31:58 INFO - "repository": "", 16:31:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 16:31:58 INFO - "buildid": "20161005135000", 16:31:58 INFO - "pgo_build": "True", 16:31:58 INFO - "basedir": "/builds/slave/test", 16:31:58 INFO - "buildnumber": 552, 16:31:58 INFO - "slavename": "talos-linux64-ix-018", 16:31:58 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:31:58 INFO - "platform": "ubuntu64_hw", 16:31:58 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 16:31:58 INFO - "branch": "mozilla-aurora", 16:31:58 INFO - "repo_path": "releases/mozilla-aurora", 16:31:58 INFO - "revision": "e666c7433e2fb5bd3f49a1e011170feca4571bd2", 16:31:58 INFO - "stage_platform": "linux64-pgo", 16:31:58 INFO - "builduid": "82443e5d3c9e4715a1b12f9c78dcdadb", 16:31:58 INFO - "slavebuilddir": "test" 16:31:58 INFO - } 16:31:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 16:31:58 INFO - Running post-action listener: _resource_record_post_action 16:31:58 INFO - [mozharness: 2016-10-05 23:31:58.904556Z] Finished read-buildbot-config step (success) 16:31:58 INFO - [mozharness: 2016-10-05 23:31:58.904645Z] Running download-and-extract step. 16:31:58 INFO - Running pre-action listener: _resource_record_pre_action 16:31:58 INFO - Running main action method: download_and_extract 16:31:58 INFO - mkdir: /builds/slave/test/build/tests 16:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:31:58 INFO - trying https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 16:31:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 16:31:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:31:59 INFO - Downloaded 1361 bytes. 16:31:59 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 16:31:59 INFO - Using the following test package requirements: 16:31:59 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 16:31:59 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:31:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 16:31:59 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:31:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 16:31:59 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:31:59 INFO - u'jsshell-linux-x86_64.zip'], 16:31:59 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:31:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 16:31:59 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 16:31:59 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:31:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 16:31:59 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:31:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 16:31:59 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:31:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 16:31:59 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:31:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 16:31:59 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:31:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 16:31:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:31:59 INFO - Fetch https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 16:32:00 INFO - Expected file size: 21906314 16:32:04 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:32:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 16:32:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:32:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 16:32:04 INFO - Expected file size: 21906314 16:32:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 16:32:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:32:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 16:32:10 INFO - Expected file size: 14414059 16:32:10 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')]} 16:32:10 INFO - trying https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 16:32:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 16:32:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:32:12 INFO - Downloaded 63940871 bytes. 16:32:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 16:32:12 INFO - mkdir: /builds/slave/test/properties 16:32:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:32:12 INFO - Writing to file /builds/slave/test/properties/build_url 16:32:12 INFO - Contents: 16:32:12 INFO - build_url:https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 16:32:12 INFO - Running post-action listener: _resource_record_post_action 16:32:12 INFO - Running post-action listener: set_extra_try_arguments 16:32:12 INFO - [mozharness: 2016-10-05 23:32:12.902789Z] Finished download-and-extract step (success) 16:32:12 INFO - [mozharness: 2016-10-05 23:32:12.903018Z] Running populate-webroot step. 16:32:12 INFO - Running pre-action listener: _resource_record_pre_action 16:32:12 INFO - Running main action method: populate_webroot 16:32:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:32:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:32:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:32:12 INFO - '--tppagecycles', 16:32:12 INFO - '7'], 16:32:12 INFO - 'tests': ['tsvgm']}}, 16:32:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:32:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:32:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:32:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:32:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:32:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:32:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:12 INFO - 'talos_options': ['--disable-e10s'], 16:32:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:32:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:32:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:12 INFO - 'talos_options': ['--disable-e10s'], 16:32:12 INFO - 'tests': ['damp', 'tps']}, 16:32:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:12 INFO - 'tests': ['damp', 'tps']}, 16:32:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:32:12 INFO - 'tests': ['dromaeo_dom']}, 16:32:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:32:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:32:12 INFO - 'tests': ['basic_compositor_video']}, 16:32:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:32:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:32:12 INFO - 'tests': ['a11yr', 16:32:12 INFO - 'ts_paint', 16:32:12 INFO - 'tpaint', 16:32:12 INFO - 'sessionrestore', 16:32:12 INFO - 'sessionrestore_no_auto_restore', 16:32:12 INFO - 'tabpaint']}, 16:32:12 INFO - 'other-e10s': {'tests': ['a11yr', 16:32:12 INFO - 'ts_paint', 16:32:12 INFO - 'tpaint', 16:32:12 INFO - 'sessionrestore', 16:32:12 INFO - 'sessionrestore_no_auto_restore', 16:32:12 INFO - 'tabpaint']}, 16:32:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:32:12 INFO - 'ts_paint', 16:32:12 INFO - 'tpaint', 16:32:12 INFO - 'sessionrestore', 16:32:12 INFO - 'sessionrestore_no_auto_restore', 16:32:12 INFO - 'tabpaint']}, 16:32:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:32:12 INFO - 'ts_paint', 16:32:12 INFO - 'tpaint', 16:32:12 INFO - 'sessionrestore', 16:32:12 INFO - 'sessionrestore_no_auto_restore', 16:32:12 INFO - 'tabpaint']}, 16:32:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:32:12 INFO - 'tests': ['a11yr', 16:32:12 INFO - 'ts_paint', 16:32:12 INFO - 'tpaint', 16:32:12 INFO - 'sessionrestore', 16:32:12 INFO - 'sessionrestore_no_auto_restore', 16:32:12 INFO - 'tabpaint']}, 16:32:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:32:12 INFO - 'tests': ['a11yr', 16:32:12 INFO - 'ts_paint', 16:32:12 INFO - 'tpaint', 16:32:12 INFO - 'sessionrestore', 16:32:12 INFO - 'sessionrestore_no_auto_restore', 16:32:12 INFO - 'tabpaint']}, 16:32:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:32:12 INFO - 'tests': ['tsvgx', 16:32:12 INFO - 'tsvgr_opacity', 16:32:12 INFO - 'tart', 16:32:12 INFO - 'tscrollx', 16:32:12 INFO - 'cart']}, 16:32:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:32:12 INFO - 'tsvgr_opacity', 16:32:12 INFO - 'tart', 16:32:12 INFO - 'tscrollx', 16:32:12 INFO - 'cart']}, 16:32:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:12 INFO - 'talos_options': ['--disable-e10s'], 16:32:12 INFO - 'tests': ['tp5o']}, 16:32:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:12 INFO - 'tests': ['tp5o']}, 16:32:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:12 INFO - 'talos_options': ['--disable-e10s', 16:32:12 INFO - '--xperf_path', 16:32:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:32:12 INFO - 'tests': ['tp5n']}, 16:32:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:12 INFO - 'talos_options': ['--xperf_path', 16:32:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:32:12 INFO - 'tests': ['tp5n']}}, 16:32:12 INFO - 'talos.zip': {'path': '', 16:32:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:32:12 INFO - Running post-action listener: _resource_record_post_action 16:32:12 INFO - [mozharness: 2016-10-05 23:32:12.920435Z] Finished populate-webroot step (success) 16:32:12 INFO - [mozharness: 2016-10-05 23:32:12.920524Z] Running create-virtualenv step. 16:32:12 INFO - Running pre-action listener: _resource_record_pre_action 16:32:12 INFO - Running main action method: create_virtualenv 16:32:12 INFO - Creating virtualenv /builds/slave/test/build/venv 16:32:12 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 16:32:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:32:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:32:13 INFO - Using real prefix '/usr' 16:32:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:32:13 INFO - Installing distribute.............................................................................................................................................................................................done. 16:32:15 INFO - Installing pip.................done. 16:32:15 INFO - Return code: 0 16:32:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:32:15 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')]} 16:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:15 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')]} 16:32:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:15 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 0x7f3ba2a4ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1885ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1882f60>, '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 0x1883d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18841d0>, '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': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', '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 16:32:15 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 16:32:15 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 16:32:15 INFO - Using env: {'DISPLAY': ':0', 16:32:15 INFO - 'HOME': '/home/cltbld', 16:32:15 INFO - 'LANG': 'en_US.UTF-8', 16:32:15 INFO - 'LANGUAGE': 'en_US:en', 16:32:15 INFO - 'LOGNAME': 'cltbld', 16:32:15 INFO - 'MAIL': '/var/mail/cltbld', 16:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:15 INFO - 'MOZ_NO_REMOTE': '1', 16:32:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:15 INFO - 'NO_EM_RESTART': '1', 16:32:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:15 INFO - 'PWD': '/builds/slave/test', 16:32:15 INFO - 'SHELL': '/bin/bash', 16:32:15 INFO - 'SHLVL': '1', 16:32:15 INFO - 'TERM': 'linux', 16:32:15 INFO - 'TMOUT': '86400', 16:32:15 INFO - 'USER': 'cltbld', 16:32:15 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', 16:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:15 INFO - '_': '/tools/buildbot/bin/python'} 16:32:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:15 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 16:32:15 INFO - Cleaning up... 16:32:15 INFO - Return code: 0 16:32:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:32:15 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')]} 16:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:15 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')]} 16:32:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:15 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 0x7f3ba2a4ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1885ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1882f60>, '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 0x1883d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18841d0>, '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': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', '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 16:32:15 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 16:32:15 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 16:32:15 INFO - Using env: {'DISPLAY': ':0', 16:32:15 INFO - 'HOME': '/home/cltbld', 16:32:15 INFO - 'LANG': 'en_US.UTF-8', 16:32:15 INFO - 'LANGUAGE': 'en_US:en', 16:32:15 INFO - 'LOGNAME': 'cltbld', 16:32:15 INFO - 'MAIL': '/var/mail/cltbld', 16:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:15 INFO - 'MOZ_NO_REMOTE': '1', 16:32:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:15 INFO - 'NO_EM_RESTART': '1', 16:32:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:15 INFO - 'PWD': '/builds/slave/test', 16:32:15 INFO - 'SHELL': '/bin/bash', 16:32:15 INFO - 'SHLVL': '1', 16:32:15 INFO - 'TERM': 'linux', 16:32:15 INFO - 'TMOUT': '86400', 16:32:15 INFO - 'USER': 'cltbld', 16:32:15 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', 16:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:15 INFO - '_': '/tools/buildbot/bin/python'} 16:32:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:15 INFO - Downloading/unpacking psutil>=3.1.1 16:32:15 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 16:32:15 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 16:32:15 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 16:32:15 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 16:32:15 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 16:32:15 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 16:32:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:32:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:32:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:32:17 INFO - Installing collected packages: psutil 16:32:17 INFO - Running setup.py install for psutil 16:32:17 INFO - building 'psutil._psutil_linux' extension 16:32:17 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 16:32:18 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 16:32:18 INFO - building 'psutil._psutil_posix' extension 16:32:18 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 16:32:18 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 16:32:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:32:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:32:18 INFO - Successfully installed psutil 16:32:18 INFO - Cleaning up... 16:32:18 INFO - Return code: 0 16:32:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:32:18 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')]} 16:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:18 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')]} 16:32:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:18 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 0x7f3ba2a4ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1885ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1882f60>, '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 0x1883d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18841d0>, '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': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', '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 16:32:18 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 16:32:18 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 16:32:18 INFO - Using env: {'DISPLAY': ':0', 16:32:18 INFO - 'HOME': '/home/cltbld', 16:32:18 INFO - 'LANG': 'en_US.UTF-8', 16:32:18 INFO - 'LANGUAGE': 'en_US:en', 16:32:18 INFO - 'LOGNAME': 'cltbld', 16:32:18 INFO - 'MAIL': '/var/mail/cltbld', 16:32:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:18 INFO - 'MOZ_NO_REMOTE': '1', 16:32:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:18 INFO - 'NO_EM_RESTART': '1', 16:32:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:18 INFO - 'PWD': '/builds/slave/test', 16:32:18 INFO - 'SHELL': '/bin/bash', 16:32:18 INFO - 'SHLVL': '1', 16:32:18 INFO - 'TERM': 'linux', 16:32:18 INFO - 'TMOUT': '86400', 16:32:18 INFO - 'USER': 'cltbld', 16:32:18 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', 16:32:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:18 INFO - '_': '/tools/buildbot/bin/python'} 16:32:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:32:18 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 16:32:18 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 16:32:18 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 16:32:18 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 16:32:18 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 16:32:18 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 16:32:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:32:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:32:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:32:20 INFO - Installing collected packages: mozsystemmonitor 16:32:20 INFO - Running setup.py install for mozsystemmonitor 16:32:20 INFO - Successfully installed mozsystemmonitor 16:32:20 INFO - Cleaning up... 16:32:20 INFO - Return code: 0 16:32:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:32:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f3ba2a4ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1885ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1882f60>, '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 0x1883d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18841d0>, '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': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', '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 16:32:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:32:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:32:20 INFO - Using env: {'DISPLAY': ':0', 16:32:20 INFO - 'HOME': '/home/cltbld', 16:32:20 INFO - 'LANG': 'en_US.UTF-8', 16:32:20 INFO - 'LANGUAGE': 'en_US:en', 16:32:20 INFO - 'LOGNAME': 'cltbld', 16:32:20 INFO - 'MAIL': '/var/mail/cltbld', 16:32:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:20 INFO - 'MOZ_NO_REMOTE': '1', 16:32:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:20 INFO - 'NO_EM_RESTART': '1', 16:32:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:20 INFO - 'PWD': '/builds/slave/test', 16:32:20 INFO - 'SHELL': '/bin/bash', 16:32:20 INFO - 'SHLVL': '1', 16:32:20 INFO - 'TERM': 'linux', 16:32:20 INFO - 'TMOUT': '86400', 16:32:20 INFO - 'USER': 'cltbld', 16:32:20 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', 16:32:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:20 INFO - '_': '/tools/buildbot/bin/python'} 16:32:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:20 INFO - Downloading/unpacking jsonschema==2.5.1 16:32:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:32:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32:23 INFO - Downloading functools32-3.2.3-2.tar.gz 16:32:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:32:23 INFO - warning: no files found matching '*.txt' 16:32:23 INFO - no previously-included directories found matching 'build' 16:32:23 INFO - no previously-included directories found matching 'dist' 16:32:23 INFO - no previously-included directories found matching '.git*' 16:32:23 INFO - Installing collected packages: jsonschema, functools32 16:32:23 INFO - Running setup.py install for functools32 16:32:23 INFO - warning: no files found matching '*.txt' 16:32:23 INFO - no previously-included directories found matching 'build' 16:32:23 INFO - no previously-included directories found matching 'dist' 16:32:23 INFO - no previously-included directories found matching '.git*' 16:32:23 INFO - Successfully installed jsonschema functools32 16:32:23 INFO - Cleaning up... 16:32:23 INFO - Return code: 0 16:32:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:32:23 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')]} 16:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:23 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')]} 16:32:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:23 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 0x7f3ba2a4ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1885ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1882f60>, '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 0x1883d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18841d0>, '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': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', '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 16:32:23 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 16:32:23 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 16:32:23 INFO - Using env: {'DISPLAY': ':0', 16:32:23 INFO - 'HOME': '/home/cltbld', 16:32:23 INFO - 'LANG': 'en_US.UTF-8', 16:32:23 INFO - 'LANGUAGE': 'en_US:en', 16:32:23 INFO - 'LOGNAME': 'cltbld', 16:32:23 INFO - 'MAIL': '/var/mail/cltbld', 16:32:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:23 INFO - 'MOZ_NO_REMOTE': '1', 16:32:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:23 INFO - 'NO_EM_RESTART': '1', 16:32:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:23 INFO - 'PWD': '/builds/slave/test', 16:32:23 INFO - 'SHELL': '/bin/bash', 16:32:23 INFO - 'SHLVL': '1', 16:32:23 INFO - 'TERM': 'linux', 16:32:23 INFO - 'TMOUT': '86400', 16:32:23 INFO - 'USER': 'cltbld', 16:32:23 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', 16:32:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:23 INFO - '_': '/tools/buildbot/bin/python'} 16:32:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:32:23 INFO - Cleaning up... 16:32:23 INFO - Return code: 0 16:32:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:32:23 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')]} 16:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:23 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')]} 16:32:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:23 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 0x7f3ba2a4ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1885ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1882f60>, '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 0x1883d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18841d0>, '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': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', '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 16:32:23 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 16:32:23 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 16:32:23 INFO - Using env: {'DISPLAY': ':0', 16:32:23 INFO - 'HOME': '/home/cltbld', 16:32:23 INFO - 'LANG': 'en_US.UTF-8', 16:32:23 INFO - 'LANGUAGE': 'en_US:en', 16:32:23 INFO - 'LOGNAME': 'cltbld', 16:32:23 INFO - 'MAIL': '/var/mail/cltbld', 16:32:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:23 INFO - 'MOZ_NO_REMOTE': '1', 16:32:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:23 INFO - 'NO_EM_RESTART': '1', 16:32:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:23 INFO - 'PWD': '/builds/slave/test', 16:32:23 INFO - 'SHELL': '/bin/bash', 16:32:23 INFO - 'SHLVL': '1', 16:32:23 INFO - 'TERM': 'linux', 16:32:23 INFO - 'TMOUT': '86400', 16:32:23 INFO - 'USER': 'cltbld', 16:32:23 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', 16:32:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:23 INFO - '_': '/tools/buildbot/bin/python'} 16:32:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:23 INFO - Downloading/unpacking blobuploader==1.2.4 16:32:23 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 16:32:23 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 16:32:23 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 16:32:23 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 16:32:23 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 16:32:23 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 16:32:25 INFO - Downloading blobuploader-1.2.4.tar.gz 16:32:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:32:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:32:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:32:26 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 16:32:26 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 16:32:26 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 16:32:26 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 16:32:26 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 16:32:26 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 16:32:26 INFO - Downloading docopt-0.6.1.tar.gz 16:32:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:32:26 INFO - Installing collected packages: blobuploader, requests, docopt 16:32:26 INFO - Running setup.py install for blobuploader 16:32:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:32:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:32:26 INFO - Running setup.py install for requests 16:32:27 INFO - Running setup.py install for docopt 16:32:27 INFO - Successfully installed blobuploader requests docopt 16:32:27 INFO - Cleaning up... 16:32:27 INFO - Return code: 0 16:32:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32: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')]} 16:32:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32: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')]} 16:32:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:27 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 0x7f3ba2a4ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1885ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1882f60>, '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 0x1883d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18841d0>, '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': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', '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 16:32:27 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 16:32:27 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 16:32:27 INFO - Using env: {'DISPLAY': ':0', 16:32:27 INFO - 'HOME': '/home/cltbld', 16:32:27 INFO - 'LANG': 'en_US.UTF-8', 16:32:27 INFO - 'LANGUAGE': 'en_US:en', 16:32:27 INFO - 'LOGNAME': 'cltbld', 16:32:27 INFO - 'MAIL': '/var/mail/cltbld', 16:32:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:27 INFO - 'MOZ_NO_REMOTE': '1', 16:32:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:27 INFO - 'NO_EM_RESTART': '1', 16:32:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:27 INFO - 'PWD': '/builds/slave/test', 16:32:27 INFO - 'SHELL': '/bin/bash', 16:32:27 INFO - 'SHLVL': '1', 16:32:27 INFO - 'TERM': 'linux', 16:32:27 INFO - 'TMOUT': '86400', 16:32:27 INFO - 'USER': 'cltbld', 16:32:27 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', 16:32:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:27 INFO - '_': '/tools/buildbot/bin/python'} 16:32:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:32:27 INFO - Running setup.py (path:/tmp/pip-pETP3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:32:27 INFO - Running setup.py (path:/tmp/pip-Zx7A6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:32:27 INFO - Running setup.py (path:/tmp/pip-S59ciK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:32:27 INFO - Running setup.py (path:/tmp/pip-Vb0gGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:32:27 INFO - Running setup.py (path:/tmp/pip-4XYm9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:32:27 INFO - Running setup.py (path:/tmp/pip-NxNrTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:32:27 INFO - Running setup.py (path:/tmp/pip-TSD0Qi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:32:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:32:28 INFO - Running setup.py (path:/tmp/pip-1XoOfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:32:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:32:28 INFO - Running setup.py (path:/tmp/pip-YOSw2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:32:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:32:28 INFO - Running setup.py (path:/tmp/pip-xeYU0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:32:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:32:28 INFO - Running setup.py (path:/tmp/pip-JWStJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:32:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:32:28 INFO - Running setup.py (path:/tmp/pip-P0erbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:32:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:32:28 INFO - Running setup.py (path:/tmp/pip-1TKMsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:32:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:32:28 INFO - Running setup.py (path:/tmp/pip-AiU4HJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:32:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:28 INFO - Running setup.py (path:/tmp/pip-slYOQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:32:28 INFO - Running setup.py (path:/tmp/pip-cxk1zl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:32:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:32:28 INFO - Running setup.py (path:/tmp/pip-rW0hcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:32:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:32:28 INFO - Running setup.py install for manifestparser 16:32:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:32:28 INFO - Running setup.py install for mozcrash 16:32:28 INFO - Running setup.py install for mozdebug 16:32:28 INFO - Running setup.py install for mozdevice 16:32:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:32:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:32:29 INFO - Running setup.py install for mozfile 16:32:29 INFO - Running setup.py install for mozhttpd 16:32:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:32:29 INFO - Running setup.py install for mozinfo 16:32:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:32:29 INFO - Running setup.py install for mozInstall 16:32:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:32:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:32:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:32:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:32:29 INFO - Running setup.py install for mozleak 16:32:29 INFO - Running setup.py install for mozlog 16:32:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:32:29 INFO - Running setup.py install for moznetwork 16:32:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:32:29 INFO - Running setup.py install for mozprocess 16:32:29 INFO - Running setup.py install for mozprofile 16:32:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:32:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:32:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:32:30 INFO - Running setup.py install for mozrunner 16:32:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:32:30 INFO - Running setup.py install for mozscreenshot 16:32:30 INFO - Running setup.py install for moztest 16:32:30 INFO - Running setup.py install for mozversion 16:32:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:32:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:32:30 INFO - Cleaning up... 16:32:30 INFO - Return code: 0 16:32:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32: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')]} 16:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32: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')]} 16:32:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:30 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 0x7f3ba2a4ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1885ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1882f60>, '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 0x1883d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18841d0>, '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': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', '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 16:32:30 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 16:32:30 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 16:32:30 INFO - Using env: {'DISPLAY': ':0', 16:32:30 INFO - 'HOME': '/home/cltbld', 16:32:30 INFO - 'LANG': 'en_US.UTF-8', 16:32:30 INFO - 'LANGUAGE': 'en_US:en', 16:32:30 INFO - 'LOGNAME': 'cltbld', 16:32:30 INFO - 'MAIL': '/var/mail/cltbld', 16:32:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:30 INFO - 'MOZ_NO_REMOTE': '1', 16:32:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:30 INFO - 'NO_EM_RESTART': '1', 16:32:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:30 INFO - 'PWD': '/builds/slave/test', 16:32:30 INFO - 'SHELL': '/bin/bash', 16:32:30 INFO - 'SHLVL': '1', 16:32:30 INFO - 'TERM': 'linux', 16:32:30 INFO - 'TMOUT': '86400', 16:32:30 INFO - 'USER': 'cltbld', 16:32:30 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', 16:32:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:30 INFO - '_': '/tools/buildbot/bin/python'} 16:32:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:32:30 INFO - Running setup.py (path:/tmp/pip-4R1lVZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:32:30 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)) 16:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:32:30 INFO - Running setup.py (path:/tmp/pip-J0Soly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:32:30 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)) 16:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:32:30 INFO - Running setup.py (path:/tmp/pip-TvwGOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:32:30 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)) 16:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:32:30 INFO - Running setup.py (path:/tmp/pip-ZgxQFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:32:31 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)) 16:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:32:31 INFO - Running setup.py (path:/tmp/pip-c8qrW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:32:31 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)) 16:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:32:31 INFO - Running setup.py (path:/tmp/pip-hXooRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:32:31 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)) 16:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:32:31 INFO - Running setup.py (path:/tmp/pip-azN95U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:32:31 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)) 16:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:32:31 INFO - Running setup.py (path:/tmp/pip-UVV6rh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:32:31 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)) 16:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:32:31 INFO - Running setup.py (path:/tmp/pip-i2YlZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:32:31 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)) 16:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:32:31 INFO - Running setup.py (path:/tmp/pip-UAVl3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:32:31 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)) 16:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:32:31 INFO - Running setup.py (path:/tmp/pip-VWTuCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:32:31 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)) 16:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:32:31 INFO - Running setup.py (path:/tmp/pip-O0lwUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:32:31 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)) 16:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:32:31 INFO - Running setup.py (path:/tmp/pip-gyXAJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:32:31 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)) 16:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:32:31 INFO - Running setup.py (path:/tmp/pip-krG0Jk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:32:31 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)) 16:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:31 INFO - Running setup.py (path:/tmp/pip-BXl65Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:31 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)) 16:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:32:31 INFO - Running setup.py (path:/tmp/pip-vIaM1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:32:31 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)) 16:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:32:31 INFO - Running setup.py (path:/tmp/pip-N0DduY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:32:31 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)) 16:32:31 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)) 16:32:31 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)) 16:32:31 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)) 16:32:31 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)) 16:32:31 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)) 16:32:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:32:31 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 16:32:31 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 16:32:31 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 16:32:31 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 16:32:31 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 16:32:31 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 16:32:33 INFO - Downloading blessings-1.6.tar.gz 16:32:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:32:33 INFO - Installing collected packages: blessings 16:32:33 INFO - Running setup.py install for blessings 16:32:34 INFO - Successfully installed blessings 16:32:34 INFO - Cleaning up... 16:32:34 INFO - Return code: 0 16:32:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:32:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:32:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:32:34 INFO - Reading from file tmpfile_stdout 16:32:34 INFO - Current package versions: 16:32:34 INFO - argparse == 1.2.1 16:32:34 INFO - blessings == 1.6 16:32:34 INFO - blobuploader == 1.2.4 16:32:34 INFO - docopt == 0.6.1 16:32:34 INFO - functools32 == 3.2.3-2 16:32:34 INFO - jsonschema == 2.5.1 16:32:34 INFO - manifestparser == 1.1 16:32:34 INFO - mozInstall == 1.12 16:32:34 INFO - mozcrash == 0.17 16:32:34 INFO - mozdebug == 0.1 16:32:34 INFO - mozdevice == 0.48 16:32:34 INFO - mozfile == 1.2 16:32:34 INFO - mozhttpd == 0.7 16:32:34 INFO - mozinfo == 0.9 16:32:34 INFO - mozleak == 0.1 16:32:34 INFO - mozlog == 3.4 16:32:34 INFO - moznetwork == 0.27 16:32:34 INFO - mozprocess == 0.23 16:32:34 INFO - mozprofile == 0.28 16:32:34 INFO - mozrunner == 6.12 16:32:34 INFO - mozscreenshot == 0.1 16:32:34 INFO - mozsystemmonitor == 0.3 16:32:34 INFO - moztest == 0.7 16:32:34 INFO - mozversion == 1.4 16:32:34 INFO - psutil == 3.1.1 16:32:34 INFO - requests == 1.2.3 16:32:34 INFO - wsgiref == 0.1.2 16:32:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32:34 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')]} 16:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:34 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')]} 16:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:34 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 0x7f3ba2a4ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1885ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1882f60>, '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 0x1883d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18841d0>, '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': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', '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 16:32:34 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 16:32:34 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 16:32:34 INFO - Using env: {'DISPLAY': ':0', 16:32:34 INFO - 'HOME': '/home/cltbld', 16:32:34 INFO - 'LANG': 'en_US.UTF-8', 16:32:34 INFO - 'LANGUAGE': 'en_US:en', 16:32:34 INFO - 'LOGNAME': 'cltbld', 16:32:34 INFO - 'MAIL': '/var/mail/cltbld', 16:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:34 INFO - 'MOZ_NO_REMOTE': '1', 16:32:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:34 INFO - 'NO_EM_RESTART': '1', 16:32:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:34 INFO - 'PWD': '/builds/slave/test', 16:32:34 INFO - 'SHELL': '/bin/bash', 16:32:34 INFO - 'SHLVL': '1', 16:32:34 INFO - 'TERM': 'linux', 16:32:34 INFO - 'TMOUT': '86400', 16:32:34 INFO - 'USER': 'cltbld', 16:32:34 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', 16:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:34 INFO - '_': '/tools/buildbot/bin/python'} 16:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:34 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)) 16:32:34 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)) 16:32:34 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)) 16:32:34 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)) 16:32:34 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)) 16:32:34 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)) 16:32:34 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)) 16:32:34 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)) 16:32:34 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)) 16:32:34 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)) 16:32:34 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)) 16:32:34 INFO - Cleaning up... 16:32:34 INFO - Return code: 0 16:32:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:32:34 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')]} 16:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:34 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')]} 16:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:34 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 0x7f3ba2a4ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1885ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1882f60>, '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 0x1883d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18841d0>, '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': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', '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 16:32:34 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 16:32:34 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 16:32:34 INFO - Using env: {'DISPLAY': ':0', 16:32:34 INFO - 'HOME': '/home/cltbld', 16:32:34 INFO - 'LANG': 'en_US.UTF-8', 16:32:34 INFO - 'LANGUAGE': 'en_US:en', 16:32:34 INFO - 'LOGNAME': 'cltbld', 16:32:34 INFO - 'MAIL': '/var/mail/cltbld', 16:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:34 INFO - 'MOZ_NO_REMOTE': '1', 16:32:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:34 INFO - 'NO_EM_RESTART': '1', 16:32:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:34 INFO - 'PWD': '/builds/slave/test', 16:32:34 INFO - 'SHELL': '/bin/bash', 16:32:34 INFO - 'SHLVL': '1', 16:32:34 INFO - 'TERM': 'linux', 16:32:34 INFO - 'TMOUT': '86400', 16:32:34 INFO - 'USER': 'cltbld', 16:32:34 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', 16:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:34 INFO - '_': '/tools/buildbot/bin/python'} 16:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:32:34 INFO - Cleaning up... 16:32:34 INFO - Return code: 0 16:32:34 INFO - Running post-action listener: _resource_record_post_action 16:32:34 INFO - Running post-action listener: _start_resource_monitoring 16:32:34 INFO - Starting resource monitoring. 16:32:34 INFO - [mozharness: 2016-10-05 23:32:34.813304Z] Finished create-virtualenv step (success) 16:32:34 INFO - [mozharness: 2016-10-05 23:32:34.813830Z] Running install step. 16:32:34 INFO - Running pre-action listener: _resource_record_pre_action 16:32:34 INFO - Running main action method: install 16:32:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:32:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:32:35 INFO - Reading from file tmpfile_stdout 16:32:35 INFO - Detecting whether we're running mozinstall >=1.0... 16:32:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:32:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:32:35 INFO - Reading from file tmpfile_stdout 16:32:35 INFO - Output received: 16:32:35 INFO - Usage: mozinstall [options] installer 16:32:35 INFO - Options: 16:32:35 INFO - -h, --help show this help message and exit 16:32:35 INFO - -d DEST, --destination=DEST 16:32:35 INFO - Directory to install application into. [default: 16:32:35 INFO - "/builds/slave/test"] 16:32:35 INFO - --app=APP Application being installed. [default: firefox] 16:32:35 INFO - mkdir: /builds/slave/test/build/application 16:32:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:32:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:32:51 INFO - Reading from file tmpfile_stdout 16:32:51 INFO - Output received: 16:32:51 INFO - /builds/slave/test/build/application/firefox/firefox 16:32:51 INFO - Running post-action listener: _resource_record_post_action 16:32:51 INFO - [mozharness: 2016-10-05 23:32:51.046508Z] Finished install step (success) 16:32:51 INFO - [mozharness: 2016-10-05 23:32:51.046755Z] Running run-tests step. 16:32:51 INFO - Running pre-action listener: _resource_record_pre_action 16:32:51 INFO - Running main action method: run_tests 16:32:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:32:51 INFO - Python 2.7.3 16:32:51 INFO - Return code: 0 16:32:51 INFO - grabbing minidump binary from tooltool 16:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:51 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1882f60>, '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 0x1883d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18841d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:32:51 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:32:51 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:32:51 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:32:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:32:51 INFO - Return code: 0 16:32:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:32:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:32:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:32:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:32:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:32:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 16:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 16:32:51 INFO - Using env: {'DISPLAY': ':0', 16:32:51 INFO - 'HOME': '/home/cltbld', 16:32:51 INFO - 'LANG': 'en_US.UTF-8', 16:32:51 INFO - 'LANGUAGE': 'en_US:en', 16:32:51 INFO - 'LOGNAME': 'cltbld', 16:32:51 INFO - 'MAIL': '/var/mail/cltbld', 16:32:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:32:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:32:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:51 INFO - 'MOZ_NO_REMOTE': '1', 16:32:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:32:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:51 INFO - 'NO_EM_RESTART': '1', 16:32:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:51 INFO - 'PWD': '/builds/slave/test', 16:32:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:32:51 INFO - 'SHELL': '/bin/bash', 16:32:51 INFO - 'SHLVL': '1', 16:32:51 INFO - 'TERM': 'linux', 16:32:51 INFO - 'TMOUT': '86400', 16:32:51 INFO - 'USER': 'cltbld', 16:32:51 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734', 16:32:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:51 INFO - '_': '/tools/buildbot/bin/python'} 16:32:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 16:32:51 INFO - mozversion application_buildid: 20161005135000 16:32:51 INFO - mozversion application_changeset: e666c7433e2fb5bd3f49a1e011170feca4571bd2 16:32:51 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:32:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:32:51 INFO - mozversion application_name: Firefox 16:32:51 INFO - mozversion application_remotingname: firefox-dev 16:32:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:32:51 INFO - mozversion application_vendor: Mozilla 16:32:51 INFO - mozversion application_version: 51.0a2 16:32:51 INFO - mozversion platform_buildid: 20161005135000 16:32:51 INFO - mozversion platform_changeset: e666c7433e2fb5bd3f49a1e011170feca4571bd2 16:32:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:32:51 INFO - mozversion platform_version: 51.0a2 16:32:51 INFO - using testdate: 1475710371 16:32:51 INFO - actual date: 1475710371 16:32:51 INFO - starting webserver on 'localhost:47411' 16:32:51 INFO - SUITE-START | Running 2 tests 16:32:51 INFO - TEST-START | dromaeo_css 16:32:51 INFO - operating with platform_type : linux_ 16:32:51 INFO - Initialising browser for dromaeo_css test... 16:32:51 INFO - TEST-INFO | started process 23981 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpznzzuq/profile http://localhost:47411/getInfo.html) 16:32:55 INFO - PROCESS | 23981 | __metrics Screen width/height:1600/1200 16:32:55 INFO - PROCESS | 23981 | colorDepth:24 16:32:55 INFO - PROCESS | 23981 | Browser inner width/height: 1024/697 16:32:55 INFO - PROCESS | 23981 | __metrics 16:32:55 INFO - PROCESS | 23981 | 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" 16:32:55 INFO - PROCESS | 23981 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:32:56 INFO - PROCESS | 23981 | console.error: PushService: 16:32:56 INFO - PROCESS | 23981 | stateChangeProcessEnqueue: Error transitioning state 16:32:56 INFO - PROCESS | 23981 | UnknownError 16:32:56 INFO - TEST-INFO | 23981: exit 0 16:32:56 INFO - Browser initialized. 16:32:56 INFO - Running cycle 1/1 for dromaeo_css test... 16:32:56 INFO - TEST-INFO | started process 24069 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpznzzuq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:33:08 INFO - PROCESS | 24069 | RSS: Main: 144191488 16:33:08 INFO - PROCESS | 24069 | 16:33:59 INFO - PROCESS | 24069 | 1475710439681 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] 16:36:08 INFO - PROCESS | 24069 | Cycle 1(1): loaded http://localhost:47411/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47411/tests/dromaeo/cssquery-ext.html) 16:36:09 INFO - PROCESS | 24069 | RSS: Main: 232914944 16:36:09 INFO - PROCESS | 24069 | 16:39:12 INFO - PROCESS | 24069 | Cycle 1(1): loaded http://localhost:47411/tests/dromaeo/cssquery-ext.html (next: http://localhost:47411/tests/dromaeo/cssquery-jquery.html) 16:39:12 INFO - PROCESS | 24069 | RSS: Main: 229343232 16:39:12 INFO - PROCESS | 24069 | 16:42:15 INFO - PROCESS | 24069 | Cycle 1(1): loaded http://localhost:47411/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47411/tests/dromaeo/cssquery-mootools.html) 16:42:15 INFO - PROCESS | 24069 | RSS: Main: 175468544 16:42:15 INFO - PROCESS | 24069 | 16:45:17 INFO - PROCESS | 24069 | Cycle 1(1): loaded http://localhost:47411/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47411/tests/dromaeo/cssquery-prototype.html) 16:45:18 INFO - PROCESS | 24069 | RSS: Main: 203198464 16:45:18 INFO - PROCESS | 24069 | 16:48:20 INFO - PROCESS | 24069 | Cycle 1(1): loaded http://localhost:47411/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47411/tests/dromaeo/cssquery-yui.html) 16:48:21 INFO - PROCESS | 24069 | RSS: Main: 176910336 16:48:21 INFO - PROCESS | 24069 | 16:51:24 INFO - PROCESS | 24069 | Cycle 1(1): loaded http://localhost:47411/tests/dromaeo/cssquery-yui.html (next: http://localhost:47411/tests/dromaeo/cssquery-dojo.html) 16:51:24 INFO - PROCESS | 24069 | RSS: Main: 262033408 16:51:24 INFO - PROCESS | 24069 | 16:51:24 INFO - PROCESS | 24069 | __start_tp_report 16:51:24 INFO - PROCESS | 24069 | _x_x_mozilla_page_load 16:51:24 INFO - PROCESS | 24069 | _x_x_mozilla_page_load_details 16:51:24 INFO - PROCESS | 24069 | |i|pagename|runs| 16:51:24 INFO - PROCESS | 24069 | |0;dojo.html;3115.7195852373284;3156.384505021521;3163.825989570574;3165.7467402607795;3197.4724170511868;5503.394626591077;5591.1054231323;5598;5609.57928155389;5614.494695477411;5579.497845193931;5624.268845050148;5633.267675202228;5642.57680673949;5655.4061823508555;5064.544191022807;5084.694918304914;5113.565346945496;5150.304708864312;5153.1754919212935;4938.876528086808;4958.652894297401;5119.692818430887;5172.353455818023;5173.637845350827;5009.0482808266315;5032.194848824189;5074.923876141861;5080.568151707109;5080.923786143211;5002.349694539705;5018.2974383586325;5035.823746168903;5059.797608095671;5089.440161582223;5000.799968001275;5104.744762761847;5109.489051094898;5111.872203194913;5139.871503212449;5358.437364076754;5412.134058550613;5535.972320138414;5559.082751346023;5620.522255608278;5843.590948633598;5849.590528662995;5856.385079566669;5868.119782032687;5882.55880808941;5911.438413350725;6020.759169633209;6025.3372129065765;6025.78909738157;6049.425304596057;5390.64960777556;5441.700706461183;5632.577556683306;5633.267675202238;5645.237892884449;5891.263592050994;5997.970010149921;6005.519558435315;6021.367756385516;6052.909206361908;5806.187133801187;5810.912836307459;5814.883702325929;5818.272715910512;5820.243368362086;5703.058995265817;5767.942320576741;5887.617304875255;5892.234009578813;5896.056630939029;5879.3826648202175;5948.167256584082;5956.702164891738;5992.550558708114;6027.457528822427;5990.371011043865;5996.610220335664;6004.849878753066;6018.187544681499;6045.425684559959;4445.310976798602;4585.082983403266;4637.884052898705;4644.698094623906;4657.883552911204;3989.122393073519;4029;4057.269691455507;4079.89800254996;4136.193442278727;5661.377248502661;5684.516816070681;5700.429957004267;5704.971475142598;5714.485696287271;6014.578979471395;6015.6691381974415;6018;6022.789202377896;6030.276366836008;5528.640638358494;5598.1322894951345;5611.6352437091455;5656.8020119295625;5674.1488776683;5227.712475813791;5228.607854410934;5229.660072095302;5230.581553475713;5236.895262094737;6207.96896015517;6208.596441231305;6209.223847019122;6228.377162283736;6236.03341482071;5769.53843692512;5776.480116789509;5891.7643294269465;5952.880942381042;5967.582269241111;6123.83172914054;6146.938530614637;6149.84625384369;6150.600210986271;6154.661493617894;5796.507296879812;5813.157092221689;5814.30228372598;5821.825345239649;5854.677992710442;5719.914201286901;5746.2529871116485;5947.256592925884;5956.851078723066;5967.9701601491715;5861.736221870111;5929.377415371324;5935.109733540004;5968.403159683997;5971.134185543159;5881.7647294109465;5916.230889984275;5930.169776231245;5931.3772053935445;5937.4656280933195;5656.434356564475;5676.51749601272;5892.057270836645;5892.587518873637;5897.70511474416;5801.158831969256;5908.172855800278;5915.645061296336;5942.58401911862;5959.76160953557;5777.480026797609;5786.884262314646;5789.45000224978;5797.2173756542325;5797.739101740516 16:51:24 INFO - PROCESS | 24069 | |1;ext.html;586.8298193523879;613.7268915332676;614.40402809275;642.9839254018652;651.5406638319985;35133.648663513355;47404.52595474044;48133;48273.27590086143;48315.75842120789;48815.51184488154;49277.26084108741;49367.753161234235;49379;49416;8376.288015518689;9221.031791661862;9373.765655858624;9375.85936210954;9384.155426011657;8171.877421838677;8441.02928163263;8444.408891377607;10707.892921070768;10970.177236707239;11205.327680339165;11237.325760454358;11248.55005799771;11264.943675281613;11269.830952535718;11007.119430445546;11085;11102.833457498184;11109.222354435193;11109.333439993627;10627.628033018807;10677.039066483976;10683.519241634145;10685.41230232337;10693.19801014927;44719.77640111811;44923.77538112321;44928.3260751089;44961.55038449606;45041.32438013433;3788.052986753312;3840.481534992768;3861.9494929884527;3866.9613303867163;4024.4969378827645;34609.653903460894;34809.825950870334;34894.6510534894;34979;35355.469667955;4509.436320459942;4536.478304994935;4603.263477843562;4633.814647414099;4678.438587369504;354767;355479;356689;356968;357204;55555.166672499945;55713;55734.44265557292;55759.44240557543;55952.720236398556;38466.615333846305;40750.79624601858;40857.59142408597;43055.7847210762;64949.67525162344;19414.22343106288;19574.8042519573;19583.902080489508;19595.804041959684;19598.8040119597;13428.664283392798;14839.40642374293;14891.925540372229;14928.10431374121;14936.178510182044;17978.2808687651;19646;20342.593148136955;20487.590248194952;21167.576648467253;7347.70609175627;7553.395728341719;7592.582407967504;7665.655045522964;7732;972.1250874213265;1030.299396410448;1109.93446291561;1116.1294190531398;1181.8286348479423;34248.82875585606;34681.132971675404;34770.652293477244;34773.652263477044;34969.30061398758;1092.7814437112609;1150.3155622404656;1182.3674334231146;1207.849018872641;1243.3720970909633;16825.495235142964;16962.321507139575;17208.65582688339;17292.567685807957;17426.56433589145;607591;609691;610756;613492;617696;60796.696016519636;61260.38739612636;61443.07835382473;61447.6927615368;61530.69234653798;1293.0948336164722;1363.7545241856362;1364.3246592936362;1424.181095869879;1465.5090544667441;30647.54028689616;34429.65570344365;34613.82693086519;34702.30595388129;34784.82607586946;53188;53805;53857;53862.46137538574;53939;446632;449695;449860;450165;450467;30553.388932221675;30585.388292234475;31952.840235799602;32158.51762223615;33334.499982499794;51271.74364128155;51611.741941290056;51754.74122629513;51791.741041294554;51801.74099129631;42905.57094429016;43486.56513434825;43631.12737745291;44336.11327773362;44980;43663.781681091394;44504.33243501285;44895.77552112219;45589.77205113953;45849;42708.78645606877;43344;44129.33805992849;44457.555424445345;44483.33275000813;35624.28751424905;36461.63538364582;38554.80722596369;39559.40660890147;39819.80090099531;29929.850350748107;30027.549586756653;30149.849250753607;30406.087817365515;30529 16:51:24 INFO - PROCESS | 24069 | |2;jquery.html;381.498329696449;406.31535862072394;418.83037369865207;424.885280974137;435.71678409034126;14463.927680361596;16327.591810204736;19813.801861981377;20824.687629685566;21158.471038224052;20030.89984550079;20167.28578070866;20592.485187870312;20824.85463299518;20991;5913.2608423947;5928.3775203603645;6052.727627256784;6060.727267272961;6098.878022439549;4438.711483753562;4655.627549796025;6037.547183961198;6049.576529642927;6093.969530152343;6340.080688300193;6591.307912669173;6627.701753421107;6749.662516874161;6766.898496522556;6549.148610680605;6709.563878347916;6745.932540674579;6751.459883209332;6761.966190169018;5817.854553636193;5968.134620480006;5976.4919981801595;5980.1328807323125;5995.400459954013;24069.39826504334;24415.145469908646;24427.389315267083;24866.627000595006;24911.377215569573;2513.195777351248;2588.9482210355877;2590.8056895732893;2749.848758318292;2978.300099476621;14730.631734206623;14865.851341486556;15382;15405;15623.218839058001;2079.6360636888485;2117.68234764785;2281.281396360158;2307.723073231223;2371.30046636241;77093;81557.18442815555;85090;99580.50209748978;103249.48375258151;17944.282228710967;18092.728609070873;18186.45440636783;18867.245310187714;18869.905650471934;34986.475202871974;35050.64949350525;35061.47407788885;35134.82432587821;35209.82395088059;10763.623273185402;11087.113030957504;11125.388103654377;11152.832707509393;11509.194356395139;9292.16370526656;9341.953290233505;9482.573284202228;9647.228221742243;9696.321257511907;9246.537673116318;9429.15137637616;9439.575219115157;9482.715518534455;9575.138237558653;3284.3595498877967;3638.599754027055;3659.835307411173;3697.8890633280507;3754.5494540655295;932.3100905330007;980.1962390839443;1031.5873650539845;1038.7299302181486;1066.2855886556026;19902.90048549777;20147.19411223564;20267.290644827655;20505.79494205039;20853.89573052125;971.3443425687633;1009.3439264478001;1024.9436281004616;1064.9307794993301;1078.385320367383;10391.740206494747;11204.719882003015;11319.490622921989;11395.715107122223;11454.370009649549;481863;486107;507524;507699;508141;75008.62495687595;82124.58937705273;96486.51756741172;98676.50661746645;100749;988.3526290279879;998.1633793819407;1036.6889933020063;1038.870141232346;1040.458961340099;17738.822611773718;18045.548861278046;18266.178021989308;18398.54003649866;18641.161147748113;33175.33649327048;44027.33958990554;45091.54908451005;45824.312635311166;46123.53876461193;60640;61323.38676613177;62681.68659156675;63350.36649633629;64018;5877.059670452707;7626.58053807046;8789.296856251356;9066.456012639263;9477.478738669439;13352.866471335163;13435.12671676377;13467.12463689857;14105.294735263402;14126.43494260218;11747.471363788822;11818.172727909308;11935.045196384443;12212.69468263265;12311;11718.765624687288;11771.23486973344;11775.175737698279;11786.941065294619;12260.26438413698;11342.638883334053;11692.824607631062;11733.178677492835;11877.524899003944;11893.989010934167;9000.819983600422;9181.954090229507;9357.625694972125;9447.433154010781;9488.573014214238;9313.068693130634;11754.05967522579;11796;11876.406179690808;12316.815247771112 16:51:24 INFO - PROCESS | 24069 | |3;mootools.html;1479.8446163152869;1485.0273071138404;1642.70431322362;1749.1166960684852;1753.4827225968338;7609.54342739436;7889.250521200491;8179.141190175035;8812.999999999993;8813.206811386983;8244.752657420288;8546.572671366423;8559.015713192985;8724.302055835533;8728.694495692653;2416.287195277393;2441.6581678565017;2443.6823212982376;2456.299954512964;2457.0785955266774;2096.3710886733993;2154.7737487563736;2346.835721499496;2451.681281433411;2454.214651311581;2446.412860913377;2484.8012159027326;2489.4523204894895;2489.825712200138;2497.9875100624563;2381.452265978817;2409.710834699847;2431.465077682907;2432.914847980312;2439.7438268981678;2335.976640233593;2342.7305859825997;2352.3060697094397;2375.406148462884;2378.9881050594636;8823.514706691129;8873.112688731075;8877.911220887772;8883.067277935854;8888.066752990908;5320.441353657849;5455.645383050129;5470.56235501159;5485.341758988907;5493.807716729935;3241.8259882159605;3324.1024923270584;3396.1981365373417;3397.541331920184;3398.7960722356493;1164.9068074554016;1191.839101721274;1221.7373264748032;1223.5472875036205;1231.8521777386682;11642.301461912313;11652.592159274385;11655.883441165564;11663;11762.470688819023;4572.542745725401;4928.162212423825;4938.802447902115;4945.332380128709;4959.305697202394;10345.37927724339;10360.171186305077;10362.585496580052;10365.06714395708;10366.274360794672;5536.474034966672;5594.636348637326;5600.383957764642;5605.66366018032;5606.6636001840025;4983.9252411213865;5038.949610503848;5102.311187989574;5113.258577506241;5172;4097.705285040387;4157.896052598649;4200.558941311118;4256.489221293465;4284.871453856389;1156.9537218511166;1185.466540056978;1289.6324547503916;1318.854925958144;1329.6409969308233;1007.9748006299754;1012.427978192319;1024.7540590258284;1024.8513965474965;1025.1696126137851;6025.0962355646125;6061.575689701678;6085.665288409092;6185.134081228632;6359.3640635936035;957.4733896356976;977.8239916814899;1016.8678071850612;1022.8977102289712;1054.7995880782628;4070.5725898780793;4137.358709399988;4143.81352839123;4158.688098392633;4300.440942677432;10427.895721042843;10621.787564248672;10720.678379648623;10731.302465339728;10766.892331076746;10479.10927571165;10560.366378017343;10597.62908298206;10609.628662996758;10625.25623206368;7605.733799316998;7918.049834019839;8281.21328473794;8415.074341822394;8416.705415310435;5952.821415357546;5957.851053723692;5997.519710333993;6073.453389194994;6085.756569737161;9080.228180604723;9409.29430292717;9440.575174117044;9480.146786789222;9596.23230141601;9389.436633801994;9411.623535058521;9510.286728495252;9644.807103858024;9729;7568.508046977149;7742.187070357531;7777.533347999138;7846.33306168982;7869.724559640385;8309.95845020771;8361.498310101413;8362.871012413247;8363.414560980673;8401;7462.962685186756;7499.287567681062;7635.198304177981;7682.347000505019;7776.611169441619;7393.667284972082;7601.0878694557005;7663.808404789702;7747.612619369122;7961.203879611992;6337.429631333202;6449;6451.3548645135115;6454.838629034311;6474.70863811139;4580.3266989136255;4663.0207656391185;4688.7655617218325;4692.53074692528;4697.6711630185555;5062.569681577107;5072.365954255718;5150.587952963829;5212.270282160576;5243.632945693765 16:51:24 INFO - PROCESS | 24069 | |4;prototype.html;353.79479901657044;356.16834690996524;360.7709104718503;364.6481145694406;366.5033879093829;5608.411116832735;5617.8033768818095;5653.604247702662;5665.883763929837;5670.0927851543765;5311.2298716686055;5330.546903513196;5367.597430192732;5498.697571633568;5554.472325129106;4337.587929146742;4363.912721745563;4366.816591704156;4389.934150987738;4394.450693663292;4310.52584215736;4375.518692943773;4391.538888416718;4426;4438.8224471021285;4348.521502689085;4348.891277718067;4354.47746270448;4355.499117601469;4395.758233297167;4329.9567004329865;4335.544767799383;4350.390945267665;4359.956400435986;4368.628666563346;3876.457295978565;3880.9223815523815;3885.747426417274;3894.948363941748;3906.648401643866;5236.162214045772;5376.758045887906;5380.892382152335;5381.3675768855865;5460.180972854064;4973.651844370896;5003.17447621142;5007.223880298559;5010.5240002199735;5011.949880501221;5756.971215143897;5764.8847023059725;5766.7116644168045;5771.489402110095;5779.566532510079;3939.527256729182;4131.706902669018;4136.937945930814;4143.7720925349095;4156.231097247019;5929.288485381739;5980.940190598125;5997.820065398045;5999.460048595603;6016.939830601682;4321.6974811762875;4330.17726631939;4330.718503297276;4336.783160841946;4368.344748287782;4225.429567008476;4360.324149756792;4363.19280933022;4364.650827933758;4373.081652852937;4904.926426103611;4936.6297527685565;4946.455889852114;4949.554540091409;4958.578520825698;5782.53048171094;5784;5788.5369170466265;5800.883982320414;5850.138908320573;1421.9360128794021;1423.8647328503775;1465.5823090419392;1477.6453651123868;1490.7167638148717;1031.7884833609091;1036.6060896859296;1038.2939601071344;1046.6493724602192;1049.354646892171;4880.365552478226;4905.3623029006;4939.221880123153;4952.706450210641;4990.8752281193265;5160.580840268881;5193.324867767242;5195.233620569024;5208.8437346879655;5252.29094072303;4803.303199231663;4804.495527969582;4943.047496238965;4953.507777017194;4982.651214415028;2792.357757715696;2792.664880214347;2795.664520257582;2805.8316501009595;2809;5339.786408543692;5340.679559226459;5383.542398896059;5403.432639572867;5412.56699464042;4955.033768415196;5300.913312770873;5305.540976231568;5310.468953104736;5324.387695415076;5243.449437809128;5243.895122097613;5257.553107985787;5285.841424757186;5314.166612518675;5132.717700526507;5196.896062078813;5204.34945631796;5283.920741188962;5302.806868454722;4786.904261914672;5110.923336149886;5143.665661731976;5190.351206099238;5190.455002224712;5300.734963251745;5354.54486368663;5391.649542779707;5411.538884501241;5422.267993820784;3942.15243722601;3960.5247370315747;3963.187546552892;3984.163325701635;3990.30169720292;4866.78099485531;5087.720175390389;5111.3355263815465;5292.947070529245;5679.630823996427;5698.31620205578;5719.684472571249;5808.506276966336;5841.883162336644;5847.298324201123;5823.359430462561;5836.474717275466;5845.707714614167;5863.237779088691;5871.6770577618645;5676.574256930664;5791.333996590446;5792.902246023473;5815.33123690764;5835.4748072673665;5829.358770535323;5849.4791354249055;5850.765969361348;5853.765849365977;5859.212940053325;5620.950726076703;5648.378678345297;5714.457126572969;5747.971260143672;5756.798512052058 16:51:24 INFO - PROCESS | 24069 | |5;yui.html;829.7054545636299;832.7085520067977;863.7106569299284;894.7226359828458;942.9717108486745;16679.583010424747;18286.268549258046;18328.81671183288;18331.816681833177;18359.908200458995;16526.917365413185;16693.49919502414;16697.332106715716;16699.165041747896;16707.91646041768;721.9891701624467;752.1200195770933;851.0723311590365;884.8893888263967;891.4428482198626;873.1225118755642;875.3041332140931;875.7022612311813;876.7808047988003;876.8334016536869;926.3191554207652;927.0497739816674;927.4759760735196;928.0348437624896;928.0997432490474;765.8889461028148;790.8655528560158;884.9601767920427;911.9544022798833;923.672096405773;675.4123912196371;675.5743881354764;675.7330854312519;676.1345477788439;676.1683129750386;16041.35834566616;16050;16116.435924742578;16120.516384508484;16144.434944776995;15057.322420491104;15531.611709707235;15813.60465988348;15965.92017039919;16120.59698507535;14219.786703199461;14387.928060359736;14459;14596.27018649074;15247.466338678203;4622.699524530928;4719.740414277213;4806.038792241566;4818.638602104857;4829.203181475087;89784;91963;93419;94205;95555;12192.512299508098;12233.571824986082;12292.323922184187;12420.875791241971;12611.432485538173;23672.763272367058;23683.407914801923;23729.28812135639;23744.88127559351;24155.15456958998;9116.817663646823;9279.396839205565;9284.907150928539;9335.393199442151;9503.002184770638;4941.357623508921;5013.448520662724;5039.982538643161;5144.922826157611;5246.950609878039;3622.873199438007;3738.43923411485;3752;3756.6243375662216;3766.4350347447553;594.1729527726393;654.6170490263249;660.9702563384659;661.0612929639923;686.0977814174345;754.381407246064;764.4495962906698;779.9181085986003;794.694042793517;797.9162187970179;10257.33327333721;10949.83575246372;11027.83458248127;11149.721256968478;11189.859751891852;718.1956209045902;774.0792884042307;802.5144787403653;806.3589446390134;829.1418381974627;4901.289313049588;5319.0957537218765;5353.544948679328;5355.250264962909;5363.19552067193;124073.37963310126;124811;125669;129679.35160324138;132752;65916.67041664761;67215.3278467209;67436.6628166856;111299;115286;58930;76893.61553192198;78620;79290;87314.56342718245;9989.150922171697;10307;10882.891171088504;11048.392338421463;11061.723456913642;49082.50917490779;49995.500044999084;50246.99999999854;52957.7352113237;53971;63117.34982577604;64555.35444645681;75475.62262188653;77571;78292.60853695695;12962.287074210632;13115.672108197372;13355.131916425402;13481.123726957716;13606.183628982293;16235;16283.185840708155;16486.69532999127;16747;17288.222030008932;14750.188739619423;14768.409263629335;14909.552713418614;14980.850191497946;15674.451394201149;13367.598972030466;14031.368588413341;14050.227237502035;14375.568732938029;14449.566513004627;12537.561185358469;12744.4265008073;12942.67643308887;13013;14585.124892506483;11719.296842189497;13077.607671769862;13528.59414217575;13726.382312795748;13910.860891390956;12194.634160975185;12472.314022728837;13575;13641.522546710818;13749.862501375259 16:51:24 INFO - PROCESS | 24069 | __end_tp_report 16:51:24 INFO - PROCESS | 24069 | __start_cc_report 16:51:24 INFO - PROCESS | 24069 | _x_x_mozilla_cycle_collect,1009 16:51:24 INFO - PROCESS | 24069 | __end_cc_report 16:51:24 INFO - PROCESS | 24069 | __startTimestamp1475711484311__endTimestamp 16:51:24 INFO - PROCESS | 24069 | 16:51:24 INFO - PROCESS | 24069 | ------- Summary: start ------- 16:51:24 INFO - PROCESS | 24069 | Number of tests: 6 16:51:24 INFO - PROCESS | 24069 | 16:51:24 INFO - PROCESS | 24069 | [#0] dojo.html Cycles:165 Average:5537.93 Median:5776.48 stddev:631.46 (10.9%) stddev-sans-first:604.13 16:51:24 INFO - PROCESS | 24069 | Values: 3115.7 3156.4 3163.8 3165.7 3197.5 5503.4 5591.1 5598.0 5609.6 5614.5 5579.5 5624.3 5633.3 5642.6 5655.4 5064.5 5084.7 5113.6 5150.3 5153.2 4938.9 4958.7 5119.7 5172.4 5173.6 5009.0 5032.2 5074.9 5080.6 5080.9 5002.3 5018.3 5035.8 5059.8 5089.4 5000.8 5104.7 5109.5 5111.9 5139.9 5358.4 5412.1 5536.0 5559.1 5620.5 5843.6 5849.6 5856.4 5868.1 5882.6 5911.4 6020.8 6025.3 6025.8 6049.4 5390.6 5441.7 5632.6 5633.3 5645.2 5891.3 5998.0 6005.5 6021.4 6052.9 5806.2 5810.9 5814.9 5818.3 5820.2 5703.1 5767.9 5887.6 5892.2 5896.1 5879.4 5948.2 5956.7 5992.6 6027.5 5990.4 5996.6 6004.8 6018.2 6045.4 4445.3 4585.1 4637.9 4644.7 4657.9 3989.1 4029.0 4057.3 4079.9 4136.2 5661.4 5684.5 5700.4 5705.0 5714.5 6014.6 6015.7 6018.0 6022.8 6030.3 5528.6 5598.1 5611.6 5656.8 5674.1 5227.7 5228.6 5229.7 5230.6 5236.9 6208.0 6208.6 6209.2 6228.4 6236.0 5769.5 5776.5 5891.8 5952.9 5967.6 6123.8 6146.9 6149.8 6150.6 6154.7 5796.5 5813.2 5814.3 5821.8 5854.7 5719.9 5746.3 5947.3 5956.9 5968.0 5861.7 5929.4 5935.1 5968.4 5971.1 5881.8 5916.2 5930.2 5931.4 5937.5 5656.4 5676.5 5892.1 5892.6 5897.7 5801.2 5908.2 5915.6 5942.6 5959.8 5777.5 5786.9 5789.5 5797.2 5797.7 16:51:24 INFO - PROCESS | 24069 | 16:51:24 INFO - PROCESS | 24069 | [#1] ext.html Cycles:180 Average:64002.37 Median:39689.60 stddev:128547.74 (323.9%) stddev-sans-first:128820.18 16:51:24 INFO - PROCESS | 24069 | Values: 586.8 613.7 614.4 643.0 651.5 35133.6 47404.5 48133.0 48273.3 48315.8 48815.5 49277.3 49367.8 49379.0 49416.0 8376.3 9221.0 9373.8 9375.9 9384.2 8171.9 8441.0 8444.4 10707.9 10970.2 11205.3 11237.3 11248.6 11264.9 11269.8 11007.1 11085.0 11102.8 11109.2 11109.3 10627.6 10677.0 10683.5 10685.4 10693.2 44719.8 44923.8 44928.3 44961.6 45041.3 3788.1 3840.5 3861.9 3867.0 4024.5 34609.7 34809.8 34894.7 34979.0 35355.5 4509.4 4536.5 4603.3 4633.8 4678.4 354767.0 355479.0 356689.0 356968.0 357204.0 55555.2 55713.0 55734.4 55759.4 55952.7 38466.6 40750.8 40857.6 43055.8 64949.7 19414.2 19574.8 19583.9 19595.8 19598.8 13428.7 14839.4 14891.9 14928.1 14936.2 17978.3 19646.0 20342.6 20487.6 21167.6 7347.7 7553.4 7592.6 7665.7 7732.0 972.1 1030.3 1109.9 1116.1 1181.8 34248.8 34681.1 34770.7 34773.7 34969.3 1092.8 1150.3 1182.4 1207.8 1243.4 16825.5 16962.3 17208.7 17292.6 17426.6 607591.0 609691.0 610756.0 613492.0 617696.0 60796.7 61260.4 61443.1 61447.7 61530.7 1293.1 1363.8 1364.3 1424.2 1465.5 30647.5 34429.7 34613.8 34702.3 34784.8 53188.0 53805.0 53857.0 53862.5 53939.0 446632.0 449695.0 449860.0 450165.0 450467.0 30553.4 30585.4 31952.8 32158.5 33334.5 51271.7 51611.7 51754.7 51791.7 51801.7 42905.6 43486.6 43631.1 44336.1 44980.0 43663.8 44504.3 44895.8 45589.8 45849.0 42708.8 43344.0 44129.3 44457.6 44483.3 35624.3 36461.6 38554.8 39559.4 39819.8 29929.9 30027.5 30149.8 30406.1 30529.0 16:51:24 INFO - PROCESS | 24069 | 16:51:24 INFO - PROCESS | 24069 | [#2] jquery.html Cycles:180 Average:31009.34 Median:3131.33 stddev:82116.70 (2622.4%) stddev-sans-first:82314.85 16:51:24 INFO - PROCESS | 24069 | Values: 381.5 406.3 418.8 424.9 435.7 14463.9 16327.6 19813.8 20824.7 21158.5 20030.9 20167.3 20592.5 20824.9 20991.0 5913.3 5928.4 6052.7 6060.7 6098.9 4438.7 4655.6 6037.5 6049.6 6094.0 6340.1 6591.3 6627.7 6749.7 6766.9 6549.1 6709.6 6745.9 6751.5 6762.0 5817.9 5968.1 5976.5 5980.1 5995.4 24069.4 24415.1 24427.4 24866.6 24911.4 2513.2 2588.9 2590.8 2749.8 2978.3 14730.6 14865.9 15382.0 15405.0 15623.2 2079.6 2117.7 2281.3 2307.7 2371.3 77093.0 81557.2 85090.0 99580.5 103249.5 17944.3 18092.7 18186.5 18867.2 18869.9 34986.5 35050.6 35061.5 35134.8 35209.8 10763.6 11087.1 11125.4 11152.8 11509.2 9292.2 9342.0 9482.6 9647.2 9696.3 9246.5 9429.2 9439.6 9482.7 9575.1 3284.4 3638.6 3659.8 3697.9 3754.5 932.3 980.2 1031.6 1038.7 1066.3 19902.9 20147.2 20267.3 20505.8 20853.9 971.3 1009.3 1024.9 1064.9 1078.4 10391.7 11204.7 11319.5 11395.7 11454.4 481863.0 486107.0 507524.0 507699.0 508141.0 75008.6 82124.6 96486.5 98676.5 100749.0 988.4 998.2 1036.7 1038.9 1040.5 17738.8 18045.5 18266.2 18398.5 18641.2 33175.3 44027.3 45091.5 45824.3 46123.5 60640.0 61323.4 62681.7 63350.4 64018.0 5877.1 7626.6 8789.3 9066.5 9477.5 13352.9 13435.1 13467.1 14105.3 14126.4 11747.5 11818.2 11935.0 12212.7 12311.0 11718.8 11771.2 11775.2 11786.9 12260.3 11342.6 11692.8 11733.2 11877.5 11894.0 9000.8 9182.0 9357.6 9447.4 9488.6 9313.1 11754.1 11796.0 11876.4 12316.8 16:51:24 INFO - PROCESS | 24069 | 16:51:24 INFO - PROCESS | 24069 | [#3] mootools.html Cycles:180 Average:5739.94 Median:4942.07 stddev:3138.80 (63.5%) stddev-sans-first:3131.27 16:51:24 INFO - PROCESS | 24069 | Values: 1479.8 1485.0 1642.7 1749.1 1753.5 7609.5 7889.3 8179.1 8813.0 8813.2 8244.8 8546.6 8559.0 8724.3 8728.7 2416.3 2441.7 2443.7 2456.3 2457.1 2096.4 2154.8 2346.8 2451.7 2454.2 2446.4 2484.8 2489.5 2489.8 2498.0 2381.5 2409.7 2431.5 2432.9 2439.7 2336.0 2342.7 2352.3 2375.4 2379.0 8823.5 8873.1 8877.9 8883.1 8888.1 5320.4 5455.6 5470.6 5485.3 5493.8 3241.8 3324.1 3396.2 3397.5 3398.8 1164.9 1191.8 1221.7 1223.5 1231.9 11642.3 11652.6 11655.9 11663.0 11762.5 4572.5 4928.2 4938.8 4945.3 4959.3 10345.4 10360.2 10362.6 10365.1 10366.3 5536.5 5594.6 5600.4 5605.7 5606.7 4983.9 5038.9 5102.3 5113.3 5172.0 4097.7 4157.9 4200.6 4256.5 4284.9 1157.0 1185.5 1289.6 1318.9 1329.6 1008.0 1012.4 1024.8 1024.9 1025.2 6025.1 6061.6 6085.7 6185.1 6359.4 957.5 977.8 1016.9 1022.9 1054.8 4070.6 4137.4 4143.8 4158.7 4300.4 10427.9 10621.8 10720.7 10731.3 10766.9 10479.1 10560.4 10597.6 10609.6 10625.3 7605.7 7918.0 8281.2 8415.1 8416.7 5952.8 5957.9 5997.5 6073.5 6085.8 9080.2 9409.3 9440.6 9480.1 9596.2 9389.4 9411.6 9510.3 9644.8 9729.0 7568.5 7742.2 7777.5 7846.3 7869.7 8310.0 8361.5 8362.9 8363.4 8401.0 7463.0 7499.3 7635.2 7682.3 7776.6 7393.7 7601.1 7663.8 7747.6 7961.2 6337.4 6449.0 6451.4 6454.8 6474.7 4580.3 4663.0 4688.8 4692.5 4697.7 5062.6 5072.4 5150.6 5212.3 5243.6 16:51:24 INFO - PROCESS | 24069 | 16:51:24 INFO - PROCESS | 24069 | [#4] prototype.html Cycles:180 Average:4679.03 Median:5122.03 stddev:1329.78 (26.0%) stddev-sans-first:1293.27 16:51:24 INFO - PROCESS | 24069 | Values: 353.8 356.2 360.8 364.6 366.5 5608.4 5617.8 5653.6 5665.9 5670.1 5311.2 5330.5 5367.6 5498.7 5554.5 4337.6 4363.9 4366.8 4389.9 4394.5 4310.5 4375.5 4391.5 4426.0 4438.8 4348.5 4348.9 4354.5 4355.5 4395.8 4330.0 4335.5 4350.4 4360.0 4368.6 3876.5 3880.9 3885.7 3894.9 3906.6 5236.2 5376.8 5380.9 5381.4 5460.2 4973.7 5003.2 5007.2 5010.5 5011.9 5757.0 5764.9 5766.7 5771.5 5779.6 3939.5 4131.7 4136.9 4143.8 4156.2 5929.3 5980.9 5997.8 5999.5 6016.9 4321.7 4330.2 4330.7 4336.8 4368.3 4225.4 4360.3 4363.2 4364.7 4373.1 4904.9 4936.6 4946.5 4949.6 4958.6 5782.5 5784.0 5788.5 5800.9 5850.1 1421.9 1423.9 1465.6 1477.6 1490.7 1031.8 1036.6 1038.3 1046.6 1049.4 4880.4 4905.4 4939.2 4952.7 4990.9 5160.6 5193.3 5195.2 5208.8 5252.3 4803.3 4804.5 4943.0 4953.5 4982.7 2792.4 2792.7 2795.7 2805.8 2809.0 5339.8 5340.7 5383.5 5403.4 5412.6 4955.0 5300.9 5305.5 5310.5 5324.4 5243.4 5243.9 5257.6 5285.8 5314.2 5132.7 5196.9 5204.3 5283.9 5302.8 4786.9 5110.9 5143.7 5190.4 5190.5 5300.7 5354.5 5391.6 5411.5 5422.3 3942.2 3960.5 3963.2 3984.2 3990.3 4866.8 5087.7 5111.3 5292.9 5679.6 5698.3 5719.7 5808.5 5841.9 5847.3 5823.4 5836.5 5845.7 5863.2 5871.7 5676.6 5791.3 5792.9 5815.3 5835.5 5829.4 5849.5 5850.8 5853.8 5859.2 5621.0 5648.4 5714.5 5748.0 5756.8 16:51:24 INFO - PROCESS | 24069 | 16:51:24 INFO - PROCESS | 24069 | [#5] yui.html Cycles:180 Average:21601.92 Median:4194.57 stddev:30584.02 (729.1%) stddev-sans-first:30630.04 16:51:24 INFO - PROCESS | 24069 | Values: 829.7 832.7 863.7 894.7 943.0 16679.6 18286.3 18328.8 18331.8 18359.9 16526.9 16693.5 16697.3 16699.2 16707.9 722.0 752.1 851.1 884.9 891.4 873.1 875.3 875.7 876.8 876.8 926.3 927.0 927.5 928.0 928.1 765.9 790.9 885.0 912.0 923.7 675.4 675.6 675.7 676.1 676.2 16041.4 16050.0 16116.4 16120.5 16144.4 15057.3 15531.6 15813.6 15965.9 16120.6 14219.8 14387.9 14459.0 14596.3 15247.5 4622.7 4719.7 4806.0 4818.6 4829.2 89784.0 91963.0 93419.0 94205.0 95555.0 12192.5 12233.6 12292.3 12420.9 12611.4 23672.8 23683.4 23729.3 23744.9 24155.2 9116.8 9279.4 9284.9 9335.4 9503.0 4941.4 5013.4 5040.0 5144.9 5247.0 3622.9 3738.4 3752.0 3756.6 3766.4 594.2 654.6 661.0 661.1 686.1 754.4 764.4 779.9 794.7 797.9 10257.3 10949.8 11027.8 11149.7 11189.9 718.2 774.1 802.5 806.4 829.1 4901.3 5319.1 5353.5 5355.3 5363.2 124073.4 124811.0 125669.0 129679.4 132752.0 65916.7 67215.3 67436.7 111299.0 115286.0 58930.0 76893.6 78620.0 79290.0 87314.6 9989.2 10307.0 10882.9 11048.4 11061.7 49082.5 49995.5 50247.0 52957.7 53971.0 63117.3 64555.4 75475.6 77571.0 78292.6 12962.3 13115.7 13355.1 13481.1 13606.2 16235.0 16283.2 16486.7 16747.0 17288.2 14750.2 14768.4 14909.6 14980.9 15674.5 13367.6 14031.4 14050.2 14375.6 14449.6 12537.6 12744.4 12942.7 13013.0 14585.1 11719.3 13077.6 13528.6 13726.4 13910.9 12194.6 12472.3 13575.0 13641.5 13749.9 16:51:24 INFO - PROCESS | 24069 | -------- Summary: end -------- 16:51:24 INFO - PROCESS | 24069 | 16:51:25 INFO - TEST-INFO | 24069: exit 0 16:51:25 INFO - TEST-OK | dromaeo_css | took 1113721ms 16:51:25 INFO - TEST-START | kraken 16:51:25 INFO - operating with platform_type : linux_ 16:51:25 INFO - Initialising browser for kraken test... 16:51:25 INFO - TEST-INFO | started process 24389 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6nQqxN/profile http://localhost:47411/getInfo.html) 16:51:28 INFO - PROCESS | 24389 | __metrics Screen width/height:1600/1200 16:51:28 INFO - PROCESS | 24389 | colorDepth:24 16:51:28 INFO - PROCESS | 24389 | Browser inner width/height: 1024/697 16:51:28 INFO - PROCESS | 24389 | __metrics 16:51:29 INFO - PROCESS | 24389 | 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" 16:51:29 INFO - PROCESS | 24389 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:51:29 INFO - PROCESS | 24389 | console.error: PushService: 16:51:29 INFO - PROCESS | 24389 | stateChangeProcessEnqueue: Error transitioning state 16:51:29 INFO - PROCESS | 24389 | UnknownError 16:51:30 INFO - TEST-INFO | 24389: exit 0 16:51:30 INFO - Browser initialized. 16:51:30 INFO - Running cycle 1/1 for kraken test... 16:51:30 INFO - TEST-INFO | started process 24481 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6nQqxN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:51:42 INFO - PROCESS | 24481 | RSS: Main: 144257024 16:51:42 INFO - PROCESS | 24481 | 16:51:44 INFO - PROCESS | 24481 | Cycle 1(1): loaded http://localhost:47411/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47411/tests/kraken/driver.html?testName=audio-beat-detection) 16:51:45 INFO - PROCESS | 24481 | RSS: Main: 186150912 16:51:45 INFO - PROCESS | 24481 | 16:51:48 INFO - PROCESS | 24481 | Cycle 1(1): loaded http://localhost:47411/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47411/tests/kraken/driver.html?testName=audio-dft) 16:51:48 INFO - PROCESS | 24481 | RSS: Main: 175288320 16:51:48 INFO - PROCESS | 24481 | 16:51:51 INFO - PROCESS | 24481 | Cycle 1(1): loaded http://localhost:47411/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47411/tests/kraken/driver.html?testName=audio-fft) 16:51:52 INFO - PROCESS | 24481 | RSS: Main: 266149888 16:51:52 INFO - PROCESS | 24481 | 16:51:54 INFO - PROCESS | 24481 | Cycle 1(1): loaded http://localhost:47411/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47411/tests/kraken/driver.html?testName=audio-oscillator) 16:51:54 INFO - PROCESS | 24481 | RSS: Main: 173617152 16:51:54 INFO - PROCESS | 24481 | 16:51:56 INFO - PROCESS | 24481 | Cycle 1(1): loaded http://localhost:47411/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47411/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:51:57 INFO - PROCESS | 24481 | RSS: Main: 175153152 16:51:57 INFO - PROCESS | 24481 | 16:52:00 INFO - PROCESS | 24481 | Cycle 1(1): loaded http://localhost:47411/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47411/tests/kraken/driver.html?testName=imaging-darkroom) 16:52:00 INFO - PROCESS | 24481 | RSS: Main: 211296256 16:52:00 INFO - PROCESS | 24481 | 16:52:04 INFO - PROCESS | 24481 | Cycle 1(1): loaded http://localhost:47411/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47411/tests/kraken/driver.html?testName=imaging-desaturate) 16:52:04 INFO - PROCESS | 24481 | RSS: Main: 209432576 16:52:04 INFO - PROCESS | 24481 | 16:52:07 INFO - PROCESS | 24481 | Cycle 1(1): loaded http://localhost:47411/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47411/tests/kraken/driver.html?testName=json-parse-financial) 16:52:08 INFO - PROCESS | 24481 | RSS: Main: 209199104 16:52:08 INFO - PROCESS | 24481 | 16:52:09 INFO - PROCESS | 24481 | Cycle 1(1): loaded http://localhost:47411/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47411/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:52:10 INFO - PROCESS | 24481 | RSS: Main: 171315200 16:52:10 INFO - PROCESS | 24481 | 16:52:12 INFO - PROCESS | 24481 | Cycle 1(1): loaded http://localhost:47411/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47411/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:52:12 INFO - PROCESS | 24481 | RSS: Main: 178319360 16:52:12 INFO - PROCESS | 24481 | 16:52:14 INFO - PROCESS | 24481 | Cycle 1(1): loaded http://localhost:47411/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47411/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:52:15 INFO - PROCESS | 24481 | RSS: Main: 188125184 16:52:15 INFO - PROCESS | 24481 | 16:52:17 INFO - PROCESS | 24481 | Cycle 1(1): loaded http://localhost:47411/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47411/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:52:18 INFO - PROCESS | 24481 | RSS: Main: 190455808 16:52:18 INFO - PROCESS | 24481 | 16:52:21 INFO - PROCESS | 24481 | Cycle 1(1): loaded http://localhost:47411/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47411/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:52:21 INFO - PROCESS | 24481 | RSS: Main: 191913984 16:52:21 INFO - PROCESS | 24481 | 16:52:23 INFO - PROCESS | 24481 | Cycle 1(1): loaded http://localhost:47411/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47411/tests/kraken/driver.html?testName=ai-astar) 16:52:23 INFO - PROCESS | 24481 | RSS: Main: 243023872 16:52:23 INFO - PROCESS | 24481 | 16:52:23 INFO - PROCESS | 24481 | __start_tp_report 16:52:23 INFO - PROCESS | 24481 | _x_x_mozilla_page_load 16:52:23 INFO - PROCESS | 24481 | _x_x_mozilla_page_load_details 16:52:23 INFO - PROCESS | 24481 | |i|pagename|runs| 16:52:23 INFO - PROCESS | 24481 | |0;ai-astar;109;111;110;109;110;111;109;109;110;110 16:52:23 INFO - PROCESS | 24481 | |1;audio-beat-detection;144;117;120;168;125;118;123;121;147;117 16:52:23 INFO - PROCESS | 24481 | |2;audio-dft;160;161;161;160;160;161;161;161;162;159 16:52:23 INFO - PROCESS | 24481 | |3;audio-fft;75;75;76;101;76;75;76;76;99;75 16:52:23 INFO - PROCESS | 24481 | |4;audio-oscillator;82;81;117;84;81;82;117;82;80;125 16:52:23 INFO - PROCESS | 24481 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;116;117 16:52:23 INFO - PROCESS | 24481 | |6;imaging-darkroom;145;145;146;149;146;145;146;146;145;145 16:52:23 INFO - PROCESS | 24481 | |7;imaging-desaturate;94;95;103;95;96;95;95;94;94;94 16:52:23 INFO - PROCESS | 24481 | |8;json-parse-financial;48;49;50;49;49;50;49;50;49;48 16:52:23 INFO - PROCESS | 24481 | |9;json-stringify-tinderbox;59;68;58;57;58;58;58;58;59;59 16:52:23 INFO - PROCESS | 24481 | |10;stanford-crypto-aes;81;76;73;76;82;74;74;73;73;74 16:52:23 INFO - PROCESS | 24481 | |11;stanford-crypto-ccm;125;128;121;128;139;122;132;119;164;141 16:52:23 INFO - PROCESS | 24481 | |12;stanford-crypto-pbkdf2;152;153;150;148;156;152;152;151;153;153 16:52:23 INFO - PROCESS | 24481 | |13;stanford-crypto-sha256-iterative;63;62;62;61;63;62;61;62;61;59 16:52:23 INFO - PROCESS | 24481 | __end_tp_report 16:52:23 INFO - PROCESS | 24481 | __start_cc_report 16:52:23 INFO - PROCESS | 24481 | _x_x_mozilla_cycle_collect,1570 16:52:23 INFO - PROCESS | 24481 | __end_cc_report 16:52:23 INFO - PROCESS | 24481 | __startTimestamp1475711543579__endTimestamp 16:52:23 INFO - PROCESS | 24481 | 16:52:23 INFO - PROCESS | 24481 | ------- Summary: start ------- 16:52:23 INFO - PROCESS | 24481 | Number of tests: 14 16:52:23 INFO - PROCESS | 24481 | 16:52:23 INFO - PROCESS | 24481 | [#0] ai-astar Cycles:10 Average:109.80 Median:110.00 stddev:0.79 (0.7%) stddev-sans-first:0.78 16:52:23 INFO - PROCESS | 24481 | Values: 109.0 111.0 110.0 109.0 110.0 111.0 109.0 109.0 110.0 110.0 16:52:23 INFO - PROCESS | 24481 | 16:52:23 INFO - PROCESS | 24481 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:124.00 stddev:17.21 (13.9%) stddev-sans-first:17.49 16:52:23 INFO - PROCESS | 24481 | Values: 144.0 117.0 120.0 168.0 125.0 118.0 123.0 121.0 147.0 117.0 16:52:23 INFO - PROCESS | 24481 | 16:52:23 INFO - PROCESS | 24481 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.84 (0.5%) stddev-sans-first:0.87 16:52:23 INFO - PROCESS | 24481 | Values: 160.0 161.0 161.0 160.0 160.0 161.0 161.0 161.0 162.0 159.0 16:52:23 INFO - PROCESS | 24481 | 16:52:23 INFO - PROCESS | 24481 | [#3] audio-fft Cycles:10 Average:80.40 Median:76.00 stddev:10.35 (13.6%) stddev-sans-first:10.79 16:52:23 INFO - PROCESS | 24481 | Values: 75.0 75.0 76.0 101.0 76.0 75.0 76.0 76.0 99.0 75.0 16:52:23 INFO - PROCESS | 24481 | 16:52:23 INFO - PROCESS | 24481 | [#4] audio-oscillator Cycles:10 Average:93.10 Median:81.50 stddev:18.49 (22.7%) stddev-sans-first:19.17 16:52:23 INFO - PROCESS | 24481 | Values: 82.0 81.0 117.0 84.0 81.0 82.0 117.0 82.0 80.0 125.0 16:52:23 INFO - PROCESS | 24481 | 16:52:23 INFO - PROCESS | 24481 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:52:23 INFO - PROCESS | 24481 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 16:52:23 INFO - PROCESS | 24481 | 16:52:23 INFO - PROCESS | 24481 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:1.23 (0.8%) stddev-sans-first:1.27 16:52:23 INFO - PROCESS | 24481 | Values: 145.0 145.0 146.0 149.0 146.0 145.0 146.0 146.0 145.0 145.0 16:52:23 INFO - PROCESS | 24481 | 16:52:23 INFO - PROCESS | 24481 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.00 stddev:2.72 (2.9%) stddev-sans-first:2.83 16:52:23 INFO - PROCESS | 24481 | Values: 94.0 95.0 103.0 95.0 96.0 95.0 95.0 94.0 94.0 94.0 16:52:23 INFO - PROCESS | 24481 | 16:52:23 INFO - PROCESS | 24481 | [#8] json-parse-financial Cycles:10 Average:49.10 Median:49.00 stddev:0.74 (1.5%) stddev-sans-first:0.67 16:52:23 INFO - PROCESS | 24481 | Values: 48.0 49.0 50.0 49.0 49.0 50.0 49.0 50.0 49.0 48.0 16:52:23 INFO - PROCESS | 24481 | 16:52:23 INFO - PROCESS | 24481 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:58.50 stddev:3.16 (5.4%) stddev-sans-first:3.35 16:52:23 INFO - PROCESS | 24481 | Values: 59.0 68.0 58.0 57.0 58.0 58.0 58.0 58.0 59.0 59.0 16:52:23 INFO - PROCESS | 24481 | 16:52:23 INFO - PROCESS | 24481 | [#10] stanford-crypto-aes Cycles:10 Average:75.60 Median:75.00 stddev:3.31 (4.4%) stddev-sans-first:2.87 16:52:23 INFO - PROCESS | 24481 | Values: 81.0 76.0 73.0 76.0 82.0 74.0 74.0 73.0 73.0 74.0 16:52:23 INFO - PROCESS | 24481 | 16:52:23 INFO - PROCESS | 24481 | [#11] stanford-crypto-ccm Cycles:10 Average:131.90 Median:130.00 stddev:13.44 (10.3%) stddev-sans-first:14.02 16:52:23 INFO - PROCESS | 24481 | Values: 125.0 128.0 121.0 128.0 139.0 122.0 132.0 119.0 164.0 141.0 16:52:23 INFO - PROCESS | 24481 | 16:52:23 INFO - PROCESS | 24481 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.00 Median:152.50 stddev:2.11 (1.4%) stddev-sans-first:2.24 16:52:23 INFO - PROCESS | 24481 | Values: 152.0 153.0 150.0 148.0 156.0 152.0 152.0 151.0 153.0 153.0 16:52:23 INFO - PROCESS | 24481 | 16:52:23 INFO - PROCESS | 24481 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:62.00 stddev:1.17 (1.9%) stddev-sans-first:1.13 16:52:23 INFO - PROCESS | 24481 | Values: 63.0 62.0 62.0 61.0 63.0 62.0 61.0 62.0 61.0 59.0 16:52:23 INFO - PROCESS | 24481 | -------- Summary: end -------- 16:52:23 INFO - PROCESS | 24481 | 16:52:24 INFO - TEST-INFO | 24481: exit 0 16:52:24 INFO - TEST-OK | kraken | took 59485ms 16:52:24 INFO - SUITE-END | took 1173s 16:52:24 INFO - Completed test suite (00:19:33) 16:52:24 INFO - javascript benchmark 16:52:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3115.7195852373284, 3156.384505021521, 3163.825989570574, 3165.7467402607795, 3197.4724170511868, 5503.394626591077, 5591.1054231323, 5598.0, 5609.57928155389, 5614.494695477411, 5579.497845193931, 5624.268845050148, 5633.267675202228, 5642.57680673949, 5655.4061823508555, 5064.544191022807, 5084.694918304914, 5113.565346945496, 5150.304708864312, 5153.1754919212935, 4938.876528086808, 4958.652894297401, 5119.692818430887, 5172.353455818023, 5173.637845350827, 5009.0482808266315, 5032.194848824189, 5074.923876141861, 5080.568151707109, 5080.923786143211, 5002.349694539705, 5018.2974383586325, 5035.823746168903, 5059.797608095671, 5089.440161582223, 5000.799968001275, 5104.744762761847, 5109.489051094898, 5111.872203194913, 5139.871503212449, 5358.437364076754, 5412.134058550613, 5535.972320138414, 5559.082751346023, 5620.522255608278, 5843.590948633598, 5849.590528662995, 5856.385079566669, 5868.119782032687, 5882.55880808941, 5911.438413350725, 6020.759169633209, 6025.3372129065765, 6025.78909738157, 6049.425304596057, 5390.64960777556, 5441.700706461183, 5632.577556683306, 5633.267675202238, 5645.237892884449, 5891.263592050994, 5997.970010149921, 6005.519558435315, 6021.367756385516, 6052.909206361908, 5806.187133801187, 5810.912836307459, 5814.883702325929, 5818.272715910512, 5820.243368362086, 5703.058995265817, 5767.942320576741, 5887.617304875255, 5892.234009578813, 5896.056630939029, 5879.3826648202175, 5948.167256584082, 5956.702164891738, 5992.550558708114, 6027.457528822427, 5990.371011043865, 5996.610220335664, 6004.849878753066, 6018.187544681499, 6045.425684559959, 4445.310976798602, 4585.082983403266, 4637.884052898705, 4644.698094623906, 4657.883552911204, 3989.122393073519, 4029.0, 4057.269691455507, 4079.89800254996, 4136.193442278727, 5661.377248502661, 5684.516816070681, 5700.429957004267, 5704.971475142598, 5714.485696287271, 6014.578979471395, 6015.6691381974415, 6018.0, 6022.789202377896, 6030.276366836008, 5528.640638358494, 5598.1322894951345, 5611.6352437091455, 5656.8020119295625, 5674.1488776683, 5227.712475813791, 5228.607854410934, 5229.660072095302, 5230.581553475713, 5236.895262094737, 6207.96896015517, 6208.596441231305, 6209.223847019122, 6228.377162283736, 6236.03341482071, 5769.53843692512, 5776.480116789509, 5891.7643294269465, 5952.880942381042, 5967.582269241111, 6123.83172914054, 6146.938530614637, 6149.84625384369, 6150.600210986271, 6154.661493617894, 5796.507296879812, 5813.157092221689, 5814.30228372598, 5821.825345239649, 5854.677992710442, 5719.914201286901, 5746.2529871116485, 5947.256592925884, 5956.851078723066, 5967.9701601491715, 5861.736221870111, 5929.377415371324, 5935.109733540004, 5968.403159683997, 5971.134185543159, 5881.7647294109465, 5916.230889984275, 5930.169776231245, 5931.3772053935445, 5937.4656280933195, 5656.434356564475, 5676.51749601272, 5892.057270836645, 5892.587518873637, 5897.70511474416, 5801.158831969256, 5908.172855800278, 5915.645061296336, 5942.58401911862, 5959.76160953557, 5777.480026797609, 5786.884262314646, 5789.45000224978, 5797.2173756542325, 5797.739101740516], "value": 5494.579726149341, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.8298193523879, 613.7268915332676, 614.40402809275, 642.9839254018652, 651.5406638319985, 35133.648663513355, 47404.52595474044, 48133.0, 48273.27590086143, 48315.75842120789, 48815.51184488154, 49277.26084108741, 49367.753161234235, 49379.0, 49416.0, 8376.288015518689, 9221.031791661862, 9373.765655858624, 9375.85936210954, 9384.155426011657, 8171.877421838677, 8441.02928163263, 8444.408891377607, 10707.892921070768, 10970.177236707239, 11205.327680339165, 11237.325760454358, 11248.55005799771, 11264.943675281613, 11269.830952535718, 11007.119430445546, 11085.0, 11102.833457498184, 11109.222354435193, 11109.333439993627, 10627.628033018807, 10677.039066483976, 10683.519241634145, 10685.41230232337, 10693.19801014927, 44719.77640111811, 44923.77538112321, 44928.3260751089, 44961.55038449606, 45041.32438013433, 3788.052986753312, 3840.481534992768, 3861.9494929884527, 3866.9613303867163, 4024.4969378827645, 34609.653903460894, 34809.825950870334, 34894.6510534894, 34979.0, 35355.469667955, 4509.436320459942, 4536.478304994935, 4603.263477843562, 4633.814647414099, 4678.438587369504, 354767.0, 355479.0, 356689.0, 356968.0, 357204.0, 55555.166672499945, 55713.0, 55734.44265557292, 55759.44240557543, 55952.720236398556, 38466.615333846305, 40750.79624601858, 40857.59142408597, 43055.7847210762, 64949.67525162344, 19414.22343106288, 19574.8042519573, 19583.902080489508, 19595.804041959684, 19598.8040119597, 13428.664283392798, 14839.40642374293, 14891.925540372229, 14928.10431374121, 14936.178510182044, 17978.2808687651, 19646.0, 20342.593148136955, 20487.590248194952, 21167.576648467253, 7347.70609175627, 7553.395728341719, 7592.582407967504, 7665.655045522964, 7732.0, 972.1250874213265, 1030.299396410448, 1109.93446291561, 1116.1294190531398, 1181.8286348479423, 34248.82875585606, 34681.132971675404, 34770.652293477244, 34773.652263477044, 34969.30061398758, 1092.7814437112609, 1150.3155622404656, 1182.3674334231146, 1207.849018872641, 1243.3720970909633, 16825.495235142964, 16962.321507139575, 17208.65582688339, 17292.567685807957, 17426.56433589145, 607591.0, 609691.0, 610756.0, 613492.0, 617696.0, 60796.696016519636, 61260.38739612636, 61443.07835382473, 61447.6927615368, 61530.69234653798, 1293.0948336164722, 1363.7545241856362, 1364.3246592936362, 1424.181095869879, 1465.5090544667441, 30647.54028689616, 34429.65570344365, 34613.82693086519, 34702.30595388129, 34784.82607586946, 53188.0, 53805.0, 53857.0, 53862.46137538574, 53939.0, 446632.0, 449695.0, 449860.0, 450165.0, 450467.0, 30553.388932221675, 30585.388292234475, 31952.840235799602, 32158.51762223615, 33334.499982499794, 51271.74364128155, 51611.741941290056, 51754.74122629513, 51791.741041294554, 51801.74099129631, 42905.57094429016, 43486.56513434825, 43631.12737745291, 44336.11327773362, 44980.0, 43663.781681091394, 44504.33243501285, 44895.77552112219, 45589.77205113953, 45849.0, 42708.78645606877, 43344.0, 44129.33805992849, 44457.555424445345, 44483.33275000813, 35624.28751424905, 36461.63538364582, 38554.80722596369, 39559.40660890147, 39819.80090099531, 29929.850350748107, 30027.549586756653, 30149.849250753607, 30406.087817365515, 30529.0], "value": 21482.424832004213, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.498329696449, 406.31535862072394, 418.83037369865207, 424.885280974137, 435.71678409034126, 14463.927680361596, 16327.591810204736, 19813.801861981377, 20824.687629685566, 21158.471038224052, 20030.89984550079, 20167.28578070866, 20592.485187870312, 20824.85463299518, 20991.0, 5913.2608423947, 5928.3775203603645, 6052.727627256784, 6060.727267272961, 6098.878022439549, 4438.711483753562, 4655.627549796025, 6037.547183961198, 6049.576529642927, 6093.969530152343, 6340.080688300193, 6591.307912669173, 6627.701753421107, 6749.662516874161, 6766.898496522556, 6549.148610680605, 6709.563878347916, 6745.932540674579, 6751.459883209332, 6761.966190169018, 5817.854553636193, 5968.134620480006, 5976.4919981801595, 5980.1328807323125, 5995.400459954013, 24069.39826504334, 24415.145469908646, 24427.389315267083, 24866.627000595006, 24911.377215569573, 2513.195777351248, 2588.9482210355877, 2590.8056895732893, 2749.848758318292, 2978.300099476621, 14730.631734206623, 14865.851341486556, 15382.0, 15405.0, 15623.218839058001, 2079.6360636888485, 2117.68234764785, 2281.281396360158, 2307.723073231223, 2371.30046636241, 77093.0, 81557.18442815555, 85090.0, 99580.50209748978, 103249.48375258151, 17944.282228710967, 18092.728609070873, 18186.45440636783, 18867.245310187714, 18869.905650471934, 34986.475202871974, 35050.64949350525, 35061.47407788885, 35134.82432587821, 35209.82395088059, 10763.623273185402, 11087.113030957504, 11125.388103654377, 11152.832707509393, 11509.194356395139, 9292.16370526656, 9341.953290233505, 9482.573284202228, 9647.228221742243, 9696.321257511907, 9246.537673116318, 9429.15137637616, 9439.575219115157, 9482.715518534455, 9575.138237558653, 3284.3595498877967, 3638.599754027055, 3659.835307411173, 3697.8890633280507, 3754.5494540655295, 932.3100905330007, 980.1962390839443, 1031.5873650539845, 1038.7299302181486, 1066.2855886556026, 19902.90048549777, 20147.19411223564, 20267.290644827655, 20505.79494205039, 20853.89573052125, 971.3443425687633, 1009.3439264478001, 1024.9436281004616, 1064.9307794993301, 1078.385320367383, 10391.740206494747, 11204.719882003015, 11319.490622921989, 11395.715107122223, 11454.370009649549, 481863.0, 486107.0, 507524.0, 507699.0, 508141.0, 75008.62495687595, 82124.58937705273, 96486.51756741172, 98676.50661746645, 100749.0, 988.3526290279879, 998.1633793819407, 1036.6889933020063, 1038.870141232346, 1040.458961340099, 17738.822611773718, 18045.548861278046, 18266.178021989308, 18398.54003649866, 18641.161147748113, 33175.33649327048, 44027.33958990554, 45091.54908451005, 45824.312635311166, 46123.53876461193, 60640.0, 61323.38676613177, 62681.68659156675, 63350.36649633629, 64018.0, 5877.059670452707, 7626.58053807046, 8789.296856251356, 9066.456012639263, 9477.478738669439, 13352.866471335163, 13435.12671676377, 13467.12463689857, 14105.294735263402, 14126.43494260218, 11747.471363788822, 11818.172727909308, 11935.045196384443, 12212.69468263265, 12311.0, 11718.765624687288, 11771.23486973344, 11775.175737698279, 11786.941065294619, 12260.26438413698, 11342.638883334053, 11692.824607631062, 11733.178677492835, 11877.524899003944, 11893.989010934167, 9000.819983600422, 9181.954090229507, 9357.625694972125, 9447.433154010781, 9488.573014214238, 9313.068693130634, 11754.05967522579, 11796.0, 11876.406179690808, 12316.815247771112], "value": 10539.167298057997, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1479.8446163152869, 1485.0273071138404, 1642.70431322362, 1749.1166960684852, 1753.4827225968338, 7609.54342739436, 7889.250521200491, 8179.141190175035, 8812.999999999993, 8813.206811386983, 8244.752657420288, 8546.572671366423, 8559.015713192985, 8724.302055835533, 8728.694495692653, 2416.287195277393, 2441.6581678565017, 2443.6823212982376, 2456.299954512964, 2457.0785955266774, 2096.3710886733993, 2154.7737487563736, 2346.835721499496, 2451.681281433411, 2454.214651311581, 2446.412860913377, 2484.8012159027326, 2489.4523204894895, 2489.825712200138, 2497.9875100624563, 2381.452265978817, 2409.710834699847, 2431.465077682907, 2432.914847980312, 2439.7438268981678, 2335.976640233593, 2342.7305859825997, 2352.3060697094397, 2375.406148462884, 2378.9881050594636, 8823.514706691129, 8873.112688731075, 8877.911220887772, 8883.067277935854, 8888.066752990908, 5320.441353657849, 5455.645383050129, 5470.56235501159, 5485.341758988907, 5493.807716729935, 3241.8259882159605, 3324.1024923270584, 3396.1981365373417, 3397.541331920184, 3398.7960722356493, 1164.9068074554016, 1191.839101721274, 1221.7373264748032, 1223.5472875036205, 1231.8521777386682, 11642.301461912313, 11652.592159274385, 11655.883441165564, 11663.0, 11762.470688819023, 4572.542745725401, 4928.162212423825, 4938.802447902115, 4945.332380128709, 4959.305697202394, 10345.37927724339, 10360.171186305077, 10362.585496580052, 10365.06714395708, 10366.274360794672, 5536.474034966672, 5594.636348637326, 5600.383957764642, 5605.66366018032, 5606.6636001840025, 4983.9252411213865, 5038.949610503848, 5102.311187989574, 5113.258577506241, 5172.0, 4097.705285040387, 4157.896052598649, 4200.558941311118, 4256.489221293465, 4284.871453856389, 1156.9537218511166, 1185.466540056978, 1289.6324547503916, 1318.854925958144, 1329.6409969308233, 1007.9748006299754, 1012.427978192319, 1024.7540590258284, 1024.8513965474965, 1025.1696126137851, 6025.0962355646125, 6061.575689701678, 6085.665288409092, 6185.134081228632, 6359.3640635936035, 957.4733896356976, 977.8239916814899, 1016.8678071850612, 1022.8977102289712, 1054.7995880782628, 4070.5725898780793, 4137.358709399988, 4143.81352839123, 4158.688098392633, 4300.440942677432, 10427.895721042843, 10621.787564248672, 10720.678379648623, 10731.302465339728, 10766.892331076746, 10479.10927571165, 10560.366378017343, 10597.62908298206, 10609.628662996758, 10625.25623206368, 7605.733799316998, 7918.049834019839, 8281.21328473794, 8415.074341822394, 8416.705415310435, 5952.821415357546, 5957.851053723692, 5997.519710333993, 6073.453389194994, 6085.756569737161, 9080.228180604723, 9409.29430292717, 9440.575174117044, 9480.146786789222, 9596.23230141601, 9389.436633801994, 9411.623535058521, 9510.286728495252, 9644.807103858024, 9729.0, 7568.508046977149, 7742.187070357531, 7777.533347999138, 7846.33306168982, 7869.724559640385, 8309.95845020771, 8361.498310101413, 8362.871012413247, 8363.414560980673, 8401.0, 7462.962685186756, 7499.287567681062, 7635.198304177981, 7682.347000505019, 7776.611169441619, 7393.667284972082, 7601.0878694557005, 7663.808404789702, 7747.612619369122, 7961.203879611992, 6337.429631333202, 6449.0, 6451.3548645135115, 6454.838629034311, 6474.70863811139, 4580.3266989136255, 4663.0207656391185, 4688.7655617218325, 4692.53074692528, 4697.6711630185555, 5062.569681577107, 5072.365954255718, 5150.587952963829, 5212.270282160576, 5243.632945693765], "value": 4664.531661674984, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.79479901657044, 356.16834690996524, 360.7709104718503, 364.6481145694406, 366.5033879093829, 5608.411116832735, 5617.8033768818095, 5653.604247702662, 5665.883763929837, 5670.0927851543765, 5311.2298716686055, 5330.546903513196, 5367.597430192732, 5498.697571633568, 5554.472325129106, 4337.587929146742, 4363.912721745563, 4366.816591704156, 4389.934150987738, 4394.450693663292, 4310.52584215736, 4375.518692943773, 4391.538888416718, 4426.0, 4438.8224471021285, 4348.521502689085, 4348.891277718067, 4354.47746270448, 4355.499117601469, 4395.758233297167, 4329.9567004329865, 4335.544767799383, 4350.390945267665, 4359.956400435986, 4368.628666563346, 3876.457295978565, 3880.9223815523815, 3885.747426417274, 3894.948363941748, 3906.648401643866, 5236.162214045772, 5376.758045887906, 5380.892382152335, 5381.3675768855865, 5460.180972854064, 4973.651844370896, 5003.17447621142, 5007.223880298559, 5010.5240002199735, 5011.949880501221, 5756.971215143897, 5764.8847023059725, 5766.7116644168045, 5771.489402110095, 5779.566532510079, 3939.527256729182, 4131.706902669018, 4136.937945930814, 4143.7720925349095, 4156.231097247019, 5929.288485381739, 5980.940190598125, 5997.820065398045, 5999.460048595603, 6016.939830601682, 4321.6974811762875, 4330.17726631939, 4330.718503297276, 4336.783160841946, 4368.344748287782, 4225.429567008476, 4360.324149756792, 4363.19280933022, 4364.650827933758, 4373.081652852937, 4904.926426103611, 4936.6297527685565, 4946.455889852114, 4949.554540091409, 4958.578520825698, 5782.53048171094, 5784.0, 5788.5369170466265, 5800.883982320414, 5850.138908320573, 1421.9360128794021, 1423.8647328503775, 1465.5823090419392, 1477.6453651123868, 1490.7167638148717, 1031.7884833609091, 1036.6060896859296, 1038.2939601071344, 1046.6493724602192, 1049.354646892171, 4880.365552478226, 4905.3623029006, 4939.221880123153, 4952.706450210641, 4990.8752281193265, 5160.580840268881, 5193.324867767242, 5195.233620569024, 5208.8437346879655, 5252.29094072303, 4803.303199231663, 4804.495527969582, 4943.047496238965, 4953.507777017194, 4982.651214415028, 2792.357757715696, 2792.664880214347, 2795.664520257582, 2805.8316501009595, 2809.0, 5339.786408543692, 5340.679559226459, 5383.542398896059, 5403.432639572867, 5412.56699464042, 4955.033768415196, 5300.913312770873, 5305.540976231568, 5310.468953104736, 5324.387695415076, 5243.449437809128, 5243.895122097613, 5257.553107985787, 5285.841424757186, 5314.166612518675, 5132.717700526507, 5196.896062078813, 5204.34945631796, 5283.920741188962, 5302.806868454722, 4786.904261914672, 5110.923336149886, 5143.665661731976, 5190.351206099238, 5190.455002224712, 5300.734963251745, 5354.54486368663, 5391.649542779707, 5411.538884501241, 5422.267993820784, 3942.15243722601, 3960.5247370315747, 3963.187546552892, 3984.163325701635, 3990.30169720292, 4866.78099485531, 5087.720175390389, 5111.3355263815465, 5292.947070529245, 5679.630823996427, 5698.31620205578, 5719.684472571249, 5808.506276966336, 5841.883162336644, 5847.298324201123, 5823.359430462561, 5836.474717275466, 5845.707714614167, 5863.237779088691, 5871.6770577618645, 5676.574256930664, 5791.333996590446, 5792.902246023473, 5815.33123690764, 5835.4748072673665, 5829.358770535323, 5849.4791354249055, 5850.765969361348, 5853.765849365977, 5859.212940053325, 5620.950726076703, 5648.378678345297, 5714.457126572969, 5747.971260143672, 5756.798512052058], "value": 4267.745294333881, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [829.7054545636299, 832.7085520067977, 863.7106569299284, 894.7226359828458, 942.9717108486745, 16679.583010424747, 18286.268549258046, 18328.81671183288, 18331.816681833177, 18359.908200458995, 16526.917365413185, 16693.49919502414, 16697.332106715716, 16699.165041747896, 16707.91646041768, 721.9891701624467, 752.1200195770933, 851.0723311590365, 884.8893888263967, 891.4428482198626, 873.1225118755642, 875.3041332140931, 875.7022612311813, 876.7808047988003, 876.8334016536869, 926.3191554207652, 927.0497739816674, 927.4759760735196, 928.0348437624896, 928.0997432490474, 765.8889461028148, 790.8655528560158, 884.9601767920427, 911.9544022798833, 923.672096405773, 675.4123912196371, 675.5743881354764, 675.7330854312519, 676.1345477788439, 676.1683129750386, 16041.35834566616, 16050.0, 16116.435924742578, 16120.516384508484, 16144.434944776995, 15057.322420491104, 15531.611709707235, 15813.60465988348, 15965.92017039919, 16120.59698507535, 14219.786703199461, 14387.928060359736, 14459.0, 14596.27018649074, 15247.466338678203, 4622.699524530928, 4719.740414277213, 4806.038792241566, 4818.638602104857, 4829.203181475087, 89784.0, 91963.0, 93419.0, 94205.0, 95555.0, 12192.512299508098, 12233.571824986082, 12292.323922184187, 12420.875791241971, 12611.432485538173, 23672.763272367058, 23683.407914801923, 23729.28812135639, 23744.88127559351, 24155.15456958998, 9116.817663646823, 9279.396839205565, 9284.907150928539, 9335.393199442151, 9503.002184770638, 4941.357623508921, 5013.448520662724, 5039.982538643161, 5144.922826157611, 5246.950609878039, 3622.873199438007, 3738.43923411485, 3752.0, 3756.6243375662216, 3766.4350347447553, 594.1729527726393, 654.6170490263249, 660.9702563384659, 661.0612929639923, 686.0977814174345, 754.381407246064, 764.4495962906698, 779.9181085986003, 794.694042793517, 797.9162187970179, 10257.33327333721, 10949.83575246372, 11027.83458248127, 11149.721256968478, 11189.859751891852, 718.1956209045902, 774.0792884042307, 802.5144787403653, 806.3589446390134, 829.1418381974627, 4901.289313049588, 5319.0957537218765, 5353.544948679328, 5355.250264962909, 5363.19552067193, 124073.37963310126, 124811.0, 125669.0, 129679.35160324138, 132752.0, 65916.67041664761, 67215.3278467209, 67436.6628166856, 111299.0, 115286.0, 58930.0, 76893.61553192198, 78620.0, 79290.0, 87314.56342718245, 9989.150922171697, 10307.0, 10882.891171088504, 11048.392338421463, 11061.723456913642, 49082.50917490779, 49995.500044999084, 50246.99999999854, 52957.7352113237, 53971.0, 63117.34982577604, 64555.35444645681, 75475.62262188653, 77571.0, 78292.60853695695, 12962.287074210632, 13115.672108197372, 13355.131916425402, 13481.123726957716, 13606.183628982293, 16235.0, 16283.185840708155, 16486.69532999127, 16747.0, 17288.222030008932, 14750.188739619423, 14768.409263629335, 14909.552713418614, 14980.850191497946, 15674.451394201149, 13367.598972030466, 14031.368588413341, 14050.227237502035, 14375.568732938029, 14449.566513004627, 12537.561185358469, 12744.4265008073, 12942.67643308887, 13013.0, 14585.124892506483, 11719.296842189497, 13077.607671769862, 13528.59414217575, 13726.382312795748, 13910.860891390956, 12194.634160975185, 12472.314022728837, 13575.0, 13641.522546710818, 13749.862501375259], "value": 8165.699763665851, "unit": "score"}], "name": "dromaeo_css", "value": 7661.469363308851, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 111.0, 110.0, 109.0, 110.0, 111.0, 109.0, 109.0, 110.0, 110.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 117.0, 120.0, 168.0, 125.0, 118.0, 123.0, 121.0, 147.0, 117.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 160.0, 160.0, 161.0, 161.0, 161.0, 162.0, 159.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 101.0, 76.0, 75.0, 76.0, 76.0, 99.0, 75.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 117.0, 84.0, 81.0, 82.0, 117.0, 82.0, 80.0, 125.0], "value": 93.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 149.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 103.0, 95.0, 96.0, 95.0, 95.0, 94.0, 94.0, 94.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 49.0, 50.0, 49.0, 49.0, 50.0, 49.0, 50.0, 49.0, 48.0], "value": 49.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 68.0, 58.0, 57.0, 58.0, 58.0, 58.0, 58.0, 59.0, 59.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 76.0, 73.0, 76.0, 82.0, 74.0, 74.0, 73.0, 73.0, 74.0], "value": 75.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 128.0, 121.0, 128.0, 139.0, 122.0, 132.0, 119.0, 164.0, 141.0], "value": 131.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 153.0, 150.0, 148.0, 156.0, 152.0, 152.0, 151.0, 153.0, 153.0], "value": 152.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 61.0, 63.0, 62.0, 61.0, 62.0, 61.0, 59.0], "value": 61.6, "unit": "score"}], "name": "kraken", "value": 1461.4999999999998, "alertThreshold": 2.0}]} 16:52:24 INFO - Return code: 0 16:52:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:52:24 INFO - # TBPL SUCCESS # 16:52:24 INFO - Running post-action listener: _resource_record_post_action 16:52:24 INFO - [mozharness: 2016-10-05 23:52:24.881971Z] Finished run-tests step (success) 16:52:24 INFO - Running post-run listener: _resource_record_post_run 16:52:24 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 1998848; Write bytes: 362946560; Read time: 72; Write time: 242928 16:52:24 INFO - TinderboxPrint: CPU usage
13.0% 16:52:24 INFO - TinderboxPrint: I/O read bytes / time
1,998,848 / 72 16:52:24 INFO - TinderboxPrint: I/O write bytes / time
362,946,560 / 242,928 16:52:24 INFO - TinderboxPrint: CPU idle
8,253.1 (87.0%) 16:52:24 INFO - TinderboxPrint: CPU user
1,191.7 (12.6%) 16:52:24 INFO - TinderboxPrint: Swap in / out
0 / 0 16:52:24 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 124108800; Read time: 0; Write time: 77048 16:52:24 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 262144; Write bytes: 238837760; Read time: 16; Write time: 165880 16:52:25 INFO - Running post-run listener: _upload_blobber_files 16:52:25 INFO - Blob upload gear active. 16:52:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:52:25 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 16:52:25 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:52:25 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:52:25 INFO - (blobuploader) - INFO - Open directory for files ... 16:52:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:52:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:52:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:26 INFO - (blobuploader) - INFO - Done attempting. 16:52:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:52:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:52:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:27 INFO - (blobuploader) - INFO - Done attempting. 16:52:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:52:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:52:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:31 INFO - (blobuploader) - INFO - Done attempting. 16:52:31 INFO - (blobuploader) - INFO - Iteration through files over. 16:52:31 INFO - Return code: 0 16:52:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:52:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:52:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a93186e4349fd9f164b375cdbab87b57dfafcdb1fa7359320530d239dcbdcaf4aee9fee8214671a99b39b2a732d7c5943cfae18ffb09040399a5e2725b21cbc7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/61ad3a17d38c7f6243edf90ca92793dca9b116bdc309a31abf2c82b00f7c98cc7e3ef8085e96e49aa9ae2726c73d6b7a93ded17b2773b6cec7f9f1836b1f43ca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f60997f447d6e31d1b248ecb285fa1a08790271ffeb4a23884836cb8e4a6da7e35061780de6abaca19df0c929807c8bac076c43dc8866cc5ab222541339e5907"} 16:52:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:52:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:52:31 INFO - Contents: 16:52:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a93186e4349fd9f164b375cdbab87b57dfafcdb1fa7359320530d239dcbdcaf4aee9fee8214671a99b39b2a732d7c5943cfae18ffb09040399a5e2725b21cbc7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/61ad3a17d38c7f6243edf90ca92793dca9b116bdc309a31abf2c82b00f7c98cc7e3ef8085e96e49aa9ae2726c73d6b7a93ded17b2773b6cec7f9f1836b1f43ca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f60997f447d6e31d1b248ecb285fa1a08790271ffeb4a23884836cb8e4a6da7e35061780de6abaca19df0c929807c8bac076c43dc8866cc5ab222541339e5907"} 16:52:31 INFO - Running post-run listener: copy_logs_to_upload_dir 16:52:31 INFO - Copying logs to upload dir... 16:52:31 INFO - mkdir: /builds/slave/test/build/upload/logs 16:52:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.082139 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-10-05 16:52:31.849418) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 16:52:31.853226) ========= 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=62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734 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-Aurora/sha512/a93186e4349fd9f164b375cdbab87b57dfafcdb1fa7359320530d239dcbdcaf4aee9fee8214671a99b39b2a732d7c5943cfae18ffb09040399a5e2725b21cbc7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/61ad3a17d38c7f6243edf90ca92793dca9b116bdc309a31abf2c82b00f7c98cc7e3ef8085e96e49aa9ae2726c73d6b7a93ded17b2773b6cec7f9f1836b1f43ca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f60997f447d6e31d1b248ecb285fa1a08790271ffeb4a23884836cb8e4a6da7e35061780de6abaca19df0c929807c8bac076c43dc8866cc5ab222541339e5907"} build_url:https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011248 build_url: 'https://queue.taskcluster.net/v1/task/Mo5yx5McTDaplru894MqXw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a93186e4349fd9f164b375cdbab87b57dfafcdb1fa7359320530d239dcbdcaf4aee9fee8214671a99b39b2a732d7c5943cfae18ffb09040399a5e2725b21cbc7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/61ad3a17d38c7f6243edf90ca92793dca9b116bdc309a31abf2c82b00f7c98cc7e3ef8085e96e49aa9ae2726c73d6b7a93ded17b2773b6cec7f9f1836b1f43ca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f60997f447d6e31d1b248ecb285fa1a08790271ffeb4a23884836cb8e4a6da7e35061780de6abaca19df0c929807c8bac076c43dc8866cc5ab222541339e5907"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 16:52:31.881275) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 16:52:31.881736) ========= 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=62c568580acbfdaac3eec7be000001dc-1475709147.202326-1031965734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004473 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 16:52:31.968770) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 16:52:31.969507) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 16:52:31.970167) ========= ========= Total master_lag: 0.24 =========