builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0095 starttime: 1467219076.65 results: success (0) buildid: 20160629071327 builduid: 15f480222fec41d8a9a17edc4a3c51d1 revision: 3cd482f4d7fe1e0a40bb27ce3557fe10068154bd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 09:51:16.645902) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 09:51:16.647013) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 09:51:16.647328) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 09:51:16.689707) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:51:16.689987) ========= 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.qXmmoYZlK0/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.Y8Luq8WSjx/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-29 09:51:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 39.0M=0s 2016-06-29 09:51:17 (39.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.604158 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:51:17.316137) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:51:17.316471) ========= 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.qXmmoYZlK0/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.Y8Luq8WSjx/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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.019557 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:51:17.365325) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:51:17.365799) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3cd482f4d7fe1e0a40bb27ce3557fe10068154bd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 3cd482f4d7fe1e0a40bb27ce3557fe10068154bd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qXmmoYZlK0/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.Y8Luq8WSjx/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-29 09:51:17,433 truncating revision to first 12 chars 2016-06-29 09:51:17,433 Setting DEBUG logging. 2016-06-29 09:51:17,433 attempt 1/10 2016-06-29 09:51:17,433 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3cd482f4d7fe?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-29 09:51:17,767 unpacking tar archive at: mozilla-central-3cd482f4d7fe/testing/mozharness/ program finished with exit code 0 elapsedTime=0.794495 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:51:18.182123) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 09:51:18.182427) ========= script_repo_revision: 3cd482f4d7fe1e0a40bb27ce3557fe10068154bd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 09:51:18.182811) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 09:51:18.183090) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 09:51:18.200825) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-06-29 09:51:18.201214) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qXmmoYZlK0/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.Y8Luq8WSjx/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 09:51:18 INFO - MultiFileLogger online at 20160629 09:51:18 in /builds/slave/test 09:51:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:51:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:51:18 INFO - {'append_to_log': False, 09:51:18 INFO - 'base_work_dir': '/builds/slave/test', 09:51:18 INFO - 'blob_upload_branch': 'Firefox', 09:51:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:51:18 INFO - 'branch': 'Firefox', 09:51:18 INFO - 'buildbot_json_path': 'buildprops.json', 09:51:18 INFO - 'config_files': ('talos/mac_config.py',), 09:51:18 INFO - 'default_actions': ('clobber', 09:51:18 INFO - 'read-buildbot-config', 09:51:18 INFO - 'download-and-extract', 09:51:18 INFO - 'populate-webroot', 09:51:18 INFO - 'create-virtualenv', 09:51:18 INFO - 'install', 09:51:18 INFO - 'run-tests'), 09:51:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:51:18 INFO - 'download_minidump_stackwalk': True, 09:51:18 INFO - 'download_symbols': 'ondemand', 09:51:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:51:18 INFO - 'tooltool.py': '/tools/tooltool.py', 09:51:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:51:18 INFO - '/tools/misc-python/virtualenv.py')}, 09:51:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:51:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:51:18 INFO - 'installer_path': 'installer.exe', 09:51:18 INFO - 'log_level': 'info', 09:51:18 INFO - 'log_name': 'talos', 09:51:18 INFO - 'log_to_console': True, 09:51:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:51:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:51:18 INFO - 'opt_config_files': (), 09:51:18 INFO - 'pip_index': False, 09:51:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:51:18 INFO - 'cmd': ('bash', 09:51:18 INFO - '-c', 09:51:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:51:18 INFO - 'enabled': True, 09:51:18 INFO - 'halt_on_failure': False, 09:51:18 INFO - 'name': 'check_screen_resolution'},), 09:51:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:51:18 INFO - 'cmd': ('bash', 09:51:18 INFO - '-c', 09:51:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:51:18 INFO - 'enabled': True, 09:51:18 INFO - 'halt_on_failure': False, 09:51:18 INFO - 'name': 'check_screen_resolution'},), 09:51:18 INFO - 'run_cmd_checks_enabled': True, 09:51:18 INFO - 'sps_profile': False, 09:51:18 INFO - 'sps_profile_interval': 0, 09:51:18 INFO - 'suite': 'dromaeojs', 09:51:18 INFO - 'system_bits': '32', 09:51:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:51:18 INFO - 'title': 't-yosemite-r7-0095', 09:51:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:51:18 INFO - 'use_talos_json': True, 09:51:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:51:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:51:18 INFO - 'work_dir': 'build'} 09:51:18 INFO - ##### 09:51:18 INFO - ##### Running clobber step. 09:51:18 INFO - ##### 09:51:18 INFO - Running pre-action listener: _resource_record_pre_action 09:51:18 INFO - Running main action method: clobber 09:51:18 INFO - rmtree: /builds/slave/test/build 09:51:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:51:20 INFO - Running post-action listener: _resource_record_post_action 09:51:20 INFO - ##### 09:51:20 INFO - ##### Finished clobber step (success) 09:51:20 INFO - ##### 09:51:20 INFO - ##### 09:51:20 INFO - ##### Running read-buildbot-config step. 09:51:20 INFO - ##### 09:51:20 INFO - Running pre-action listener: _resource_record_pre_action 09:51:20 INFO - Running main action method: read_buildbot_config 09:51:20 INFO - Using buildbot properties: 09:51:20 INFO - { 09:51:20 INFO - "project": "", 09:51:20 INFO - "product": "firefox", 09:51:20 INFO - "script_repo_revision": "production", 09:51:20 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:51:20 INFO - "repository": "", 09:51:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:51:20 INFO - "buildid": "20160629071327", 09:51:20 INFO - "pgo_build": "False", 09:51:20 INFO - "basedir": "/builds/slave/test", 09:51:20 INFO - "buildnumber": 269, 09:51:20 INFO - "slavename": "t-yosemite-r7-0095", 09:51:20 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:51:20 INFO - "platform": "yosemite_r7", 09:51:20 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:51:20 INFO - "branch": "mozilla-central", 09:51:20 INFO - "repo_path": "mozilla-central", 09:51:20 INFO - "revision": "3cd482f4d7fe1e0a40bb27ce3557fe10068154bd", 09:51:20 INFO - "stage_platform": "macosx64", 09:51:20 INFO - "builduid": "15f480222fec41d8a9a17edc4a3c51d1", 09:51:20 INFO - "slavebuilddir": "test" 09:51:20 INFO - } 09:51:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:51:20 INFO - Running post-action listener: _resource_record_post_action 09:51:20 INFO - ##### 09:51:20 INFO - ##### Finished read-buildbot-config step (success) 09:51:20 INFO - ##### 09:51:20 INFO - ##### 09:51:20 INFO - ##### Running download-and-extract step. 09:51:20 INFO - ##### 09:51:20 INFO - Running pre-action listener: _resource_record_pre_action 09:51:20 INFO - Running main action method: download_and_extract 09:51:20 INFO - mkdir: /builds/slave/test/build/tests 09:51:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:20 INFO - https://queue.taskcluster.net/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:51:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:51:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:51:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:51:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 09:51:40 INFO - Downloaded 1199 bytes. 09:51:40 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:51:40 INFO - Using the following test package requirements: 09:51:40 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:51:40 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:51:40 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:51:40 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:51:40 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:51:40 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:51:40 INFO - u'jsshell-mac.zip'], 09:51:40 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:51:40 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:51:40 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:51:40 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:51:40 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:51:40 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:51:40 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:51:40 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:51:40 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:51:40 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:51:40 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:51:40 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:51: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:40 INFO - https://queue.taskcluster.net/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:51:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:51:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:51:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:51:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:51:43 INFO - Downloaded 18787872 bytes. 09:51:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:51:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:51:47 INFO - Return code: 0 09:51:47 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:51:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:47 INFO - https://queue.taskcluster.net/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:51:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:51:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:51:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:51:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:51:49 INFO - Downloaded 18787872 bytes. 09:51:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:51:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:51:53 INFO - Return code: 0 09:51: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:53 INFO - https://queue.taskcluster.net/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:51:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:51:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:51:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:51:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:51:54 INFO - Downloaded 14405245 bytes. 09:51:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:51:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:51:54 INFO - Return code: 0 09:51:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:54 INFO - https://queue.taskcluster.net/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:51:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:51:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:51:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:51:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 09:51:58 INFO - Downloaded 112396036 bytes. 09:51:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:51:58 INFO - mkdir: /builds/slave/test/properties 09:51:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:51:58 INFO - Writing to file /builds/slave/test/properties/build_url 09:51:58 INFO - Contents: 09:51:58 INFO - build_url:https://queue.taskcluster.net/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:51:59 INFO - Running post-action listener: _resource_record_post_action 09:51:59 INFO - Running post-action listener: set_extra_try_arguments 09:51:59 INFO - ##### 09:51:59 INFO - ##### Finished download-and-extract step (success) 09:51:59 INFO - ##### 09:51:59 INFO - ##### 09:51:59 INFO - ##### Running populate-webroot step. 09:51:59 INFO - ##### 09:51:59 INFO - Running pre-action listener: _resource_record_pre_action 09:51:59 INFO - Running main action method: populate_webroot 09:51:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:51:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:51:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:51:59 INFO - '--tppagecycles', 09:51:59 INFO - '7'], 09:51:59 INFO - 'tests': ['tsvgm']}}, 09:51:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:51:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:51:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:51:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:51:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:51:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:51:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:59 INFO - 'talos_options': ['--disable-e10s'], 09:51:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:51:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:51:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:59 INFO - 'talos_options': ['--disable-e10s'], 09:51:59 INFO - 'tests': ['damp', 'tps']}, 09:51:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:59 INFO - 'tests': ['damp', 'tps']}, 09:51:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:51:59 INFO - 'tests': ['dromaeo_dom']}, 09:51:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:51:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:51:59 INFO - 'tests': ['basic_compositor_video']}, 09:51:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:51:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:51:59 INFO - 'tests': ['a11yr', 09:51:59 INFO - 'ts_paint', 09:51:59 INFO - 'tpaint', 09:51:59 INFO - 'sessionrestore', 09:51:59 INFO - 'sessionrestore_no_auto_restore', 09:51:59 INFO - 'tabpaint']}, 09:51:59 INFO - 'other-e10s': {'tests': ['a11yr', 09:51:59 INFO - 'ts_paint', 09:51:59 INFO - 'tpaint', 09:51:59 INFO - 'sessionrestore', 09:51:59 INFO - 'sessionrestore_no_auto_restore', 09:51:59 INFO - 'tabpaint']}, 09:51:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:51:59 INFO - 'ts_paint', 09:51:59 INFO - 'tpaint', 09:51:59 INFO - 'sessionrestore', 09:51:59 INFO - 'sessionrestore_no_auto_restore', 09:51:59 INFO - 'tabpaint']}, 09:51:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:51:59 INFO - 'ts_paint', 09:51:59 INFO - 'tpaint', 09:51:59 INFO - 'sessionrestore', 09:51:59 INFO - 'sessionrestore_no_auto_restore', 09:51:59 INFO - 'tabpaint']}, 09:51:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:51:59 INFO - 'tests': ['a11yr', 09:51:59 INFO - 'ts_paint', 09:51:59 INFO - 'tpaint', 09:51:59 INFO - 'sessionrestore', 09:51:59 INFO - 'sessionrestore_no_auto_restore', 09:51:59 INFO - 'tabpaint']}, 09:51:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:51:59 INFO - 'tests': ['a11yr', 09:51:59 INFO - 'ts_paint', 09:51:59 INFO - 'tpaint', 09:51:59 INFO - 'sessionrestore', 09:51:59 INFO - 'sessionrestore_no_auto_restore', 09:51:59 INFO - 'tabpaint']}, 09:51:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:51:59 INFO - 'tests': ['tsvgx', 09:51:59 INFO - 'tsvgr_opacity', 09:51:59 INFO - 'tart', 09:51:59 INFO - 'tscrollx', 09:51:59 INFO - 'cart']}, 09:51:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:51:59 INFO - 'tsvgr_opacity', 09:51:59 INFO - 'tart', 09:51:59 INFO - 'tscrollx', 09:51:59 INFO - 'cart']}, 09:51:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:59 INFO - 'talos_options': ['--disable-e10s'], 09:51:59 INFO - 'tests': ['tp5o']}, 09:51:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:59 INFO - 'tests': ['tp5o']}, 09:51:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:59 INFO - 'talos_options': ['--disable-e10s', 09:51:59 INFO - '--xperf_path', 09:51:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:51:59 INFO - 'tests': ['tp5n']}, 09:51:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:59 INFO - 'talos_options': ['--xperf_path', 09:51:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:51:59 INFO - 'tests': ['tp5n']}}, 09:51:59 INFO - 'talos.zip': {'path': '', 09:51:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:51:59 INFO - Running post-action listener: _resource_record_post_action 09:51:59 INFO - ##### 09:51:59 INFO - ##### Finished populate-webroot step (success) 09:51:59 INFO - ##### 09:51:59 INFO - ##### 09:51:59 INFO - ##### Running create-virtualenv step. 09:51:59 INFO - ##### 09:51:59 INFO - Running pre-action listener: _resource_record_pre_action 09:51:59 INFO - Running main action method: create_virtualenv 09:51:59 INFO - Creating virtualenv /builds/slave/test/build/venv 09:51:59 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 09:51:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:51:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:51:59 INFO - Using real prefix '/tools/python27' 09:51:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:52:00 INFO - Installing distribute.............................................................................................................................................................................................done. 09:52:02 INFO - Installing pip.................done. 09:52:02 INFO - Return code: 0 09:52:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:52:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103642a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8219d75f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035a6d30>, '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 0x10330bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359eb20>, '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.qXmmoYZlK0/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.Y8Luq8WSjx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 09:52:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:52:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:52:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXmmoYZlK0/Render', 09:52:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:02 INFO - 'HOME': '/Users/cltbld', 09:52:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:02 INFO - 'LOGNAME': 'cltbld', 09:52:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:02 INFO - 'MOZ_NO_REMOTE': '1', 09:52:02 INFO - 'NO_EM_RESTART': '1', 09:52:02 INFO - 'PAGER': '/bin/cat', 09:52:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:02 INFO - 'PWD': '/builds/slave/test', 09:52:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:02 INFO - 'SHELL': '/bin/bash', 09:52:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y8Luq8WSjx/Listeners', 09:52:02 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 09:52:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:02 INFO - 'USER': 'cltbld', 09:52:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:02 INFO - 'XPC_FLAGS': '0x0', 09:52:02 INFO - 'XPC_SERVICE_NAME': '0', 09:52:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:03 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 09:52:03 INFO - Cleaning up... 09:52:03 INFO - Return code: 0 09:52:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:03 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 0x103642a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8219d75f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035a6d30>, '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 0x10330bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359eb20>, '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.qXmmoYZlK0/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.Y8Luq8WSjx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 09:52:03 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 09:52:03 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 09:52:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXmmoYZlK0/Render', 09:52:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:03 INFO - 'HOME': '/Users/cltbld', 09:52:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:03 INFO - 'LOGNAME': 'cltbld', 09:52:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:03 INFO - 'MOZ_NO_REMOTE': '1', 09:52:03 INFO - 'NO_EM_RESTART': '1', 09:52:03 INFO - 'PAGER': '/bin/cat', 09:52:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:03 INFO - 'PWD': '/builds/slave/test', 09:52:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:03 INFO - 'SHELL': '/bin/bash', 09:52:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y8Luq8WSjx/Listeners', 09:52:03 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 09:52:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:03 INFO - 'USER': 'cltbld', 09:52:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:03 INFO - 'XPC_FLAGS': '0x0', 09:52:03 INFO - 'XPC_SERVICE_NAME': '0', 09:52:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:03 INFO - Downloading/unpacking psutil>=3.1.1 09:52:03 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 09:52:03 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 09:52:03 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 09:52:03 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 09:52:03 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 09:52:03 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 09:52:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:52:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:52:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:52:06 INFO - Installing collected packages: psutil 09:52:06 INFO - Running setup.py install for psutil 09:52:06 INFO - building 'psutil._psutil_osx' extension 09:52:06 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 09:52:07 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 09:52:07 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 09:52:07 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 09:52:07 INFO - building 'psutil._psutil_posix' extension 09:52:07 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 09:52:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:52:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:52:07 INFO - ^ 09:52:07 INFO - 1 warning generated. 09:52:07 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 09:52:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:52:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:52:07 INFO - Successfully installed psutil 09:52:07 INFO - Cleaning up... 09:52:07 INFO - Return code: 0 09:52:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:52:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:07 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 0x103642a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8219d75f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035a6d30>, '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 0x10330bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359eb20>, '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.qXmmoYZlK0/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.Y8Luq8WSjx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 09:52:07 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 09:52:07 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 09:52:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXmmoYZlK0/Render', 09:52:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:07 INFO - 'HOME': '/Users/cltbld', 09:52:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:07 INFO - 'LOGNAME': 'cltbld', 09:52:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:07 INFO - 'MOZ_NO_REMOTE': '1', 09:52:07 INFO - 'NO_EM_RESTART': '1', 09:52:07 INFO - 'PAGER': '/bin/cat', 09:52:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:07 INFO - 'PWD': '/builds/slave/test', 09:52:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:07 INFO - 'SHELL': '/bin/bash', 09:52:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y8Luq8WSjx/Listeners', 09:52:07 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 09:52:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:07 INFO - 'USER': 'cltbld', 09:52:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:07 INFO - 'XPC_FLAGS': '0x0', 09:52:07 INFO - 'XPC_SERVICE_NAME': '0', 09:52:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:52:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:52:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:52:10 INFO - Installing collected packages: mozsystemmonitor 09:52:10 INFO - Running setup.py install for mozsystemmonitor 09:52:10 INFO - Successfully installed mozsystemmonitor 09:52:10 INFO - Cleaning up... 09:52:10 INFO - Return code: 0 09:52:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:52:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:10 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 0x103642a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8219d75f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035a6d30>, '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 0x10330bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359eb20>, '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.qXmmoYZlK0/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.Y8Luq8WSjx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 09:52:10 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 09:52:10 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 09:52:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXmmoYZlK0/Render', 09:52:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:10 INFO - 'HOME': '/Users/cltbld', 09:52:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:10 INFO - 'LOGNAME': 'cltbld', 09:52:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:10 INFO - 'MOZ_NO_REMOTE': '1', 09:52:10 INFO - 'NO_EM_RESTART': '1', 09:52:10 INFO - 'PAGER': '/bin/cat', 09:52:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:10 INFO - 'PWD': '/builds/slave/test', 09:52:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:10 INFO - 'SHELL': '/bin/bash', 09:52:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y8Luq8WSjx/Listeners', 09:52:10 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 09:52:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:10 INFO - 'USER': 'cltbld', 09:52:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:10 INFO - 'XPC_FLAGS': '0x0', 09:52:10 INFO - 'XPC_SERVICE_NAME': '0', 09:52:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:10 INFO - Downloading/unpacking jsonschema==2.5.1 09:52:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:52:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:52:13 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 09:52:13 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 09:52:13 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 09:52:13 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 09:52:13 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 09:52:13 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 09:52:13 INFO - Downloading functools32-3.2.3-2.tar.gz 09:52:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:52:13 INFO - warning: no files found matching '*.txt' 09:52:13 INFO - no previously-included directories found matching 'build' 09:52:13 INFO - no previously-included directories found matching 'dist' 09:52:13 INFO - no previously-included directories found matching '.git*' 09:52:13 INFO - Installing collected packages: jsonschema, functools32 09:52:13 INFO - Running setup.py install for functools32 09:52:13 INFO - warning: no files found matching '*.txt' 09:52:13 INFO - no previously-included directories found matching 'build' 09:52:13 INFO - no previously-included directories found matching 'dist' 09:52:13 INFO - no previously-included directories found matching '.git*' 09:52:13 INFO - Successfully installed jsonschema functools32 09:52:13 INFO - Cleaning up... 09:52:13 INFO - Return code: 0 09:52:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:52:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:13 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 0x103642a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8219d75f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035a6d30>, '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 0x10330bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359eb20>, '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.qXmmoYZlK0/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.Y8Luq8WSjx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 09:52:13 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 09:52:13 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 09:52:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXmmoYZlK0/Render', 09:52:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:13 INFO - 'HOME': '/Users/cltbld', 09:52:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:13 INFO - 'LOGNAME': 'cltbld', 09:52:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:13 INFO - 'MOZ_NO_REMOTE': '1', 09:52:13 INFO - 'NO_EM_RESTART': '1', 09:52:13 INFO - 'PAGER': '/bin/cat', 09:52:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:13 INFO - 'PWD': '/builds/slave/test', 09:52:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:13 INFO - 'SHELL': '/bin/bash', 09:52:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y8Luq8WSjx/Listeners', 09:52:13 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 09:52:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:13 INFO - 'USER': 'cltbld', 09:52:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:13 INFO - 'XPC_FLAGS': '0x0', 09:52:13 INFO - 'XPC_SERVICE_NAME': '0', 09:52:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:14 INFO - Downloading/unpacking blobuploader==1.2.4 09:52:14 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 09:52:14 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 09:52:14 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 09:52:14 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 09:52:14 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 09:52:14 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 09:52:16 INFO - Downloading blobuploader-1.2.4.tar.gz 09:52:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:52:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:52:16 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 09:52:16 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 09:52:16 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 09:52:16 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 09:52:16 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 09:52:16 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 09:52:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:52:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:52:17 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 09:52:17 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 09:52:17 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 09:52:17 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 09:52:17 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 09:52:17 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 09:52:17 INFO - Downloading docopt-0.6.1.tar.gz 09:52:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:52:17 INFO - Installing collected packages: blobuploader, requests, docopt 09:52:17 INFO - Running setup.py install for blobuploader 09:52:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:52:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:52:18 INFO - Running setup.py install for requests 09:52:18 INFO - Running setup.py install for docopt 09:52:18 INFO - Successfully installed blobuploader requests docopt 09:52:18 INFO - Cleaning up... 09:52:18 INFO - Return code: 0 09:52:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:52:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:18 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 0x103642a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8219d75f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035a6d30>, '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 0x10330bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359eb20>, '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.qXmmoYZlK0/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.Y8Luq8WSjx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 09:52:18 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 09:52:18 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 09:52:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXmmoYZlK0/Render', 09:52:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:18 INFO - 'HOME': '/Users/cltbld', 09:52:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:18 INFO - 'LOGNAME': 'cltbld', 09:52:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:18 INFO - 'MOZ_NO_REMOTE': '1', 09:52:18 INFO - 'NO_EM_RESTART': '1', 09:52:18 INFO - 'PAGER': '/bin/cat', 09:52:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:18 INFO - 'PWD': '/builds/slave/test', 09:52:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:18 INFO - 'SHELL': '/bin/bash', 09:52:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y8Luq8WSjx/Listeners', 09:52:18 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 09:52:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:18 INFO - 'USER': 'cltbld', 09:52:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:18 INFO - 'XPC_FLAGS': '0x0', 09:52:18 INFO - 'XPC_SERVICE_NAME': '0', 09:52:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:52:19 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-pptl4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:52:19 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-M1YrUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:52:19 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-qcIWLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:52:19 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-MM3C5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:52:19 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-vNUkQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:52:19 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-XVwrIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:52:19 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-203JCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:52:19 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-j1dpT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:52:19 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-ovBJ1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:52:19 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-2Zid_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:52:20 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-nb9dfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:52:20 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-u5PQIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:52:20 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-yPJJOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:52:20 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-sINwI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:52:20 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-AW8i42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:52:20 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-iTb0yL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:52:20 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-6mEEVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:52:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:52:20 INFO - Running setup.py install for manifestparser 09:52:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:52:20 INFO - Running setup.py install for mozcrash 09:52:21 INFO - Running setup.py install for mozdebug 09:52:21 INFO - Running setup.py install for mozdevice 09:52:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:52:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:52:21 INFO - Running setup.py install for mozfile 09:52:21 INFO - Running setup.py install for mozhttpd 09:52:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:52:21 INFO - Running setup.py install for mozinfo 09:52:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:52:21 INFO - Running setup.py install for mozInstall 09:52:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:52:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:52:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:52:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:52:21 INFO - Running setup.py install for mozleak 09:52:22 INFO - Running setup.py install for mozlog 09:52:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:52:22 INFO - Running setup.py install for moznetwork 09:52:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:52:22 INFO - Running setup.py install for mozprocess 09:52:22 INFO - Running setup.py install for mozprofile 09:52:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:52:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:52:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:52:22 INFO - Running setup.py install for mozrunner 09:52:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:52:22 INFO - Running setup.py install for mozscreenshot 09:52:23 INFO - Running setup.py install for moztest 09:52:23 INFO - Running setup.py install for mozversion 09:52:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:52:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:52:23 INFO - Cleaning up... 09:52:23 INFO - Return code: 0 09:52:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:52:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:23 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 0x103642a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8219d75f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035a6d30>, '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 0x10330bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359eb20>, '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.qXmmoYZlK0/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.Y8Luq8WSjx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 09:52:23 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 09:52:23 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 09:52:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXmmoYZlK0/Render', 09:52:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:23 INFO - 'HOME': '/Users/cltbld', 09:52:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:23 INFO - 'LOGNAME': 'cltbld', 09:52:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:23 INFO - 'MOZ_NO_REMOTE': '1', 09:52:23 INFO - 'NO_EM_RESTART': '1', 09:52:23 INFO - 'PAGER': '/bin/cat', 09:52:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:23 INFO - 'PWD': '/builds/slave/test', 09:52:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:23 INFO - 'SHELL': '/bin/bash', 09:52:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y8Luq8WSjx/Listeners', 09:52:23 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 09:52:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:23 INFO - 'USER': 'cltbld', 09:52:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:23 INFO - 'XPC_FLAGS': '0x0', 09:52:23 INFO - 'XPC_SERVICE_NAME': '0', 09:52:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:52:24 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-ZXs99I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:52:24 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)) 09:52:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:52:24 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-WJ6Wxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:52:24 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)) 09:52:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:52:24 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-YRR1lb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:52:24 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)) 09:52:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:52:24 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-KKtGc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:52:24 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)) 09:52:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:52:24 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-KabJmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:52:24 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)) 09:52:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:52:24 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-CKUZ1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:52:24 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)) 09:52:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:52:24 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-7b6Zq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:52:24 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)) 09:52:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:52:24 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-woZAfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:52:24 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)) 09:52:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:52:24 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-bMyTlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:52:25 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)) 09:52:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:52:25 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-gbASH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:52:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:52:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:52:25 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-36zlYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:52:25 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)) 09:52:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:52:25 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-K3pBFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:52:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:52:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:52:25 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-bIrFTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:52:25 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)) 09:52:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:52:25 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-Pkug7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:52:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:52:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:52:25 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-RLtGQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:52:25 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)) 09:52:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:52:25 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-D3V7P6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:52:25 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)) 09:52:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:52:25 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-lWVc3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:52:25 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)) 09:52:25 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)) 09:52:25 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)) 09:52:25 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)) 09:52:25 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)) 09:52:25 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)) 09:52:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:28 INFO - Downloading blessings-1.6.tar.gz 09:52:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:52:28 INFO - Installing collected packages: blessings 09:52:28 INFO - Running setup.py install for blessings 09:52:29 INFO - Successfully installed blessings 09:52:29 INFO - Cleaning up... 09:52:29 INFO - Return code: 0 09:52:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:52:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:52:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:52:29 INFO - Reading from file tmpfile_stdout 09:52:29 INFO - Current package versions: 09:52:29 INFO - blessings == 1.6 09:52:29 INFO - blobuploader == 1.2.4 09:52:29 INFO - docopt == 0.6.1 09:52:29 INFO - functools32 == 3.2.3-2 09:52:29 INFO - jsonschema == 2.5.1 09:52:29 INFO - manifestparser == 1.1 09:52:29 INFO - mozInstall == 1.12 09:52:29 INFO - mozcrash == 0.17 09:52:29 INFO - mozdebug == 0.1 09:52:29 INFO - mozdevice == 0.48 09:52:29 INFO - mozfile == 1.2 09:52:29 INFO - mozhttpd == 0.7 09:52:29 INFO - mozinfo == 0.9 09:52:29 INFO - mozleak == 0.1 09:52:29 INFO - mozlog == 3.2 09:52:29 INFO - moznetwork == 0.27 09:52:29 INFO - mozprocess == 0.22 09:52:29 INFO - mozprofile == 0.28 09:52:29 INFO - mozrunner == 6.11 09:52:29 INFO - mozscreenshot == 0.1 09:52:29 INFO - mozsystemmonitor == 0.3 09:52:29 INFO - moztest == 0.7 09:52:29 INFO - mozversion == 1.4 09:52:29 INFO - psutil == 3.1.1 09:52:29 INFO - requests == 1.2.3 09:52:29 INFO - wsgiref == 0.1.2 09:52:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:29 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 0x103642a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8219d75f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035a6d30>, '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 0x10330bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359eb20>, '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.qXmmoYZlK0/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.Y8Luq8WSjx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 09:52:29 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 09:52:29 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 09:52:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXmmoYZlK0/Render', 09:52:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:29 INFO - 'HOME': '/Users/cltbld', 09:52:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:29 INFO - 'LOGNAME': 'cltbld', 09:52:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:29 INFO - 'MOZ_NO_REMOTE': '1', 09:52:29 INFO - 'NO_EM_RESTART': '1', 09:52:29 INFO - 'PAGER': '/bin/cat', 09:52:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:29 INFO - 'PWD': '/builds/slave/test', 09:52:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:29 INFO - 'SHELL': '/bin/bash', 09:52:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y8Luq8WSjx/Listeners', 09:52:29 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 09:52:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:29 INFO - 'USER': 'cltbld', 09:52:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:29 INFO - 'XPC_FLAGS': '0x0', 09:52:29 INFO - 'XPC_SERVICE_NAME': '0', 09:52:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:29 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)) 09:52:29 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)) 09:52:29 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)) 09:52:29 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)) 09:52:29 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)) 09:52:29 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)) 09:52:29 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)) 09:52:29 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)) 09:52:29 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)) 09:52:29 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)) 09:52:29 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)) 09:52:29 INFO - Cleaning up... 09:52:29 INFO - Return code: 0 09:52:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:29 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 0x103642a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8219d75f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035a6d30>, '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 0x10330bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359eb20>, '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.qXmmoYZlK0/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.Y8Luq8WSjx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 09:52:29 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 09:52:29 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 09:52:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXmmoYZlK0/Render', 09:52:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:29 INFO - 'HOME': '/Users/cltbld', 09:52:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:29 INFO - 'LOGNAME': 'cltbld', 09:52:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:29 INFO - 'MOZ_NO_REMOTE': '1', 09:52:29 INFO - 'NO_EM_RESTART': '1', 09:52:29 INFO - 'PAGER': '/bin/cat', 09:52:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:29 INFO - 'PWD': '/builds/slave/test', 09:52:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:29 INFO - 'SHELL': '/bin/bash', 09:52:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y8Luq8WSjx/Listeners', 09:52:29 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 09:52:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:29 INFO - 'USER': 'cltbld', 09:52:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:29 INFO - 'XPC_FLAGS': '0x0', 09:52:29 INFO - 'XPC_SERVICE_NAME': '0', 09:52:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:52:29 INFO - Cleaning up... 09:52:29 INFO - Return code: 0 09:52:29 INFO - Running post-action listener: _resource_record_post_action 09:52:29 INFO - Running post-action listener: _start_resource_monitoring 09:52:29 INFO - Starting resource monitoring. 09:52:29 INFO - ##### 09:52:29 INFO - ##### Finished create-virtualenv step (success) 09:52:29 INFO - ##### 09:52:29 INFO - ##### 09:52:29 INFO - ##### Running install step. 09:52:29 INFO - ##### 09:52:29 INFO - Running pre-action listener: _resource_record_pre_action 09:52:29 INFO - Running main action method: install 09:52:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:52:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:52:30 INFO - Reading from file tmpfile_stdout 09:52:30 INFO - Detecting whether we're running mozinstall >=1.0... 09:52:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:52:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:52:30 INFO - Reading from file tmpfile_stdout 09:52:30 INFO - Output received: 09:52:30 INFO - Usage: mozinstall [options] installer 09:52:30 INFO - Options: 09:52:30 INFO - -h, --help show this help message and exit 09:52:30 INFO - -d DEST, --destination=DEST 09:52:30 INFO - Directory to install application into. [default: 09:52:30 INFO - "/builds/slave/test"] 09:52:30 INFO - --app=APP Application being installed. [default: firefox] 09:52:30 INFO - mkdir: /builds/slave/test/build/application 09:52:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:52:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:53:04 INFO - Reading from file tmpfile_stdout 09:53:04 INFO - Output received: 09:53:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:53:04 INFO - Running post-action listener: _resource_record_post_action 09:53:04 INFO - ##### 09:53:04 INFO - ##### Finished install step (success) 09:53:04 INFO - ##### 09:53:04 INFO - ##### 09:53:04 INFO - ##### Running run-tests step. 09:53:04 INFO - ##### 09:53:04 INFO - Running pre-action listener: _resource_record_pre_action 09:53:04 INFO - Running main action method: run_tests 09:53:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:53:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:53:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:53:04 INFO - 2016-06-29 09:53:04.313 screenresolution[1957:65009] starting screenresolution argv=screenresolution get 09:53:04 INFO - 2016-06-29 09:53:04.316 screenresolution[1957:65009] Display 0: 1600x1200x32@60 09:53:04 INFO - 2016-06-29 09:53:04.322 screenresolution[1958:65011] starting screenresolution argv=screenresolution list 09:53:04 INFO - Available Modes on Display 0 09:53:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:53:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:53:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:53:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:53:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:53:04 INFO - Intel Iris: 09:53:04 INFO - Chipset Model: Intel Iris 09:53:04 INFO - Type: GPU 09:53:04 INFO - Bus: Built-In 09:53:04 INFO - VRAM (Dynamic, Max): 1536 MB 09:53:04 INFO - Vendor: Intel (0x8086) 09:53:04 INFO - Device ID: 0x0a2e 09:53:04 INFO - Revision ID: 0x0009 09:53:04 INFO - Displays: 09:53:04 INFO - Display: 09:53:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:53:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:53:04 INFO - Display Serial Number: 1600x1200 60 09:53:04 INFO - Main Display: Yes 09:53:04 INFO - Mirror: Off 09:53:04 INFO - Online: Yes 09:53:04 INFO - Rotation: Supported 09:53:04 INFO - Return code: 0 09:53:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:53:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:53:04 INFO - Python 2.7.3 09:53:04 INFO - Return code: 0 09:53:04 INFO - grabbing minidump binary from tooltool 09:53:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:04 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 0x1035a6d30>, '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 0x10330bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:53:04 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 09:53:04 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 09:53:04 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 09:53:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:53:04 INFO - Return code: 0 09:53:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:53:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:53:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:53:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:53:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:53:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0095', '--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 09:53:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0095 --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 09:53:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXmmoYZlK0/Render', 09:53:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:04 INFO - 'HOME': '/Users/cltbld', 09:53:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:04 INFO - 'LOGNAME': 'cltbld', 09:53:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:53:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:53:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:04 INFO - 'MOZ_NO_REMOTE': '1', 09:53:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:53:04 INFO - 'NO_EM_RESTART': '1', 09:53:04 INFO - 'PAGER': '/bin/cat', 09:53:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:04 INFO - 'PWD': '/builds/slave/test', 09:53:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:53:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:04 INFO - 'SHELL': '/bin/bash', 09:53:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y8Luq8WSjx/Listeners', 09:53:04 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 09:53:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:04 INFO - 'USER': 'cltbld', 09:53:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:04 INFO - 'XPC_FLAGS': '0x0', 09:53:04 INFO - 'XPC_SERVICE_NAME': '0', 09:53:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0095', '--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 09:53:04 INFO - mozversion application_buildid: 20160629071327 09:53:04 INFO - mozversion application_changeset: 3cd482f4d7fe1e0a40bb27ce3557fe10068154bd 09:53:04 INFO - mozversion application_display_name: Nightly 09:53:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:53:04 INFO - mozversion application_name: Firefox 09:53:04 INFO - mozversion application_remotingname: firefox 09:53:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:53:04 INFO - mozversion application_vendor: Mozilla 09:53:04 INFO - mozversion application_version: 50.0a1 09:53:04 INFO - mozversion platform_buildid: 20160629071327 09:53:04 INFO - mozversion platform_changeset: 3cd482f4d7fe1e0a40bb27ce3557fe10068154bd 09:53:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:53:04 INFO - mozversion platform_version: 50.0a1 09:53:04 INFO - using testdate: 1467219184 09:53:04 INFO - actual date: 1467219184 09:53:04 INFO - starting webserver on 'localhost:50837' 09:53:04 INFO - SUITE-START | Running 2 tests 09:53:04 INFO - TEST-START | dromaeo_css 09:53:04 INFO - operating with platform_type : mac_ 09:53:04 INFO - Initialising browser for dromaeo_css test... 09:53:04 INFO - TEST-INFO | started process 1968 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpAIysui/profile http://localhost:50837/getInfo.html) 09:53:07 INFO - PROCESS | 1968 | __metrics Screen width/height:1600/1200 09:53:07 INFO - PROCESS | 1968 | colorDepth:24 09:53:07 INFO - PROCESS | 1968 | Browser inner width/height: 1024/689 09:53:07 INFO - PROCESS | 1968 | __metrics 09:53:07 INFO - PROCESS | 1968 | 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" 09:53:07 INFO - PROCESS | 1968 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:53:08 INFO - TEST-INFO | 1968: exit 0 09:53:08 INFO - Browser initialized. 09:53:08 INFO - Running cycle 1/1 for dromaeo_css test... 09:53:08 INFO - TEST-INFO | started process 1970 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpAIysui/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:53:19 INFO - PROCESS | 1970 | RSS: Main: 243470336 09:53:19 INFO - PROCESS | 1970 | 09:54:11 INFO - PROCESS | 1970 | 1467219251662 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] 09:56:22 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:50837/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50837/tests/dromaeo/cssquery-ext.html) 09:56:23 INFO - PROCESS | 1970 | RSS: Main: 383983616 09:56:23 INFO - PROCESS | 1970 | 09:59:27 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:50837/tests/dromaeo/cssquery-ext.html (next: http://localhost:50837/tests/dromaeo/cssquery-jquery.html) 09:59:27 INFO - PROCESS | 1970 | RSS: Main: 382316544 09:59:27 INFO - PROCESS | 1970 | 10:02:30 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:50837/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50837/tests/dromaeo/cssquery-mootools.html) 10:02:30 INFO - PROCESS | 1970 | RSS: Main: 373534720 10:02:30 INFO - PROCESS | 1970 | 10:05:32 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:50837/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50837/tests/dromaeo/cssquery-prototype.html) 10:05:33 INFO - PROCESS | 1970 | RSS: Main: 374071296 10:05:33 INFO - PROCESS | 1970 | 10:08:36 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:50837/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50837/tests/dromaeo/cssquery-yui.html) 10:08:37 INFO - PROCESS | 1970 | RSS: Main: 345915392 10:08:37 INFO - PROCESS | 1970 | 10:11:40 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:50837/tests/dromaeo/cssquery-yui.html (next: http://localhost:50837/tests/dromaeo/cssquery-dojo.html) 10:11:40 INFO - PROCESS | 1970 | RSS: Main: 393986048 10:11:40 INFO - PROCESS | 1970 | 10:11:40 INFO - PROCESS | 1970 | __start_tp_report 10:11:40 INFO - PROCESS | 1970 | _x_x_mozilla_page_load 10:11:40 INFO - PROCESS | 1970 | _x_x_mozilla_page_load_details 10:11:40 INFO - PROCESS | 1970 | |i|pagename|runs| 10:11:40 INFO - PROCESS | 1970 | |0;dojo.html;3343.933121337573;3346.163459135216;3354.3962086824363;3390.406678831207;3406.50605662179;5604.187392828037;5727.513161381283;5768.423157684229;5806.883862322751;5852.795152169675;5067.113255180348;5720.22776925115;5788.218590490283;5796.50729687977;5811.360750317473;4864.635152363587;4932.8026878924975;5067.8479645610505;5252;5376.1398176291805;4373.715708478939;5307.655002424849;5342.599305052117;5384.838454846361;5432.266644003049;5366.865828354303;5381.784728610871;5391.973040134794;5398.514133727964;5440.836774896759;905.5070449963945;5155.9484405155845;5246.186841039645;5317.973410132963;5348.411674715778;5213.635045546814;5256.106461901486;5269.288646032813;5269.657472264291;5280;5695.088785794253;5705.315362156526;5714.942850571482;5724.656520608777;5899.380565040652;6268.341824108448;6290;6324.019776934537;6335.5565110442285;6339.714712837887;6239.469645080174;6296.992481203031;6302.054691796267;6304.653244071575;6312.147860038882;5492.917606235909;5730.36965933747;5831.533477321804;5846.7368968396095;5863.970680146657;6335.176427064452;6381.1385462962835;6384.329645387167;6390.872182556416;6400.039994000937;6174.6912654368;6271.216097987752;6277.874442511215;6288.213973253343;6289.55973081889;6143.818944274807;6236.376362363818;6275.780347687809;6304.338044505353;6305.211848518935;5146.433892271847;6028.9397106029255;6307.873842523124;6310.274318453345;6330.905036424458;6165.722542485599;6279.623222606567;6308.085327627468;6319.905201421982;6335.714892829834;4897.2898929655;4909.705417674951;4930.482299358588;5002.074616196015;5017.974910125426;4483.2826747720865;4493.550644935481;4500.684952053325;4514.164879497303;4528.818847246073;5915.438033386821;5925.229720136441;5956.6723830188885;5963.224780778471;6009.699515024231;6213.1922850029205;6244.313125556185;6265.8746825063245;6290.182276304051;6327.683615819191;5909.763609455659;5910.408959104056;5919.082542205965;5921.970390148108;5977.342492325841;5469.15228139639;5477.233187353773;5483.067878460592;5500.614956952975;5503.091989821634;6198.6280823150755;6231.9688401557705;6286.905696414466;6301.905471417841;6435.646039467687;6243.219597550306;6253.812385628438;6265;6268.592541484789;6280.120783090462;6196.566240363131;6346.365363463617;6365.872682546231;6425.678716064272;6430.935690643034;6191;6223.719932603134;6286.025666021591;6318.052292156213;6323.873522529616;6258.249010118815;6273.749050037947;6284.874302514016;6289.528285378707;6293.244810622755;5122.308488354024;5779.335376431764;6028.186194863637;6125.203723515914;6228.034654628359;6105.520399749632;6205.65868877198;6209.720562574786;6219.0982307566055;6234.719437625227;6054.969725151346;6093.878122437615;6095.8518082563905;6108.08378743192;6124.485966383171;5198.194279886624;6024.969875150771;6119.502553941335;6132.6627035513475;6219.657918814509;5934.376890426443;6012.278526576839;6063.878722425615;6124.479419249235;6134.631922084689 10:11:40 INFO - PROCESS | 1970 | |1;ext.html;480.3275414419814;506.8682142642916;511.1974200505207;522.2844702757224;557.3339858868651;30526.84736576317;41368;42608.99999999996;42863.57136428635;42890.356644650274;43438.782806085925;43897.78051109748;43960;43977.56022439775;44099.77950110245;8638.740837774845;9437.480938548377;9642.469664168468;9662.275329350292;9668.371555848847;8668.26319762821;11713.414329283502;11927.58253461129;11932.403379831017;12138.696532586668;11707.824382634268;11809.173357864953;11811.114166437552;11841;11865.881341186563;8733.170348816851;8923.10768923109;8926.2412694921;11756.647300580953;11881.821772673373;8692.087330830234;11387.601433949772;11662.650120496399;11665.358405287705;11843.644690659294;41941;42159.36760948588;42344.36483452751;42396.1520769586;42402.36396454056;3465.601455832561;3493.0917961329983;3505.263894582141;3623.7282203834816;3790.6967442604528;31750.841245793854;32466.513002304986;32515.6748432515;32823;33205.83397083023;3239.919002024945;3263.738900887923;3277.5247589099686;3347.3640008398324;3420.350133474632;527816;529637;529695;530633;532391;34089;34901;59763.701181493816;60103.69948150319;60147;35959.64040359615;36187.27625447476;36242.36909339086;36243.456348154796;36263;14978.775318370233;15319.157446340336;15337.079775213522;15342.693146137015;15367.846321536641;11761;11926.761464770829;12745.553905613258;12932.741345173044;13011.089223754247;7260.947162661385;7481.364084052808;7879.803004924808;7881.369490440751;7942.007249093864;3220.726238269773;3265.706086452183;3278.1149089745636;3282;3303.9834800825843;941.505709502503;942.2132519346422;964.4261664309724;1023.4545835788014;1024.4263212600968;30773.8461307692;31151;31373.8431307842;31718.524222136686;31729;1086.940218287986;1113.716002419378;1159.5999380213814;1173.7711146326385;1227.6501197158946;13228.272445015617;16054.117023563582;16060.43788467398;16335.01989880611;16490.75263871043;710976;713364;713709;714650;716018;60540.69729651324;61251.69374153101;61266;61373.693131534055;61565.69217153885;1263.2673049631192;1407.394820227312;1432.534426311453;1462.444271176946;1463.3853781411833;28043;31888.840555797073;32239.677603224605;32327.838360808044;33181.50227746634;50240.49759502358;50333.74833125811;50738.731531712;50769;50826;513398;514385;514986;518886;518989;23790.215733819685;26408.20775376742;26527.60208596834;27114.593281101195;27150.592741109293;49609.75195124001;49667.503324967736;50206.748966254934;50677.49322506875;50784.492155077976;39395.80302098471;40345.798271008454;40718.59281407148;41178.970525737095;41554.75335739933;32935.50596741099;39851.80074099611;40513.1897362057;41190.38214426846;42060;39453.802730986165;40006.39990400088;40199.99500012523;40336.39495407513;40489;34201.31597368088;35151.82424087863;35936.46095308625;36020.81989590035;36116;22253.233322534696;23689.52620947606;26385.076522321626;26685.86657066702;26811.731882680924 10:11:40 INFO - PROCESS | 1970 | |2;jquery.html;315.4637116901268;378.3284669711262;384.6903242889473;393.8306528192876;398.5257543523207;19921.003949802507;20131.396058118262;21058.578828423462;21304.680429793567;21323.893380533096;11714.7657046859;12069.275843449399;12352.258864468138;12390.132690711655;12397.070219733534;5293.232481290201;5559.63862348946;5580.553555715533;5589.692566908819;5591.9720401397935;3891.747036442622;4163.333866581347;5200.68795872247;5871.618344807595;6192.690365481731;5913.319968203668;6212.068189771525;6214.130021796952;6216.440520353167;6224.439800417961;5292.576593872481;6062.787802426938;6091.0863370494335;6167.105769663396;6183.227096612924;3755.9436608450897;5530.834074977757;5882.941170588282;5883.205767221413;5994.100884867261;13397.330133493286;13495.932520337434;13501.729965400735;21577.568448631097;25848.483030339477;2267.7278726552754;2456.4227406559444;2558;2655.933601659974;3017.5624534442604;10074.949625251827;10292.588296468133;10303.330283531546;10672.986066323687;10706.143508519377;1850.0102445191758;1920.5294702797903;1920.8847469151756;1921.8750781199178;1950.4246247357064;93551;121847;123629.14556281573;123714;129257.35371323083;9196.448213107235;11464.885351146382;11653.786861111108;11669.416529173508;11807.114466415052;14705.26473676312;14788.038777479429;14821.258937053104;14861.479848205261;14881.33034013472;6803.965980170067;8703.43427677199;8742.86885696715;8794.648214071494;8845.336599755045;8422.578871056423;8706.651733930572;8748.956255218684;8756.956215218883;8880.333974951775;4498.3927169832305;4570.474395444567;4574.702644328108;4622.583967442946;4641.280601506772;1538.323137819356;1603.4548253593834;1740.9283377627532;1943.5335519475225;1967.4589487890944;749.6401727170879;803.5058439059936;880.859062549989;916.158521372047;921.2307723051193;21228.681569776465;21783.455413614785;21955.890220549114;21966.011529481213;21973.340799776033;959.6257459590765;1008.2790804574618;1013.1692012549638;1016.2682868334787;1050.6847945616285;8440;11331.951347436183;11421.600243991421;11481.655550333504;11491.597794077166;552103;573241;583604;592769;593998;119449;120463.795362047;121672.39163804124;124824;128226.71773282147;1020.9183265338755;1021.6883850425543;1046.6755305855208;1071.2715353559654;1074.7635520185547;16195.595110122342;18676.81323186805;19003.71494427557;19086.23655053838;19103.904480477508;49743.00513989772;55431;55641;55672;56285;74560.25439745533;74809;79655;79911;80950;7787.872616274142;7790.182030886902;7993.120756716873;8138.633761480866;8362.916370836458;12124.939375303067;12831.550895718605;13128.212307261594;13175.011874109672;13349.532766353132;11355.318680879174;11483.311001339946;11568;11620.941895290469;11658.18392712502;10840.566377345134;11492;11521.423928803693;11647.534098636299;11816.527338906348;5860.267466566679;7843;8631.7483964826;11425.428728563706;11454.110071838053;6905.689243984133;7339.449541284318;7348.008018917378;8000.439969202333;8486.19381158789;8243.299319557666;10227.284090113919;11252.212345135762;11517.82723259135;11590.71023224426 10:11:40 INFO - PROCESS | 1970 | |3;mootools.html;1383.1908333624829;1478.2239324354714;1540.1606124662057;1754.833290837371;1924.4900101473113;7825.100113486951;8567.78580535486;8801.339899507531;8822.206001459876;8956.238719708816;8625.870611940825;8862.512561809099;8868.24619907307;8884.022757496687;8887.511186884718;2329.7768671447498;2347.730011048726;2369.9881500592473;2371;2371.2174982255815;2337.228714524203;2357.174988753931;2378.8691621960784;2379.095943541452;2379.6549500322444;2325.802306803915;2342;2353.51752890657;2367.183321753992;2374.881255937205;2313.803326717231;2324.8721320327377;2339.9298021059312;2342.0865862313713;2354.7880690737916;2319.478117423583;2320.8491448055825;2323.7095363079616;2326.9650955235516;2331.484535052213;8771.429857059335;8780.956095219546;8955.507447090407;8980.595873185723;9084.40951338161;5234.8691282717855;5389.218563308337;5392.838214853561;5399.433059528771;5404.162354835007;3531.2231309111953;3590.551181102362;3598.586162591283;3673.1368128489785;3683.650053244937;1067.5622994572266;1071.244772435439;1071.2715353559654;1081.3187691754224;1086.788076325117;11758.000569951564;11934.820977685342;11946.820797687955;11954.222975506651;11964.222325548812;4403.801828917706;4442.489113751895;4546.204414227497;4570.771461426915;4575.885602859955;9197.81604367909;9219.953900230455;9221;9243.352965292364;9259.981402045769;5208.322918020633;5209.140491818884;5213.895722085537;5232.633715639869;5242.370915490089;4346.7826608670075;4502.684812063124;4683.789229484613;4726.338312636233;4857.368542089505;2920.956185657217;3133.937321253562;3577.874774382884;3673.1551743098703;3830.0807806126327;1153.7519433321877;1164.0803765025705;1169.05306701572;1171.2328424881716;1176.3177357132843;562.9493345598916;725.6516871901517;867.9522626255616;917.5366439947784;962.605331813953;5560.749766260528;5848.298204215436;5871.3541510433815;5956.55325850563;6150.846228844314;476.1239319651788;477.63460952371463;482.78579372877243;498.832890981518;649.1560970738021;3174.873005079771;3794.2790869734663;3944.684425245973;3946.4869566956113;3968.2261958917734;10514.579416823395;10805;10851.023407893328;10954.507047182897;10990.285631433931;10645.148388128931;10808;10874;10901.400422976654;10929.398883061507;6660.433863121689;8268.09051004389;8340.499570025819;8364.790880228044;8371.246587807127;5765.106408506688;5838.532917366685;5862.355140934579;5990.7903223386975;6076.665783381956;9782.853257201004;9989.60041598328;10104.242181836245;10109;10121.089101980857;10230.232732545237;10279.743006424898;10296.021877921585;10348.430836304076;10365.378077315385;6898.827529311607;6993.545419547713;7065.399441047569;7075.292470752884;7079.256678048937;8178.059523154675;8352.87470687928;8355.415120941476;8363.874541881754;8423.157684231774;6113.51091912637;6842.828929276808;7279.272072792678;7568.962155189189;7622.847543049219;7378.483506154733;7515.436342274242;7531.209223031722;7568.886466702894;7684;6933.064036355027;6983.825404364931;7068.222495525591;7075.433965282662;7082.079329687108;4872.537108974641;4901.828436004723;4977.452480227244;5078.568321692697;5138.8972220556125;5021.146405111067;5026.421961474477;5119.564836988898;5161.845144645667;5258.0798360286035 10:11:40 INFO - PROCESS | 1970 | |4;prototype.html;297.8302367650439;304.593367853915;315.0187166974873;316.34200862206615;318.27115904578517;4137.565555616662;4295.398644189812;4404.62560682342;4572.611328037121;4883.925536381995;4306.72006319589;4929.7042177469375;4935.950640493603;5124.308218390516;5268.707058088695;3447.9483757453904;3954.861579844706;3979.1643754811525;3979.4877946380334;4017.1965606878593;3479;3795.0322667719693;3934.173823497069;3980.303446896796;3992.500937382827;3133.717965383115;3752.587215406303;3844.75009124406;3892.8832135035996;3989.683404476516;3902.2634927457293;3907.8667186515877;3934.2328245992044;3970.8014599270064;3976.2842688316236;3312.5528053712683;3444.7436928811667;3465.6880880720855;3467.549218601591;3468.358353704573;3785.564660064073;5051.621128415383;5062.594992400636;5163.251328557376;5311.49540793624;3820.192158763307;4469.3296005599095;4487.259602165639;4519.186546421643;4548.613367863735;4563.840265590688;4812.40415403289;5173.482651734834;5387.24578559002;5427.782888684448;3424.092615456906;3782.243551289753;3835.827387767529;3838.366669499529;3854.094287842355;4216.135692183095;5505.284313039319;5760.798372056958;5786.884262314814;5790.913136302959;3033.6814634463503;3666.2300916807235;3746.9625303747157;3747.725773237058;3760.7555508891833;3436.810975396377;3545.007397928584;3703.518542589446;3788.3938569828697;3828.942565861514;4182.414461975265;4441.467023957146;4442.577955094261;4456.532064133284;4457.576530229623;5222.00781851447;5418.376886658006;5442.782288708408;5452;5473.972630136824;1244.7055062734655;1292.6879217594148;1321.6167311479562;1325.3770727757937;1326.0584984660998;771.4753967302177;833.8582440985047;857.4897935728231;888.2982443869271;896.3232759266712;4122.484689413824;4925.236588328815;5039.798408063636;5131.229136266375;5152.871178220575;4744.288356746447;5599.216109744639;5623.2408624835125;5631.774729010794;5677.473524281655;4747.074320507537;4782.3543821584335;5050.6464547482055;5146.6911985281;5179.922301165486;2897.2322334581354;2909.767218622505;2913.3590610065753;2935.1488068459907;2941.4852400830196;4903.690009243746;5713.371529131792;5739.053056245756;5784.987627165229;5816.767329306865;4544.772761361919;5983.551233657493;6000.579959402888;6014.09788531715;6058.848528786815;5198.402183748842;5231.659942103751;5985.461308482258;6034.668093254825;6098.817035488765;5593.636413633101;5795.681237531976;5821.912671309849;5837.912431313449;5867.799575159315;5153.974230128825;5527.949689558971;5657.971710141423;5712.7714891403875;5744.396838331915;5659.858503537444;5996.37038110992;6051.878962420815;6057.303410107894;6072.2352141672445;3570.4465807799834;3846.942295865508;3851.7496362736333;3860.7876566787972;3897.5712671606666;4911.926321105115;5768.336641286138;5823.602335439548;5839.299284085937;5862.970685146547;5485.122380419114;5704.942950570441;5706.3152421709665;5709.457601527848;5761.625494342854;4234.915301693887;4668.269062598282;5465.9726701366235;5617.410171931889;5681.261436013292;4945.604351651931;5191.402988656353;5380.130981461783;5411.107167317506;5447.820871651371;4823.493533179106;5094.923576146435;5488.725563721878;5498.175273708815;5507.311586051744;5418.945810541844;5447.4824891635235;5485.807996720096;5487.807926722546;5509.807156749494 10:11:40 INFO - PROCESS | 1970 | |5;yui.html;721.3003386714887;737.7086051009851;794.9006374203225;810.8459392715383;821.1952286758976;14860.40558377665;15548.76676849848;15556.533304000885;15559.844401555982;15571.29929153188;14246.720524716047;14295.428182872698;14356.138631682106;14357.210353430557;14365;712.3482013957223;734.3024127079296;889.968851090212;897.4301318662647;917.554985831871;906.8141031088611;920.5305294299922;924.1820988425225;926.1340646495514;926.6802952981211;976.24828881761;976.8192884316388;977.8142152990945;978.5107446276861;978.7063880835757;739.6597565120052;924.8658944453017;952.9714108576753;960.4765402855461;976.9218462522981;719.1549928833599;721.7726416178887;722.8409749855024;722.9746958856467;723.1177962885272;14737.11577305365;14797.704045919128;14816.851831481656;14823.925880370529;14824;13310.534131305454;13563.254021028839;13563.52527661537;13582.185068895798;13706.053198562357;12305.323207223599;12384.442700078518;12445.564405245774;12506.937465312707;12545.686357841036;4427.291633338683;4496.3705081288645;4509.7970591323465;4511.278195488739;4562.315652652095;99993.50003249937;101828.98171018269;102994;102997;104809;10705.411202383944;10763.408012559228;10851.728706782236;10882.888273867373;11193.048590869705;21009.78990210109;21059.894700526398;21074.683879741817;21103.8944805275;21114.9442527873;7849.254320839511;7872.606369681493;7901.644426000844;8020;8089.02931648206;3832.1952389997855;4495.730256184639;4519.909601807945;4578.725276483355;4589.931151032737;3460.480927860791;3594.263176048904;3595.1551385424405;3596.7122630189524;3602.603713591503;559.2729451712758;562.1707980728393;565.8415643619793;661.83784972682;679.7349033876792;581.7637520269427;588.6556364526714;633.6071635585874;643.9227292724903;656.7077650445506;8864.113588641085;8930.73207803754;8952.328575356874;9007.909920900838;9107.954460227656;572.8539222498237;591.0690662207041;591.822453264019;640.8333833203401;645.4900628503524;4098.200850834119;4238.682098842599;4309.91380172401;4390.297552391602;4401.801918913592;105314.89370212705;130597.73505662245;136847;144782.27608861888;156408;98379.50810246046;127173;127833.36083319524;127942;128121.35939320244;110760.4461977685;111534.44232779108;112237.43881280541;112433;112610;8333.916660833314;8421.747347579583;8472.61873215719;8628.094050124646;8722.345824063348;44512.77743611391;53104.46895530995;62911.37088629055;65034.67482662557;65388.346116538225;92124;92170.07829921615;92419;92973.53513232661;94624.05375946153;9438.905610943802;11536.653900383002;11769.528399036299;12015.579454719043;12136.514539418324;12765.170263933187;13065.738685226432;13107.279099649611;13121.475140994253;13420.932895335462;10431.321964072615;12146.271223726604;12178.512859485521;12190.2076365036;12555.87444125547;10977.725556861142;11267.605633802777;11405.372704501;11525.769484610428;11544.82682759776;11840.230385024946;12041.33772642513;12071.698207544881;12189.146759726735;12423.62729118128;9640.325177237526;9694.418334899929;9786.314957952874;9882.30823842353;10040.598376065167;9215.631374745204;10292.897071029493;10313.74215644615;10341.586336546454;10349.534270957674 10:11:40 INFO - PROCESS | 1970 | __end_tp_report 10:11:40 INFO - PROCESS | 1970 | __start_cc_report 10:11:40 INFO - PROCESS | 1970 | _x_x_mozilla_cycle_collect,1398 10:11:40 INFO - PROCESS | 1970 | __end_cc_report 10:11:40 INFO - PROCESS | 1970 | __startTimestamp1467220300833__endTimestamp 10:11:40 INFO - PROCESS | 1970 | 10:11:40 INFO - PROCESS | 1970 | ------- Summary: start ------- 10:11:40 INFO - PROCESS | 1970 | Number of tests: 6 10:11:40 INFO - PROCESS | 1970 | 10:11:40 INFO - PROCESS | 1970 | [#0] dojo.html Cycles:165 Average:5759.43 Median:6028.94 stddev:760.22 (12.6%) stddev-sans-first:738.56 10:11:40 INFO - PROCESS | 1970 | Values: 3343.9 3346.2 3354.4 3390.4 3406.5 5604.2 5727.5 5768.4 5806.9 5852.8 5067.1 5720.2 5788.2 5796.5 5811.4 4864.6 4932.8 5067.8 5252.0 5376.1 4373.7 5307.7 5342.6 5384.8 5432.3 5366.9 5381.8 5392.0 5398.5 5440.8 905.5 5155.9 5246.2 5318.0 5348.4 5213.6 5256.1 5269.3 5269.7 5280.0 5695.1 5705.3 5714.9 5724.7 5899.4 6268.3 6290.0 6324.0 6335.6 6339.7 6239.5 6297.0 6302.1 6304.7 6312.1 5492.9 5730.4 5831.5 5846.7 5864.0 6335.2 6381.1 6384.3 6390.9 6400.0 6174.7 6271.2 6277.9 6288.2 6289.6 6143.8 6236.4 6275.8 6304.3 6305.2 5146.4 6028.9 6307.9 6310.3 6330.9 6165.7 6279.6 6308.1 6319.9 6335.7 4897.3 4909.7 4930.5 5002.1 5018.0 4483.3 4493.6 4500.7 4514.2 4528.8 5915.4 5925.2 5956.7 5963.2 6009.7 6213.2 6244.3 6265.9 6290.2 6327.7 5909.8 5910.4 5919.1 5922.0 5977.3 5469.2 5477.2 5483.1 5500.6 5503.1 6198.6 6232.0 6286.9 6301.9 6435.6 6243.2 6253.8 6265.0 6268.6 6280.1 6196.6 6346.4 6365.9 6425.7 6430.9 6191.0 6223.7 6286.0 6318.1 6323.9 6258.2 6273.7 6284.9 6289.5 6293.2 5122.3 5779.3 6028.2 6125.2 6228.0 6105.5 6205.7 6209.7 6219.1 6234.7 6055.0 6093.9 6095.9 6108.1 6124.5 5198.2 6025.0 6119.5 6132.7 6219.7 5934.4 6012.3 6063.9 6124.5 6134.6 10:11:40 INFO - PROCESS | 1970 | 10:11:40 INFO - PROCESS | 1970 | [#1] ext.html Cycles:180 Average:70978.55 Median:19905.50 stddev:159017.80 (798.9%) stddev-sans-first:159375.79 10:11:40 INFO - PROCESS | 1970 | Values: 480.3 506.9 511.2 522.3 557.3 30526.8 41368.0 42609.0 42863.6 42890.4 43438.8 43897.8 43960.0 43977.6 44099.8 8638.7 9437.5 9642.5 9662.3 9668.4 8668.3 11713.4 11927.6 11932.4 12138.7 11707.8 11809.2 11811.1 11841.0 11865.9 8733.2 8923.1 8926.2 11756.6 11881.8 8692.1 11387.6 11662.7 11665.4 11843.6 41941.0 42159.4 42344.4 42396.2 42402.4 3465.6 3493.1 3505.3 3623.7 3790.7 31750.8 32466.5 32515.7 32823.0 33205.8 3239.9 3263.7 3277.5 3347.4 3420.4 527816.0 529637.0 529695.0 530633.0 532391.0 34089.0 34901.0 59763.7 60103.7 60147.0 35959.6 36187.3 36242.4 36243.5 36263.0 14978.8 15319.2 15337.1 15342.7 15367.8 11761.0 11926.8 12745.6 12932.7 13011.1 7260.9 7481.4 7879.8 7881.4 7942.0 3220.7 3265.7 3278.1 3282.0 3304.0 941.5 942.2 964.4 1023.5 1024.4 30773.8 31151.0 31373.8 31718.5 31729.0 1086.9 1113.7 1159.6 1173.8 1227.7 13228.3 16054.1 16060.4 16335.0 16490.8 710976.0 713364.0 713709.0 714650.0 716018.0 60540.7 61251.7 61266.0 61373.7 61565.7 1263.3 1407.4 1432.5 1462.4 1463.4 28043.0 31888.8 32239.7 32327.8 33181.5 50240.5 50333.7 50738.7 50769.0 50826.0 513398.0 514385.0 514986.0 518886.0 518989.0 23790.2 26408.2 26527.6 27114.6 27150.6 49609.8 49667.5 50206.7 50677.5 50784.5 39395.8 40345.8 40718.6 41179.0 41554.8 32935.5 39851.8 40513.2 41190.4 42060.0 39453.8 40006.4 40200.0 40336.4 40489.0 34201.3 35151.8 35936.5 36020.8 36116.0 22253.2 23689.5 26385.1 26685.9 26811.7 10:11:40 INFO - PROCESS | 1970 | 10:11:40 INFO - PROCESS | 1970 | [#2] jquery.html Cycles:180 Average:33927.52 Median:12120.53 stddev:96875.36 (799.3%) stddev-sans-first:97114.25 10:11:40 INFO - PROCESS | 1970 | Values: 315.5 378.3 384.7 393.8 398.5 19921.0 20131.4 21058.6 21304.7 21323.9 11714.8 12069.3 12352.3 12390.1 12397.1 5293.2 5559.6 5580.6 5589.7 5592.0 3891.7 4163.3 5200.7 5871.6 6192.7 5913.3 6212.1 6214.1 6216.4 6224.4 5292.6 6062.8 6091.1 6167.1 6183.2 3755.9 5530.8 5882.9 5883.2 5994.1 13397.3 13495.9 13501.7 21577.6 25848.5 2267.7 2456.4 2558.0 2655.9 3017.6 10074.9 10292.6 10303.3 10673.0 10706.1 1850.0 1920.5 1920.9 1921.9 1950.4 93551.0 121847.0 123629.1 123714.0 129257.4 9196.4 11464.9 11653.8 11669.4 11807.1 14705.3 14788.0 14821.3 14861.5 14881.3 6804.0 8703.4 8742.9 8794.6 8845.3 8422.6 8706.7 8749.0 8757.0 8880.3 4498.4 4570.5 4574.7 4622.6 4641.3 1538.3 1603.5 1740.9 1943.5 1967.5 749.6 803.5 880.9 916.2 921.2 21228.7 21783.5 21955.9 21966.0 21973.3 959.6 1008.3 1013.2 1016.3 1050.7 8440.0 11332.0 11421.6 11481.7 11491.6 552103.0 573241.0 583604.0 592769.0 593998.0 119449.0 120463.8 121672.4 124824.0 128226.7 1020.9 1021.7 1046.7 1071.3 1074.8 16195.6 18676.8 19003.7 19086.2 19103.9 49743.0 55431.0 55641.0 55672.0 56285.0 74560.3 74809.0 79655.0 79911.0 80950.0 7787.9 7790.2 7993.1 8138.6 8362.9 12124.9 12831.6 13128.2 13175.0 13349.5 11355.3 11483.3 11568.0 11620.9 11658.2 10840.6 11492.0 11521.4 11647.5 11816.5 5860.3 7843.0 8631.7 11425.4 11454.1 6905.7 7339.4 7348.0 8000.4 8486.2 8243.3 10227.3 11252.2 11517.8 11590.7 10:11:40 INFO - PROCESS | 1970 | 10:11:40 INFO - PROCESS | 1970 | [#3] mootools.html Cycles:180 Average:5661.18 Median:4864.95 stddev:3245.46 (66.7%) stddev-sans-first:3238.64 10:11:40 INFO - PROCESS | 1970 | Values: 1383.2 1478.2 1540.2 1754.8 1924.5 7825.1 8567.8 8801.3 8822.2 8956.2 8625.9 8862.5 8868.2 8884.0 8887.5 2329.8 2347.7 2370.0 2371.0 2371.2 2337.2 2357.2 2378.9 2379.1 2379.7 2325.8 2342.0 2353.5 2367.2 2374.9 2313.8 2324.9 2339.9 2342.1 2354.8 2319.5 2320.8 2323.7 2327.0 2331.5 8771.4 8781.0 8955.5 8980.6 9084.4 5234.9 5389.2 5392.8 5399.4 5404.2 3531.2 3590.6 3598.6 3673.1 3683.7 1067.6 1071.2 1071.3 1081.3 1086.8 11758.0 11934.8 11946.8 11954.2 11964.2 4403.8 4442.5 4546.2 4570.8 4575.9 9197.8 9220.0 9221.0 9243.4 9260.0 5208.3 5209.1 5213.9 5232.6 5242.4 4346.8 4502.7 4683.8 4726.3 4857.4 2921.0 3133.9 3577.9 3673.2 3830.1 1153.8 1164.1 1169.1 1171.2 1176.3 562.9 725.7 868.0 917.5 962.6 5560.7 5848.3 5871.4 5956.6 6150.8 476.1 477.6 482.8 498.8 649.2 3174.9 3794.3 3944.7 3946.5 3968.2 10514.6 10805.0 10851.0 10954.5 10990.3 10645.1 10808.0 10874.0 10901.4 10929.4 6660.4 8268.1 8340.5 8364.8 8371.2 5765.1 5838.5 5862.4 5990.8 6076.7 9782.9 9989.6 10104.2 10109.0 10121.1 10230.2 10279.7 10296.0 10348.4 10365.4 6898.8 6993.5 7065.4 7075.3 7079.3 8178.1 8352.9 8355.4 8363.9 8423.2 6113.5 6842.8 7279.3 7569.0 7622.8 7378.5 7515.4 7531.2 7568.9 7684.0 6933.1 6983.8 7068.2 7075.4 7082.1 4872.5 4901.8 4977.5 5078.6 5138.9 5021.1 5026.4 5119.6 5161.8 5258.1 10:11:40 INFO - PROCESS | 1970 | 10:11:40 INFO - PROCESS | 1970 | [#4] prototype.html Cycles:180 Average:4444.66 Median:4927.47 stddev:1396.88 (28.3%) stddev-sans-first:1365.68 10:11:40 INFO - PROCESS | 1970 | Values: 297.8 304.6 315.0 316.3 318.3 4137.6 4295.4 4404.6 4572.6 4883.9 4306.7 4929.7 4936.0 5124.3 5268.7 3447.9 3954.9 3979.2 3979.5 4017.2 3479.0 3795.0 3934.2 3980.3 3992.5 3133.7 3752.6 3844.8 3892.9 3989.7 3902.3 3907.9 3934.2 3970.8 3976.3 3312.6 3444.7 3465.7 3467.5 3468.4 3785.6 5051.6 5062.6 5163.3 5311.5 3820.2 4469.3 4487.3 4519.2 4548.6 4563.8 4812.4 5173.5 5387.2 5427.8 3424.1 3782.2 3835.8 3838.4 3854.1 4216.1 5505.3 5760.8 5786.9 5790.9 3033.7 3666.2 3747.0 3747.7 3760.8 3436.8 3545.0 3703.5 3788.4 3828.9 4182.4 4441.5 4442.6 4456.5 4457.6 5222.0 5418.4 5442.8 5452.0 5474.0 1244.7 1292.7 1321.6 1325.4 1326.1 771.5 833.9 857.5 888.3 896.3 4122.5 4925.2 5039.8 5131.2 5152.9 4744.3 5599.2 5623.2 5631.8 5677.5 4747.1 4782.4 5050.6 5146.7 5179.9 2897.2 2909.8 2913.4 2935.1 2941.5 4903.7 5713.4 5739.1 5785.0 5816.8 4544.8 5983.6 6000.6 6014.1 6058.8 5198.4 5231.7 5985.5 6034.7 6098.8 5593.6 5795.7 5821.9 5837.9 5867.8 5154.0 5527.9 5658.0 5712.8 5744.4 5659.9 5996.4 6051.9 6057.3 6072.2 3570.4 3846.9 3851.7 3860.8 3897.6 4911.9 5768.3 5823.6 5839.3 5863.0 5485.1 5704.9 5706.3 5709.5 5761.6 4234.9 4668.3 5466.0 5617.4 5681.3 4945.6 5191.4 5380.1 5411.1 5447.8 4823.5 5094.9 5488.7 5498.2 5507.3 5418.9 5447.5 5485.8 5487.8 5509.8 10:11:40 INFO - PROCESS | 1970 | 10:11:40 INFO - PROCESS | 1970 | [#5] yui.html Cycles:180 Average:23710.17 Median:4350.11 stddev:37783.22 (868.6%) stddev-sans-first:37849.78 10:11:40 INFO - PROCESS | 1970 | Values: 721.3 737.7 794.9 810.8 821.2 14860.4 15548.8 15556.5 15559.8 15571.3 14246.7 14295.4 14356.1 14357.2 14365.0 712.3 734.3 890.0 897.4 917.6 906.8 920.5 924.2 926.1 926.7 976.2 976.8 977.8 978.5 978.7 739.7 924.9 953.0 960.5 976.9 719.2 721.8 722.8 723.0 723.1 14737.1 14797.7 14816.9 14823.9 14824.0 13310.5 13563.3 13563.5 13582.2 13706.1 12305.3 12384.4 12445.6 12506.9 12545.7 4427.3 4496.4 4509.8 4511.3 4562.3 99993.5 101829.0 102994.0 102997.0 104809.0 10705.4 10763.4 10851.7 10882.9 11193.0 21009.8 21059.9 21074.7 21103.9 21114.9 7849.3 7872.6 7901.6 8020.0 8089.0 3832.2 4495.7 4519.9 4578.7 4589.9 3460.5 3594.3 3595.2 3596.7 3602.6 559.3 562.2 565.8 661.8 679.7 581.8 588.7 633.6 643.9 656.7 8864.1 8930.7 8952.3 9007.9 9108.0 572.9 591.1 591.8 640.8 645.5 4098.2 4238.7 4309.9 4390.3 4401.8 105314.9 130597.7 136847.0 144782.3 156408.0 98379.5 127173.0 127833.4 127942.0 128121.4 110760.4 111534.4 112237.4 112433.0 112610.0 8333.9 8421.7 8472.6 8628.1 8722.3 44512.8 53104.5 62911.4 65034.7 65388.3 92124.0 92170.1 92419.0 92973.5 94624.1 9438.9 11536.7 11769.5 12015.6 12136.5 12765.2 13065.7 13107.3 13121.5 13420.9 10431.3 12146.3 12178.5 12190.2 12555.9 10977.7 11267.6 11405.4 11525.8 11544.8 11840.2 12041.3 12071.7 12189.1 12423.6 9640.3 9694.4 9786.3 9882.3 10040.6 9215.6 10292.9 10313.7 10341.6 10349.5 10:11:40 INFO - PROCESS | 1970 | -------- Summary: end -------- 10:11:40 INFO - PROCESS | 1970 | 10:11:41 INFO - TEST-INFO | 1970: exit 0 10:11:41 INFO - TEST-OK | dromaeo_css | took 1116524ms 10:11:41 INFO - TEST-START | kraken 10:11:41 INFO - operating with platform_type : mac_ 10:11:41 INFO - Initialising browser for kraken test... 10:11:41 INFO - TEST-INFO | started process 1972 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpkFwkCI/profile http://localhost:50837/getInfo.html) 10:11:43 INFO - PROCESS | 1972 | __metrics Screen width/height:1600/1200 10:11:43 INFO - PROCESS | 1972 | colorDepth:24 10:11:43 INFO - PROCESS | 1972 | Browser inner width/height: 1024/689 10:11:43 INFO - PROCESS | 1972 | __metrics 10:11:43 INFO - PROCESS | 1972 | 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" 10:11:43 INFO - PROCESS | 1972 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:11:44 INFO - TEST-INFO | 1972: exit 0 10:11:44 INFO - Browser initialized. 10:11:44 INFO - Running cycle 1/1 for kraken test... 10:11:44 INFO - TEST-INFO | started process 1973 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpkFwkCI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:11:56 INFO - PROCESS | 1973 | RSS: Main: 243503104 10:11:56 INFO - PROCESS | 1973 | 10:11:58 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:50837/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50837/tests/kraken/driver.html?testName=audio-beat-detection) 10:11:59 INFO - PROCESS | 1973 | RSS: Main: 294121472 10:11:59 INFO - PROCESS | 1973 | 10:12:02 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:50837/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50837/tests/kraken/driver.html?testName=audio-dft) 10:12:03 INFO - PROCESS | 1973 | RSS: Main: 304246784 10:12:03 INFO - PROCESS | 1973 | 10:12:06 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:50837/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50837/tests/kraken/driver.html?testName=audio-fft) 10:12:07 INFO - PROCESS | 1973 | RSS: Main: 389500928 10:12:07 INFO - PROCESS | 1973 | 10:12:09 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:50837/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50837/tests/kraken/driver.html?testName=audio-oscillator) 10:12:10 INFO - PROCESS | 1973 | RSS: Main: 290713600 10:12:10 INFO - PROCESS | 1973 | 10:12:12 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:50837/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50837/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:12:12 INFO - PROCESS | 1973 | RSS: Main: 294481920 10:12:12 INFO - PROCESS | 1973 | 10:12:15 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:50837/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50837/tests/kraken/driver.html?testName=imaging-darkroom) 10:12:16 INFO - PROCESS | 1973 | RSS: Main: 331149312 10:12:16 INFO - PROCESS | 1973 | 10:12:18 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:50837/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50837/tests/kraken/driver.html?testName=imaging-desaturate) 10:12:19 INFO - PROCESS | 1973 | RSS: Main: 334020608 10:12:19 INFO - PROCESS | 1973 | 10:12:22 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:50837/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50837/tests/kraken/driver.html?testName=json-parse-financial) 10:12:23 INFO - PROCESS | 1973 | RSS: Main: 331554816 10:12:23 INFO - PROCESS | 1973 | 10:12:24 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:50837/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50837/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:12:25 INFO - PROCESS | 1973 | RSS: Main: 312623104 10:12:25 INFO - PROCESS | 1973 | 10:12:27 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:50837/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50837/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:12:27 INFO - PROCESS | 1973 | RSS: Main: 319668224 10:12:27 INFO - PROCESS | 1973 | 10:12:30 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:50837/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50837/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:12:30 INFO - PROCESS | 1973 | RSS: Main: 322072576 10:12:30 INFO - PROCESS | 1973 | 10:12:33 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:50837/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50837/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:12:34 INFO - PROCESS | 1973 | RSS: Main: 336969728 10:12:34 INFO - PROCESS | 1973 | 10:12:37 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:50837/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50837/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:12:38 INFO - PROCESS | 1973 | RSS: Main: 333185024 10:12:38 INFO - PROCESS | 1973 | 10:12:39 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:50837/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50837/tests/kraken/driver.html?testName=ai-astar) 10:12:40 INFO - PROCESS | 1973 | RSS: Main: 376414208 10:12:40 INFO - PROCESS | 1973 | 10:12:40 INFO - PROCESS | 1973 | __start_tp_report 10:12:40 INFO - PROCESS | 1973 | _x_x_mozilla_page_load 10:12:40 INFO - PROCESS | 1973 | _x_x_mozilla_page_load_details 10:12:40 INFO - PROCESS | 1973 | |i|pagename|runs| 10:12:40 INFO - PROCESS | 1973 | |0;ai-astar;98;117;118;111;111;113;154;113;111;112 10:12:40 INFO - PROCESS | 1973 | |1;audio-beat-detection;156;119;117;174;119;120;142;118;160;121 10:12:40 INFO - PROCESS | 1973 | |2;audio-dft;185;185;183;184;186;181;183;181;180;185 10:12:40 INFO - PROCESS | 1973 | |3;audio-fft;78;74;58;106;58;56;63;60;95;72 10:12:40 INFO - PROCESS | 1973 | |4;audio-oscillator;87;89;95;90;102;99;109;70;68;99 10:12:40 INFO - PROCESS | 1973 | |5;imaging-gaussian-blur;86;95;86;84;86;83;87;83;87;84 10:12:40 INFO - PROCESS | 1973 | |6;imaging-darkroom;93;96;93;93;92;93;93;93;96;88 10:12:40 INFO - PROCESS | 1973 | |7;imaging-desaturate;84;84;86;94;75;122;139;133;84;85 10:12:40 INFO - PROCESS | 1973 | |8;json-parse-financial;56;54;53;54;54;54;54;53;54;53 10:12:40 INFO - PROCESS | 1973 | |9;json-stringify-tinderbox;68;69;78;78;72;70;72;71;71;73 10:12:40 INFO - PROCESS | 1973 | |10;stanford-crypto-aes;85;85;85;84;85;86;86;93;120;101 10:12:40 INFO - PROCESS | 1973 | |11;stanford-crypto-ccm;143;141;141;137;137;140;138;148;145;140 10:12:40 INFO - PROCESS | 1973 | |12;stanford-crypto-pbkdf2;168;168;170;170;170;221;206;167;167;166 10:12:40 INFO - PROCESS | 1973 | |13;stanford-crypto-sha256-iterative;67;66;68;66;67;66;76;85;86;70 10:12:40 INFO - PROCESS | 1973 | __end_tp_report 10:12:40 INFO - PROCESS | 1973 | __start_cc_report 10:12:40 INFO - PROCESS | 1973 | _x_x_mozilla_cycle_collect,2096 10:12:40 INFO - PROCESS | 1973 | __end_cc_report 10:12:40 INFO - PROCESS | 1973 | __startTimestamp1467220360048__endTimestamp 10:12:40 INFO - PROCESS | 1973 | 10:12:40 INFO - PROCESS | 1973 | ------- Summary: start ------- 10:12:40 INFO - PROCESS | 1973 | Number of tests: 14 10:12:40 INFO - PROCESS | 1973 | 10:12:40 INFO - PROCESS | 1973 | [#0] ai-astar Cycles:10 Average:115.80 Median:115.00 stddev:14.46 (12.6%) stddev-sans-first:13.83 10:12:40 INFO - PROCESS | 1973 | Values: 98.0 117.0 118.0 111.0 111.0 113.0 154.0 113.0 111.0 112.0 10:12:40 INFO - PROCESS | 1973 | 10:12:40 INFO - PROCESS | 1973 | [#1] audio-beat-detection Cycles:10 Average:134.60 Median:131.50 stddev:21.55 (16.4%) stddev-sans-first:21.42 10:12:40 INFO - PROCESS | 1973 | Values: 156.0 119.0 117.0 174.0 119.0 120.0 142.0 118.0 160.0 121.0 10:12:40 INFO - PROCESS | 1973 | 10:12:40 INFO - PROCESS | 1973 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.50 stddev:2.06 (1.1%) stddev-sans-first:2.09 10:12:40 INFO - PROCESS | 1973 | Values: 185.0 185.0 183.0 184.0 186.0 181.0 183.0 181.0 180.0 185.0 10:12:40 INFO - PROCESS | 1973 | 10:12:40 INFO - PROCESS | 1973 | [#3] audio-fft Cycles:10 Average:72.00 Median:67.50 stddev:16.99 (25.2%) stddev-sans-first:17.88 10:12:40 INFO - PROCESS | 1973 | Values: 78.0 74.0 58.0 106.0 58.0 56.0 63.0 60.0 95.0 72.0 10:12:40 INFO - PROCESS | 1973 | 10:12:40 INFO - PROCESS | 1973 | [#4] audio-oscillator Cycles:10 Average:90.80 Median:89.50 stddev:13.25 (14.8%) stddev-sans-first:13.98 10:12:40 INFO - PROCESS | 1973 | Values: 87.0 89.0 95.0 90.0 102.0 99.0 109.0 70.0 68.0 99.0 10:12:40 INFO - PROCESS | 1973 | 10:12:40 INFO - PROCESS | 1973 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:86.00 stddev:3.48 (4.0%) stddev-sans-first:3.69 10:12:40 INFO - PROCESS | 1973 | Values: 86.0 95.0 86.0 84.0 86.0 83.0 87.0 83.0 87.0 84.0 10:12:40 INFO - PROCESS | 1973 | 10:12:40 INFO - PROCESS | 1973 | [#6] imaging-darkroom Cycles:10 Average:93.00 Median:93.00 stddev:2.21 (2.4%) stddev-sans-first:2.35 10:12:40 INFO - PROCESS | 1973 | Values: 93.0 96.0 93.0 93.0 92.0 93.0 93.0 93.0 96.0 88.0 10:12:40 INFO - PROCESS | 1973 | 10:12:40 INFO - PROCESS | 1973 | [#7] imaging-desaturate Cycles:10 Average:98.60 Median:84.00 stddev:23.39 (27.8%) stddev-sans-first:24.21 10:12:40 INFO - PROCESS | 1973 | Values: 84.0 84.0 86.0 94.0 75.0 122.0 139.0 133.0 84.0 85.0 10:12:40 INFO - PROCESS | 1973 | 10:12:40 INFO - PROCESS | 1973 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:0.88 (1.6%) stddev-sans-first:0.50 10:12:40 INFO - PROCESS | 1973 | Values: 56.0 54.0 53.0 54.0 54.0 54.0 54.0 53.0 54.0 53.0 10:12:40 INFO - PROCESS | 1973 | 10:12:40 INFO - PROCESS | 1973 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:72.00 stddev:3.39 (4.7%) stddev-sans-first:3.24 10:12:40 INFO - PROCESS | 1973 | Values: 68.0 69.0 78.0 78.0 72.0 70.0 72.0 71.0 71.0 73.0 10:12:40 INFO - PROCESS | 1973 | 10:12:40 INFO - PROCESS | 1973 | [#10] stanford-crypto-aes Cycles:10 Average:91.00 Median:85.00 stddev:11.49 (13.5%) stddev-sans-first:11.98 10:12:40 INFO - PROCESS | 1973 | Values: 85.0 85.0 85.0 84.0 85.0 86.0 86.0 93.0 120.0 101.0 10:12:40 INFO - PROCESS | 1973 | 10:12:40 INFO - PROCESS | 1973 | [#11] stanford-crypto-ccm Cycles:10 Average:141.00 Median:141.00 stddev:3.53 (2.5%) stddev-sans-first:3.67 10:12:40 INFO - PROCESS | 1973 | Values: 143.0 141.0 141.0 137.0 137.0 140.0 138.0 148.0 145.0 140.0 10:12:40 INFO - PROCESS | 1973 | 10:12:40 INFO - PROCESS | 1973 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.30 Median:170.00 stddev:19.45 (11.4%) stddev-sans-first:20.34 10:12:40 INFO - PROCESS | 1973 | Values: 168.0 168.0 170.0 170.0 170.0 221.0 206.0 167.0 167.0 166.0 10:12:40 INFO - PROCESS | 1973 | 10:12:40 INFO - PROCESS | 1973 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.70 Median:69.00 stddev:7.87 (11.4%) stddev-sans-first:8.17 10:12:40 INFO - PROCESS | 1973 | Values: 67.0 66.0 68.0 66.0 67.0 66.0 76.0 85.0 86.0 70.0 10:12:40 INFO - PROCESS | 1973 | -------- Summary: end -------- 10:12:40 INFO - PROCESS | 1973 | 10:12:40 INFO - TEST-INFO | 1973: exit 0 10:12:40 INFO - TEST-OK | kraken | took 59438ms 10:12:40 INFO - SUITE-END | took 1175s 10:12:41 INFO - Completed test suite (00:19:36) 10:12:41 INFO - javascript benchmark 10:12:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3343.933121337573, 3346.163459135216, 3354.3962086824363, 3390.406678831207, 3406.50605662179, 5604.187392828037, 5727.513161381283, 5768.423157684229, 5806.883862322751, 5852.795152169675, 5067.113255180348, 5720.22776925115, 5788.218590490283, 5796.50729687977, 5811.360750317473, 4864.635152363587, 4932.8026878924975, 5067.8479645610505, 5252.0, 5376.1398176291805, 4373.715708478939, 5307.655002424849, 5342.599305052117, 5384.838454846361, 5432.266644003049, 5366.865828354303, 5381.784728610871, 5391.973040134794, 5398.514133727964, 5440.836774896759, 905.5070449963945, 5155.9484405155845, 5246.186841039645, 5317.973410132963, 5348.411674715778, 5213.635045546814, 5256.106461901486, 5269.288646032813, 5269.657472264291, 5280.0, 5695.088785794253, 5705.315362156526, 5714.942850571482, 5724.656520608777, 5899.380565040652, 6268.341824108448, 6290.0, 6324.019776934537, 6335.5565110442285, 6339.714712837887, 6239.469645080174, 6296.992481203031, 6302.054691796267, 6304.653244071575, 6312.147860038882, 5492.917606235909, 5730.36965933747, 5831.533477321804, 5846.7368968396095, 5863.970680146657, 6335.176427064452, 6381.1385462962835, 6384.329645387167, 6390.872182556416, 6400.039994000937, 6174.6912654368, 6271.216097987752, 6277.874442511215, 6288.213973253343, 6289.55973081889, 6143.818944274807, 6236.376362363818, 6275.780347687809, 6304.338044505353, 6305.211848518935, 5146.433892271847, 6028.9397106029255, 6307.873842523124, 6310.274318453345, 6330.905036424458, 6165.722542485599, 6279.623222606567, 6308.085327627468, 6319.905201421982, 6335.714892829834, 4897.2898929655, 4909.705417674951, 4930.482299358588, 5002.074616196015, 5017.974910125426, 4483.2826747720865, 4493.550644935481, 4500.684952053325, 4514.164879497303, 4528.818847246073, 5915.438033386821, 5925.229720136441, 5956.6723830188885, 5963.224780778471, 6009.699515024231, 6213.1922850029205, 6244.313125556185, 6265.8746825063245, 6290.182276304051, 6327.683615819191, 5909.763609455659, 5910.408959104056, 5919.082542205965, 5921.970390148108, 5977.342492325841, 5469.15228139639, 5477.233187353773, 5483.067878460592, 5500.614956952975, 5503.091989821634, 6198.6280823150755, 6231.9688401557705, 6286.905696414466, 6301.905471417841, 6435.646039467687, 6243.219597550306, 6253.812385628438, 6265.0, 6268.592541484789, 6280.120783090462, 6196.566240363131, 6346.365363463617, 6365.872682546231, 6425.678716064272, 6430.935690643034, 6191.0, 6223.719932603134, 6286.025666021591, 6318.052292156213, 6323.873522529616, 6258.249010118815, 6273.749050037947, 6284.874302514016, 6289.528285378707, 6293.244810622755, 5122.308488354024, 5779.335376431764, 6028.186194863637, 6125.203723515914, 6228.034654628359, 6105.520399749632, 6205.65868877198, 6209.720562574786, 6219.0982307566055, 6234.719437625227, 6054.969725151346, 6093.878122437615, 6095.8518082563905, 6108.08378743192, 6124.485966383171, 5198.194279886624, 6024.969875150771, 6119.502553941335, 6132.6627035513475, 6219.657918814509, 5934.376890426443, 6012.278526576839, 6063.878722425615, 6124.479419249235, 6134.631922084689], "value": 5712.421521094599, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.3275414419814, 506.8682142642916, 511.1974200505207, 522.2844702757224, 557.3339858868651, 30526.84736576317, 41368.0, 42608.99999999996, 42863.57136428635, 42890.356644650274, 43438.782806085925, 43897.78051109748, 43960.0, 43977.56022439775, 44099.77950110245, 8638.740837774845, 9437.480938548377, 9642.469664168468, 9662.275329350292, 9668.371555848847, 8668.26319762821, 11713.414329283502, 11927.58253461129, 11932.403379831017, 12138.696532586668, 11707.824382634268, 11809.173357864953, 11811.114166437552, 11841.0, 11865.881341186563, 8733.170348816851, 8923.10768923109, 8926.2412694921, 11756.647300580953, 11881.821772673373, 8692.087330830234, 11387.601433949772, 11662.650120496399, 11665.358405287705, 11843.644690659294, 41941.0, 42159.36760948588, 42344.36483452751, 42396.1520769586, 42402.36396454056, 3465.601455832561, 3493.0917961329983, 3505.263894582141, 3623.7282203834816, 3790.6967442604528, 31750.841245793854, 32466.513002304986, 32515.6748432515, 32823.0, 33205.83397083023, 3239.919002024945, 3263.738900887923, 3277.5247589099686, 3347.3640008398324, 3420.350133474632, 527816.0, 529637.0, 529695.0, 530633.0, 532391.0, 34089.0, 34901.0, 59763.701181493816, 60103.69948150319, 60147.0, 35959.64040359615, 36187.27625447476, 36242.36909339086, 36243.456348154796, 36263.0, 14978.775318370233, 15319.157446340336, 15337.079775213522, 15342.693146137015, 15367.846321536641, 11761.0, 11926.761464770829, 12745.553905613258, 12932.741345173044, 13011.089223754247, 7260.947162661385, 7481.364084052808, 7879.803004924808, 7881.369490440751, 7942.007249093864, 3220.726238269773, 3265.706086452183, 3278.1149089745636, 3282.0, 3303.9834800825843, 941.505709502503, 942.2132519346422, 964.4261664309724, 1023.4545835788014, 1024.4263212600968, 30773.8461307692, 31151.0, 31373.8431307842, 31718.524222136686, 31729.0, 1086.940218287986, 1113.716002419378, 1159.5999380213814, 1173.7711146326385, 1227.6501197158946, 13228.272445015617, 16054.117023563582, 16060.43788467398, 16335.01989880611, 16490.75263871043, 710976.0, 713364.0, 713709.0, 714650.0, 716018.0, 60540.69729651324, 61251.69374153101, 61266.0, 61373.693131534055, 61565.69217153885, 1263.2673049631192, 1407.394820227312, 1432.534426311453, 1462.444271176946, 1463.3853781411833, 28043.0, 31888.840555797073, 32239.677603224605, 32327.838360808044, 33181.50227746634, 50240.49759502358, 50333.74833125811, 50738.731531712, 50769.0, 50826.0, 513398.0, 514385.0, 514986.0, 518886.0, 518989.0, 23790.215733819685, 26408.20775376742, 26527.60208596834, 27114.593281101195, 27150.592741109293, 49609.75195124001, 49667.503324967736, 50206.748966254934, 50677.49322506875, 50784.492155077976, 39395.80302098471, 40345.798271008454, 40718.59281407148, 41178.970525737095, 41554.75335739933, 32935.50596741099, 39851.80074099611, 40513.1897362057, 41190.38214426846, 42060.0, 39453.802730986165, 40006.39990400088, 40199.99500012523, 40336.39495407513, 40489.0, 34201.31597368088, 35151.82424087863, 35936.46095308625, 36020.81989590035, 36116.0, 22253.233322534696, 23689.52620947606, 26385.076522321626, 26685.86657066702, 26811.731882680924], "value": 19321.0152155937, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.4637116901268, 378.3284669711262, 384.6903242889473, 393.8306528192876, 398.5257543523207, 19921.003949802507, 20131.396058118262, 21058.578828423462, 21304.680429793567, 21323.893380533096, 11714.7657046859, 12069.275843449399, 12352.258864468138, 12390.132690711655, 12397.070219733534, 5293.232481290201, 5559.63862348946, 5580.553555715533, 5589.692566908819, 5591.9720401397935, 3891.747036442622, 4163.333866581347, 5200.68795872247, 5871.618344807595, 6192.690365481731, 5913.319968203668, 6212.068189771525, 6214.130021796952, 6216.440520353167, 6224.439800417961, 5292.576593872481, 6062.787802426938, 6091.0863370494335, 6167.105769663396, 6183.227096612924, 3755.9436608450897, 5530.834074977757, 5882.941170588282, 5883.205767221413, 5994.100884867261, 13397.330133493286, 13495.932520337434, 13501.729965400735, 21577.568448631097, 25848.483030339477, 2267.7278726552754, 2456.4227406559444, 2558.0, 2655.933601659974, 3017.5624534442604, 10074.949625251827, 10292.588296468133, 10303.330283531546, 10672.986066323687, 10706.143508519377, 1850.0102445191758, 1920.5294702797903, 1920.8847469151756, 1921.8750781199178, 1950.4246247357064, 93551.0, 121847.0, 123629.14556281573, 123714.0, 129257.35371323083, 9196.448213107235, 11464.885351146382, 11653.786861111108, 11669.416529173508, 11807.114466415052, 14705.26473676312, 14788.038777479429, 14821.258937053104, 14861.479848205261, 14881.33034013472, 6803.965980170067, 8703.43427677199, 8742.86885696715, 8794.648214071494, 8845.336599755045, 8422.578871056423, 8706.651733930572, 8748.956255218684, 8756.956215218883, 8880.333974951775, 4498.3927169832305, 4570.474395444567, 4574.702644328108, 4622.583967442946, 4641.280601506772, 1538.323137819356, 1603.4548253593834, 1740.9283377627532, 1943.5335519475225, 1967.4589487890944, 749.6401727170879, 803.5058439059936, 880.859062549989, 916.158521372047, 921.2307723051193, 21228.681569776465, 21783.455413614785, 21955.890220549114, 21966.011529481213, 21973.340799776033, 959.6257459590765, 1008.2790804574618, 1013.1692012549638, 1016.2682868334787, 1050.6847945616285, 8440.0, 11331.951347436183, 11421.600243991421, 11481.655550333504, 11491.597794077166, 552103.0, 573241.0, 583604.0, 592769.0, 593998.0, 119449.0, 120463.795362047, 121672.39163804124, 124824.0, 128226.71773282147, 1020.9183265338755, 1021.6883850425543, 1046.6755305855208, 1071.2715353559654, 1074.7635520185547, 16195.595110122342, 18676.81323186805, 19003.71494427557, 19086.23655053838, 19103.904480477508, 49743.00513989772, 55431.0, 55641.0, 55672.0, 56285.0, 74560.25439745533, 74809.0, 79655.0, 79911.0, 80950.0, 7787.872616274142, 7790.182030886902, 7993.120756716873, 8138.633761480866, 8362.916370836458, 12124.939375303067, 12831.550895718605, 13128.212307261594, 13175.011874109672, 13349.532766353132, 11355.318680879174, 11483.311001339946, 11568.0, 11620.941895290469, 11658.18392712502, 10840.566377345134, 11492.0, 11521.423928803693, 11647.534098636299, 11816.527338906348, 5860.267466566679, 7843.0, 8631.7483964826, 11425.428728563706, 11454.110071838053, 6905.689243984133, 7339.449541284318, 7348.008018917378, 8000.439969202333, 8486.19381158789, 8243.299319557666, 10227.284090113919, 11252.212345135762, 11517.82723259135, 11590.71023224426], "value": 9269.57229078553, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1383.1908333624829, 1478.2239324354714, 1540.1606124662057, 1754.833290837371, 1924.4900101473113, 7825.100113486951, 8567.78580535486, 8801.339899507531, 8822.206001459876, 8956.238719708816, 8625.870611940825, 8862.512561809099, 8868.24619907307, 8884.022757496687, 8887.511186884718, 2329.7768671447498, 2347.730011048726, 2369.9881500592473, 2371.0, 2371.2174982255815, 2337.228714524203, 2357.174988753931, 2378.8691621960784, 2379.095943541452, 2379.6549500322444, 2325.802306803915, 2342.0, 2353.51752890657, 2367.183321753992, 2374.881255937205, 2313.803326717231, 2324.8721320327377, 2339.9298021059312, 2342.0865862313713, 2354.7880690737916, 2319.478117423583, 2320.8491448055825, 2323.7095363079616, 2326.9650955235516, 2331.484535052213, 8771.429857059335, 8780.956095219546, 8955.507447090407, 8980.595873185723, 9084.40951338161, 5234.8691282717855, 5389.218563308337, 5392.838214853561, 5399.433059528771, 5404.162354835007, 3531.2231309111953, 3590.551181102362, 3598.586162591283, 3673.1368128489785, 3683.650053244937, 1067.5622994572266, 1071.244772435439, 1071.2715353559654, 1081.3187691754224, 1086.788076325117, 11758.000569951564, 11934.820977685342, 11946.820797687955, 11954.222975506651, 11964.222325548812, 4403.801828917706, 4442.489113751895, 4546.204414227497, 4570.771461426915, 4575.885602859955, 9197.81604367909, 9219.953900230455, 9221.0, 9243.352965292364, 9259.981402045769, 5208.322918020633, 5209.140491818884, 5213.895722085537, 5232.633715639869, 5242.370915490089, 4346.7826608670075, 4502.684812063124, 4683.789229484613, 4726.338312636233, 4857.368542089505, 2920.956185657217, 3133.937321253562, 3577.874774382884, 3673.1551743098703, 3830.0807806126327, 1153.7519433321877, 1164.0803765025705, 1169.05306701572, 1171.2328424881716, 1176.3177357132843, 562.9493345598916, 725.6516871901517, 867.9522626255616, 917.5366439947784, 962.605331813953, 5560.749766260528, 5848.298204215436, 5871.3541510433815, 5956.55325850563, 6150.846228844314, 476.1239319651788, 477.63460952371463, 482.78579372877243, 498.832890981518, 649.1560970738021, 3174.873005079771, 3794.2790869734663, 3944.684425245973, 3946.4869566956113, 3968.2261958917734, 10514.579416823395, 10805.0, 10851.023407893328, 10954.507047182897, 10990.285631433931, 10645.148388128931, 10808.0, 10874.0, 10901.400422976654, 10929.398883061507, 6660.433863121689, 8268.09051004389, 8340.499570025819, 8364.790880228044, 8371.246587807127, 5765.106408506688, 5838.532917366685, 5862.355140934579, 5990.7903223386975, 6076.665783381956, 9782.853257201004, 9989.60041598328, 10104.242181836245, 10109.0, 10121.089101980857, 10230.232732545237, 10279.743006424898, 10296.021877921585, 10348.430836304076, 10365.378077315385, 6898.827529311607, 6993.545419547713, 7065.399441047569, 7075.292470752884, 7079.256678048937, 8178.059523154675, 8352.87470687928, 8355.415120941476, 8363.874541881754, 8423.157684231774, 6113.51091912637, 6842.828929276808, 7279.272072792678, 7568.962155189189, 7622.847543049219, 7378.483506154733, 7515.436342274242, 7531.209223031722, 7568.886466702894, 7684.0, 6933.064036355027, 6983.825404364931, 7068.222495525591, 7075.433965282662, 7082.079329687108, 4872.537108974641, 4901.828436004723, 4977.452480227244, 5078.568321692697, 5138.8972220556125, 5021.146405111067, 5026.421961474477, 5119.564836988898, 5161.845144645667, 5258.0798360286035], "value": 4456.643233017781, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.8302367650439, 304.593367853915, 315.0187166974873, 316.34200862206615, 318.27115904578517, 4137.565555616662, 4295.398644189812, 4404.62560682342, 4572.611328037121, 4883.925536381995, 4306.72006319589, 4929.7042177469375, 4935.950640493603, 5124.308218390516, 5268.707058088695, 3447.9483757453904, 3954.861579844706, 3979.1643754811525, 3979.4877946380334, 4017.1965606878593, 3479.0, 3795.0322667719693, 3934.173823497069, 3980.303446896796, 3992.500937382827, 3133.717965383115, 3752.587215406303, 3844.75009124406, 3892.8832135035996, 3989.683404476516, 3902.2634927457293, 3907.8667186515877, 3934.2328245992044, 3970.8014599270064, 3976.2842688316236, 3312.5528053712683, 3444.7436928811667, 3465.6880880720855, 3467.549218601591, 3468.358353704573, 3785.564660064073, 5051.621128415383, 5062.594992400636, 5163.251328557376, 5311.49540793624, 3820.192158763307, 4469.3296005599095, 4487.259602165639, 4519.186546421643, 4548.613367863735, 4563.840265590688, 4812.40415403289, 5173.482651734834, 5387.24578559002, 5427.782888684448, 3424.092615456906, 3782.243551289753, 3835.827387767529, 3838.366669499529, 3854.094287842355, 4216.135692183095, 5505.284313039319, 5760.798372056958, 5786.884262314814, 5790.913136302959, 3033.6814634463503, 3666.2300916807235, 3746.9625303747157, 3747.725773237058, 3760.7555508891833, 3436.810975396377, 3545.007397928584, 3703.518542589446, 3788.3938569828697, 3828.942565861514, 4182.414461975265, 4441.467023957146, 4442.577955094261, 4456.532064133284, 4457.576530229623, 5222.00781851447, 5418.376886658006, 5442.782288708408, 5452.0, 5473.972630136824, 1244.7055062734655, 1292.6879217594148, 1321.6167311479562, 1325.3770727757937, 1326.0584984660998, 771.4753967302177, 833.8582440985047, 857.4897935728231, 888.2982443869271, 896.3232759266712, 4122.484689413824, 4925.236588328815, 5039.798408063636, 5131.229136266375, 5152.871178220575, 4744.288356746447, 5599.216109744639, 5623.2408624835125, 5631.774729010794, 5677.473524281655, 4747.074320507537, 4782.3543821584335, 5050.6464547482055, 5146.6911985281, 5179.922301165486, 2897.2322334581354, 2909.767218622505, 2913.3590610065753, 2935.1488068459907, 2941.4852400830196, 4903.690009243746, 5713.371529131792, 5739.053056245756, 5784.987627165229, 5816.767329306865, 4544.772761361919, 5983.551233657493, 6000.579959402888, 6014.09788531715, 6058.848528786815, 5198.402183748842, 5231.659942103751, 5985.461308482258, 6034.668093254825, 6098.817035488765, 5593.636413633101, 5795.681237531976, 5821.912671309849, 5837.912431313449, 5867.799575159315, 5153.974230128825, 5527.949689558971, 5657.971710141423, 5712.7714891403875, 5744.396838331915, 5659.858503537444, 5996.37038110992, 6051.878962420815, 6057.303410107894, 6072.2352141672445, 3570.4465807799834, 3846.942295865508, 3851.7496362736333, 3860.7876566787972, 3897.5712671606666, 4911.926321105115, 5768.336641286138, 5823.602335439548, 5839.299284085937, 5862.970685146547, 5485.122380419114, 5704.942950570441, 5706.3152421709665, 5709.457601527848, 5761.625494342854, 4234.915301693887, 4668.269062598282, 5465.9726701366235, 5617.410171931889, 5681.261436013292, 4945.604351651931, 5191.402988656353, 5380.130981461783, 5411.107167317506, 5447.820871651371, 4823.493533179106, 5094.923576146435, 5488.725563721878, 5498.175273708815, 5507.311586051744, 5418.945810541844, 5447.4824891635235, 5485.807996720096, 5487.807926722546, 5509.807156749494], "value": 4006.0487235693327, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.3003386714887, 737.7086051009851, 794.9006374203225, 810.8459392715383, 821.1952286758976, 14860.40558377665, 15548.76676849848, 15556.533304000885, 15559.844401555982, 15571.29929153188, 14246.720524716047, 14295.428182872698, 14356.138631682106, 14357.210353430557, 14365.0, 712.3482013957223, 734.3024127079296, 889.968851090212, 897.4301318662647, 917.554985831871, 906.8141031088611, 920.5305294299922, 924.1820988425225, 926.1340646495514, 926.6802952981211, 976.24828881761, 976.8192884316388, 977.8142152990945, 978.5107446276861, 978.7063880835757, 739.6597565120052, 924.8658944453017, 952.9714108576753, 960.4765402855461, 976.9218462522981, 719.1549928833599, 721.7726416178887, 722.8409749855024, 722.9746958856467, 723.1177962885272, 14737.11577305365, 14797.704045919128, 14816.851831481656, 14823.925880370529, 14824.0, 13310.534131305454, 13563.254021028839, 13563.52527661537, 13582.185068895798, 13706.053198562357, 12305.323207223599, 12384.442700078518, 12445.564405245774, 12506.937465312707, 12545.686357841036, 4427.291633338683, 4496.3705081288645, 4509.7970591323465, 4511.278195488739, 4562.315652652095, 99993.50003249937, 101828.98171018269, 102994.0, 102997.0, 104809.0, 10705.411202383944, 10763.408012559228, 10851.728706782236, 10882.888273867373, 11193.048590869705, 21009.78990210109, 21059.894700526398, 21074.683879741817, 21103.8944805275, 21114.9442527873, 7849.254320839511, 7872.606369681493, 7901.644426000844, 8020.0, 8089.02931648206, 3832.1952389997855, 4495.730256184639, 4519.909601807945, 4578.725276483355, 4589.931151032737, 3460.480927860791, 3594.263176048904, 3595.1551385424405, 3596.7122630189524, 3602.603713591503, 559.2729451712758, 562.1707980728393, 565.8415643619793, 661.83784972682, 679.7349033876792, 581.7637520269427, 588.6556364526714, 633.6071635585874, 643.9227292724903, 656.7077650445506, 8864.113588641085, 8930.73207803754, 8952.328575356874, 9007.909920900838, 9107.954460227656, 572.8539222498237, 591.0690662207041, 591.822453264019, 640.8333833203401, 645.4900628503524, 4098.200850834119, 4238.682098842599, 4309.91380172401, 4390.297552391602, 4401.801918913592, 105314.89370212705, 130597.73505662245, 136847.0, 144782.27608861888, 156408.0, 98379.50810246046, 127173.0, 127833.36083319524, 127942.0, 128121.35939320244, 110760.4461977685, 111534.44232779108, 112237.43881280541, 112433.0, 112610.0, 8333.916660833314, 8421.747347579583, 8472.61873215719, 8628.094050124646, 8722.345824063348, 44512.77743611391, 53104.46895530995, 62911.37088629055, 65034.67482662557, 65388.346116538225, 92124.0, 92170.07829921615, 92419.0, 92973.53513232661, 94624.05375946153, 9438.905610943802, 11536.653900383002, 11769.528399036299, 12015.579454719043, 12136.514539418324, 12765.170263933187, 13065.738685226432, 13107.279099649611, 13121.475140994253, 13420.932895335462, 10431.321964072615, 12146.271223726604, 12178.512859485521, 12190.2076365036, 12555.87444125547, 10977.725556861142, 11267.605633802777, 11405.372704501, 11525.769484610428, 11544.82682759776, 11840.230385024946, 12041.33772642513, 12071.698207544881, 12189.146759726735, 12423.62729118128, 9640.325177237526, 9694.418334899929, 9786.314957952874, 9882.30823842353, 10040.598376065167, 9215.631374745204, 10292.897071029493, 10313.74215644615, 10341.586336546454, 10349.534270957674], "value": 7597.162954249885, "unit": "score"}], "name": "dromaeo_css", "value": 7195.405510001431, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 117.0, 118.0, 111.0, 111.0, 113.0, 154.0, 113.0, 111.0, 112.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 119.0, 117.0, 174.0, 119.0, 120.0, 142.0, 118.0, 160.0, 121.0], "value": 134.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 183.0, 184.0, 186.0, 181.0, 183.0, 181.0, 180.0, 185.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 58.0, 106.0, 58.0, 56.0, 63.0, 60.0, 95.0, 72.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 95.0, 90.0, 102.0, 99.0, 109.0, 70.0, 68.0, 99.0], "value": 90.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 95.0, 86.0, 84.0, 86.0, 83.0, 87.0, 83.0, 87.0, 84.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 93.0, 93.0, 92.0, 93.0, 93.0, 93.0, 96.0, 88.0], "value": 93.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 86.0, 94.0, 75.0, 122.0, 139.0, 133.0, 84.0, 85.0], "value": 98.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 53.0, 54.0, 54.0, 54.0, 54.0, 53.0, 54.0, 53.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 78.0, 78.0, 72.0, 70.0, 72.0, 71.0, 71.0, 73.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 84.0, 85.0, 86.0, 86.0, 93.0, 120.0, 101.0], "value": 91.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 141.0, 141.0, 137.0, 137.0, 140.0, 138.0, 148.0, 145.0, 140.0], "value": 141.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 170.0, 170.0, 170.0, 221.0, 206.0, 167.0, 167.0, 166.0], "value": 177.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 68.0, 66.0, 67.0, 66.0, 76.0, 85.0, 86.0, 70.0], "value": 71.7, "unit": "score"}], "name": "kraken", "value": 1481.3000000000002, "alertThreshold": 2.0}]} 10:12:41 INFO - Return code: 0 10:12:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:12:41 INFO - # TBPL SUCCESS # 10:12:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:12:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:12:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:12:41 INFO - 2016-06-29 10:12:41.346 screenresolution[1977:66904] starting screenresolution argv=screenresolution get 10:12:41 INFO - 2016-06-29 10:12:41.348 screenresolution[1977:66904] Display 0: 1600x1200x32@60 10:12:41 INFO - 2016-06-29 10:12:41.354 screenresolution[1978:66907] starting screenresolution argv=screenresolution list 10:12:41 INFO - Available Modes on Display 0 10:12:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:12:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:12:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:12:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:12:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:12:41 INFO - Intel Iris: 10:12:41 INFO - Chipset Model: Intel Iris 10:12:41 INFO - Type: GPU 10:12:41 INFO - Bus: Built-In 10:12:41 INFO - VRAM (Dynamic, Max): 1536 MB 10:12:41 INFO - Vendor: Intel (0x8086) 10:12:41 INFO - Device ID: 0x0a2e 10:12:41 INFO - Revision ID: 0x0009 10:12:41 INFO - Displays: 10:12:41 INFO - Display: 10:12:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:12:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:12:41 INFO - Display Serial Number: 1600x1200 60 10:12:41 INFO - Main Display: Yes 10:12:41 INFO - Mirror: Off 10:12:41 INFO - Online: Yes 10:12:41 INFO - Rotation: Supported 10:12:41 INFO - Return code: 0 10:12:41 INFO - Running post-action listener: _resource_record_post_action 10:12:41 INFO - ##### 10:12:41 INFO - ##### Finished run-tests step (success) 10:12:41 INFO - ##### 10:12:41 INFO - Running post-run listener: _resource_record_post_run 10:12:41 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 32768; Write bytes: 703872512; Read time: 2; Write time: 1861 10:12:41 INFO - TinderboxPrint: CPU usage
53.6% 10:12:41 INFO - TinderboxPrint: I/O read bytes / time
32,768 / 2 10:12:41 INFO - TinderboxPrint: I/O write bytes / time
703,872,512 / 1,861 10:12:41 INFO - TinderboxPrint: CPU idle
2,246.7 (46.4%) 10:12:41 INFO - TinderboxPrint: CPU system
964.6 (19.9%) 10:12:41 INFO - TinderboxPrint: CPU user
1,634.4 (33.7%) 10:12:41 INFO - TinderboxPrint: Swap in / out
406,155,264 / 0 10:12:41 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 404498944; Write bytes: 399780864; Read time: 29742; Write time: 739 10:12:41 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 32768; Write bytes: 292311552; Read time: 2; Write time: 1087 10:12:42 INFO - Running post-run listener: _upload_blobber_files 10:12:42 INFO - Blob upload gear active. 10:12:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:12:42 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 10:12:42 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:12:42 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:12:42 INFO - (blobuploader) - INFO - Open directory for files ... 10:12:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:12:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:12:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:12:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:12:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:12:43 INFO - (blobuploader) - INFO - Done attempting. 10:12:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:12:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:12:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:12:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:12:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:12:44 INFO - (blobuploader) - INFO - Done attempting. 10:12:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:12:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:12:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:12:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:12:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:12:47 INFO - (blobuploader) - INFO - Done attempting. 10:12:47 INFO - (blobuploader) - INFO - Iteration through files over. 10:12:47 INFO - Return code: 0 10:12:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:12:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:12:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6342bc878026ede31e52b187d928e0acc1523a3d03546006d1ef4325c6d5a449d855df02eb34f78dacc77f92ad82f2cfd8bb9553a3714dd80865b5ea96d2e5ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae875a33efddeac0d0b86d6ae5bb09b0767914e0017d7352c4f755cc6c7529740f2ed38226ac03fd5701e3b954d2581463bdfac8655b0499604901d35cde517c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15db989bcf6349cc6ed3f2d98129eda4c0b3c579a7c364b553a582eb9af3962f5c1866091e225871a5e62eae38971641966bbeead1b31d1c59c4c26c3a64698f"} 10:12:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:12:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:12:47 INFO - Contents: 10:12:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6342bc878026ede31e52b187d928e0acc1523a3d03546006d1ef4325c6d5a449d855df02eb34f78dacc77f92ad82f2cfd8bb9553a3714dd80865b5ea96d2e5ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae875a33efddeac0d0b86d6ae5bb09b0767914e0017d7352c4f755cc6c7529740f2ed38226ac03fd5701e3b954d2581463bdfac8655b0499604901d35cde517c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15db989bcf6349cc6ed3f2d98129eda4c0b3c579a7c364b553a582eb9af3962f5c1866091e225871a5e62eae38971641966bbeead1b31d1c59c4c26c3a64698f"} 10:12:47 INFO - Running post-run listener: copy_logs_to_upload_dir 10:12:47 INFO - Copying logs to upload dir... 10:12:47 INFO - mkdir: /builds/slave/test/build/upload/logs 10:12:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.989456 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-06-29 10:12:47.228677) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 10:12:47.232440) ========= 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.qXmmoYZlK0/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.Y8Luq8WSjx/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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/Firefox/sha512/6342bc878026ede31e52b187d928e0acc1523a3d03546006d1ef4325c6d5a449d855df02eb34f78dacc77f92ad82f2cfd8bb9553a3714dd80865b5ea96d2e5ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae875a33efddeac0d0b86d6ae5bb09b0767914e0017d7352c4f755cc6c7529740f2ed38226ac03fd5701e3b954d2581463bdfac8655b0499604901d35cde517c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15db989bcf6349cc6ed3f2d98129eda4c0b3c579a7c364b553a582eb9af3962f5c1866091e225871a5e62eae38971641966bbeead1b31d1c59c4c26c3a64698f"} build_url:https://queue.taskcluster.net/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011178 build_url: 'https://queue.taskcluster.net/v1/task/Dk1nGLCnT-Kaez9bqEUh3A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6342bc878026ede31e52b187d928e0acc1523a3d03546006d1ef4325c6d5a449d855df02eb34f78dacc77f92ad82f2cfd8bb9553a3714dd80865b5ea96d2e5ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae875a33efddeac0d0b86d6ae5bb09b0767914e0017d7352c4f755cc6c7529740f2ed38226ac03fd5701e3b954d2581463bdfac8655b0499604901d35cde517c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15db989bcf6349cc6ed3f2d98129eda4c0b3c579a7c364b553a582eb9af3962f5c1866091e225871a5e62eae38971641966bbeead1b31d1c59c4c26c3a64698f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 10:12:47.265415) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 10:12:47.265766) ========= 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.qXmmoYZlK0/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.Y8Luq8WSjx/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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.005012 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 10:12:47.315001) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 10:12:47.315324) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 10:12:47.315666) ========= ========= Total master_lag: 0.18 =========