builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0096 starttime: 1467138420.06 results: success (0) buildid: 20160628092022 builduid: 473e175aabb640198c0553202ffc4580 revision: 4aeee4260fcd87d5616203937177dbe949a46dc9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-28 11:27:00.065254) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-28 11:27:00.065837) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-28 11:27:00.066190) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-28 11:27:00.104990) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 11:27:00.105518) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VYnTHfSgt9/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-28 11:27:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.1M=0.001s 2016-06-28 11:27:00 (15.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.492130 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 11:27:00.622287) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 11:27:00.622585) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VYnTHfSgt9/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.033914 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 11:27:00.677794) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 11:27:00.678146) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 4aeee4260fcd87d5616203937177dbe949a46dc9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 4aeee4260fcd87d5616203937177dbe949a46dc9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VYnTHfSgt9/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-28 11:27:00,753 truncating revision to first 12 chars 2016-06-28 11:27:00,753 Setting DEBUG logging. 2016-06-28 11:27:00,753 attempt 1/10 2016-06-28 11:27:00,753 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/4aeee4260fcd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-28 11:27:01,095 unpacking tar archive at: fx-team-4aeee4260fcd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.613378 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 11:27:01.315152) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-28 11:27:01.315500) ========= script_repo_revision: 4aeee4260fcd87d5616203937177dbe949a46dc9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-28 11:27:01.315930) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-28 11:27:01.316212) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-28 11:27:01.334377) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 53 secs) (at 2016-06-28 11:27:01.334848) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VYnTHfSgt9/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 11:27:01 INFO - MultiFileLogger online at 20160628 11:27:01 in /builds/slave/test 11:27:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 11:27:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:27:01 INFO - {'append_to_log': False, 11:27:01 INFO - 'base_work_dir': '/builds/slave/test', 11:27:01 INFO - 'blob_upload_branch': 'Fx-Team', 11:27:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:27:01 INFO - 'branch': 'Fx-Team', 11:27:01 INFO - 'buildbot_json_path': 'buildprops.json', 11:27:01 INFO - 'config_files': ('talos/mac_config.py',), 11:27:01 INFO - 'default_actions': ('clobber', 11:27:01 INFO - 'read-buildbot-config', 11:27:01 INFO - 'download-and-extract', 11:27:01 INFO - 'populate-webroot', 11:27:01 INFO - 'create-virtualenv', 11:27:01 INFO - 'install', 11:27:01 INFO - 'run-tests'), 11:27:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:27:01 INFO - 'download_minidump_stackwalk': True, 11:27:01 INFO - 'download_symbols': 'ondemand', 11:27:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:27:01 INFO - 'tooltool.py': '/tools/tooltool.py', 11:27:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:27:01 INFO - '/tools/misc-python/virtualenv.py')}, 11:27:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:27:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:27:01 INFO - 'installer_path': 'installer.exe', 11:27:01 INFO - 'log_level': 'info', 11:27:01 INFO - 'log_name': 'talos', 11:27:01 INFO - 'log_to_console': True, 11:27:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:27:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:27:01 INFO - 'opt_config_files': (), 11:27:01 INFO - 'pip_index': False, 11:27:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:27:01 INFO - 'cmd': ('bash', 11:27:01 INFO - '-c', 11:27:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:27:01 INFO - 'enabled': True, 11:27:01 INFO - 'halt_on_failure': False, 11:27:01 INFO - 'name': 'check_screen_resolution'},), 11:27:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:27:01 INFO - 'cmd': ('bash', 11:27:01 INFO - '-c', 11:27:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:27:01 INFO - 'enabled': True, 11:27:01 INFO - 'halt_on_failure': False, 11:27:01 INFO - 'name': 'check_screen_resolution'},), 11:27:01 INFO - 'run_cmd_checks_enabled': True, 11:27:01 INFO - 'sps_profile': False, 11:27:01 INFO - 'sps_profile_interval': 0, 11:27:01 INFO - 'suite': 'dromaeojs', 11:27:01 INFO - 'system_bits': '32', 11:27:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:27:01 INFO - 'title': 't-yosemite-r7-0096', 11:27:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:27:01 INFO - 'use_talos_json': True, 11:27:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:27:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:27:01 INFO - 'work_dir': 'build'} 11:27:01 INFO - ##### 11:27:01 INFO - ##### Running clobber step. 11:27:01 INFO - ##### 11:27:01 INFO - Running pre-action listener: _resource_record_pre_action 11:27:01 INFO - Running main action method: clobber 11:27:01 INFO - rmtree: /builds/slave/test/build 11:27:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:27:03 INFO - Running post-action listener: _resource_record_post_action 11:27:03 INFO - ##### 11:27:03 INFO - ##### Finished clobber step (success) 11:27:03 INFO - ##### 11:27:03 INFO - ##### 11:27:03 INFO - ##### Running read-buildbot-config step. 11:27:03 INFO - ##### 11:27:03 INFO - Running pre-action listener: _resource_record_pre_action 11:27:03 INFO - Running main action method: read_buildbot_config 11:27:03 INFO - Using buildbot properties: 11:27:03 INFO - { 11:27:03 INFO - "project": "", 11:27:03 INFO - "product": "firefox", 11:27:03 INFO - "script_repo_revision": "production", 11:27:03 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 11:27:03 INFO - "repository": "", 11:27:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 11:27:03 INFO - "buildid": "20160628092022", 11:27:03 INFO - "pgo_build": "False", 11:27:03 INFO - "basedir": "/builds/slave/test", 11:27:03 INFO - "buildnumber": 828, 11:27:03 INFO - "slavename": "t-yosemite-r7-0096", 11:27:03 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 11:27:03 INFO - "platform": "yosemite_r7", 11:27:03 INFO - "scheduler": "tests-fx-team-macosx64-talos", 11:27:03 INFO - "branch": "fx-team", 11:27:03 INFO - "repo_path": "integration/fx-team", 11:27:03 INFO - "revision": "4aeee4260fcd87d5616203937177dbe949a46dc9", 11:27:03 INFO - "stage_platform": "macosx64", 11:27:03 INFO - "builduid": "473e175aabb640198c0553202ffc4580", 11:27:03 INFO - "slavebuilddir": "test" 11:27:03 INFO - } 11:27:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 11:27:03 INFO - Running post-action listener: _resource_record_post_action 11:27:03 INFO - ##### 11:27:03 INFO - ##### Finished read-buildbot-config step (success) 11:27:03 INFO - ##### 11:27:03 INFO - ##### 11:27:03 INFO - ##### Running download-and-extract step. 11:27:03 INFO - ##### 11:27:03 INFO - Running pre-action listener: _resource_record_pre_action 11:27:03 INFO - Running main action method: download_and_extract 11:27:03 INFO - mkdir: /builds/slave/test/build/tests 11:27:03 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:27:03 INFO - https://queue.taskcluster.net/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:27:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 11:27:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 11:27:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 11:27:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 11:27:04 INFO - Downloaded 1199 bytes. 11:27:04 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 11:27:04 INFO - Using the following test package requirements: 11:27:04 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 11:27:04 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:27:04 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 11:27:04 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:27:04 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 11:27:04 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:27:04 INFO - u'jsshell-mac.zip'], 11:27:04 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:27:04 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 11:27:04 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 11:27:04 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:27:04 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 11:27:04 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:27:04 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 11:27:04 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:27:04 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 11:27:04 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:27:04 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 11:27:04 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:27:04 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:27:04 INFO - https://queue.taskcluster.net/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:27:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:27:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:27:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:27:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:27:07 INFO - Downloaded 18786895 bytes. 11:27:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:27:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:27:10 INFO - Return code: 0 11:27:10 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:27: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:27:10 INFO - https://queue.taskcluster.net/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:27:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:27:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:27:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:27:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:27:53 INFO - Downloaded 18786895 bytes. 11:27:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:27:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:27:57 INFO - Return code: 0 11:27:57 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:27:57 INFO - https://queue.taskcluster.net/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:27:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 11:27:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 11:27:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 11:27:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:28:00 INFO - Downloaded 14405245 bytes. 11:28:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:28:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 11:28:00 INFO - Return code: 0 11:28:00 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:00 INFO - https://queue.taskcluster.net/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 11:28:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:28:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:28:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 11:28:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 11:28:03 INFO - Downloaded 112374178 bytes. 11:28:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:28:03 INFO - mkdir: /builds/slave/test/properties 11:28:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:28:03 INFO - Writing to file /builds/slave/test/properties/build_url 11:28:03 INFO - Contents: 11:28:03 INFO - build_url:https://queue.taskcluster.net/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:28:05 INFO - Running post-action listener: _resource_record_post_action 11:28:05 INFO - Running post-action listener: set_extra_try_arguments 11:28:05 INFO - ##### 11:28:05 INFO - ##### Finished download-and-extract step (success) 11:28:05 INFO - ##### 11:28:05 INFO - ##### 11:28:05 INFO - ##### Running populate-webroot step. 11:28:05 INFO - ##### 11:28:05 INFO - Running pre-action listener: _resource_record_pre_action 11:28:05 INFO - Running main action method: populate_webroot 11:28:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:28:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:28:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:28:05 INFO - '--tppagecycles', 11:28:05 INFO - '7'], 11:28:05 INFO - 'tests': ['tsvgm']}}, 11:28:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:28:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:28:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:28:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:28:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:28:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:28:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:05 INFO - 'talos_options': ['--disable-e10s'], 11:28:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:28:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:28:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:05 INFO - 'talos_options': ['--disable-e10s'], 11:28:05 INFO - 'tests': ['damp', 'tps']}, 11:28:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:05 INFO - 'tests': ['damp', 'tps']}, 11:28:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:28:05 INFO - 'tests': ['dromaeo_dom']}, 11:28:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:28:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:28:05 INFO - 'tests': ['basic_compositor_video']}, 11:28:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:28:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:28:05 INFO - 'tests': ['a11yr', 11:28:05 INFO - 'ts_paint', 11:28:05 INFO - 'tpaint', 11:28:05 INFO - 'sessionrestore', 11:28:05 INFO - 'sessionrestore_no_auto_restore', 11:28:05 INFO - 'tabpaint']}, 11:28:05 INFO - 'other-e10s': {'tests': ['a11yr', 11:28:05 INFO - 'ts_paint', 11:28:05 INFO - 'tpaint', 11:28:05 INFO - 'sessionrestore', 11:28:05 INFO - 'sessionrestore_no_auto_restore', 11:28:05 INFO - 'tabpaint']}, 11:28:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:28:05 INFO - 'ts_paint', 11:28:05 INFO - 'tpaint', 11:28:05 INFO - 'sessionrestore', 11:28:05 INFO - 'sessionrestore_no_auto_restore', 11:28:05 INFO - 'tabpaint']}, 11:28:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:28:05 INFO - 'ts_paint', 11:28:05 INFO - 'tpaint', 11:28:05 INFO - 'sessionrestore', 11:28:05 INFO - 'sessionrestore_no_auto_restore', 11:28:05 INFO - 'tabpaint']}, 11:28:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:28:05 INFO - 'tests': ['a11yr', 11:28:05 INFO - 'ts_paint', 11:28:05 INFO - 'tpaint', 11:28:05 INFO - 'sessionrestore', 11:28:05 INFO - 'sessionrestore_no_auto_restore', 11:28:05 INFO - 'tabpaint']}, 11:28:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:28:05 INFO - 'tests': ['a11yr', 11:28:05 INFO - 'ts_paint', 11:28:05 INFO - 'tpaint', 11:28:05 INFO - 'sessionrestore', 11:28:05 INFO - 'sessionrestore_no_auto_restore', 11:28:05 INFO - 'tabpaint']}, 11:28:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:28:05 INFO - 'tests': ['tsvgx', 11:28:05 INFO - 'tsvgr_opacity', 11:28:05 INFO - 'tart', 11:28:05 INFO - 'tscrollx', 11:28:05 INFO - 'cart']}, 11:28:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:28:05 INFO - 'tsvgr_opacity', 11:28:05 INFO - 'tart', 11:28:05 INFO - 'tscrollx', 11:28:05 INFO - 'cart']}, 11:28:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:05 INFO - 'talos_options': ['--disable-e10s'], 11:28:05 INFO - 'tests': ['tp5o']}, 11:28:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:05 INFO - 'tests': ['tp5o']}, 11:28:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:05 INFO - 'talos_options': ['--disable-e10s', 11:28:05 INFO - '--xperf_path', 11:28:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:28:05 INFO - 'tests': ['tp5n']}, 11:28:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:05 INFO - 'talos_options': ['--xperf_path', 11:28:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:28:05 INFO - 'tests': ['tp5n']}}, 11:28:05 INFO - 'talos.zip': {'path': '', 11:28:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:28:05 INFO - Running post-action listener: _resource_record_post_action 11:28:05 INFO - ##### 11:28:05 INFO - ##### Finished populate-webroot step (success) 11:28:05 INFO - ##### 11:28:05 INFO - ##### 11:28:05 INFO - ##### Running create-virtualenv step. 11:28:05 INFO - ##### 11:28:05 INFO - Running pre-action listener: _resource_record_pre_action 11:28:05 INFO - Running main action method: create_virtualenv 11:28:05 INFO - Creating virtualenv /builds/slave/test/build/venv 11:28:05 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 11:28:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:28:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:28:05 INFO - Using real prefix '/tools/python27' 11:28:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:28:06 INFO - Installing distribute.............................................................................................................................................................................................done. 11:28:09 INFO - Installing pip.................done. 11:28:09 INFO - Return code: 0 11:28:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:28:09 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:09 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:09 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 0x1011cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101261618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff28bc8c4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101130d30>, '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 0x100e95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101128b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VYnTHfSgt9/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:28:09 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 11:28:09 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 11:28:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VYnTHfSgt9/Render', 11:28:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:09 INFO - 'HOME': '/Users/cltbld', 11:28:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:09 INFO - 'LOGNAME': 'cltbld', 11:28:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:09 INFO - 'MOZ_NO_REMOTE': '1', 11:28:09 INFO - 'NO_EM_RESTART': '1', 11:28:09 INFO - 'PAGER': '/bin/cat', 11:28:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:09 INFO - 'PWD': '/builds/slave/test', 11:28:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:09 INFO - 'SHELL': '/bin/bash', 11:28:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners', 11:28:09 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 11:28:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:09 INFO - 'USER': 'cltbld', 11:28:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:09 INFO - 'XPC_FLAGS': '0x0', 11:28:09 INFO - 'XPC_SERVICE_NAME': '0', 11:28:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:09 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 11:28:09 INFO - Cleaning up... 11:28:09 INFO - Return code: 0 11:28:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:28:09 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:09 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:09 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 0x1011cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101261618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff28bc8c4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101130d30>, '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 0x100e95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101128b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VYnTHfSgt9/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:28:09 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 11:28:09 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 11:28:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VYnTHfSgt9/Render', 11:28:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:09 INFO - 'HOME': '/Users/cltbld', 11:28:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:09 INFO - 'LOGNAME': 'cltbld', 11:28:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:09 INFO - 'MOZ_NO_REMOTE': '1', 11:28:09 INFO - 'NO_EM_RESTART': '1', 11:28:09 INFO - 'PAGER': '/bin/cat', 11:28:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:09 INFO - 'PWD': '/builds/slave/test', 11:28:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:09 INFO - 'SHELL': '/bin/bash', 11:28:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners', 11:28:09 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 11:28:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:09 INFO - 'USER': 'cltbld', 11:28:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:09 INFO - 'XPC_FLAGS': '0x0', 11:28:09 INFO - 'XPC_SERVICE_NAME': '0', 11:28:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:10 INFO - Downloading/unpacking psutil>=3.1.1 11:28:10 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 11:28:10 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 11:28:10 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 11:28:10 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 11:28:10 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 11:28:10 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 11:28:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:28:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:28:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:28:13 INFO - Installing collected packages: psutil 11:28:13 INFO - Running setup.py install for psutil 11:28:13 INFO - building 'psutil._psutil_osx' extension 11:28:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 11:28:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:28:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:28:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 11:28:14 INFO - building 'psutil._psutil_posix' extension 11:28:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 11:28:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:28:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:28:14 INFO - ^ 11:28:14 INFO - 1 warning generated. 11:28:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 11:28:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:28:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:28:14 INFO - Successfully installed psutil 11:28:14 INFO - Cleaning up... 11:28:14 INFO - Return code: 0 11:28:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:28:14 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:14 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:14 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 0x1011cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101261618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff28bc8c4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101130d30>, '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 0x100e95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101128b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VYnTHfSgt9/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:28:14 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 11:28:14 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 11:28:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VYnTHfSgt9/Render', 11:28:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:14 INFO - 'HOME': '/Users/cltbld', 11:28:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:14 INFO - 'LOGNAME': 'cltbld', 11:28:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:14 INFO - 'MOZ_NO_REMOTE': '1', 11:28:14 INFO - 'NO_EM_RESTART': '1', 11:28:14 INFO - 'PAGER': '/bin/cat', 11:28:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:14 INFO - 'PWD': '/builds/slave/test', 11:28:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:14 INFO - 'SHELL': '/bin/bash', 11:28:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners', 11:28:14 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 11:28:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:14 INFO - 'USER': 'cltbld', 11:28:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:14 INFO - 'XPC_FLAGS': '0x0', 11:28:14 INFO - 'XPC_SERVICE_NAME': '0', 11:28:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:28: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 11:28: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 11:28: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 11:28: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 11:28: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 11:28: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 11:28:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:28:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:28:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:28:17 INFO - Installing collected packages: mozsystemmonitor 11:28:17 INFO - Running setup.py install for mozsystemmonitor 11:28:17 INFO - Successfully installed mozsystemmonitor 11:28:17 INFO - Cleaning up... 11:28:17 INFO - Return code: 0 11:28:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:28:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1011cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101261618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff28bc8c4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101130d30>, '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 0x100e95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101128b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VYnTHfSgt9/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:28:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:28:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:28:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VYnTHfSgt9/Render', 11:28:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:17 INFO - 'HOME': '/Users/cltbld', 11:28:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:17 INFO - 'LOGNAME': 'cltbld', 11:28:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:17 INFO - 'MOZ_NO_REMOTE': '1', 11:28:17 INFO - 'NO_EM_RESTART': '1', 11:28:17 INFO - 'PAGER': '/bin/cat', 11:28:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:17 INFO - 'PWD': '/builds/slave/test', 11:28:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:17 INFO - 'SHELL': '/bin/bash', 11:28:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners', 11:28:17 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 11:28:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:17 INFO - 'USER': 'cltbld', 11:28:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:17 INFO - 'XPC_FLAGS': '0x0', 11:28:17 INFO - 'XPC_SERVICE_NAME': '0', 11:28:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:18 INFO - Downloading/unpacking jsonschema==2.5.1 11:28: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 11:28: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 11:28: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 11:28: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 11:28: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 11:28: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 11:28:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:28:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:28: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 11:28: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 11:28: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 11:28: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 11:28: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 11:28: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 11:28:20 INFO - Downloading functools32-3.2.3-2.tar.gz 11:28:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:28:20 INFO - warning: no files found matching '*.txt' 11:28:20 INFO - no previously-included directories found matching 'build' 11:28:20 INFO - no previously-included directories found matching 'dist' 11:28:20 INFO - no previously-included directories found matching '.git*' 11:28:20 INFO - Installing collected packages: jsonschema, functools32 11:28:20 INFO - Running setup.py install for functools32 11:28:21 INFO - warning: no files found matching '*.txt' 11:28:21 INFO - no previously-included directories found matching 'build' 11:28:21 INFO - no previously-included directories found matching 'dist' 11:28:21 INFO - no previously-included directories found matching '.git*' 11:28:21 INFO - Successfully installed jsonschema functools32 11:28:21 INFO - Cleaning up... 11:28:21 INFO - Return code: 0 11:28:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:28:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1011cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101261618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff28bc8c4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101130d30>, '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 0x100e95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101128b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VYnTHfSgt9/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:28:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:28:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:28:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VYnTHfSgt9/Render', 11:28:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:21 INFO - 'HOME': '/Users/cltbld', 11:28:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:21 INFO - 'LOGNAME': 'cltbld', 11:28:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:21 INFO - 'MOZ_NO_REMOTE': '1', 11:28:21 INFO - 'NO_EM_RESTART': '1', 11:28:21 INFO - 'PAGER': '/bin/cat', 11:28:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:21 INFO - 'PWD': '/builds/slave/test', 11:28:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:21 INFO - 'SHELL': '/bin/bash', 11:28:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners', 11:28:21 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 11:28:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:21 INFO - 'USER': 'cltbld', 11:28:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:21 INFO - 'XPC_FLAGS': '0x0', 11:28:21 INFO - 'XPC_SERVICE_NAME': '0', 11:28:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:21 INFO - Downloading/unpacking blobuploader==1.2.4 11:28:21 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 11:28:21 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 11:28:21 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 11:28:21 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 11:28:21 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 11:28:21 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 11:28:23 INFO - Downloading blobuploader-1.2.4.tar.gz 11:28:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:28:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:28:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:28:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:28:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:25 INFO - Downloading docopt-0.6.1.tar.gz 11:28:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:28:25 INFO - Installing collected packages: blobuploader, requests, docopt 11:28:25 INFO - Running setup.py install for blobuploader 11:28:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:28:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:28:25 INFO - Running setup.py install for requests 11:28:25 INFO - Running setup.py install for docopt 11:28:26 INFO - Successfully installed blobuploader requests docopt 11:28:26 INFO - Cleaning up... 11:28:26 INFO - Return code: 0 11:28:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:28:26 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:26 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:26 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 0x1011cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101261618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff28bc8c4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101130d30>, '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 0x100e95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101128b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VYnTHfSgt9/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:28:26 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 11:28:26 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 11:28:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VYnTHfSgt9/Render', 11:28:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:26 INFO - 'HOME': '/Users/cltbld', 11:28:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:26 INFO - 'LOGNAME': 'cltbld', 11:28:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:26 INFO - 'MOZ_NO_REMOTE': '1', 11:28:26 INFO - 'NO_EM_RESTART': '1', 11:28:26 INFO - 'PAGER': '/bin/cat', 11:28:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:26 INFO - 'PWD': '/builds/slave/test', 11:28:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:26 INFO - 'SHELL': '/bin/bash', 11:28:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners', 11:28:26 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 11:28:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:26 INFO - 'USER': 'cltbld', 11:28:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:26 INFO - 'XPC_FLAGS': '0x0', 11:28:26 INFO - 'XPC_SERVICE_NAME': '0', 11:28:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:28:26 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-EtBsEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:28:26 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-vyrW1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:28:26 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-gAOAl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:28:26 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-ykHcNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:28:26 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-gG3Szt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:28:26 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-9xLY6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:28:27 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-RKmU8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:28:27 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-NXysGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:28:27 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-RzK3Ml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:28:27 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-ej7olO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:28:27 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-52K5kE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:28:27 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-H7Sox4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:28:27 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-q08QA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:28:27 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-5KQL83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:28:27 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-XQlaAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:28:27 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-4rf73I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:28:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:28:28 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-4b2O57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:28:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:28:28 INFO - Running setup.py install for manifestparser 11:28:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:28:28 INFO - Running setup.py install for mozcrash 11:28:28 INFO - Running setup.py install for mozdebug 11:28:28 INFO - Running setup.py install for mozdevice 11:28:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:28:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:28:28 INFO - Running setup.py install for mozfile 11:28:28 INFO - Running setup.py install for mozhttpd 11:28:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:28:29 INFO - Running setup.py install for mozinfo 11:28:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:28:29 INFO - Running setup.py install for mozInstall 11:28:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:28:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:28:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:28:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:28:29 INFO - Running setup.py install for mozleak 11:28:29 INFO - Running setup.py install for mozlog 11:28:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:28:29 INFO - Running setup.py install for moznetwork 11:28:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:28:29 INFO - Running setup.py install for mozprocess 11:28:29 INFO - Running setup.py install for mozprofile 11:28:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:28:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:28:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:28:30 INFO - Running setup.py install for mozrunner 11:28:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:28:30 INFO - Running setup.py install for mozscreenshot 11:28:30 INFO - Running setup.py install for moztest 11:28:30 INFO - Running setup.py install for mozversion 11:28:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:28:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:28:30 INFO - Cleaning up... 11:28:30 INFO - Return code: 0 11:28:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:28: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28: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 0x1011cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101261618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff28bc8c4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101130d30>, '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 0x100e95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101128b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VYnTHfSgt9/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:28: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 11:28: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 11:28:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VYnTHfSgt9/Render', 11:28:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:30 INFO - 'HOME': '/Users/cltbld', 11:28:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:30 INFO - 'LOGNAME': 'cltbld', 11:28:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:30 INFO - 'MOZ_NO_REMOTE': '1', 11:28:30 INFO - 'NO_EM_RESTART': '1', 11:28:30 INFO - 'PAGER': '/bin/cat', 11:28:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:30 INFO - 'PWD': '/builds/slave/test', 11:28:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:30 INFO - 'SHELL': '/bin/bash', 11:28:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners', 11:28:30 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 11:28:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:30 INFO - 'USER': 'cltbld', 11:28:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:30 INFO - 'XPC_FLAGS': '0x0', 11:28:30 INFO - 'XPC_SERVICE_NAME': '0', 11:28:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:28:31 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-7f0N3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:28:31 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)) 11:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:28:31 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-wP6RIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:28:31 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)) 11:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:28:31 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-mFAk75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:28:31 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)) 11:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:28:31 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-9svq85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:28: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)) 11:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:28:31 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-L_1q58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:28: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)) 11:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:28:31 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-Azx3Bo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:28: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)) 11:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:28:31 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-9yJhny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:28: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)) 11:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:28:31 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-uUsx9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:28: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)) 11:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:28:31 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-NPDyzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:28: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)) 11:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:28:32 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-FGZVxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:28:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 11:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:28:32 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-RNrX0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:28:32 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)) 11:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:28:32 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-ZD4Co8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:28:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 11:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:28:32 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-LBs3CH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:28:32 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)) 11:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:28:32 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-cnTT2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:28:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 11:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:28:32 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-RMruSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:28:32 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)) 11:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:28:32 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-rsS9kH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:28:32 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)) 11:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:28:32 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-vQLPpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:28:32 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)) 11:28:32 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)) 11:28:32 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)) 11:28:32 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)) 11:28:32 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)) 11:28:32 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)) 11:28:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:35 INFO - Downloading blessings-1.6.tar.gz 11:28:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:28:35 INFO - Installing collected packages: blessings 11:28:35 INFO - Running setup.py install for blessings 11:28:35 INFO - Successfully installed blessings 11:28:35 INFO - Cleaning up... 11:28:35 INFO - Return code: 0 11:28:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:28:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:28:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:28:35 INFO - Reading from file tmpfile_stdout 11:28:35 INFO - Current package versions: 11:28:35 INFO - blessings == 1.6 11:28:35 INFO - blobuploader == 1.2.4 11:28:35 INFO - docopt == 0.6.1 11:28:35 INFO - functools32 == 3.2.3-2 11:28:35 INFO - jsonschema == 2.5.1 11:28:35 INFO - manifestparser == 1.1 11:28:35 INFO - mozInstall == 1.12 11:28:35 INFO - mozcrash == 0.17 11:28:35 INFO - mozdebug == 0.1 11:28:35 INFO - mozdevice == 0.48 11:28:35 INFO - mozfile == 1.2 11:28:35 INFO - mozhttpd == 0.7 11:28:35 INFO - mozinfo == 0.9 11:28:35 INFO - mozleak == 0.1 11:28:35 INFO - mozlog == 3.2 11:28:35 INFO - moznetwork == 0.27 11:28:35 INFO - mozprocess == 0.22 11:28:35 INFO - mozprofile == 0.28 11:28:35 INFO - mozrunner == 6.11 11:28:35 INFO - mozscreenshot == 0.1 11:28:35 INFO - mozsystemmonitor == 0.3 11:28:35 INFO - moztest == 0.7 11:28:35 INFO - mozversion == 1.4 11:28:35 INFO - psutil == 3.1.1 11:28:35 INFO - requests == 1.2.3 11:28:35 INFO - wsgiref == 0.1.2 11:28:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:28:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:35 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 0x1011cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101261618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff28bc8c4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101130d30>, '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 0x100e95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101128b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VYnTHfSgt9/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:28:35 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 11:28:35 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 11:28:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VYnTHfSgt9/Render', 11:28:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:35 INFO - 'HOME': '/Users/cltbld', 11:28:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:35 INFO - 'LOGNAME': 'cltbld', 11:28:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:35 INFO - 'MOZ_NO_REMOTE': '1', 11:28:35 INFO - 'NO_EM_RESTART': '1', 11:28:35 INFO - 'PAGER': '/bin/cat', 11:28:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:35 INFO - 'PWD': '/builds/slave/test', 11:28:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:35 INFO - 'SHELL': '/bin/bash', 11:28:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners', 11:28:35 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 11:28:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:35 INFO - 'USER': 'cltbld', 11:28:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:35 INFO - 'XPC_FLAGS': '0x0', 11:28:35 INFO - 'XPC_SERVICE_NAME': '0', 11:28:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:28:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:28:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:28:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:28:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:28:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:28:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:28:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:28:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:28:36 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:28:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:28:36 INFO - Cleaning up... 11:28:36 INFO - Return code: 0 11:28:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:28:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1011cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101261618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff28bc8c4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101130d30>, '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 0x100e95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101128b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VYnTHfSgt9/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:28:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:28:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:28:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VYnTHfSgt9/Render', 11:28:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:36 INFO - 'HOME': '/Users/cltbld', 11:28:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:36 INFO - 'LOGNAME': 'cltbld', 11:28:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:36 INFO - 'MOZ_NO_REMOTE': '1', 11:28:36 INFO - 'NO_EM_RESTART': '1', 11:28:36 INFO - 'PAGER': '/bin/cat', 11:28:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:36 INFO - 'PWD': '/builds/slave/test', 11:28:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:36 INFO - 'SHELL': '/bin/bash', 11:28:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners', 11:28:36 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 11:28:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:36 INFO - 'USER': 'cltbld', 11:28:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:36 INFO - 'XPC_FLAGS': '0x0', 11:28:36 INFO - 'XPC_SERVICE_NAME': '0', 11:28:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:28:36 INFO - Cleaning up... 11:28:36 INFO - Return code: 0 11:28:36 INFO - Running post-action listener: _resource_record_post_action 11:28:36 INFO - Running post-action listener: _start_resource_monitoring 11:28:36 INFO - Starting resource monitoring. 11:28:36 INFO - ##### 11:28:36 INFO - ##### Finished create-virtualenv step (success) 11:28:36 INFO - ##### 11:28:36 INFO - ##### 11:28:36 INFO - ##### Running install step. 11:28:36 INFO - ##### 11:28:36 INFO - Running pre-action listener: _resource_record_pre_action 11:28:36 INFO - Running main action method: install 11:28:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:28:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:28:36 INFO - Reading from file tmpfile_stdout 11:28:36 INFO - Detecting whether we're running mozinstall >=1.0... 11:28:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:28:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:28:36 INFO - Reading from file tmpfile_stdout 11:28:36 INFO - Output received: 11:28:36 INFO - Usage: mozinstall [options] installer 11:28:36 INFO - Options: 11:28:36 INFO - -h, --help show this help message and exit 11:28:36 INFO - -d DEST, --destination=DEST 11:28:36 INFO - Directory to install application into. [default: 11:28:36 INFO - "/builds/slave/test"] 11:28:36 INFO - --app=APP Application being installed. [default: firefox] 11:28:36 INFO - mkdir: /builds/slave/test/build/application 11:28:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:28:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:29:11 INFO - Reading from file tmpfile_stdout 11:29:11 INFO - Output received: 11:29:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:29:11 INFO - Running post-action listener: _resource_record_post_action 11:29:11 INFO - ##### 11:29:11 INFO - ##### Finished install step (success) 11:29:11 INFO - ##### 11:29:11 INFO - ##### 11:29:11 INFO - ##### Running run-tests step. 11:29:11 INFO - ##### 11:29:11 INFO - Running pre-action listener: _resource_record_pre_action 11:29:11 INFO - Running main action method: run_tests 11:29:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:29:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:29:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:29:11 INFO - 2016-06-28 11:29:11.107 screenresolution[1640:8005] starting screenresolution argv=screenresolution get 11:29:11 INFO - 2016-06-28 11:29:11.110 screenresolution[1640:8005] Display 0: 1600x1200x32@60 11:29:11 INFO - 2016-06-28 11:29:11.116 screenresolution[1641:8007] starting screenresolution argv=screenresolution list 11:29:11 INFO - Available Modes on Display 0 11:29:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:29:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:29:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:29:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:29:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:29:11 INFO - Intel Iris: 11:29:11 INFO - Chipset Model: Intel Iris 11:29:11 INFO - Type: GPU 11:29:11 INFO - Bus: Built-In 11:29:11 INFO - VRAM (Dynamic, Max): 1536 MB 11:29:11 INFO - Vendor: Intel (0x8086) 11:29:11 INFO - Device ID: 0x0a2e 11:29:11 INFO - Revision ID: 0x0009 11:29:11 INFO - Displays: 11:29:11 INFO - Display: 11:29:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:29:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:29:11 INFO - Display Serial Number: 1600x1200 60 11:29:11 INFO - Main Display: Yes 11:29:11 INFO - Mirror: Off 11:29:11 INFO - Online: Yes 11:29:11 INFO - Rotation: Supported 11:29:11 INFO - Return code: 0 11:29:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:29:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:29:11 INFO - Python 2.7.3 11:29:11 INFO - Return code: 0 11:29:11 INFO - grabbing minidump binary from tooltool 11:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:29:11 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/macosx64/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 0x101130d30>, '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 0x100e95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101128b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:29:11 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:29:11 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 11:29:11 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:29:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:29:11 INFO - Return code: 0 11:29:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:29:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:29:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:29:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:29:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:29:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0096', '--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 11:29:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0096 --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 11:29:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VYnTHfSgt9/Render', 11:29:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:29:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:29:11 INFO - 'HOME': '/Users/cltbld', 11:29:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:29:11 INFO - 'LOGNAME': 'cltbld', 11:29:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:29:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:11 INFO - 'MOZ_NO_REMOTE': '1', 11:29:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:29:11 INFO - 'NO_EM_RESTART': '1', 11:29:11 INFO - 'PAGER': '/bin/cat', 11:29:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:29:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:11 INFO - 'PWD': '/builds/slave/test', 11:29:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:29:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:29:11 INFO - 'SHELL': '/bin/bash', 11:29:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners', 11:29:11 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 11:29:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:29:11 INFO - 'USER': 'cltbld', 11:29:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:29:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:11 INFO - 'XPC_FLAGS': '0x0', 11:29:11 INFO - 'XPC_SERVICE_NAME': '0', 11:29:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:29:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0096', '--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 11:29:11 INFO - mozversion application_buildid: 20160628092022 11:29:11 INFO - mozversion application_changeset: 4aeee4260fcd87d5616203937177dbe949a46dc9 11:29:11 INFO - mozversion application_display_name: Nightly 11:29:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:29:11 INFO - mozversion application_name: Firefox 11:29:11 INFO - mozversion application_remotingname: firefox 11:29:11 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:29:11 INFO - mozversion application_vendor: Mozilla 11:29:11 INFO - mozversion application_version: 50.0a1 11:29:11 INFO - mozversion platform_buildid: 20160628092022 11:29:11 INFO - mozversion platform_changeset: 4aeee4260fcd87d5616203937177dbe949a46dc9 11:29:11 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:29:11 INFO - mozversion platform_version: 50.0a1 11:29:11 INFO - using testdate: 1467138551 11:29:11 INFO - actual date: 1467138551 11:29:11 INFO - starting webserver on 'localhost:49266' 11:29:11 INFO - SUITE-START | Running 2 tests 11:29:11 INFO - TEST-START | dromaeo_css 11:29:11 INFO - operating with platform_type : mac_ 11:29:11 INFO - Initialising browser for dromaeo_css test... 11:29:11 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmpKHjVrp/profile http://localhost:49266/getInfo.html) 11:29:14 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 11:29:14 INFO - PROCESS | 1651 | colorDepth:24 11:29:14 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 11:29:14 INFO - PROCESS | 1651 | __metrics 11:29:14 INFO - PROCESS | 1651 | 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" 11:29:14 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:29:15 INFO - TEST-INFO | 1651: exit 0 11:29:15 INFO - Browser initialized. 11:29:15 INFO - Running cycle 1/1 for dromaeo_css test... 11:29:15 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmpKHjVrp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:29:26 INFO - PROCESS | 1654 | RSS: Main: 243572736 11:29:26 INFO - PROCESS | 1654 | 11:30:18 INFO - PROCESS | 1654 | 1467138618639 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] 11:32:29 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 11:32:30 INFO - PROCESS | 1654 | RSS: Main: 381648896 11:32:30 INFO - PROCESS | 1654 | 11:35:34 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 11:35:34 INFO - PROCESS | 1654 | RSS: Main: 381206528 11:35:34 INFO - PROCESS | 1654 | 11:38:37 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 11:38:37 INFO - PROCESS | 1654 | RSS: Main: 371982336 11:38:37 INFO - PROCESS | 1654 | 11:41:39 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 11:41:40 INFO - PROCESS | 1654 | RSS: Main: 372547584 11:41:40 INFO - PROCESS | 1654 | 11:44:43 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 11:44:44 INFO - PROCESS | 1654 | RSS: Main: 342495232 11:44:44 INFO - PROCESS | 1654 | 11:47:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 11:47:47 INFO - PROCESS | 1654 | RSS: Main: 383397888 11:47:47 INFO - PROCESS | 1654 | 11:47:47 INFO - PROCESS | 1654 | __start_tp_report 11:47:47 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 11:47:47 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 11:47:47 INFO - PROCESS | 1654 | |i|pagename|runs| 11:47:47 INFO - PROCESS | 1654 | |0;dojo.html;3389.4407422775244;3398.779079359843;3418.555587773589;3423.4693622488517;3466.324066806972;5655.293088363955;5680.744366503507;5693.231413759141;5765.279340082485;5799.449052340026;5690.487856092951;5721.256236689224;5807.796727114552;5818.389069147732;5823.475887170154;5156.716380599066;5168.819091331804;5223.869403264911;5237.214417837316;5372.247885296062;5331.946680533203;5367.758450869701;5377.946220537803;5393.19102134679;5417.322834645669;5176.249443830642;5195.272661827347;5218.8695282617855;5236.554892834114;5273.8945221095555;5218.295530103425;5227.66020208689;5227.686338819683;5256.211568264791;5341.2255222992835;5202.895942081175;5203.9219411708855;5236.423993360727;5307.867303317448;5327.254184414185;5588.552915766729;5640.294963129609;5756.539476841843;5823.5341172706085;5835.095560188178;6199.659018753967;6220.222472190976;6257.311695713468;6279.560430769848;6282.183316168869;6277.403646653606;6285.245770507523;6296.496280297565;6305.968470157666;6307.684615769193;4909.018196360742;5725.685087320154;5789.710514474344;5821.767129314865;5881.411858814127;6243.43809057187;6261.311255761862;6270.59241149333;6275.497960163176;6282.089097080989;6174.691265436711;6177.752889884444;6205.410486003822;6207.472364849038;6214.471769899519;6125.601835880653;6126.969365153146;6165.260168779775;6256.874862502725;6303.243610766737;6182.6599537025895;6191.25704915413;6225.844353891189;6226.937730622726;6237.656928868956;6241.687915604292;6266.905996410058;6286;6297.2128483939505;6299;4843.685160464559;4866.026794641086;4921.138800709861;4958.42978057528;4989.351384320015;4479.059397526491;4506.008678090815;4510.368548403226;4510.985028368591;4514.751688657089;5698.686572238566;5707.12041204764;5713.771449141987;5719.571032172604;5730;5140.588752899759;6237.563370564017;6287.276963149205;6301.653409062454;6329.493640508748;5692.174634677948;5724.771009159587;5730.054541000688;5750.971245143747;5752.165935939349;5076.593872490192;5248.527632513092;5329.147336426231;5422.674639521641;5423.593230507728;6218.81343559694;6250.499960003189;6290.496760259169;6294.307626161119;6393.8721225576155;6260.906086408617;6277.654728989767;6279.277883043325;6317.4630156437215;6319;6179.011358182669;6347.39699728525;6352.809415717536;6362.872742545216;6368.076628888878;6103.277060575968;6157.938420615916;6196.318404975539;6256.249250090018;6259.530535209787;5256.290400795997;5485.341758988947;5946.345901950868;6261.78083767066;6291.591046581958;5960.552958528041;6045.304789949037;6136.8772624548155;6138;6149.292831324455;5306.8938621228135;5976.641401515923;6057.575969682256;6065.8180254592435;6092.9086063709965;5842.474177324061;5906.173135761082;5912.231409916855;5970.641761494324;5985.461308482258;6019.789307374221;6103.244309015946;6110.816675499742;6112.786052488142;6129.549118752428;4994.875128121826;5955.106733989763;6019.307779605227;6020.789272375621;6050.0924861269405 11:47:47 INFO - PROCESS | 1654 | |1;ext.html;492.79795283933584;494.67598722836556;495.96591107540775;512.1523877981937;528.5692160888875;24251.636225456612;30526.084217473483;41788.58211417885;43025;43032.35451468223;41402.79298603503;43917.12165756683;43942.780286098605;43974.12051758971;43997.7800110999;9581.329306948533;9712;9778;9780.55987480565;9795.853062204036;7450;9018;11820.7635847283;12040.397980101005;12045.698857528545;11787.882121178807;11862.52549898003;11879.287242765418;11906.107041971844;11906.34515101669;8804.779880502974;11787.823182652268;11910.880891191107;11987.100967427477;12012.219205751642;11583.18917675763;11783.234089784222;11787.17489775716;11796.587119450864;11801.350925699084;40806;41207.38188927169;41309.586904130876;41444;41523;3542.929141417157;3658.5426821647293;3787.772733635963;3853.556840963269;4213.157368526307;31284.06147815569;31668.89158879451;32465.675343246738;32541.698332066688;32563.837180814182;2918.3871387008753;3240.3843269778904;3407.4548072308557;3431.605365382988;3458.5849698036145;474301;503101;506454;512721.4363928194;513039;60925;61081.08378374328;61463;61466;61692.07461888075;63886;63998;64180.67909660515;64205.357946420874;64258;16476.588085297964;16723.49829505117;16743.07913064769;16750.16249187536;16796.580085497717;11170.385628790495;11727.882721172678;12103.213291136048;12865.163764355288;13007.609771706675;13576.253306068262;16145.919270403572;16258.75611865823;16303.755443668355;16327;5546.667199968015;6512.772052978123;6535.281119076898;6559.672016399161;6567.573107747981;969.583079275904;1036.2176556699644;1071.5338827609858;1098.873629532598;1181.8109102543724;25409.237722868344;29780.2554936124;29786.851065744533;30494.69505304963;30707.9252226171;1105.40308233553;1109.6615532262576;1111.8943700348455;1133.0878642692712;1149.557420393138;15254.03490616735;15841.128737919526;15921.124338161513;15983.680326393407;16325.510234692978;657554;659112;662569;663001;663585;60169.398306017254;60179.09731354033;60190.69904650449;60264.698676507214;60314.39685603176;1208.9335086570322;1264.7849865522883;1377;1401.0962928910797;1409.9224542650252;28517;31546.36907261888;32007.839960800047;32174.678253217167;32212.033638990866;50572;51177;51204;51265.23102153545;51266;532020;552198;552822;554625;554988;34822.47766283558;40990.79504602458;41280.79359603183;41819;42724.35913461363;48459;48498.75750621224;50017.74991125021;50244.246336304255;50308.24537632012;32778.508322374706;39853.80073099616;40234;41170.38244426276;41189;32419.513707294882;33172.50241246335;38531.03672408212;39453.408198877616;40324.79837600793;30360.696393035785;36955.260894781415;38318.80840595779;39031;39155.41266880942;33110.66889331076;34355.65644343525;35137;35738.821305894344;35839.08655071988;25476.74523254818;25634.743652563237;25820.483590327713;26063.73936260613;26114.86942565275 11:47:47 INFO - PROCESS | 1654 | |2;jquery.html;329.6489238960507;381.52119090541356;383.15131982658414;395.78825328449284;407.6494214975121;18576.978266195354;21366.89316553419;21370;21764;21833.126674933003;20878.89560552199;21262.893685531588;21284.361469155912;21725;21792.237271695492;5362.91955620666;5885.234919460475;6167.5065994720535;6179.19670442844;6179.412955769218;5854.73653685583;6031.215941927543;6110.755569777203;6173.259208894939;6202.6278423294525;6075.939240607604;6081.908771368433;6099.512039036888;6100.176476175726;6110.419510146529;5795.159701843251;6089.0866370044405;6092.847678808021;6109.725062372204;6111.847203819918;5274.287971123926;5300.1784723367555;5308.92036619451;5310.123829568117;5310.9734451327495;25729.614055789178;26054.609180862302;26406.339841503923;26488.73511264882;26490.47019059608;2273.7271527416815;2276.1919518571;2312.6068568343253;2426.8907899144406;2429.6112621980396;13313.535511093773;14474.348654310581;14990.47533336328;16278.860479766303;16445.753313700305;1697.889637173592;1867.794542600313;1872.7097299918535;1953.5018570264492;1978.9307374241832;80393.19606803915;99907.50046249722;102037;106298;110518.44740776325;10985.835212471819;11077.676696990871;11130.387828669502;11170.553177872956;11338.886611133783;13593.320333983262;13736.038477306693;13738.519151829638;13741.793873091912;13749.518766843312;8585.828283434297;8768.78078048054;8813.779655508664;8866.77833054166;8879.200871921428;7351.375133113638;8821.514816685143;8915.242204412569;8918.817807706697;8967.372283940193;6594.505412094112;6717.56335838169;6719.395254427125;6734.1918969722965;6742.089817874622;1425.8217722784652;1646.4566692991284;1810.1220907859677;1821.8724689271623;1853.888766673977;945.479986007693;1000.3247807729754;1077.4828082520435;1080.508368692243;1084.5282302198568;19658.41024769259;21474.658037362806;22320.21879234219;22368.664470032963;22546.661800073012;935.4378188425346;968.9273304502191;982.3516479123743;1009.4902074452355;1043.7234132954773;11369.431528423711;11473.139514536444;11647.359395233174;11723;11765.470553825098;600468.5863484581;603944;611592;620045;631373;116569.41715291538;117345.41327293309;117475.8252417482;119376.40311798385;121393;990.4601991914424;1004.8492726091145;1012.5139932832282;1016.7915577306634;1044.4360045575304;15853.207339633203;20698.689519657517;20700.378988630364;20822.27122050721;20861.37415877526;51899.84801215818;51956.48043519516;52186.73906630443;52396.47603524068;52618.42144735664;62425.063624044764;64389;67038.66480667566;68923;69412.3058769426;6643.634600096848;6760.898586521108;7990.480618759994;8119.147489513516;8466.830663386821;12681.682957926125;13469;13739.931300343434;13793.793093103412;14019.579412617637;11606.477708503306;11708.775332686762;11718.062554995751;11908.40457977115;11977.820332695192;11567.537298508301;11604.709882253012;11716.004139648225;11818.40907954616;12021.939890300842;11842.881571184178;11935.224210426295;12033.939830300793;12188.756224875275;12230.082743794073;7198.5680859148615;7557.093148822177;7579.924200758142;8362.958185209034;8545.700900468453;8473.83052338962;11370.886291136983;11744.471498782405;11841.940790295994;12023.21849079807 11:47:47 INFO - PROCESS | 1654 | |3;mootools.html;1391.8121053657756;1397.9790303145448;1547.9613009674754;1750.929962801488;1941.9223231070757;7946.761597152087;8623.439476434036;8884.289256859453;8885.222955408928;9026.413283136608;8382.329413646909;8612.22489975902;8618.396712230146;8620.612072456739;8622.525761083138;2310.7111611048617;2336.411240356557;2338.205010296499;2342.554914566227;2344.0506594829035;2014.2547257514666;2276.430892276931;2319.7332306784683;2340.789328960393;2346.9765302346927;2309.595820731374;2325.476767727265;2337.4506990857135;2341.1220792202926;2342.4261056041296;2292.954140917189;2313.3175713164574;2321.338418550705;2324.8953797079002;2335.2994101769573;2320.083566991046;2323.5004474038165;2332.661764044221;2333.94165145871;2334.5330933813307;8719;8946.821063578756;9030.187283144549;9062.682806101755;9078.773030674285;5238.345206849144;5408.729563521808;5414.729263536847;5415.539679127279;5448.536874365684;3420.435628121357;3510.087377281925;3570.250247448039;3581.212133330663;3627.582827974764;1067;1070.0797314309686;1072.983905241422;1079.3523885668535;1093.8413929980188;11732.17874748768;11803.291802491878;11822.290662560188;11829.763404731943;11839.408029598571;4375.146846364992;4404.6035856772405;4501.7524036177665;4523.344115103291;4526.366308716782;9289.256859451227;9291.58187881547;9294.674686386079;9298.442093474414;9299.95350023259;5218.373795144607;5218.425973142951;5220.504052115043;5228.05894938907;5230.320058392384;3647.4164133739005;3772.9245415091546;4576.405067341224;4757.833475828399;4885.487023862514;3740.8129593520216;3763.2285381496727;3849.634284742945;3862.420636904487;3907.2185562887535;1134.9602763903224;1137.2039572299423;1150.8734039255676;1162.0877611075266;1164.0280365894573;631.1857703562445;773.7833406646148;941.5527624378366;942.6747772018642;954.6229239450378;5894.528437724972;5989.550783691154;6156.538259630545;6170.660613666291;6205.410486003912;476.4592187866796;495.247224219192;496.2704823908848;496.4662987288679;632.6235889645653;3448.379291727463;4085.2850751118435;4099.077707515785;4146.771927543954;4162.708610397303;10400.791984160274;10799.83800242997;10955.726106847393;10955.890441095487;10979.780404391868;10872.67381978542;10900.291481053706;10904.745954215367;10922.29005114665;10935.890641093647;6618.70215840298;7062.187848397397;8342.62458189383;8355.289800367036;8388.32893368529;6135.9386406137155;6152.200213972155;6198.380161983766;6236.251649802053;6281.371862813682;10206.387616743019;10367.481625918825;10379.636712715019;10397.168226541708;10399.428031458343;9984.800303994025;10286.845697314397;10390.792184156124;10501.002404771534;10521.263511554118;7611.858221266854;7719;7775.339096176888;7778.455508114604;7779.533228006338;8303.501789892625;8409.663613455394;8472.872906906277;8495.23542881119;8503.872441913252;7706.691732330868;7727.381809455118;7748.208090186968;7792.142864285038;7866.528008319519;7582.241775822595;7582.54504729718;7649.273319034683;7653.770386888402;7663.195364486649;7150.96424517874;7184.820379490346;7244.4566657499845;7246.565206087441;7259.854802903807;4693.3854156287725;5091.949080509147;5175.482451754794;5203.947960520346;5241.056609810271;5130.58955283569;5326.147816349366;5331.893362132813;5376.354837419534;5475.808346707846 11:47:47 INFO - PROCESS | 1654 | |4;prototype.html;297.87489254513105;303.50074128059345;306.14890604120546;308.73448834002755;311.36636943819303;4473.932891006638;5190.351206099238;5295.4704529547025;5309.33633295838;5377.946220537793;4168.437260969768;4694.929576056362;5062.569681577062;5196.636235463509;5219.408080535435;3547.6518922809296;3604.8197590120453;3684.244729830392;3954.9604503955024;4082.1835632873544;3915.8643993242067;3964.5044369453817;4011.7563555297806;4026.9865067466267;4035.6973227007943;3703.7037037037107;3984.4421780950547;3991.12638899993;4003.75977441353;4009.218202450523;3460.7058400036026;3770.377887648535;3823.3118038752873;4002.4578248250364;4020.638142567183;2927.2535503446697;2941.8970336038137;2952.099609619065;3181.284211052518;3463.965360346415;4040.373742069984;4963.255511673242;5077.984403119391;5082.695038297677;5129.333186685744;3590.9640903591153;4033.878983630496;4381.364702118207;4557.293619488984;4576.3821884045565;5408.404520666413;5461.344638643388;5465.508104270595;5466.098093814516;5509.807156749534;3308.8180150091735;3804.714646401531;3835.347990841564;3839.616038396166;3840.711946603988;5532.029642650249;5767.509761670221;5791.21818554494;5796.797112101099;5802.767889284423;2996.1011696490964;3636.454531820248;3847.3840986785617;3863.5363756349416;3898.7075969302414;3953.1537293148886;3954.844609687727;3960.32674445345;3964.6431821136234;3965.5439624442984;3866.883993480144;4071.42999980003;4228.471441069866;4375.824967001348;4543.5910768031035;5226.869328266824;5408.350997880201;5480.848002877362;5498.945010549844;5581.302337207849;1184.1119160629528;1327.4424741608548;1330.7690386392742;1336.6057013181135;1353.2083731017465;764.3846703403814;816.7059858450953;829.2826704900218;841.3101256969227;888.6223355074068;4325.4593175853015;4559.726416415026;4789.784459699322;4988.351514303117;5005.774740136702;4845.63657725672;5077.923831142536;5283.573435172559;5284.9471505284455;5710.200571919934;4561.201789686792;4918.6556941014505;5141.665791723526;5145.3568303962;5166.767495462713;2538.37809736612;2663.227663977425;2734.8632568371504;2840.0059979007183;2869.188195568313;5515.283013208337;5649.661020338793;6005.3394126646035;6048.3145842907525;6057.515398768088;4833.685810422311;5059.721715305694;5678.602497825113;5846.970765146147;5899.616524925866;5644.8306550803545;5716.28546431696;5859.619124756877;5904.998844567267;6018.608790428608;4799.66402351832;5524.922640085145;5697.316322041382;5747.224124743273;5766.711664416678;5251.3960894496095;5408.296921400193;5571.629164355507;5700.629459085146;5738.942610573841;4440.800163992563;5113.8721531962;5903.439173278532;6044.576879618385;6052.576319657758;3539.71682265413;3731.216444546567;3845.596212397658;3848.191879705293;3862.729608927348;5356.5982551308025;5502.257195278587;5535.667859928417;5588.323502949181;5876.823695289148;5187.584993200459;5568.777248909998;5615;5618.915716264341;5626.803061892978;4573.2164455861575;5294.338207724035;5466.594698332721;5553.195636203692;5614.29821272341;4783.282507623745;4981.310092549982;5353.822159124987;5354.340154552101;5415.48552887475;5021.573166280907;5238.738063096754;5489.478499542537;5548.167774833646;5552.69460179694;3986.6810655148097;4795.880102997453;5203.921941170961;5217.713025783618;5467.945320546903 11:47:47 INFO - PROCESS | 1654 | |5;yui.html;643.8647883944371;810.3193317613204;843.2705709561228;852.7441767469758;856.8543347630903;15223.31495082721;15337.23313834308;15629.531114066582;15648.921755391222;16088.597785055379;14819.407223711065;14944.62638434037;14965.775513367309;14975.625609359771;14975.625609359771;718.3534818663193;740.1599184925095;901.2924853989603;907.4373888189299;921.4839689773714;873.5981448533682;908.2007833106857;909.1181553892746;909.222614664459;910.7950711090018;956.8564715292727;964.753987733127;965.0638880286111;965.3242730088931;966.917811985982;747.3274053351972;916.422653728147;949.4018768176005;960.0687333286701;960.3277705606068;701.8385771272585;702.4380495603497;703.1281211298004;703.7325816189815;703.968321425537;15747.606309842231;15749.2125393731;15752.1336326502;15759.369625214978;15774.526764196979;12450.190737602026;13243.668908277274;14104.435822567086;14533.636659083502;14625.488107916171;12617.621471355875;13123.226034948031;13129.934350328283;13382.330883455788;13636.250006249653;4485.349624304457;4519.751413672247;4567.383403240553;4655.045715628288;4790.904181916377;105963;106434.93565064405;109537.07083181276;110666;111595.44202278937;9591.280653950982;11317.28385187549;11330.60342887995;11449.255798373079;11508.849115088591;22831.429214269778;22868.771312286663;22892.969816358305;22930;23365.649515257286;8099;8106.108328083906;8201.71294004707;8272.875906861402;8317.001959764866;3813.694904407641;4436.82252709888;4583.083383323348;4694.36626055485;4732.290156476556;3563.679268865763;3680.650338217865;3684.6683798458266;3688.114852435396;3693.316736403774;536.6029138437606;638.0397501759821;652.2890049845691;661.9073329733841;668.7826456401689;516.3133033066014;536.629725489411;549.228334190463;605.140700205709;628.955973081889;9064.274858011344;9083.81832363349;9128.08719128095;9181;9266.258699304039;554.27666894702;600.1178267946112;607.6293460988793;641.8555824939351;643.4080645805776;4438.378626992224;4461.13007963444;4476.798544065459;4543.704659197141;4589.632829373642;86788.56605716932;111319.44340278409;141816;151531;154377;82193;129735;130579;133958;135661;72063;72361.91457128148;100443.49778251209;101056;101802;9805.754856128653;10032.448215348226;10043;10075.596976120873;10089.344192627455;58128.70935645295;58376;60905.39094609174;60921.344719316985;66525;77122.2287777115;93500;98293.50853245688;99819;100747.9326860586;12798.424070917017;13160.407781649657;13228.404721787736;13358.866411335763;13423.664408389868;14990.625234369229;15129.47046853399;15410.306536206122;15731.68536629238;15741.527754167393;13692.52076177329;14058.086224395382;14069.577912662637;14183.290835458392;14481.275936203358;13928.094673846168;14071.929640351733;14183.361748721127;14277.571672849832;14395.712085758018;13704.314784260947;13872.861271387157;13899.096558723651;14085.08446950945;14095.295235237993;9800.264980126376;10678.46607669629;11141.118504800004;11585.304881707125;11665.650030499099;9366.297527685314;10361.430121343097;10710.617889583284;10996.351165433798;11632.592859249886 11:47:47 INFO - PROCESS | 1654 | __end_tp_report 11:47:47 INFO - PROCESS | 1654 | __start_cc_report 11:47:47 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1377 11:47:47 INFO - PROCESS | 1654 | __end_cc_report 11:47:47 INFO - PROCESS | 1654 | __startTimestamp1467139667939__endTimestamp 11:47:47 INFO - PROCESS | 1654 | 11:47:47 INFO - PROCESS | 1654 | ------- Summary: start ------- 11:47:47 INFO - PROCESS | 1654 | Number of tests: 6 11:47:47 INFO - PROCESS | 1654 | 11:47:47 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5735.67 Median:5912.23 stddev:632.61 (10.7%) stddev-sans-first:607.19 11:47:47 INFO - PROCESS | 1654 | Values: 3389.4 3398.8 3418.6 3423.5 3466.3 5655.3 5680.7 5693.2 5765.3 5799.4 5690.5 5721.3 5807.8 5818.4 5823.5 5156.7 5168.8 5223.9 5237.2 5372.2 5331.9 5367.8 5377.9 5393.2 5417.3 5176.2 5195.3 5218.9 5236.6 5273.9 5218.3 5227.7 5227.7 5256.2 5341.2 5202.9 5203.9 5236.4 5307.9 5327.3 5588.6 5640.3 5756.5 5823.5 5835.1 6199.7 6220.2 6257.3 6279.6 6282.2 6277.4 6285.2 6296.5 6306.0 6307.7 4909.0 5725.7 5789.7 5821.8 5881.4 6243.4 6261.3 6270.6 6275.5 6282.1 6174.7 6177.8 6205.4 6207.5 6214.5 6125.6 6127.0 6165.3 6256.9 6303.2 6182.7 6191.3 6225.8 6226.9 6237.7 6241.7 6266.9 6286.0 6297.2 6299.0 4843.7 4866.0 4921.1 4958.4 4989.4 4479.1 4506.0 4510.4 4511.0 4514.8 5698.7 5707.1 5713.8 5719.6 5730.0 5140.6 6237.6 6287.3 6301.7 6329.5 5692.2 5724.8 5730.1 5751.0 5752.2 5076.6 5248.5 5329.1 5422.7 5423.6 6218.8 6250.5 6290.5 6294.3 6393.9 6260.9 6277.7 6279.3 6317.5 6319.0 6179.0 6347.4 6352.8 6362.9 6368.1 6103.3 6157.9 6196.3 6256.2 6259.5 5256.3 5485.3 5946.3 6261.8 6291.6 5960.6 6045.3 6136.9 6138.0 6149.3 5306.9 5976.6 6057.6 6065.8 6092.9 5842.5 5906.2 5912.2 5970.6 5985.5 6019.8 6103.2 6110.8 6112.8 6129.5 4994.9 5955.1 6019.3 6020.8 6050.1 11:47:47 INFO - PROCESS | 1654 | 11:47:47 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:71088.17 Median:20371.52 stddev:153451.44 (753.3%) stddev-sans-first:153790.37 11:47:47 INFO - PROCESS | 1654 | Values: 492.8 494.7 496.0 512.2 528.6 24251.6 30526.1 41788.6 43025.0 43032.4 41402.8 43917.1 43942.8 43974.1 43997.8 9581.3 9712.0 9778.0 9780.6 9795.9 7450.0 9018.0 11820.8 12040.4 12045.7 11787.9 11862.5 11879.3 11906.1 11906.3 8804.8 11787.8 11910.9 11987.1 12012.2 11583.2 11783.2 11787.2 11796.6 11801.4 40806.0 41207.4 41309.6 41444.0 41523.0 3542.9 3658.5 3787.8 3853.6 4213.2 31284.1 31668.9 32465.7 32541.7 32563.8 2918.4 3240.4 3407.5 3431.6 3458.6 474301.0 503101.0 506454.0 512721.4 513039.0 60925.0 61081.1 61463.0 61466.0 61692.1 63886.0 63998.0 64180.7 64205.4 64258.0 16476.6 16723.5 16743.1 16750.2 16796.6 11170.4 11727.9 12103.2 12865.2 13007.6 13576.3 16145.9 16258.8 16303.8 16327.0 5546.7 6512.8 6535.3 6559.7 6567.6 969.6 1036.2 1071.5 1098.9 1181.8 25409.2 29780.3 29786.9 30494.7 30707.9 1105.4 1109.7 1111.9 1133.1 1149.6 15254.0 15841.1 15921.1 15983.7 16325.5 657554.0 659112.0 662569.0 663001.0 663585.0 60169.4 60179.1 60190.7 60264.7 60314.4 1208.9 1264.8 1377.0 1401.1 1409.9 28517.0 31546.4 32007.8 32174.7 32212.0 50572.0 51177.0 51204.0 51265.2 51266.0 532020.0 552198.0 552822.0 554625.0 554988.0 34822.5 40990.8 41280.8 41819.0 42724.4 48459.0 48498.8 50017.7 50244.2 50308.2 32778.5 39853.8 40234.0 41170.4 41189.0 32419.5 33172.5 38531.0 39453.4 40324.8 30360.7 36955.3 38318.8 39031.0 39155.4 33110.7 34355.7 35137.0 35738.8 35839.1 25476.7 25634.7 25820.5 26063.7 26114.9 11:47:47 INFO - PROCESS | 1654 | 11:47:47 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:34737.64 Median:22457.66 stddev:101478.43 (451.9%) stddev-sans-first:101730.22 11:47:47 INFO - PROCESS | 1654 | Values: 329.6 381.5 383.2 395.8 407.6 18577.0 21366.9 21370.0 21764.0 21833.1 20878.9 21262.9 21284.4 21725.0 21792.2 5362.9 5885.2 6167.5 6179.2 6179.4 5854.7 6031.2 6110.8 6173.3 6202.6 6075.9 6081.9 6099.5 6100.2 6110.4 5795.2 6089.1 6092.8 6109.7 6111.8 5274.3 5300.2 5308.9 5310.1 5311.0 25729.6 26054.6 26406.3 26488.7 26490.5 2273.7 2276.2 2312.6 2426.9 2429.6 13313.5 14474.3 14990.5 16278.9 16445.8 1697.9 1867.8 1872.7 1953.5 1978.9 80393.2 99907.5 102037.0 106298.0 110518.4 10985.8 11077.7 11130.4 11170.6 11338.9 13593.3 13736.0 13738.5 13741.8 13749.5 8585.8 8768.8 8813.8 8866.8 8879.2 7351.4 8821.5 8915.2 8918.8 8967.4 6594.5 6717.6 6719.4 6734.2 6742.1 1425.8 1646.5 1810.1 1821.9 1853.9 945.5 1000.3 1077.5 1080.5 1084.5 19658.4 21474.7 22320.2 22368.7 22546.7 935.4 968.9 982.4 1009.5 1043.7 11369.4 11473.1 11647.4 11723.0 11765.5 600468.6 603944.0 611592.0 620045.0 631373.0 116569.4 117345.4 117475.8 119376.4 121393.0 990.5 1004.8 1012.5 1016.8 1044.4 15853.2 20698.7 20700.4 20822.3 20861.4 51899.8 51956.5 52186.7 52396.5 52618.4 62425.1 64389.0 67038.7 68923.0 69412.3 6643.6 6760.9 7990.5 8119.1 8466.8 12681.7 13469.0 13739.9 13793.8 14019.6 11606.5 11708.8 11718.1 11908.4 11977.8 11567.5 11604.7 11716.0 11818.4 12021.9 11842.9 11935.2 12033.9 12188.8 12230.1 7198.6 7557.1 7579.9 8363.0 8545.7 8473.8 11370.9 11744.5 11841.9 12023.2 11:47:47 INFO - PROCESS | 1654 | 11:47:47 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5735.65 Median:2690.37 stddev:3275.66 (121.8%) stddev-sans-first:3268.58 11:47:47 INFO - PROCESS | 1654 | Values: 1391.8 1398.0 1548.0 1750.9 1941.9 7946.8 8623.4 8884.3 8885.2 9026.4 8382.3 8612.2 8618.4 8620.6 8622.5 2310.7 2336.4 2338.2 2342.6 2344.1 2014.3 2276.4 2319.7 2340.8 2347.0 2309.6 2325.5 2337.5 2341.1 2342.4 2293.0 2313.3 2321.3 2324.9 2335.3 2320.1 2323.5 2332.7 2333.9 2334.5 8719.0 8946.8 9030.2 9062.7 9078.8 5238.3 5408.7 5414.7 5415.5 5448.5 3420.4 3510.1 3570.3 3581.2 3627.6 1067.0 1070.1 1073.0 1079.4 1093.8 11732.2 11803.3 11822.3 11829.8 11839.4 4375.1 4404.6 4501.8 4523.3 4526.4 9289.3 9291.6 9294.7 9298.4 9300.0 5218.4 5218.4 5220.5 5228.1 5230.3 3647.4 3772.9 4576.4 4757.8 4885.5 3740.8 3763.2 3849.6 3862.4 3907.2 1135.0 1137.2 1150.9 1162.1 1164.0 631.2 773.8 941.6 942.7 954.6 5894.5 5989.6 6156.5 6170.7 6205.4 476.5 495.2 496.3 496.5 632.6 3448.4 4085.3 4099.1 4146.8 4162.7 10400.8 10799.8 10955.7 10955.9 10979.8 10872.7 10900.3 10904.7 10922.3 10935.9 6618.7 7062.2 8342.6 8355.3 8388.3 6135.9 6152.2 6198.4 6236.3 6281.4 10206.4 10367.5 10379.6 10397.2 10399.4 9984.8 10286.8 10390.8 10501.0 10521.3 7611.9 7719.0 7775.3 7778.5 7779.5 8303.5 8409.7 8472.9 8495.2 8503.9 7706.7 7727.4 7748.2 7792.1 7866.5 7582.2 7582.5 7649.3 7653.8 7663.2 7151.0 7184.8 7244.5 7246.6 7259.9 4693.4 5091.9 5175.5 5203.9 5241.1 5130.6 5326.1 5331.9 5376.4 5475.8 11:47:47 INFO - PROCESS | 1654 | 11:47:47 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4426.72 Median:5013.67 stddev:1392.37 (27.8%) stddev-sans-first:1361.35 11:47:47 INFO - PROCESS | 1654 | Values: 297.9 303.5 306.1 308.7 311.4 4473.9 5190.4 5295.5 5309.3 5377.9 4168.4 4694.9 5062.6 5196.6 5219.4 3547.7 3604.8 3684.2 3955.0 4082.2 3915.9 3964.5 4011.8 4027.0 4035.7 3703.7 3984.4 3991.1 4003.8 4009.2 3460.7 3770.4 3823.3 4002.5 4020.6 2927.3 2941.9 2952.1 3181.3 3464.0 4040.4 4963.3 5078.0 5082.7 5129.3 3591.0 4033.9 4381.4 4557.3 4576.4 5408.4 5461.3 5465.5 5466.1 5509.8 3308.8 3804.7 3835.3 3839.6 3840.7 5532.0 5767.5 5791.2 5796.8 5802.8 2996.1 3636.5 3847.4 3863.5 3898.7 3953.2 3954.8 3960.3 3964.6 3965.5 3866.9 4071.4 4228.5 4375.8 4543.6 5226.9 5408.4 5480.8 5498.9 5581.3 1184.1 1327.4 1330.8 1336.6 1353.2 764.4 816.7 829.3 841.3 888.6 4325.5 4559.7 4789.8 4988.4 5005.8 4845.6 5077.9 5283.6 5284.9 5710.2 4561.2 4918.7 5141.7 5145.4 5166.8 2538.4 2663.2 2734.9 2840.0 2869.2 5515.3 5649.7 6005.3 6048.3 6057.5 4833.7 5059.7 5678.6 5847.0 5899.6 5644.8 5716.3 5859.6 5905.0 6018.6 4799.7 5524.9 5697.3 5747.2 5766.7 5251.4 5408.3 5571.6 5700.6 5738.9 4440.8 5113.9 5903.4 6044.6 6052.6 3539.7 3731.2 3845.6 3848.2 3862.7 5356.6 5502.3 5535.7 5588.3 5876.8 5187.6 5568.8 5615.0 5618.9 5626.8 4573.2 5294.3 5466.6 5553.2 5614.3 4783.3 4981.3 5353.8 5354.3 5415.5 5021.6 5238.7 5489.5 5548.2 5552.7 3986.7 4795.9 5203.9 5217.7 5467.9 11:47:47 INFO - PROCESS | 1654 | 11:47:47 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:23777.41 Median:3686.39 stddev:36768.91 (997.4%) stddev-sans-first:36831.03 11:47:47 INFO - PROCESS | 1654 | Values: 643.9 810.3 843.3 852.7 856.9 15223.3 15337.2 15629.5 15648.9 16088.6 14819.4 14944.6 14965.8 14975.6 14975.6 718.4 740.2 901.3 907.4 921.5 873.6 908.2 909.1 909.2 910.8 956.9 964.8 965.1 965.3 966.9 747.3 916.4 949.4 960.1 960.3 701.8 702.4 703.1 703.7 704.0 15747.6 15749.2 15752.1 15759.4 15774.5 12450.2 13243.7 14104.4 14533.6 14625.5 12617.6 13123.2 13129.9 13382.3 13636.3 4485.3 4519.8 4567.4 4655.0 4790.9 105963.0 106434.9 109537.1 110666.0 111595.4 9591.3 11317.3 11330.6 11449.3 11508.8 22831.4 22868.8 22893.0 22930.0 23365.6 8099.0 8106.1 8201.7 8272.9 8317.0 3813.7 4436.8 4583.1 4694.4 4732.3 3563.7 3680.7 3684.7 3688.1 3693.3 536.6 638.0 652.3 661.9 668.8 516.3 536.6 549.2 605.1 629.0 9064.3 9083.8 9128.1 9181.0 9266.3 554.3 600.1 607.6 641.9 643.4 4438.4 4461.1 4476.8 4543.7 4589.6 86788.6 111319.4 141816.0 151531.0 154377.0 82193.0 129735.0 130579.0 133958.0 135661.0 72063.0 72361.9 100443.5 101056.0 101802.0 9805.8 10032.4 10043.0 10075.6 10089.3 58128.7 58376.0 60905.4 60921.3 66525.0 77122.2 93500.0 98293.5 99819.0 100747.9 12798.4 13160.4 13228.4 13358.9 13423.7 14990.6 15129.5 15410.3 15731.7 15741.5 13692.5 14058.1 14069.6 14183.3 14481.3 13928.1 14071.9 14183.4 14277.6 14395.7 13704.3 13872.9 13899.1 14085.1 14095.3 9800.3 10678.5 11141.1 11585.3 11665.7 9366.3 10361.4 10710.6 10996.4 11632.6 11:47:47 INFO - PROCESS | 1654 | -------- Summary: end -------- 11:47:47 INFO - PROCESS | 1654 | 11:47:48 INFO - TEST-INFO | 1654: exit 0 11:47:48 INFO - TEST-OK | dromaeo_css | took 1116835ms 11:47:48 INFO - TEST-START | kraken 11:47:48 INFO - operating with platform_type : mac_ 11:47:48 INFO - Initialising browser for kraken test... 11:47:48 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmp5VnUk3/profile http://localhost:49266/getInfo.html) 11:47:50 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 11:47:50 INFO - PROCESS | 1660 | colorDepth:24 11:47:50 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 11:47:50 INFO - PROCESS | 1660 | __metrics 11:47:50 INFO - PROCESS | 1660 | 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" 11:47:50 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:47:51 INFO - TEST-INFO | 1660: exit 0 11:47:51 INFO - Browser initialized. 11:47:51 INFO - Running cycle 1/1 for kraken test... 11:47:51 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmp5VnUk3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:48:03 INFO - PROCESS | 1661 | RSS: Main: 243310592 11:48:03 INFO - PROCESS | 1661 | 11:48:06 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 11:48:07 INFO - PROCESS | 1661 | RSS: Main: 293744640 11:48:07 INFO - PROCESS | 1661 | 11:48:10 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 11:48:10 INFO - PROCESS | 1661 | RSS: Main: 304160768 11:48:10 INFO - PROCESS | 1661 | 11:48:14 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 11:48:14 INFO - PROCESS | 1661 | RSS: Main: 392024064 11:48:14 INFO - PROCESS | 1661 | 11:48:16 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 11:48:17 INFO - PROCESS | 1661 | RSS: Main: 293011456 11:48:17 INFO - PROCESS | 1661 | 11:48:19 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:48:20 INFO - PROCESS | 1661 | RSS: Main: 297050112 11:48:20 INFO - PROCESS | 1661 | 11:48:22 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 11:48:23 INFO - PROCESS | 1661 | RSS: Main: 334270464 11:48:23 INFO - PROCESS | 1661 | 11:48:26 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 11:48:27 INFO - PROCESS | 1661 | RSS: Main: 335392768 11:48:27 INFO - PROCESS | 1661 | 11:48:29 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 11:48:30 INFO - PROCESS | 1661 | RSS: Main: 333144064 11:48:30 INFO - PROCESS | 1661 | 11:48:32 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:48:32 INFO - PROCESS | 1661 | RSS: Main: 312135680 11:48:32 INFO - PROCESS | 1661 | 11:48:34 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:48:35 INFO - PROCESS | 1661 | RSS: Main: 319565824 11:48:35 INFO - PROCESS | 1661 | 11:48:37 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:48:38 INFO - PROCESS | 1661 | RSS: Main: 320978944 11:48:38 INFO - PROCESS | 1661 | 11:48:40 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:48:41 INFO - PROCESS | 1661 | RSS: Main: 336588800 11:48:41 INFO - PROCESS | 1661 | 11:48:44 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:48:45 INFO - PROCESS | 1661 | RSS: Main: 332865536 11:48:45 INFO - PROCESS | 1661 | 11:48:46 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 11:48:47 INFO - PROCESS | 1661 | RSS: Main: 376537088 11:48:47 INFO - PROCESS | 1661 | 11:48:47 INFO - PROCESS | 1661 | __start_tp_report 11:48:47 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 11:48:47 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 11:48:47 INFO - PROCESS | 1661 | |i|pagename|runs| 11:48:47 INFO - PROCESS | 1661 | |0;ai-astar;101;112;113;113;113;111;113;113;112;113 11:48:47 INFO - PROCESS | 1661 | |1;audio-beat-detection;154;117;123;197;142;130;122;131;152;120 11:48:47 INFO - PROCESS | 1661 | |2;audio-dft;196;264;200;184;183;184;184;184;180;180 11:48:47 INFO - PROCESS | 1661 | |3;audio-fft;80;74;56;107;61;58;63;67;82;56 11:48:47 INFO - PROCESS | 1661 | |4;audio-oscillator;86;90;94;72;72;69;96;70;68;102 11:48:47 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;87;85;87;84;87;83;87;83;87;87 11:48:47 INFO - PROCESS | 1661 | |6;imaging-darkroom;98;93;93;94;93;93;94;94;92;94 11:48:47 INFO - PROCESS | 1661 | |7;imaging-desaturate;84;94;119;84;83;85;83;86;84;85 11:48:47 INFO - PROCESS | 1661 | |8;json-parse-financial;55;56;54;55;54;53;55;52;54;54 11:48:47 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;65;72;79;74;72;70;69;70;70;70 11:48:47 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;85;83;84;85;84;80;86;87;86;86 11:48:47 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;135;145;141;134;137;137;134;139;138;139 11:48:47 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;165;165;165;146;195;224;165;169;163;162 11:48:47 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;66;67;67;65;65;66;67;65;64;66 11:48:47 INFO - PROCESS | 1661 | __end_tp_report 11:48:47 INFO - PROCESS | 1661 | __start_cc_report 11:48:47 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,2037 11:48:47 INFO - PROCESS | 1661 | __end_cc_report 11:48:47 INFO - PROCESS | 1661 | __startTimestamp1467139727060__endTimestamp 11:48:47 INFO - PROCESS | 1661 | 11:48:47 INFO - PROCESS | 1661 | ------- Summary: start ------- 11:48:47 INFO - PROCESS | 1661 | Number of tests: 14 11:48:47 INFO - PROCESS | 1661 | 11:48:47 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:111.40 Median:113.00 stddev:3.72 (3.3%) stddev-sans-first:0.73 11:48:47 INFO - PROCESS | 1661 | Values: 101.0 112.0 113.0 113.0 113.0 111.0 113.0 113.0 112.0 113.0 11:48:47 INFO - PROCESS | 1661 | 11:48:47 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:138.80 Median:136.50 stddev:24.27 (17.8%) stddev-sans-first:25.11 11:48:47 INFO - PROCESS | 1661 | Values: 154.0 117.0 123.0 197.0 142.0 130.0 122.0 131.0 152.0 120.0 11:48:47 INFO - PROCESS | 1661 | 11:48:47 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:193.90 Median:184.00 stddev:25.50 (13.9%) stddev-sans-first:27.04 11:48:47 INFO - PROCESS | 1661 | Values: 196.0 264.0 200.0 184.0 183.0 184.0 184.0 184.0 180.0 180.0 11:48:47 INFO - PROCESS | 1661 | 11:48:47 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:70.40 Median:65.00 stddev:15.99 (24.6%) stddev-sans-first:16.58 11:48:47 INFO - PROCESS | 1661 | Values: 80.0 74.0 56.0 107.0 61.0 58.0 63.0 67.0 82.0 56.0 11:48:47 INFO - PROCESS | 1661 | 11:48:47 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:81.90 Median:79.00 stddev:13.03 (16.5%) stddev-sans-first:13.74 11:48:47 INFO - PROCESS | 1661 | Values: 86.0 90.0 94.0 72.0 72.0 69.0 96.0 70.0 68.0 102.0 11:48:47 INFO - PROCESS | 1661 | 11:48:47 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:87.00 stddev:1.77 (2.0%) stddev-sans-first:1.81 11:48:47 INFO - PROCESS | 1661 | Values: 87.0 85.0 87.0 84.0 87.0 83.0 87.0 83.0 87.0 87.0 11:48:47 INFO - PROCESS | 1661 | 11:48:47 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:1.62 (1.7%) stddev-sans-first:0.71 11:48:47 INFO - PROCESS | 1661 | Values: 98.0 93.0 93.0 94.0 93.0 93.0 94.0 94.0 92.0 94.0 11:48:47 INFO - PROCESS | 1661 | 11:48:47 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:88.70 Median:84.50 stddev:11.12 (13.2%) stddev-sans-first:11.66 11:48:47 INFO - PROCESS | 1661 | Values: 84.0 94.0 119.0 84.0 83.0 85.0 83.0 86.0 84.0 85.0 11:48:47 INFO - PROCESS | 1661 | 11:48:47 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.50 stddev:1.14 (2.1%) stddev-sans-first:1.17 11:48:47 INFO - PROCESS | 1661 | Values: 55.0 56.0 54.0 55.0 54.0 53.0 55.0 52.0 54.0 54.0 11:48:47 INFO - PROCESS | 1661 | 11:48:47 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:71.00 stddev:3.63 (5.1%) stddev-sans-first:3.11 11:48:47 INFO - PROCESS | 1661 | Values: 65.0 72.0 79.0 74.0 72.0 70.0 69.0 70.0 70.0 70.0 11:48:47 INFO - PROCESS | 1661 | 11:48:47 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:85.50 stddev:2.01 (2.4%) stddev-sans-first:2.13 11:48:47 INFO - PROCESS | 1661 | Values: 85.0 83.0 84.0 85.0 84.0 80.0 86.0 87.0 86.0 86.0 11:48:47 INFO - PROCESS | 1661 | 11:48:47 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:137.90 Median:138.50 stddev:3.38 (2.4%) stddev-sans-first:3.42 11:48:47 INFO - PROCESS | 1661 | Values: 135.0 145.0 141.0 134.0 137.0 137.0 134.0 139.0 138.0 139.0 11:48:47 INFO - PROCESS | 1661 | 11:48:47 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:165.00 stddev:21.85 (13.2%) stddev-sans-first:23.03 11:48:47 INFO - PROCESS | 1661 | Values: 165.0 165.0 165.0 146.0 195.0 224.0 165.0 169.0 163.0 162.0 11:48:47 INFO - PROCESS | 1661 | 11:48:47 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:1.03 (1.6%) stddev-sans-first:1.09 11:48:47 INFO - PROCESS | 1661 | Values: 66.0 67.0 67.0 65.0 65.0 66.0 67.0 65.0 64.0 66.0 11:48:47 INFO - PROCESS | 1661 | -------- Summary: end -------- 11:48:47 INFO - PROCESS | 1661 | 11:48:47 INFO - TEST-INFO | 1661: exit 0 11:48:47 INFO - TEST-OK | kraken | took 59356ms 11:48:47 INFO - SUITE-END | took 1176s 11:48:48 INFO - Completed test suite (00:19:36) 11:48:48 INFO - javascript benchmark 11:48:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3389.4407422775244, 3398.779079359843, 3418.555587773589, 3423.4693622488517, 3466.324066806972, 5655.293088363955, 5680.744366503507, 5693.231413759141, 5765.279340082485, 5799.449052340026, 5690.487856092951, 5721.256236689224, 5807.796727114552, 5818.389069147732, 5823.475887170154, 5156.716380599066, 5168.819091331804, 5223.869403264911, 5237.214417837316, 5372.247885296062, 5331.946680533203, 5367.758450869701, 5377.946220537803, 5393.19102134679, 5417.322834645669, 5176.249443830642, 5195.272661827347, 5218.8695282617855, 5236.554892834114, 5273.8945221095555, 5218.295530103425, 5227.66020208689, 5227.686338819683, 5256.211568264791, 5341.2255222992835, 5202.895942081175, 5203.9219411708855, 5236.423993360727, 5307.867303317448, 5327.254184414185, 5588.552915766729, 5640.294963129609, 5756.539476841843, 5823.5341172706085, 5835.095560188178, 6199.659018753967, 6220.222472190976, 6257.311695713468, 6279.560430769848, 6282.183316168869, 6277.403646653606, 6285.245770507523, 6296.496280297565, 6305.968470157666, 6307.684615769193, 4909.018196360742, 5725.685087320154, 5789.710514474344, 5821.767129314865, 5881.411858814127, 6243.43809057187, 6261.311255761862, 6270.59241149333, 6275.497960163176, 6282.089097080989, 6174.691265436711, 6177.752889884444, 6205.410486003822, 6207.472364849038, 6214.471769899519, 6125.601835880653, 6126.969365153146, 6165.260168779775, 6256.874862502725, 6303.243610766737, 6182.6599537025895, 6191.25704915413, 6225.844353891189, 6226.937730622726, 6237.656928868956, 6241.687915604292, 6266.905996410058, 6286.0, 6297.2128483939505, 6299.0, 4843.685160464559, 4866.026794641086, 4921.138800709861, 4958.42978057528, 4989.351384320015, 4479.059397526491, 4506.008678090815, 4510.368548403226, 4510.985028368591, 4514.751688657089, 5698.686572238566, 5707.12041204764, 5713.771449141987, 5719.571032172604, 5730.0, 5140.588752899759, 6237.563370564017, 6287.276963149205, 6301.653409062454, 6329.493640508748, 5692.174634677948, 5724.771009159587, 5730.054541000688, 5750.971245143747, 5752.165935939349, 5076.593872490192, 5248.527632513092, 5329.147336426231, 5422.674639521641, 5423.593230507728, 6218.81343559694, 6250.499960003189, 6290.496760259169, 6294.307626161119, 6393.8721225576155, 6260.906086408617, 6277.654728989767, 6279.277883043325, 6317.4630156437215, 6319.0, 6179.011358182669, 6347.39699728525, 6352.809415717536, 6362.872742545216, 6368.076628888878, 6103.277060575968, 6157.938420615916, 6196.318404975539, 6256.249250090018, 6259.530535209787, 5256.290400795997, 5485.341758988947, 5946.345901950868, 6261.78083767066, 6291.591046581958, 5960.552958528041, 6045.304789949037, 6136.8772624548155, 6138.0, 6149.292831324455, 5306.8938621228135, 5976.641401515923, 6057.575969682256, 6065.8180254592435, 6092.9086063709965, 5842.474177324061, 5906.173135761082, 5912.231409916855, 5970.641761494324, 5985.461308482258, 6019.789307374221, 6103.244309015946, 6110.816675499742, 6112.786052488142, 6129.549118752428, 4994.875128121826, 5955.106733989763, 6019.307779605227, 6020.789272375621, 6050.0924861269405], "value": 5697.246821888266, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.79795283933584, 494.67598722836556, 495.96591107540775, 512.1523877981937, 528.5692160888875, 24251.636225456612, 30526.084217473483, 41788.58211417885, 43025.0, 43032.35451468223, 41402.79298603503, 43917.12165756683, 43942.780286098605, 43974.12051758971, 43997.7800110999, 9581.329306948533, 9712.0, 9778.0, 9780.55987480565, 9795.853062204036, 7450.0, 9018.0, 11820.7635847283, 12040.397980101005, 12045.698857528545, 11787.882121178807, 11862.52549898003, 11879.287242765418, 11906.107041971844, 11906.34515101669, 8804.779880502974, 11787.823182652268, 11910.880891191107, 11987.100967427477, 12012.219205751642, 11583.18917675763, 11783.234089784222, 11787.17489775716, 11796.587119450864, 11801.350925699084, 40806.0, 41207.38188927169, 41309.586904130876, 41444.0, 41523.0, 3542.929141417157, 3658.5426821647293, 3787.772733635963, 3853.556840963269, 4213.157368526307, 31284.06147815569, 31668.89158879451, 32465.675343246738, 32541.698332066688, 32563.837180814182, 2918.3871387008753, 3240.3843269778904, 3407.4548072308557, 3431.605365382988, 3458.5849698036145, 474301.0, 503101.0, 506454.0, 512721.4363928194, 513039.0, 60925.0, 61081.08378374328, 61463.0, 61466.0, 61692.07461888075, 63886.0, 63998.0, 64180.67909660515, 64205.357946420874, 64258.0, 16476.588085297964, 16723.49829505117, 16743.07913064769, 16750.16249187536, 16796.580085497717, 11170.385628790495, 11727.882721172678, 12103.213291136048, 12865.163764355288, 13007.609771706675, 13576.253306068262, 16145.919270403572, 16258.75611865823, 16303.755443668355, 16327.0, 5546.667199968015, 6512.772052978123, 6535.281119076898, 6559.672016399161, 6567.573107747981, 969.583079275904, 1036.2176556699644, 1071.5338827609858, 1098.873629532598, 1181.8109102543724, 25409.237722868344, 29780.2554936124, 29786.851065744533, 30494.69505304963, 30707.9252226171, 1105.40308233553, 1109.6615532262576, 1111.8943700348455, 1133.0878642692712, 1149.557420393138, 15254.03490616735, 15841.128737919526, 15921.124338161513, 15983.680326393407, 16325.510234692978, 657554.0, 659112.0, 662569.0, 663001.0, 663585.0, 60169.398306017254, 60179.09731354033, 60190.69904650449, 60264.698676507214, 60314.39685603176, 1208.9335086570322, 1264.7849865522883, 1377.0, 1401.0962928910797, 1409.9224542650252, 28517.0, 31546.36907261888, 32007.839960800047, 32174.678253217167, 32212.033638990866, 50572.0, 51177.0, 51204.0, 51265.23102153545, 51266.0, 532020.0, 552198.0, 552822.0, 554625.0, 554988.0, 34822.47766283558, 40990.79504602458, 41280.79359603183, 41819.0, 42724.35913461363, 48459.0, 48498.75750621224, 50017.74991125021, 50244.246336304255, 50308.24537632012, 32778.508322374706, 39853.80073099616, 40234.0, 41170.38244426276, 41189.0, 32419.513707294882, 33172.50241246335, 38531.03672408212, 39453.408198877616, 40324.79837600793, 30360.696393035785, 36955.260894781415, 38318.80840595779, 39031.0, 39155.41266880942, 33110.66889331076, 34355.65644343525, 35137.0, 35738.821305894344, 35839.08655071988, 25476.74523254818, 25634.743652563237, 25820.483590327713, 26063.73936260613, 26114.86942565275], "value": 20635.081348160944, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.6489238960507, 381.52119090541356, 383.15131982658414, 395.78825328449284, 407.6494214975121, 18576.978266195354, 21366.89316553419, 21370.0, 21764.0, 21833.126674933003, 20878.89560552199, 21262.893685531588, 21284.361469155912, 21725.0, 21792.237271695492, 5362.91955620666, 5885.234919460475, 6167.5065994720535, 6179.19670442844, 6179.412955769218, 5854.73653685583, 6031.215941927543, 6110.755569777203, 6173.259208894939, 6202.6278423294525, 6075.939240607604, 6081.908771368433, 6099.512039036888, 6100.176476175726, 6110.419510146529, 5795.159701843251, 6089.0866370044405, 6092.847678808021, 6109.725062372204, 6111.847203819918, 5274.287971123926, 5300.1784723367555, 5308.92036619451, 5310.123829568117, 5310.9734451327495, 25729.614055789178, 26054.609180862302, 26406.339841503923, 26488.73511264882, 26490.47019059608, 2273.7271527416815, 2276.1919518571, 2312.6068568343253, 2426.8907899144406, 2429.6112621980396, 13313.535511093773, 14474.348654310581, 14990.47533336328, 16278.860479766303, 16445.753313700305, 1697.889637173592, 1867.794542600313, 1872.7097299918535, 1953.5018570264492, 1978.9307374241832, 80393.19606803915, 99907.50046249722, 102037.0, 106298.0, 110518.44740776325, 10985.835212471819, 11077.676696990871, 11130.387828669502, 11170.553177872956, 11338.886611133783, 13593.320333983262, 13736.038477306693, 13738.519151829638, 13741.793873091912, 13749.518766843312, 8585.828283434297, 8768.78078048054, 8813.779655508664, 8866.77833054166, 8879.200871921428, 7351.375133113638, 8821.514816685143, 8915.242204412569, 8918.817807706697, 8967.372283940193, 6594.505412094112, 6717.56335838169, 6719.395254427125, 6734.1918969722965, 6742.089817874622, 1425.8217722784652, 1646.4566692991284, 1810.1220907859677, 1821.8724689271623, 1853.888766673977, 945.479986007693, 1000.3247807729754, 1077.4828082520435, 1080.508368692243, 1084.5282302198568, 19658.41024769259, 21474.658037362806, 22320.21879234219, 22368.664470032963, 22546.661800073012, 935.4378188425346, 968.9273304502191, 982.3516479123743, 1009.4902074452355, 1043.7234132954773, 11369.431528423711, 11473.139514536444, 11647.359395233174, 11723.0, 11765.470553825098, 600468.5863484581, 603944.0, 611592.0, 620045.0, 631373.0, 116569.41715291538, 117345.41327293309, 117475.8252417482, 119376.40311798385, 121393.0, 990.4601991914424, 1004.8492726091145, 1012.5139932832282, 1016.7915577306634, 1044.4360045575304, 15853.207339633203, 20698.689519657517, 20700.378988630364, 20822.27122050721, 20861.37415877526, 51899.84801215818, 51956.48043519516, 52186.73906630443, 52396.47603524068, 52618.42144735664, 62425.063624044764, 64389.0, 67038.66480667566, 68923.0, 69412.3058769426, 6643.634600096848, 6760.898586521108, 7990.480618759994, 8119.147489513516, 8466.830663386821, 12681.682957926125, 13469.0, 13739.931300343434, 13793.793093103412, 14019.579412617637, 11606.477708503306, 11708.775332686762, 11718.062554995751, 11908.40457977115, 11977.820332695192, 11567.537298508301, 11604.709882253012, 11716.004139648225, 11818.40907954616, 12021.939890300842, 11842.881571184178, 11935.224210426295, 12033.939830300793, 12188.756224875275, 12230.082743794073, 7198.5680859148615, 7557.093148822177, 7579.924200758142, 8362.958185209034, 8545.700900468453, 8473.83052338962, 11370.886291136983, 11744.471498782405, 11841.940790295994, 12023.21849079807], "value": 9830.491696314002, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1391.8121053657756, 1397.9790303145448, 1547.9613009674754, 1750.929962801488, 1941.9223231070757, 7946.761597152087, 8623.439476434036, 8884.289256859453, 8885.222955408928, 9026.413283136608, 8382.329413646909, 8612.22489975902, 8618.396712230146, 8620.612072456739, 8622.525761083138, 2310.7111611048617, 2336.411240356557, 2338.205010296499, 2342.554914566227, 2344.0506594829035, 2014.2547257514666, 2276.430892276931, 2319.7332306784683, 2340.789328960393, 2346.9765302346927, 2309.595820731374, 2325.476767727265, 2337.4506990857135, 2341.1220792202926, 2342.4261056041296, 2292.954140917189, 2313.3175713164574, 2321.338418550705, 2324.8953797079002, 2335.2994101769573, 2320.083566991046, 2323.5004474038165, 2332.661764044221, 2333.94165145871, 2334.5330933813307, 8719.0, 8946.821063578756, 9030.187283144549, 9062.682806101755, 9078.773030674285, 5238.345206849144, 5408.729563521808, 5414.729263536847, 5415.539679127279, 5448.536874365684, 3420.435628121357, 3510.087377281925, 3570.250247448039, 3581.212133330663, 3627.582827974764, 1067.0, 1070.0797314309686, 1072.983905241422, 1079.3523885668535, 1093.8413929980188, 11732.17874748768, 11803.291802491878, 11822.290662560188, 11829.763404731943, 11839.408029598571, 4375.146846364992, 4404.6035856772405, 4501.7524036177665, 4523.344115103291, 4526.366308716782, 9289.256859451227, 9291.58187881547, 9294.674686386079, 9298.442093474414, 9299.95350023259, 5218.373795144607, 5218.425973142951, 5220.504052115043, 5228.05894938907, 5230.320058392384, 3647.4164133739005, 3772.9245415091546, 4576.405067341224, 4757.833475828399, 4885.487023862514, 3740.8129593520216, 3763.2285381496727, 3849.634284742945, 3862.420636904487, 3907.2185562887535, 1134.9602763903224, 1137.2039572299423, 1150.8734039255676, 1162.0877611075266, 1164.0280365894573, 631.1857703562445, 773.7833406646148, 941.5527624378366, 942.6747772018642, 954.6229239450378, 5894.528437724972, 5989.550783691154, 6156.538259630545, 6170.660613666291, 6205.410486003912, 476.4592187866796, 495.247224219192, 496.2704823908848, 496.4662987288679, 632.6235889645653, 3448.379291727463, 4085.2850751118435, 4099.077707515785, 4146.771927543954, 4162.708610397303, 10400.791984160274, 10799.83800242997, 10955.726106847393, 10955.890441095487, 10979.780404391868, 10872.67381978542, 10900.291481053706, 10904.745954215367, 10922.29005114665, 10935.890641093647, 6618.70215840298, 7062.187848397397, 8342.62458189383, 8355.289800367036, 8388.32893368529, 6135.9386406137155, 6152.200213972155, 6198.380161983766, 6236.251649802053, 6281.371862813682, 10206.387616743019, 10367.481625918825, 10379.636712715019, 10397.168226541708, 10399.428031458343, 9984.800303994025, 10286.845697314397, 10390.792184156124, 10501.002404771534, 10521.263511554118, 7611.858221266854, 7719.0, 7775.339096176888, 7778.455508114604, 7779.533228006338, 8303.501789892625, 8409.663613455394, 8472.872906906277, 8495.23542881119, 8503.872441913252, 7706.691732330868, 7727.381809455118, 7748.208090186968, 7792.142864285038, 7866.528008319519, 7582.241775822595, 7582.54504729718, 7649.273319034683, 7653.770386888402, 7663.195364486649, 7150.96424517874, 7184.820379490346, 7244.4566657499845, 7246.565206087441, 7259.854802903807, 4693.3854156287725, 5091.949080509147, 5175.482451754794, 5203.947960520346, 5241.056609810271, 5130.58955283569, 5326.147816349366, 5331.893362132813, 5376.354837419534, 5475.808346707846], "value": 4508.15725388014, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.87489254513105, 303.50074128059345, 306.14890604120546, 308.73448834002755, 311.36636943819303, 4473.932891006638, 5190.351206099238, 5295.4704529547025, 5309.33633295838, 5377.946220537793, 4168.437260969768, 4694.929576056362, 5062.569681577062, 5196.636235463509, 5219.408080535435, 3547.6518922809296, 3604.8197590120453, 3684.244729830392, 3954.9604503955024, 4082.1835632873544, 3915.8643993242067, 3964.5044369453817, 4011.7563555297806, 4026.9865067466267, 4035.6973227007943, 3703.7037037037107, 3984.4421780950547, 3991.12638899993, 4003.75977441353, 4009.218202450523, 3460.7058400036026, 3770.377887648535, 3823.3118038752873, 4002.4578248250364, 4020.638142567183, 2927.2535503446697, 2941.8970336038137, 2952.099609619065, 3181.284211052518, 3463.965360346415, 4040.373742069984, 4963.255511673242, 5077.984403119391, 5082.695038297677, 5129.333186685744, 3590.9640903591153, 4033.878983630496, 4381.364702118207, 4557.293619488984, 4576.3821884045565, 5408.404520666413, 5461.344638643388, 5465.508104270595, 5466.098093814516, 5509.807156749534, 3308.8180150091735, 3804.714646401531, 3835.347990841564, 3839.616038396166, 3840.711946603988, 5532.029642650249, 5767.509761670221, 5791.21818554494, 5796.797112101099, 5802.767889284423, 2996.1011696490964, 3636.454531820248, 3847.3840986785617, 3863.5363756349416, 3898.7075969302414, 3953.1537293148886, 3954.844609687727, 3960.32674445345, 3964.6431821136234, 3965.5439624442984, 3866.883993480144, 4071.42999980003, 4228.471441069866, 4375.824967001348, 4543.5910768031035, 5226.869328266824, 5408.350997880201, 5480.848002877362, 5498.945010549844, 5581.302337207849, 1184.1119160629528, 1327.4424741608548, 1330.7690386392742, 1336.6057013181135, 1353.2083731017465, 764.3846703403814, 816.7059858450953, 829.2826704900218, 841.3101256969227, 888.6223355074068, 4325.4593175853015, 4559.726416415026, 4789.784459699322, 4988.351514303117, 5005.774740136702, 4845.63657725672, 5077.923831142536, 5283.573435172559, 5284.9471505284455, 5710.200571919934, 4561.201789686792, 4918.6556941014505, 5141.665791723526, 5145.3568303962, 5166.767495462713, 2538.37809736612, 2663.227663977425, 2734.8632568371504, 2840.0059979007183, 2869.188195568313, 5515.283013208337, 5649.661020338793, 6005.3394126646035, 6048.3145842907525, 6057.515398768088, 4833.685810422311, 5059.721715305694, 5678.602497825113, 5846.970765146147, 5899.616524925866, 5644.8306550803545, 5716.28546431696, 5859.619124756877, 5904.998844567267, 6018.608790428608, 4799.66402351832, 5524.922640085145, 5697.316322041382, 5747.224124743273, 5766.711664416678, 5251.3960894496095, 5408.296921400193, 5571.629164355507, 5700.629459085146, 5738.942610573841, 4440.800163992563, 5113.8721531962, 5903.439173278532, 6044.576879618385, 6052.576319657758, 3539.71682265413, 3731.216444546567, 3845.596212397658, 3848.191879705293, 3862.729608927348, 5356.5982551308025, 5502.257195278587, 5535.667859928417, 5588.323502949181, 5876.823695289148, 5187.584993200459, 5568.777248909998, 5615.0, 5618.915716264341, 5626.803061892978, 4573.2164455861575, 5294.338207724035, 5466.594698332721, 5553.195636203692, 5614.29821272341, 4783.282507623745, 4981.310092549982, 5353.822159124987, 5354.340154552101, 5415.48552887475, 5021.573166280907, 5238.738063096754, 5489.478499542537, 5548.167774833646, 5552.69460179694, 3986.6810655148097, 4795.880102997453, 5203.921941170961, 5217.713025783618, 5467.945320546903], "value": 3984.560330068072, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [643.8647883944371, 810.3193317613204, 843.2705709561228, 852.7441767469758, 856.8543347630903, 15223.31495082721, 15337.23313834308, 15629.531114066582, 15648.921755391222, 16088.597785055379, 14819.407223711065, 14944.62638434037, 14965.775513367309, 14975.625609359771, 14975.625609359771, 718.3534818663193, 740.1599184925095, 901.2924853989603, 907.4373888189299, 921.4839689773714, 873.5981448533682, 908.2007833106857, 909.1181553892746, 909.222614664459, 910.7950711090018, 956.8564715292727, 964.753987733127, 965.0638880286111, 965.3242730088931, 966.917811985982, 747.3274053351972, 916.422653728147, 949.4018768176005, 960.0687333286701, 960.3277705606068, 701.8385771272585, 702.4380495603497, 703.1281211298004, 703.7325816189815, 703.968321425537, 15747.606309842231, 15749.2125393731, 15752.1336326502, 15759.369625214978, 15774.526764196979, 12450.190737602026, 13243.668908277274, 14104.435822567086, 14533.636659083502, 14625.488107916171, 12617.621471355875, 13123.226034948031, 13129.934350328283, 13382.330883455788, 13636.250006249653, 4485.349624304457, 4519.751413672247, 4567.383403240553, 4655.045715628288, 4790.904181916377, 105963.0, 106434.93565064405, 109537.07083181276, 110666.0, 111595.44202278937, 9591.280653950982, 11317.28385187549, 11330.60342887995, 11449.255798373079, 11508.849115088591, 22831.429214269778, 22868.771312286663, 22892.969816358305, 22930.0, 23365.649515257286, 8099.0, 8106.108328083906, 8201.71294004707, 8272.875906861402, 8317.001959764866, 3813.694904407641, 4436.82252709888, 4583.083383323348, 4694.36626055485, 4732.290156476556, 3563.679268865763, 3680.650338217865, 3684.6683798458266, 3688.114852435396, 3693.316736403774, 536.6029138437606, 638.0397501759821, 652.2890049845691, 661.9073329733841, 668.7826456401689, 516.3133033066014, 536.629725489411, 549.228334190463, 605.140700205709, 628.955973081889, 9064.274858011344, 9083.81832363349, 9128.08719128095, 9181.0, 9266.258699304039, 554.27666894702, 600.1178267946112, 607.6293460988793, 641.8555824939351, 643.4080645805776, 4438.378626992224, 4461.13007963444, 4476.798544065459, 4543.704659197141, 4589.632829373642, 86788.56605716932, 111319.44340278409, 141816.0, 151531.0, 154377.0, 82193.0, 129735.0, 130579.0, 133958.0, 135661.0, 72063.0, 72361.91457128148, 100443.49778251209, 101056.0, 101802.0, 9805.754856128653, 10032.448215348226, 10043.0, 10075.596976120873, 10089.344192627455, 58128.70935645295, 58376.0, 60905.39094609174, 60921.344719316985, 66525.0, 77122.2287777115, 93500.0, 98293.50853245688, 99819.0, 100747.9326860586, 12798.424070917017, 13160.407781649657, 13228.404721787736, 13358.866411335763, 13423.664408389868, 14990.625234369229, 15129.47046853399, 15410.306536206122, 15731.68536629238, 15741.527754167393, 13692.52076177329, 14058.086224395382, 14069.577912662637, 14183.290835458392, 14481.275936203358, 13928.094673846168, 14071.929640351733, 14183.361748721127, 14277.571672849832, 14395.712085758018, 13704.314784260947, 13872.861271387157, 13899.096558723651, 14085.08446950945, 14095.295235237993, 9800.264980126376, 10678.46607669629, 11141.118504800004, 11585.304881707125, 11665.650030499099, 9366.297527685314, 10361.430121343097, 10710.617889583284, 10996.351165433798, 11632.592859249886], "value": 7898.019455362324, "unit": "score"}], "name": "dromaeo_css", "value": 7398.305798291684, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 113.0, 113.0, 113.0, 111.0, 113.0, 113.0, 112.0, 113.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 117.0, 123.0, 197.0, 142.0, 130.0, 122.0, 131.0, 152.0, 120.0], "value": 138.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 264.0, 200.0, 184.0, 183.0, 184.0, 184.0, 184.0, 180.0, 180.0], "value": 193.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 74.0, 56.0, 107.0, 61.0, 58.0, 63.0, 67.0, 82.0, 56.0], "value": 70.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 90.0, 94.0, 72.0, 72.0, 69.0, 96.0, 70.0, 68.0, 102.0], "value": 81.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 84.0, 87.0, 83.0, 87.0, 83.0, 87.0, 87.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 93.0, 93.0, 94.0, 93.0, 93.0, 94.0, 94.0, 92.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 94.0, 119.0, 84.0, 83.0, 85.0, 83.0, 86.0, 84.0, 85.0], "value": 88.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 54.0, 55.0, 54.0, 53.0, 55.0, 52.0, 54.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 79.0, 74.0, 72.0, 70.0, 69.0, 70.0, 70.0, 70.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 84.0, 85.0, 84.0, 80.0, 86.0, 87.0, 86.0, 86.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 145.0, 141.0, 134.0, 137.0, 137.0, 134.0, 139.0, 138.0, 139.0], "value": 137.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 165.0, 146.0, 195.0, 224.0, 165.0, 169.0, 163.0, 162.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 65.0, 65.0, 66.0, 67.0, 65.0, 64.0, 66.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1450.1000000000001, "alertThreshold": 2.0}]} 11:48:48 INFO - Return code: 0 11:48:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:48:48 INFO - # TBPL SUCCESS # 11:48:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:48:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:48:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:48:48 INFO - 2016-06-28 11:48:48.357 screenresolution[1665:10153] starting screenresolution argv=screenresolution get 11:48:48 INFO - 2016-06-28 11:48:48.359 screenresolution[1665:10153] Display 0: 1600x1200x32@60 11:48:48 INFO - 2016-06-28 11:48:48.366 screenresolution[1666:10157] starting screenresolution argv=screenresolution list 11:48:48 INFO - Available Modes on Display 0 11:48:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:48:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:48:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:48:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:48:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:48:48 INFO - Intel Iris: 11:48:48 INFO - Chipset Model: Intel Iris 11:48:48 INFO - Type: GPU 11:48:48 INFO - Bus: Built-In 11:48:48 INFO - VRAM (Dynamic, Max): 1536 MB 11:48:48 INFO - Vendor: Intel (0x8086) 11:48:48 INFO - Device ID: 0x0a2e 11:48:48 INFO - Revision ID: 0x0009 11:48:48 INFO - Displays: 11:48:48 INFO - Display: 11:48:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:48:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:48:48 INFO - Display Serial Number: 1600x1200 60 11:48:48 INFO - Main Display: Yes 11:48:48 INFO - Mirror: Off 11:48:48 INFO - Online: Yes 11:48:48 INFO - Rotation: Supported 11:48:48 INFO - Return code: 0 11:48:48 INFO - Running post-action listener: _resource_record_post_action 11:48:48 INFO - ##### 11:48:48 INFO - ##### Finished run-tests step (success) 11:48:48 INFO - ##### 11:48:48 INFO - Running post-run listener: _resource_record_post_run 11:48:48 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 32628224; Write bytes: 706361344; Read time: 318; Write time: 2047 11:48:48 INFO - TinderboxPrint: CPU usage
53.7% 11:48:48 INFO - TinderboxPrint: I/O read bytes / time
32,628,224 / 318 11:48:48 INFO - TinderboxPrint: I/O write bytes / time
706,361,344 / 2,047 11:48:48 INFO - TinderboxPrint: CPU idle
2,242.5 (46.3%) 11:48:48 INFO - TinderboxPrint: CPU system
966.9 (20.0%) 11:48:48 INFO - TinderboxPrint: CPU user
1,636.4 (33.8%) 11:48:48 INFO - TinderboxPrint: Swap in / out
435,204,096 / 0 11:48:48 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 396302848; Write bytes: 389974016; Read time: 29289; Write time: 714 11:48:48 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 30690816; Write bytes: 296538112; Read time: 297; Write time: 1295 11:48:49 INFO - Running post-run listener: _upload_blobber_files 11:48:49 INFO - Blob upload gear active. 11:48:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:48:49 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 11:48:49 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:48:49 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:48:49 INFO - (blobuploader) - INFO - Open directory for files ... 11:48:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:48:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:48:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:48:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:48:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:48:50 INFO - (blobuploader) - INFO - Done attempting. 11:48:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:48:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:48:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:48:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:48:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:48:51 INFO - (blobuploader) - INFO - Done attempting. 11:48:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:48:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:48:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:48:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:48:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:48:54 INFO - (blobuploader) - INFO - Done attempting. 11:48:54 INFO - (blobuploader) - INFO - Iteration through files over. 11:48:54 INFO - Return code: 0 11:48:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:48:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:48:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/493f0c892f7b405ca560d5e1c5ad5cd14e2a05b555067b30df89c48179fc45b6231021d9afc979e035830344db490ccbd3463e007a47ccbd8d0252f0b8d44dd7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2cd7264c65fc5ad354ded8d7bd02470d8952663e3b43cbb71a8e92f721d2e93cc0761320543c91f20c1b0b3f8522da07691e95cc12324ef5e565a78ebe5c3321", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/92079775d507be3fe0d4ffd6274dc96d57cdc2960eb73d27fe16f93519d9c7bec645283ecff59726ef116049d8345254a486d22ff1b187d623857bd37b5cf99a"} 11:48:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:48:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:48:54 INFO - Contents: 11:48:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/493f0c892f7b405ca560d5e1c5ad5cd14e2a05b555067b30df89c48179fc45b6231021d9afc979e035830344db490ccbd3463e007a47ccbd8d0252f0b8d44dd7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2cd7264c65fc5ad354ded8d7bd02470d8952663e3b43cbb71a8e92f721d2e93cc0761320543c91f20c1b0b3f8522da07691e95cc12324ef5e565a78ebe5c3321", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/92079775d507be3fe0d4ffd6274dc96d57cdc2960eb73d27fe16f93519d9c7bec645283ecff59726ef116049d8345254a486d22ff1b187d623857bd37b5cf99a"} 11:48:54 INFO - Running post-run listener: copy_logs_to_upload_dir 11:48:54 INFO - Copying logs to upload dir... 11:48:54 INFO - mkdir: /builds/slave/test/build/upload/logs 11:48:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1313.172139 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 53 secs) (at 2016-06-28 11:48:54.545309) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-28 11:48:54.548483) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VYnTHfSgt9/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/493f0c892f7b405ca560d5e1c5ad5cd14e2a05b555067b30df89c48179fc45b6231021d9afc979e035830344db490ccbd3463e007a47ccbd8d0252f0b8d44dd7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2cd7264c65fc5ad354ded8d7bd02470d8952663e3b43cbb71a8e92f721d2e93cc0761320543c91f20c1b0b3f8522da07691e95cc12324ef5e565a78ebe5c3321", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/92079775d507be3fe0d4ffd6274dc96d57cdc2960eb73d27fe16f93519d9c7bec645283ecff59726ef116049d8345254a486d22ff1b187d623857bd37b5cf99a"} build_url:https://queue.taskcluster.net/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011426 build_url: 'https://queue.taskcluster.net/v1/task/SlG31YBlQSWrgCBNYS6DPA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/493f0c892f7b405ca560d5e1c5ad5cd14e2a05b555067b30df89c48179fc45b6231021d9afc979e035830344db490ccbd3463e007a47ccbd8d0252f0b8d44dd7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2cd7264c65fc5ad354ded8d7bd02470d8952663e3b43cbb71a8e92f721d2e93cc0761320543c91f20c1b0b3f8522da07691e95cc12324ef5e565a78ebe5c3321", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/92079775d507be3fe0d4ffd6274dc96d57cdc2960eb73d27fe16f93519d9c7bec645283ecff59726ef116049d8345254a486d22ff1b187d623857bd37b5cf99a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-28 11:48:54.581399) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 11:48:54.581712) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VYnTHfSgt9/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.mJ1H0eyu5i/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005082 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 11:48:54.629797) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-28 11:48:54.630136) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-28 11:48:54.630473) ========= ========= Total master_lag: 0.17 =========