builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0019 starttime: 1473184921.36 results: success (0) buildid: 20160906093855 builduid: 40c6d81fbecc4e6e9988812f7aa6a65f revision: dd6f0732c26084b026b8fc99b2da9f801baa9107 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 11:02:01.360616) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 11:02:01.361051) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 11:02:01.361524) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 11:02:02.188862) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 11:02:02.189107) ========= 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.MZJ4Fnyilw/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.q8f5cdZRfs/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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-09-06 11:02:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 152M=0s 2016-09-06 11:02:02 (152 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.579851 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 11:02:02.798995) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 11:02:02.799315) ========= 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.MZJ4Fnyilw/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.q8f5cdZRfs/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.030097 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 11:02:02.850670) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 11:02:02.851015) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev dd6f0732c26084b026b8fc99b2da9f801baa9107 --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 dd6f0732c26084b026b8fc99b2da9f801baa9107 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MZJ4Fnyilw/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.q8f5cdZRfs/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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-09-06 11:02:02,925 truncating revision to first 12 chars 2016-09-06 11:02:02,926 Setting DEBUG logging. 2016-09-06 11:02:02,926 attempt 1/10 2016-09-06 11:02:02,926 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/dd6f0732c260?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 11:02:04,115 unpacking tar archive at: autoland-dd6f0732c260/testing/mozharness/ program finished with exit code 0 elapsedTime=1.443875 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 11:02:04.317550) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 11:02:04.317847) ========= script_repo_revision: dd6f0732c26084b026b8fc99b2da9f801baa9107 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 11:02:04.318250) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 11:02:04.318551) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 11:02:04.337670) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-06 11:02:04.338041) ========= /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.MZJ4Fnyilw/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.q8f5cdZRfs/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 11:02:04 INFO - MultiFileLogger online at 20160906 11:02:04 in /builds/slave/test 11:02:04 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 11:02:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:02:04 INFO - {'append_to_log': False, 11:02:04 INFO - 'base_work_dir': '/builds/slave/test', 11:02:04 INFO - 'blob_upload_branch': 'Autoland', 11:02:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:02:04 INFO - 'branch': 'Autoland', 11:02:04 INFO - 'buildbot_json_path': 'buildprops.json', 11:02:04 INFO - 'config_files': ('talos/mac_config.py',), 11:02:04 INFO - 'default_actions': ('clobber', 11:02:04 INFO - 'read-buildbot-config', 11:02:04 INFO - 'download-and-extract', 11:02:04 INFO - 'populate-webroot', 11:02:04 INFO - 'create-virtualenv', 11:02:04 INFO - 'install', 11:02:04 INFO - 'run-tests'), 11:02:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:02:04 INFO - 'download_minidump_stackwalk': True, 11:02:04 INFO - 'download_symbols': 'ondemand', 11:02:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:02:04 INFO - 'tooltool.py': '/tools/tooltool.py', 11:02:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:02:04 INFO - '/tools/misc-python/virtualenv.py')}, 11:02:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:02:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:02:04 INFO - 'installer_path': 'installer.exe', 11:02:04 INFO - 'log_level': 'info', 11:02:04 INFO - 'log_name': 'talos', 11:02:04 INFO - 'log_to_console': True, 11:02:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:02:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:02:04 INFO - 'opt_config_files': (), 11:02:04 INFO - 'pip_index': False, 11:02:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:02:04 INFO - 'cmd': ('bash', 11:02:04 INFO - '-c', 11:02:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:02:04 INFO - 'enabled': True, 11:02:04 INFO - 'halt_on_failure': False, 11:02:04 INFO - 'name': 'check_screen_resolution'},), 11:02:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:02:04 INFO - 'cmd': ('bash', 11:02:04 INFO - '-c', 11:02:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:02:04 INFO - 'enabled': True, 11:02:04 INFO - 'halt_on_failure': False, 11:02:04 INFO - 'name': 'check_screen_resolution'},), 11:02:04 INFO - 'run_cmd_checks_enabled': True, 11:02:04 INFO - 'sps_profile': False, 11:02:04 INFO - 'sps_profile_interval': 0, 11:02:04 INFO - 'suite': 'dromaeojs', 11:02:04 INFO - 'system_bits': '32', 11:02:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:02:04 INFO - 'title': 't-yosemite-r7-0019', 11:02:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:02:04 INFO - 'use_talos_json': True, 11:02:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:02:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:02:04 INFO - 'work_dir': 'build'} 11:02:04 INFO - [mozharness: 2016-09-06 18:02:04.548149Z] Running clobber step. 11:02:04 INFO - Running pre-action listener: _resource_record_pre_action 11:02:04 INFO - Running main action method: clobber 11:02:04 INFO - rmtree: /builds/slave/test/build 11:02:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:02:06 INFO - Running post-action listener: _resource_record_post_action 11:02:06 INFO - [mozharness: 2016-09-06 18:02:06.886579Z] Finished clobber step (success) 11:02:06 INFO - [mozharness: 2016-09-06 18:02:06.886899Z] Running read-buildbot-config step. 11:02:06 INFO - Running pre-action listener: _resource_record_pre_action 11:02:06 INFO - Running main action method: read_buildbot_config 11:02:06 INFO - Using buildbot properties: 11:02:06 INFO - { 11:02:06 INFO - "project": "", 11:02:06 INFO - "product": "firefox", 11:02:06 INFO - "script_repo_revision": "production", 11:02:06 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 11:02:06 INFO - "repository": "", 11:02:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 11:02:06 INFO - "buildid": "20160906093855", 11:02:06 INFO - "pgo_build": "False", 11:02:06 INFO - "basedir": "/builds/slave/test", 11:02:06 INFO - "buildnumber": 222, 11:02:06 INFO - "slavename": "t-yosemite-r7-0019", 11:02:06 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 11:02:06 INFO - "platform": "yosemite_r7", 11:02:06 INFO - "scheduler": "tests-autoland-macosx64-talos", 11:02:06 INFO - "branch": "autoland", 11:02:06 INFO - "repo_path": "integration/autoland", 11:02:06 INFO - "revision": "dd6f0732c26084b026b8fc99b2da9f801baa9107", 11:02:06 INFO - "stage_platform": "macosx64", 11:02:06 INFO - "builduid": "40c6d81fbecc4e6e9988812f7aa6a65f", 11:02:06 INFO - "slavebuilddir": "test" 11:02:06 INFO - } 11:02:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/B5L0tibMSjCM0hf7r6NZug/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 11:02:06 INFO - Running post-action listener: _resource_record_post_action 11:02:06 INFO - [mozharness: 2016-09-06 18:02:06.892942Z] Finished read-buildbot-config step (success) 11:02:06 INFO - [mozharness: 2016-09-06 18:02:06.893149Z] Running download-and-extract step. 11:02:06 INFO - Running pre-action listener: _resource_record_pre_action 11:02:06 INFO - Running main action method: download_and_extract 11:02:06 INFO - mkdir: /builds/slave/test/build/tests 11:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:06 INFO - trying https://queue.taskcluster.net/v1/task/B5L0tibMSjCM0hf7r6NZug/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 11:02:06 INFO - Downloading https://queue.taskcluster.net/v1/task/B5L0tibMSjCM0hf7r6NZug/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:02:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B5L0tibMSjCM0hf7r6NZug/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 11:02:07 INFO - Downloaded 1199 bytes. 11:02:07 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:02:07 INFO - Using the following test package requirements: 11:02:07 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:02:07 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:02:07 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 11:02:07 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:02:07 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 11:02:07 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:02:07 INFO - u'jsshell-mac.zip'], 11:02:07 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:02:07 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 11:02:07 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:02:07 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:02:07 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 11:02:07 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:02:07 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 11:02:07 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:02:07 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 11:02:07 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:02:07 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 11:02:07 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:02:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B5L0tibMSjCM0hf7r6NZug/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:02:08 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:02:15 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:02:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B5L0tibMSjCM0hf7r6NZug/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:02:16 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:02:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B5L0tibMSjCM0hf7r6NZug/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:02:24 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:25 INFO - trying https://queue.taskcluster.net/v1/task/B5L0tibMSjCM0hf7r6NZug/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:02:25 INFO - Downloading https://queue.taskcluster.net/v1/task/B5L0tibMSjCM0hf7r6NZug/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 11:02:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B5L0tibMSjCM0hf7r6NZug/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 11:02:28 INFO - Downloaded 111518705 bytes. 11:02:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B5L0tibMSjCM0hf7r6NZug/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:02:28 INFO - mkdir: /builds/slave/test/properties 11:02:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:02:28 INFO - Writing to file /builds/slave/test/properties/build_url 11:02:28 INFO - Contents: 11:02:28 INFO - build_url:https://queue.taskcluster.net/v1/task/B5L0tibMSjCM0hf7r6NZug/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:02:29 INFO - Running post-action listener: _resource_record_post_action 11:02:29 INFO - Running post-action listener: set_extra_try_arguments 11:02:29 INFO - [mozharness: 2016-09-06 18:02:29.055082Z] Finished download-and-extract step (success) 11:02:29 INFO - [mozharness: 2016-09-06 18:02:29.055235Z] Running populate-webroot step. 11:02:29 INFO - Running pre-action listener: _resource_record_pre_action 11:02:29 INFO - Running main action method: populate_webroot 11:02:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:02:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:02:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:02:29 INFO - '--tppagecycles', 11:02:29 INFO - '7'], 11:02:29 INFO - 'tests': ['tsvgm']}}, 11:02:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:02:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:02:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:02:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:02:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:02:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:02:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:02:29 INFO - 'talos_options': ['--disable-e10s'], 11:02:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:02:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:02:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:02:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:02:29 INFO - 'talos_options': ['--disable-e10s'], 11:02:29 INFO - 'tests': ['damp', 'tps']}, 11:02:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:02:29 INFO - 'tests': ['damp', 'tps']}, 11:02:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:02:29 INFO - 'tests': ['dromaeo_dom']}, 11:02:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:02:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:02:29 INFO - 'tests': ['basic_compositor_video']}, 11:02:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:02:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:02:29 INFO - 'tests': ['a11yr', 11:02:29 INFO - 'ts_paint', 11:02:29 INFO - 'tpaint', 11:02:29 INFO - 'sessionrestore', 11:02:29 INFO - 'sessionrestore_no_auto_restore', 11:02:29 INFO - 'tabpaint']}, 11:02:29 INFO - 'other-e10s': {'tests': ['a11yr', 11:02:29 INFO - 'ts_paint', 11:02:29 INFO - 'tpaint', 11:02:29 INFO - 'sessionrestore', 11:02:29 INFO - 'sessionrestore_no_auto_restore', 11:02:29 INFO - 'tabpaint']}, 11:02:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:02:29 INFO - 'ts_paint', 11:02:29 INFO - 'tpaint', 11:02:29 INFO - 'sessionrestore', 11:02:29 INFO - 'sessionrestore_no_auto_restore', 11:02:29 INFO - 'tabpaint']}, 11:02:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:02:29 INFO - 'ts_paint', 11:02:29 INFO - 'tpaint', 11:02:29 INFO - 'sessionrestore', 11:02:29 INFO - 'sessionrestore_no_auto_restore', 11:02:29 INFO - 'tabpaint']}, 11:02:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:02:29 INFO - 'tests': ['a11yr', 11:02:29 INFO - 'ts_paint', 11:02:29 INFO - 'tpaint', 11:02:29 INFO - 'sessionrestore', 11:02:29 INFO - 'sessionrestore_no_auto_restore', 11:02:29 INFO - 'tabpaint']}, 11:02:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:02:29 INFO - 'tests': ['a11yr', 11:02:29 INFO - 'ts_paint', 11:02:29 INFO - 'tpaint', 11:02:29 INFO - 'sessionrestore', 11:02:29 INFO - 'sessionrestore_no_auto_restore', 11:02:29 INFO - 'tabpaint']}, 11:02:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:02:29 INFO - 'tests': ['tsvgx', 11:02:29 INFO - 'tsvgr_opacity', 11:02:29 INFO - 'tart', 11:02:29 INFO - 'tscrollx', 11:02:29 INFO - 'cart']}, 11:02:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:02:29 INFO - 'tsvgr_opacity', 11:02:29 INFO - 'tart', 11:02:29 INFO - 'tscrollx', 11:02:29 INFO - 'cart']}, 11:02:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:02:29 INFO - 'talos_options': ['--disable-e10s'], 11:02:29 INFO - 'tests': ['tp5o']}, 11:02:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:02:29 INFO - 'tests': ['tp5o']}, 11:02:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:02:29 INFO - 'talos_options': ['--disable-e10s', 11:02:29 INFO - '--xperf_path', 11:02:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:02:29 INFO - 'tests': ['tp5n']}, 11:02:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:02:29 INFO - 'talos_options': ['--xperf_path', 11:02:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:02:29 INFO - 'tests': ['tp5n']}}, 11:02:29 INFO - 'talos.zip': {'path': '', 11:02:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:02:29 INFO - Running post-action listener: _resource_record_post_action 11:02:29 INFO - [mozharness: 2016-09-06 18:02:29.073498Z] Finished populate-webroot step (success) 11:02:29 INFO - [mozharness: 2016-09-06 18:02:29.073626Z] Running create-virtualenv step. 11:02:29 INFO - Running pre-action listener: _resource_record_pre_action 11:02:29 INFO - Running main action method: create_virtualenv 11:02:29 INFO - Creating virtualenv /builds/slave/test/build/venv 11:02:29 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:02:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:02:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:02:29 INFO - Using real prefix '/tools/python27' 11:02:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:02:29 INFO - Installing distribute.............................................................................................................................................................................................done. 11:02:32 INFO - Installing pip.................done. 11:02:32 INFO - Return code: 0 11:02:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:02:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:32 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 0x10a3b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb81adac7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a31bd30>, '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 0x10a080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a313b20>, '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.MZJ4Fnyilw/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.q8f5cdZRfs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:02:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:02:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MZJ4Fnyilw/Render', 11:02:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:32 INFO - 'HOME': '/Users/cltbld', 11:02:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:32 INFO - 'LOGNAME': 'cltbld', 11:02:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:32 INFO - 'MOZ_NO_REMOTE': '1', 11:02:32 INFO - 'NO_EM_RESTART': '1', 11:02:32 INFO - 'PAGER': '/bin/cat', 11:02:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:32 INFO - 'PWD': '/builds/slave/test', 11:02:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:32 INFO - 'SHELL': '/bin/bash', 11:02:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8f5cdZRfs/Listeners', 11:02:32 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 11:02:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:32 INFO - 'USER': 'cltbld', 11:02:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:32 INFO - 'XPC_FLAGS': '0x0', 11:02:32 INFO - 'XPC_SERVICE_NAME': '0', 11:02:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:33 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:02:33 INFO - Cleaning up... 11:02:33 INFO - Return code: 0 11:02:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:02:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:33 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 0x10a3b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb81adac7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a31bd30>, '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 0x10a080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a313b20>, '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.MZJ4Fnyilw/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.q8f5cdZRfs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:02:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:02:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MZJ4Fnyilw/Render', 11:02:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:33 INFO - 'HOME': '/Users/cltbld', 11:02:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:33 INFO - 'LOGNAME': 'cltbld', 11:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:33 INFO - 'MOZ_NO_REMOTE': '1', 11:02:33 INFO - 'NO_EM_RESTART': '1', 11:02:33 INFO - 'PAGER': '/bin/cat', 11:02:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:33 INFO - 'PWD': '/builds/slave/test', 11:02:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:33 INFO - 'SHELL': '/bin/bash', 11:02:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8f5cdZRfs/Listeners', 11:02:33 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 11:02:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:33 INFO - 'USER': 'cltbld', 11:02:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:33 INFO - 'XPC_FLAGS': '0x0', 11:02:33 INFO - 'XPC_SERVICE_NAME': '0', 11:02:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:33 INFO - Downloading/unpacking psutil>=3.1.1 11:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:02:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:02:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:02:35 INFO - Installing collected packages: psutil 11:02:35 INFO - Running setup.py install for psutil 11:02:36 INFO - building 'psutil._psutil_osx' extension 11:02:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 11:02:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:02:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:02:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 11:02:36 INFO - building 'psutil._psutil_posix' extension 11:02:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 11:02:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:02:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:02:36 INFO - ^ 11:02:36 INFO - 1 warning generated. 11:02:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 11:02:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:02:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:02:37 INFO - Successfully installed psutil 11:02:37 INFO - Cleaning up... 11:02:37 INFO - Return code: 0 11:02:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:02:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:37 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 0x10a3b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb81adac7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a31bd30>, '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 0x10a080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a313b20>, '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.MZJ4Fnyilw/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.q8f5cdZRfs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:02:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:02:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MZJ4Fnyilw/Render', 11:02:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:37 INFO - 'HOME': '/Users/cltbld', 11:02:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:37 INFO - 'LOGNAME': 'cltbld', 11:02:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:37 INFO - 'MOZ_NO_REMOTE': '1', 11:02:37 INFO - 'NO_EM_RESTART': '1', 11:02:37 INFO - 'PAGER': '/bin/cat', 11:02:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:37 INFO - 'PWD': '/builds/slave/test', 11:02:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:37 INFO - 'SHELL': '/bin/bash', 11:02:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8f5cdZRfs/Listeners', 11:02:37 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 11:02:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:37 INFO - 'USER': 'cltbld', 11:02:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:37 INFO - 'XPC_FLAGS': '0x0', 11:02:37 INFO - 'XPC_SERVICE_NAME': '0', 11:02:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:02:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:02:40 INFO - Installing collected packages: mozsystemmonitor 11:02:40 INFO - Running setup.py install for mozsystemmonitor 11:02:40 INFO - Successfully installed mozsystemmonitor 11:02:40 INFO - Cleaning up... 11:02:40 INFO - Return code: 0 11:02:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:02:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:40 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 0x10a3b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb81adac7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a31bd30>, '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 0x10a080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a313b20>, '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.MZJ4Fnyilw/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.q8f5cdZRfs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:02:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MZJ4Fnyilw/Render', 11:02:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:40 INFO - 'HOME': '/Users/cltbld', 11:02:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:40 INFO - 'LOGNAME': 'cltbld', 11:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:40 INFO - 'MOZ_NO_REMOTE': '1', 11:02:40 INFO - 'NO_EM_RESTART': '1', 11:02:40 INFO - 'PAGER': '/bin/cat', 11:02:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:40 INFO - 'PWD': '/builds/slave/test', 11:02:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:40 INFO - 'SHELL': '/bin/bash', 11:02:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8f5cdZRfs/Listeners', 11:02:40 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 11:02:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:40 INFO - 'USER': 'cltbld', 11:02:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:40 INFO - 'XPC_FLAGS': '0x0', 11:02:40 INFO - 'XPC_SERVICE_NAME': '0', 11:02:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:40 INFO - Downloading/unpacking jsonschema==2.5.1 11:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:02:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:43 INFO - Downloading functools32-3.2.3-2.tar.gz 11:02:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:02:43 INFO - warning: no files found matching '*.txt' 11:02:43 INFO - no previously-included directories found matching 'build' 11:02:43 INFO - no previously-included directories found matching 'dist' 11:02:43 INFO - no previously-included directories found matching '.git*' 11:02:43 INFO - Installing collected packages: jsonschema, functools32 11:02:43 INFO - Running setup.py install for functools32 11:02:43 INFO - warning: no files found matching '*.txt' 11:02:43 INFO - no previously-included directories found matching 'build' 11:02:43 INFO - no previously-included directories found matching 'dist' 11:02:43 INFO - no previously-included directories found matching '.git*' 11:02:43 INFO - Successfully installed jsonschema functools32 11:02:43 INFO - Cleaning up... 11:02:43 INFO - Return code: 0 11:02:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:02:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a3b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb81adac7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a31bd30>, '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 0x10a080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a313b20>, '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.MZJ4Fnyilw/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.q8f5cdZRfs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:02:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:02:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MZJ4Fnyilw/Render', 11:02:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:43 INFO - 'HOME': '/Users/cltbld', 11:02:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:43 INFO - 'LOGNAME': 'cltbld', 11:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:43 INFO - 'MOZ_NO_REMOTE': '1', 11:02:43 INFO - 'NO_EM_RESTART': '1', 11:02:43 INFO - 'PAGER': '/bin/cat', 11:02:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:43 INFO - 'PWD': '/builds/slave/test', 11:02:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:43 INFO - 'SHELL': '/bin/bash', 11:02:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8f5cdZRfs/Listeners', 11:02:43 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 11:02:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:43 INFO - 'USER': 'cltbld', 11:02:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:43 INFO - 'XPC_FLAGS': '0x0', 11:02:43 INFO - 'XPC_SERVICE_NAME': '0', 11:02:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:02:44 INFO - Cleaning up... 11:02:44 INFO - Return code: 0 11:02:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:02:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:44 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 0x10a3b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb81adac7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a31bd30>, '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 0x10a080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a313b20>, '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.MZJ4Fnyilw/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.q8f5cdZRfs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:02:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:02:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MZJ4Fnyilw/Render', 11:02:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:44 INFO - 'HOME': '/Users/cltbld', 11:02:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:44 INFO - 'LOGNAME': 'cltbld', 11:02:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:44 INFO - 'MOZ_NO_REMOTE': '1', 11:02:44 INFO - 'NO_EM_RESTART': '1', 11:02:44 INFO - 'PAGER': '/bin/cat', 11:02:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:44 INFO - 'PWD': '/builds/slave/test', 11:02:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:44 INFO - 'SHELL': '/bin/bash', 11:02:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8f5cdZRfs/Listeners', 11:02:44 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 11:02:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:44 INFO - 'USER': 'cltbld', 11:02:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:44 INFO - 'XPC_FLAGS': '0x0', 11:02:44 INFO - 'XPC_SERVICE_NAME': '0', 11:02:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:44 INFO - Downloading/unpacking blobuploader==1.2.4 11:02:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:46 INFO - Downloading blobuploader-1.2.4.tar.gz 11:02:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:02:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:02:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:02:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:47 INFO - Downloading docopt-0.6.1.tar.gz 11:02:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:02:48 INFO - Installing collected packages: blobuploader, requests, docopt 11:02:48 INFO - Running setup.py install for blobuploader 11:02:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:02:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:02:48 INFO - Running setup.py install for requests 11:02:48 INFO - Running setup.py install for docopt 11:02:48 INFO - Successfully installed blobuploader requests docopt 11:02:48 INFO - Cleaning up... 11:02:48 INFO - Return code: 0 11:02:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:02: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:49 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 0x10a3b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb81adac7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a31bd30>, '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 0x10a080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a313b20>, '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.MZJ4Fnyilw/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.q8f5cdZRfs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:02:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:02:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MZJ4Fnyilw/Render', 11:02:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:49 INFO - 'HOME': '/Users/cltbld', 11:02:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:49 INFO - 'LOGNAME': 'cltbld', 11:02:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:49 INFO - 'MOZ_NO_REMOTE': '1', 11:02:49 INFO - 'NO_EM_RESTART': '1', 11:02:49 INFO - 'PAGER': '/bin/cat', 11:02:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:49 INFO - 'PWD': '/builds/slave/test', 11:02:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:49 INFO - 'SHELL': '/bin/bash', 11:02:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8f5cdZRfs/Listeners', 11:02:49 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 11:02:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:49 INFO - 'USER': 'cltbld', 11:02:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:49 INFO - 'XPC_FLAGS': '0x0', 11:02:49 INFO - 'XPC_SERVICE_NAME': '0', 11:02:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:02:49 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-0UVHxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:02:49 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-35woYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:02:49 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-mrUsMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:02:49 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-uoOhty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:02:49 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-h3kEcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:02:49 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-uWxZCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:02:49 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-e2jLVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:02:49 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-UWOuOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:02:50 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-6X9wXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:02:50 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-reZKc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:02:50 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-0KtfqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:02:50 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-G85Kh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:02:50 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-o5NkOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:02:50 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-Z1wJzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:02:50 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-OKsY2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:02:50 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-gVpkld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:02:50 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-8c1de8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:02:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:02:51 INFO - Running setup.py install for manifestparser 11:02:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:02:51 INFO - Running setup.py install for mozcrash 11:02:51 INFO - Running setup.py install for mozdebug 11:02:51 INFO - Running setup.py install for mozdevice 11:02:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:02:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:02:51 INFO - Running setup.py install for mozfile 11:02:51 INFO - Running setup.py install for mozhttpd 11:02:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:02:51 INFO - Running setup.py install for mozinfo 11:02:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:02:52 INFO - Running setup.py install for mozInstall 11:02:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:02:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:02:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:02:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:02:52 INFO - Running setup.py install for mozleak 11:02:52 INFO - Running setup.py install for mozlog 11:02:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:02:52 INFO - Running setup.py install for moznetwork 11:02:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:02:52 INFO - Running setup.py install for mozprocess 11:02:52 INFO - Running setup.py install for mozprofile 11:02:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:02:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:02:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:02:52 INFO - Running setup.py install for mozrunner 11:02:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:02:53 INFO - Running setup.py install for mozscreenshot 11:02:53 INFO - Running setup.py install for moztest 11:02:53 INFO - Running setup.py install for mozversion 11:02:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:02:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:02:53 INFO - Cleaning up... 11:02:53 INFO - Return code: 0 11:02:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:02:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:53 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 0x10a3b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb81adac7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a31bd30>, '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 0x10a080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a313b20>, '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.MZJ4Fnyilw/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.q8f5cdZRfs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:02:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:02:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MZJ4Fnyilw/Render', 11:02:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:53 INFO - 'HOME': '/Users/cltbld', 11:02:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:53 INFO - 'LOGNAME': 'cltbld', 11:02:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:53 INFO - 'MOZ_NO_REMOTE': '1', 11:02:53 INFO - 'NO_EM_RESTART': '1', 11:02:53 INFO - 'PAGER': '/bin/cat', 11:02:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:53 INFO - 'PWD': '/builds/slave/test', 11:02:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:53 INFO - 'SHELL': '/bin/bash', 11:02:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8f5cdZRfs/Listeners', 11:02:53 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 11:02:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:53 INFO - 'USER': 'cltbld', 11:02:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:53 INFO - 'XPC_FLAGS': '0x0', 11:02:53 INFO - 'XPC_SERVICE_NAME': '0', 11:02:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:02:53 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-FLS6Uw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:02:53 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:02:53 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-evUNIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:02:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:02:54 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-LIGocD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:02:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:02:54 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-4wq4Qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:02:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:02:54 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-nXvhhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:02:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:02:54 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-AZep0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:02:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:02:54 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-kYEPR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:02:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:02:54 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-08zMtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:02:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:02:54 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-wG1TvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:02:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:02:54 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-AwRYbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:02:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:02:54 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-a6L5Zj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:02:55 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-dhLKnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:02:55 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-n36Ifs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:02:55 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-yq0zdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:02:55 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-zA39VE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:02:55 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-h9hzT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:02:55 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-O8kjUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:02:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:58 INFO - Downloading blessings-1.6.tar.gz 11:02:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:02:58 INFO - Installing collected packages: blessings 11:02:58 INFO - Running setup.py install for blessings 11:02:58 INFO - Successfully installed blessings 11:02:58 INFO - Cleaning up... 11:02:58 INFO - Return code: 0 11:02:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:02:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:02:58 INFO - Reading from file tmpfile_stdout 11:02:58 INFO - Current package versions: 11:02:58 INFO - blessings == 1.6 11:02:58 INFO - blobuploader == 1.2.4 11:02:58 INFO - docopt == 0.6.1 11:02:58 INFO - functools32 == 3.2.3-2 11:02:58 INFO - jsonschema == 2.5.1 11:02:58 INFO - manifestparser == 1.1 11:02:58 INFO - mozInstall == 1.12 11:02:58 INFO - mozcrash == 0.17 11:02:58 INFO - mozdebug == 0.1 11:02:58 INFO - mozdevice == 0.48 11:02:58 INFO - mozfile == 1.2 11:02:58 INFO - mozhttpd == 0.7 11:02:58 INFO - mozinfo == 0.9 11:02:58 INFO - mozleak == 0.1 11:02:58 INFO - mozlog == 3.3 11:02:58 INFO - moznetwork == 0.27 11:02:58 INFO - mozprocess == 0.23 11:02:58 INFO - mozprofile == 0.28 11:02:58 INFO - mozrunner == 6.12 11:02:58 INFO - mozscreenshot == 0.1 11:02:58 INFO - mozsystemmonitor == 0.3 11:02:58 INFO - moztest == 0.7 11:02:58 INFO - mozversion == 1.4 11:02:58 INFO - psutil == 3.1.1 11:02:58 INFO - requests == 1.2.3 11:02:58 INFO - wsgiref == 0.1.2 11:02:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:02:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:58 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 0x10a3b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb81adac7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a31bd30>, '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 0x10a080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a313b20>, '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.MZJ4Fnyilw/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.q8f5cdZRfs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:02:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MZJ4Fnyilw/Render', 11:02:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:58 INFO - 'HOME': '/Users/cltbld', 11:02:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:58 INFO - 'LOGNAME': 'cltbld', 11:02:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:58 INFO - 'MOZ_NO_REMOTE': '1', 11:02:58 INFO - 'NO_EM_RESTART': '1', 11:02:58 INFO - 'PAGER': '/bin/cat', 11:02:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:58 INFO - 'PWD': '/builds/slave/test', 11:02:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:58 INFO - 'SHELL': '/bin/bash', 11:02:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8f5cdZRfs/Listeners', 11:02:58 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 11:02:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:58 INFO - 'USER': 'cltbld', 11:02:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:58 INFO - 'XPC_FLAGS': '0x0', 11:02:58 INFO - 'XPC_SERVICE_NAME': '0', 11:02:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:02:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:02:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:02:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:02:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:02:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:02:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:02:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:02:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:02:58 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:02:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:02:58 INFO - Cleaning up... 11:02:59 INFO - Return code: 0 11:02:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:02:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:59 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 0x10a3b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb81adac7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a31bd30>, '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 0x10a080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a313b20>, '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.MZJ4Fnyilw/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.q8f5cdZRfs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:02:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MZJ4Fnyilw/Render', 11:02:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:59 INFO - 'HOME': '/Users/cltbld', 11:02:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:59 INFO - 'LOGNAME': 'cltbld', 11:02:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:59 INFO - 'MOZ_NO_REMOTE': '1', 11:02:59 INFO - 'NO_EM_RESTART': '1', 11:02:59 INFO - 'PAGER': '/bin/cat', 11:02:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:59 INFO - 'PWD': '/builds/slave/test', 11:02:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:59 INFO - 'SHELL': '/bin/bash', 11:02:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8f5cdZRfs/Listeners', 11:02:59 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 11:02:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:59 INFO - 'USER': 'cltbld', 11:02:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:59 INFO - 'XPC_FLAGS': '0x0', 11:02:59 INFO - 'XPC_SERVICE_NAME': '0', 11:02:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:02:59 INFO - Cleaning up... 11:02:59 INFO - Return code: 0 11:02:59 INFO - Running post-action listener: _resource_record_post_action 11:02:59 INFO - Running post-action listener: _start_resource_monitoring 11:02:59 INFO - Starting resource monitoring. 11:02:59 INFO - [mozharness: 2016-09-06 18:02:59.298858Z] Finished create-virtualenv step (success) 11:02:59 INFO - [mozharness: 2016-09-06 18:02:59.299823Z] Running install step. 11:02:59 INFO - Running pre-action listener: _resource_record_pre_action 11:02:59 INFO - Running main action method: install 11:02:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:02:59 INFO - Reading from file tmpfile_stdout 11:02:59 INFO - Detecting whether we're running mozinstall >=1.0... 11:02:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:02:59 INFO - Reading from file tmpfile_stdout 11:02:59 INFO - Output received: 11:02:59 INFO - Usage: mozinstall [options] installer 11:02:59 INFO - Options: 11:02:59 INFO - -h, --help show this help message and exit 11:02:59 INFO - -d DEST, --destination=DEST 11:02:59 INFO - Directory to install application into. [default: 11:02:59 INFO - "/builds/slave/test"] 11:02:59 INFO - --app=APP Application being installed. [default: firefox] 11:02:59 INFO - mkdir: /builds/slave/test/build/application 11:02:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:03:33 INFO - Reading from file tmpfile_stdout 11:03:33 INFO - Output received: 11:03:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:03:33 INFO - Running post-action listener: _resource_record_post_action 11:03:33 INFO - [mozharness: 2016-09-06 18:03:33.590337Z] Finished install step (success) 11:03:33 INFO - [mozharness: 2016-09-06 18:03:33.590465Z] Running run-tests step. 11:03:33 INFO - Running pre-action listener: _resource_record_pre_action 11:03:33 INFO - Running main action method: run_tests 11:03:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:03:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:03:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:03:33 INFO - 2016-09-06 11:03:33.605 screenresolution[1651:8296] starting screenresolution argv=screenresolution get 11:03:33 INFO - 2016-09-06 11:03:33.607 screenresolution[1651:8296] Display 0: 1600x1200x32@60 11:03:33 INFO - 2016-09-06 11:03:33.613 screenresolution[1652:8298] starting screenresolution argv=screenresolution list 11:03:33 INFO - Available Modes on Display 0 11:03:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:03:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:03:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:03:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:03:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:03:33 INFO - Intel Iris: 11:03:33 INFO - Chipset Model: Intel Iris 11:03:33 INFO - Type: GPU 11:03:33 INFO - Bus: Built-In 11:03:33 INFO - VRAM (Dynamic, Max): 1536 MB 11:03:33 INFO - Vendor: Intel (0x8086) 11:03:33 INFO - Device ID: 0x0a2e 11:03:33 INFO - Revision ID: 0x0009 11:03:33 INFO - Displays: 11:03:33 INFO - Display: 11:03:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:03:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:03:33 INFO - Display Serial Number: 1600x1200 60 11:03:33 INFO - Main Display: Yes 11:03:33 INFO - Mirror: Off 11:03:33 INFO - Online: Yes 11:03:33 INFO - Rotation: Supported 11:03:33 INFO - Return code: 0 11:03:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:03:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:03:33 INFO - Python 2.7.3 11:03:33 INFO - Return code: 0 11:03:33 INFO - grabbing minidump binary from tooltool 11:03:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:33 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 0x10a31bd30>, '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 0x10a080db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a313b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:03:33 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:03:33 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 11:03:33 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:03:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:03:33 INFO - Return code: 0 11:03:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:03:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:03:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:03:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:03:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:03:33 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/B5L0tibMSjCM0hf7r6NZug/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:03:33 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/B5L0tibMSjCM0hf7r6NZug/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0019 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:03:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MZJ4Fnyilw/Render', 11:03:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:33 INFO - 'HOME': '/Users/cltbld', 11:03:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:33 INFO - 'LOGNAME': 'cltbld', 11:03:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:03:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:03:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:33 INFO - 'MOZ_NO_REMOTE': '1', 11:03:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:03:33 INFO - 'NO_EM_RESTART': '1', 11:03:33 INFO - 'PAGER': '/bin/cat', 11:03:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:33 INFO - 'PWD': '/builds/slave/test', 11:03:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:03:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:33 INFO - 'SHELL': '/bin/bash', 11:03:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8f5cdZRfs/Listeners', 11:03:33 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 11:03:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:33 INFO - 'USER': 'cltbld', 11:03:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:33 INFO - 'XPC_FLAGS': '0x0', 11:03:33 INFO - 'XPC_SERVICE_NAME': '0', 11:03:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:33 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/B5L0tibMSjCM0hf7r6NZug/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:03:34 INFO - mozversion application_buildid: 20160906093855 11:03:34 INFO - mozversion application_changeset: dd6f0732c26084b026b8fc99b2da9f801baa9107 11:03:34 INFO - mozversion application_display_name: Nightly 11:03:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:03:34 INFO - mozversion application_name: Firefox 11:03:34 INFO - mozversion application_remotingname: firefox 11:03:34 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 11:03:34 INFO - mozversion application_vendor: Mozilla 11:03:34 INFO - mozversion application_version: 51.0a1 11:03:34 INFO - mozversion platform_buildid: 20160906093855 11:03:34 INFO - mozversion platform_changeset: dd6f0732c26084b026b8fc99b2da9f801baa9107 11:03:34 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 11:03:34 INFO - mozversion platform_version: 51.0a1 11:03:34 INFO - using testdate: 1473185014 11:03:34 INFO - actual date: 1473185014 11:03:34 INFO - starting webserver on 'localhost:49269' 11:03:34 INFO - SUITE-START | Running 2 tests 11:03:34 INFO - TEST-START | dromaeo_css 11:03:34 INFO - operating with platform_type : mac_ 11:03:34 INFO - Initialising browser for dromaeo_css test... 11:03:34 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpMnCgoN/profile http://localhost:49269/getInfo.html) 11:03:36 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 11:03:36 INFO - PROCESS | 1662 | colorDepth:24 11:03:36 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 11:03:36 INFO - PROCESS | 1662 | __metrics 11:03:36 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:03:36 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:03:37 INFO - TEST-INFO | 1662: exit 0 11:03:37 INFO - Browser initialized. 11:03:37 INFO - Running cycle 1/1 for dromaeo_css test... 11:03:37 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpMnCgoN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:03:49 INFO - PROCESS | 1665 | RSS: Main: 232439808 11:03:49 INFO - PROCESS | 1665 | 11:04:40 INFO - PROCESS | 1665 | 1473185080501 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:06:52 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49269/tests/dromaeo/cssquery-ext.html) 11:06:53 INFO - PROCESS | 1665 | RSS: Main: 384217088 11:06:53 INFO - PROCESS | 1665 | 11:09:57 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-ext.html (next: http://localhost:49269/tests/dromaeo/cssquery-jquery.html) 11:09:57 INFO - PROCESS | 1665 | RSS: Main: 371249152 11:09:57 INFO - PROCESS | 1665 | 11:13:00 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49269/tests/dromaeo/cssquery-mootools.html) 11:13:00 INFO - PROCESS | 1665 | RSS: Main: 361095168 11:13:00 INFO - PROCESS | 1665 | 11:16:03 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49269/tests/dromaeo/cssquery-prototype.html) 11:16:03 INFO - PROCESS | 1665 | RSS: Main: 335982592 11:16:03 INFO - PROCESS | 1665 | 11:19:07 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49269/tests/dromaeo/cssquery-yui.html) 11:19:07 INFO - PROCESS | 1665 | RSS: Main: 324980736 11:19:07 INFO - PROCESS | 1665 | 11:22:11 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-yui.html (next: http://localhost:49269/tests/dromaeo/cssquery-dojo.html) 11:22:11 INFO - PROCESS | 1665 | RSS: Main: 378621952 11:22:11 INFO - PROCESS | 1665 | 11:22:11 INFO - PROCESS | 1665 | __start_tp_report 11:22:11 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 11:22:11 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 11:22:11 INFO - PROCESS | 1665 | |i|pagename|runs| 11:22:11 INFO - PROCESS | 1665 | |0;dojo.html;3249.9025029249115;3258.674132586741;3301.702846743794;3312.1222875937865;3364.428047231972;4688.320193571934;5491.203775452557;5604.831855044345;5638.943610563893;5676;5601.467860553251;5613.971930140354;5648.265725455685;5657.8585535361635;5718.914216286759;5118.360204974379;5167.689938603696;5200.037992971313;5205.713685747282;5223.764930578133;5126.948730512703;5200.063988482072;5248.711320877369;5317.441668624797;5320.654157479771;5252.921206181911;5270.314859068335;5276.2085687146855;5297.523222909937;5334.199870019489;5108.310378098983;5129.487051294878;5142.61430392722;5205.843824685266;5221;5096.337476128079;5103.540681338697;5134.897302053975;5166.715830629314;5245.632805703603;5636.351819540764;5678.949394362056;5693.202951586781;5719.656820590736;5748.7125643717645;6064.848378790522;6138.600990935575;6193.783217587408;6207.441330280297;6215.378462153793;6167.938320616781;6178.505719542426;6204.9689751551405;6209.937900620981;6271.216097987752;5759.712014399263;5762.538996880239;5765.990951583498;5772.2207501986695;5781.595288329777;6121.816345509642;6123.404636889863;6193.287771906288;6223.937760622426;6249.375062493714;6103.115048317969;6118.479929205978;6120.17287048654;6122.7550897964475;6125.050617154348;6142.324344322121;6198.101275315054;6204.193454850931;6219.036049412348;6225.62646241227;5917.349091599921;6030.306514750772;6036.3360030396625;6159.291681456601;6179.3820617938745;6104.4505994460715;6116.944513560587;6117.15760190703;6120.234970628671;6184.103305020747;4890.021995600894;4900.754962251873;4903.632227582945;4925.7783399747095;4940.431850337185;4393.934090988638;4401.119776044804;4405.0969551241915;4430.13612345596;4453.643708503377;5553.250311207935;5558.805441809517;5600.159976003551;5621.662700237999;5628.97185514078;6182.165407669907;6183.598066125687;6187.721552530147;6192.5974811638;6194.81415557534;5631.71841407928;5645.604807663506;5651;5661.235733176051;5706.201131841545;5272.340957380327;5340.038793017216;5347.652402593819;5354.250404943311;5364.249005139283;6248.750049997949;6276.372362763687;6296.33888441707;6317.989121740591;6322.178116844781;6166.290876549254;6204.5036397089025;6207.224096987877;6227.252729672468;6239.78160764371;6142.232220972379;6148.016317389196;6177.073438984188;6199.225096862892;6277.372262773686;6105.247473117015;6109.61901919832;6113.841195312776;6119.898632333015;6133.202683651097;5170.457102004386;6127.637555910542;6167.5682702210415;6185.2886918003205;6186.721597528032;5102.36220472441;5676.176954341515;5853.64878107315;5870.882582348415;5901.940980590139;5872.383399742966;5908.645481271137;5912.113183022581;5915.319738230159;5944.8811023780145;5694.088945768657;5734.2258795062135;5750.511206547323;5761.48146666802;5770.047942089676;5874.647521148745;5898.793542226002;5945;5956.672383018976;5968.612040217372;5899.528037756883;5911.704414779158;5912.349641539341;5923.7926672566255;5981.3719559445635 11:22:11 INFO - PROCESS | 1665 | |1;ext.html;481.96144308455325;490.99018019639607;536.3188750287133;536.9033573956689;558.3662543013678;30862.228444288878;32950.176245593844;32964.340713185724;33000.83499582499;41875.371869421986;34045.31909361811;44323.77838110805;44383.77808110963;44384.77807610966;44465.55534444655;9896.158826499755;10100.848487272697;10107.039831216023;10124.746881327988;10126.54430550627;8862;9077.727668169966;9740.51297435129;9862.654807081753;9874.9506252469;9111.316651251149;9176.632934682604;9185.219256363218;12030.157888947733;12349.382530873465;12314.261144331369;12352.62942111738;12394.256344619353;12405.813912791315;12457.501699932038;11904.761904761855;11993.580224692181;12011.339376334297;12019;12020.158588898781;37025.70410035636;41688.79155604233;42102.947426314284;42157.367639485434;42183.7890810544;3115.2679120406688;3322.732046247489;3349.5980482342275;3453.913652158716;3530.3645343838098;31716.682833171835;32521.51217731736;32673.019809405756;32803.22762882275;33172.3365532688;3234.15911862915;3252.414565378231;3256.804591724504;3361.210115622827;3365.562476878015;486580;486607;486781;487162;487269;33825.15437114042;34432.82783586066;34501.48247776285;35337.82331088328;62501.37498625046;35757.46363804493;35845.8207708965;35880.461793073126;35930;35941.28117437637;14422.350994205286;14590;14592.854071459149;14593.85406145925;14611.342489587994;12296.569620063434;12402.81395779064;12410.007199424024;12418.81371779424;12453.25280483174;7786.649600767979;7793.883091753628;7812.882806757903;7877.684892604231;7896.091949425775;2827.2366461055813;3061.1122774395176;3148.5906832111677;3151.952720709191;3190.6171259449015;965.5365424596092;974.2741657465234;979.3829887171037;1030.6032177611673;1038.2991480750463;29920.551191732142;30249;30546.694533054386;30718.539221911687;30810.383792324028;934.4066517761271;1014.0923873133504;1045.6810672744887;1066.4081434803759;1091.1161958813386;15225.162616056223;15797.447089351817;15850.96968697031;15875.920620397055;16120.677586448206;686784;694385;694724;695003;697956;50243.24635130476;59235.40764592299;59877;59964;60054;1247.4012474012525;1345.6299517632533;1396.4274647394925;1397.82527184102;1405.7750759878575;30992.53511197379;31002.0699379019;31605.683943161195;31608.209794755316;31739.41302934798;51006.74496627493;51535.48464515457;51635.741821290656;51676.74161629168;51681.483185167664;554937;556994;557462;877816;903133;26340.868295658398;42275.57724422839;42306;42710;42758.78620606877;43754.34368484412;45612.08775824531;46678.76660616811;47017.76491117659;47247;38211.61788382192;38302;38732.03169920774;39722;40123;33579.83210083934;38525;38889;39873.80063099666;40108.59891401049;36443.27113457769;36619.63380366269;37029.81485092557;37372.25255494929;37925.620743792206;32474.83762581172;33766.66233337635;33785.66214337923;33850.66149338475;33923.66076339205;21282.57434851325;23685.881570592035;23837.808109594796;24229.636555452034;25018.874905626082 11:22:11 INFO - PROCESS | 1665 | |2;jquery.html;341.63615749227057;363.33872352318775;365.30591875436664;369.6857670979668;394.9901252468688;15605.609859753511;15950.840491595081;16832.579185520368;20616.484587885312;20630.86530240836;15919.522414327559;20402.795972040276;20722.27472038479;20902.686459703116;21164.576708465822;5457.334382501463;6003.729832157553;6158.815235542918;6188.381161883821;6188.969055154718;4183;4459.554044595547;6089.2692876854835;6131;6475.255345635242;6718.899216511756;6795.558288711218;6802.863942721143;6819.727210911557;6838.281980392062;6503.967480162616;6753.257141714383;6778.084958530584;6785.796426107225;6788.456923446112;5156.871078223037;5418.431064738224;5865.58940874139;5866.941330586682;5870.882582348372;12696.428660710199;12816.679583010406;12851.614451566467;12854.357282135856;19421.417357479157;2216.180013395038;2247.6403775395856;2259.7027902932696;2399.9400014999765;2819.8156774154904;9758.707238782848;9827.312088153833;10001.199904007663;10256.43589602579;10313.587456501731;1763.6560870630233;1860.2001139510005;1874.3158747057362;1919.500929758259;1919.6928491441302;105084.47457762662;113519.86480135175;116502.39677904783;117941;118861.40569297098;10670.626528071636;11368.772624547628;11596.130290228266;11678.416079196006;11704.590339338083;14609.196494192709;14609.707805843824;14619.122852628876;14625.707485850224;14648.487302944346;8169.142240064826;8490.617922193516;8503.829923401498;8557.358198135165;8607.526586037828;6822.488313376517;6984.79045628632;8336.166383361615;8350.540720260444;8548.658053677907;3798.962010379916;4469.128519938646;4476.820927162877;4482;4502.932456013163;1727.2486468386292;1812.2750899639987;1872.8127187281164;1912.5696718238564;1914.7127930810211;835.4319063037071;866.8396346675885;891.4072142025481;894.2309613732185;923.612082925173;16712.916435417745;18973.33593324227;21780;21804.781952180594;21909.794961276963;912.589334799343;924.7318277699392;934.45334479329;934.4580143516744;975.4683697384942;7611;9686.903130968742;10980.560777568808;11475.942620286845;11765.294082354914;553985;574681;605630;605957;611565.2482588617;130817.34591326982;134986;139996;141377;141937.29031354983;1011.4184344002228;1019.6788011776267;1022.4734261855151;1049.0610903241557;1050.112654806687;19054.904725476285;19859.63088120611;19965.311426853885;20310.438571942803;20382.28661996823;43869.12261754683;48404.7579762113;49615.75192124161;52080.21879671732;53402.31193841401;62937;69276;70595;71185;73736.63131684308;5493.121100623881;6737.023131645982;8035.718749843727;8054.959725201336;8415.915840841513;11940.820887686865;12566.937165314115;12796.296203708886;12930.612081637566;13137.868621313664;8931.240844528287;10371.274010819472;10799.352038877692;10862.837057443987;11163.274387164809;10803.189760768131;10877.129829613454;10977.231593788358;11216.495257713259;11274.492647830702;11255;11351.262167959056;11432;11599.130065244972;11691.473883675426;7960.283574478325;8067.798305042186;8069.838603227785;8106.270435660819;8287.834243315221;8248.670053197804;10635.255532112678;11169.497372618414;11186.216964812385;11357.37534435614 11:22:11 INFO - PROCESS | 1665 | |3;mootools.html;934.9672761453346;1037.8028174646818;1157.4502111497045;1162.3084264862405;1265.3420221484828;8252;8881.555922203888;8907.376483646147;8927.866082008759;8937.776555586106;7803.60981950903;8009.719659811908;8021.077576078753;8143.307818835391;8712.869306960401;2171.5873983943;2336.100601268515;2379.393254720044;2425.8180636452335;2435.866027368494;2415.384077060356;2416;2416.3838221253554;2417.0331867253153;2417.359399759065;2360.8819559022068;2415.9879200603973;2421.624648179535;2426.162973774054;2427.647991041307;2392.653065305521;2406.89168987396;2418.4195793009553;2426.46617744096;2427.647991041307;2388.558116748407;2396.2332053742807;2405.6992875890514;2417.5164967006494;2425.0542288507495;8796.912030879674;8879.866801997976;8917.33120015995;8928;8954.910450895473;5261.76322065508;5418.106012507931;5430.5384042356445;5431.375391829911;5444.40111587725;3524.0661224775454;3650.3064417760543;3659.1949771050326;3709.0356507308293;3722.3113723960882;1045.644480876498;1078.6032088445481;1093.2019625673224;1103.2112039891515;1106.1427393769811;11576.768464630744;11586.768264634744;11595.94202028993;11599.88400116005;11631.30212187263;4629.907401851944;4699.78850951708;4849.593617850855;4875.68308059975;4940.802367905316;9087.591058402388;9141.680041198526;9151.496667683208;9156.175944165057;9164.633414663338;5266.078436273637;5271.314729085195;5271.920921186185;5277.709725965109;5283.260343551906;4725.527447255247;4849.4908034656555;4929.876753081202;4949.455559888409;4955.975220123948;3111.393278310753;3536.3457760314423;3638.0359204810748;3660.7803531788177;3673.246984368198;1135.5798354608683;1182.8048372018693;1184.988150118505;1191.4876603060768;1193.5941779794912;744.2148533297424;853.6457370191425;882.955852207387;890.2699786175274;932.6709439049328;5393.622446428791;5619.634723742944;5828.038373668382;5895.263092113486;6144.692765361714;918.169057003413;926.3561824531886;928.2202949522433;940.2243149401634;970.815545046453;3585.402858739879;3715.64701353371;3822.235552889433;3834.213986132836;3978.980105099514;10336.224783141295;10739.624113156002;10758.300710453796;10764.192685548614;10772;10042.949785251027;10510.527026283835;10705.46472676363;10718.892811071946;10721.678349649523;8005.359571234288;8192.959035204905;8206.958965205136;8212.014558253048;8224.62989165489;5787.913181302368;5796.101604251347;5798.536117110705;6053.6283891546345;6098.573099882966;9631.518424078628;10080;10084.647037353658;10100.98990100984;10108.84836727435;8480.83038339242;10220.386776793417;10254.6410875619;10290.845637315297;10315.638952636622;7491;7610.733624323344;7612.657430415756;7614.809629759079;7631.69473221065;8144.714934977247;8151.266386025286;8230.259276665129;8260.256576908107;8336;6095.908561371672;7383.187849336461;7427.82922860267;7514.248575142442;7596.354309883722;7357.1907090218965;7402.3707984821895;7403.296686814744;7446.590437525988;7460.142083660448;6885.965570172117;6934.271901450275;6943.618101004493;6974.6861391238535;7010.614416207158;4867.221244600847;4939.678920870132;4975.9004819902675;4979.3526841510375;5116.769745361542;4093.4473846030405;4763.809447622057;4778.546038126373;4810.254410566367;4871.318015477765 11:22:11 INFO - PROCESS | 1665 | |4;prototype.html;293.45856894030516;293.91623387334613;302.35447319971854;305.0634551925588;310.399377205108;5399.2441058251825;5440.782368705252;5468.398476167618;5494.966862260969;5519.654146987525;4291.613754762071;4378.365137055125;4719.4100737407825;5045.192769156935;5400.513953744162;3520.3839328117606;3777.8866634001024;3803.7067397084984;3874.9225015499674;4123.247619761604;3495.493153492742;4108.219438306712;4133.648639865615;4169.228692691847;4177.33162693969;3369.8315084245814;3754.0051886250167;3839.5008648875746;4107.835686572534;4131.834726610947;3553.378158822222;4058.4318195452806;4075.735077219971;4094.6724262058965;4099.9180016399505;3531.5408996830506;3543.114221444639;3556.608773034964;3558.7508874378545;3574.6782789549065;4833.371661683994;5211.843644690665;5252.395974462947;5267.657602255841;5290.894182116336;4579.22153233951;4592.402987611623;4613.561711637389;4644.210484217691;4666.486686464486;5054.545090941833;5526.861828454281;5663.4336566343445;5675.574331925122;5775.49837042372;3401.115709915415;3772.4152756322815;3801.5248093988253;3822.751521151144;3832.2335532893535;4691.83578574755;5098.974505127488;5275.762590683428;5923.229980102602;5953.940460595425;3876.321643712339;3877.2245550889934;3879.321118804198;3879.553851307079;3882.8058597070035;3772.6227377262603;3932.311845427096;3940;3957.5053118360206;3965.7620542767527;4174.666026717855;4415.2935530315;4591.44902611682;4606.124836281096;4646.814127434933;5214.0614689355525;5287.101192797234;5658.91511627326;5660.848877426634;5693.544516438675;1196.2403873540366;1245.3773113443278;1267.7464507098432;1315.224017829466;1347.6630842289428;801.2548330053106;846.8264005878904;851.9733720866341;881.7619242804407;906.1708536688856;4596.632269418438;5188.9740551297;5229.790808367623;5233.293505376725;5249.451411833518;5484.533814625722;5684.857878553069;5712.857178570486;5717.628354156966;5750.712464376765;4699;4833.081714474239;4879.878003049881;5142.460041695568;5176.3270774799785;2868.770498360168;2992.431438026768;2997.28065264335;3019.758419326449;3028.9242768930512;5587.552995760329;5917.940820591825;5924.896661685126;5925.703714814242;5976.910346344808;4946.802127914843;5469.741959349292;5615.831525054255;5737.483626473638;5860.882782344329;5353.973230133903;5576.052071147915;5915.349311575809;5921.378255283219;5956.493698035629;4806;5045.763787871995;5267.051930652519;5668.489835914787;5697.943020569741;4845.5881250094135;5122.795088196431;5372.597055220952;5548.167774833807;5684.118961560965;5010.398752149766;5923.822285331447;5929.555283353852;5959.851003724942;5973.253343332083;3294.374068926896;3638.69071128957;3825.6365645263654;3864.2078373932713;3902.843886244518;5671.97904377216;5716.6284191529085;5724.570657200643;5729.62757420783;5735.942640573541;4763.809447622057;5559.610827242054;5579.74891129892;5591.496765291144;5611.6352437091455;4057.4522439470293;5371.946280537301;5476.123820188751;5518.089515229942;5643.984082864959;5033.597312215087;5402.405735369028;5424.945750542602;5430.375506816608;5435.755391007493;4503.797329120263;4825.914169311876;5563.248961390161;5575.972120139373;5579.302587176603;4970.453250142409;5542.8337149885565;5563.8887222256135;5573.3869274380595;5586.357568879468 11:22:11 INFO - PROCESS | 1665 | |5;yui.html;766.9271419215172;790.284792263002;801.5831767480906;810.7851419373867;851.1318455175717;14721.190334531597;15389.923050384761;15405.614859628487;15422.151781652005;15440.84559154408;14528.128312301269;14635.63410914725;14664.193469359208;14683.926580367082;14728.6317842054;675.665545554951;689.2246222999127;829.9585020748968;839.039300001498;844.4089137603702;850.1201256699318;851.7572491839827;864.2826454043129;865.3985480091308;865.8268346330727;918.3663272342096;919.7654598077481;920.4201353147507;921.1341339141219;923.2613908872909;706.5795851468368;885.1590988560829;911.2026976395654;915.1534830282029;915.9679411220575;562.7748900439857;678.7624331483942;679.2019377231783;679.4157024958532;679.8334408070006;14364.35360408784;14378.496752613608;14392.496262630757;14394.496192633207;14397.352119154662;12766.36168191596;13799.724005519935;14006.74200686082;14013.159210447304;14024.789628155586;11954.58158964432;12168.756624867541;12237.265764054097;12265.57070502528;12786.808197877039;4500.257457519506;4536.092781443692;4645.210314246552;4649.674522783406;4649.976750116261;113650;117110;117975.41012294969;118935;118957;10550.89449105499;10902.236843421044;11190.104791616652;11274.041706454878;11422.485988130555;21703.891480542497;21705.67441488379;21709;21720.891395543236;21760;7693.07683078023;7893;7905.090914544786;7989.400794940403;8007.239312265326;3678.9080272993388;4091.5703851095204;4174.039970806671;4283.528811830696;4396.582324679151;3435.1927297085167;3504.8948531544092;3553.733469989761;3556.1643013891717;3559.6796288334176;638.1098367777018;645.7472503343498;647.9546431749826;660.4122331125302;661.1438187547138;468.85231152187635;592.5437413191818;624.96875156242;673.8753330633848;674.3930462583714;9070.727878163665;9361.204297634777;9540.379875308083;9562.61749530011;9694;643.1060825452624;655.6361219523116;661.1702313596405;673.1518286958468;674.4671709349559;4446.777661116931;4506.797194126272;4672.112298663243;4673.976630116827;4701.882452938704;156964.21517892566;165404;166127.3387266136;167184;171903;134859.65140348667;137120.31439842738;141315;141571.2921435407;145149;97724;117905;118609.8139018616;120849;122162.3891880535;8785.428947118417;8817.074207208407;8904.777380565538;8905.376623636283;9153.359264851397;63079.26905482016;63154.68422658041;63735.043974339496;65198.34801651923;68661.65669171623;85685.57157214174;98443.50778246063;99910.5004475002;102139.48930255302;104908.47545762222;12427.378631068592;13128.934355328543;13486.460541578226;13523.256220907944;14084;14691.727594840622;14966.775498367751;15186.35212475319;15362.120581296236;15567.532974010797;12180.817287740869;13753.598729276046;13945.721085578029;14343.928280358532;14810.851891480947;12216.816747748613;13389.263590502227;13724.65688357799;13818.516351927634;14270.357833897708;13397.330133493091;13795.862041379458;13917.443302267797;13924.860751392356;14247.287635618384;11001.284916480403;11387.316760994367;11389.772204556028;11394.316341019565;11481.942590286995;9449.669261575811;10577.471126443801;11293.604723834625;11521.638064196986;11667 11:22:11 INFO - PROCESS | 1665 | __end_tp_report 11:22:11 INFO - PROCESS | 1665 | __start_cc_report 11:22:11 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1436 11:22:11 INFO - PROCESS | 1665 | __end_cc_report 11:22:11 INFO - PROCESS | 1665 | __startTimestamp1473186131867__endTimestamp 11:22:11 INFO - PROCESS | 1665 | 11:22:11 INFO - PROCESS | 1665 | ------- Summary: start ------- 11:22:11 INFO - PROCESS | 1665 | Number of tests: 6 11:22:11 INFO - PROCESS | 1665 | 11:22:11 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5666.08 Median:5781.60 stddev:625.50 (10.8%) stddev-sans-first:598.01 11:22:11 INFO - PROCESS | 1665 | Values: 3249.9 3258.7 3301.7 3312.1 3364.4 4688.3 5491.2 5604.8 5638.9 5676.0 5601.5 5614.0 5648.3 5657.9 5718.9 5118.4 5167.7 5200.0 5205.7 5223.8 5126.9 5200.1 5248.7 5317.4 5320.7 5252.9 5270.3 5276.2 5297.5 5334.2 5108.3 5129.5 5142.6 5205.8 5221.0 5096.3 5103.5 5134.9 5166.7 5245.6 5636.4 5678.9 5693.2 5719.7 5748.7 6064.8 6138.6 6193.8 6207.4 6215.4 6167.9 6178.5 6205.0 6209.9 6271.2 5759.7 5762.5 5766.0 5772.2 5781.6 6121.8 6123.4 6193.3 6223.9 6249.4 6103.1 6118.5 6120.2 6122.8 6125.1 6142.3 6198.1 6204.2 6219.0 6225.6 5917.3 6030.3 6036.3 6159.3 6179.4 6104.5 6116.9 6117.2 6120.2 6184.1 4890.0 4900.8 4903.6 4925.8 4940.4 4393.9 4401.1 4405.1 4430.1 4453.6 5553.3 5558.8 5600.2 5621.7 5629.0 6182.2 6183.6 6187.7 6192.6 6194.8 5631.7 5645.6 5651.0 5661.2 5706.2 5272.3 5340.0 5347.7 5354.3 5364.2 6248.8 6276.4 6296.3 6318.0 6322.2 6166.3 6204.5 6207.2 6227.3 6239.8 6142.2 6148.0 6177.1 6199.2 6277.4 6105.2 6109.6 6113.8 6119.9 6133.2 5170.5 6127.6 6167.6 6185.3 6186.7 5102.4 5676.2 5853.6 5870.9 5901.9 5872.4 5908.6 5912.1 5915.3 5944.9 5694.1 5734.2 5750.5 5761.5 5770.0 5874.6 5898.8 5945.0 5956.7 5968.6 5899.5 5911.7 5912.3 5923.8 5981.4 11:22:11 INFO - PROCESS | 1665 | 11:22:11 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:73430.30 Median:3492.14 stddev:171672.20 (4916.0%) stddev-sans-first:172066.42 11:22:11 INFO - PROCESS | 1665 | Values: 482.0 491.0 536.3 536.9 558.4 30862.2 32950.2 32964.3 33000.8 41875.4 34045.3 44323.8 44383.8 44384.8 44465.6 9896.2 10100.8 10107.0 10124.7 10126.5 8862.0 9077.7 9740.5 9862.7 9875.0 9111.3 9176.6 9185.2 12030.2 12349.4 12314.3 12352.6 12394.3 12405.8 12457.5 11904.8 11993.6 12011.3 12019.0 12020.2 37025.7 41688.8 42102.9 42157.4 42183.8 3115.3 3322.7 3349.6 3453.9 3530.4 31716.7 32521.5 32673.0 32803.2 33172.3 3234.2 3252.4 3256.8 3361.2 3365.6 486580.0 486607.0 486781.0 487162.0 487269.0 33825.2 34432.8 34501.5 35337.8 62501.4 35757.5 35845.8 35880.5 35930.0 35941.3 14422.4 14590.0 14592.9 14593.9 14611.3 12296.6 12402.8 12410.0 12418.8 12453.3 7786.6 7793.9 7812.9 7877.7 7896.1 2827.2 3061.1 3148.6 3152.0 3190.6 965.5 974.3 979.4 1030.6 1038.3 29920.6 30249.0 30546.7 30718.5 30810.4 934.4 1014.1 1045.7 1066.4 1091.1 15225.2 15797.4 15851.0 15875.9 16120.7 686784.0 694385.0 694724.0 695003.0 697956.0 50243.2 59235.4 59877.0 59964.0 60054.0 1247.4 1345.6 1396.4 1397.8 1405.8 30992.5 31002.1 31605.7 31608.2 31739.4 51006.7 51535.5 51635.7 51676.7 51681.5 554937.0 556994.0 557462.0 877816.0 903133.0 26340.9 42275.6 42306.0 42710.0 42758.8 43754.3 45612.1 46678.8 47017.8 47247.0 38211.6 38302.0 38732.0 39722.0 40123.0 33579.8 38525.0 38889.0 39873.8 40108.6 36443.3 36619.6 37029.8 37372.3 37925.6 32474.8 33766.7 33785.7 33850.7 33923.7 21282.6 23685.9 23837.8 24229.6 25018.9 11:22:11 INFO - PROCESS | 1665 | 11:22:11 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:34224.99 Median:2253.67 stddev:98833.56 (4385.4%) stddev-sans-first:99078.06 11:22:11 INFO - PROCESS | 1665 | Values: 341.6 363.3 365.3 369.7 395.0 15605.6 15950.8 16832.6 20616.5 20630.9 15919.5 20402.8 20722.3 20902.7 21164.6 5457.3 6003.7 6158.8 6188.4 6189.0 4183.0 4459.6 6089.3 6131.0 6475.3 6718.9 6795.6 6802.9 6819.7 6838.3 6504.0 6753.3 6778.1 6785.8 6788.5 5156.9 5418.4 5865.6 5866.9 5870.9 12696.4 12816.7 12851.6 12854.4 19421.4 2216.2 2247.6 2259.7 2399.9 2819.8 9758.7 9827.3 10001.2 10256.4 10313.6 1763.7 1860.2 1874.3 1919.5 1919.7 105084.5 113519.9 116502.4 117941.0 118861.4 10670.6 11368.8 11596.1 11678.4 11704.6 14609.2 14609.7 14619.1 14625.7 14648.5 8169.1 8490.6 8503.8 8557.4 8607.5 6822.5 6984.8 8336.2 8350.5 8548.7 3799.0 4469.1 4476.8 4482.0 4502.9 1727.2 1812.3 1872.8 1912.6 1914.7 835.4 866.8 891.4 894.2 923.6 16712.9 18973.3 21780.0 21804.8 21909.8 912.6 924.7 934.5 934.5 975.5 7611.0 9686.9 10980.6 11475.9 11765.3 553985.0 574681.0 605630.0 605957.0 611565.2 130817.3 134986.0 139996.0 141377.0 141937.3 1011.4 1019.7 1022.5 1049.1 1050.1 19054.9 19859.6 19965.3 20310.4 20382.3 43869.1 48404.8 49615.8 52080.2 53402.3 62937.0 69276.0 70595.0 71185.0 73736.6 5493.1 6737.0 8035.7 8055.0 8415.9 11940.8 12566.9 12796.3 12930.6 13137.9 8931.2 10371.3 10799.4 10862.8 11163.3 10803.2 10877.1 10977.2 11216.5 11274.5 11255.0 11351.3 11432.0 11599.1 11691.5 7960.3 8067.8 8069.8 8106.3 8287.8 8248.7 10635.3 11169.5 11186.2 11357.4 11:22:11 INFO - PROCESS | 1665 | 11:22:11 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5640.06 Median:4940.24 stddev:3171.80 (64.2%) stddev-sans-first:3160.98 11:22:11 INFO - PROCESS | 1665 | Values: 935.0 1037.8 1157.5 1162.3 1265.3 8252.0 8881.6 8907.4 8927.9 8937.8 7803.6 8009.7 8021.1 8143.3 8712.9 2171.6 2336.1 2379.4 2425.8 2435.9 2415.4 2416.0 2416.4 2417.0 2417.4 2360.9 2416.0 2421.6 2426.2 2427.6 2392.7 2406.9 2418.4 2426.5 2427.6 2388.6 2396.2 2405.7 2417.5 2425.1 8796.9 8879.9 8917.3 8928.0 8954.9 5261.8 5418.1 5430.5 5431.4 5444.4 3524.1 3650.3 3659.2 3709.0 3722.3 1045.6 1078.6 1093.2 1103.2 1106.1 11576.8 11586.8 11595.9 11599.9 11631.3 4629.9 4699.8 4849.6 4875.7 4940.8 9087.6 9141.7 9151.5 9156.2 9164.6 5266.1 5271.3 5271.9 5277.7 5283.3 4725.5 4849.5 4929.9 4949.5 4956.0 3111.4 3536.3 3638.0 3660.8 3673.2 1135.6 1182.8 1185.0 1191.5 1193.6 744.2 853.6 883.0 890.3 932.7 5393.6 5619.6 5828.0 5895.3 6144.7 918.2 926.4 928.2 940.2 970.8 3585.4 3715.6 3822.2 3834.2 3979.0 10336.2 10739.6 10758.3 10764.2 10772.0 10042.9 10510.5 10705.5 10718.9 10721.7 8005.4 8193.0 8207.0 8212.0 8224.6 5787.9 5796.1 5798.5 6053.6 6098.6 9631.5 10080.0 10084.6 10101.0 10108.8 8480.8 10220.4 10254.6 10290.8 10315.6 7491.0 7610.7 7612.7 7614.8 7631.7 8144.7 8151.3 8230.3 8260.3 8336.0 6095.9 7383.2 7427.8 7514.2 7596.4 7357.2 7402.4 7403.3 7446.6 7460.1 6886.0 6934.3 6943.6 6974.7 7010.6 4867.2 4939.7 4975.9 4979.4 5116.8 4093.4 4763.8 4778.5 4810.3 4871.3 11:22:11 INFO - PROCESS | 1665 | 11:22:11 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:4510.06 Median:5050.15 stddev:1385.46 (27.4%) stddev-sans-first:1352.71 11:22:11 INFO - PROCESS | 1665 | Values: 293.5 293.9 302.4 305.1 310.4 5399.2 5440.8 5468.4 5495.0 5519.7 4291.6 4378.4 4719.4 5045.2 5400.5 3520.4 3777.9 3803.7 3874.9 4123.2 3495.5 4108.2 4133.6 4169.2 4177.3 3369.8 3754.0 3839.5 4107.8 4131.8 3553.4 4058.4 4075.7 4094.7 4099.9 3531.5 3543.1 3556.6 3558.8 3574.7 4833.4 5211.8 5252.4 5267.7 5290.9 4579.2 4592.4 4613.6 4644.2 4666.5 5054.5 5526.9 5663.4 5675.6 5775.5 3401.1 3772.4 3801.5 3822.8 3832.2 4691.8 5099.0 5275.8 5923.2 5953.9 3876.3 3877.2 3879.3 3879.6 3882.8 3772.6 3932.3 3940.0 3957.5 3965.8 4174.7 4415.3 4591.4 4606.1 4646.8 5214.1 5287.1 5658.9 5660.8 5693.5 1196.2 1245.4 1267.7 1315.2 1347.7 801.3 846.8 852.0 881.8 906.2 4596.6 5189.0 5229.8 5233.3 5249.5 5484.5 5684.9 5712.9 5717.6 5750.7 4699.0 4833.1 4879.9 5142.5 5176.3 2868.8 2992.4 2997.3 3019.8 3028.9 5587.6 5917.9 5924.9 5925.7 5976.9 4946.8 5469.7 5615.8 5737.5 5860.9 5354.0 5576.1 5915.3 5921.4 5956.5 4806.0 5045.8 5267.1 5668.5 5697.9 4845.6 5122.8 5372.6 5548.2 5684.1 5010.4 5923.8 5929.6 5959.9 5973.3 3294.4 3638.7 3825.6 3864.2 3902.8 5672.0 5716.6 5724.6 5729.6 5735.9 4763.8 5559.6 5579.7 5591.5 5611.6 4057.5 5371.9 5476.1 5518.1 5644.0 5033.6 5402.4 5424.9 5430.4 5435.8 4503.8 4825.9 5563.2 5576.0 5579.3 4970.5 5542.8 5563.9 5573.4 5586.4 11:22:11 INFO - PROCESS | 1665 | 11:22:11 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:26281.87 Median:3470.04 stddev:43051.67 (1240.7%) stddev-sans-first:43129.81 11:22:11 INFO - PROCESS | 1665 | Values: 766.9 790.3 801.6 810.8 851.1 14721.2 15389.9 15405.6 15422.2 15440.8 14528.1 14635.6 14664.2 14683.9 14728.6 675.7 689.2 830.0 839.0 844.4 850.1 851.8 864.3 865.4 865.8 918.4 919.8 920.4 921.1 923.3 706.6 885.2 911.2 915.2 916.0 562.8 678.8 679.2 679.4 679.8 14364.4 14378.5 14392.5 14394.5 14397.4 12766.4 13799.7 14006.7 14013.2 14024.8 11954.6 12168.8 12237.3 12265.6 12786.8 4500.3 4536.1 4645.2 4649.7 4650.0 113650.0 117110.0 117975.4 118935.0 118957.0 10550.9 10902.2 11190.1 11274.0 11422.5 21703.9 21705.7 21709.0 21720.9 21760.0 7693.1 7893.0 7905.1 7989.4 8007.2 3678.9 4091.6 4174.0 4283.5 4396.6 3435.2 3504.9 3553.7 3556.2 3559.7 638.1 645.7 648.0 660.4 661.1 468.9 592.5 625.0 673.9 674.4 9070.7 9361.2 9540.4 9562.6 9694.0 643.1 655.6 661.2 673.2 674.5 4446.8 4506.8 4672.1 4674.0 4701.9 156964.2 165404.0 166127.3 167184.0 171903.0 134859.7 137120.3 141315.0 141571.3 145149.0 97724.0 117905.0 118609.8 120849.0 122162.4 8785.4 8817.1 8904.8 8905.4 9153.4 63079.3 63154.7 63735.0 65198.3 68661.7 85685.6 98443.5 99910.5 102139.5 104908.5 12427.4 13128.9 13486.5 13523.3 14084.0 14691.7 14966.8 15186.4 15362.1 15567.5 12180.8 13753.6 13945.7 14343.9 14810.9 12216.8 13389.3 13724.7 13818.5 14270.4 13397.3 13795.9 13917.4 13924.9 14247.3 11001.3 11387.3 11389.8 11394.3 11481.9 9449.7 10577.5 11293.6 11521.6 11667.0 11:22:11 INFO - PROCESS | 1665 | -------- Summary: end -------- 11:22:11 INFO - PROCESS | 1665 | 11:22:12 INFO - TEST-INFO | 1665: exit 0 11:22:12 INFO - TEST-OK | dromaeo_css | took 1118229ms 11:22:12 INFO - TEST-START | kraken 11:22:12 INFO - operating with platform_type : mac_ 11:22:12 INFO - Initialising browser for kraken test... 11:22:12 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpKPp0Bz/profile http://localhost:49269/getInfo.html) 11:22:14 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 11:22:14 INFO - PROCESS | 1669 | colorDepth:24 11:22:14 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 11:22:14 INFO - PROCESS | 1669 | __metrics 11:22:14 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:22:14 INFO - PROCESS | 1669 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:22:15 INFO - TEST-INFO | 1669: exit 0 11:22:15 INFO - Browser initialized. 11:22:15 INFO - Running cycle 1/1 for kraken test... 11:22:15 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpKPp0Bz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:22:27 INFO - PROCESS | 1670 | RSS: Main: 232243200 11:22:27 INFO - PROCESS | 1670 | 11:22:30 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection) 11:22:31 INFO - PROCESS | 1670 | RSS: Main: 291270656 11:22:31 INFO - PROCESS | 1670 | 11:22:33 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-dft) 11:22:34 INFO - PROCESS | 1670 | RSS: Main: 287707136 11:22:34 INFO - PROCESS | 1670 | 11:22:37 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-fft) 11:22:38 INFO - PROCESS | 1670 | RSS: Main: 376115200 11:22:38 INFO - PROCESS | 1670 | 11:22:40 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator) 11:22:41 INFO - PROCESS | 1670 | RSS: Main: 279117824 11:22:41 INFO - PROCESS | 1670 | 11:22:43 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:22:44 INFO - PROCESS | 1670 | RSS: Main: 284151808 11:22:44 INFO - PROCESS | 1670 | 11:22:46 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom) 11:22:47 INFO - PROCESS | 1670 | RSS: Main: 322904064 11:22:47 INFO - PROCESS | 1670 | 11:22:50 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate) 11:22:51 INFO - PROCESS | 1670 | RSS: Main: 322867200 11:22:51 INFO - PROCESS | 1670 | 11:22:53 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial) 11:22:54 INFO - PROCESS | 1670 | RSS: Main: 321572864 11:22:54 INFO - PROCESS | 1670 | 11:22:56 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:22:56 INFO - PROCESS | 1670 | RSS: Main: 297914368 11:22:56 INFO - PROCESS | 1670 | 11:22:58 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:22:59 INFO - PROCESS | 1670 | RSS: Main: 304340992 11:22:59 INFO - PROCESS | 1670 | 11:23:01 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:23:02 INFO - PROCESS | 1670 | RSS: Main: 302366720 11:23:02 INFO - PROCESS | 1670 | 11:23:05 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:23:05 INFO - PROCESS | 1670 | RSS: Main: 311431168 11:23:05 INFO - PROCESS | 1670 | 11:23:08 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:23:09 INFO - PROCESS | 1670 | RSS: Main: 312934400 11:23:09 INFO - PROCESS | 1670 | 11:23:11 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49269/tests/kraken/driver.html?testName=ai-astar) 11:23:11 INFO - PROCESS | 1670 | RSS: Main: 356634624 11:23:11 INFO - PROCESS | 1670 | 11:23:11 INFO - PROCESS | 1670 | __start_tp_report 11:23:11 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 11:23:11 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 11:23:11 INFO - PROCESS | 1670 | |i|pagename|runs| 11:23:11 INFO - PROCESS | 1670 | |0;ai-astar;114;114;117;113;113;114;113;113;113;122 11:23:11 INFO - PROCESS | 1670 | |1;audio-beat-detection;158;126;160;161;108;129;127;106;160;106 11:23:11 INFO - PROCESS | 1670 | |2;audio-dft;186;186;186;183;183;183;183;178;181;188 11:23:11 INFO - PROCESS | 1670 | |3;audio-fft;72;79;75;157;58;58;59;64;142;75 11:23:11 INFO - PROCESS | 1670 | |4;audio-oscillator;83;83;132;96;99;121;117;67;70;119 11:23:11 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;88;84;88;85;88;85;91;86;88;84 11:23:11 INFO - PROCESS | 1670 | |6;imaging-darkroom;92;92;103;147;89;143;145;93;93;92 11:23:11 INFO - PROCESS | 1670 | |7;imaging-desaturate;96;141;130;84;85;84;84;86;85;84 11:23:11 INFO - PROCESS | 1670 | |8;json-parse-financial;55;54;56;52;52;55;82;57;54;54 11:23:11 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;67;65;79;75;73;71;70;69;71;70 11:23:11 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;86;83;85;86;82;89;84;86;85;86 11:23:11 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;138;143;140;139;137;141;152;156;192;138 11:23:11 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;172;172;171;172;172;172;173;170;172;171 11:23:11 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;67;67;69;68;69;67;67;67;67;67 11:23:11 INFO - PROCESS | 1670 | __end_tp_report 11:23:11 INFO - PROCESS | 1670 | __start_cc_report 11:23:11 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,2046 11:23:11 INFO - PROCESS | 1670 | __end_cc_report 11:23:11 INFO - PROCESS | 1670 | __startTimestamp1473186191267__endTimestamp 11:23:11 INFO - PROCESS | 1670 | 11:23:11 INFO - PROCESS | 1670 | ------- Summary: start ------- 11:23:11 INFO - PROCESS | 1670 | Number of tests: 14 11:23:11 INFO - PROCESS | 1670 | 11:23:11 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:114.60 Median:114.00 stddev:2.88 (2.5%) stddev-sans-first:3.04 11:23:11 INFO - PROCESS | 1670 | Values: 114.0 114.0 117.0 113.0 113.0 114.0 113.0 113.0 113.0 122.0 11:23:11 INFO - PROCESS | 1670 | 11:23:11 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:134.10 Median:143.50 stddev:23.66 (16.5%) stddev-sans-first:23.46 11:23:11 INFO - PROCESS | 1670 | Values: 158.0 126.0 160.0 161.0 108.0 129.0 127.0 106.0 160.0 106.0 11:23:11 INFO - PROCESS | 1670 | 11:23:11 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.50 stddev:2.91 (1.6%) stddev-sans-first:2.96 11:23:11 INFO - PROCESS | 1670 | Values: 186.0 186.0 186.0 183.0 183.0 183.0 183.0 178.0 181.0 188.0 11:23:11 INFO - PROCESS | 1670 | 11:23:11 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:83.90 Median:68.00 stddev:35.59 (52.3%) stddev-sans-first:37.49 11:23:11 INFO - PROCESS | 1670 | Values: 72.0 79.0 75.0 157.0 58.0 58.0 59.0 64.0 142.0 75.0 11:23:11 INFO - PROCESS | 1670 | 11:23:11 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:98.70 Median:76.50 stddev:22.81 (29.8%) stddev-sans-first:23.47 11:23:11 INFO - PROCESS | 1670 | Values: 83.0 83.0 132.0 96.0 99.0 121.0 117.0 67.0 70.0 119.0 11:23:11 INFO - PROCESS | 1670 | 11:23:11 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:88.00 stddev:2.26 (2.6%) stddev-sans-first:2.35 11:23:11 INFO - PROCESS | 1670 | Values: 88.0 84.0 88.0 85.0 88.0 85.0 91.0 86.0 88.0 84.0 11:23:11 INFO - PROCESS | 1670 | 11:23:11 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:108.90 Median:92.00 stddev:25.19 (27.4%) stddev-sans-first:25.97 11:23:11 INFO - PROCESS | 1670 | Values: 92.0 92.0 103.0 147.0 89.0 143.0 145.0 93.0 93.0 92.0 11:23:11 INFO - PROCESS | 1670 | 11:23:11 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:84.50 stddev:21.34 (25.3%) stddev-sans-first:22.64 11:23:11 INFO - PROCESS | 1670 | Values: 96.0 141.0 130.0 84.0 85.0 84.0 84.0 86.0 85.0 84.0 11:23:11 INFO - PROCESS | 1670 | 11:23:11 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:55.00 stddev:8.89 (16.2%) stddev-sans-first:9.39 11:23:11 INFO - PROCESS | 1670 | Values: 55.0 54.0 56.0 52.0 52.0 55.0 82.0 57.0 54.0 54.0 11:23:11 INFO - PROCESS | 1670 | 11:23:11 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.00 stddev:3.97 (5.6%) stddev-sans-first:3.94 11:23:11 INFO - PROCESS | 1670 | Values: 67.0 65.0 79.0 75.0 73.0 71.0 70.0 69.0 71.0 70.0 11:23:11 INFO - PROCESS | 1670 | 11:23:11 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:86.00 stddev:1.93 (2.2%) stddev-sans-first:2.03 11:23:11 INFO - PROCESS | 1670 | Values: 86.0 83.0 85.0 86.0 82.0 89.0 84.0 86.0 85.0 86.0 11:23:11 INFO - PROCESS | 1670 | 11:23:11 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:147.60 Median:142.00 stddev:16.85 (11.9%) stddev-sans-first:17.51 11:23:11 INFO - PROCESS | 1670 | Values: 138.0 143.0 140.0 139.0 137.0 141.0 152.0 156.0 192.0 138.0 11:23:11 INFO - PROCESS | 1670 | 11:23:11 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.70 Median:172.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 11:23:11 INFO - PROCESS | 1670 | Values: 172.0 172.0 171.0 172.0 172.0 172.0 173.0 170.0 172.0 171.0 11:23:11 INFO - PROCESS | 1670 | 11:23:11 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 11:23:11 INFO - PROCESS | 1670 | Values: 67.0 67.0 69.0 68.0 69.0 67.0 67.0 67.0 67.0 67.0 11:23:11 INFO - PROCESS | 1670 | -------- Summary: end -------- 11:23:11 INFO - PROCESS | 1670 | 11:23:12 INFO - TEST-INFO | 1670: exit 0 11:23:12 INFO - TEST-OK | kraken | took 59727ms 11:23:12 INFO - SUITE-END | took 1177s 11:23:12 INFO - Completed test suite (00:19:38) 11:23:12 INFO - javascript benchmark 11:23:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3249.9025029249115, 3258.674132586741, 3301.702846743794, 3312.1222875937865, 3364.428047231972, 4688.320193571934, 5491.203775452557, 5604.831855044345, 5638.943610563893, 5676.0, 5601.467860553251, 5613.971930140354, 5648.265725455685, 5657.8585535361635, 5718.914216286759, 5118.360204974379, 5167.689938603696, 5200.037992971313, 5205.713685747282, 5223.764930578133, 5126.948730512703, 5200.063988482072, 5248.711320877369, 5317.441668624797, 5320.654157479771, 5252.921206181911, 5270.314859068335, 5276.2085687146855, 5297.523222909937, 5334.199870019489, 5108.310378098983, 5129.487051294878, 5142.61430392722, 5205.843824685266, 5221.0, 5096.337476128079, 5103.540681338697, 5134.897302053975, 5166.715830629314, 5245.632805703603, 5636.351819540764, 5678.949394362056, 5693.202951586781, 5719.656820590736, 5748.7125643717645, 6064.848378790522, 6138.600990935575, 6193.783217587408, 6207.441330280297, 6215.378462153793, 6167.938320616781, 6178.505719542426, 6204.9689751551405, 6209.937900620981, 6271.216097987752, 5759.712014399263, 5762.538996880239, 5765.990951583498, 5772.2207501986695, 5781.595288329777, 6121.816345509642, 6123.404636889863, 6193.287771906288, 6223.937760622426, 6249.375062493714, 6103.115048317969, 6118.479929205978, 6120.17287048654, 6122.7550897964475, 6125.050617154348, 6142.324344322121, 6198.101275315054, 6204.193454850931, 6219.036049412348, 6225.62646241227, 5917.349091599921, 6030.306514750772, 6036.3360030396625, 6159.291681456601, 6179.3820617938745, 6104.4505994460715, 6116.944513560587, 6117.15760190703, 6120.234970628671, 6184.103305020747, 4890.021995600894, 4900.754962251873, 4903.632227582945, 4925.7783399747095, 4940.431850337185, 4393.934090988638, 4401.119776044804, 4405.0969551241915, 4430.13612345596, 4453.643708503377, 5553.250311207935, 5558.805441809517, 5600.159976003551, 5621.662700237999, 5628.97185514078, 6182.165407669907, 6183.598066125687, 6187.721552530147, 6192.5974811638, 6194.81415557534, 5631.71841407928, 5645.604807663506, 5651.0, 5661.235733176051, 5706.201131841545, 5272.340957380327, 5340.038793017216, 5347.652402593819, 5354.250404943311, 5364.249005139283, 6248.750049997949, 6276.372362763687, 6296.33888441707, 6317.989121740591, 6322.178116844781, 6166.290876549254, 6204.5036397089025, 6207.224096987877, 6227.252729672468, 6239.78160764371, 6142.232220972379, 6148.016317389196, 6177.073438984188, 6199.225096862892, 6277.372262773686, 6105.247473117015, 6109.61901919832, 6113.841195312776, 6119.898632333015, 6133.202683651097, 5170.457102004386, 6127.637555910542, 6167.5682702210415, 6185.2886918003205, 6186.721597528032, 5102.36220472441, 5676.176954341515, 5853.64878107315, 5870.882582348415, 5901.940980590139, 5872.383399742966, 5908.645481271137, 5912.113183022581, 5915.319738230159, 5944.8811023780145, 5694.088945768657, 5734.2258795062135, 5750.511206547323, 5761.48146666802, 5770.047942089676, 5874.647521148745, 5898.793542226002, 5945.0, 5956.672383018976, 5968.612040217372, 5899.528037756883, 5911.704414779158, 5912.349641539341, 5923.7926672566255, 5981.3719559445635], "value": 5626.321542868304, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.96144308455325, 490.99018019639607, 536.3188750287133, 536.9033573956689, 558.3662543013678, 30862.228444288878, 32950.176245593844, 32964.340713185724, 33000.83499582499, 41875.371869421986, 34045.31909361811, 44323.77838110805, 44383.77808110963, 44384.77807610966, 44465.55534444655, 9896.158826499755, 10100.848487272697, 10107.039831216023, 10124.746881327988, 10126.54430550627, 8862.0, 9077.727668169966, 9740.51297435129, 9862.654807081753, 9874.9506252469, 9111.316651251149, 9176.632934682604, 9185.219256363218, 12030.157888947733, 12349.382530873465, 12314.261144331369, 12352.62942111738, 12394.256344619353, 12405.813912791315, 12457.501699932038, 11904.761904761855, 11993.580224692181, 12011.339376334297, 12019.0, 12020.158588898781, 37025.70410035636, 41688.79155604233, 42102.947426314284, 42157.367639485434, 42183.7890810544, 3115.2679120406688, 3322.732046247489, 3349.5980482342275, 3453.913652158716, 3530.3645343838098, 31716.682833171835, 32521.51217731736, 32673.019809405756, 32803.22762882275, 33172.3365532688, 3234.15911862915, 3252.414565378231, 3256.804591724504, 3361.210115622827, 3365.562476878015, 486580.0, 486607.0, 486781.0, 487162.0, 487269.0, 33825.15437114042, 34432.82783586066, 34501.48247776285, 35337.82331088328, 62501.37498625046, 35757.46363804493, 35845.8207708965, 35880.461793073126, 35930.0, 35941.28117437637, 14422.350994205286, 14590.0, 14592.854071459149, 14593.85406145925, 14611.342489587994, 12296.569620063434, 12402.81395779064, 12410.007199424024, 12418.81371779424, 12453.25280483174, 7786.649600767979, 7793.883091753628, 7812.882806757903, 7877.684892604231, 7896.091949425775, 2827.2366461055813, 3061.1122774395176, 3148.5906832111677, 3151.952720709191, 3190.6171259449015, 965.5365424596092, 974.2741657465234, 979.3829887171037, 1030.6032177611673, 1038.2991480750463, 29920.551191732142, 30249.0, 30546.694533054386, 30718.539221911687, 30810.383792324028, 934.4066517761271, 1014.0923873133504, 1045.6810672744887, 1066.4081434803759, 1091.1161958813386, 15225.162616056223, 15797.447089351817, 15850.96968697031, 15875.920620397055, 16120.677586448206, 686784.0, 694385.0, 694724.0, 695003.0, 697956.0, 50243.24635130476, 59235.40764592299, 59877.0, 59964.0, 60054.0, 1247.4012474012525, 1345.6299517632533, 1396.4274647394925, 1397.82527184102, 1405.7750759878575, 30992.53511197379, 31002.0699379019, 31605.683943161195, 31608.209794755316, 31739.41302934798, 51006.74496627493, 51535.48464515457, 51635.741821290656, 51676.74161629168, 51681.483185167664, 554937.0, 556994.0, 557462.0, 877816.0, 903133.0, 26340.868295658398, 42275.57724422839, 42306.0, 42710.0, 42758.78620606877, 43754.34368484412, 45612.08775824531, 46678.76660616811, 47017.76491117659, 47247.0, 38211.61788382192, 38302.0, 38732.03169920774, 39722.0, 40123.0, 33579.83210083934, 38525.0, 38889.0, 39873.80063099666, 40108.59891401049, 36443.27113457769, 36619.63380366269, 37029.81485092557, 37372.25255494929, 37925.620743792206, 32474.83762581172, 33766.66233337635, 33785.66214337923, 33850.66149338475, 33923.66076339205, 21282.57434851325, 23685.881570592035, 23837.808109594796, 24229.636555452034, 25018.874905626082], "value": 19052.97777677257, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.63615749227057, 363.33872352318775, 365.30591875436664, 369.6857670979668, 394.9901252468688, 15605.609859753511, 15950.840491595081, 16832.579185520368, 20616.484587885312, 20630.86530240836, 15919.522414327559, 20402.795972040276, 20722.27472038479, 20902.686459703116, 21164.576708465822, 5457.334382501463, 6003.729832157553, 6158.815235542918, 6188.381161883821, 6188.969055154718, 4183.0, 4459.554044595547, 6089.2692876854835, 6131.0, 6475.255345635242, 6718.899216511756, 6795.558288711218, 6802.863942721143, 6819.727210911557, 6838.281980392062, 6503.967480162616, 6753.257141714383, 6778.084958530584, 6785.796426107225, 6788.456923446112, 5156.871078223037, 5418.431064738224, 5865.58940874139, 5866.941330586682, 5870.882582348372, 12696.428660710199, 12816.679583010406, 12851.614451566467, 12854.357282135856, 19421.417357479157, 2216.180013395038, 2247.6403775395856, 2259.7027902932696, 2399.9400014999765, 2819.8156774154904, 9758.707238782848, 9827.312088153833, 10001.199904007663, 10256.43589602579, 10313.587456501731, 1763.6560870630233, 1860.2001139510005, 1874.3158747057362, 1919.500929758259, 1919.6928491441302, 105084.47457762662, 113519.86480135175, 116502.39677904783, 117941.0, 118861.40569297098, 10670.626528071636, 11368.772624547628, 11596.130290228266, 11678.416079196006, 11704.590339338083, 14609.196494192709, 14609.707805843824, 14619.122852628876, 14625.707485850224, 14648.487302944346, 8169.142240064826, 8490.617922193516, 8503.829923401498, 8557.358198135165, 8607.526586037828, 6822.488313376517, 6984.79045628632, 8336.166383361615, 8350.540720260444, 8548.658053677907, 3798.962010379916, 4469.128519938646, 4476.820927162877, 4482.0, 4502.932456013163, 1727.2486468386292, 1812.2750899639987, 1872.8127187281164, 1912.5696718238564, 1914.7127930810211, 835.4319063037071, 866.8396346675885, 891.4072142025481, 894.2309613732185, 923.612082925173, 16712.916435417745, 18973.33593324227, 21780.0, 21804.781952180594, 21909.794961276963, 912.589334799343, 924.7318277699392, 934.45334479329, 934.4580143516744, 975.4683697384942, 7611.0, 9686.903130968742, 10980.560777568808, 11475.942620286845, 11765.294082354914, 553985.0, 574681.0, 605630.0, 605957.0, 611565.2482588617, 130817.34591326982, 134986.0, 139996.0, 141377.0, 141937.29031354983, 1011.4184344002228, 1019.6788011776267, 1022.4734261855151, 1049.0610903241557, 1050.112654806687, 19054.904725476285, 19859.63088120611, 19965.311426853885, 20310.438571942803, 20382.28661996823, 43869.12261754683, 48404.7579762113, 49615.75192124161, 52080.21879671732, 53402.31193841401, 62937.0, 69276.0, 70595.0, 71185.0, 73736.63131684308, 5493.121100623881, 6737.023131645982, 8035.718749843727, 8054.959725201336, 8415.915840841513, 11940.820887686865, 12566.937165314115, 12796.296203708886, 12930.612081637566, 13137.868621313664, 8931.240844528287, 10371.274010819472, 10799.352038877692, 10862.837057443987, 11163.274387164809, 10803.189760768131, 10877.129829613454, 10977.231593788358, 11216.495257713259, 11274.492647830702, 11255.0, 11351.262167959056, 11432.0, 11599.130065244972, 11691.473883675426, 7960.283574478325, 8067.798305042186, 8069.838603227785, 8106.270435660819, 8287.834243315221, 8248.670053197804, 10635.255532112678, 11169.497372618414, 11186.216964812385, 11357.37534435614], "value": 9300.295605452013, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [934.9672761453346, 1037.8028174646818, 1157.4502111497045, 1162.3084264862405, 1265.3420221484828, 8252.0, 8881.555922203888, 8907.376483646147, 8927.866082008759, 8937.776555586106, 7803.60981950903, 8009.719659811908, 8021.077576078753, 8143.307818835391, 8712.869306960401, 2171.5873983943, 2336.100601268515, 2379.393254720044, 2425.8180636452335, 2435.866027368494, 2415.384077060356, 2416.0, 2416.3838221253554, 2417.0331867253153, 2417.359399759065, 2360.8819559022068, 2415.9879200603973, 2421.624648179535, 2426.162973774054, 2427.647991041307, 2392.653065305521, 2406.89168987396, 2418.4195793009553, 2426.46617744096, 2427.647991041307, 2388.558116748407, 2396.2332053742807, 2405.6992875890514, 2417.5164967006494, 2425.0542288507495, 8796.912030879674, 8879.866801997976, 8917.33120015995, 8928.0, 8954.910450895473, 5261.76322065508, 5418.106012507931, 5430.5384042356445, 5431.375391829911, 5444.40111587725, 3524.0661224775454, 3650.3064417760543, 3659.1949771050326, 3709.0356507308293, 3722.3113723960882, 1045.644480876498, 1078.6032088445481, 1093.2019625673224, 1103.2112039891515, 1106.1427393769811, 11576.768464630744, 11586.768264634744, 11595.94202028993, 11599.88400116005, 11631.30212187263, 4629.907401851944, 4699.78850951708, 4849.593617850855, 4875.68308059975, 4940.802367905316, 9087.591058402388, 9141.680041198526, 9151.496667683208, 9156.175944165057, 9164.633414663338, 5266.078436273637, 5271.314729085195, 5271.920921186185, 5277.709725965109, 5283.260343551906, 4725.527447255247, 4849.4908034656555, 4929.876753081202, 4949.455559888409, 4955.975220123948, 3111.393278310753, 3536.3457760314423, 3638.0359204810748, 3660.7803531788177, 3673.246984368198, 1135.5798354608683, 1182.8048372018693, 1184.988150118505, 1191.4876603060768, 1193.5941779794912, 744.2148533297424, 853.6457370191425, 882.955852207387, 890.2699786175274, 932.6709439049328, 5393.622446428791, 5619.634723742944, 5828.038373668382, 5895.263092113486, 6144.692765361714, 918.169057003413, 926.3561824531886, 928.2202949522433, 940.2243149401634, 970.815545046453, 3585.402858739879, 3715.64701353371, 3822.235552889433, 3834.213986132836, 3978.980105099514, 10336.224783141295, 10739.624113156002, 10758.300710453796, 10764.192685548614, 10772.0, 10042.949785251027, 10510.527026283835, 10705.46472676363, 10718.892811071946, 10721.678349649523, 8005.359571234288, 8192.959035204905, 8206.958965205136, 8212.014558253048, 8224.62989165489, 5787.913181302368, 5796.101604251347, 5798.536117110705, 6053.6283891546345, 6098.573099882966, 9631.518424078628, 10080.0, 10084.647037353658, 10100.98990100984, 10108.84836727435, 8480.83038339242, 10220.386776793417, 10254.6410875619, 10290.845637315297, 10315.638952636622, 7491.0, 7610.733624323344, 7612.657430415756, 7614.809629759079, 7631.69473221065, 8144.714934977247, 8151.266386025286, 8230.259276665129, 8260.256576908107, 8336.0, 6095.908561371672, 7383.187849336461, 7427.82922860267, 7514.248575142442, 7596.354309883722, 7357.1907090218965, 7402.3707984821895, 7403.296686814744, 7446.590437525988, 7460.142083660448, 6885.965570172117, 6934.271901450275, 6943.618101004493, 6974.6861391238535, 7010.614416207158, 4867.221244600847, 4939.678920870132, 4975.9004819902675, 4979.3526841510375, 5116.769745361542, 4093.4473846030405, 4763.809447622057, 4778.546038126373, 4810.254410566367, 4871.318015477765], "value": 4502.6203228172635, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.45856894030516, 293.91623387334613, 302.35447319971854, 305.0634551925588, 310.399377205108, 5399.2441058251825, 5440.782368705252, 5468.398476167618, 5494.966862260969, 5519.654146987525, 4291.613754762071, 4378.365137055125, 4719.4100737407825, 5045.192769156935, 5400.513953744162, 3520.3839328117606, 3777.8866634001024, 3803.7067397084984, 3874.9225015499674, 4123.247619761604, 3495.493153492742, 4108.219438306712, 4133.648639865615, 4169.228692691847, 4177.33162693969, 3369.8315084245814, 3754.0051886250167, 3839.5008648875746, 4107.835686572534, 4131.834726610947, 3553.378158822222, 4058.4318195452806, 4075.735077219971, 4094.6724262058965, 4099.9180016399505, 3531.5408996830506, 3543.114221444639, 3556.608773034964, 3558.7508874378545, 3574.6782789549065, 4833.371661683994, 5211.843644690665, 5252.395974462947, 5267.657602255841, 5290.894182116336, 4579.22153233951, 4592.402987611623, 4613.561711637389, 4644.210484217691, 4666.486686464486, 5054.545090941833, 5526.861828454281, 5663.4336566343445, 5675.574331925122, 5775.49837042372, 3401.115709915415, 3772.4152756322815, 3801.5248093988253, 3822.751521151144, 3832.2335532893535, 4691.83578574755, 5098.974505127488, 5275.762590683428, 5923.229980102602, 5953.940460595425, 3876.321643712339, 3877.2245550889934, 3879.321118804198, 3879.553851307079, 3882.8058597070035, 3772.6227377262603, 3932.311845427096, 3940.0, 3957.5053118360206, 3965.7620542767527, 4174.666026717855, 4415.2935530315, 4591.44902611682, 4606.124836281096, 4646.814127434933, 5214.0614689355525, 5287.101192797234, 5658.91511627326, 5660.848877426634, 5693.544516438675, 1196.2403873540366, 1245.3773113443278, 1267.7464507098432, 1315.224017829466, 1347.6630842289428, 801.2548330053106, 846.8264005878904, 851.9733720866341, 881.7619242804407, 906.1708536688856, 4596.632269418438, 5188.9740551297, 5229.790808367623, 5233.293505376725, 5249.451411833518, 5484.533814625722, 5684.857878553069, 5712.857178570486, 5717.628354156966, 5750.712464376765, 4699.0, 4833.081714474239, 4879.878003049881, 5142.460041695568, 5176.3270774799785, 2868.770498360168, 2992.431438026768, 2997.28065264335, 3019.758419326449, 3028.9242768930512, 5587.552995760329, 5917.940820591825, 5924.896661685126, 5925.703714814242, 5976.910346344808, 4946.802127914843, 5469.741959349292, 5615.831525054255, 5737.483626473638, 5860.882782344329, 5353.973230133903, 5576.052071147915, 5915.349311575809, 5921.378255283219, 5956.493698035629, 4806.0, 5045.763787871995, 5267.051930652519, 5668.489835914787, 5697.943020569741, 4845.5881250094135, 5122.795088196431, 5372.597055220952, 5548.167774833807, 5684.118961560965, 5010.398752149766, 5923.822285331447, 5929.555283353852, 5959.851003724942, 5973.253343332083, 3294.374068926896, 3638.69071128957, 3825.6365645263654, 3864.2078373932713, 3902.843886244518, 5671.97904377216, 5716.6284191529085, 5724.570657200643, 5729.62757420783, 5735.942640573541, 4763.809447622057, 5559.610827242054, 5579.74891129892, 5591.496765291144, 5611.6352437091455, 4057.4522439470293, 5371.946280537301, 5476.123820188751, 5518.089515229942, 5643.984082864959, 5033.597312215087, 5402.405735369028, 5424.945750542602, 5430.375506816608, 5435.755391007493, 4503.797329120263, 4825.914169311876, 5563.248961390161, 5575.972120139373, 5579.302587176603, 4970.453250142409, 5542.8337149885565, 5563.8887222256135, 5573.3869274380595, 5586.357568879468], "value": 4063.652532869096, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [766.9271419215172, 790.284792263002, 801.5831767480906, 810.7851419373867, 851.1318455175717, 14721.190334531597, 15389.923050384761, 15405.614859628487, 15422.151781652005, 15440.84559154408, 14528.128312301269, 14635.63410914725, 14664.193469359208, 14683.926580367082, 14728.6317842054, 675.665545554951, 689.2246222999127, 829.9585020748968, 839.039300001498, 844.4089137603702, 850.1201256699318, 851.7572491839827, 864.2826454043129, 865.3985480091308, 865.8268346330727, 918.3663272342096, 919.7654598077481, 920.4201353147507, 921.1341339141219, 923.2613908872909, 706.5795851468368, 885.1590988560829, 911.2026976395654, 915.1534830282029, 915.9679411220575, 562.7748900439857, 678.7624331483942, 679.2019377231783, 679.4157024958532, 679.8334408070006, 14364.35360408784, 14378.496752613608, 14392.496262630757, 14394.496192633207, 14397.352119154662, 12766.36168191596, 13799.724005519935, 14006.74200686082, 14013.159210447304, 14024.789628155586, 11954.58158964432, 12168.756624867541, 12237.265764054097, 12265.57070502528, 12786.808197877039, 4500.257457519506, 4536.092781443692, 4645.210314246552, 4649.674522783406, 4649.976750116261, 113650.0, 117110.0, 117975.41012294969, 118935.0, 118957.0, 10550.89449105499, 10902.236843421044, 11190.104791616652, 11274.041706454878, 11422.485988130555, 21703.891480542497, 21705.67441488379, 21709.0, 21720.891395543236, 21760.0, 7693.07683078023, 7893.0, 7905.090914544786, 7989.400794940403, 8007.239312265326, 3678.9080272993388, 4091.5703851095204, 4174.039970806671, 4283.528811830696, 4396.582324679151, 3435.1927297085167, 3504.8948531544092, 3553.733469989761, 3556.1643013891717, 3559.6796288334176, 638.1098367777018, 645.7472503343498, 647.9546431749826, 660.4122331125302, 661.1438187547138, 468.85231152187635, 592.5437413191818, 624.96875156242, 673.8753330633848, 674.3930462583714, 9070.727878163665, 9361.204297634777, 9540.379875308083, 9562.61749530011, 9694.0, 643.1060825452624, 655.6361219523116, 661.1702313596405, 673.1518286958468, 674.4671709349559, 4446.777661116931, 4506.797194126272, 4672.112298663243, 4673.976630116827, 4701.882452938704, 156964.21517892566, 165404.0, 166127.3387266136, 167184.0, 171903.0, 134859.65140348667, 137120.31439842738, 141315.0, 141571.2921435407, 145149.0, 97724.0, 117905.0, 118609.8139018616, 120849.0, 122162.3891880535, 8785.428947118417, 8817.074207208407, 8904.777380565538, 8905.376623636283, 9153.359264851397, 63079.26905482016, 63154.68422658041, 63735.043974339496, 65198.34801651923, 68661.65669171623, 85685.57157214174, 98443.50778246063, 99910.5004475002, 102139.48930255302, 104908.47545762222, 12427.378631068592, 13128.934355328543, 13486.460541578226, 13523.256220907944, 14084.0, 14691.727594840622, 14966.775498367751, 15186.35212475319, 15362.120581296236, 15567.532974010797, 12180.817287740869, 13753.598729276046, 13945.721085578029, 14343.928280358532, 14810.851891480947, 12216.816747748613, 13389.263590502227, 13724.65688357799, 13818.516351927634, 14270.357833897708, 13397.330133493091, 13795.862041379458, 13917.443302267797, 13924.860751392356, 14247.287635618384, 11001.284916480403, 11387.316760994367, 11389.772204556028, 11394.316341019565, 11481.942590286995, 9449.669261575811, 10577.471126443801, 11293.604723834625, 11521.638064196986, 11667.0], "value": 7945.333373870627, "unit": "score"}], "name": "dromaeo_css", "value": 7247.76134766075, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 117.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 122.0], "value": 114.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 126.0, 160.0, 161.0, 108.0, 129.0, 127.0, 106.0, 160.0, 106.0], "value": 134.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 186.0, 183.0, 183.0, 183.0, 183.0, 178.0, 181.0, 188.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 79.0, 75.0, 157.0, 58.0, 58.0, 59.0, 64.0, 142.0, 75.0], "value": 83.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 132.0, 96.0, 99.0, 121.0, 117.0, 67.0, 70.0, 119.0], "value": 98.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 88.0, 85.0, 88.0, 85.0, 91.0, 86.0, 88.0, 84.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 103.0, 147.0, 89.0, 143.0, 145.0, 93.0, 93.0, 92.0], "value": 108.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 141.0, 130.0, 84.0, 85.0, 84.0, 84.0, 86.0, 85.0, 84.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 56.0, 52.0, 52.0, 55.0, 82.0, 57.0, 54.0, 54.0], "value": 57.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 79.0, 75.0, 73.0, 71.0, 70.0, 69.0, 71.0, 70.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 85.0, 86.0, 82.0, 89.0, 84.0, 86.0, 85.0, 86.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 143.0, 140.0, 139.0, 137.0, 141.0, 152.0, 156.0, 192.0, 138.0], "value": 147.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 171.0, 172.0, 172.0, 172.0, 173.0, 170.0, 172.0, 171.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 69.0, 68.0, 69.0, 67.0, 67.0, 67.0, 67.0, 67.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1506.6, "alertThreshold": 2.0}]} 11:23:12 INFO - Return code: 0 11:23:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:23:12 INFO - # TBPL SUCCESS # 11:23:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:23:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:23:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:23:12 INFO - 2016-09-06 11:23:12.580 screenresolution[1674:10427] starting screenresolution argv=screenresolution get 11:23:12 INFO - 2016-09-06 11:23:12.583 screenresolution[1674:10427] Display 0: 1600x1200x32@60 11:23:12 INFO - 2016-09-06 11:23:12.590 screenresolution[1675:10430] starting screenresolution argv=screenresolution list 11:23:12 INFO - Available Modes on Display 0 11:23:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:23:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:23:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:23:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:23:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:23:12 INFO - Intel Iris: 11:23:12 INFO - Chipset Model: Intel Iris 11:23:12 INFO - Type: GPU 11:23:12 INFO - Bus: Built-In 11:23:12 INFO - VRAM (Dynamic, Max): 1536 MB 11:23:12 INFO - Vendor: Intel (0x8086) 11:23:12 INFO - Device ID: 0x0a2e 11:23:12 INFO - Revision ID: 0x0009 11:23:12 INFO - Displays: 11:23:12 INFO - Display: 11:23:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:23:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:23:12 INFO - Display Serial Number: 1600x1200 60 11:23:12 INFO - Main Display: Yes 11:23:12 INFO - Mirror: Off 11:23:12 INFO - Online: Yes 11:23:12 INFO - Rotation: Supported 11:23:12 INFO - Return code: 0 11:23:12 INFO - Running post-action listener: _resource_record_post_action 11:23:12 INFO - [mozharness: 2016-09-06 18:23:12.826247Z] Finished run-tests step (success) 11:23:12 INFO - Running post-run listener: _resource_record_post_run 11:23:12 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 32926720; Write bytes: 705016832; Read time: 307; Write time: 1865 11:23:12 INFO - TinderboxPrint: CPU usage
53.6% 11:23:12 INFO - TinderboxPrint: I/O read bytes / time
32,926,720 / 307 11:23:12 INFO - TinderboxPrint: I/O write bytes / time
705,016,832 / 1,865 11:23:12 INFO - TinderboxPrint: CPU idle
2,253.4 (46.4%) 11:23:12 INFO - TinderboxPrint: CPU system
971.8 (20.0%) 11:23:12 INFO - TinderboxPrint: CPU user
1,628.5 (33.6%) 11:23:12 INFO - TinderboxPrint: Swap in / out
434,237,440 / 0 11:23:12 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 402508288; Write bytes: 397464064; Read time: 29654; Write time: 681 11:23:12 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 30841856; Write bytes: 294769152; Read time: 284; Write time: 1148 11:23:13 INFO - Running post-run listener: _upload_blobber_files 11:23:13 INFO - Blob upload gear active. 11:23:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:23:13 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:23:13 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'] 11:23:13 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 11:23:13 INFO - (blobuploader) - INFO - Open directory for files ... 11:23:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:23:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:23:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:23:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:23:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:23:15 INFO - (blobuploader) - INFO - Done attempting. 11:23:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:23:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:23:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:23:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:23:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:23:16 INFO - (blobuploader) - INFO - Done attempting. 11:23:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:23:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:23:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:23:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:23:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:23:18 INFO - (blobuploader) - INFO - Done attempting. 11:23:18 INFO - (blobuploader) - INFO - Iteration through files over. 11:23:18 INFO - Return code: 0 11:23:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:23:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:23:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/56747c3406d8fa7b5e0c6a2ecc4bee394d197ddb0a14378cd67aa9560bcb8d11b891897e8b6f667d21d6119aac55e0a80aff67c6abc5786b4ac3d077db6cf79f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/35904b835e4b5539856a6cd3ea4412bfb0cad86448858793be41c84fd190351f710f37ea6d3d66437c9dbbcafef1484ded01807a98f8f3d9c6460639a95cc8ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3adefb3d48434012e5a8bdf944fe90b7cbf0f44e13a5f56f0de7d02f2d6e0e7ea1e9c3d9a89233249d28d428b18519fc781133fd73a5dc48325d03516d5c922f"} 11:23:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:23:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:23:18 INFO - Contents: 11:23:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/56747c3406d8fa7b5e0c6a2ecc4bee394d197ddb0a14378cd67aa9560bcb8d11b891897e8b6f667d21d6119aac55e0a80aff67c6abc5786b4ac3d077db6cf79f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/35904b835e4b5539856a6cd3ea4412bfb0cad86448858793be41c84fd190351f710f37ea6d3d66437c9dbbcafef1484ded01807a98f8f3d9c6460639a95cc8ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3adefb3d48434012e5a8bdf944fe90b7cbf0f44e13a5f56f0de7d02f2d6e0e7ea1e9c3d9a89233249d28d428b18519fc781133fd73a5dc48325d03516d5c922f"} 11:23:18 INFO - Running post-run listener: copy_logs_to_upload_dir 11:23:18 INFO - Copying logs to upload dir... 11:23:18 INFO - mkdir: /builds/slave/test/build/upload/logs 11:23:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.486272 ========= master_lag: 0.87 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-06 11:23:19.690251) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-09-06 11:23:19.697684) ========= 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.MZJ4Fnyilw/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.q8f5cdZRfs/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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/56747c3406d8fa7b5e0c6a2ecc4bee394d197ddb0a14378cd67aa9560bcb8d11b891897e8b6f667d21d6119aac55e0a80aff67c6abc5786b4ac3d077db6cf79f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/35904b835e4b5539856a6cd3ea4412bfb0cad86448858793be41c84fd190351f710f37ea6d3d66437c9dbbcafef1484ded01807a98f8f3d9c6460639a95cc8ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3adefb3d48434012e5a8bdf944fe90b7cbf0f44e13a5f56f0de7d02f2d6e0e7ea1e9c3d9a89233249d28d428b18519fc781133fd73a5dc48325d03516d5c922f"} build_url:https://queue.taskcluster.net/v1/task/B5L0tibMSjCM0hf7r6NZug/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011520 build_url: 'https://queue.taskcluster.net/v1/task/B5L0tibMSjCM0hf7r6NZug/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/56747c3406d8fa7b5e0c6a2ecc4bee394d197ddb0a14378cd67aa9560bcb8d11b891897e8b6f667d21d6119aac55e0a80aff67c6abc5786b4ac3d077db6cf79f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/35904b835e4b5539856a6cd3ea4412bfb0cad86448858793be41c84fd190351f710f37ea6d3d66437c9dbbcafef1484ded01807a98f8f3d9c6460639a95cc8ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3adefb3d48434012e5a8bdf944fe90b7cbf0f44e13a5f56f0de7d02f2d6e0e7ea1e9c3d9a89233249d28d428b18519fc781133fd73a5dc48325d03516d5c922f"}' ========= master_lag: 1.31 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-09-06 11:23:21.023630) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-09-06 11:23:21.024014) ========= 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.MZJ4Fnyilw/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.q8f5cdZRfs/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.005286 ========= master_lag: 3.27 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-09-06 11:23:24.299539) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 11:23:24.299854) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 11:23:24.308209) ========= ========= Total master_lag: 5.52 =========