builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0155 starttime: 1467162513.58 results: success (0) buildid: 20160628171428 builduid: 8c9d98ad66a14461853b99c2d7d772bf revision: 6eccb6aa19da093a279397d1233fc73dfc72dc4b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-28 18:08:33.583574) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-28 18:08:33.584473) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-28 18:08:33.585115) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-28 18:08:33.628336) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 18:08:33.629030) ========= 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.YfjfLFTgiB/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.klo1ngLWin/Listeners TMPDIR=/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 18:08:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2016-06-28 18:08:33 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.127428 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 18:08:33.780151) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 18:08:33.780481) ========= 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.YfjfLFTgiB/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.klo1ngLWin/Listeners TMPDIR=/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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.020865 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 18:08:33.831641) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-28 18:08:33.832408) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 6eccb6aa19da093a279397d1233fc73dfc72dc4b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 6eccb6aa19da093a279397d1233fc73dfc72dc4b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YfjfLFTgiB/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.klo1ngLWin/Listeners TMPDIR=/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 18:08:33,904 truncating revision to first 12 chars 2016-06-28 18:08:33,905 Setting DEBUG logging. 2016-06-28 18:08:33,905 attempt 1/10 2016-06-28 18:08:33,905 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/6eccb6aa19da?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-28 18:08:34,685 unpacking tar archive at: autoland-6eccb6aa19da/testing/mozharness/ program finished with exit code 0 elapsedTime=1.047139 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-28 18:08:34.903254) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-28 18:08:34.903618) ========= script_repo_revision: 6eccb6aa19da093a279397d1233fc73dfc72dc4b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-28 18:08:34.904072) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-28 18:08:34.904375) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-28 18:08:34.923519) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-06-28 18:08:34.923841) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YfjfLFTgiB/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.klo1ngLWin/Listeners TMPDIR=/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 18:08:35 INFO - MultiFileLogger online at 20160628 18:08:35 in /builds/slave/test 18:08:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 18:08:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:08:35 INFO - {'append_to_log': False, 18:08:35 INFO - 'base_work_dir': '/builds/slave/test', 18:08:35 INFO - 'blob_upload_branch': 'Autoland', 18:08:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:08:35 INFO - 'branch': 'Autoland', 18:08:35 INFO - 'buildbot_json_path': 'buildprops.json', 18:08:35 INFO - 'config_files': ('talos/mac_config.py',), 18:08:35 INFO - 'default_actions': ('clobber', 18:08:35 INFO - 'read-buildbot-config', 18:08:35 INFO - 'download-and-extract', 18:08:35 INFO - 'populate-webroot', 18:08:35 INFO - 'create-virtualenv', 18:08:35 INFO - 'install', 18:08:35 INFO - 'run-tests'), 18:08:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:08:35 INFO - 'download_minidump_stackwalk': True, 18:08:35 INFO - 'download_symbols': 'ondemand', 18:08:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:08:35 INFO - 'tooltool.py': '/tools/tooltool.py', 18:08:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:08:35 INFO - '/tools/misc-python/virtualenv.py')}, 18:08:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:08:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:08:35 INFO - 'installer_path': 'installer.exe', 18:08:35 INFO - 'log_level': 'info', 18:08:35 INFO - 'log_name': 'talos', 18:08:35 INFO - 'log_to_console': True, 18:08:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:08:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:08:35 INFO - 'opt_config_files': (), 18:08:35 INFO - 'pip_index': False, 18:08:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:08:35 INFO - 'cmd': ('bash', 18:08:35 INFO - '-c', 18:08:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:08:35 INFO - 'enabled': True, 18:08:35 INFO - 'halt_on_failure': False, 18:08:35 INFO - 'name': 'check_screen_resolution'},), 18:08:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:08:35 INFO - 'cmd': ('bash', 18:08:35 INFO - '-c', 18:08:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:08:35 INFO - 'enabled': True, 18:08:35 INFO - 'halt_on_failure': False, 18:08:35 INFO - 'name': 'check_screen_resolution'},), 18:08:35 INFO - 'run_cmd_checks_enabled': True, 18:08:35 INFO - 'sps_profile': False, 18:08:35 INFO - 'sps_profile_interval': 0, 18:08:35 INFO - 'suite': 'dromaeojs', 18:08:35 INFO - 'system_bits': '32', 18:08:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:08:35 INFO - 'title': 't-yosemite-r7-0155', 18:08:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:08:35 INFO - 'use_talos_json': True, 18:08:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:08:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:08:35 INFO - 'work_dir': 'build'} 18:08:35 INFO - ##### 18:08:35 INFO - ##### Running clobber step. 18:08:35 INFO - ##### 18:08:35 INFO - Running pre-action listener: _resource_record_pre_action 18:08:35 INFO - Running main action method: clobber 18:08:35 INFO - rmtree: /builds/slave/test/build 18:08:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:08:36 INFO - Running post-action listener: _resource_record_post_action 18:08:36 INFO - ##### 18:08:36 INFO - ##### Finished clobber step (success) 18:08:36 INFO - ##### 18:08:36 INFO - ##### 18:08:36 INFO - ##### Running read-buildbot-config step. 18:08:36 INFO - ##### 18:08:36 INFO - Running pre-action listener: _resource_record_pre_action 18:08:36 INFO - Running main action method: read_buildbot_config 18:08:36 INFO - Using buildbot properties: 18:08:36 INFO - { 18:08:36 INFO - "project": "", 18:08:36 INFO - "product": "firefox", 18:08:36 INFO - "script_repo_revision": "production", 18:08:36 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 18:08:36 INFO - "repository": "", 18:08:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 18:08:36 INFO - "buildid": "20160628171428", 18:08:36 INFO - "pgo_build": "False", 18:08:36 INFO - "basedir": "/builds/slave/test", 18:08:36 INFO - "buildnumber": 4, 18:08:36 INFO - "slavename": "t-yosemite-r7-0155", 18:08:36 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 18:08:36 INFO - "platform": "yosemite_r7", 18:08:36 INFO - "scheduler": "tests-autoland-macosx64-talos", 18:08:36 INFO - "branch": "autoland", 18:08:36 INFO - "repo_path": "integration/autoland", 18:08:36 INFO - "revision": "6eccb6aa19da093a279397d1233fc73dfc72dc4b", 18:08:36 INFO - "stage_platform": "macosx64", 18:08:36 INFO - "builduid": "8c9d98ad66a14461853b99c2d7d772bf", 18:08:36 INFO - "slavebuilddir": "test" 18:08:36 INFO - } 18:08:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 18:08:36 INFO - Running post-action listener: _resource_record_post_action 18:08:36 INFO - ##### 18:08:36 INFO - ##### Finished read-buildbot-config step (success) 18:08:36 INFO - ##### 18:08:36 INFO - ##### 18:08:36 INFO - ##### Running download-and-extract step. 18:08:36 INFO - ##### 18:08:36 INFO - Running pre-action listener: _resource_record_pre_action 18:08:36 INFO - Running main action method: download_and_extract 18:08:36 INFO - mkdir: /builds/slave/test/build/tests 18:08: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')]} 18:08:36 INFO - https://queue.taskcluster.net/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:08:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 18:08:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 18:08:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fbxy5DLhRsietPSnsaGYDw/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 18:08:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fbxy5DLhRsietPSnsaGYDw/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 18:08:38 INFO - Downloaded 1199 bytes. 18:08:38 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 18:08:38 INFO - Using the following test package requirements: 18:08:38 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 18:08:38 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:08:38 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 18:08:38 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:08:38 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 18:08:38 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:08:38 INFO - u'jsshell-mac.zip'], 18:08:38 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:08:38 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 18:08:38 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 18:08:38 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:08:38 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 18:08:38 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:08:38 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 18:08:38 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:08:38 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 18:08:38 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:08:38 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 18:08:38 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:08:38 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')]} 18:08:38 INFO - https://queue.taskcluster.net/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:08:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:08:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:08:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fbxy5DLhRsietPSnsaGYDw/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 18:08:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fbxy5DLhRsietPSnsaGYDw/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 18:08:39 INFO - Downloaded 18786819 bytes. 18:08:39 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'] 18:08:39 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 18:08:42 INFO - Return code: 0 18:08:42 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 18:08:42 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')]} 18:08:42 INFO - https://queue.taskcluster.net/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:08:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:08:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:08:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fbxy5DLhRsietPSnsaGYDw/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 18:08:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fbxy5DLhRsietPSnsaGYDw/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 18:08:43 INFO - Downloaded 18786819 bytes. 18:08:43 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'] 18:08:43 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 18:08:47 INFO - Return code: 0 18:08:47 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')]} 18:08:47 INFO - https://queue.taskcluster.net/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:08:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 18:08:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 18:08:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fbxy5DLhRsietPSnsaGYDw/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 18:08:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fbxy5DLhRsietPSnsaGYDw/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 18:08:48 INFO - Downloaded 14405245 bytes. 18:08:48 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'] 18:08:48 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 18:08:48 INFO - Return code: 0 18:08:48 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')]} 18:08:48 INFO - https://queue.taskcluster.net/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:08:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:08:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:08:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 18:08:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fbxy5DLhRsietPSnsaGYDw/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 18:08:52 INFO - Downloaded 112354061 bytes. 18:08:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:08:52 INFO - mkdir: /builds/slave/test/properties 18:08:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:08:52 INFO - Writing to file /builds/slave/test/properties/build_url 18:08:52 INFO - Contents: 18:08:52 INFO - build_url:https://queue.taskcluster.net/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:08:53 INFO - Running post-action listener: _resource_record_post_action 18:08:53 INFO - Running post-action listener: set_extra_try_arguments 18:08:53 INFO - ##### 18:08:53 INFO - ##### Finished download-and-extract step (success) 18:08:53 INFO - ##### 18:08:53 INFO - ##### 18:08:53 INFO - ##### Running populate-webroot step. 18:08:53 INFO - ##### 18:08:53 INFO - Running pre-action listener: _resource_record_pre_action 18:08:53 INFO - Running main action method: populate_webroot 18:08:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:08:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:08:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:08:53 INFO - '--tppagecycles', 18:08:53 INFO - '7'], 18:08:53 INFO - 'tests': ['tsvgm']}}, 18:08:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:08:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:08:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:08:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:08:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:08:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:08:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:53 INFO - 'talos_options': ['--disable-e10s'], 18:08:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:08:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:08:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:53 INFO - 'talos_options': ['--disable-e10s'], 18:08:53 INFO - 'tests': ['damp', 'tps']}, 18:08:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:53 INFO - 'tests': ['damp', 'tps']}, 18:08:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:08:53 INFO - 'tests': ['dromaeo_dom']}, 18:08:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:08:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:08:53 INFO - 'tests': ['basic_compositor_video']}, 18:08:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:08:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:08:53 INFO - 'tests': ['a11yr', 18:08:53 INFO - 'ts_paint', 18:08:53 INFO - 'tpaint', 18:08:53 INFO - 'sessionrestore', 18:08:53 INFO - 'sessionrestore_no_auto_restore', 18:08:53 INFO - 'tabpaint']}, 18:08:53 INFO - 'other-e10s': {'tests': ['a11yr', 18:08:53 INFO - 'ts_paint', 18:08:53 INFO - 'tpaint', 18:08:53 INFO - 'sessionrestore', 18:08:53 INFO - 'sessionrestore_no_auto_restore', 18:08:53 INFO - 'tabpaint']}, 18:08:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:08:53 INFO - 'ts_paint', 18:08:53 INFO - 'tpaint', 18:08:53 INFO - 'sessionrestore', 18:08:53 INFO - 'sessionrestore_no_auto_restore', 18:08:53 INFO - 'tabpaint']}, 18:08:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:08:53 INFO - 'ts_paint', 18:08:53 INFO - 'tpaint', 18:08:53 INFO - 'sessionrestore', 18:08:53 INFO - 'sessionrestore_no_auto_restore', 18:08:53 INFO - 'tabpaint']}, 18:08:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:08:53 INFO - 'tests': ['a11yr', 18:08:53 INFO - 'ts_paint', 18:08:53 INFO - 'tpaint', 18:08:53 INFO - 'sessionrestore', 18:08:53 INFO - 'sessionrestore_no_auto_restore', 18:08:53 INFO - 'tabpaint']}, 18:08:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:08:53 INFO - 'tests': ['a11yr', 18:08:53 INFO - 'ts_paint', 18:08:53 INFO - 'tpaint', 18:08:53 INFO - 'sessionrestore', 18:08:53 INFO - 'sessionrestore_no_auto_restore', 18:08:53 INFO - 'tabpaint']}, 18:08:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:08:53 INFO - 'tests': ['tsvgx', 18:08:53 INFO - 'tsvgr_opacity', 18:08:53 INFO - 'tart', 18:08:53 INFO - 'tscrollx', 18:08:53 INFO - 'cart']}, 18:08:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:08:53 INFO - 'tsvgr_opacity', 18:08:53 INFO - 'tart', 18:08:53 INFO - 'tscrollx', 18:08:53 INFO - 'cart']}, 18:08:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:53 INFO - 'talos_options': ['--disable-e10s'], 18:08:53 INFO - 'tests': ['tp5o']}, 18:08:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:53 INFO - 'tests': ['tp5o']}, 18:08:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:53 INFO - 'talos_options': ['--disable-e10s', 18:08:53 INFO - '--xperf_path', 18:08:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:08:53 INFO - 'tests': ['tp5n']}, 18:08:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:53 INFO - 'talos_options': ['--xperf_path', 18:08:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:08:53 INFO - 'tests': ['tp5n']}}, 18:08:53 INFO - 'talos.zip': {'path': '', 18:08:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:08:53 INFO - Running post-action listener: _resource_record_post_action 18:08:53 INFO - ##### 18:08:53 INFO - ##### Finished populate-webroot step (success) 18:08:53 INFO - ##### 18:08:53 INFO - ##### 18:08:53 INFO - ##### Running create-virtualenv step. 18:08:53 INFO - ##### 18:08:53 INFO - Running pre-action listener: _resource_record_pre_action 18:08:53 INFO - Running main action method: create_virtualenv 18:08:53 INFO - Creating virtualenv /builds/slave/test/build/venv 18:08:53 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:08:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:08:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:08:54 INFO - Using real prefix '/tools/python27' 18:08:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:08:54 INFO - Installing distribute.............................................................................................................................................................................................done. 18:08:57 INFO - Installing pip.................done. 18:08:57 INFO - Return code: 0 18:08:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:08: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')]} 18:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08: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')]} 18:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:57 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 0x10ccb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd4c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9751c386e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc1bd30>, '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 0x10c980db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc13b20>, '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.YfjfLFTgiB/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.klo1ngLWin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 18:08:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:08:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:08:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YfjfLFTgiB/Render', 18:08:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:57 INFO - 'HOME': '/Users/cltbld', 18:08:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:57 INFO - 'LOGNAME': 'cltbld', 18:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:57 INFO - 'MOZ_NO_REMOTE': '1', 18:08:57 INFO - 'NO_EM_RESTART': '1', 18:08:57 INFO - 'PAGER': '/bin/cat', 18:08:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:57 INFO - 'PWD': '/builds/slave/test', 18:08:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:57 INFO - 'SHELL': '/bin/bash', 18:08:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.klo1ngLWin/Listeners', 18:08:57 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 18:08:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:57 INFO - 'USER': 'cltbld', 18:08:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:57 INFO - 'XPC_FLAGS': '0x0', 18:08:57 INFO - 'XPC_SERVICE_NAME': '0', 18:08:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:08:57 INFO - Cleaning up... 18:08:57 INFO - Return code: 0 18:08:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:08: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')]} 18:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08: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')]} 18:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:57 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 0x10ccb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd4c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9751c386e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc1bd30>, '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 0x10c980db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc13b20>, '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.YfjfLFTgiB/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.klo1ngLWin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 18:08:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:08:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:08:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YfjfLFTgiB/Render', 18:08:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:57 INFO - 'HOME': '/Users/cltbld', 18:08:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:57 INFO - 'LOGNAME': 'cltbld', 18:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:57 INFO - 'MOZ_NO_REMOTE': '1', 18:08:57 INFO - 'NO_EM_RESTART': '1', 18:08:57 INFO - 'PAGER': '/bin/cat', 18:08:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:57 INFO - 'PWD': '/builds/slave/test', 18:08:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:57 INFO - 'SHELL': '/bin/bash', 18:08:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.klo1ngLWin/Listeners', 18:08:57 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 18:08:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:57 INFO - 'USER': 'cltbld', 18:08:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:57 INFO - 'XPC_FLAGS': '0x0', 18:08:57 INFO - 'XPC_SERVICE_NAME': '0', 18:08:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:58 INFO - Downloading/unpacking psutil>=3.1.1 18:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:09:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:09:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:09:01 INFO - Installing collected packages: psutil 18:09:01 INFO - Running setup.py install for psutil 18:09:01 INFO - building 'psutil._psutil_osx' extension 18:09:01 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 18:09:01 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 18:09:01 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 18:09:01 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 18:09:01 INFO - building 'psutil._psutil_posix' extension 18:09:01 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 18:09:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:09:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:09:01 INFO - ^ 18:09:01 INFO - 1 warning generated. 18:09:01 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 18:09:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:09:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:09:01 INFO - Successfully installed psutil 18:09:01 INFO - Cleaning up... 18:09:02 INFO - Return code: 0 18:09:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:09:02 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')]} 18:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:02 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')]} 18:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:02 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 0x10ccb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd4c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9751c386e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc1bd30>, '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 0x10c980db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc13b20>, '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.YfjfLFTgiB/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.klo1ngLWin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 18:09:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:09:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YfjfLFTgiB/Render', 18:09:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:09:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:09:02 INFO - 'HOME': '/Users/cltbld', 18:09:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:09:02 INFO - 'LOGNAME': 'cltbld', 18:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:02 INFO - 'MOZ_NO_REMOTE': '1', 18:09:02 INFO - 'NO_EM_RESTART': '1', 18:09:02 INFO - 'PAGER': '/bin/cat', 18:09:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:02 INFO - 'PWD': '/builds/slave/test', 18:09:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:09:02 INFO - 'SHELL': '/bin/bash', 18:09:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.klo1ngLWin/Listeners', 18:09:02 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 18:09:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:09:02 INFO - 'USER': 'cltbld', 18:09:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:09:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:02 INFO - 'XPC_FLAGS': '0x0', 18:09:02 INFO - 'XPC_SERVICE_NAME': '0', 18:09:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:09:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:09:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:09:04 INFO - Installing collected packages: mozsystemmonitor 18:09:04 INFO - Running setup.py install for mozsystemmonitor 18:09:04 INFO - Successfully installed mozsystemmonitor 18:09:04 INFO - Cleaning up... 18:09:05 INFO - Return code: 0 18:09:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:09:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:05 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 0x10ccb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd4c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9751c386e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc1bd30>, '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 0x10c980db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc13b20>, '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.YfjfLFTgiB/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.klo1ngLWin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 18:09:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:09:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:09:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YfjfLFTgiB/Render', 18:09:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:09:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:09:05 INFO - 'HOME': '/Users/cltbld', 18:09:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:09:05 INFO - 'LOGNAME': 'cltbld', 18:09:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:05 INFO - 'MOZ_NO_REMOTE': '1', 18:09:05 INFO - 'NO_EM_RESTART': '1', 18:09:05 INFO - 'PAGER': '/bin/cat', 18:09:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:09:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:05 INFO - 'PWD': '/builds/slave/test', 18:09:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:09:05 INFO - 'SHELL': '/bin/bash', 18:09:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.klo1ngLWin/Listeners', 18:09:05 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 18:09:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:09:05 INFO - 'USER': 'cltbld', 18:09:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:09:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:09:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:05 INFO - 'XPC_FLAGS': '0x0', 18:09:05 INFO - 'XPC_SERVICE_NAME': '0', 18:09:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:09:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:05 INFO - Downloading/unpacking jsonschema==2.5.1 18:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:09:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:09:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:09:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:08 INFO - Downloading functools32-3.2.3-2.tar.gz 18:09:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:09:08 INFO - warning: no files found matching '*.txt' 18:09:08 INFO - no previously-included directories found matching 'build' 18:09:08 INFO - no previously-included directories found matching 'dist' 18:09:08 INFO - no previously-included directories found matching '.git*' 18:09:08 INFO - Installing collected packages: jsonschema, functools32 18:09:08 INFO - Running setup.py install for functools32 18:09:08 INFO - warning: no files found matching '*.txt' 18:09:08 INFO - no previously-included directories found matching 'build' 18:09:08 INFO - no previously-included directories found matching 'dist' 18:09:08 INFO - no previously-included directories found matching '.git*' 18:09:08 INFO - Successfully installed jsonschema functools32 18:09:08 INFO - Cleaning up... 18:09:08 INFO - Return code: 0 18:09:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:09:08 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')]} 18:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:08 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')]} 18:09:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:08 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 0x10ccb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd4c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9751c386e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc1bd30>, '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 0x10c980db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc13b20>, '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.YfjfLFTgiB/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.klo1ngLWin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 18:09:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:09:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:09:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YfjfLFTgiB/Render', 18:09:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:09:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:09:08 INFO - 'HOME': '/Users/cltbld', 18:09:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:09:08 INFO - 'LOGNAME': 'cltbld', 18:09:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:08 INFO - 'MOZ_NO_REMOTE': '1', 18:09:08 INFO - 'NO_EM_RESTART': '1', 18:09:08 INFO - 'PAGER': '/bin/cat', 18:09:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:09:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:08 INFO - 'PWD': '/builds/slave/test', 18:09:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:09:08 INFO - 'SHELL': '/bin/bash', 18:09:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.klo1ngLWin/Listeners', 18:09:08 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 18:09:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:09:08 INFO - 'USER': 'cltbld', 18:09:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:09:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:09:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:08 INFO - 'XPC_FLAGS': '0x0', 18:09:08 INFO - 'XPC_SERVICE_NAME': '0', 18:09:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:09:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:08 INFO - Downloading/unpacking blobuploader==1.2.4 18:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:09:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:11 INFO - Downloading blobuploader-1.2.4.tar.gz 18:09:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:09:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:09:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:09:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:09:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:12 INFO - Downloading docopt-0.6.1.tar.gz 18:09:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:09:12 INFO - Installing collected packages: blobuploader, requests, docopt 18:09:12 INFO - Running setup.py install for blobuploader 18:09:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:09:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:09:12 INFO - Running setup.py install for requests 18:09:12 INFO - Running setup.py install for docopt 18:09:13 INFO - Successfully installed blobuploader requests docopt 18:09:13 INFO - Cleaning up... 18:09:13 INFO - Return code: 0 18:09:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:09:13 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')]} 18:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:13 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')]} 18:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:13 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 0x10ccb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd4c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9751c386e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc1bd30>, '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 0x10c980db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc13b20>, '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.YfjfLFTgiB/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.klo1ngLWin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 18:09:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:09:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:09:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YfjfLFTgiB/Render', 18:09:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:09:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:09:13 INFO - 'HOME': '/Users/cltbld', 18:09:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:09:13 INFO - 'LOGNAME': 'cltbld', 18:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:13 INFO - 'MOZ_NO_REMOTE': '1', 18:09:13 INFO - 'NO_EM_RESTART': '1', 18:09:13 INFO - 'PAGER': '/bin/cat', 18:09:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:13 INFO - 'PWD': '/builds/slave/test', 18:09:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:09:13 INFO - 'SHELL': '/bin/bash', 18:09:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.klo1ngLWin/Listeners', 18:09:13 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 18:09:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:09:13 INFO - 'USER': 'cltbld', 18:09:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:09:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:13 INFO - 'XPC_FLAGS': '0x0', 18:09:13 INFO - 'XPC_SERVICE_NAME': '0', 18:09:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:09:13 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-hx_bte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:09:13 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-A_GKSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:09:13 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-pBKUIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:09:13 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-FzNLmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:09:13 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-lRHw2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:09:14 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-f8qO0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:09:14 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-OwkcLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:09:14 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-HQQ6C4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:09:14 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-kMKc5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:09:14 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-xmsViZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:09:14 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-vttiR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:09:14 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-Njw400-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:09:14 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-pWPghv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:09:14 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-qX4P4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:09:14 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-upegx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:09:15 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-e8yO_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:09:15 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-DxHqOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:09:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:09:15 INFO - Running setup.py install for manifestparser 18:09:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:09:15 INFO - Running setup.py install for mozcrash 18:09:15 INFO - Running setup.py install for mozdebug 18:09:15 INFO - Running setup.py install for mozdevice 18:09:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:09:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:09:15 INFO - Running setup.py install for mozfile 18:09:16 INFO - Running setup.py install for mozhttpd 18:09:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:09:16 INFO - Running setup.py install for mozinfo 18:09:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:09:16 INFO - Running setup.py install for mozInstall 18:09:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:09:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:09:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:09:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:09:16 INFO - Running setup.py install for mozleak 18:09:16 INFO - Running setup.py install for mozlog 18:09:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:09:16 INFO - Running setup.py install for moznetwork 18:09:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:09:16 INFO - Running setup.py install for mozprocess 18:09:17 INFO - Running setup.py install for mozprofile 18:09:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:09:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:09:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:09:17 INFO - Running setup.py install for mozrunner 18:09:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:09:17 INFO - Running setup.py install for mozscreenshot 18:09:17 INFO - Running setup.py install for moztest 18:09:17 INFO - Running setup.py install for mozversion 18:09:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:09:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:09:17 INFO - Cleaning up... 18:09:17 INFO - Return code: 0 18:09:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:09: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')]} 18:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09: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')]} 18:09:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:17 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 0x10ccb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd4c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9751c386e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc1bd30>, '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 0x10c980db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc13b20>, '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.YfjfLFTgiB/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.klo1ngLWin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 18:09:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:09:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YfjfLFTgiB/Render', 18:09:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:09:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:09:17 INFO - 'HOME': '/Users/cltbld', 18:09:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:09:17 INFO - 'LOGNAME': 'cltbld', 18:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:17 INFO - 'MOZ_NO_REMOTE': '1', 18:09:17 INFO - 'NO_EM_RESTART': '1', 18:09:17 INFO - 'PAGER': '/bin/cat', 18:09:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:17 INFO - 'PWD': '/builds/slave/test', 18:09:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:09:17 INFO - 'SHELL': '/bin/bash', 18:09:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.klo1ngLWin/Listeners', 18:09:17 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 18:09:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:09:17 INFO - 'USER': 'cltbld', 18:09:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:09:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:17 INFO - 'XPC_FLAGS': '0x0', 18:09:17 INFO - 'XPC_SERVICE_NAME': '0', 18:09:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:09:18 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-kF59TG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:09:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:09:18 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-S11qmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:09:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:09:18 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-OytRZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:09:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:09:18 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-Z9CtBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:09:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:09:18 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-2QyOQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:09:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:09:18 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-NoeBLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:09:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:09:18 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-ItjRc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:09:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:09:18 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-80Xtb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:09:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:09:18 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-VrnVN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:09:19 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-oERqmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:09:19 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)) 18:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:09:19 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-5go6zT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:09:19 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-1okXQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:09:19 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)) 18:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:09:19 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-MnaVZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:09:19 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-bn1D2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:09:19 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)) 18:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:09:19 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-UK2_yu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:09:19 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-Uapg_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:09:19 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-m2siJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:09:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:09:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:22 INFO - Downloading blessings-1.6.tar.gz 18:09:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:09:22 INFO - Installing collected packages: blessings 18:09:22 INFO - Running setup.py install for blessings 18:09:22 INFO - Successfully installed blessings 18:09:22 INFO - Cleaning up... 18:09:22 INFO - Return code: 0 18:09:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:09:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:09:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:09:22 INFO - Reading from file tmpfile_stdout 18:09:22 INFO - Current package versions: 18:09:22 INFO - blessings == 1.6 18:09:22 INFO - blobuploader == 1.2.4 18:09:22 INFO - docopt == 0.6.1 18:09:22 INFO - functools32 == 3.2.3-2 18:09:22 INFO - jsonschema == 2.5.1 18:09:22 INFO - manifestparser == 1.1 18:09:22 INFO - mozInstall == 1.12 18:09:22 INFO - mozcrash == 0.17 18:09:22 INFO - mozdebug == 0.1 18:09:22 INFO - mozdevice == 0.48 18:09:22 INFO - mozfile == 1.2 18:09:22 INFO - mozhttpd == 0.7 18:09:22 INFO - mozinfo == 0.9 18:09:22 INFO - mozleak == 0.1 18:09:22 INFO - mozlog == 3.2 18:09:22 INFO - moznetwork == 0.27 18:09:22 INFO - mozprocess == 0.22 18:09:22 INFO - mozprofile == 0.28 18:09:22 INFO - mozrunner == 6.11 18:09:22 INFO - mozscreenshot == 0.1 18:09:22 INFO - mozsystemmonitor == 0.3 18:09:22 INFO - moztest == 0.7 18:09:22 INFO - mozversion == 1.4 18:09:22 INFO - psutil == 3.1.1 18:09:22 INFO - requests == 1.2.3 18:09:22 INFO - wsgiref == 0.1.2 18:09:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:09:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:09:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:22 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 0x10ccb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd4c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9751c386e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc1bd30>, '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 0x10c980db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc13b20>, '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.YfjfLFTgiB/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.klo1ngLWin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 18:09:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:09:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:09:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YfjfLFTgiB/Render', 18:09:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:09:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:09:22 INFO - 'HOME': '/Users/cltbld', 18:09:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:09:22 INFO - 'LOGNAME': 'cltbld', 18:09:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:22 INFO - 'MOZ_NO_REMOTE': '1', 18:09:22 INFO - 'NO_EM_RESTART': '1', 18:09:22 INFO - 'PAGER': '/bin/cat', 18:09:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:09:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:22 INFO - 'PWD': '/builds/slave/test', 18:09:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:09:22 INFO - 'SHELL': '/bin/bash', 18:09:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.klo1ngLWin/Listeners', 18:09:22 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 18:09:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:09:22 INFO - 'USER': 'cltbld', 18:09:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:09:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:09:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:22 INFO - 'XPC_FLAGS': '0x0', 18:09:22 INFO - 'XPC_SERVICE_NAME': '0', 18:09:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:09:23 INFO - Cleaning up... 18:09:23 INFO - Return code: 0 18:09:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ccb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd4c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9751c386e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc1bd30>, '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 0x10c980db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc13b20>, '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.YfjfLFTgiB/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.klo1ngLWin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 18:09:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:09:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YfjfLFTgiB/Render', 18:09:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:09:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:09:23 INFO - 'HOME': '/Users/cltbld', 18:09:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:09:23 INFO - 'LOGNAME': 'cltbld', 18:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:23 INFO - 'MOZ_NO_REMOTE': '1', 18:09:23 INFO - 'NO_EM_RESTART': '1', 18:09:23 INFO - 'PAGER': '/bin/cat', 18:09:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:23 INFO - 'PWD': '/builds/slave/test', 18:09:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:09:23 INFO - 'SHELL': '/bin/bash', 18:09:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.klo1ngLWin/Listeners', 18:09:23 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 18:09:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:09:23 INFO - 'USER': 'cltbld', 18:09:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:09:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:23 INFO - 'XPC_FLAGS': '0x0', 18:09:23 INFO - 'XPC_SERVICE_NAME': '0', 18:09:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:09:23 INFO - Cleaning up... 18:09:23 INFO - Return code: 0 18:09:23 INFO - Running post-action listener: _resource_record_post_action 18:09:23 INFO - Running post-action listener: _start_resource_monitoring 18:09:23 INFO - Starting resource monitoring. 18:09:23 INFO - ##### 18:09:23 INFO - ##### Finished create-virtualenv step (success) 18:09:23 INFO - ##### 18:09:23 INFO - ##### 18:09:23 INFO - ##### Running install step. 18:09:23 INFO - ##### 18:09:23 INFO - Running pre-action listener: _resource_record_pre_action 18:09:23 INFO - Running main action method: install 18:09:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:09:23 INFO - Reading from file tmpfile_stdout 18:09:23 INFO - Detecting whether we're running mozinstall >=1.0... 18:09:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:09:23 INFO - Reading from file tmpfile_stdout 18:09:23 INFO - Output received: 18:09:23 INFO - Usage: mozinstall [options] installer 18:09:23 INFO - Options: 18:09:23 INFO - -h, --help show this help message and exit 18:09:23 INFO - -d DEST, --destination=DEST 18:09:23 INFO - Directory to install application into. [default: 18:09:23 INFO - "/builds/slave/test"] 18:09:23 INFO - --app=APP Application being installed. [default: firefox] 18:09:23 INFO - mkdir: /builds/slave/test/build/application 18:09:23 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'] 18:09:23 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 18:09:58 INFO - Reading from file tmpfile_stdout 18:09:58 INFO - Output received: 18:09:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:09:58 INFO - Running post-action listener: _resource_record_post_action 18:09:58 INFO - ##### 18:09:58 INFO - ##### Finished install step (success) 18:09:58 INFO - ##### 18:09:58 INFO - ##### 18:09:58 INFO - ##### Running run-tests step. 18:09:58 INFO - ##### 18:09:58 INFO - Running pre-action listener: _resource_record_pre_action 18:09:58 INFO - Running main action method: run_tests 18:09:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:09:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:09:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:09:58 INFO - 2016-06-28 18:09:58.070 screenresolution[1914:16794] starting screenresolution argv=screenresolution get 18:09:58 INFO - 2016-06-28 18:09:58.072 screenresolution[1914:16794] Display 0: 1600x1200x32@60 18:09:58 INFO - 2016-06-28 18:09:58.077 screenresolution[1915:16797] starting screenresolution argv=screenresolution list 18:09:58 INFO - Available Modes on Display 0 18:09:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:09:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:09:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:09:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:09:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:09:58 INFO - Intel Iris: 18:09:58 INFO - Chipset Model: Intel Iris 18:09:58 INFO - Type: GPU 18:09:58 INFO - Bus: Built-In 18:09:58 INFO - VRAM (Dynamic, Max): 1536 MB 18:09:58 INFO - Vendor: Intel (0x8086) 18:09:58 INFO - Device ID: 0x0a2e 18:09:58 INFO - Revision ID: 0x0009 18:09:58 INFO - Displays: 18:09:58 INFO - Display: 18:09:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:09:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:09:58 INFO - Display Serial Number: 1600x1200 60 18:09:58 INFO - Main Display: Yes 18:09:58 INFO - Mirror: Off 18:09:58 INFO - Online: Yes 18:09:58 INFO - Rotation: Supported 18:09:58 INFO - Return code: 0 18:09:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:09:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:09:58 INFO - Python 2.7.3 18:09:58 INFO - Return code: 0 18:09:58 INFO - grabbing minidump binary from tooltool 18:09:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:09:58 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 0x10cc1bd30>, '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 0x10c980db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc13b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:09:58 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 18:09:58 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 18:09:58 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 18:09:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:09:58 INFO - Return code: 0 18:09:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:09:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:09:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:09:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:09:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:09:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0155', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:09:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0155 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:09:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YfjfLFTgiB/Render', 18:09:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:09:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:09:58 INFO - 'HOME': '/Users/cltbld', 18:09:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:09:58 INFO - 'LOGNAME': 'cltbld', 18:09:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:09:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:09:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:58 INFO - 'MOZ_NO_REMOTE': '1', 18:09:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:09:58 INFO - 'NO_EM_RESTART': '1', 18:09:58 INFO - 'PAGER': '/bin/cat', 18:09:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:09:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:58 INFO - 'PWD': '/builds/slave/test', 18:09:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:09:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:09:58 INFO - 'SHELL': '/bin/bash', 18:09:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.klo1ngLWin/Listeners', 18:09:58 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 18:09:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:09:58 INFO - 'USER': 'cltbld', 18:09:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:09:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:09:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:58 INFO - 'XPC_FLAGS': '0x0', 18:09:58 INFO - 'XPC_SERVICE_NAME': '0', 18:09:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:09:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0155', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:09:58 INFO - mozversion application_buildid: 20160628171428 18:09:58 INFO - mozversion application_changeset: 6eccb6aa19da093a279397d1233fc73dfc72dc4b 18:09:58 INFO - mozversion application_display_name: Nightly 18:09:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:09:58 INFO - mozversion application_name: Firefox 18:09:58 INFO - mozversion application_remotingname: firefox 18:09:58 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 18:09:58 INFO - mozversion application_vendor: Mozilla 18:09:58 INFO - mozversion application_version: 50.0a1 18:09:58 INFO - mozversion platform_buildid: 20160628171428 18:09:58 INFO - mozversion platform_changeset: 6eccb6aa19da093a279397d1233fc73dfc72dc4b 18:09:58 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 18:09:58 INFO - mozversion platform_version: 50.0a1 18:09:58 INFO - using testdate: 1467162598 18:09:58 INFO - actual date: 1467162598 18:09:58 INFO - starting webserver on 'localhost:54503' 18:09:58 INFO - SUITE-START | Running 2 tests 18:09:58 INFO - TEST-START | dromaeo_css 18:09:58 INFO - operating with platform_type : mac_ 18:09:58 INFO - Initialising browser for dromaeo_css test... 18:09:58 INFO - TEST-INFO | started process 1925 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/tmps_tRD4/profile http://localhost:54503/getInfo.html) 18:10:00 INFO - PROCESS | 1925 | __metrics Screen width/height:1600/1200 18:10:00 INFO - PROCESS | 1925 | colorDepth:24 18:10:00 INFO - PROCESS | 1925 | Browser inner width/height: 1024/689 18:10:00 INFO - PROCESS | 1925 | __metrics 18:10:01 INFO - PROCESS | 1925 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:10:01 INFO - PROCESS | 1925 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:10:01 INFO - TEST-INFO | 1925: exit 0 18:10:01 INFO - Browser initialized. 18:10:01 INFO - Running cycle 1/1 for dromaeo_css test... 18:10:01 INFO - TEST-INFO | started process 1927 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/tmps_tRD4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:10:13 INFO - PROCESS | 1927 | RSS: Main: 243220480 18:10:13 INFO - PROCESS | 1927 | 18:11:05 INFO - PROCESS | 1927 | 1467162665177 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:13:15 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:54503/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54503/tests/dromaeo/cssquery-ext.html) 18:13:16 INFO - PROCESS | 1927 | RSS: Main: 354631680 18:13:16 INFO - PROCESS | 1927 | 18:16:19 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:54503/tests/dromaeo/cssquery-ext.html (next: http://localhost:54503/tests/dromaeo/cssquery-jquery.html) 18:16:20 INFO - PROCESS | 1927 | RSS: Main: 374591488 18:16:20 INFO - PROCESS | 1927 | 18:19:22 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:54503/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54503/tests/dromaeo/cssquery-mootools.html) 18:19:23 INFO - PROCESS | 1927 | RSS: Main: 373264384 18:19:23 INFO - PROCESS | 1927 | 18:22:25 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:54503/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54503/tests/dromaeo/cssquery-prototype.html) 18:22:26 INFO - PROCESS | 1927 | RSS: Main: 375566336 18:22:26 INFO - PROCESS | 1927 | 18:25:29 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:54503/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54503/tests/dromaeo/cssquery-yui.html) 18:25:30 INFO - PROCESS | 1927 | RSS: Main: 345485312 18:25:30 INFO - PROCESS | 1927 | 18:28:34 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:54503/tests/dromaeo/cssquery-yui.html (next: http://localhost:54503/tests/dromaeo/cssquery-dojo.html) 18:28:34 INFO - PROCESS | 1927 | RSS: Main: 386953216 18:28:34 INFO - PROCESS | 1927 | 18:28:34 INFO - PROCESS | 1927 | __start_tp_report 18:28:34 INFO - PROCESS | 1927 | _x_x_mozilla_page_load 18:28:34 INFO - PROCESS | 1927 | _x_x_mozilla_page_load_details 18:28:34 INFO - PROCESS | 1927 | |i|pagename|runs| 18:28:34 INFO - PROCESS | 1927 | |0;dojo.html;3211.32562161946;3356.6643335666436;3384.695377416032;3392.0841372829336;3422.486627005949;5342.626016178869;5639.097744360903;5740.540756739467;5748.338941021784;5781.017227071397;5735.799247026344;5742.994975879225;5823.097419899912;5850.268716410449;5878.970605146979;5164.431912489642;5166.302549155872;5237.162054071349;5270.499302566269;5276.050310944029;5248.71132087735;5298.019866324723;5299.364076310829;5310.15037593985;5334.866628334284;5173.870653233662;5179.870503237412;5219.973900130513;5241.449647786965;5255.211718242256;5199.272101905736;5205.947940520584;5213.113770658959;5216.217567364888;5261.5790736741355;5196.090684130299;5200.895982080375;5236.450172731885;5250.606204534675;5255.710935898524;5622.943770562283;5694.5159661428825;5809.302883653946;5828.912566311508;5863.618864773776;6250.656213908233;6272.466840318579;6332.1135041094285;6333.714982825738;6338.714757835908;6228.470580000705;6269.341719119518;6288.182536270301;6293.905591416133;6309.274433440167;5444.972775136099;5543.307086614173;5613.073842815971;5619.943800561983;5625.156226566007;6291.653959032297;6301.0863424804065;6302.590331628429;6312.210973628296;6324.462420694293;6243.469305109116;6252.999520076766;6254.124422580842;6267.592606480564;6268.905966410507;6158.199434073631;6188.81433556994;6302.338254483305;6309.684515774193;6315.052742088632;6249;6330.0821380899515;6330.525210609223;6334.3665633436285;6340.144080549159;6269.780557680459;6324.778632747923;6334.5249106317215;6339.397757213057;6350.7777227796805;4853.635977301717;4907.092187945242;4913.508649135058;4919.114559379346;4945.9752701236985;4240.703150779477;4446.911061778746;4466.196084704784;4482.484514280834;4494.775261236925;5850.444207800252;5876.7355469004;5882.617629854046;5886.323072846678;5936.821895343147;5199.55803756685;6241.7191226394925;6251.3123556408755;6292.622442653455;6294.276158241861;5820.35976042635;5827.621204621686;5850.239468869105;5860.120981852671;5901.17383566301;5435.809746658848;5447.53695935842;5467.644603100785;5470.343558772893;5480;5347.652402593819;6227.719752611143;6278.905816412757;6283;6341.746330146835;6243.43809057187;6286.4342209201395;6322.810315690536;6331.113644089924;6336.239651241696;6336.271328797063;6366.904496432465;6368.363163683594;6368.904466432915;6379.489640828815;6216.720247588779;6242.875142497034;6247.562670613013;6262.5929314594405;6275.6862156892885;6162.044883043135;6249.031400132987;6265.686715664289;6314.55798094129;6376.139221205078;6202.627842329474;6226.719797609027;6236.158118653923;6243.687815609111;6256.5307601930945;6046.183765191643;6149.139120523219;6178.691065446799;6208.751649933952;6213.471854892385;4986.626003049858;5933.732982015733;6024.909626355696;6078.209832721718;6087.604305720114;6085.361037091042;6141.635470344039;6155.604133545346;6176.567640265138;6195.101710252078;5870.970645146747;5904.173415721888;5951.137085122719;6050.45545900871;6064.969675151596 18:28:34 INFO - PROCESS | 1927 | |1;ext.html;490.2743938970324;495.33130274129906;512.9846104616862;514.2697369734976;564.6301672404575;30907;43218.13563728723;44474;44601.77699111508;46211;44470;44753.88115297111;44935.325970110476;44957.10085798282;45025.54974450255;9632.951835240832;9822.70531884041;9824.214062874953;9829.410235385863;9838.36050656705;9756.804863902718;9880.654177103803;9881.703548893545;9897;9900.25748068894;9840.95079524605;9851.605935762562;9890.752731181707;9902.653407130752;9915.702528924108;9801.460919649417;9894.257930655158;9903.801923961553;9910.90089099107;9913.256505762025;9773.755656108584;9845.163161131313;9846.310758246926;9849.852252216222;9866.605335786631;42758.358624620654;43010.56989430097;43144.56855431437;43155;43192.78403607962;3559.501669766235;3629.6188900165366;3741.5136032315895;3751.531058617673;3979.4826672532677;32644.347113057844;33310;33321.83339083289;33672.15819604505;34426;3324.750643701732;3332.216929021702;3338.1821453743746;3341.899743007714;3394.321135772855;523233;524389;525015;525517.3724131393;526971;64668.35331646623;65856.68286634337;65939.67030164914;65982;66018.67962640816;35608.64391356105;36575;36628.63371366252;37784.811075944446;37802.43296350557;17203.225854836313;17359.264073592793;17363.91318043427;17373.305067797148;17409.129543523028;13087.476500940045;13201.60395188146;13206.73586528264;13214.603561893158;13223.2727200003;16860.578485537713;20586.382408527767;20742.27402040921;20764;20849.58300833975;8189.836203276021;8230.547319897463;8234;8303.252707256375;8371.832563348698;891.6210610490516;910.6311943662827;1023.6059117239917;1109.9778004440027;1151.6660168551193;31322.68677313243;31512;31588.842055789573;31618.525722114187;32436.351272974407;938.3853575907791;1043.7651528406047;1127.808272593661;1147.7532330548972;1209.8245754365569;13993.16041037541;16037;16372.836271637369;16421.70096186535;16907.492775216764;682834;684279;691217.0878291253;695663;696161;63188;63961.081167564116;64082.35917640857;64111.67944160249;64203.35796642067;1289.2586762611536;1324.7019420630475;1411.9788203177166;1433.340663294896;1449.9927500362642;30633.23416914595;32389.676103238668;32461.837690811393;33113.50329745008;33189;59790.70104649449;62301;62349.37650623436;65329;65561;572664;668156;668848;669620;670106;37381.439278410304;37421;42789.14421711486;46024.309635354824;46697.766511167225;41498;50713;51172;51833.7408312956;52310.73844630752;39793.00517487086;41898.37152442655;42121.36817947795;42392.37496393366;42437.5756242446;33465.83267083649;39692.40461393024;40777;40859.59140408558;41149.382759259235;32202.35595288128;32562.185945351557;34471.31057378888;39803;39844;33218;35869.46195807117;36722.8163859179;36748.81625591855;36874.44688329731;25869.353266168495;26347.736522635296;26491.7350826497;26562.86718566395;26781.86609066942 18:28:34 INFO - PROCESS | 1927 | |2;jquery.html;379.8366702318003;386.45509831138094;387.4788409589103;401.6144501278772;404.7268094036526;18203.81796182038;19396;21896;21942.670859937072;22139.114435422583;20981.950902454893;21480.140794368246;21569.67645485319;21896.8905155474;21920.890395548;5724.169995350682;6153.384661533833;6203.441690247877;6207.937920620804;6222.315545290015;4200.138971510833;4440.666949978749;4446.777661116931;4461.7322960622305;6059.091136329542;4415.315626077963;6615.801525954205;6627.138471998657;6637.933620663804;6644.43522300605;6501.772437964649;6622.8675426491445;6628.502862285324;6648.368405001541;6822.658867056677;3767;5865.387018569885;5885.852258809534;5899.557533184985;5900.763969441217;20035.899820500803;23873;25678;26123.477530449476;27104.457910841673;2013.9597208055757;2149.9462513437134;2349.8237632177656;2959.5708622249867;3238.3361410910707;10162.441065741381;10204.132648724868;10242.385456872611;10548.156147508182;10593.417362045084;1796.3353559183056;1872.7467117531542;1883.5102873252915;1910.9904450477798;1920.6926891697262;95134;96142.51928740382;99625.90356956526;118916.99999999914;125932;9442.622295108256;10004.599816007423;10008;10145.847812282822;10183.18534517237;12326.50693972231;12350.62948111558;12436.37818109091;12467.376631168405;12467.940225080969;8068.8386232275025;8228.44018987186;8279;8344.207300306583;8373.985365268498;8783.824323513621;8786.956065219632;8822.426542274732;8952.910470895338;8955.820883582423;3829.5787463379006;3897.9805100974313;3900.4539364488996;3955.4462375267485;3959.762414255154;1670.640812225353;1910.360029390142;1942.2522328903474;1948.2207117153253;1949.4346639474393;870.912908709124;896.9103089690979;992.1120597065731;998.4654323694186;1005.331454582709;19597.706034409497;21266.36200913975;22455;22478;22524;930.9906900931039;940.6237504997919;944.8015916657441;946.4289279795506;1015.7968406318765;11212.374205740285;11668.70828229301;11714.472848721827;11730.70673233176;11757.706057348634;546738.2663086739;567913.4812977809;603458;624007;626298;130215;132386;133535;133543;133608;983.1741337276721;984.060222487537;998.3111652959435;1001.7746007148334;1004.3873237325225;19161.712574311674;19515.60968780645;19548.60902781908;19549.60900781948;19662.115204816102;53061.46938530565;53123.81257124585;53978.46021539735;54112;58153.83692326045;78993.8150927748;83040.16959830324;83334.5833270854;83376.58311708402;85421.57289213514;8214.548199849065;8260.793480163044;8267.710630127916;8297.502149871027;8495.447795893248;12397.661984323435;13345.617006944223;13467.461301547828;13533.120347177402;13545.187288762705;11048.337099774039;11185.32888026721;11295;11355.716107097389;11370.147238956946;10019.448930308612;10519.474026298501;11410.258333208314;11537.596184133516;11688.18182727235;10632.670916135483;10976.451177440937;11691.473883675086;11818.586349477728;11935.582254621047;7052.753153639598;7449.888251676338;7510.436717246338;7682.8079298018;8484.703035393732;10175.7456063599;10774.946125269324;11526.711832204262;11932.880671193525;11936.462859171184 18:28:34 INFO - PROCESS | 1927 | |3;mootools.html;1389.4303335632392;1557.6651020030695;1567.4513920127954;1804.3414153833855;1952.9707054394187;7699.615019249036;8486.6181021854;8709.651613935435;8732.912670873282;8805.119488051192;8418.873716894237;8636.956815215915;8641.740747777576;8642.17899299567;8648.740537783877;2067.751869775629;2139.5827813576357;2141.9143234270687;2296.0815673730517;2335.6730057791924;2165.9891700541475;2194.5720584485953;2297.7587353327913;2382.0114652419225;2385.439421735891;2355.1521452277166;2358.749862572839;2360.9055637774554;2361.1853910400882;2368.7039120109985;2288.8054515366216;2340.134150364359;2345.401922509749;2347.1550241912983;2350.811935045192;2092.843036772248;2305.6772051912744;2375.6080246759266;2376.0614557249964;2387.9880600597057;8480.279176270025;8634.525101119436;8706;8735.650573977033;8735.825283494294;4580.152671755703;5236.659617124875;5237.869053273661;5238.05714971305;5241.213817927303;3397.8810741623925;3530.346885826105;3602.3875941089814;3604.567451905762;3612.060864175307;1035.7100011996654;1057.2704833664748;1075.6557901471533;1088.5101704233127;1088.9455527223686;11423;11477.483513241925;11481.885181148165;11491.908268714542;11497.712557186054;4559.361689363492;4653.790579423934;4751.786169622375;4752.382190315306;4757.738324392122;9756.219502439788;9760.511974401252;9768.609255629695;9778.462184579916;9790.167835734042;5297.947020529746;5310.495502927216;5311.990721762852;5316.627836051517;5324.520793128637;4353.25994580922;4689.320048592935;4733.5503127202865;4867.440244371872;4874.878128046827;3552.4138517144897;3716.0338312039066;3758.8496460141287;3841.9231615368094;3852.556955950102;1138.2203190814319;1150.1373969522858;1154.0709728668503;1158.692946369213;1161.4947497838298;902.0843843498853;913.5523593439167;920.3787443475627;933.3060495201323;950.1591091883732;5722.37053924068;5918.585699001029;5920.31916329619;6014.759409623566;6126.744017476407;931.0968360690119;956.3305686019814;998.3161534348994;1006.3257617396362;1039.8076355874186;3663.5054267674054;3832.2718683450057;3840.462335273064;4037.192561487714;4115.52671442782;10285.125764310118;10512.57949682004;10686.0916822071;10698.251122421356;10710.46447677613;10634.574617015387;10653.84019239712;10654.307470014424;10665.626703065356;10670.839937400946;7633.961830190813;7997.600119993977;8017;8067.394945379003;8088.797780055545;5925.140854576149;6114.755409783559;6169.907451388143;6177.320494745664;6288.1825362704385;9632.614695412105;9941.105300522988;10085.29402941787;10085.344452610558;10085.69742907714;9998.20014398861;10120.778460347501;10243.180545556188;10265.024822641837;10349.379037257788;6784.423324017514;7498.175200728025;7616.162222155669;7648.464607477423;7684.769456916301;6862.656867156522;8398.790030249293;8423.873641895254;8494.83010339802;8532;7387.482876198615;7483.962580187064;7527.209642987407;7647.88528172089;7700.730474433368;7349.228331025379;7418.146913104846;7428.368588670023;7469.7385591504035;7484.909855263466;6941.687624057031;7021.543599666005;7023;7035.5426897253565;7058.54119482232;4865.148598995091;4980.5019498049905;5023.2214006829;5040.395152581714;5100.928804951001;4867.245576935581;4971.130052240916;4971.65198436106;4971.900561988812;5082.440931497458 18:28:34 INFO - PROCESS | 1927 | |4;prototype.html;304.1453515621105;310.79642833943774;314.81111333200073;317.0931136948329;319.5893277138877;5371.75827087781;5386.38056623488;5407.351117865852;5417.729113544322;5421.403645598981;4402.471703395589;5327.7336133193285;5357.544608708265;5384.219288203209;5389.353277606683;3231.4506533889235;3368.8989330320005;3464.566929133858;4037.1723796621623;4090.8545607229876;3778.244351129771;4000.0999775050527;4001.5798341174195;4064.715469917107;4087.509498860141;3375.8312084395807;4047.7571345719352;4049.5950404959417;4052.5136983562015;4054.6011625988986;3449.810260435675;3824.5984171661853;3874.341361968458;4038.182817732003;4078.3066878630702;2812.142296599536;3497.7551571390013;3504.894853154384;3522.3230344937774;3541.4864844597646;4804.327394164819;4873.902521949577;5032.2703208035;5232.555232805216;5240.554552863011;3768.3593789055653;3780.4329350597354;4390.824367025315;4441.800118994653;4685.812567497296;5113.642045056848;5432.728363581845;5452.727363631802;5478.397376288605;5488.725563721838;3401.9489707654407;3441.659671423431;3535.840887160084;3589.515415418937;3608.1340478285024;5083.602009447394;5777.508911742549;5861.794837180678;5981.773736384031;5987.281526216838;4000.3199456092534;4014.3577027676015;4014.498187726534;4023.203873308023;4023.3562629979647;3803.6386543278345;4007.899802504961;4050.2911990401562;4070.75575465473;4087.7138600297694;3858.479105320802;4370.431843860278;4658.767061646904;4688.671792974527;4699.459562150328;4615.376924115201;4967.975160124176;5196.648871293415;5392.649477783932;5593.902286642509;1198.4726720243066;1273.9426725797362;1300.3173333999725;1378.4348417148922;1381.854905234956;758.9943325094272;777.5373652676649;843.0978852627752;872.289084396215;890.4835195586544;4521.502634710179;5143.151380022329;5246.816361427332;5304.628675992721;5305.495977882095;5160.574529970908;5190.714510701948;5550.944490555124;5816.836632673482;5829;4011.3782363733667;4528.5245049270015;5016.974915125401;5189.896202075937;5323.467653234646;2774.5988275920936;2922.3570814420796;2999.9850000750293;3057.2509735114595;3058.1284333964713;5383.596230282745;5978.940210597838;5988.431099045671;6051.213342265477;6053.939460605426;5628.439060227586;5690.9146362804595;5939.554533409925;5940.61386009908;6030.698465076816;5318.813841515605;5931.745936544172;5960.344362120163;5960.986632272524;6026.548008899263;5140.97429512865;5708.743106560131;5791.9131213031;5809.24479817621;5847.912281315868;5170.767315470888;5753.562883075453;5767.826965191051;5803.448672376287;5846;5513.393526712139;5620.707237335354;5673.234113394639;5917.497012753965;6064;2884.0388535795023;3178.98410507946;3277.7213936815638;3313.6023677158896;3367.915802104967;4279.486461624625;4301.956980430155;4685.742284174403;4771.212749896228;4956.900861982812;4365.912681746284;4415.114699064802;4444.31113177458;4719.457262414729;4795.280707893844;4085.1216988347646;4150.398192262165;4428.468583769968;4520;4599.896024954054;3862.0496649191978;4143.606357396043;4534.569215924482;4633.976830115827;5113.667611605234;4579.702319349231;5504.146857237135;5507.642003269774;5523.950449414599;5589.8261365113785;5034.194528875362;5204.53898157736;5450.345958484849;5503.284573005483;5577.888442231214 18:28:34 INFO - PROCESS | 1927 | |5;yui.html;726.9091363579554;762.4662736084742;787.3622365883634;840.1220724343061;845.4208866926155;17387.913060434697;18046.458606241802;18070.909645451753;18105;18152.54618634533;16314.347426102942;16430.260638271277;16515.91742041288;16519.752203716955;16564.420245291418;713.9678714457835;910.9979023074632;918.4535201555065;919.3013309884469;922.5156792683828;731.4441024821148;781.8240895798428;915.2174890468654;919.6689191890914;921.2261700171854;949.952502374882;960.2893858544696;961.4808003678005;964.0744884910495;966.3911735606594;739.7299985505252;915.7069737683944;958.2190514730507;958.2525630008604;966.9798362727355;709.3615745828744;709.5175280809048;710.3464812372578;712.1134187936053;712.3624356201171;15865.444709435229;15867.841321586751;15883.444079457278;15892.205389730467;15906.045637261801;11986.220895641756;13902.582922512342;14374.353154107986;14383.640408989755;14400.351984160738;13367.997400195025;13627.931860340734;13692.65768355789;13924;13946.930265348608;4499.145162419162;4664.580187783082;4718.787654555554;4725.243960966263;4788.904221915577;124871;126756.73243267633;127025;127193.72806271819;127199.72800272064;10837.729056773644;11113.277636953571;11343.546258149581;11411.48648310828;11613.129015323884;21947.341579752632;22089.453738238168;22156.33530994073;22202.66695999561;22214.55570888573;7635;7726.381889448943;7951.920480795234;8147.67409303633;8158.510489370657;4586.151561961048;4594.425696787902;4694.7417892016265;4780.211265141213;4789.30555069513;3535.611082780892;3644.635536446387;3653.872114476034;3655.9817200913826;3658.286634106377;662.8707402056552;667.7996601019674;672.2638710611873;684.3567046975827;685.2975699907535;475.59099174709723;516.5428595692839;538.569144684251;543.5407081016534;553.914143307788;9002.954985225031;9074.237858959608;9219.216366608913;9265.166135047879;9371.437713737198;484.367899890639;560.8345538066199;590.3971312246747;591.955603329752;606.5450911816138;4331.151819422449;4422.115576884636;4424.424824772759;4492.477642296645;4528.388667529907;89119.21761564733;94531.52734236422;95393.5230323844;96235.51882240544;126580;111884;135505.322473387;138327;138723.45106195842;140300;99066;118406.4079679596;118505;121523.78476215301;124609;7733.574653394117;9495.097965693249;9715.70852874415;9915.851262230928;9916.851247231152;55999.720001401365;62031.689841550506;63288.05067923893;64008.35991640024;65138.67430662816;89388.5530572343;90680.27959161236;91097.54451227702;92229.53885230531;93358.06641933494;13359.799603006157;13405.597832065054;13423.932880335537;13432.19406835593;13646.181229126285;14860.925695371454;14951.476698315506;15024.17367044823;15412.075275483507;15537.06777593348;11570.88429115698;14063.859361406256;14252.28738563047;14302.856971430569;14512.709745804814;13917;14102;14190.432382704576;14288.214148221947;14620.634484137981;13714.51999180024;13847.515336963157;14058.578242652327;14073.366698498798;14318;9879.407235565888;11351.659450216506;11473.598424055119;11691.569418693463;11724.941375293069;11426;11522.769544608893;11685.824712629148;11708.414579271173;11717 18:28:34 INFO - PROCESS | 1927 | __end_tp_report 18:28:34 INFO - PROCESS | 1927 | __start_cc_report 18:28:34 INFO - PROCESS | 1927 | _x_x_mozilla_cycle_collect,1172 18:28:34 INFO - PROCESS | 1927 | __end_cc_report 18:28:34 INFO - PROCESS | 1927 | __startTimestamp1467163714378__endTimestamp 18:28:34 INFO - PROCESS | 1927 | 18:28:34 INFO - PROCESS | 1927 | ------- Summary: start ------- 18:28:34 INFO - PROCESS | 1927 | Number of tests: 6 18:28:34 INFO - PROCESS | 1927 | 18:28:34 INFO - PROCESS | 1927 | [#0] dojo.html Cycles:165 Average:5775.06 Median:6024.91 stddev:661.56 (11.0%) stddev-sans-first:632.28 18:28:34 INFO - PROCESS | 1927 | Values: 3211.3 3356.7 3384.7 3392.1 3422.5 5342.6 5639.1 5740.5 5748.3 5781.0 5735.8 5743.0 5823.1 5850.3 5879.0 5164.4 5166.3 5237.2 5270.5 5276.1 5248.7 5298.0 5299.4 5310.2 5334.9 5173.9 5179.9 5220.0 5241.4 5255.2 5199.3 5205.9 5213.1 5216.2 5261.6 5196.1 5200.9 5236.5 5250.6 5255.7 5622.9 5694.5 5809.3 5828.9 5863.6 6250.7 6272.5 6332.1 6333.7 6338.7 6228.5 6269.3 6288.2 6293.9 6309.3 5445.0 5543.3 5613.1 5619.9 5625.2 6291.7 6301.1 6302.6 6312.2 6324.5 6243.5 6253.0 6254.1 6267.6 6268.9 6158.2 6188.8 6302.3 6309.7 6315.1 6249.0 6330.1 6330.5 6334.4 6340.1 6269.8 6324.8 6334.5 6339.4 6350.8 4853.6 4907.1 4913.5 4919.1 4946.0 4240.7 4446.9 4466.2 4482.5 4494.8 5850.4 5876.7 5882.6 5886.3 5936.8 5199.6 6241.7 6251.3 6292.6 6294.3 5820.4 5827.6 5850.2 5860.1 5901.2 5435.8 5447.5 5467.6 5470.3 5480.0 5347.7 6227.7 6278.9 6283.0 6341.7 6243.4 6286.4 6322.8 6331.1 6336.2 6336.3 6366.9 6368.4 6368.9 6379.5 6216.7 6242.9 6247.6 6262.6 6275.7 6162.0 6249.0 6265.7 6314.6 6376.1 6202.6 6226.7 6236.2 6243.7 6256.5 6046.2 6149.1 6178.7 6208.8 6213.5 4986.6 5933.7 6024.9 6078.2 6087.6 6085.4 6141.6 6155.6 6176.6 6195.1 5871.0 5904.2 5951.1 6050.5 6065.0 18:28:34 INFO - PROCESS | 1927 | 18:28:34 INFO - PROCESS | 1927 | [#1] ext.html Cycles:180 Average:75760.45 Median:42597.97 stddev:167476.30 (393.2%) stddev-sans-first:167850.76 18:28:34 INFO - PROCESS | 1927 | Values: 490.3 495.3 513.0 514.3 564.6 30907.0 43218.1 44474.0 44601.8 46211.0 44470.0 44753.9 44935.3 44957.1 45025.5 9633.0 9822.7 9824.2 9829.4 9838.4 9756.8 9880.7 9881.7 9897.0 9900.3 9841.0 9851.6 9890.8 9902.7 9915.7 9801.5 9894.3 9903.8 9910.9 9913.3 9773.8 9845.2 9846.3 9849.9 9866.6 42758.4 43010.6 43144.6 43155.0 43192.8 3559.5 3629.6 3741.5 3751.5 3979.5 32644.3 33310.0 33321.8 33672.2 34426.0 3324.8 3332.2 3338.2 3341.9 3394.3 523233.0 524389.0 525015.0 525517.4 526971.0 64668.4 65856.7 65939.7 65982.0 66018.7 35608.6 36575.0 36628.6 37784.8 37802.4 17203.2 17359.3 17363.9 17373.3 17409.1 13087.5 13201.6 13206.7 13214.6 13223.3 16860.6 20586.4 20742.3 20764.0 20849.6 8189.8 8230.5 8234.0 8303.3 8371.8 891.6 910.6 1023.6 1110.0 1151.7 31322.7 31512.0 31588.8 31618.5 32436.4 938.4 1043.8 1127.8 1147.8 1209.8 13993.2 16037.0 16372.8 16421.7 16907.5 682834.0 684279.0 691217.1 695663.0 696161.0 63188.0 63961.1 64082.4 64111.7 64203.4 1289.3 1324.7 1412.0 1433.3 1450.0 30633.2 32389.7 32461.8 33113.5 33189.0 59790.7 62301.0 62349.4 65329.0 65561.0 572664.0 668156.0 668848.0 669620.0 670106.0 37381.4 37421.0 42789.1 46024.3 46697.8 41498.0 50713.0 51172.0 51833.7 52310.7 39793.0 41898.4 42121.4 42392.4 42437.6 33465.8 39692.4 40777.0 40859.6 41149.4 32202.4 32562.2 34471.3 39803.0 39844.0 33218.0 35869.5 36722.8 36748.8 36874.4 25869.4 26347.7 26491.7 26562.9 26781.9 18:28:34 INFO - PROCESS | 1927 | 18:28:34 INFO - PROCESS | 1927 | [#2] jquery.html Cycles:180 Average:34918.27 Median:25900.74 stddev:99250.18 (383.2%) stddev-sans-first:99494.72 18:28:34 INFO - PROCESS | 1927 | Values: 379.8 386.5 387.5 401.6 404.7 18203.8 19396.0 21896.0 21942.7 22139.1 20982.0 21480.1 21569.7 21896.9 21920.9 5724.2 6153.4 6203.4 6207.9 6222.3 4200.1 4440.7 4446.8 4461.7 6059.1 4415.3 6615.8 6627.1 6637.9 6644.4 6501.8 6622.9 6628.5 6648.4 6822.7 3767.0 5865.4 5885.9 5899.6 5900.8 20035.9 23873.0 25678.0 26123.5 27104.5 2014.0 2149.9 2349.8 2959.6 3238.3 10162.4 10204.1 10242.4 10548.2 10593.4 1796.3 1872.7 1883.5 1911.0 1920.7 95134.0 96142.5 99625.9 118917.0 125932.0 9442.6 10004.6 10008.0 10145.8 10183.2 12326.5 12350.6 12436.4 12467.4 12467.9 8068.8 8228.4 8279.0 8344.2 8374.0 8783.8 8787.0 8822.4 8952.9 8955.8 3829.6 3898.0 3900.5 3955.4 3959.8 1670.6 1910.4 1942.3 1948.2 1949.4 870.9 896.9 992.1 998.5 1005.3 19597.7 21266.4 22455.0 22478.0 22524.0 931.0 940.6 944.8 946.4 1015.8 11212.4 11668.7 11714.5 11730.7 11757.7 546738.3 567913.5 603458.0 624007.0 626298.0 130215.0 132386.0 133535.0 133543.0 133608.0 983.2 984.1 998.3 1001.8 1004.4 19161.7 19515.6 19548.6 19549.6 19662.1 53061.5 53123.8 53978.5 54112.0 58153.8 78993.8 83040.2 83334.6 83376.6 85421.6 8214.5 8260.8 8267.7 8297.5 8495.4 12397.7 13345.6 13467.5 13533.1 13545.2 11048.3 11185.3 11295.0 11355.7 11370.1 10019.4 10519.5 11410.3 11537.6 11688.2 10632.7 10976.5 11691.5 11818.6 11935.6 7052.8 7449.9 7510.4 7682.8 8484.7 10175.7 10774.9 11526.7 11932.9 11936.5 18:28:34 INFO - PROCESS | 1927 | 18:28:34 INFO - PROCESS | 1927 | [#3] mootools.html Cycles:180 Average:5673.89 Median:4871.16 stddev:3179.25 (65.3%) stddev-sans-first:3171.86 18:28:34 INFO - PROCESS | 1927 | Values: 1389.4 1557.7 1567.5 1804.3 1953.0 7699.6 8486.6 8709.7 8732.9 8805.1 8418.9 8637.0 8641.7 8642.2 8648.7 2067.8 2139.6 2141.9 2296.1 2335.7 2166.0 2194.6 2297.8 2382.0 2385.4 2355.2 2358.7 2360.9 2361.2 2368.7 2288.8 2340.1 2345.4 2347.2 2350.8 2092.8 2305.7 2375.6 2376.1 2388.0 8480.3 8634.5 8706.0 8735.7 8735.8 4580.2 5236.7 5237.9 5238.1 5241.2 3397.9 3530.3 3602.4 3604.6 3612.1 1035.7 1057.3 1075.7 1088.5 1088.9 11423.0 11477.5 11481.9 11491.9 11497.7 4559.4 4653.8 4751.8 4752.4 4757.7 9756.2 9760.5 9768.6 9778.5 9790.2 5297.9 5310.5 5312.0 5316.6 5324.5 4353.3 4689.3 4733.6 4867.4 4874.9 3552.4 3716.0 3758.8 3841.9 3852.6 1138.2 1150.1 1154.1 1158.7 1161.5 902.1 913.6 920.4 933.3 950.2 5722.4 5918.6 5920.3 6014.8 6126.7 931.1 956.3 998.3 1006.3 1039.8 3663.5 3832.3 3840.5 4037.2 4115.5 10285.1 10512.6 10686.1 10698.3 10710.5 10634.6 10653.8 10654.3 10665.6 10670.8 7634.0 7997.6 8017.0 8067.4 8088.8 5925.1 6114.8 6169.9 6177.3 6288.2 9632.6 9941.1 10085.3 10085.3 10085.7 9998.2 10120.8 10243.2 10265.0 10349.4 6784.4 7498.2 7616.2 7648.5 7684.8 6862.7 8398.8 8423.9 8494.8 8532.0 7387.5 7484.0 7527.2 7647.9 7700.7 7349.2 7418.1 7428.4 7469.7 7484.9 6941.7 7021.5 7023.0 7035.5 7058.5 4865.1 4980.5 5023.2 5040.4 5100.9 4867.2 4971.1 4971.7 4971.9 5082.4 18:28:34 INFO - PROCESS | 1927 | 18:28:34 INFO - PROCESS | 1927 | [#4] prototype.html Cycles:180 Average:4395.29 Median:4709.46 stddev:1386.76 (29.4%) stddev-sans-first:1356.23 18:28:34 INFO - PROCESS | 1927 | Values: 304.1 310.8 314.8 317.1 319.6 5371.8 5386.4 5407.4 5417.7 5421.4 4402.5 5327.7 5357.5 5384.2 5389.4 3231.5 3368.9 3464.6 4037.2 4090.9 3778.2 4000.1 4001.6 4064.7 4087.5 3375.8 4047.8 4049.6 4052.5 4054.6 3449.8 3824.6 3874.3 4038.2 4078.3 2812.1 3497.8 3504.9 3522.3 3541.5 4804.3 4873.9 5032.3 5232.6 5240.6 3768.4 3780.4 4390.8 4441.8 4685.8 5113.6 5432.7 5452.7 5478.4 5488.7 3401.9 3441.7 3535.8 3589.5 3608.1 5083.6 5777.5 5861.8 5981.8 5987.3 4000.3 4014.4 4014.5 4023.2 4023.4 3803.6 4007.9 4050.3 4070.8 4087.7 3858.5 4370.4 4658.8 4688.7 4699.5 4615.4 4968.0 5196.6 5392.6 5593.9 1198.5 1273.9 1300.3 1378.4 1381.9 759.0 777.5 843.1 872.3 890.5 4521.5 5143.2 5246.8 5304.6 5305.5 5160.6 5190.7 5550.9 5816.8 5829.0 4011.4 4528.5 5017.0 5189.9 5323.5 2774.6 2922.4 3000.0 3057.3 3058.1 5383.6 5978.9 5988.4 6051.2 6053.9 5628.4 5690.9 5939.6 5940.6 6030.7 5318.8 5931.7 5960.3 5961.0 6026.5 5141.0 5708.7 5791.9 5809.2 5847.9 5170.8 5753.6 5767.8 5803.4 5846.0 5513.4 5620.7 5673.2 5917.5 6064.0 2884.0 3179.0 3277.7 3313.6 3367.9 4279.5 4302.0 4685.7 4771.2 4956.9 4365.9 4415.1 4444.3 4719.5 4795.3 4085.1 4150.4 4428.5 4520.0 4599.9 3862.0 4143.6 4534.6 4634.0 5113.7 4579.7 5504.1 5507.6 5524.0 5589.8 5034.2 5204.5 5450.3 5503.3 5577.9 18:28:34 INFO - PROCESS | 1927 | 18:28:34 INFO - PROCESS | 1927 | [#5] yui.html Cycles:180 Average:24588.71 Median:4423.27 stddev:37955.15 (858.1%) stddev-sans-first:38019.34 18:28:34 INFO - PROCESS | 1927 | Values: 726.9 762.5 787.4 840.1 845.4 17387.9 18046.5 18070.9 18105.0 18152.5 16314.3 16430.3 16515.9 16519.8 16564.4 714.0 911.0 918.5 919.3 922.5 731.4 781.8 915.2 919.7 921.2 950.0 960.3 961.5 964.1 966.4 739.7 915.7 958.2 958.3 967.0 709.4 709.5 710.3 712.1 712.4 15865.4 15867.8 15883.4 15892.2 15906.0 11986.2 13902.6 14374.4 14383.6 14400.4 13368.0 13627.9 13692.7 13924.0 13946.9 4499.1 4664.6 4718.8 4725.2 4788.9 124871.0 126756.7 127025.0 127193.7 127199.7 10837.7 11113.3 11343.5 11411.5 11613.1 21947.3 22089.5 22156.3 22202.7 22214.6 7635.0 7726.4 7951.9 8147.7 8158.5 4586.2 4594.4 4694.7 4780.2 4789.3 3535.6 3644.6 3653.9 3656.0 3658.3 662.9 667.8 672.3 684.4 685.3 475.6 516.5 538.6 543.5 553.9 9003.0 9074.2 9219.2 9265.2 9371.4 484.4 560.8 590.4 592.0 606.5 4331.2 4422.1 4424.4 4492.5 4528.4 89119.2 94531.5 95393.5 96235.5 126580.0 111884.0 135505.3 138327.0 138723.5 140300.0 99066.0 118406.4 118505.0 121523.8 124609.0 7733.6 9495.1 9715.7 9915.9 9916.9 55999.7 62031.7 63288.1 64008.4 65138.7 89388.6 90680.3 91097.5 92229.5 93358.1 13359.8 13405.6 13423.9 13432.2 13646.2 14860.9 14951.5 15024.2 15412.1 15537.1 11570.9 14063.9 14252.3 14302.9 14512.7 13917.0 14102.0 14190.4 14288.2 14620.6 13714.5 13847.5 14058.6 14073.4 14318.0 9879.4 11351.7 11473.6 11691.6 11724.9 11426.0 11522.8 11685.8 11708.4 11717.0 18:28:34 INFO - PROCESS | 1927 | -------- Summary: end -------- 18:28:34 INFO - PROCESS | 1927 | 18:28:35 INFO - TEST-INFO | 1927: exit 0 18:28:35 INFO - TEST-OK | dromaeo_css | took 1116375ms 18:28:35 INFO - TEST-START | kraken 18:28:35 INFO - operating with platform_type : mac_ 18:28:35 INFO - Initialising browser for kraken test... 18:28:35 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/tmpHm7XAa/profile http://localhost:54503/getInfo.html) 18:28:37 INFO - PROCESS | 1930 | __metrics Screen width/height:1600/1200 18:28:37 INFO - PROCESS | 1930 | colorDepth:24 18:28:37 INFO - PROCESS | 1930 | Browser inner width/height: 1024/689 18:28:37 INFO - PROCESS | 1930 | __metrics 18:28:37 INFO - PROCESS | 1930 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:28:37 INFO - PROCESS | 1930 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:28:38 INFO - TEST-INFO | 1930: exit 0 18:28:38 INFO - Browser initialized. 18:28:38 INFO - Running cycle 1/1 for kraken test... 18:28:38 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/tmpHm7XAa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:28:50 INFO - PROCESS | 1931 | RSS: Main: 243437568 18:28:50 INFO - PROCESS | 1931 | 18:28:52 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54503/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54503/tests/kraken/driver.html?testName=audio-beat-detection) 18:28:53 INFO - PROCESS | 1931 | RSS: Main: 294412288 18:28:53 INFO - PROCESS | 1931 | 18:28:56 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54503/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54503/tests/kraken/driver.html?testName=audio-dft) 18:28:57 INFO - PROCESS | 1931 | RSS: Main: 304365568 18:28:57 INFO - PROCESS | 1931 | 18:29:00 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54503/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54503/tests/kraken/driver.html?testName=audio-fft) 18:29:01 INFO - PROCESS | 1931 | RSS: Main: 387010560 18:29:01 INFO - PROCESS | 1931 | 18:29:03 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54503/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54503/tests/kraken/driver.html?testName=audio-oscillator) 18:29:03 INFO - PROCESS | 1931 | RSS: Main: 287887360 18:29:03 INFO - PROCESS | 1931 | 18:29:05 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54503/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54503/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:29:06 INFO - PROCESS | 1931 | RSS: Main: 291258368 18:29:06 INFO - PROCESS | 1931 | 18:29:09 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54503/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54503/tests/kraken/driver.html?testName=imaging-darkroom) 18:29:09 INFO - PROCESS | 1931 | RSS: Main: 329236480 18:29:09 INFO - PROCESS | 1931 | 18:29:12 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54503/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54503/tests/kraken/driver.html?testName=imaging-desaturate) 18:29:13 INFO - PROCESS | 1931 | RSS: Main: 330866688 18:29:13 INFO - PROCESS | 1931 | 18:29:16 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54503/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54503/tests/kraken/driver.html?testName=json-parse-financial) 18:29:16 INFO - PROCESS | 1931 | RSS: Main: 330547200 18:29:16 INFO - PROCESS | 1931 | 18:29:18 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54503/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54503/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:29:18 INFO - PROCESS | 1931 | RSS: Main: 311209984 18:29:18 INFO - PROCESS | 1931 | 18:29:21 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54503/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54503/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:29:21 INFO - PROCESS | 1931 | RSS: Main: 318148608 18:29:21 INFO - PROCESS | 1931 | 18:29:23 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54503/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54503/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:29:24 INFO - PROCESS | 1931 | RSS: Main: 321712128 18:29:24 INFO - PROCESS | 1931 | 18:29:27 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54503/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54503/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:29:28 INFO - PROCESS | 1931 | RSS: Main: 324493312 18:29:28 INFO - PROCESS | 1931 | 18:29:30 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54503/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54503/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:29:31 INFO - PROCESS | 1931 | RSS: Main: 324997120 18:29:31 INFO - PROCESS | 1931 | 18:29:33 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54503/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54503/tests/kraken/driver.html?testName=ai-astar) 18:29:33 INFO - PROCESS | 1931 | RSS: Main: 368918528 18:29:33 INFO - PROCESS | 1931 | 18:29:33 INFO - PROCESS | 1931 | __start_tp_report 18:29:33 INFO - PROCESS | 1931 | _x_x_mozilla_page_load 18:29:33 INFO - PROCESS | 1931 | _x_x_mozilla_page_load_details 18:29:33 INFO - PROCESS | 1931 | |i|pagename|runs| 18:29:33 INFO - PROCESS | 1931 | |0;ai-astar;100;112;112;112;112;113;115;157;165;90 18:29:33 INFO - PROCESS | 1931 | |1;audio-beat-detection;158;119;118;171;121;141;142;141;156;119 18:29:33 INFO - PROCESS | 1931 | |2;audio-dft;184;185;183;184;186;185;183;186;180;180 18:29:33 INFO - PROCESS | 1931 | |3;audio-fft;79;76;58;107;56;57;61;65;96;56 18:29:33 INFO - PROCESS | 1931 | |4;audio-oscillator;86;96;95;72;71;69;96;71;68;103 18:29:33 INFO - PROCESS | 1931 | |5;imaging-gaussian-blur;86;84;87;84;86;83;89;88;88;84 18:29:33 INFO - PROCESS | 1931 | |6;imaging-darkroom;93;117;151;93;94;93;93;93;92;93 18:29:33 INFO - PROCESS | 1931 | |7;imaging-desaturate;84;84;84;85;85;84;85;84;84;84 18:29:33 INFO - PROCESS | 1931 | |8;json-parse-financial;56;54;56;54;54;56;53;53;54;54 18:29:33 INFO - PROCESS | 1931 | |9;json-stringify-tinderbox;66;72;80;76;101;104;106;71;69;71 18:29:33 INFO - PROCESS | 1931 | |10;stanford-crypto-aes;85;84;84;86;81;84;86;83;99;85 18:29:33 INFO - PROCESS | 1931 | |11;stanford-crypto-ccm;136;141;142;138;136;141;141;138;146;141 18:29:33 INFO - PROCESS | 1931 | |12;stanford-crypto-pbkdf2;166;167;166;167;165;166;167;180;156;198 18:29:33 INFO - PROCESS | 1931 | |13;stanford-crypto-sha256-iterative;66;67;66;66;66;66;68;63;57;84 18:29:33 INFO - PROCESS | 1931 | __end_tp_report 18:29:33 INFO - PROCESS | 1931 | __start_cc_report 18:29:33 INFO - PROCESS | 1931 | _x_x_mozilla_cycle_collect,2114 18:29:33 INFO - PROCESS | 1931 | __end_cc_report 18:29:33 INFO - PROCESS | 1931 | __startTimestamp1467163773585__endTimestamp 18:29:33 INFO - PROCESS | 1931 | 18:29:33 INFO - PROCESS | 1931 | ------- Summary: start ------- 18:29:33 INFO - PROCESS | 1931 | Number of tests: 14 18:29:33 INFO - PROCESS | 1931 | 18:29:33 INFO - PROCESS | 1931 | [#0] ai-astar Cycles:10 Average:118.80 Median:114.00 stddev:23.59 (20.7%) stddev-sans-first:24.02 18:29:33 INFO - PROCESS | 1931 | Values: 100.0 112.0 112.0 112.0 112.0 113.0 115.0 157.0 165.0 90.0 18:29:33 INFO - PROCESS | 1931 | 18:29:33 INFO - PROCESS | 1931 | [#1] audio-beat-detection Cycles:10 Average:138.60 Median:141.50 stddev:19.02 (13.4%) stddev-sans-first:18.83 18:29:33 INFO - PROCESS | 1931 | Values: 158.0 119.0 118.0 171.0 121.0 141.0 142.0 141.0 156.0 119.0 18:29:33 INFO - PROCESS | 1931 | 18:29:33 INFO - PROCESS | 1931 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.50 stddev:2.17 (1.2%) stddev-sans-first:2.30 18:29:33 INFO - PROCESS | 1931 | Values: 184.0 185.0 183.0 184.0 186.0 185.0 183.0 186.0 180.0 180.0 18:29:33 INFO - PROCESS | 1931 | 18:29:33 INFO - PROCESS | 1931 | [#3] audio-fft Cycles:10 Average:71.10 Median:63.00 stddev:18.14 (28.8%) stddev-sans-first:19.01 18:29:33 INFO - PROCESS | 1931 | Values: 79.0 76.0 58.0 107.0 56.0 57.0 61.0 65.0 96.0 56.0 18:29:33 INFO - PROCESS | 1931 | 18:29:33 INFO - PROCESS | 1931 | [#4] audio-oscillator Cycles:10 Average:82.70 Median:79.00 stddev:13.82 (17.5%) stddev-sans-first:14.61 18:29:33 INFO - PROCESS | 1931 | Values: 86.0 96.0 95.0 72.0 71.0 69.0 96.0 71.0 68.0 103.0 18:29:33 INFO - PROCESS | 1931 | 18:29:33 INFO - PROCESS | 1931 | [#5] imaging-gaussian-blur Cycles:10 Average:85.90 Median:86.50 stddev:2.08 (2.4%) stddev-sans-first:2.20 18:29:33 INFO - PROCESS | 1931 | Values: 86.0 84.0 87.0 84.0 86.0 83.0 89.0 88.0 88.0 84.0 18:29:33 INFO - PROCESS | 1931 | 18:29:33 INFO - PROCESS | 1931 | [#6] imaging-darkroom Cycles:10 Average:101.20 Median:93.00 stddev:19.06 (20.5%) stddev-sans-first:19.98 18:29:33 INFO - PROCESS | 1931 | Values: 93.0 117.0 151.0 93.0 94.0 93.0 93.0 93.0 92.0 93.0 18:29:33 INFO - PROCESS | 1931 | 18:29:33 INFO - PROCESS | 1931 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:84.00 stddev:0.48 (0.6%) stddev-sans-first:0.50 18:29:33 INFO - PROCESS | 1931 | Values: 84.0 84.0 84.0 85.0 85.0 84.0 85.0 84.0 84.0 84.0 18:29:33 INFO - PROCESS | 1931 | 18:29:33 INFO - PROCESS | 1931 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.00 stddev:1.17 (2.2%) stddev-sans-first:1.09 18:29:33 INFO - PROCESS | 1931 | Values: 56.0 54.0 56.0 54.0 54.0 56.0 53.0 53.0 54.0 54.0 18:29:33 INFO - PROCESS | 1931 | 18:29:33 INFO - PROCESS | 1931 | [#9] json-stringify-tinderbox Cycles:10 Average:81.60 Median:71.00 stddev:15.73 (22.2%) stddev-sans-first:15.64 18:29:33 INFO - PROCESS | 1931 | Values: 66.0 72.0 80.0 76.0 101.0 104.0 106.0 71.0 69.0 71.0 18:29:33 INFO - PROCESS | 1931 | 18:29:33 INFO - PROCESS | 1931 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:85.00 stddev:4.90 (5.8%) stddev-sans-first:5.19 18:29:33 INFO - PROCESS | 1931 | Values: 85.0 84.0 84.0 86.0 81.0 84.0 86.0 83.0 99.0 85.0 18:29:33 INFO - PROCESS | 1931 | 18:29:33 INFO - PROCESS | 1931 | [#11] stanford-crypto-ccm Cycles:10 Average:140.00 Median:141.00 stddev:3.06 (2.2%) stddev-sans-first:2.88 18:29:33 INFO - PROCESS | 1931 | Values: 136.0 141.0 142.0 138.0 136.0 141.0 141.0 138.0 146.0 141.0 18:29:33 INFO - PROCESS | 1931 | 18:29:33 INFO - PROCESS | 1931 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.80 Median:167.00 stddev:11.45 (6.9%) stddev-sans-first:12.06 18:29:33 INFO - PROCESS | 1931 | Values: 166.0 167.0 166.0 167.0 165.0 166.0 167.0 180.0 156.0 198.0 18:29:33 INFO - PROCESS | 1931 | 18:29:33 INFO - PROCESS | 1931 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:66.00 stddev:6.76 (10.2%) stddev-sans-first:7.16 18:29:33 INFO - PROCESS | 1931 | Values: 66.0 67.0 66.0 66.0 66.0 66.0 68.0 63.0 57.0 84.0 18:29:33 INFO - PROCESS | 1931 | -------- Summary: end -------- 18:29:33 INFO - PROCESS | 1931 | 18:29:34 INFO - TEST-INFO | 1931: exit 0 18:29:34 INFO - TEST-OK | kraken | took 59470ms 18:29:34 INFO - SUITE-END | took 1175s 18:29:34 INFO - Completed test suite (00:19:36) 18:29:34 INFO - javascript benchmark 18:29:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3211.32562161946, 3356.6643335666436, 3384.695377416032, 3392.0841372829336, 3422.486627005949, 5342.626016178869, 5639.097744360903, 5740.540756739467, 5748.338941021784, 5781.017227071397, 5735.799247026344, 5742.994975879225, 5823.097419899912, 5850.268716410449, 5878.970605146979, 5164.431912489642, 5166.302549155872, 5237.162054071349, 5270.499302566269, 5276.050310944029, 5248.71132087735, 5298.019866324723, 5299.364076310829, 5310.15037593985, 5334.866628334284, 5173.870653233662, 5179.870503237412, 5219.973900130513, 5241.449647786965, 5255.211718242256, 5199.272101905736, 5205.947940520584, 5213.113770658959, 5216.217567364888, 5261.5790736741355, 5196.090684130299, 5200.895982080375, 5236.450172731885, 5250.606204534675, 5255.710935898524, 5622.943770562283, 5694.5159661428825, 5809.302883653946, 5828.912566311508, 5863.618864773776, 6250.656213908233, 6272.466840318579, 6332.1135041094285, 6333.714982825738, 6338.714757835908, 6228.470580000705, 6269.341719119518, 6288.182536270301, 6293.905591416133, 6309.274433440167, 5444.972775136099, 5543.307086614173, 5613.073842815971, 5619.943800561983, 5625.156226566007, 6291.653959032297, 6301.0863424804065, 6302.590331628429, 6312.210973628296, 6324.462420694293, 6243.469305109116, 6252.999520076766, 6254.124422580842, 6267.592606480564, 6268.905966410507, 6158.199434073631, 6188.81433556994, 6302.338254483305, 6309.684515774193, 6315.052742088632, 6249.0, 6330.0821380899515, 6330.525210609223, 6334.3665633436285, 6340.144080549159, 6269.780557680459, 6324.778632747923, 6334.5249106317215, 6339.397757213057, 6350.7777227796805, 4853.635977301717, 4907.092187945242, 4913.508649135058, 4919.114559379346, 4945.9752701236985, 4240.703150779477, 4446.911061778746, 4466.196084704784, 4482.484514280834, 4494.775261236925, 5850.444207800252, 5876.7355469004, 5882.617629854046, 5886.323072846678, 5936.821895343147, 5199.55803756685, 6241.7191226394925, 6251.3123556408755, 6292.622442653455, 6294.276158241861, 5820.35976042635, 5827.621204621686, 5850.239468869105, 5860.120981852671, 5901.17383566301, 5435.809746658848, 5447.53695935842, 5467.644603100785, 5470.343558772893, 5480.0, 5347.652402593819, 6227.719752611143, 6278.905816412757, 6283.0, 6341.746330146835, 6243.43809057187, 6286.4342209201395, 6322.810315690536, 6331.113644089924, 6336.239651241696, 6336.271328797063, 6366.904496432465, 6368.363163683594, 6368.904466432915, 6379.489640828815, 6216.720247588779, 6242.875142497034, 6247.562670613013, 6262.5929314594405, 6275.6862156892885, 6162.044883043135, 6249.031400132987, 6265.686715664289, 6314.55798094129, 6376.139221205078, 6202.627842329474, 6226.719797609027, 6236.158118653923, 6243.687815609111, 6256.5307601930945, 6046.183765191643, 6149.139120523219, 6178.691065446799, 6208.751649933952, 6213.471854892385, 4986.626003049858, 5933.732982015733, 6024.909626355696, 6078.209832721718, 6087.604305720114, 6085.361037091042, 6141.635470344039, 6155.604133545346, 6176.567640265138, 6195.101710252078, 5870.970645146747, 5904.173415721888, 5951.137085122719, 6050.45545900871, 6064.969675151596], "value": 5731.897877650827, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.2743938970324, 495.33130274129906, 512.9846104616862, 514.2697369734976, 564.6301672404575, 30907.0, 43218.13563728723, 44474.0, 44601.77699111508, 46211.0, 44470.0, 44753.88115297111, 44935.325970110476, 44957.10085798282, 45025.54974450255, 9632.951835240832, 9822.70531884041, 9824.214062874953, 9829.410235385863, 9838.36050656705, 9756.804863902718, 9880.654177103803, 9881.703548893545, 9897.0, 9900.25748068894, 9840.95079524605, 9851.605935762562, 9890.752731181707, 9902.653407130752, 9915.702528924108, 9801.460919649417, 9894.257930655158, 9903.801923961553, 9910.90089099107, 9913.256505762025, 9773.755656108584, 9845.163161131313, 9846.310758246926, 9849.852252216222, 9866.605335786631, 42758.358624620654, 43010.56989430097, 43144.56855431437, 43155.0, 43192.78403607962, 3559.501669766235, 3629.6188900165366, 3741.5136032315895, 3751.531058617673, 3979.4826672532677, 32644.347113057844, 33310.0, 33321.83339083289, 33672.15819604505, 34426.0, 3324.750643701732, 3332.216929021702, 3338.1821453743746, 3341.899743007714, 3394.321135772855, 523233.0, 524389.0, 525015.0, 525517.3724131393, 526971.0, 64668.35331646623, 65856.68286634337, 65939.67030164914, 65982.0, 66018.67962640816, 35608.64391356105, 36575.0, 36628.63371366252, 37784.811075944446, 37802.43296350557, 17203.225854836313, 17359.264073592793, 17363.91318043427, 17373.305067797148, 17409.129543523028, 13087.476500940045, 13201.60395188146, 13206.73586528264, 13214.603561893158, 13223.2727200003, 16860.578485537713, 20586.382408527767, 20742.27402040921, 20764.0, 20849.58300833975, 8189.836203276021, 8230.547319897463, 8234.0, 8303.252707256375, 8371.832563348698, 891.6210610490516, 910.6311943662827, 1023.6059117239917, 1109.9778004440027, 1151.6660168551193, 31322.68677313243, 31512.0, 31588.842055789573, 31618.525722114187, 32436.351272974407, 938.3853575907791, 1043.7651528406047, 1127.808272593661, 1147.7532330548972, 1209.8245754365569, 13993.16041037541, 16037.0, 16372.836271637369, 16421.70096186535, 16907.492775216764, 682834.0, 684279.0, 691217.0878291253, 695663.0, 696161.0, 63188.0, 63961.081167564116, 64082.35917640857, 64111.67944160249, 64203.35796642067, 1289.2586762611536, 1324.7019420630475, 1411.9788203177166, 1433.340663294896, 1449.9927500362642, 30633.23416914595, 32389.676103238668, 32461.837690811393, 33113.50329745008, 33189.0, 59790.70104649449, 62301.0, 62349.37650623436, 65329.0, 65561.0, 572664.0, 668156.0, 668848.0, 669620.0, 670106.0, 37381.439278410304, 37421.0, 42789.14421711486, 46024.309635354824, 46697.766511167225, 41498.0, 50713.0, 51172.0, 51833.7408312956, 52310.73844630752, 39793.00517487086, 41898.37152442655, 42121.36817947795, 42392.37496393366, 42437.5756242446, 33465.83267083649, 39692.40461393024, 40777.0, 40859.59140408558, 41149.382759259235, 32202.35595288128, 32562.185945351557, 34471.31057378888, 39803.0, 39844.0, 33218.0, 35869.46195807117, 36722.8163859179, 36748.81625591855, 36874.44688329731, 25869.353266168495, 26347.736522635296, 26491.7350826497, 26562.86718566395, 26781.86609066942], "value": 21015.766391644196, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.8366702318003, 386.45509831138094, 387.4788409589103, 401.6144501278772, 404.7268094036526, 18203.81796182038, 19396.0, 21896.0, 21942.670859937072, 22139.114435422583, 20981.950902454893, 21480.140794368246, 21569.67645485319, 21896.8905155474, 21920.890395548, 5724.169995350682, 6153.384661533833, 6203.441690247877, 6207.937920620804, 6222.315545290015, 4200.138971510833, 4440.666949978749, 4446.777661116931, 4461.7322960622305, 6059.091136329542, 4415.315626077963, 6615.801525954205, 6627.138471998657, 6637.933620663804, 6644.43522300605, 6501.772437964649, 6622.8675426491445, 6628.502862285324, 6648.368405001541, 6822.658867056677, 3767.0, 5865.387018569885, 5885.852258809534, 5899.557533184985, 5900.763969441217, 20035.899820500803, 23873.0, 25678.0, 26123.477530449476, 27104.457910841673, 2013.9597208055757, 2149.9462513437134, 2349.8237632177656, 2959.5708622249867, 3238.3361410910707, 10162.441065741381, 10204.132648724868, 10242.385456872611, 10548.156147508182, 10593.417362045084, 1796.3353559183056, 1872.7467117531542, 1883.5102873252915, 1910.9904450477798, 1920.6926891697262, 95134.0, 96142.51928740382, 99625.90356956526, 118916.99999999914, 125932.0, 9442.622295108256, 10004.599816007423, 10008.0, 10145.847812282822, 10183.18534517237, 12326.50693972231, 12350.62948111558, 12436.37818109091, 12467.376631168405, 12467.940225080969, 8068.8386232275025, 8228.44018987186, 8279.0, 8344.207300306583, 8373.985365268498, 8783.824323513621, 8786.956065219632, 8822.426542274732, 8952.910470895338, 8955.820883582423, 3829.5787463379006, 3897.9805100974313, 3900.4539364488996, 3955.4462375267485, 3959.762414255154, 1670.640812225353, 1910.360029390142, 1942.2522328903474, 1948.2207117153253, 1949.4346639474393, 870.912908709124, 896.9103089690979, 992.1120597065731, 998.4654323694186, 1005.331454582709, 19597.706034409497, 21266.36200913975, 22455.0, 22478.0, 22524.0, 930.9906900931039, 940.6237504997919, 944.8015916657441, 946.4289279795506, 1015.7968406318765, 11212.374205740285, 11668.70828229301, 11714.472848721827, 11730.70673233176, 11757.706057348634, 546738.2663086739, 567913.4812977809, 603458.0, 624007.0, 626298.0, 130215.0, 132386.0, 133535.0, 133543.0, 133608.0, 983.1741337276721, 984.060222487537, 998.3111652959435, 1001.7746007148334, 1004.3873237325225, 19161.712574311674, 19515.60968780645, 19548.60902781908, 19549.60900781948, 19662.115204816102, 53061.46938530565, 53123.81257124585, 53978.46021539735, 54112.0, 58153.83692326045, 78993.8150927748, 83040.16959830324, 83334.5833270854, 83376.58311708402, 85421.57289213514, 8214.548199849065, 8260.793480163044, 8267.710630127916, 8297.502149871027, 8495.447795893248, 12397.661984323435, 13345.617006944223, 13467.461301547828, 13533.120347177402, 13545.187288762705, 11048.337099774039, 11185.32888026721, 11295.0, 11355.716107097389, 11370.147238956946, 10019.448930308612, 10519.474026298501, 11410.258333208314, 11537.596184133516, 11688.18182727235, 10632.670916135483, 10976.451177440937, 11691.473883675086, 11818.586349477728, 11935.582254621047, 7052.753153639598, 7449.888251676338, 7510.436717246338, 7682.8079298018, 8484.703035393732, 10175.7456063599, 10774.946125269324, 11526.711832204262, 11932.880671193525, 11936.462859171184], "value": 9574.801139294646, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1389.4303335632392, 1557.6651020030695, 1567.4513920127954, 1804.3414153833855, 1952.9707054394187, 7699.615019249036, 8486.6181021854, 8709.651613935435, 8732.912670873282, 8805.119488051192, 8418.873716894237, 8636.956815215915, 8641.740747777576, 8642.17899299567, 8648.740537783877, 2067.751869775629, 2139.5827813576357, 2141.9143234270687, 2296.0815673730517, 2335.6730057791924, 2165.9891700541475, 2194.5720584485953, 2297.7587353327913, 2382.0114652419225, 2385.439421735891, 2355.1521452277166, 2358.749862572839, 2360.9055637774554, 2361.1853910400882, 2368.7039120109985, 2288.8054515366216, 2340.134150364359, 2345.401922509749, 2347.1550241912983, 2350.811935045192, 2092.843036772248, 2305.6772051912744, 2375.6080246759266, 2376.0614557249964, 2387.9880600597057, 8480.279176270025, 8634.525101119436, 8706.0, 8735.650573977033, 8735.825283494294, 4580.152671755703, 5236.659617124875, 5237.869053273661, 5238.05714971305, 5241.213817927303, 3397.8810741623925, 3530.346885826105, 3602.3875941089814, 3604.567451905762, 3612.060864175307, 1035.7100011996654, 1057.2704833664748, 1075.6557901471533, 1088.5101704233127, 1088.9455527223686, 11423.0, 11477.483513241925, 11481.885181148165, 11491.908268714542, 11497.712557186054, 4559.361689363492, 4653.790579423934, 4751.786169622375, 4752.382190315306, 4757.738324392122, 9756.219502439788, 9760.511974401252, 9768.609255629695, 9778.462184579916, 9790.167835734042, 5297.947020529746, 5310.495502927216, 5311.990721762852, 5316.627836051517, 5324.520793128637, 4353.25994580922, 4689.320048592935, 4733.5503127202865, 4867.440244371872, 4874.878128046827, 3552.4138517144897, 3716.0338312039066, 3758.8496460141287, 3841.9231615368094, 3852.556955950102, 1138.2203190814319, 1150.1373969522858, 1154.0709728668503, 1158.692946369213, 1161.4947497838298, 902.0843843498853, 913.5523593439167, 920.3787443475627, 933.3060495201323, 950.1591091883732, 5722.37053924068, 5918.585699001029, 5920.31916329619, 6014.759409623566, 6126.744017476407, 931.0968360690119, 956.3305686019814, 998.3161534348994, 1006.3257617396362, 1039.8076355874186, 3663.5054267674054, 3832.2718683450057, 3840.462335273064, 4037.192561487714, 4115.52671442782, 10285.125764310118, 10512.57949682004, 10686.0916822071, 10698.251122421356, 10710.46447677613, 10634.574617015387, 10653.84019239712, 10654.307470014424, 10665.626703065356, 10670.839937400946, 7633.961830190813, 7997.600119993977, 8017.0, 8067.394945379003, 8088.797780055545, 5925.140854576149, 6114.755409783559, 6169.907451388143, 6177.320494745664, 6288.1825362704385, 9632.614695412105, 9941.105300522988, 10085.29402941787, 10085.344452610558, 10085.69742907714, 9998.20014398861, 10120.778460347501, 10243.180545556188, 10265.024822641837, 10349.379037257788, 6784.423324017514, 7498.175200728025, 7616.162222155669, 7648.464607477423, 7684.769456916301, 6862.656867156522, 8398.790030249293, 8423.873641895254, 8494.83010339802, 8532.0, 7387.482876198615, 7483.962580187064, 7527.209642987407, 7647.88528172089, 7700.730474433368, 7349.228331025379, 7418.146913104846, 7428.368588670023, 7469.7385591504035, 7484.909855263466, 6941.687624057031, 7021.543599666005, 7023.0, 7035.5426897253565, 7058.54119482232, 4865.148598995091, 4980.5019498049905, 5023.2214006829, 5040.395152581714, 5100.928804951001, 4867.245576935581, 4971.130052240916, 4971.65198436106, 4971.900561988812, 5082.440931497458], "value": 4556.977579030479, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.1453515621105, 310.79642833943774, 314.81111333200073, 317.0931136948329, 319.5893277138877, 5371.75827087781, 5386.38056623488, 5407.351117865852, 5417.729113544322, 5421.403645598981, 4402.471703395589, 5327.7336133193285, 5357.544608708265, 5384.219288203209, 5389.353277606683, 3231.4506533889235, 3368.8989330320005, 3464.566929133858, 4037.1723796621623, 4090.8545607229876, 3778.244351129771, 4000.0999775050527, 4001.5798341174195, 4064.715469917107, 4087.509498860141, 3375.8312084395807, 4047.7571345719352, 4049.5950404959417, 4052.5136983562015, 4054.6011625988986, 3449.810260435675, 3824.5984171661853, 3874.341361968458, 4038.182817732003, 4078.3066878630702, 2812.142296599536, 3497.7551571390013, 3504.894853154384, 3522.3230344937774, 3541.4864844597646, 4804.327394164819, 4873.902521949577, 5032.2703208035, 5232.555232805216, 5240.554552863011, 3768.3593789055653, 3780.4329350597354, 4390.824367025315, 4441.800118994653, 4685.812567497296, 5113.642045056848, 5432.728363581845, 5452.727363631802, 5478.397376288605, 5488.725563721838, 3401.9489707654407, 3441.659671423431, 3535.840887160084, 3589.515415418937, 3608.1340478285024, 5083.602009447394, 5777.508911742549, 5861.794837180678, 5981.773736384031, 5987.281526216838, 4000.3199456092534, 4014.3577027676015, 4014.498187726534, 4023.203873308023, 4023.3562629979647, 3803.6386543278345, 4007.899802504961, 4050.2911990401562, 4070.75575465473, 4087.7138600297694, 3858.479105320802, 4370.431843860278, 4658.767061646904, 4688.671792974527, 4699.459562150328, 4615.376924115201, 4967.975160124176, 5196.648871293415, 5392.649477783932, 5593.902286642509, 1198.4726720243066, 1273.9426725797362, 1300.3173333999725, 1378.4348417148922, 1381.854905234956, 758.9943325094272, 777.5373652676649, 843.0978852627752, 872.289084396215, 890.4835195586544, 4521.502634710179, 5143.151380022329, 5246.816361427332, 5304.628675992721, 5305.495977882095, 5160.574529970908, 5190.714510701948, 5550.944490555124, 5816.836632673482, 5829.0, 4011.3782363733667, 4528.5245049270015, 5016.974915125401, 5189.896202075937, 5323.467653234646, 2774.5988275920936, 2922.3570814420796, 2999.9850000750293, 3057.2509735114595, 3058.1284333964713, 5383.596230282745, 5978.940210597838, 5988.431099045671, 6051.213342265477, 6053.939460605426, 5628.439060227586, 5690.9146362804595, 5939.554533409925, 5940.61386009908, 6030.698465076816, 5318.813841515605, 5931.745936544172, 5960.344362120163, 5960.986632272524, 6026.548008899263, 5140.97429512865, 5708.743106560131, 5791.9131213031, 5809.24479817621, 5847.912281315868, 5170.767315470888, 5753.562883075453, 5767.826965191051, 5803.448672376287, 5846.0, 5513.393526712139, 5620.707237335354, 5673.234113394639, 5917.497012753965, 6064.0, 2884.0388535795023, 3178.98410507946, 3277.7213936815638, 3313.6023677158896, 3367.915802104967, 4279.486461624625, 4301.956980430155, 4685.742284174403, 4771.212749896228, 4956.900861982812, 4365.912681746284, 4415.114699064802, 4444.31113177458, 4719.457262414729, 4795.280707893844, 4085.1216988347646, 4150.398192262165, 4428.468583769968, 4520.0, 4599.896024954054, 3862.0496649191978, 4143.606357396043, 4534.569215924482, 4633.976830115827, 5113.667611605234, 4579.702319349231, 5504.146857237135, 5507.642003269774, 5523.950449414599, 5589.8261365113785, 5034.194528875362, 5204.53898157736, 5450.345958484849, 5503.284573005483, 5577.888442231214], "value": 3961.0207778313475, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [726.9091363579554, 762.4662736084742, 787.3622365883634, 840.1220724343061, 845.4208866926155, 17387.913060434697, 18046.458606241802, 18070.909645451753, 18105.0, 18152.54618634533, 16314.347426102942, 16430.260638271277, 16515.91742041288, 16519.752203716955, 16564.420245291418, 713.9678714457835, 910.9979023074632, 918.4535201555065, 919.3013309884469, 922.5156792683828, 731.4441024821148, 781.8240895798428, 915.2174890468654, 919.6689191890914, 921.2261700171854, 949.952502374882, 960.2893858544696, 961.4808003678005, 964.0744884910495, 966.3911735606594, 739.7299985505252, 915.7069737683944, 958.2190514730507, 958.2525630008604, 966.9798362727355, 709.3615745828744, 709.5175280809048, 710.3464812372578, 712.1134187936053, 712.3624356201171, 15865.444709435229, 15867.841321586751, 15883.444079457278, 15892.205389730467, 15906.045637261801, 11986.220895641756, 13902.582922512342, 14374.353154107986, 14383.640408989755, 14400.351984160738, 13367.997400195025, 13627.931860340734, 13692.65768355789, 13924.0, 13946.930265348608, 4499.145162419162, 4664.580187783082, 4718.787654555554, 4725.243960966263, 4788.904221915577, 124871.0, 126756.73243267633, 127025.0, 127193.72806271819, 127199.72800272064, 10837.729056773644, 11113.277636953571, 11343.546258149581, 11411.48648310828, 11613.129015323884, 21947.341579752632, 22089.453738238168, 22156.33530994073, 22202.66695999561, 22214.55570888573, 7635.0, 7726.381889448943, 7951.920480795234, 8147.67409303633, 8158.510489370657, 4586.151561961048, 4594.425696787902, 4694.7417892016265, 4780.211265141213, 4789.30555069513, 3535.611082780892, 3644.635536446387, 3653.872114476034, 3655.9817200913826, 3658.286634106377, 662.8707402056552, 667.7996601019674, 672.2638710611873, 684.3567046975827, 685.2975699907535, 475.59099174709723, 516.5428595692839, 538.569144684251, 543.5407081016534, 553.914143307788, 9002.954985225031, 9074.237858959608, 9219.216366608913, 9265.166135047879, 9371.437713737198, 484.367899890639, 560.8345538066199, 590.3971312246747, 591.955603329752, 606.5450911816138, 4331.151819422449, 4422.115576884636, 4424.424824772759, 4492.477642296645, 4528.388667529907, 89119.21761564733, 94531.52734236422, 95393.5230323844, 96235.51882240544, 126580.0, 111884.0, 135505.322473387, 138327.0, 138723.45106195842, 140300.0, 99066.0, 118406.4079679596, 118505.0, 121523.78476215301, 124609.0, 7733.574653394117, 9495.097965693249, 9715.70852874415, 9915.851262230928, 9916.851247231152, 55999.720001401365, 62031.689841550506, 63288.05067923893, 64008.35991640024, 65138.67430662816, 89388.5530572343, 90680.27959161236, 91097.54451227702, 92229.53885230531, 93358.06641933494, 13359.799603006157, 13405.597832065054, 13423.932880335537, 13432.19406835593, 13646.181229126285, 14860.925695371454, 14951.476698315506, 15024.17367044823, 15412.075275483507, 15537.06777593348, 11570.88429115698, 14063.859361406256, 14252.28738563047, 14302.856971430569, 14512.709745804814, 13917.0, 14102.0, 14190.432382704576, 14288.214148221947, 14620.634484137981, 13714.51999180024, 13847.515336963157, 14058.578242652327, 14073.366698498798, 14318.0, 9879.407235565888, 11351.659450216506, 11473.598424055119, 11691.569418693463, 11724.941375293069, 11426.0, 11522.769544608893, 11685.824712629148, 11708.414579271173, 11717.0], "value": 7999.104177196066, "unit": "score"}], "name": "dromaeo_css", "value": 7417.509083394056, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 112.0, 112.0, 112.0, 112.0, 113.0, 115.0, 157.0, 165.0, 90.0], "value": 118.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 119.0, 118.0, 171.0, 121.0, 141.0, 142.0, 141.0, 156.0, 119.0], "value": 138.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 183.0, 184.0, 186.0, 185.0, 183.0, 186.0, 180.0, 180.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 76.0, 58.0, 107.0, 56.0, 57.0, 61.0, 65.0, 96.0, 56.0], "value": 71.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 96.0, 95.0, 72.0, 71.0, 69.0, 96.0, 71.0, 68.0, 103.0], "value": 82.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 84.0, 86.0, 83.0, 89.0, 88.0, 88.0, 84.0], "value": 85.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 117.0, 151.0, 93.0, 94.0, 93.0, 93.0, 93.0, 92.0, 93.0], "value": 101.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 85.0, 85.0, 84.0, 85.0, 84.0, 84.0, 84.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 56.0, 54.0, 54.0, 56.0, 53.0, 53.0, 54.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 80.0, 76.0, 101.0, 104.0, 106.0, 71.0, 69.0, 71.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 86.0, 81.0, 84.0, 86.0, 83.0, 99.0, 85.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 141.0, 142.0, 138.0, 136.0, 141.0, 141.0, 138.0, 146.0, 141.0], "value": 140.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 166.0, 167.0, 165.0, 166.0, 167.0, 180.0, 156.0, 198.0], "value": 169.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 66.0, 66.0, 66.0, 68.0, 63.0, 57.0, 84.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1464.6000000000001, "alertThreshold": 2.0}]} 18:29:34 INFO - Return code: 0 18:29:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:29:34 INFO - # TBPL SUCCESS # 18:29:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:29:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:29:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:29:34 INFO - 2016-06-28 18:29:34.890 screenresolution[1935:18580] starting screenresolution argv=screenresolution get 18:29:34 INFO - 2016-06-28 18:29:34.893 screenresolution[1935:18580] Display 0: 1600x1200x32@60 18:29:34 INFO - 2016-06-28 18:29:34.899 screenresolution[1936:18583] starting screenresolution argv=screenresolution list 18:29:34 INFO - Available Modes on Display 0 18:29:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:29:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:29:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:29:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:29:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:29:35 INFO - Intel Iris: 18:29:35 INFO - Chipset Model: Intel Iris 18:29:35 INFO - Type: GPU 18:29:35 INFO - Bus: Built-In 18:29:35 INFO - VRAM (Dynamic, Max): 1536 MB 18:29:35 INFO - Vendor: Intel (0x8086) 18:29:35 INFO - Device ID: 0x0a2e 18:29:35 INFO - Revision ID: 0x0009 18:29:35 INFO - Displays: 18:29:35 INFO - Display: 18:29:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:29:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:29:35 INFO - Display Serial Number: 1600x1200 60 18:29:35 INFO - Main Display: Yes 18:29:35 INFO - Mirror: Off 18:29:35 INFO - Online: Yes 18:29:35 INFO - Rotation: Supported 18:29:35 INFO - Return code: 0 18:29:35 INFO - Running post-action listener: _resource_record_post_action 18:29:35 INFO - ##### 18:29:35 INFO - ##### Finished run-tests step (success) 18:29:35 INFO - ##### 18:29:35 INFO - Running post-run listener: _resource_record_post_run 18:29:35 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 598016; Write bytes: 703912448; Read time: 7; Write time: 2090 18:29:35 INFO - TinderboxPrint: CPU usage
53.9% 18:29:35 INFO - TinderboxPrint: I/O read bytes / time
598,016 / 7 18:29:35 INFO - TinderboxPrint: I/O write bytes / time
703,912,448 / 2,090 18:29:35 INFO - TinderboxPrint: CPU idle
2,234.6 (46.1%) 18:29:35 INFO - TinderboxPrint: CPU system
967.5 (20.0%) 18:29:35 INFO - TinderboxPrint: CPU user
1,643.6 (33.9%) 18:29:35 INFO - TinderboxPrint: Swap in / out
406,847,488 / 0 18:29:35 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 398166528; Write bytes: 391665152; Read time: 29519; Write time: 745 18:29:35 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 598016; Write bytes: 292414464; Read time: 7; Write time: 1300 18:29:35 INFO - Running post-run listener: _upload_blobber_files 18:29:35 INFO - Blob upload gear active. 18:29:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:29:35 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:29:35 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:29:35 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:29:35 INFO - (blobuploader) - INFO - Open directory for files ... 18:29:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:29:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:29:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:29:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:29:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:29:36 INFO - (blobuploader) - INFO - Done attempting. 18:29:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:29:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:29:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:29:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:29:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:29:37 INFO - (blobuploader) - INFO - Done attempting. 18:29:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:29:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:29:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:29:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:29:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:29:40 INFO - (blobuploader) - INFO - Done attempting. 18:29:40 INFO - (blobuploader) - INFO - Iteration through files over. 18:29:40 INFO - Return code: 0 18:29:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:29:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:29:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8e102fbe424765112eb09cc5fc0b71db29c2278a9997e063ee199587414d47e55791966bedc4610e2a691d0324d22cb41c5c3afdd6600c5ca3e8db157c19efc4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5b78df19cca3d51682cb0feec0678f48bd73bd084d7d745e784bb26d26c5850978aa3db5a645bb2638fa344d78fd4d0c876c482a02b63474ea490adb4ee0f7c5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b4bd32852b911480579b09622f55fa2025d3a0e3548ab90564f167b66a81733791fe84ff59965505f6b90e242d70e86b528b5783d0dda59119ff96118e190b1b"} 18:29:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:29:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:29:40 INFO - Contents: 18:29:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8e102fbe424765112eb09cc5fc0b71db29c2278a9997e063ee199587414d47e55791966bedc4610e2a691d0324d22cb41c5c3afdd6600c5ca3e8db157c19efc4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5b78df19cca3d51682cb0feec0678f48bd73bd084d7d745e784bb26d26c5850978aa3db5a645bb2638fa344d78fd4d0c876c482a02b63474ea490adb4ee0f7c5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b4bd32852b911480579b09622f55fa2025d3a0e3548ab90564f167b66a81733791fe84ff59965505f6b90e242d70e86b528b5783d0dda59119ff96118e190b1b"} 18:29:40 INFO - Running post-run listener: copy_logs_to_upload_dir 18:29:40 INFO - Copying logs to upload dir... 18:29:40 INFO - mkdir: /builds/slave/test/build/upload/logs 18:29:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.316210 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-06-28 18:29:40.280737) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-28 18:29:40.284165) ========= 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.YfjfLFTgiB/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.klo1ngLWin/Listeners TMPDIR=/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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/Autoland/sha512/8e102fbe424765112eb09cc5fc0b71db29c2278a9997e063ee199587414d47e55791966bedc4610e2a691d0324d22cb41c5c3afdd6600c5ca3e8db157c19efc4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5b78df19cca3d51682cb0feec0678f48bd73bd084d7d745e784bb26d26c5850978aa3db5a645bb2638fa344d78fd4d0c876c482a02b63474ea490adb4ee0f7c5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b4bd32852b911480579b09622f55fa2025d3a0e3548ab90564f167b66a81733791fe84ff59965505f6b90e242d70e86b528b5783d0dda59119ff96118e190b1b"} build_url:https://queue.taskcluster.net/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011135 build_url: 'https://queue.taskcluster.net/v1/task/fbxy5DLhRsietPSnsaGYDw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8e102fbe424765112eb09cc5fc0b71db29c2278a9997e063ee199587414d47e55791966bedc4610e2a691d0324d22cb41c5c3afdd6600c5ca3e8db157c19efc4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5b78df19cca3d51682cb0feec0678f48bd73bd084d7d745e784bb26d26c5850978aa3db5a645bb2638fa344d78fd4d0c876c482a02b63474ea490adb4ee0f7c5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b4bd32852b911480579b09622f55fa2025d3a0e3548ab90564f167b66a81733791fe84ff59965505f6b90e242d70e86b528b5783d0dda59119ff96118e190b1b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-28 18:29:40.317182) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 18:29:40.317501) ========= 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.YfjfLFTgiB/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.klo1ngLWin/Listeners TMPDIR=/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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.005160 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 18:29:40.368965) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-28 18:29:40.369270) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-28 18:29:40.371235) ========= ========= Total master_lag: 0.19 =========