builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0094 starttime: 1467462560.97 results: success (0) buildid: 20160702030219 builduid: 5e94ce95d87542d69b9e698cb6a7d425 revision: 39dffbba764210b25bfc1e749b4f16db77fa0d46 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-02 05:29:20.973987) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-02 05:29:20.974415) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-02 05:29:20.974739) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-02 05:29:21.140397) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 05:29:21.140714) ========= 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.P7ZZe8Qt5f/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.Tp3xa6us9V/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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-07-02 05:29:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-07-02 05:29:21 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.560020 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 05:29:21.735509) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 05:29:21.736153) ========= 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.P7ZZe8Qt5f/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.Tp3xa6us9V/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.020527 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 05:29:21.788762) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-02 05:29:21.789515) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 39dffbba764210b25bfc1e749b4f16db77fa0d46 --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 39dffbba764210b25bfc1e749b4f16db77fa0d46 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.P7ZZe8Qt5f/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.Tp3xa6us9V/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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-07-02 05:29:21,863 truncating revision to first 12 chars 2016-07-02 05:29:21,863 Setting DEBUG logging. 2016-07-02 05:29:21,863 attempt 1/10 2016-07-02 05:29:21,863 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/39dffbba7642?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-02 05:29:22,725 unpacking tar archive at: mozilla-central-39dffbba7642/testing/mozharness/ program finished with exit code 0 elapsedTime=1.120135 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-02 05:29:22.936017) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-02 05:29:22.936704) ========= script_repo_revision: 39dffbba764210b25bfc1e749b4f16db77fa0d46 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-02 05:29:22.937557) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-02 05:29:22.938226) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-02 05:29:22.959600) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-07-02 05:29:22.960327) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.P7ZZe8Qt5f/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.Tp3xa6us9V/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 05:29:23 INFO - MultiFileLogger online at 20160702 05:29:23 in /builds/slave/test 05:29:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:29:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:29:23 INFO - {'append_to_log': False, 05:29:23 INFO - 'base_work_dir': '/builds/slave/test', 05:29:23 INFO - 'blob_upload_branch': 'Firefox', 05:29:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:29:23 INFO - 'branch': 'Firefox', 05:29:23 INFO - 'buildbot_json_path': 'buildprops.json', 05:29:23 INFO - 'config_files': ('talos/mac_config.py',), 05:29:23 INFO - 'default_actions': ('clobber', 05:29:23 INFO - 'read-buildbot-config', 05:29:23 INFO - 'download-and-extract', 05:29:23 INFO - 'populate-webroot', 05:29:23 INFO - 'create-virtualenv', 05:29:23 INFO - 'install', 05:29:23 INFO - 'run-tests'), 05:29:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:29:23 INFO - 'download_minidump_stackwalk': True, 05:29:23 INFO - 'download_symbols': 'ondemand', 05:29:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:29:23 INFO - 'tooltool.py': '/tools/tooltool.py', 05:29:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:29:23 INFO - '/tools/misc-python/virtualenv.py')}, 05:29:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:29:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:29:23 INFO - 'installer_path': 'installer.exe', 05:29:23 INFO - 'log_level': 'info', 05:29:23 INFO - 'log_name': 'talos', 05:29:23 INFO - 'log_to_console': True, 05:29:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:29:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:29:23 INFO - 'opt_config_files': (), 05:29:23 INFO - 'pip_index': False, 05:29:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:29:23 INFO - 'cmd': ('bash', 05:29:23 INFO - '-c', 05:29:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:29:23 INFO - 'enabled': True, 05:29:23 INFO - 'halt_on_failure': False, 05:29:23 INFO - 'name': 'check_screen_resolution'},), 05:29:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:29:23 INFO - 'cmd': ('bash', 05:29:23 INFO - '-c', 05:29:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:29:23 INFO - 'enabled': True, 05:29:23 INFO - 'halt_on_failure': False, 05:29:23 INFO - 'name': 'check_screen_resolution'},), 05:29:23 INFO - 'run_cmd_checks_enabled': True, 05:29:23 INFO - 'sps_profile': False, 05:29:23 INFO - 'sps_profile_interval': 0, 05:29:23 INFO - 'suite': 'dromaeojs-e10s', 05:29:23 INFO - 'system_bits': '32', 05:29:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:29:23 INFO - 'title': 't-yosemite-r7-0094', 05:29:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:29:23 INFO - 'use_talos_json': True, 05:29:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:29:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:29:23 INFO - 'work_dir': 'build'} 05:29:23 INFO - ##### 05:29:23 INFO - ##### Running clobber step. 05:29:23 INFO - ##### 05:29:23 INFO - Running pre-action listener: _resource_record_pre_action 05:29:23 INFO - Running main action method: clobber 05:29:23 INFO - rmtree: /builds/slave/test/build 05:29:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:29:25 INFO - Running post-action listener: _resource_record_post_action 05:29:25 INFO - ##### 05:29:25 INFO - ##### Finished clobber step (success) 05:29:25 INFO - ##### 05:29:25 INFO - ##### 05:29:25 INFO - ##### Running read-buildbot-config step. 05:29:25 INFO - ##### 05:29:25 INFO - Running pre-action listener: _resource_record_pre_action 05:29:25 INFO - Running main action method: read_buildbot_config 05:29:25 INFO - Using buildbot properties: 05:29:25 INFO - { 05:29:25 INFO - "project": "", 05:29:25 INFO - "product": "firefox", 05:29:25 INFO - "script_repo_revision": "production", 05:29:25 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:29:25 INFO - "repository": "", 05:29:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:29:25 INFO - "buildid": "20160702030219", 05:29:25 INFO - "stage_platform": "macosx64", 05:29:25 INFO - "basedir": "/builds/slave/test", 05:29:25 INFO - "nightly_build": "True", 05:29:25 INFO - "buildnumber": 33, 05:29:25 INFO - "slavename": "t-yosemite-r7-0094", 05:29:25 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 05:29:25 INFO - "platform": "yosemite_r7", 05:29:25 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:29:25 INFO - "branch": "mozilla-central", 05:29:25 INFO - "repo_path": "mozilla-central", 05:29:25 INFO - "revision": "39dffbba764210b25bfc1e749b4f16db77fa0d46", 05:29:25 INFO - "pgo_build": "False", 05:29:25 INFO - "builduid": "5e94ce95d87542d69b9e698cb6a7d425", 05:29:25 INFO - "slavebuilddir": "test" 05:29:25 INFO - } 05:29:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 05:29:25 INFO - Running post-action listener: _resource_record_post_action 05:29:25 INFO - ##### 05:29:25 INFO - ##### Finished read-buildbot-config step (success) 05:29:25 INFO - ##### 05:29:25 INFO - ##### 05:29:25 INFO - ##### Running download-and-extract step. 05:29:25 INFO - ##### 05:29:25 INFO - Running pre-action listener: _resource_record_pre_action 05:29:25 INFO - Running main action method: download_and_extract 05:29:25 INFO - mkdir: /builds/slave/test/build/tests 05:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:29:25 INFO - https://queue.taskcluster.net/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:29:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:29:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:29:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LXBSOjAuT9GDcyHm1KxAdw/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 05:29:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LXBSOjAuT9GDcyHm1KxAdw/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 05:29:45 INFO - Downloaded 1199 bytes. 05:29:45 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:29:45 INFO - Using the following test package requirements: 05:29:45 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:29:45 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:29:45 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 05:29:45 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:29:45 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 05:29:45 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:29:45 INFO - u'jsshell-mac.zip'], 05:29:45 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:29:45 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 05:29:45 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:29:45 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:29:45 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 05:29:45 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:29:45 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 05:29:45 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:29:45 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 05:29:45 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:29:45 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 05:29:45 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:29:45 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')]} 05:29:45 INFO - https://queue.taskcluster.net/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:29:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:29:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:29:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LXBSOjAuT9GDcyHm1KxAdw/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 05:29:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LXBSOjAuT9GDcyHm1KxAdw/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 05:29:47 INFO - Downloaded 18791467 bytes. 05:29:47 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'] 05:29:47 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 05:29:50 INFO - Return code: 0 05:29:50 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 05:29:50 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')]} 05:29:50 INFO - https://queue.taskcluster.net/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:29:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:29:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:29:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LXBSOjAuT9GDcyHm1KxAdw/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 05:29:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LXBSOjAuT9GDcyHm1KxAdw/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 05:29:56 INFO - Downloaded 18791467 bytes. 05:29:56 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'] 05:29:56 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 05:29:59 INFO - Return code: 0 05:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:29:59 INFO - https://queue.taskcluster.net/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:29:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:29:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:29:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LXBSOjAuT9GDcyHm1KxAdw/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 05:29:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LXBSOjAuT9GDcyHm1KxAdw/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 05:30:00 INFO - Downloaded 14405327 bytes. 05:30:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:30:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:30:01 INFO - Return code: 0 05:30:01 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')]} 05:30:01 INFO - https://queue.taskcluster.net/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:30:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:30:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:30:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 05:30:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LXBSOjAuT9GDcyHm1KxAdw/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 05:30:05 INFO - Downloaded 112575458 bytes. 05:30:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:30:05 INFO - mkdir: /builds/slave/test/properties 05:30:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:30:05 INFO - Writing to file /builds/slave/test/properties/build_url 05:30:05 INFO - Contents: 05:30:05 INFO - build_url:https://queue.taskcluster.net/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:30:06 INFO - Running post-action listener: _resource_record_post_action 05:30:06 INFO - Running post-action listener: set_extra_try_arguments 05:30:06 INFO - ##### 05:30:06 INFO - ##### Finished download-and-extract step (success) 05:30:06 INFO - ##### 05:30:06 INFO - ##### 05:30:06 INFO - ##### Running populate-webroot step. 05:30:06 INFO - ##### 05:30:06 INFO - Running pre-action listener: _resource_record_pre_action 05:30:06 INFO - Running main action method: populate_webroot 05:30:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:30:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:30:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:30:06 INFO - '--tppagecycles', 05:30:06 INFO - '7'], 05:30:06 INFO - 'tests': ['tsvgm']}}, 05:30:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:30:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:30:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:30:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:30:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:30:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:30:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:06 INFO - 'talos_options': ['--disable-e10s'], 05:30:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:30:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:30:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:06 INFO - 'talos_options': ['--disable-e10s'], 05:30:06 INFO - 'tests': ['damp', 'tps']}, 05:30:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:06 INFO - 'tests': ['damp', 'tps']}, 05:30:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:30:06 INFO - 'tests': ['dromaeo_dom']}, 05:30:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:30:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:30:06 INFO - 'tests': ['basic_compositor_video']}, 05:30:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:30:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:30:06 INFO - 'tests': ['a11yr', 05:30:06 INFO - 'ts_paint', 05:30:06 INFO - 'tpaint', 05:30:06 INFO - 'sessionrestore', 05:30:06 INFO - 'sessionrestore_no_auto_restore', 05:30:06 INFO - 'tabpaint']}, 05:30:06 INFO - 'other-e10s': {'tests': ['a11yr', 05:30:06 INFO - 'ts_paint', 05:30:06 INFO - 'tpaint', 05:30:06 INFO - 'sessionrestore', 05:30:06 INFO - 'sessionrestore_no_auto_restore', 05:30:06 INFO - 'tabpaint']}, 05:30:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:30:06 INFO - 'ts_paint', 05:30:06 INFO - 'tpaint', 05:30:06 INFO - 'sessionrestore', 05:30:06 INFO - 'sessionrestore_no_auto_restore', 05:30:06 INFO - 'tabpaint']}, 05:30:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:30:06 INFO - 'ts_paint', 05:30:06 INFO - 'tpaint', 05:30:06 INFO - 'sessionrestore', 05:30:06 INFO - 'sessionrestore_no_auto_restore', 05:30:06 INFO - 'tabpaint']}, 05:30:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:30:06 INFO - 'tests': ['a11yr', 05:30:06 INFO - 'ts_paint', 05:30:06 INFO - 'tpaint', 05:30:06 INFO - 'sessionrestore', 05:30:06 INFO - 'sessionrestore_no_auto_restore', 05:30:06 INFO - 'tabpaint']}, 05:30:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:30:06 INFO - 'tests': ['a11yr', 05:30:06 INFO - 'ts_paint', 05:30:06 INFO - 'tpaint', 05:30:06 INFO - 'sessionrestore', 05:30:06 INFO - 'sessionrestore_no_auto_restore', 05:30:06 INFO - 'tabpaint']}, 05:30:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:30:06 INFO - 'tests': ['tsvgx', 05:30:06 INFO - 'tsvgr_opacity', 05:30:06 INFO - 'tart', 05:30:06 INFO - 'tscrollx', 05:30:06 INFO - 'cart']}, 05:30:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:30:06 INFO - 'tsvgr_opacity', 05:30:06 INFO - 'tart', 05:30:06 INFO - 'tscrollx', 05:30:06 INFO - 'cart']}, 05:30:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:06 INFO - 'talos_options': ['--disable-e10s'], 05:30:06 INFO - 'tests': ['tp5o']}, 05:30:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:06 INFO - 'tests': ['tp5o']}, 05:30:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:06 INFO - 'talos_options': ['--disable-e10s', 05:30:06 INFO - '--xperf_path', 05:30:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:30:06 INFO - 'tests': ['tp5n']}, 05:30:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:06 INFO - 'talos_options': ['--xperf_path', 05:30:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:30:06 INFO - 'tests': ['tp5n']}}, 05:30:06 INFO - 'talos.zip': {'path': '', 05:30:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:30:06 INFO - Running post-action listener: _resource_record_post_action 05:30:06 INFO - ##### 05:30:06 INFO - ##### Finished populate-webroot step (success) 05:30:06 INFO - ##### 05:30:06 INFO - ##### 05:30:06 INFO - ##### Running create-virtualenv step. 05:30:06 INFO - ##### 05:30:06 INFO - Running pre-action listener: _resource_record_pre_action 05:30:06 INFO - Running main action method: create_virtualenv 05:30:06 INFO - Creating virtualenv /builds/slave/test/build/venv 05:30:06 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 05:30:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:30:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:30:06 INFO - Using real prefix '/tools/python27' 05:30:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:30:07 INFO - Installing distribute.............................................................................................................................................................................................done. 05:30:09 INFO - Installing pip.................done. 05:30:09 INFO - Return code: 0 05:30:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f76618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f806b5a0dc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e45d30>, '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 0x102baadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e3db20>, '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.P7ZZe8Qt5f/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.Tp3xa6us9V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 05:30:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:30:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7ZZe8Qt5f/Render', 05:30:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:09 INFO - 'HOME': '/Users/cltbld', 05:30:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:09 INFO - 'LOGNAME': 'cltbld', 05:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:09 INFO - 'MOZ_NO_REMOTE': '1', 05:30:09 INFO - 'NO_EM_RESTART': '1', 05:30:09 INFO - 'PAGER': '/bin/cat', 05:30:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:09 INFO - 'PWD': '/builds/slave/test', 05:30:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:09 INFO - 'SHELL': '/bin/bash', 05:30:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tp3xa6us9V/Listeners', 05:30:09 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:30:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:09 INFO - 'USER': 'cltbld', 05:30:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:09 INFO - 'XPC_FLAGS': '0x0', 05:30:09 INFO - 'XPC_SERVICE_NAME': '0', 05:30:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:10 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 05:30:10 INFO - Cleaning up... 05:30:10 INFO - Return code: 0 05:30:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:30: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')]} 05:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30: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')]} 05:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30: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', '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 0x102ee1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f76618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f806b5a0dc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e45d30>, '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 0x102baadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e3db20>, '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.P7ZZe8Qt5f/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.Tp3xa6us9V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 05:30: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:30: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 psutil>=3.1.1 05:30:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7ZZe8Qt5f/Render', 05:30:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:10 INFO - 'HOME': '/Users/cltbld', 05:30:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:10 INFO - 'LOGNAME': 'cltbld', 05:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:10 INFO - 'MOZ_NO_REMOTE': '1', 05:30:10 INFO - 'NO_EM_RESTART': '1', 05:30:10 INFO - 'PAGER': '/bin/cat', 05:30:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:10 INFO - 'PWD': '/builds/slave/test', 05:30:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:10 INFO - 'SHELL': '/bin/bash', 05:30:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tp3xa6us9V/Listeners', 05:30:10 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:30:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:10 INFO - 'USER': 'cltbld', 05:30:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:10 INFO - 'XPC_FLAGS': '0x0', 05:30:10 INFO - 'XPC_SERVICE_NAME': '0', 05:30:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:10 INFO - Downloading/unpacking psutil>=3.1.1 05:30: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 05:30: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 05:30: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 05:30: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 05:30: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 05:30: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 05:30:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:30:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:30:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:30:13 INFO - Installing collected packages: psutil 05:30:13 INFO - Running setup.py install for psutil 05:30:13 INFO - building 'psutil._psutil_osx' extension 05:30:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:30:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:30:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:30:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:30:14 INFO - building 'psutil._psutil_posix' extension 05:30:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:30:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:30:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:30:14 INFO - ^ 05:30:14 INFO - 1 warning generated. 05:30:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:30:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:30:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:30:14 INFO - Successfully installed psutil 05:30:14 INFO - Cleaning up... 05:30:14 INFO - Return code: 0 05:30:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:30:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f76618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f806b5a0dc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e45d30>, '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 0x102baadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e3db20>, '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.P7ZZe8Qt5f/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.Tp3xa6us9V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 05:30:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:30:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:30:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7ZZe8Qt5f/Render', 05:30:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:14 INFO - 'HOME': '/Users/cltbld', 05:30:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:14 INFO - 'LOGNAME': 'cltbld', 05:30:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:14 INFO - 'MOZ_NO_REMOTE': '1', 05:30:14 INFO - 'NO_EM_RESTART': '1', 05:30:14 INFO - 'PAGER': '/bin/cat', 05:30:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:14 INFO - 'PWD': '/builds/slave/test', 05:30:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:14 INFO - 'SHELL': '/bin/bash', 05:30:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tp3xa6us9V/Listeners', 05:30:14 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:30:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:14 INFO - 'USER': 'cltbld', 05:30:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:14 INFO - 'XPC_FLAGS': '0x0', 05:30:14 INFO - 'XPC_SERVICE_NAME': '0', 05:30:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:30:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:30:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:30:17 INFO - Installing collected packages: mozsystemmonitor 05:30:17 INFO - Running setup.py install for mozsystemmonitor 05:30:17 INFO - Successfully installed mozsystemmonitor 05:30:17 INFO - Cleaning up... 05:30:17 INFO - Return code: 0 05:30:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f76618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f806b5a0dc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e45d30>, '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 0x102baadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e3db20>, '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.P7ZZe8Qt5f/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.Tp3xa6us9V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 05:30:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:30:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:30:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7ZZe8Qt5f/Render', 05:30:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:17 INFO - 'HOME': '/Users/cltbld', 05:30:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:17 INFO - 'LOGNAME': 'cltbld', 05:30:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:17 INFO - 'MOZ_NO_REMOTE': '1', 05:30:17 INFO - 'NO_EM_RESTART': '1', 05:30:17 INFO - 'PAGER': '/bin/cat', 05:30:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:17 INFO - 'PWD': '/builds/slave/test', 05:30:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:17 INFO - 'SHELL': '/bin/bash', 05:30:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tp3xa6us9V/Listeners', 05:30:17 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:30:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:17 INFO - 'USER': 'cltbld', 05:30:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:17 INFO - 'XPC_FLAGS': '0x0', 05:30:17 INFO - 'XPC_SERVICE_NAME': '0', 05:30:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:18 INFO - Downloading/unpacking jsonschema==2.5.1 05:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:30:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:20 INFO - Downloading functools32-3.2.3-2.tar.gz 05:30:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:30:20 INFO - warning: no files found matching '*.txt' 05:30:20 INFO - no previously-included directories found matching 'build' 05:30:20 INFO - no previously-included directories found matching 'dist' 05:30:20 INFO - no previously-included directories found matching '.git*' 05:30:20 INFO - Installing collected packages: jsonschema, functools32 05:30:20 INFO - Running setup.py install for functools32 05:30:21 INFO - warning: no files found matching '*.txt' 05:30:21 INFO - no previously-included directories found matching 'build' 05:30:21 INFO - no previously-included directories found matching 'dist' 05:30:21 INFO - no previously-included directories found matching '.git*' 05:30:21 INFO - Successfully installed jsonschema functools32 05:30:21 INFO - Cleaning up... 05:30:21 INFO - Return code: 0 05:30:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f76618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f806b5a0dc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e45d30>, '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 0x102baadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e3db20>, '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.P7ZZe8Qt5f/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.Tp3xa6us9V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 05:30:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:30:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7ZZe8Qt5f/Render', 05:30:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:21 INFO - 'HOME': '/Users/cltbld', 05:30:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:21 INFO - 'LOGNAME': 'cltbld', 05:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:21 INFO - 'MOZ_NO_REMOTE': '1', 05:30:21 INFO - 'NO_EM_RESTART': '1', 05:30:21 INFO - 'PAGER': '/bin/cat', 05:30:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:21 INFO - 'PWD': '/builds/slave/test', 05:30:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:21 INFO - 'SHELL': '/bin/bash', 05:30:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tp3xa6us9V/Listeners', 05:30:21 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:30:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:21 INFO - 'USER': 'cltbld', 05:30:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:21 INFO - 'XPC_FLAGS': '0x0', 05:30:21 INFO - 'XPC_SERVICE_NAME': '0', 05:30:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:21 INFO - Downloading/unpacking blobuploader==1.2.4 05:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:23 INFO - Downloading blobuploader-1.2.4.tar.gz 05:30:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:30:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:30:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:30:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:25 INFO - Downloading docopt-0.6.1.tar.gz 05:30:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:30:25 INFO - Installing collected packages: blobuploader, requests, docopt 05:30:25 INFO - Running setup.py install for blobuploader 05:30:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:30:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:30:25 INFO - Running setup.py install for requests 05:30:25 INFO - Running setup.py install for docopt 05:30:25 INFO - Successfully installed blobuploader requests docopt 05:30:25 INFO - Cleaning up... 05:30:26 INFO - Return code: 0 05:30:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f76618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f806b5a0dc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e45d30>, '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 0x102baadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e3db20>, '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.P7ZZe8Qt5f/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.Tp3xa6us9V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 05:30:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:30:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:30:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7ZZe8Qt5f/Render', 05:30:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:26 INFO - 'HOME': '/Users/cltbld', 05:30:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:26 INFO - 'LOGNAME': 'cltbld', 05:30:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:26 INFO - 'MOZ_NO_REMOTE': '1', 05:30:26 INFO - 'NO_EM_RESTART': '1', 05:30:26 INFO - 'PAGER': '/bin/cat', 05:30:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:26 INFO - 'PWD': '/builds/slave/test', 05:30:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:26 INFO - 'SHELL': '/bin/bash', 05:30:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tp3xa6us9V/Listeners', 05:30:26 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:30:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:26 INFO - 'USER': 'cltbld', 05:30:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:26 INFO - 'XPC_FLAGS': '0x0', 05:30:26 INFO - 'XPC_SERVICE_NAME': '0', 05:30:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:30:26 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-ZX07TB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:30:26 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-cIQ6m1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:30:26 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-nhayzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:30:27 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-090rTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:30:27 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-4eJqzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:30:27 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-skZh4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:30:27 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-i3fKOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:30:27 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-sEYWo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:30:27 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Q7JV3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:30:27 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-5wA0Ki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:30:27 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-0oqE2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:30:27 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-BR3N_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:30:28 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-C2G4yN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:30:28 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-wSYgLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:28 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-vsPrf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:30:28 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-_qfcaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:30:28 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-X7T770-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:30:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:30:28 INFO - Running setup.py install for manifestparser 05:30:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:30:28 INFO - Running setup.py install for mozcrash 05:30:28 INFO - Running setup.py install for mozdebug 05:30:28 INFO - Running setup.py install for mozdevice 05:30:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:30:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:30:29 INFO - Running setup.py install for mozfile 05:30:29 INFO - Running setup.py install for mozhttpd 05:30:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:30:29 INFO - Running setup.py install for mozinfo 05:30:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:30:29 INFO - Running setup.py install for mozInstall 05:30:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:30:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:30:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:30:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:30:29 INFO - Running setup.py install for mozleak 05:30:29 INFO - Running setup.py install for mozlog 05:30:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:30:30 INFO - Running setup.py install for moznetwork 05:30:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:30:30 INFO - Running setup.py install for mozprocess 05:30:30 INFO - Running setup.py install for mozprofile 05:30:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:30:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:30:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:30:30 INFO - Running setup.py install for mozrunner 05:30:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:30:30 INFO - Running setup.py install for mozscreenshot 05:30:30 INFO - Running setup.py install for moztest 05:30:30 INFO - Running setup.py install for mozversion 05:30:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:30:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:30:31 INFO - Cleaning up... 05:30:31 INFO - Return code: 0 05:30:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30:31 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')]} 05:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:31 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')]} 05:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:31 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 0x102ee1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f76618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f806b5a0dc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e45d30>, '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 0x102baadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e3db20>, '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.P7ZZe8Qt5f/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.Tp3xa6us9V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 05:30:31 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 05:30:31 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 05:30:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7ZZe8Qt5f/Render', 05:30:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:31 INFO - 'HOME': '/Users/cltbld', 05:30:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:31 INFO - 'LOGNAME': 'cltbld', 05:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:31 INFO - 'MOZ_NO_REMOTE': '1', 05:30:31 INFO - 'NO_EM_RESTART': '1', 05:30:31 INFO - 'PAGER': '/bin/cat', 05:30:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:31 INFO - 'PWD': '/builds/slave/test', 05:30:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:31 INFO - 'SHELL': '/bin/bash', 05:30:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tp3xa6us9V/Listeners', 05:30:31 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:30:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:31 INFO - 'USER': 'cltbld', 05:30:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:31 INFO - 'XPC_FLAGS': '0x0', 05:30:31 INFO - 'XPC_SERVICE_NAME': '0', 05:30:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:30:31 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-wzfzTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:30:31 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:30:31 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-LzYo_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:30:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:30:31 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-fnTINa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:30:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:30:31 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-_1ee2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:30:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:30:31 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-USVQUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:30:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:30:31 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-y9a8mC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:30:32 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)) 05:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:30:32 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Ce68LB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:30:32 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)) 05:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:30:32 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-CCuRsJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:30:32 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)) 05:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:30:32 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-iCSxQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:30:32 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)) 05:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:30:32 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-0lDINd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:30:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:30:32 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-jJreCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:30:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:30:32 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-JBCjKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:30:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:30:32 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-9smqWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:30:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:30:32 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-V8QFUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:30:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:32 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-d38W5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:33 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)) 05:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:30:33 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-1UpJQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:30:33 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)) 05:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:30:33 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-h_WYN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:30:33 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)) 05:30:33 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)) 05:30:33 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)) 05:30:33 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)) 05:30:33 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)) 05:30:33 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)) 05:30:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:36 INFO - Downloading blessings-1.6.tar.gz 05:30:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:30:36 INFO - Installing collected packages: blessings 05:30:36 INFO - Running setup.py install for blessings 05:30:36 INFO - Successfully installed blessings 05:30:36 INFO - Cleaning up... 05:30:36 INFO - Return code: 0 05:30:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:30:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:30:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:30:36 INFO - Reading from file tmpfile_stdout 05:30:36 INFO - Current package versions: 05:30:36 INFO - blessings == 1.6 05:30:36 INFO - blobuploader == 1.2.4 05:30:36 INFO - docopt == 0.6.1 05:30:36 INFO - functools32 == 3.2.3-2 05:30:36 INFO - jsonschema == 2.5.1 05:30:36 INFO - manifestparser == 1.1 05:30:36 INFO - mozInstall == 1.12 05:30:36 INFO - mozcrash == 0.17 05:30:36 INFO - mozdebug == 0.1 05:30:36 INFO - mozdevice == 0.48 05:30:36 INFO - mozfile == 1.2 05:30:36 INFO - mozhttpd == 0.7 05:30:36 INFO - mozinfo == 0.9 05:30:36 INFO - mozleak == 0.1 05:30:36 INFO - mozlog == 3.2 05:30:36 INFO - moznetwork == 0.27 05:30:36 INFO - mozprocess == 0.22 05:30:36 INFO - mozprofile == 0.28 05:30:36 INFO - mozrunner == 6.11 05:30:36 INFO - mozscreenshot == 0.1 05:30:36 INFO - mozsystemmonitor == 0.3 05:30:36 INFO - moztest == 0.7 05:30:36 INFO - mozversion == 1.4 05:30:36 INFO - psutil == 3.1.1 05:30:36 INFO - requests == 1.2.3 05:30:36 INFO - wsgiref == 0.1.2 05:30:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:36 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 0x102ee1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f76618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f806b5a0dc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e45d30>, '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 0x102baadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e3db20>, '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.P7ZZe8Qt5f/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.Tp3xa6us9V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 05:30:36 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 05:30:36 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 05:30:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7ZZe8Qt5f/Render', 05:30:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:36 INFO - 'HOME': '/Users/cltbld', 05:30:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:36 INFO - 'LOGNAME': 'cltbld', 05:30:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:36 INFO - 'MOZ_NO_REMOTE': '1', 05:30:36 INFO - 'NO_EM_RESTART': '1', 05:30:36 INFO - 'PAGER': '/bin/cat', 05:30:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:36 INFO - 'PWD': '/builds/slave/test', 05:30:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:36 INFO - 'SHELL': '/bin/bash', 05:30:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tp3xa6us9V/Listeners', 05:30:36 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:30:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:36 INFO - 'USER': 'cltbld', 05:30:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:36 INFO - 'XPC_FLAGS': '0x0', 05:30:36 INFO - 'XPC_SERVICE_NAME': '0', 05:30:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:30:36 INFO - Cleaning up... 05:30:36 INFO - Return code: 0 05:30:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:30:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:30:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f76618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f806b5a0dc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e45d30>, '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 0x102baadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e3db20>, '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.P7ZZe8Qt5f/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.Tp3xa6us9V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 05:30:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:30:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7ZZe8Qt5f/Render', 05:30:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:37 INFO - 'HOME': '/Users/cltbld', 05:30:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:37 INFO - 'LOGNAME': 'cltbld', 05:30:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:37 INFO - 'MOZ_NO_REMOTE': '1', 05:30:37 INFO - 'NO_EM_RESTART': '1', 05:30:37 INFO - 'PAGER': '/bin/cat', 05:30:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:37 INFO - 'PWD': '/builds/slave/test', 05:30:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:37 INFO - 'SHELL': '/bin/bash', 05:30:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tp3xa6us9V/Listeners', 05:30:37 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:30:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:37 INFO - 'USER': 'cltbld', 05:30:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:37 INFO - 'XPC_FLAGS': '0x0', 05:30:37 INFO - 'XPC_SERVICE_NAME': '0', 05:30:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:30:37 INFO - Cleaning up... 05:30:37 INFO - Return code: 0 05:30:37 INFO - Running post-action listener: _resource_record_post_action 05:30:37 INFO - Running post-action listener: _start_resource_monitoring 05:30:37 INFO - Starting resource monitoring. 05:30:37 INFO - ##### 05:30:37 INFO - ##### Finished create-virtualenv step (success) 05:30:37 INFO - ##### 05:30:37 INFO - ##### 05:30:37 INFO - ##### Running install step. 05:30:37 INFO - ##### 05:30:37 INFO - Running pre-action listener: _resource_record_pre_action 05:30:37 INFO - Running main action method: install 05:30:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:30:37 INFO - Reading from file tmpfile_stdout 05:30:37 INFO - Detecting whether we're running mozinstall >=1.0... 05:30:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:30:37 INFO - Reading from file tmpfile_stdout 05:30:37 INFO - Output received: 05:30:37 INFO - Usage: mozinstall [options] installer 05:30:37 INFO - Options: 05:30:37 INFO - -h, --help show this help message and exit 05:30:37 INFO - -d DEST, --destination=DEST 05:30:37 INFO - Directory to install application into. [default: 05:30:37 INFO - "/builds/slave/test"] 05:30:37 INFO - --app=APP Application being installed. [default: firefox] 05:30:37 INFO - mkdir: /builds/slave/test/build/application 05:30:37 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'] 05:30:37 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 05:31:11 INFO - Reading from file tmpfile_stdout 05:31:11 INFO - Output received: 05:31:11 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:31:11 INFO - Running post-action listener: _resource_record_post_action 05:31:11 INFO - ##### 05:31:11 INFO - ##### Finished install step (success) 05:31:11 INFO - ##### 05:31:11 INFO - ##### 05:31:11 INFO - ##### Running run-tests step. 05:31:11 INFO - ##### 05:31:11 INFO - Running pre-action listener: _resource_record_pre_action 05:31:11 INFO - Running main action method: run_tests 05:31:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:31:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:31:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:31:11 INFO - 2016-07-02 05:31:11.964 screenresolution[1935:19176] starting screenresolution argv=screenresolution get 05:31:11 INFO - 2016-07-02 05:31:11.967 screenresolution[1935:19176] Display 0: 1600x1200x32@60 05:31:11 INFO - 2016-07-02 05:31:11.972 screenresolution[1936:19178] starting screenresolution argv=screenresolution list 05:31:11 INFO - Available Modes on Display 0 05:31:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:31:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:31:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:31:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:31:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:31:12 INFO - Intel Iris: 05:31:12 INFO - Chipset Model: Intel Iris 05:31:12 INFO - Type: GPU 05:31:12 INFO - Bus: Built-In 05:31:12 INFO - VRAM (Dynamic, Max): 1536 MB 05:31:12 INFO - Vendor: Intel (0x8086) 05:31:12 INFO - Device ID: 0x0a2e 05:31:12 INFO - Revision ID: 0x0009 05:31:12 INFO - Displays: 05:31:12 INFO - Display: 05:31:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:31:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:31:12 INFO - Display Serial Number: 1600x1200 60 05:31:12 INFO - Main Display: Yes 05:31:12 INFO - Mirror: Off 05:31:12 INFO - Online: Yes 05:31:12 INFO - Rotation: Supported 05:31:12 INFO - Return code: 0 05:31:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:31:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:31:12 INFO - Python 2.7.3 05:31:12 INFO - Return code: 0 05:31:12 INFO - grabbing minidump binary from tooltool 05:31:12 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')]} 05:31:12 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 0x102e45d30>, '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 0x102baadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e3db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:31:12 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 05:31:12 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 05:31:12 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 05:31:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:31:12 INFO - Return code: 0 05:31:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:31:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:31:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:31:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:31:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:31:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:31:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0094 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:31:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7ZZe8Qt5f/Render', 05:31:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:12 INFO - 'HOME': '/Users/cltbld', 05:31:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:12 INFO - 'LOGNAME': 'cltbld', 05:31:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:31:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:31:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:12 INFO - 'MOZ_NO_REMOTE': '1', 05:31:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:31:12 INFO - 'NO_EM_RESTART': '1', 05:31:12 INFO - 'PAGER': '/bin/cat', 05:31:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:12 INFO - 'PWD': '/builds/slave/test', 05:31:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:31:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:12 INFO - 'SHELL': '/bin/bash', 05:31:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tp3xa6us9V/Listeners', 05:31:12 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:31:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:12 INFO - 'USER': 'cltbld', 05:31:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:12 INFO - 'XPC_FLAGS': '0x0', 05:31:12 INFO - 'XPC_SERVICE_NAME': '0', 05:31:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:31:12 INFO - mozversion application_buildid: 20160702030219 05:31:12 INFO - mozversion application_changeset: 39dffbba764210b25bfc1e749b4f16db77fa0d46 05:31:12 INFO - mozversion application_display_name: Nightly 05:31:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:31:12 INFO - mozversion application_name: Firefox 05:31:12 INFO - mozversion application_remotingname: firefox 05:31:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:31:12 INFO - mozversion application_vendor: Mozilla 05:31:12 INFO - mozversion application_version: 50.0a1 05:31:12 INFO - mozversion platform_buildid: 20160702030219 05:31:12 INFO - mozversion platform_changeset: 39dffbba764210b25bfc1e749b4f16db77fa0d46 05:31:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:31:12 INFO - mozversion platform_version: 50.0a1 05:31:12 INFO - using testdate: 1467462672 05:31:12 INFO - actual date: 1467462672 05:31:12 INFO - starting webserver on 'localhost:56100' 05:31:12 INFO - SUITE-START | Running 2 tests 05:31:12 INFO - TEST-START | dromaeo_css 05:31:12 INFO - operating with platform_type : mac_ 05:31:12 INFO - Initialising browser for dromaeo_css test... 05:31:12 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpmBQIrV/profile http://localhost:56100/getInfo.html) 05:31:15 INFO - PROCESS | 1946 | __metrics Screen width/height:1600/1200 05:31:15 INFO - PROCESS | 1946 | colorDepth:24 05:31:15 INFO - PROCESS | 1946 | Browser inner width/height: 1024/689 05:31:15 INFO - PROCESS | 1946 | __metrics 05:31:15 ERROR - PROCESS | 1946 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 05:31:16 INFO - TEST-INFO | 1946: exit 0 05:31:16 INFO - Browser initialized. 05:31:16 INFO - Running cycle 1/1 for dromaeo_css test... 05:31:16 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpmBQIrV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:31:28 INFO - PROCESS | 1950 | RSS: Main: 255795200 05:31:28 INFO - PROCESS | 1950 | 05:32:18 INFO - PROCESS | 1950 | 1467462738105 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] 05:34:29 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:56100/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56100/tests/dromaeo/cssquery-ext.html) 05:34:31 INFO - PROCESS | 1950 | RSS: Main: 268054528 05:34:31 INFO - PROCESS | 1950 | 05:37:33 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:56100/tests/dromaeo/cssquery-ext.html (next: http://localhost:56100/tests/dromaeo/cssquery-jquery.html) 05:37:33 INFO - PROCESS | 1950 | RSS: Main: 276713472 05:37:33 INFO - PROCESS | 1950 | 05:40:35 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:56100/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56100/tests/dromaeo/cssquery-mootools.html) 05:40:36 INFO - PROCESS | 1950 | RSS: Main: 281116672 05:40:36 INFO - PROCESS | 1950 | 05:43:38 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:56100/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56100/tests/dromaeo/cssquery-prototype.html) 05:43:38 INFO - PROCESS | 1950 | RSS: Main: 280633344 05:43:38 INFO - PROCESS | 1950 | 05:46:41 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:56100/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56100/tests/dromaeo/cssquery-yui.html) 05:46:42 INFO - PROCESS | 1950 | RSS: Main: 283934720 05:46:42 INFO - PROCESS | 1950 | 05:49:44 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:56100/tests/dromaeo/cssquery-yui.html (next: http://localhost:56100/tests/dromaeo/cssquery-dojo.html) 05:49:45 INFO - PROCESS | 1950 | RSS: Main: 283893760 05:49:45 INFO - PROCESS | 1950 | 05:49:45 INFO - PROCESS | 1950 | __start_tp_report 05:49:45 INFO - PROCESS | 1950 | _x_x_mozilla_page_load 05:49:45 INFO - PROCESS | 1950 | _x_x_mozilla_page_load_details 05:49:45 INFO - PROCESS | 1950 | |i|pagename|runs| 05:49:45 INFO - PROCESS | 1950 | |0;dojo.html;3385.1367901185195;3387.0854869185305;3415.282790613971;3418.914527136821;3432.7082198013154;5847.79532716355;5900.527957763374;5947.9999999999945;5983;5990.970045149768;5724.9141262880985;5921.407859214077;5924.614900031495;5932.228810254672;5985.551083668732;4231.978840105795;5207.166853303482;5253.317068781053;5319.148936170213;5372.516473517383;5269.789208431677;5401.540869026137;5420.430854760252;5431.049566325877;5439.5104440600335;5192.376914770214;5259.421463638997;5262.500062494057;5291.629585928987;5296.999999999981;5270.341207349082;5288.391834938992;5289.867753306198;5295.4704529547125;5296.152615581488;5230.189320655304;5285.497877701612;5287.101192797234;5293.7353132343615;5403.378611459654;5621.959937411592;5784.363719990798;5840.97079514604;5914.378990206053;5935.49548288396;6224.782132625382;6256.405641464054;6272.56092073555;6306.1486699295465;6320.209973753281;6205.410486003822;6286.842828929268;6311.96844015777;6319.336469670664;6325.493960483151;5911.261092363455;5938.7624495020145;5978.641281523122;5986.25171853514;5990.951583472962;5080.933004069113;6007.609505382136;6330.873382532323;6339.985602303609;6359.8092057238355;6097.917917222642;6207.068939659087;6280.623162610258;6327.778527751507;6348.333424990309;6222.968885155546;6244;6286.9056964145575;6295.244570651551;6302.149209856702;4616.976915115403;4816.8554943351755;6109.009944784373;6230.221222347207;6313.842153946188;6198.380161983766;6218.9378106219265;6277.027060805583;6281.214848143982;6283.465905398092;4972.801087956441;5035.0685123252315;5037.546620804145;5039.294498770175;5069.543741063322;4501.077279157765;4542.432195975503;4545.522720114341;4552.11233809404;4565.931511027337;5268.604854635918;5691.231683722644;5930.436608522184;6030.246219222598;6064.939350606437;6261.5616906816995;6284.214473190851;6307.810765677037;6312.305646378894;6336.58812177207;6083.543734219951;6084.056971169476;6119.44924956756;6139.324674285825;6163.96918015407;5416.539594134464;5519.530839878575;5551.111822108443;5582.078956972054;5595.356533998561;6452.677366131675;6490.610563366213;6493.318201588765;6494.805155845333;6504.447121994494;6283;6289.528285378524;6294.433500984934;6301.873962520815;6384.680765961591;6277.623342599459;6333.746650133943;6338.714757835816;6380.521460890544;6476.352364763675;6156.230471191101;6186.721597528212;6230.221222347207;6241.3134555197985;6251.093591429309;6145.446909777963;6234.688265586793;6261.78083767066;6263.718132684131;6268.153799237045;6070.939290607037;6121.209103132385;6125.693326622233;6133.212252453684;6137.193127861533;6163.630182189083;6174.135621013152;6205.937940620536;6214.098955651496;6247.937520624736;6032;6061.36355682647;6093.664848433379;6109.930762116523;6165.352637972948;5215.322008138917;6132.539076726721;6140.293866205444;6147.231596050494;6195.070739389128;5144.6913185209005;6183.3816618337805;6186.1648677427975;6189.94770888959;6197.194364732736 05:49:45 INFO - PROCESS | 1950 | |1;ext.html;445.57002492594637;518.3339408859615;533.2214966149421;534.4601952028453;557.754587981288;38317.616823831755;40828.38757418641;40891;40929.386059209064;41084.972875678126;41858.7907060465;42268;42402.7879860601;42515.78742106285;42649.57350426495;9565.091316324955;9672.226221902267;9709.17472014876;9728.464934428604;9756.804863902753;9625.326227164102;9698.321117521777;9700.805983880353;9723.90276097237;9729.513524323755;9698.612055517806;9739.415635061883;9744.074312940294;9745.90254097457;9748.02519748023;9666.758331041745;9696.563654635558;9726.513674316257;9728.513574321256;9729.075737804897;9502.762430939212;9539.18916892065;9578.473183974878;9599.904000960041;9615.567299471471;40168;40168.59831401707;40263.194736105404;40423;40447.59552404497;3334.694975540273;3450.1547120955274;3643.508126402928;4016.859409920668;4082;31372.372552548823;32064;32280.838595807105;32371.514427283608;32546.511802322984;3207.9198020049453;3259.5110733389943;3278.1968417737808;3293.1602441377295;3395.47370157626;496153;503605;505366.4731676355;506258;511872;33748.831255843565;34396.48405273923;34481.655183447845;61301.77396452046;61762.0735688965;33384.49923251153;33629.15927101793;33636.49545256823;33761.49357759635;33923;14754.631134221516;14809.555713328617;14830;14896.329665165094;14902.403903843942;12657.620271391874;12728.490860365482;12738.617841464771;12745.426455809511;12749.745005099847;7327.157376901618;7329.780106596811;7349.044624198927;7383.556986580822;7400.666969986363;2976.791624586258;3014.0355086372588;3015.080400477875;3051.298201413643;3053.7556995440314;917.9724608261763;927.3091546797679;1001.6293971230546;1007.5768177365524;1081.7457897394106;27536.862315688293;30613.387732245676;30683.846580766953;31158.376832463226;31302.686973130432;1057.5928267617023;1064.4305296666246;1090;1125.3754166437707;1161.3264306702092;12556.937215313865;15787.763183552255;16087.919560402122;16101.677966440606;16521.173941302888;666545.3400062947;684315;684460;687052;691092;58986.41013589895;59579;59649;59676.70161649164;59837.700811496536;1344.683999260173;1356.687961768779;1370.205280937054;1377.8208832851865;1379.717157982611;30163.547546787253;30219.848900756235;30482.542761858145;30675.539866901567;30974.535381969738;53147.73426132845;53173;53662.65843353947;53892.46107538875;53982.65043373947;489327;490203;490345;492093;493659;27046.91812327485;27597.448051039268;27723.44553108967;27744.8902043916;27990;48865;49167;49685.00629987452;50244.497555025446;50355;40244;40318.59681403266;40397.59602404056;41046.79476602598;41639.58360416357;40148;40442.59557404506;40922.59077409188;41118.97202569959;41379.58620413757;35032.649673502936;38635.6136438632;38872;39311.803440982614;41076.58923410847;34173.82913085419;34373.48439773455;35223.29553408969;35912.281754365285;36000.81999589985;24278.02887884465;25875.22374328773;26048.73951260539;26123.477530448905;26167.214983550522 05:49:45 INFO - PROCESS | 1950 | |2;jquery.html;378.1057798307005;383.3617027648964;385.3622255167697;395.70519962627844;406.79050289101116;19073.904630476845;20965.266215682455;21160.470988225265;21346.89326553369;21389.786102138994;17615.911920440412;19757.308494202705;20178;20591.794082059176;20609.690854637156;5246.37043554772;5319.388270348902;5427.158790387496;5949.49429298509;6772.627505487197;5777.653340799545;6698.665066746667;6720.193576770795;6727;6771.4921380896385;5888.852778680524;6426.742930282783;6622.1722284714415;6787.355201255873;6789.898151527731;5749.3388260350175;6673.165854268216;6742.123523941881;6746.19045714515;6761.4590832733265;4572.9314060289125;5101.081805275049;5774.393688662714;5912.14273930282;6062.211912451397;26199.607005894926;26290.948362065494;26723.46553068928;26957.730422695913;26969.190924272305;2204.8015678589004;2239.8320125990617;2272.8181745460324;2317.7102862142233;2399.3161948844495;12533.749325013448;15856.920715396349;15939.362425502966;15952.680946381008;16150.434734784227;1825.634873025387;1884.7172924061363;1946.0756140833132;1984.1071517816895;2073.7304150460345;123012;128431;129042.3547882264;129560.35219823934;130660.34669826685;11499.712507187387;11723.648290551297;11860.644180674593;11929;11933.52265909354;14610;14672.486462973744;14680.752136068553;14685.192314422595;14712.264386780618;8373.956993749556;8471.06818249992;8620;8625.0943650917;8733.694320698745;8559.486430814171;8604.48373097616;8618.913810861812;8703.347248956354;8801.647934082694;4570.314452832035;4618.907621847544;4621.722696638212;4625.514320996314;4652.674312798139;1949.8830070195834;1956.4521933858543;1977.4463150317933;2018.8788672679686;2019.8788072715683;763.7441457111819;807.4307613132727;999.8900120986685;1050.3487837540774;1066.7653116314398;20847.37457876266;21546.892265538572;21593.02831372592;21617.351479455643;21767;844.8014716541608;918.9678361257323;943.9764005899907;969.1762002297991;1000.364768372089;10895.237333386745;11212.831807522894;11619.477123529461;11622.70943226426;11623.418829058513;511097;511600;512524;529783;583108;129367;132175;132186.33906830405;132259;134217;974.8635191073397;981.4160574458186;999.3853779925294;1000.9149222316039;1002.4586723169407;14076.718465630835;19345.51636209106;19384;19616.803831961497;19737.605247895248;54902.45097548974;56039.439605603424;56783;57496.71251643882;57922.26233213011;80740.34817562714;81847.5907620458;82076.76884846852;82466.58766706128;82941.75587366073;6962.477814163856;8402.453840500348;8422.24199822019;8487.151284871463;8647.654093836176;11987;12810.935945320214;13269.933650331686;13578.45686172542;13779.724405512034;10984.340939543654;11384.601538946097;11509.13681473876;11558.711032224262;11589.130815188726;8926.73219803407;9502.904970950201;10639.574417023232;11073.833892491459;11492.367919764492;10822.242443029227;11492.94253528727;11675.3578553177;11876.524939002344;11994.220375675553;7689.461737678309;7980.800479988046;8303.543305118277;8383.245507904317;8407.45351552147;10505.842412363974;11411.37237451915;11411.372374519482;11486.885131148916;11494.597689080842 05:49:45 INFO - PROCESS | 1950 | |3;mootools.html;1496.0799108548247;1529.2200977501475;1732.4715961631698;1844.7232915062748;1884.575970406658;8000.799980000503;8597.914020859796;8608.26829719473;9065.048169942153;9083.500407477586;8673.869891951626;8731.519766412845;8837.602307896144;8892.599833007514;8894.55527223638;2354.117206047732;2356.6582845487396;2390.6175011998084;2393.7127544694663;2402.0752010475917;1896.6206758648257;2363.3028256664325;2370.2178281167257;2373.893174807129;2417.516496700658;2383.916562920298;2386.4153282445827;2397.7482364351754;2406.1578447543307;2416.0818888822223;2369.3839601703507;2383.678203442539;2393.317904397256;2395.6286775549816;2395.9041638334447;2341.7775311345395;2381;2381.5951288281035;2382.952340953171;2383.511380167062;8059.314958228558;8696.43464176448;8968.327375446868;9005.459672419676;9032.683856065072;5281.815136470205;5378.946210537883;5452.672839629635;5453.15476101205;5465.180222966547;3507.771994820354;3602.783832970004;3638.854445822164;3665.5601327840563;3693.9261214775825;1063.7819247054335;1068.743501559628;1070.8929107089307;1071.7749272652752;1087.8694556653172;11715.765684686345;11752.236104653084;11827.704307392298;11850.407479625985;11873.821892671616;4615.79228934692;4652.55800698933;4813.687110337817;4848.12733707936;4848.781804818792;9995.550200241007;10091.344062636052;10097.192224622013;10139.442330671884;10144.797104057878;5405.540529055074;5407.675539467645;5414.673405015797;5428.6199966002005;5440.6735595864375;4384.517703052662;4777.020710754287;4947.950520494821;4965.4289756678445;4982.402111746614;3456.7407444441264;3919.412088186795;3946.7039972002217;4010.2380547695843;4039.979800100981;1274.095392271479;1278.757036163126;1284.01131129032;1291.5092264939262;1294.605145430634;931.6351544986627;933.1834644685899;935.700575815746;940.9388389754644;1001.9599216031423;6015.879682406415;6021.488173505213;6096.207493025879;6119.204503414617;6178.351273116258;429.5189387885526;436.3759823452493;447.46974834820844;456.46137557682255;459.4073644997982;3690.4279836625365;3967.583403742623;4181.832726690898;4182.53992060873;4249.107687385622;8966.596503157374;10298.536565854554;10612.840807387895;10641.414722190199;10655.30740501865;9389.62441502346;10111.191104711605;10171.54228059739;10453.895461045444;10623.628173014062;7922.801929951797;8086.474379165335;8097.433179677484;8110.188981101843;8205.466644668077;4910.484399138039;5846.093855452412;5906.291245050621;5958.7616495341435;6053.152558641706;9542.379745316532;9848.901510984799;9857;9908.653197138066;9946.701598952042;8298.502089874626;10064.295499314978;10068.244881634053;10110.443925584164;10117.696469105938;6925.238223795278;7373.33639972405;7457.142428620778;7984.20157984197;8341.290990265896;8353.289970352345;8404.621792019252;8476.449030812977;8544.957275213832;8556;7478.364339031025;7628.580428076509;7631.3894888407685;7652.923470765221;7663.540187588763;7437.925620743939;7479.850402991801;7484.588347640932;7517.661705223169;7587.772366829004;7038.753643622448;7067.5052746307265;7131.8930216045765;7144.071270734771;7176.533525320838;5006.3992320921725;5075.2387141929;5169.405518365436;5183.740812959412;5190.870228244324;5169.198774190007;5179.533841954091;5199.558037566698;5204.297419848272;5325.201219817059 05:49:45 INFO - PROCESS | 1950 | |4;prototype.html;310.4319096054221;314.63659473308337;316.30886512969164;317.2259686365267;323.0905002418194;5784.537237021039;5863.853403664905;5877.235959325281;5879.617824841383;5883.469918049168;5726.370099289085;5741.799037033705;5776.624519406236;5797.840177103478;5817.331006934204;4096.97951510242;4140.50313962325;4244.405783190356;4289.806958686867;4325.740455572675;4279.315309550472;4289.442372491571;4293.506246781613;4332.480102387718;4347.5653633965085;3552.4848896909934;3811.980940095296;4270.871873843774;4327.242732521812;4337.725017611699;3804.8668296609762;4086.9591304087025;4318.136372725452;4332.306830907071;4345.826166953318;3733.253349330145;3749.906252343686;3763.9811800941093;3772.3398405278967;3773.6037716039696;5618.6628802272;5638.802641907513;5674.928651042381;5689.667236433084;5716.1711551825165;4574.931376029363;4961.858772482349;5099.898002039976;5109.4379618241965;5114.744262786846;5986.73059712314;6064.696765161769;6103.664298463583;6111.266648002268;6126.264848218242;4040.5757395473347;4192.8532501362615;4194.937075943863;4214.683898707609;4223.97888010561;5571.777128914839;5632.633878797865;5951.256092988377;6363.631819158857;6369.751426772333;3160.9051728448185;3176.4123637127204;3581.552305961755;3732.712214286067;4070.8575199867864;3623.2572322673786;3687.2994131115;3695.6304369562827;3922.235164143022;4160.979195104005;4773.188557945157;4899.706017638881;4901.460839307673;4904.901901962012;4908.607311415079;5518.448155184449;5648.548116150698;6042.637441753509;6048.123022161762;6087.786927457606;1312.4225340849996;1434.6413396650837;1446.0528353928194;1477.8226612806532;1481.2815784344584;699.6152116335995;824.9358327757126;911.6717981526646;958.990410095904;995.7112437393075;4542.11428771393;5031.33965791288;5151.2530683050745;5587.262241501716;5668.773249070074;5329.413764485904;5439.619226654176;6055;6156.844130212089;6240.53196010292;5362.812301569426;5640.7743690252755;5660.5471562274915;5663;5705;2887.148291254085;3249.382617302705;3254.1864533866533;3272.5582046423906;3333.583302087239;5963.642181469126;6422.550421470452;6433.300852010113;6444.583281269524;6475.7733479328;5800.506956908625;6356.4279214870885;6377.426031657081;6381.851266771932;6387.137736405619;6395.840103997437;6466.6766661666725;6467.773627923094;6473.320301368382;6487.605164889572;4840.709557426566;4931.988942266755;5523.82950872089;5771.971140144273;6193.907091393723;5600.35595906476;5889.469947704727;6270.561060725706;6303.212098487689;6347.809565713036;5067.898642027212;5798.159266906359;6569.671516424255;6595.241547221939;6597.80206593803;3353.631100578983;3572.214112895159;4000.079981604189;4054.817533211072;4111.712180147361;5536.7785288588;6013;6314.778982735766;6343.714532845941;6416.582922110048;4880.511948805091;5091.389033315878;5682.943170568242;6136.32500424944;6156.938430615636;6024.3674414187635;6075.301340345917;6124.54065945047;6141.692915354304;6160.907586386297;5384.730763461889;5557;5932.466078052996;5963.7912673056235;5968.432998865101;5301.814436494704;5784.421557844182;6083.330833608388;6128.754849806136;6147.032949456131;4996.750162491933;5292.729744861283;5372.328458942632;6033.215681961317;6096.969515152396 05:49:45 INFO - PROCESS | 1950 | |5;yui.html;638.2978723404259;671.4930227678102;707.7628994286916;822.5311572403731;844.1389782421932;15483.909672194264;15956.202189890488;16143.757843632326;16158.434454794084;16206.027638341693;14531.709365812678;14583.781243281359;14689.118652880808;14695.191764452951;15502.837287203476;874.1564390363283;904.0868722590199;906.9365144439892;907.9954600226989;916.4180745226796;687.7283473028151;860.8378688770173;894.5795476126243;901.4185850126664;901.7610333261691;937.8452555328363;938.3478482454697;939.1876027236425;940.7742141885932;944.0135058863505;884.3058199313572;902.3052249767675;934.5560858592148;935.8830146231721;936.0686117313282;694.4236283884364;695.9547629404124;699.9370056694871;700.3381804195038;701.551007355293;14732.484363047242;14856.554303370916;14909.850901490954;14921.253937303092;14972.850271497255;13998.510052148125;14067.01530892838;14082.577522674335;14209;14491.71016579673;11930.642080737593;12043.698907527294;12044.81932771009;12380.195287306296;12431.12982091254;3901.1807520420743;3977.6817854571564;4058.391241313797;4059.5940405959464;4061.776602286873;57110;82785.58607206926;82984.71552600455;98411.0158898409;105168;11179.770509983437;11487;11573.074154067823;11573.942130289295;11756.470958806874;22334.106635734715;22369.217077402212;22395.440113996956;22428.439289017904;22467.43831404228;7360.96319518399;7822.882656760153;7859.742441209493;8298.502089874626;8581.914180858112;4597.7011494252765;4622.375979242759;4671.929921051187;4674.0885527322525;4675.08835777027;3560.287942411528;3622.0582648511577;3626.1841085756023;3667.614900435416;3678.3562876496508;555.3391464157639;560.4143669865001;573.8909607174623;636.48762745989;638.4732595608549;584.8187062010791;590.8109404990362;598.7784519727728;636.9426751592379;641.2561428742636;9112.49881256524;9400.24798016157;9418.62325506972;9554.570044348022;9589.041095890494;544.5099410530554;637.1939496536885;637.9298277189505;637.9457746091634;661.7452280871898;4550.49944506104;4643.117807616542;4709.222978208626;4731.33761273422;4731.809434778553;120934.39532802456;142140;146804.26597866943;148668;151482;89219.55390223138;108797.45601271943;111666.44166779114;132375;133555.3255636048;74176.25823741802;82356;82787.75818362729;82984;83206;9817.656382026595;10203.234757393077;10234.02776736209;10276.332038417479;10277.486125693835;61199.69400152971;62518;62600.68699656473;64263.71472570429;64573;69851.30148698651;78859.4228115423;84673;84832;93575;11739.178257522233;13529.2558909257;13586;13614.523491677744;14160.221187834768;15091.24543772785;15107;15481.380744770084;15500.68998620044;15952.282147303167;14001;14063.367148478139;14132.364043618267;14253.50112746049;14367.856321436651;12771.61685149447;12791.36043197855;13218;13376.996725245841;13390.263535505252;13868.93065534666;13926.860731392557;14256.928715356356;14316.713665726418;14435.494757683431;11272.830907536558;11454.541818327174;11466.484008219484;11690.649280521598;11790.351530665552;10827.187960903122;11391.715207119889;11438.08495320389;11781.352025638673;11801.29192248468 05:49:45 INFO - PROCESS | 1950 | __end_tp_report 05:49:45 INFO - PROCESS | 1950 | __start_cc_report 05:49:45 INFO - PROCESS | 1950 | _x_x_mozilla_cycle_collect,436 05:49:45 INFO - PROCESS | 1950 | __end_cc_report 05:49:45 INFO - PROCESS | 1950 | __startTimestamp1467463785217__endTimestamp 05:49:45 INFO - PROCESS | 1950 | 05:49:45 INFO - PROCESS | 1950 | ------- Summary: start ------- 05:49:45 INFO - PROCESS | 1950 | Number of tests: 6 05:49:45 INFO - PROCESS | 1950 | 05:49:45 INFO - PROCESS | 1950 | [#0] dojo.html Cycles:165 Average:5806.22 Median:6097.92 stddev:660.31 (10.8%) stddev-sans-first:634.43 05:49:45 INFO - PROCESS | 1950 | Values: 3385.1 3387.1 3415.3 3418.9 3432.7 5847.8 5900.5 5948.0 5983.0 5991.0 5724.9 5921.4 5924.6 5932.2 5985.6 4232.0 5207.2 5253.3 5319.1 5372.5 5269.8 5401.5 5420.4 5431.0 5439.5 5192.4 5259.4 5262.5 5291.6 5297.0 5270.3 5288.4 5289.9 5295.5 5296.2 5230.2 5285.5 5287.1 5293.7 5403.4 5622.0 5784.4 5841.0 5914.4 5935.5 6224.8 6256.4 6272.6 6306.1 6320.2 6205.4 6286.8 6312.0 6319.3 6325.5 5911.3 5938.8 5978.6 5986.3 5991.0 5080.9 6007.6 6330.9 6340.0 6359.8 6097.9 6207.1 6280.6 6327.8 6348.3 6223.0 6244.0 6286.9 6295.2 6302.1 4617.0 4816.9 6109.0 6230.2 6313.8 6198.4 6218.9 6277.0 6281.2 6283.5 4972.8 5035.1 5037.5 5039.3 5069.5 4501.1 4542.4 4545.5 4552.1 4565.9 5268.6 5691.2 5930.4 6030.2 6064.9 6261.6 6284.2 6307.8 6312.3 6336.6 6083.5 6084.1 6119.4 6139.3 6164.0 5416.5 5519.5 5551.1 5582.1 5595.4 6452.7 6490.6 6493.3 6494.8 6504.4 6283.0 6289.5 6294.4 6301.9 6384.7 6277.6 6333.7 6338.7 6380.5 6476.4 6156.2 6186.7 6230.2 6241.3 6251.1 6145.4 6234.7 6261.8 6263.7 6268.2 6070.9 6121.2 6125.7 6133.2 6137.2 6163.6 6174.1 6205.9 6214.1 6247.9 6032.0 6061.4 6093.7 6109.9 6165.4 5215.3 6132.5 6140.3 6147.2 6195.1 5144.7 6183.4 6186.2 6189.9 6197.2 05:49:45 INFO - PROCESS | 1950 | 05:49:45 INFO - PROCESS | 1950 | [#1] ext.html Cycles:180 Average:68257.54 Median:40358.10 stddev:151633.58 (375.7%) stddev-sans-first:151973.47 05:49:45 INFO - PROCESS | 1950 | Values: 445.6 518.3 533.2 534.5 557.8 38317.6 40828.4 40891.0 40929.4 41085.0 41858.8 42268.0 42402.8 42515.8 42649.6 9565.1 9672.2 9709.2 9728.5 9756.8 9625.3 9698.3 9700.8 9723.9 9729.5 9698.6 9739.4 9744.1 9745.9 9748.0 9666.8 9696.6 9726.5 9728.5 9729.1 9502.8 9539.2 9578.5 9599.9 9615.6 40168.0 40168.6 40263.2 40423.0 40447.6 3334.7 3450.2 3643.5 4016.9 4082.0 31372.4 32064.0 32280.8 32371.5 32546.5 3207.9 3259.5 3278.2 3293.2 3395.5 496153.0 503605.0 505366.5 506258.0 511872.0 33748.8 34396.5 34481.7 61301.8 61762.1 33384.5 33629.2 33636.5 33761.5 33923.0 14754.6 14809.6 14830.0 14896.3 14902.4 12657.6 12728.5 12738.6 12745.4 12749.7 7327.2 7329.8 7349.0 7383.6 7400.7 2976.8 3014.0 3015.1 3051.3 3053.8 918.0 927.3 1001.6 1007.6 1081.7 27536.9 30613.4 30683.8 31158.4 31302.7 1057.6 1064.4 1090.0 1125.4 1161.3 12556.9 15787.8 16087.9 16101.7 16521.2 666545.3 684315.0 684460.0 687052.0 691092.0 58986.4 59579.0 59649.0 59676.7 59837.7 1344.7 1356.7 1370.2 1377.8 1379.7 30163.5 30219.8 30482.5 30675.5 30974.5 53147.7 53173.0 53662.7 53892.5 53982.7 489327.0 490203.0 490345.0 492093.0 493659.0 27046.9 27597.4 27723.4 27744.9 27990.0 48865.0 49167.0 49685.0 50244.5 50355.0 40244.0 40318.6 40397.6 41046.8 41639.6 40148.0 40442.6 40922.6 41119.0 41379.6 35032.6 38635.6 38872.0 39311.8 41076.6 34173.8 34373.5 35223.3 35912.3 36000.8 24278.0 25875.2 26048.7 26123.5 26167.2 05:49:45 INFO - PROCESS | 1950 | 05:49:45 INFO - PROCESS | 1950 | [#2] jquery.html Cycles:180 Average:34021.26 Median:2295.26 stddev:89635.52 (3905.2%) stddev-sans-first:89851.37 05:49:45 INFO - PROCESS | 1950 | Values: 378.1 383.4 385.4 395.7 406.8 19073.9 20965.3 21160.5 21346.9 21389.8 17615.9 19757.3 20178.0 20591.8 20609.7 5246.4 5319.4 5427.2 5949.5 6772.6 5777.7 6698.7 6720.2 6727.0 6771.5 5888.9 6426.7 6622.2 6787.4 6789.9 5749.3 6673.2 6742.1 6746.2 6761.5 4572.9 5101.1 5774.4 5912.1 6062.2 26199.6 26290.9 26723.5 26957.7 26969.2 2204.8 2239.8 2272.8 2317.7 2399.3 12533.7 15856.9 15939.4 15952.7 16150.4 1825.6 1884.7 1946.1 1984.1 2073.7 123012.0 128431.0 129042.4 129560.4 130660.3 11499.7 11723.6 11860.6 11929.0 11933.5 14610.0 14672.5 14680.8 14685.2 14712.3 8374.0 8471.1 8620.0 8625.1 8733.7 8559.5 8604.5 8618.9 8703.3 8801.6 4570.3 4618.9 4621.7 4625.5 4652.7 1949.9 1956.5 1977.4 2018.9 2019.9 763.7 807.4 999.9 1050.3 1066.8 20847.4 21546.9 21593.0 21617.4 21767.0 844.8 919.0 944.0 969.2 1000.4 10895.2 11212.8 11619.5 11622.7 11623.4 511097.0 511600.0 512524.0 529783.0 583108.0 129367.0 132175.0 132186.3 132259.0 134217.0 974.9 981.4 999.4 1000.9 1002.5 14076.7 19345.5 19384.0 19616.8 19737.6 54902.5 56039.4 56783.0 57496.7 57922.3 80740.3 81847.6 82076.8 82466.6 82941.8 6962.5 8402.5 8422.2 8487.2 8647.7 11987.0 12810.9 13269.9 13578.5 13779.7 10984.3 11384.6 11509.1 11558.7 11589.1 8926.7 9502.9 10639.6 11073.8 11492.4 10822.2 11492.9 11675.4 11876.5 11994.2 7689.5 7980.8 8303.5 8383.2 8407.5 10505.8 11411.4 11411.4 11486.9 11494.6 05:49:45 INFO - PROCESS | 1950 | 05:49:45 INFO - PROCESS | 1950 | [#3] mootools.html Cycles:180 Average:5717.64 Median:4956.69 stddev:3178.27 (64.1%) stddev-sans-first:3171.36 05:49:45 INFO - PROCESS | 1950 | Values: 1496.1 1529.2 1732.5 1844.7 1884.6 8000.8 8597.9 8608.3 9065.0 9083.5 8673.9 8731.5 8837.6 8892.6 8894.6 2354.1 2356.7 2390.6 2393.7 2402.1 1896.6 2363.3 2370.2 2373.9 2417.5 2383.9 2386.4 2397.7 2406.2 2416.1 2369.4 2383.7 2393.3 2395.6 2395.9 2341.8 2381.0 2381.6 2383.0 2383.5 8059.3 8696.4 8968.3 9005.5 9032.7 5281.8 5378.9 5452.7 5453.2 5465.2 3507.8 3602.8 3638.9 3665.6 3693.9 1063.8 1068.7 1070.9 1071.8 1087.9 11715.8 11752.2 11827.7 11850.4 11873.8 4615.8 4652.6 4813.7 4848.1 4848.8 9995.6 10091.3 10097.2 10139.4 10144.8 5405.5 5407.7 5414.7 5428.6 5440.7 4384.5 4777.0 4948.0 4965.4 4982.4 3456.7 3919.4 3946.7 4010.2 4040.0 1274.1 1278.8 1284.0 1291.5 1294.6 931.6 933.2 935.7 940.9 1002.0 6015.9 6021.5 6096.2 6119.2 6178.4 429.5 436.4 447.5 456.5 459.4 3690.4 3967.6 4181.8 4182.5 4249.1 8966.6 10298.5 10612.8 10641.4 10655.3 9389.6 10111.2 10171.5 10453.9 10623.6 7922.8 8086.5 8097.4 8110.2 8205.5 4910.5 5846.1 5906.3 5958.8 6053.2 9542.4 9848.9 9857.0 9908.7 9946.7 8298.5 10064.3 10068.2 10110.4 10117.7 6925.2 7373.3 7457.1 7984.2 8341.3 8353.3 8404.6 8476.4 8545.0 8556.0 7478.4 7628.6 7631.4 7652.9 7663.5 7437.9 7479.9 7484.6 7517.7 7587.8 7038.8 7067.5 7131.9 7144.1 7176.5 5006.4 5075.2 5169.4 5183.7 5190.9 5169.2 5179.5 5199.6 5204.3 5325.2 05:49:45 INFO - PROCESS | 1950 | 05:49:45 INFO - PROCESS | 1950 | [#4] prototype.html Cycles:180 Average:4866.07 Median:5579.52 stddev:1541.54 (27.6%) stddev-sans-first:1507.47 05:49:45 INFO - PROCESS | 1950 | Values: 310.4 314.6 316.3 317.2 323.1 5784.5 5863.9 5877.2 5879.6 5883.5 5726.4 5741.8 5776.6 5797.8 5817.3 4097.0 4140.5 4244.4 4289.8 4325.7 4279.3 4289.4 4293.5 4332.5 4347.6 3552.5 3812.0 4270.9 4327.2 4337.7 3804.9 4087.0 4318.1 4332.3 4345.8 3733.3 3749.9 3764.0 3772.3 3773.6 5618.7 5638.8 5674.9 5689.7 5716.2 4574.9 4961.9 5099.9 5109.4 5114.7 5986.7 6064.7 6103.7 6111.3 6126.3 4040.6 4192.9 4194.9 4214.7 4224.0 5571.8 5632.6 5951.3 6363.6 6369.8 3160.9 3176.4 3581.6 3732.7 4070.9 3623.3 3687.3 3695.6 3922.2 4161.0 4773.2 4899.7 4901.5 4904.9 4908.6 5518.4 5648.5 6042.6 6048.1 6087.8 1312.4 1434.6 1446.1 1477.8 1481.3 699.6 824.9 911.7 959.0 995.7 4542.1 5031.3 5151.3 5587.3 5668.8 5329.4 5439.6 6055.0 6156.8 6240.5 5362.8 5640.8 5660.5 5663.0 5705.0 2887.1 3249.4 3254.2 3272.6 3333.6 5963.6 6422.6 6433.3 6444.6 6475.8 5800.5 6356.4 6377.4 6381.9 6387.1 6395.8 6466.7 6467.8 6473.3 6487.6 4840.7 4932.0 5523.8 5772.0 6193.9 5600.4 5889.5 6270.6 6303.2 6347.8 5067.9 5798.2 6569.7 6595.2 6597.8 3353.6 3572.2 4000.1 4054.8 4111.7 5536.8 6013.0 6314.8 6343.7 6416.6 4880.5 5091.4 5682.9 6136.3 6156.9 6024.4 6075.3 6124.5 6141.7 6160.9 5384.7 5557.0 5932.5 5963.8 5968.4 5301.8 5784.4 6083.3 6128.8 6147.0 4996.8 5292.7 5372.3 6033.2 6097.0 05:49:45 INFO - PROCESS | 1950 | 05:49:45 INFO - PROCESS | 1950 | [#5] yui.html Cycles:180 Average:22761.02 Median:4018.04 stddev:34812.18 (866.4%) stddev-sans-first:34870.21 05:49:45 INFO - PROCESS | 1950 | Values: 638.3 671.5 707.8 822.5 844.1 15483.9 15956.2 16143.8 16158.4 16206.0 14531.7 14583.8 14689.1 14695.2 15502.8 874.2 904.1 906.9 908.0 916.4 687.7 860.8 894.6 901.4 901.8 937.8 938.3 939.2 940.8 944.0 884.3 902.3 934.6 935.9 936.1 694.4 696.0 699.9 700.3 701.6 14732.5 14856.6 14909.9 14921.3 14972.9 13998.5 14067.0 14082.6 14209.0 14491.7 11930.6 12043.7 12044.8 12380.2 12431.1 3901.2 3977.7 4058.4 4059.6 4061.8 57110.0 82785.6 82984.7 98411.0 105168.0 11179.8 11487.0 11573.1 11573.9 11756.5 22334.1 22369.2 22395.4 22428.4 22467.4 7361.0 7822.9 7859.7 8298.5 8581.9 4597.7 4622.4 4671.9 4674.1 4675.1 3560.3 3622.1 3626.2 3667.6 3678.4 555.3 560.4 573.9 636.5 638.5 584.8 590.8 598.8 636.9 641.3 9112.5 9400.2 9418.6 9554.6 9589.0 544.5 637.2 637.9 637.9 661.7 4550.5 4643.1 4709.2 4731.3 4731.8 120934.4 142140.0 146804.3 148668.0 151482.0 89219.6 108797.5 111666.4 132375.0 133555.3 74176.3 82356.0 82787.8 82984.0 83206.0 9817.7 10203.2 10234.0 10276.3 10277.5 61199.7 62518.0 62600.7 64263.7 64573.0 69851.3 78859.4 84673.0 84832.0 93575.0 11739.2 13529.3 13586.0 13614.5 14160.2 15091.2 15107.0 15481.4 15500.7 15952.3 14001.0 14063.4 14132.4 14253.5 14367.9 12771.6 12791.4 13218.0 13377.0 13390.3 13868.9 13926.9 14256.9 14316.7 14435.5 11272.8 11454.5 11466.5 11690.6 11790.4 10827.2 11391.7 11438.1 11781.4 11801.3 05:49:45 INFO - PROCESS | 1950 | -------- Summary: end -------- 05:49:45 INFO - PROCESS | 1950 | 05:49:45 INFO - TEST-INFO | 1950: exit 0 05:49:45 INFO - TEST-OK | dromaeo_css | took 1113243ms 05:49:45 INFO - TEST-START | kraken 05:49:45 INFO - operating with platform_type : mac_ 05:49:45 INFO - Initialising browser for kraken test... 05:49:45 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpAZumD_/profile http://localhost:56100/getInfo.html) 05:49:49 INFO - PROCESS | 1954 | __metrics Screen width/height:1600/1200 05:49:49 INFO - PROCESS | 1954 | colorDepth:24 05:49:49 INFO - PROCESS | 1954 | Browser inner width/height: 1024/689 05:49:49 INFO - PROCESS | 1954 | __metrics 05:49:49 ERROR - PROCESS | 1954 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 05:49:49 INFO - TEST-INFO | 1954: exit 0 05:49:49 INFO - Browser initialized. 05:49:49 INFO - Running cycle 1/1 for kraken test... 05:49:49 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpAZumD_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:50:02 INFO - PROCESS | 1957 | RSS: Main: 255741952 05:50:02 INFO - PROCESS | 1957 | 05:50:04 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:56100/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56100/tests/kraken/driver.html?testName=audio-beat-detection) 05:50:05 INFO - PROCESS | 1957 | RSS: Main: 268550144 05:50:05 INFO - PROCESS | 1957 | 05:50:08 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:56100/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56100/tests/kraken/driver.html?testName=audio-dft) 05:50:08 INFO - PROCESS | 1957 | RSS: Main: 272728064 05:50:08 INFO - PROCESS | 1957 | 05:50:12 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:56100/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56100/tests/kraken/driver.html?testName=audio-fft) 05:50:12 INFO - PROCESS | 1957 | RSS: Main: 273125376 05:50:12 INFO - PROCESS | 1957 | 05:50:14 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:56100/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56100/tests/kraken/driver.html?testName=audio-oscillator) 05:50:15 INFO - PROCESS | 1957 | RSS: Main: 274014208 05:50:15 INFO - PROCESS | 1957 | 05:50:17 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:56100/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56100/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:50:18 INFO - PROCESS | 1957 | RSS: Main: 274595840 05:50:18 INFO - PROCESS | 1957 | 05:50:21 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:56100/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56100/tests/kraken/driver.html?testName=imaging-darkroom) 05:50:21 INFO - PROCESS | 1957 | RSS: Main: 275292160 05:50:21 INFO - PROCESS | 1957 | 05:50:24 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:56100/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56100/tests/kraken/driver.html?testName=imaging-desaturate) 05:50:25 INFO - PROCESS | 1957 | RSS: Main: 275402752 05:50:25 INFO - PROCESS | 1957 | 05:50:28 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:56100/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56100/tests/kraken/driver.html?testName=json-parse-financial) 05:50:28 INFO - PROCESS | 1957 | RSS: Main: 275668992 05:50:28 INFO - PROCESS | 1957 | 05:50:30 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:56100/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56100/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:50:31 INFO - PROCESS | 1957 | RSS: Main: 275689472 05:50:31 INFO - PROCESS | 1957 | 05:50:33 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:56100/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56100/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:50:33 INFO - PROCESS | 1957 | RSS: Main: 275279872 05:50:33 INFO - PROCESS | 1957 | 05:50:35 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:56100/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56100/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:50:36 INFO - PROCESS | 1957 | RSS: Main: 275374080 05:50:36 INFO - PROCESS | 1957 | 05:50:39 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:56100/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56100/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:50:40 INFO - PROCESS | 1957 | RSS: Main: 275361792 05:50:40 INFO - PROCESS | 1957 | 05:50:43 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:56100/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56100/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:50:43 INFO - PROCESS | 1957 | RSS: Main: 275529728 05:50:43 INFO - PROCESS | 1957 | 05:50:45 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:56100/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56100/tests/kraken/driver.html?testName=ai-astar) 05:50:45 INFO - PROCESS | 1957 | RSS: Main: 276230144 05:50:45 INFO - PROCESS | 1957 | 05:50:45 INFO - PROCESS | 1957 | __start_tp_report 05:50:45 INFO - PROCESS | 1957 | _x_x_mozilla_page_load 05:50:45 INFO - PROCESS | 1957 | _x_x_mozilla_page_load_details 05:50:45 INFO - PROCESS | 1957 | |i|pagename|runs| 05:50:45 INFO - PROCESS | 1957 | |0;ai-astar;104;115;105;115;114;115;115;115;117;135 05:50:45 INFO - PROCESS | 1957 | |1;audio-beat-detection;147;108;131;155;110;134;161;140;155;110 05:50:45 INFO - PROCESS | 1957 | |2;audio-dft;200;214;202;237;270;209;198;199;197;197 05:50:45 INFO - PROCESS | 1957 | |3;audio-fft;71;83;72;110;57;58;58;63;131;51 05:50:45 INFO - PROCESS | 1957 | |4;audio-oscillator;70;70;118;72;73;82;117;71;78;126 05:50:45 INFO - PROCESS | 1957 | |5;imaging-gaussian-blur;100;91;93;128;133;108;89;86;89;87 05:50:45 INFO - PROCESS | 1957 | |6;imaging-darkroom;95;97;96;96;101;91;96;133;149;95 05:50:45 INFO - PROCESS | 1957 | |7;imaging-desaturate;143;134;91;112;139;128;88;90;91;91 05:50:45 INFO - PROCESS | 1957 | |8;json-parse-financial;55;58;57;57;59;59;56;65;69;85 05:50:45 INFO - PROCESS | 1957 | |9;json-stringify-tinderbox;63;72;70;76;78;82;72;70;70;100 05:50:45 INFO - PROCESS | 1957 | |10;stanford-crypto-aes;93;100;81;87;86;80;84;83;88;103 05:50:45 INFO - PROCESS | 1957 | |11;stanford-crypto-ccm;136;136;142;144;149;134;139;137;184;148 05:50:45 INFO - PROCESS | 1957 | |12;stanford-crypto-pbkdf2;165;218;188;162;167;166;171;161;200;222 05:50:45 INFO - PROCESS | 1957 | |13;stanford-crypto-sha256-iterative;63;83;86;84;87;84;74;82;67;59 05:50:45 INFO - PROCESS | 1957 | __end_tp_report 05:50:45 INFO - PROCESS | 1957 | __start_cc_report 05:50:45 INFO - PROCESS | 1957 | _x_x_mozilla_cycle_collect,1128 05:50:45 INFO - PROCESS | 1957 | __end_cc_report 05:50:45 INFO - PROCESS | 1957 | __startTimestamp1467463845816__endTimestamp 05:50:45 INFO - PROCESS | 1957 | 05:50:45 INFO - PROCESS | 1957 | ------- Summary: start ------- 05:50:45 INFO - PROCESS | 1957 | Number of tests: 14 05:50:45 INFO - PROCESS | 1957 | 05:50:45 INFO - PROCESS | 1957 | [#0] ai-astar Cycles:10 Average:115.00 Median:115.00 stddev:8.34 (7.3%) stddev-sans-first:7.84 05:50:45 INFO - PROCESS | 1957 | Values: 104.0 115.0 105.0 115.0 114.0 115.0 115.0 115.0 117.0 135.0 05:50:45 INFO - PROCESS | 1957 | 05:50:45 INFO - PROCESS | 1957 | [#1] audio-beat-detection Cycles:10 Average:135.10 Median:143.50 stddev:20.11 (14.0%) stddev-sans-first:20.87 05:50:45 INFO - PROCESS | 1957 | Values: 147.0 108.0 131.0 155.0 110.0 134.0 161.0 140.0 155.0 110.0 05:50:45 INFO - PROCESS | 1957 | 05:50:45 INFO - PROCESS | 1957 | [#2] audio-dft Cycles:10 Average:212.30 Median:205.50 stddev:23.71 (11.5%) stddev-sans-first:24.73 05:50:45 INFO - PROCESS | 1957 | Values: 200.0 214.0 202.0 237.0 270.0 209.0 198.0 199.0 197.0 197.0 05:50:45 INFO - PROCESS | 1957 | 05:50:45 INFO - PROCESS | 1957 | [#3] audio-fft Cycles:10 Average:75.40 Median:60.50 stddev:25.97 (42.9%) stddev-sans-first:27.50 05:50:45 INFO - PROCESS | 1957 | Values: 71.0 83.0 72.0 110.0 57.0 58.0 58.0 63.0 131.0 51.0 05:50:45 INFO - PROCESS | 1957 | 05:50:45 INFO - PROCESS | 1957 | [#4] audio-oscillator Cycles:10 Average:87.70 Median:71.50 stddev:22.94 (32.1%) stddev-sans-first:23.43 05:50:45 INFO - PROCESS | 1957 | Values: 70.0 70.0 118.0 72.0 73.0 82.0 117.0 71.0 78.0 126.0 05:50:45 INFO - PROCESS | 1957 | 05:50:45 INFO - PROCESS | 1957 | [#5] imaging-gaussian-blur Cycles:10 Average:100.40 Median:88.00 stddev:17.23 (19.6%) stddev-sans-first:18.28 05:50:45 INFO - PROCESS | 1957 | Values: 100.0 91.0 93.0 128.0 133.0 108.0 89.0 86.0 89.0 87.0 05:50:45 INFO - PROCESS | 1957 | 05:50:45 INFO - PROCESS | 1957 | [#6] imaging-darkroom Cycles:10 Average:104.90 Median:95.50 stddev:19.55 (20.5%) stddev-sans-first:20.40 05:50:45 INFO - PROCESS | 1957 | Values: 95.0 97.0 96.0 96.0 101.0 91.0 96.0 133.0 149.0 95.0 05:50:45 INFO - PROCESS | 1957 | 05:50:45 INFO - PROCESS | 1957 | [#7] imaging-desaturate Cycles:10 Average:110.70 Median:89.00 stddev:23.08 (25.9%) stddev-sans-first:21.32 05:50:45 INFO - PROCESS | 1957 | Values: 143.0 134.0 91.0 112.0 139.0 128.0 88.0 90.0 91.0 91.0 05:50:45 INFO - PROCESS | 1957 | 05:50:45 INFO - PROCESS | 1957 | [#8] json-parse-financial Cycles:10 Average:62.00 Median:59.00 stddev:9.17 (15.5%) stddev-sans-first:9.36 05:50:45 INFO - PROCESS | 1957 | Values: 55.0 58.0 57.0 57.0 59.0 59.0 56.0 65.0 69.0 85.0 05:50:45 INFO - PROCESS | 1957 | 05:50:45 INFO - PROCESS | 1957 | [#9] json-stringify-tinderbox Cycles:10 Average:75.30 Median:72.00 stddev:10.11 (14.0%) stddev-sans-first:9.70 05:50:45 INFO - PROCESS | 1957 | Values: 63.0 72.0 70.0 76.0 78.0 82.0 72.0 70.0 70.0 100.0 05:50:45 INFO - PROCESS | 1957 | 05:50:45 INFO - PROCESS | 1957 | [#10] stanford-crypto-aes Cycles:10 Average:88.50 Median:85.00 stddev:7.82 (9.2%) stddev-sans-first:8.12 05:50:45 INFO - PROCESS | 1957 | Values: 93.0 100.0 81.0 87.0 86.0 80.0 84.0 83.0 88.0 103.0 05:50:45 INFO - PROCESS | 1957 | 05:50:45 INFO - PROCESS | 1957 | [#11] stanford-crypto-ccm Cycles:10 Average:144.90 Median:143.00 stddev:14.68 (10.3%) stddev-sans-first:15.21 05:50:45 INFO - PROCESS | 1957 | Values: 136.0 136.0 142.0 144.0 149.0 134.0 139.0 137.0 184.0 148.0 05:50:45 INFO - PROCESS | 1957 | 05:50:45 INFO - PROCESS | 1957 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.00 Median:179.50 stddev:23.54 (13.1%) stddev-sans-first:24.15 05:50:45 INFO - PROCESS | 1957 | Values: 165.0 218.0 188.0 162.0 167.0 166.0 171.0 161.0 200.0 222.0 05:50:45 INFO - PROCESS | 1957 | 05:50:45 INFO - PROCESS | 1957 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.90 Median:83.50 stddev:10.38 (12.4%) stddev-sans-first:9.71 05:50:45 INFO - PROCESS | 1957 | Values: 63.0 83.0 86.0 84.0 87.0 84.0 74.0 82.0 67.0 59.0 05:50:45 INFO - PROCESS | 1957 | -------- Summary: end -------- 05:50:45 INFO - PROCESS | 1957 | 05:50:46 INFO - TEST-INFO | 1957: exit 0 05:50:46 INFO - TEST-OK | kraken | took 61015ms 05:50:46 INFO - SUITE-END | took 1174s 05:50:46 INFO - Completed test suite (00:19:34) 05:50:46 INFO - javascript benchmark 05:50:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7405.330065289361, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3385.1367901185195, 3387.0854869185305, 3415.282790613971, 3418.914527136821, 3432.7082198013154, 5847.79532716355, 5900.527957763374, 5947.9999999999945, 5983.0, 5990.970045149768, 5724.9141262880985, 5921.407859214077, 5924.614900031495, 5932.228810254672, 5985.551083668732, 4231.978840105795, 5207.166853303482, 5253.317068781053, 5319.148936170213, 5372.516473517383, 5269.789208431677, 5401.540869026137, 5420.430854760252, 5431.049566325877, 5439.5104440600335, 5192.376914770214, 5259.421463638997, 5262.500062494057, 5291.629585928987, 5296.999999999981, 5270.341207349082, 5288.391834938992, 5289.867753306198, 5295.4704529547125, 5296.152615581488, 5230.189320655304, 5285.497877701612, 5287.101192797234, 5293.7353132343615, 5403.378611459654, 5621.959937411592, 5784.363719990798, 5840.97079514604, 5914.378990206053, 5935.49548288396, 6224.782132625382, 6256.405641464054, 6272.56092073555, 6306.1486699295465, 6320.209973753281, 6205.410486003822, 6286.842828929268, 6311.96844015777, 6319.336469670664, 6325.493960483151, 5911.261092363455, 5938.7624495020145, 5978.641281523122, 5986.25171853514, 5990.951583472962, 5080.933004069113, 6007.609505382136, 6330.873382532323, 6339.985602303609, 6359.8092057238355, 6097.917917222642, 6207.068939659087, 6280.623162610258, 6327.778527751507, 6348.333424990309, 6222.968885155546, 6244.0, 6286.9056964145575, 6295.244570651551, 6302.149209856702, 4616.976915115403, 4816.8554943351755, 6109.009944784373, 6230.221222347207, 6313.842153946188, 6198.380161983766, 6218.9378106219265, 6277.027060805583, 6281.214848143982, 6283.465905398092, 4972.801087956441, 5035.0685123252315, 5037.546620804145, 5039.294498770175, 5069.543741063322, 4501.077279157765, 4542.432195975503, 4545.522720114341, 4552.11233809404, 4565.931511027337, 5268.604854635918, 5691.231683722644, 5930.436608522184, 6030.246219222598, 6064.939350606437, 6261.5616906816995, 6284.214473190851, 6307.810765677037, 6312.305646378894, 6336.58812177207, 6083.543734219951, 6084.056971169476, 6119.44924956756, 6139.324674285825, 6163.96918015407, 5416.539594134464, 5519.530839878575, 5551.111822108443, 5582.078956972054, 5595.356533998561, 6452.677366131675, 6490.610563366213, 6493.318201588765, 6494.805155845333, 6504.447121994494, 6283.0, 6289.528285378524, 6294.433500984934, 6301.873962520815, 6384.680765961591, 6277.623342599459, 6333.746650133943, 6338.714757835816, 6380.521460890544, 6476.352364763675, 6156.230471191101, 6186.721597528212, 6230.221222347207, 6241.3134555197985, 6251.093591429309, 6145.446909777963, 6234.688265586793, 6261.78083767066, 6263.718132684131, 6268.153799237045, 6070.939290607037, 6121.209103132385, 6125.693326622233, 6133.212252453684, 6137.193127861533, 6163.630182189083, 6174.135621013152, 6205.937940620536, 6214.098955651496, 6247.937520624736, 6032.0, 6061.36355682647, 6093.664848433379, 6109.930762116523, 6165.352637972948, 5215.322008138917, 6132.539076726721, 6140.293866205444, 6147.231596050494, 6195.070739389128, 5144.6913185209005, 6183.3816618337805, 6186.1648677427975, 6189.94770888959, 6197.194364732736], "value": 5765.997320064226, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [445.57002492594637, 518.3339408859615, 533.2214966149421, 534.4601952028453, 557.754587981288, 38317.616823831755, 40828.38757418641, 40891.0, 40929.386059209064, 41084.972875678126, 41858.7907060465, 42268.0, 42402.7879860601, 42515.78742106285, 42649.57350426495, 9565.091316324955, 9672.226221902267, 9709.17472014876, 9728.464934428604, 9756.804863902753, 9625.326227164102, 9698.321117521777, 9700.805983880353, 9723.90276097237, 9729.513524323755, 9698.612055517806, 9739.415635061883, 9744.074312940294, 9745.90254097457, 9748.02519748023, 9666.758331041745, 9696.563654635558, 9726.513674316257, 9728.513574321256, 9729.075737804897, 9502.762430939212, 9539.18916892065, 9578.473183974878, 9599.904000960041, 9615.567299471471, 40168.0, 40168.59831401707, 40263.194736105404, 40423.0, 40447.59552404497, 3334.694975540273, 3450.1547120955274, 3643.508126402928, 4016.859409920668, 4082.0, 31372.372552548823, 32064.0, 32280.838595807105, 32371.514427283608, 32546.511802322984, 3207.9198020049453, 3259.5110733389943, 3278.1968417737808, 3293.1602441377295, 3395.47370157626, 496153.0, 503605.0, 505366.4731676355, 506258.0, 511872.0, 33748.831255843565, 34396.48405273923, 34481.655183447845, 61301.77396452046, 61762.0735688965, 33384.49923251153, 33629.15927101793, 33636.49545256823, 33761.49357759635, 33923.0, 14754.631134221516, 14809.555713328617, 14830.0, 14896.329665165094, 14902.403903843942, 12657.620271391874, 12728.490860365482, 12738.617841464771, 12745.426455809511, 12749.745005099847, 7327.157376901618, 7329.780106596811, 7349.044624198927, 7383.556986580822, 7400.666969986363, 2976.791624586258, 3014.0355086372588, 3015.080400477875, 3051.298201413643, 3053.7556995440314, 917.9724608261763, 927.3091546797679, 1001.6293971230546, 1007.5768177365524, 1081.7457897394106, 27536.862315688293, 30613.387732245676, 30683.846580766953, 31158.376832463226, 31302.686973130432, 1057.5928267617023, 1064.4305296666246, 1090.0, 1125.3754166437707, 1161.3264306702092, 12556.937215313865, 15787.763183552255, 16087.919560402122, 16101.677966440606, 16521.173941302888, 666545.3400062947, 684315.0, 684460.0, 687052.0, 691092.0, 58986.41013589895, 59579.0, 59649.0, 59676.70161649164, 59837.700811496536, 1344.683999260173, 1356.687961768779, 1370.205280937054, 1377.8208832851865, 1379.717157982611, 30163.547546787253, 30219.848900756235, 30482.542761858145, 30675.539866901567, 30974.535381969738, 53147.73426132845, 53173.0, 53662.65843353947, 53892.46107538875, 53982.65043373947, 489327.0, 490203.0, 490345.0, 492093.0, 493659.0, 27046.91812327485, 27597.448051039268, 27723.44553108967, 27744.8902043916, 27990.0, 48865.0, 49167.0, 49685.00629987452, 50244.497555025446, 50355.0, 40244.0, 40318.59681403266, 40397.59602404056, 41046.79476602598, 41639.58360416357, 40148.0, 40442.59557404506, 40922.59077409188, 41118.97202569959, 41379.58620413757, 35032.649673502936, 38635.6136438632, 38872.0, 39311.803440982614, 41076.58923410847, 34173.82913085419, 34373.48439773455, 35223.29553408969, 35912.281754365285, 36000.81999589985, 24278.02887884465, 25875.22374328773, 26048.73951260539, 26123.477530448905, 26167.214983550522], "value": 18761.357091783444, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.1057798307005, 383.3617027648964, 385.3622255167697, 395.70519962627844, 406.79050289101116, 19073.904630476845, 20965.266215682455, 21160.470988225265, 21346.89326553369, 21389.786102138994, 17615.911920440412, 19757.308494202705, 20178.0, 20591.794082059176, 20609.690854637156, 5246.37043554772, 5319.388270348902, 5427.158790387496, 5949.49429298509, 6772.627505487197, 5777.653340799545, 6698.665066746667, 6720.193576770795, 6727.0, 6771.4921380896385, 5888.852778680524, 6426.742930282783, 6622.1722284714415, 6787.355201255873, 6789.898151527731, 5749.3388260350175, 6673.165854268216, 6742.123523941881, 6746.19045714515, 6761.4590832733265, 4572.9314060289125, 5101.081805275049, 5774.393688662714, 5912.14273930282, 6062.211912451397, 26199.607005894926, 26290.948362065494, 26723.46553068928, 26957.730422695913, 26969.190924272305, 2204.8015678589004, 2239.8320125990617, 2272.8181745460324, 2317.7102862142233, 2399.3161948844495, 12533.749325013448, 15856.920715396349, 15939.362425502966, 15952.680946381008, 16150.434734784227, 1825.634873025387, 1884.7172924061363, 1946.0756140833132, 1984.1071517816895, 2073.7304150460345, 123012.0, 128431.0, 129042.3547882264, 129560.35219823934, 130660.34669826685, 11499.712507187387, 11723.648290551297, 11860.644180674593, 11929.0, 11933.52265909354, 14610.0, 14672.486462973744, 14680.752136068553, 14685.192314422595, 14712.264386780618, 8373.956993749556, 8471.06818249992, 8620.0, 8625.0943650917, 8733.694320698745, 8559.486430814171, 8604.48373097616, 8618.913810861812, 8703.347248956354, 8801.647934082694, 4570.314452832035, 4618.907621847544, 4621.722696638212, 4625.514320996314, 4652.674312798139, 1949.8830070195834, 1956.4521933858543, 1977.4463150317933, 2018.8788672679686, 2019.8788072715683, 763.7441457111819, 807.4307613132727, 999.8900120986685, 1050.3487837540774, 1066.7653116314398, 20847.37457876266, 21546.892265538572, 21593.02831372592, 21617.351479455643, 21767.0, 844.8014716541608, 918.9678361257323, 943.9764005899907, 969.1762002297991, 1000.364768372089, 10895.237333386745, 11212.831807522894, 11619.477123529461, 11622.70943226426, 11623.418829058513, 511097.0, 511600.0, 512524.0, 529783.0, 583108.0, 129367.0, 132175.0, 132186.33906830405, 132259.0, 134217.0, 974.8635191073397, 981.4160574458186, 999.3853779925294, 1000.9149222316039, 1002.4586723169407, 14076.718465630835, 19345.51636209106, 19384.0, 19616.803831961497, 19737.605247895248, 54902.45097548974, 56039.439605603424, 56783.0, 57496.71251643882, 57922.26233213011, 80740.34817562714, 81847.5907620458, 82076.76884846852, 82466.58766706128, 82941.75587366073, 6962.477814163856, 8402.453840500348, 8422.24199822019, 8487.151284871463, 8647.654093836176, 11987.0, 12810.935945320214, 13269.933650331686, 13578.45686172542, 13779.724405512034, 10984.340939543654, 11384.601538946097, 11509.13681473876, 11558.711032224262, 11589.130815188726, 8926.73219803407, 9502.904970950201, 10639.574417023232, 11073.833892491459, 11492.367919764492, 10822.242443029227, 11492.94253528727, 11675.3578553177, 11876.524939002344, 11994.220375675553, 7689.461737678309, 7980.800479988046, 8303.543305118277, 8383.245507904317, 8407.45351552147, 10505.842412363974, 11411.37237451915, 11411.372374519482, 11486.885131148916, 11494.597689080842], "value": 9883.462921934593, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1496.0799108548247, 1529.2200977501475, 1732.4715961631698, 1844.7232915062748, 1884.575970406658, 8000.799980000503, 8597.914020859796, 8608.26829719473, 9065.048169942153, 9083.500407477586, 8673.869891951626, 8731.519766412845, 8837.602307896144, 8892.599833007514, 8894.55527223638, 2354.117206047732, 2356.6582845487396, 2390.6175011998084, 2393.7127544694663, 2402.0752010475917, 1896.6206758648257, 2363.3028256664325, 2370.2178281167257, 2373.893174807129, 2417.516496700658, 2383.916562920298, 2386.4153282445827, 2397.7482364351754, 2406.1578447543307, 2416.0818888822223, 2369.3839601703507, 2383.678203442539, 2393.317904397256, 2395.6286775549816, 2395.9041638334447, 2341.7775311345395, 2381.0, 2381.5951288281035, 2382.952340953171, 2383.511380167062, 8059.314958228558, 8696.43464176448, 8968.327375446868, 9005.459672419676, 9032.683856065072, 5281.815136470205, 5378.946210537883, 5452.672839629635, 5453.15476101205, 5465.180222966547, 3507.771994820354, 3602.783832970004, 3638.854445822164, 3665.5601327840563, 3693.9261214775825, 1063.7819247054335, 1068.743501559628, 1070.8929107089307, 1071.7749272652752, 1087.8694556653172, 11715.765684686345, 11752.236104653084, 11827.704307392298, 11850.407479625985, 11873.821892671616, 4615.79228934692, 4652.55800698933, 4813.687110337817, 4848.12733707936, 4848.781804818792, 9995.550200241007, 10091.344062636052, 10097.192224622013, 10139.442330671884, 10144.797104057878, 5405.540529055074, 5407.675539467645, 5414.673405015797, 5428.6199966002005, 5440.6735595864375, 4384.517703052662, 4777.020710754287, 4947.950520494821, 4965.4289756678445, 4982.402111746614, 3456.7407444441264, 3919.412088186795, 3946.7039972002217, 4010.2380547695843, 4039.979800100981, 1274.095392271479, 1278.757036163126, 1284.01131129032, 1291.5092264939262, 1294.605145430634, 931.6351544986627, 933.1834644685899, 935.700575815746, 940.9388389754644, 1001.9599216031423, 6015.879682406415, 6021.488173505213, 6096.207493025879, 6119.204503414617, 6178.351273116258, 429.5189387885526, 436.3759823452493, 447.46974834820844, 456.46137557682255, 459.4073644997982, 3690.4279836625365, 3967.583403742623, 4181.832726690898, 4182.53992060873, 4249.107687385622, 8966.596503157374, 10298.536565854554, 10612.840807387895, 10641.414722190199, 10655.30740501865, 9389.62441502346, 10111.191104711605, 10171.54228059739, 10453.895461045444, 10623.628173014062, 7922.801929951797, 8086.474379165335, 8097.433179677484, 8110.188981101843, 8205.466644668077, 4910.484399138039, 5846.093855452412, 5906.291245050621, 5958.7616495341435, 6053.152558641706, 9542.379745316532, 9848.901510984799, 9857.0, 9908.653197138066, 9946.701598952042, 8298.502089874626, 10064.295499314978, 10068.244881634053, 10110.443925584164, 10117.696469105938, 6925.238223795278, 7373.33639972405, 7457.142428620778, 7984.20157984197, 8341.290990265896, 8353.289970352345, 8404.621792019252, 8476.449030812977, 8544.957275213832, 8556.0, 7478.364339031025, 7628.580428076509, 7631.3894888407685, 7652.923470765221, 7663.540187588763, 7437.925620743939, 7479.850402991801, 7484.588347640932, 7517.661705223169, 7587.772366829004, 7038.753643622448, 7067.5052746307265, 7131.8930216045765, 7144.071270734771, 7176.533525320838, 5006.3992320921725, 5075.2387141929, 5169.405518365436, 5183.740812959412, 5190.870228244324, 5169.198774190007, 5179.533841954091, 5199.558037566698, 5204.297419848272, 5325.201219817059], "value": 4536.0649684714745, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.4319096054221, 314.63659473308337, 316.30886512969164, 317.2259686365267, 323.0905002418194, 5784.537237021039, 5863.853403664905, 5877.235959325281, 5879.617824841383, 5883.469918049168, 5726.370099289085, 5741.799037033705, 5776.624519406236, 5797.840177103478, 5817.331006934204, 4096.97951510242, 4140.50313962325, 4244.405783190356, 4289.806958686867, 4325.740455572675, 4279.315309550472, 4289.442372491571, 4293.506246781613, 4332.480102387718, 4347.5653633965085, 3552.4848896909934, 3811.980940095296, 4270.871873843774, 4327.242732521812, 4337.725017611699, 3804.8668296609762, 4086.9591304087025, 4318.136372725452, 4332.306830907071, 4345.826166953318, 3733.253349330145, 3749.906252343686, 3763.9811800941093, 3772.3398405278967, 3773.6037716039696, 5618.6628802272, 5638.802641907513, 5674.928651042381, 5689.667236433084, 5716.1711551825165, 4574.931376029363, 4961.858772482349, 5099.898002039976, 5109.4379618241965, 5114.744262786846, 5986.73059712314, 6064.696765161769, 6103.664298463583, 6111.266648002268, 6126.264848218242, 4040.5757395473347, 4192.8532501362615, 4194.937075943863, 4214.683898707609, 4223.97888010561, 5571.777128914839, 5632.633878797865, 5951.256092988377, 6363.631819158857, 6369.751426772333, 3160.9051728448185, 3176.4123637127204, 3581.552305961755, 3732.712214286067, 4070.8575199867864, 3623.2572322673786, 3687.2994131115, 3695.6304369562827, 3922.235164143022, 4160.979195104005, 4773.188557945157, 4899.706017638881, 4901.460839307673, 4904.901901962012, 4908.607311415079, 5518.448155184449, 5648.548116150698, 6042.637441753509, 6048.123022161762, 6087.786927457606, 1312.4225340849996, 1434.6413396650837, 1446.0528353928194, 1477.8226612806532, 1481.2815784344584, 699.6152116335995, 824.9358327757126, 911.6717981526646, 958.990410095904, 995.7112437393075, 4542.11428771393, 5031.33965791288, 5151.2530683050745, 5587.262241501716, 5668.773249070074, 5329.413764485904, 5439.619226654176, 6055.0, 6156.844130212089, 6240.53196010292, 5362.812301569426, 5640.7743690252755, 5660.5471562274915, 5663.0, 5705.0, 2887.148291254085, 3249.382617302705, 3254.1864533866533, 3272.5582046423906, 3333.583302087239, 5963.642181469126, 6422.550421470452, 6433.300852010113, 6444.583281269524, 6475.7733479328, 5800.506956908625, 6356.4279214870885, 6377.426031657081, 6381.851266771932, 6387.137736405619, 6395.840103997437, 6466.6766661666725, 6467.773627923094, 6473.320301368382, 6487.605164889572, 4840.709557426566, 4931.988942266755, 5523.82950872089, 5771.971140144273, 6193.907091393723, 5600.35595906476, 5889.469947704727, 6270.561060725706, 6303.212098487689, 6347.809565713036, 5067.898642027212, 5798.159266906359, 6569.671516424255, 6595.241547221939, 6597.80206593803, 3353.631100578983, 3572.214112895159, 4000.079981604189, 4054.817533211072, 4111.712180147361, 5536.7785288588, 6013.0, 6314.778982735766, 6343.714532845941, 6416.582922110048, 4880.511948805091, 5091.389033315878, 5682.943170568242, 6136.32500424944, 6156.938430615636, 6024.3674414187635, 6075.301340345917, 6124.54065945047, 6141.692915354304, 6160.907586386297, 5384.730763461889, 5557.0, 5932.466078052996, 5963.7912673056235, 5968.432998865101, 5301.814436494704, 5784.421557844182, 6083.330833608388, 6128.754849806136, 6147.032949456131, 4996.750162491933, 5292.729744861283, 5372.328458942632, 6033.215681961317, 6096.969515152396], "value": 4366.1818286355165, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.2978723404259, 671.4930227678102, 707.7628994286916, 822.5311572403731, 844.1389782421932, 15483.909672194264, 15956.202189890488, 16143.757843632326, 16158.434454794084, 16206.027638341693, 14531.709365812678, 14583.781243281359, 14689.118652880808, 14695.191764452951, 15502.837287203476, 874.1564390363283, 904.0868722590199, 906.9365144439892, 907.9954600226989, 916.4180745226796, 687.7283473028151, 860.8378688770173, 894.5795476126243, 901.4185850126664, 901.7610333261691, 937.8452555328363, 938.3478482454697, 939.1876027236425, 940.7742141885932, 944.0135058863505, 884.3058199313572, 902.3052249767675, 934.5560858592148, 935.8830146231721, 936.0686117313282, 694.4236283884364, 695.9547629404124, 699.9370056694871, 700.3381804195038, 701.551007355293, 14732.484363047242, 14856.554303370916, 14909.850901490954, 14921.253937303092, 14972.850271497255, 13998.510052148125, 14067.01530892838, 14082.577522674335, 14209.0, 14491.71016579673, 11930.642080737593, 12043.698907527294, 12044.81932771009, 12380.195287306296, 12431.12982091254, 3901.1807520420743, 3977.6817854571564, 4058.391241313797, 4059.5940405959464, 4061.776602286873, 57110.0, 82785.58607206926, 82984.71552600455, 98411.0158898409, 105168.0, 11179.770509983437, 11487.0, 11573.074154067823, 11573.942130289295, 11756.470958806874, 22334.106635734715, 22369.217077402212, 22395.440113996956, 22428.439289017904, 22467.43831404228, 7360.96319518399, 7822.882656760153, 7859.742441209493, 8298.502089874626, 8581.914180858112, 4597.7011494252765, 4622.375979242759, 4671.929921051187, 4674.0885527322525, 4675.08835777027, 3560.287942411528, 3622.0582648511577, 3626.1841085756023, 3667.614900435416, 3678.3562876496508, 555.3391464157639, 560.4143669865001, 573.8909607174623, 636.48762745989, 638.4732595608549, 584.8187062010791, 590.8109404990362, 598.7784519727728, 636.9426751592379, 641.2561428742636, 9112.49881256524, 9400.24798016157, 9418.62325506972, 9554.570044348022, 9589.041095890494, 544.5099410530554, 637.1939496536885, 637.9298277189505, 637.9457746091634, 661.7452280871898, 4550.49944506104, 4643.117807616542, 4709.222978208626, 4731.33761273422, 4731.809434778553, 120934.39532802456, 142140.0, 146804.26597866943, 148668.0, 151482.0, 89219.55390223138, 108797.45601271943, 111666.44166779114, 132375.0, 133555.3255636048, 74176.25823741802, 82356.0, 82787.75818362729, 82984.0, 83206.0, 9817.656382026595, 10203.234757393077, 10234.02776736209, 10276.332038417479, 10277.486125693835, 61199.69400152971, 62518.0, 62600.68699656473, 64263.71472570429, 64573.0, 69851.30148698651, 78859.4228115423, 84673.0, 84832.0, 93575.0, 11739.178257522233, 13529.2558909257, 13586.0, 13614.523491677744, 14160.221187834768, 15091.24543772785, 15107.0, 15481.380744770084, 15500.68998620044, 15952.282147303167, 14001.0, 14063.367148478139, 14132.364043618267, 14253.50112746049, 14367.856321436651, 12771.61685149447, 12791.36043197855, 13218.0, 13376.996725245841, 13390.263535505252, 13868.93065534666, 13926.860731392557, 14256.928715356356, 14316.713665726418, 14435.494757683431, 11272.830907536558, 11454.541818327174, 11466.484008219484, 11690.649280521598, 11790.351530665552, 10827.187960903122, 11391.715207119889, 11438.08495320389, 11781.352025638673, 11801.29192248468], "value": 7787.465125763178, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1571.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 105.0, 115.0, 114.0, 115.0, 115.0, 115.0, 117.0, 135.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 108.0, 131.0, 155.0, 110.0, 134.0, 161.0, 140.0, 155.0, 110.0], "value": 135.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 214.0, 202.0, 237.0, 270.0, 209.0, 198.0, 199.0, 197.0, 197.0], "value": 212.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 83.0, 72.0, 110.0, 57.0, 58.0, 58.0, 63.0, 131.0, 51.0], "value": 75.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 118.0, 72.0, 73.0, 82.0, 117.0, 71.0, 78.0, 126.0], "value": 87.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 91.0, 93.0, 128.0, 133.0, 108.0, 89.0, 86.0, 89.0, 87.0], "value": 100.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 96.0, 96.0, 101.0, 91.0, 96.0, 133.0, 149.0, 95.0], "value": 104.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 134.0, 91.0, 112.0, 139.0, 128.0, 88.0, 90.0, 91.0, 91.0], "value": 110.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 58.0, 57.0, 57.0, 59.0, 59.0, 56.0, 65.0, 69.0, 85.0], "value": 62.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 72.0, 70.0, 76.0, 78.0, 82.0, 72.0, 70.0, 70.0, 100.0], "value": 75.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 100.0, 81.0, 87.0, 86.0, 80.0, 84.0, 83.0, 88.0, 103.0], "value": 88.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 136.0, 142.0, 144.0, 149.0, 134.0, 139.0, 137.0, 184.0, 148.0], "value": 144.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 218.0, 188.0, 162.0, 167.0, 166.0, 171.0, 161.0, 200.0, 222.0], "value": 182.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 83.0, 86.0, 84.0, 87.0, 84.0, 74.0, 82.0, 67.0, 59.0], "value": 76.9, "unit": "score"}]}]} 05:50:47 INFO - Return code: 0 05:50:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:50:47 ERROR - # TBPL SUCCESS # 05:50:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:50:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:50:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:50:47 INFO - 2016-07-02 05:50:47.171 screenresolution[1962:21782] starting screenresolution argv=screenresolution get 05:50:47 INFO - 2016-07-02 05:50:47.174 screenresolution[1962:21782] Display 0: 1600x1200x32@60 05:50:47 INFO - 2016-07-02 05:50:47.181 screenresolution[1963:21785] starting screenresolution argv=screenresolution list 05:50:47 INFO - Available Modes on Display 0 05:50:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:50:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:50:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:50:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:50:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:50:47 INFO - Intel Iris: 05:50:47 INFO - Chipset Model: Intel Iris 05:50:47 INFO - Type: GPU 05:50:47 INFO - Bus: Built-In 05:50:47 INFO - VRAM (Dynamic, Max): 1536 MB 05:50:47 INFO - Vendor: Intel (0x8086) 05:50:47 INFO - Device ID: 0x0a2e 05:50:47 INFO - Revision ID: 0x0009 05:50:47 INFO - Displays: 05:50:47 INFO - Display: 05:50:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:50:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:50:47 INFO - Display Serial Number: 1600x1200 60 05:50:47 INFO - Main Display: Yes 05:50:47 INFO - Mirror: Off 05:50:47 INFO - Online: Yes 05:50:47 INFO - Rotation: Supported 05:50:47 INFO - Return code: 0 05:50:47 INFO - Running post-action listener: _resource_record_post_action 05:50:47 INFO - ##### 05:50:47 INFO - ##### Finished run-tests step (success) 05:50:47 INFO - ##### 05:50:47 INFO - Running post-run listener: _resource_record_post_run 05:50:47 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 942080; Write bytes: 708645888; Read time: 10; Write time: 1907 05:50:47 INFO - TinderboxPrint: CPU usage
54.3% 05:50:47 INFO - TinderboxPrint: I/O read bytes / time
942,080 / 10 05:50:47 INFO - TinderboxPrint: I/O write bytes / time
708,645,888 / 1,907 05:50:47 INFO - TinderboxPrint: CPU idle
2,209.4 (45.7%) 05:50:47 INFO - TinderboxPrint: CPU system
977.9 (20.2%) 05:50:47 INFO - TinderboxPrint: CPU user
1,650.4 (34.1%) 05:50:47 INFO - TinderboxPrint: Swap in / out
407,359,488 / 0 05:50:47 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 394109440; Write bytes: 388720128; Read time: 29279; Write time: 710 05:50:47 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 942080; Write bytes: 297975296; Read time: 10; Write time: 1145 05:50:47 INFO - Running post-run listener: _upload_blobber_files 05:50:47 INFO - Blob upload gear active. 05:50:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:50:47 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 05:50:47 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'] 05:50:47 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 05:50:48 INFO - (blobuploader) - INFO - Open directory for files ... 05:50:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:50:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:50:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:49 INFO - (blobuploader) - INFO - Done attempting. 05:50:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:50:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:50:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:50 INFO - (blobuploader) - INFO - Done attempting. 05:50:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:50:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:50:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:52 INFO - (blobuploader) - INFO - Done attempting. 05:50:52 INFO - (blobuploader) - INFO - Iteration through files over. 05:50:52 INFO - Return code: 0 05:50:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:50:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:50:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb6262a28b62ac15feae85c243e96c505fd37ef3a72f81417ac97d8ff2b2d574aeaf68ddef478aff5e652f9c25afc23bd9c06c0375ef656fcb1706645a8fd5c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d90abce41d74f5d05d3d832dd4f0e28bde376f5f12497495ea6a91983c79858a340d391c26926e7e6f7dad59138412ad014ccd27c6ccbef40fab2fc6a3b996c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de16959645caa0687503360513d9891c99b31a2adf8245add1a138183f7521629e82017a2e50ac84c76167464b28d044220c9a64972a9a075d43bb86178e2f5f"} 05:50:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:50:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:50:52 INFO - Contents: 05:50:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb6262a28b62ac15feae85c243e96c505fd37ef3a72f81417ac97d8ff2b2d574aeaf68ddef478aff5e652f9c25afc23bd9c06c0375ef656fcb1706645a8fd5c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d90abce41d74f5d05d3d832dd4f0e28bde376f5f12497495ea6a91983c79858a340d391c26926e7e6f7dad59138412ad014ccd27c6ccbef40fab2fc6a3b996c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de16959645caa0687503360513d9891c99b31a2adf8245add1a138183f7521629e82017a2e50ac84c76167464b28d044220c9a64972a9a075d43bb86178e2f5f"} 05:50:52 INFO - Running post-run listener: copy_logs_to_upload_dir 05:50:52 INFO - Copying logs to upload dir... 05:50:52 INFO - mkdir: /builds/slave/test/build/upload/logs 05:50:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.913370 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-07-02 05:50:52.912332) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-02 05:50:52.916142) ========= 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.P7ZZe8Qt5f/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.Tp3xa6us9V/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb6262a28b62ac15feae85c243e96c505fd37ef3a72f81417ac97d8ff2b2d574aeaf68ddef478aff5e652f9c25afc23bd9c06c0375ef656fcb1706645a8fd5c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d90abce41d74f5d05d3d832dd4f0e28bde376f5f12497495ea6a91983c79858a340d391c26926e7e6f7dad59138412ad014ccd27c6ccbef40fab2fc6a3b996c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de16959645caa0687503360513d9891c99b31a2adf8245add1a138183f7521629e82017a2e50ac84c76167464b28d044220c9a64972a9a075d43bb86178e2f5f"} build_url:https://queue.taskcluster.net/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011151 build_url: 'https://queue.taskcluster.net/v1/task/LXBSOjAuT9GDcyHm1KxAdw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb6262a28b62ac15feae85c243e96c505fd37ef3a72f81417ac97d8ff2b2d574aeaf68ddef478aff5e652f9c25afc23bd9c06c0375ef656fcb1706645a8fd5c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d90abce41d74f5d05d3d832dd4f0e28bde376f5f12497495ea6a91983c79858a340d391c26926e7e6f7dad59138412ad014ccd27c6ccbef40fab2fc6a3b996c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de16959645caa0687503360513d9891c99b31a2adf8245add1a138183f7521629e82017a2e50ac84c76167464b28d044220c9a64972a9a075d43bb86178e2f5f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-02 05:50:52.948988) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 05:50:52.949317) ========= 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.P7ZZe8Qt5f/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.Tp3xa6us9V/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.004897 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 05:50:52.997661) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-02 05:50:52.997991) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-02 05:50:52.998346) ========= ========= Total master_lag: 0.20 =========