builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0292 starttime: 1467459136.67 results: success (0) buildid: 20160702022223 builduid: a2977a659de84849a22cd9c894b302e3 revision: 39dffbba764210b25bfc1e749b4f16db77fa0d46 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-02 04:32:16.673838) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-02 04:32:16.674490) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-02 04:32:16.674831) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-02 04:32:16.708386) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 04:32:16.708845) ========= 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.xLdOETKv81/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.HjXel97mCA/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 04:32:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.7M=0.001s 2016-07-02 04:32:16 (18.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.107988 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 04:32:16.840338) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 04:32:16.840821) ========= 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.xLdOETKv81/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.HjXel97mCA/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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.026952 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 04:32:16.897494) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-02 04:32:16.897886) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --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 integration/fx-team --rev 39dffbba764210b25bfc1e749b4f16db77fa0d46 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xLdOETKv81/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.HjXel97mCA/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 04:32:16,979 truncating revision to first 12 chars 2016-07-02 04:32:16,979 Setting DEBUG logging. 2016-07-02 04:32:16,979 attempt 1/10 2016-07-02 04:32:16,979 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/39dffbba7642?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-02 04:32:18,210 unpacking tar archive at: fx-team-39dffbba7642/testing/mozharness/ program finished with exit code 0 elapsedTime=1.521638 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-02 04:32:18.444994) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-02 04:32:18.445727) ========= script_repo_revision: 39dffbba764210b25bfc1e749b4f16db77fa0d46 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-02 04:32:18.446622) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-02 04:32:18.447305) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-02 04:32:18.470450) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-07-02 04:32:18.471158) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xLdOETKv81/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.HjXel97mCA/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 04:32:18 INFO - MultiFileLogger online at 20160702 04:32:18 in /builds/slave/test 04:32:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 04:32:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:32:18 INFO - {'append_to_log': False, 04:32:18 INFO - 'base_work_dir': '/builds/slave/test', 04:32:18 INFO - 'blob_upload_branch': 'Fx-Team', 04:32:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:32:18 INFO - 'branch': 'Fx-Team', 04:32:18 INFO - 'buildbot_json_path': 'buildprops.json', 04:32:18 INFO - 'config_files': ('talos/mac_config.py',), 04:32:18 INFO - 'default_actions': ('clobber', 04:32:18 INFO - 'read-buildbot-config', 04:32:18 INFO - 'download-and-extract', 04:32:18 INFO - 'populate-webroot', 04:32:18 INFO - 'create-virtualenv', 04:32:18 INFO - 'install', 04:32:18 INFO - 'run-tests'), 04:32:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:32:18 INFO - 'download_minidump_stackwalk': True, 04:32:18 INFO - 'download_symbols': 'ondemand', 04:32:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:32:18 INFO - 'tooltool.py': '/tools/tooltool.py', 04:32:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:32:18 INFO - '/tools/misc-python/virtualenv.py')}, 04:32:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:32:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:32:18 INFO - 'installer_path': 'installer.exe', 04:32:18 INFO - 'log_level': 'info', 04:32:18 INFO - 'log_name': 'talos', 04:32:18 INFO - 'log_to_console': True, 04:32:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:32:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:32:18 INFO - 'opt_config_files': (), 04:32:18 INFO - 'pip_index': False, 04:32:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:32:18 INFO - 'cmd': ('bash', 04:32:18 INFO - '-c', 04:32:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:32:18 INFO - 'enabled': True, 04:32:18 INFO - 'halt_on_failure': False, 04:32:18 INFO - 'name': 'check_screen_resolution'},), 04:32:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:32:18 INFO - 'cmd': ('bash', 04:32:18 INFO - '-c', 04:32:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:32:18 INFO - 'enabled': True, 04:32:18 INFO - 'halt_on_failure': False, 04:32:18 INFO - 'name': 'check_screen_resolution'},), 04:32:18 INFO - 'run_cmd_checks_enabled': True, 04:32:18 INFO - 'sps_profile': False, 04:32:18 INFO - 'sps_profile_interval': 0, 04:32:18 INFO - 'suite': 'dromaeojs', 04:32:18 INFO - 'system_bits': '32', 04:32:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:32:18 INFO - 'title': 't-yosemite-r7-0292', 04:32:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:32:18 INFO - 'use_talos_json': True, 04:32:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:32:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:32:18 INFO - 'work_dir': 'build'} 04:32:18 INFO - ##### 04:32:18 INFO - ##### Running clobber step. 04:32:18 INFO - ##### 04:32:18 INFO - Running pre-action listener: _resource_record_pre_action 04:32:18 INFO - Running main action method: clobber 04:32:18 INFO - rmtree: /builds/slave/test/build 04:32:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:32:20 INFO - Running post-action listener: _resource_record_post_action 04:32:20 INFO - ##### 04:32:20 INFO - ##### Finished clobber step (success) 04:32:20 INFO - ##### 04:32:20 INFO - ##### 04:32:20 INFO - ##### Running read-buildbot-config step. 04:32:20 INFO - ##### 04:32:20 INFO - Running pre-action listener: _resource_record_pre_action 04:32:20 INFO - Running main action method: read_buildbot_config 04:32:20 INFO - Using buildbot properties: 04:32:20 INFO - { 04:32:20 INFO - "project": "", 04:32:20 INFO - "product": "firefox", 04:32:20 INFO - "script_repo_revision": "production", 04:32:20 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:32:20 INFO - "repository": "", 04:32:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:32:20 INFO - "buildid": "20160702022223", 04:32:20 INFO - "pgo_build": "False", 04:32:20 INFO - "basedir": "/builds/slave/test", 04:32:20 INFO - "buildnumber": 69, 04:32:20 INFO - "slavename": "t-yosemite-r7-0292", 04:32:20 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 04:32:20 INFO - "platform": "yosemite_r7", 04:32:20 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:32:20 INFO - "branch": "fx-team", 04:32:20 INFO - "repo_path": "integration/fx-team", 04:32:20 INFO - "revision": "39dffbba764210b25bfc1e749b4f16db77fa0d46", 04:32:20 INFO - "stage_platform": "macosx64", 04:32:20 INFO - "builduid": "a2977a659de84849a22cd9c894b302e3", 04:32:20 INFO - "slavebuilddir": "test" 04:32:20 INFO - } 04:32:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 04:32:20 INFO - Running post-action listener: _resource_record_post_action 04:32:20 INFO - ##### 04:32:20 INFO - ##### Finished read-buildbot-config step (success) 04:32:20 INFO - ##### 04:32:20 INFO - ##### 04:32:20 INFO - ##### Running download-and-extract step. 04:32:20 INFO - ##### 04:32:20 INFO - Running pre-action listener: _resource_record_pre_action 04:32:20 INFO - Running main action method: download_and_extract 04:32:20 INFO - mkdir: /builds/slave/test/build/tests 04:32:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:20 INFO - https://queue.taskcluster.net/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:32:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 04:32:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 04:32:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BmHuL9IsQKy25VXgknLigQ/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 04:32:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BmHuL9IsQKy25VXgknLigQ/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 04:32:22 INFO - Downloaded 1199 bytes. 04:32:22 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 04:32:22 INFO - Using the following test package requirements: 04:32:22 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 04:32:22 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:32:22 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 04:32:22 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:32:22 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 04:32:22 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:32:22 INFO - u'jsshell-mac.zip'], 04:32:22 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:32:22 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 04:32:22 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 04:32:22 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:32:22 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 04:32:22 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:32:22 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 04:32:22 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:32:22 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 04:32:22 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:32:22 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 04:32:22 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:32:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:22 INFO - https://queue.taskcluster.net/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:32:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:32:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:32:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BmHuL9IsQKy25VXgknLigQ/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 04:32:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BmHuL9IsQKy25VXgknLigQ/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 04:32:24 INFO - Downloaded 18791388 bytes. 04:32:24 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'] 04:32:24 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 04:32:27 INFO - Return code: 0 04:32:27 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 04:32:27 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')]} 04:32:27 INFO - https://queue.taskcluster.net/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:32:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:32:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:32:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BmHuL9IsQKy25VXgknLigQ/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 04:32:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BmHuL9IsQKy25VXgknLigQ/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 04:32:33 INFO - Downloaded 18791388 bytes. 04:32:33 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'] 04:32:33 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 04:32:36 INFO - Return code: 0 04:32: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')]} 04:32:36 INFO - https://queue.taskcluster.net/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:32:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:32:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:32:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BmHuL9IsQKy25VXgknLigQ/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 04:32:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BmHuL9IsQKy25VXgknLigQ/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 04:32:37 INFO - Downloaded 14405327 bytes. 04:32:37 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'] 04:32:37 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 04:32:38 INFO - Return code: 0 04:32:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:38 INFO - https://queue.taskcluster.net/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:32:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:32:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:32:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 04:32:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BmHuL9IsQKy25VXgknLigQ/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 04:32:41 INFO - Downloaded 112449303 bytes. 04:32:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:32:41 INFO - mkdir: /builds/slave/test/properties 04:32:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:32:41 INFO - Writing to file /builds/slave/test/properties/build_url 04:32:41 INFO - Contents: 04:32:41 INFO - build_url:https://queue.taskcluster.net/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:32:42 INFO - Running post-action listener: _resource_record_post_action 04:32:42 INFO - Running post-action listener: set_extra_try_arguments 04:32:42 INFO - ##### 04:32:42 INFO - ##### Finished download-and-extract step (success) 04:32:42 INFO - ##### 04:32:42 INFO - ##### 04:32:42 INFO - ##### Running populate-webroot step. 04:32:42 INFO - ##### 04:32:42 INFO - Running pre-action listener: _resource_record_pre_action 04:32:42 INFO - Running main action method: populate_webroot 04:32:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:32:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:32:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:32:42 INFO - '--tppagecycles', 04:32:42 INFO - '7'], 04:32:42 INFO - 'tests': ['tsvgm']}}, 04:32:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:32:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:32:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:32:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:32:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:32:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:32:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:42 INFO - 'talos_options': ['--disable-e10s'], 04:32:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:32:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:32:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:42 INFO - 'talos_options': ['--disable-e10s'], 04:32:42 INFO - 'tests': ['damp', 'tps']}, 04:32:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:42 INFO - 'tests': ['damp', 'tps']}, 04:32:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:32:42 INFO - 'tests': ['dromaeo_dom']}, 04:32:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:32:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:32:42 INFO - 'tests': ['basic_compositor_video']}, 04:32:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:32:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:32:42 INFO - 'tests': ['a11yr', 04:32:42 INFO - 'ts_paint', 04:32:42 INFO - 'tpaint', 04:32:42 INFO - 'sessionrestore', 04:32:42 INFO - 'sessionrestore_no_auto_restore', 04:32:42 INFO - 'tabpaint']}, 04:32:42 INFO - 'other-e10s': {'tests': ['a11yr', 04:32:42 INFO - 'ts_paint', 04:32:42 INFO - 'tpaint', 04:32:42 INFO - 'sessionrestore', 04:32:42 INFO - 'sessionrestore_no_auto_restore', 04:32:42 INFO - 'tabpaint']}, 04:32:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:32:42 INFO - 'ts_paint', 04:32:42 INFO - 'tpaint', 04:32:42 INFO - 'sessionrestore', 04:32:42 INFO - 'sessionrestore_no_auto_restore', 04:32:42 INFO - 'tabpaint']}, 04:32:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:32:42 INFO - 'ts_paint', 04:32:42 INFO - 'tpaint', 04:32:42 INFO - 'sessionrestore', 04:32:42 INFO - 'sessionrestore_no_auto_restore', 04:32:42 INFO - 'tabpaint']}, 04:32:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:32:42 INFO - 'tests': ['a11yr', 04:32:42 INFO - 'ts_paint', 04:32:42 INFO - 'tpaint', 04:32:42 INFO - 'sessionrestore', 04:32:42 INFO - 'sessionrestore_no_auto_restore', 04:32:42 INFO - 'tabpaint']}, 04:32:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:32:42 INFO - 'tests': ['a11yr', 04:32:42 INFO - 'ts_paint', 04:32:42 INFO - 'tpaint', 04:32:42 INFO - 'sessionrestore', 04:32:42 INFO - 'sessionrestore_no_auto_restore', 04:32:42 INFO - 'tabpaint']}, 04:32:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:32:42 INFO - 'tests': ['tsvgx', 04:32:42 INFO - 'tsvgr_opacity', 04:32:42 INFO - 'tart', 04:32:42 INFO - 'tscrollx', 04:32:42 INFO - 'cart']}, 04:32:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:32:42 INFO - 'tsvgr_opacity', 04:32:42 INFO - 'tart', 04:32:42 INFO - 'tscrollx', 04:32:42 INFO - 'cart']}, 04:32:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:42 INFO - 'talos_options': ['--disable-e10s'], 04:32:42 INFO - 'tests': ['tp5o']}, 04:32:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:42 INFO - 'tests': ['tp5o']}, 04:32:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:42 INFO - 'talos_options': ['--disable-e10s', 04:32:42 INFO - '--xperf_path', 04:32:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:32:42 INFO - 'tests': ['tp5n']}, 04:32:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:42 INFO - 'talos_options': ['--xperf_path', 04:32:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:32:42 INFO - 'tests': ['tp5n']}}, 04:32:42 INFO - 'talos.zip': {'path': '', 04:32:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:32:42 INFO - Running post-action listener: _resource_record_post_action 04:32:42 INFO - ##### 04:32:42 INFO - ##### Finished populate-webroot step (success) 04:32:42 INFO - ##### 04:32:42 INFO - ##### 04:32:42 INFO - ##### Running create-virtualenv step. 04:32:42 INFO - ##### 04:32:42 INFO - Running pre-action listener: _resource_record_pre_action 04:32:42 INFO - Running main action method: create_virtualenv 04:32:42 INFO - Creating virtualenv /builds/slave/test/build/venv 04:32:42 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 04:32:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:32:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:32:42 INFO - Using real prefix '/tools/python27' 04:32:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:32:43 INFO - Installing distribute.............................................................................................................................................................................................done. 04:32:46 INFO - Installing pip.................done. 04:32:46 INFO - Return code: 0 04:32:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:32:46 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')]} 04:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:46 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')]} 04:32:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:46 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 0x10c127a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdeeb593970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c08bd30>, '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 0x10bdf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c083b20>, '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.xLdOETKv81/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.HjXel97mCA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 04:32:46 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 04:32:46 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 04:32:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLdOETKv81/Render', 04:32:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:46 INFO - 'HOME': '/Users/cltbld', 04:32:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:46 INFO - 'LOGNAME': 'cltbld', 04:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:46 INFO - 'MOZ_NO_REMOTE': '1', 04:32:46 INFO - 'NO_EM_RESTART': '1', 04:32:46 INFO - 'PAGER': '/bin/cat', 04:32:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:46 INFO - 'PWD': '/builds/slave/test', 04:32:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:46 INFO - 'SHELL': '/bin/bash', 04:32:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HjXel97mCA/Listeners', 04:32:46 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 04:32:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:46 INFO - 'USER': 'cltbld', 04:32:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:46 INFO - 'XPC_FLAGS': '0x0', 04:32:46 INFO - 'XPC_SERVICE_NAME': '0', 04:32:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:46 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 04:32:46 INFO - Cleaning up... 04:32:46 INFO - Return code: 0 04:32:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:32:46 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')]} 04:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:46 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')]} 04:32:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:46 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 0x10c127a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdeeb593970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c08bd30>, '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 0x10bdf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c083b20>, '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.xLdOETKv81/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.HjXel97mCA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 04:32:46 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 04:32:46 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 04:32:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLdOETKv81/Render', 04:32:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:46 INFO - 'HOME': '/Users/cltbld', 04:32:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:46 INFO - 'LOGNAME': 'cltbld', 04:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:46 INFO - 'MOZ_NO_REMOTE': '1', 04:32:46 INFO - 'NO_EM_RESTART': '1', 04:32:46 INFO - 'PAGER': '/bin/cat', 04:32:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:46 INFO - 'PWD': '/builds/slave/test', 04:32:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:46 INFO - 'SHELL': '/bin/bash', 04:32:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HjXel97mCA/Listeners', 04:32:46 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 04:32:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:46 INFO - 'USER': 'cltbld', 04:32:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:46 INFO - 'XPC_FLAGS': '0x0', 04:32:46 INFO - 'XPC_SERVICE_NAME': '0', 04:32:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:46 INFO - Downloading/unpacking psutil>=3.1.1 04:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:32:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:32:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:32:49 INFO - Installing collected packages: psutil 04:32:49 INFO - Running setup.py install for psutil 04:32:49 INFO - building 'psutil._psutil_osx' extension 04:32:49 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 04:32:50 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 04:32:50 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 04:32:50 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 04:32:50 INFO - building 'psutil._psutil_posix' extension 04:32:50 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 04:32:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:32:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:32:50 INFO - ^ 04:32:50 INFO - 1 warning generated. 04:32:50 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 04:32:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:32:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:32:50 INFO - Successfully installed psutil 04:32:50 INFO - Cleaning up... 04:32:50 INFO - Return code: 0 04:32:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:32: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')]} 04:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32: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')]} 04:32:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:50 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 0x10c127a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdeeb593970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c08bd30>, '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 0x10bdf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c083b20>, '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.xLdOETKv81/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.HjXel97mCA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 04:32:50 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 04:32:50 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 04:32:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLdOETKv81/Render', 04:32:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:50 INFO - 'HOME': '/Users/cltbld', 04:32:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:50 INFO - 'LOGNAME': 'cltbld', 04:32:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:50 INFO - 'MOZ_NO_REMOTE': '1', 04:32:50 INFO - 'NO_EM_RESTART': '1', 04:32:50 INFO - 'PAGER': '/bin/cat', 04:32:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:50 INFO - 'PWD': '/builds/slave/test', 04:32:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:50 INFO - 'SHELL': '/bin/bash', 04:32:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HjXel97mCA/Listeners', 04:32:50 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 04:32:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:50 INFO - 'USER': 'cltbld', 04:32:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:50 INFO - 'XPC_FLAGS': '0x0', 04:32:50 INFO - 'XPC_SERVICE_NAME': '0', 04:32:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:32:51 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 04:32:51 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 04:32:51 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 04:32:51 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 04:32:51 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 04:32:51 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 04:32:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:32:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:32:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:32:53 INFO - Installing collected packages: mozsystemmonitor 04:32:53 INFO - Running setup.py install for mozsystemmonitor 04:32:54 INFO - Successfully installed mozsystemmonitor 04:32:54 INFO - Cleaning up... 04:32:54 INFO - Return code: 0 04:32:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:32:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:54 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 0x10c127a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdeeb593970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c08bd30>, '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 0x10bdf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c083b20>, '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.xLdOETKv81/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.HjXel97mCA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 04:32:54 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 04:32:54 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 04:32:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLdOETKv81/Render', 04:32:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:54 INFO - 'HOME': '/Users/cltbld', 04:32:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:54 INFO - 'LOGNAME': 'cltbld', 04:32:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:54 INFO - 'MOZ_NO_REMOTE': '1', 04:32:54 INFO - 'NO_EM_RESTART': '1', 04:32:54 INFO - 'PAGER': '/bin/cat', 04:32:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:54 INFO - 'PWD': '/builds/slave/test', 04:32:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:54 INFO - 'SHELL': '/bin/bash', 04:32:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HjXel97mCA/Listeners', 04:32:54 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 04:32:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:54 INFO - 'USER': 'cltbld', 04:32:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:54 INFO - 'XPC_FLAGS': '0x0', 04:32:54 INFO - 'XPC_SERVICE_NAME': '0', 04:32:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:54 INFO - Downloading/unpacking jsonschema==2.5.1 04:32:54 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 04:32:54 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 04:32:54 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 04:32:54 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 04:32:54 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 04:32:54 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 04:32:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:32:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:32:56 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 04:32:56 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 04:32:56 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 04:32:56 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 04:32:56 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 04:32:56 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 04:32:57 INFO - Downloading functools32-3.2.3-2.tar.gz 04:32:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:32:57 INFO - warning: no files found matching '*.txt' 04:32:57 INFO - no previously-included directories found matching 'build' 04:32:57 INFO - no previously-included directories found matching 'dist' 04:32:57 INFO - no previously-included directories found matching '.git*' 04:32:57 INFO - Installing collected packages: jsonschema, functools32 04:32:57 INFO - Running setup.py install for functools32 04:32:57 INFO - warning: no files found matching '*.txt' 04:32:57 INFO - no previously-included directories found matching 'build' 04:32:57 INFO - no previously-included directories found matching 'dist' 04:32:57 INFO - no previously-included directories found matching '.git*' 04:32:57 INFO - Successfully installed jsonschema functools32 04:32:57 INFO - Cleaning up... 04:32:57 INFO - Return code: 0 04:32:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:32:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c127a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdeeb593970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c08bd30>, '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 0x10bdf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c083b20>, '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.xLdOETKv81/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.HjXel97mCA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 04:32:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:32:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:32:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLdOETKv81/Render', 04:32:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:57 INFO - 'HOME': '/Users/cltbld', 04:32:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:57 INFO - 'LOGNAME': 'cltbld', 04:32:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:57 INFO - 'MOZ_NO_REMOTE': '1', 04:32:57 INFO - 'NO_EM_RESTART': '1', 04:32:57 INFO - 'PAGER': '/bin/cat', 04:32:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:57 INFO - 'PWD': '/builds/slave/test', 04:32:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:57 INFO - 'SHELL': '/bin/bash', 04:32:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HjXel97mCA/Listeners', 04:32:57 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 04:32:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:57 INFO - 'USER': 'cltbld', 04:32:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:57 INFO - 'XPC_FLAGS': '0x0', 04:32:57 INFO - 'XPC_SERVICE_NAME': '0', 04:32:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:57 INFO - Downloading/unpacking blobuploader==1.2.4 04:32:57 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 04:32:57 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 04:32:57 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 04:32:57 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 04:32:57 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 04:32:57 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 04:33:00 INFO - Downloading blobuploader-1.2.4.tar.gz 04:33:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:33:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:33:00 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 04:33:00 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 04:33:00 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 04:33:00 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 04:33:00 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 04:33:00 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 04:33:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:33:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:33:00 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 04:33:00 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 04:33:00 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 04:33:00 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 04:33:00 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 04:33:00 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 04:33:01 INFO - Downloading docopt-0.6.1.tar.gz 04:33:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:33:01 INFO - Installing collected packages: blobuploader, requests, docopt 04:33:01 INFO - Running setup.py install for blobuploader 04:33:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:33:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:33:01 INFO - Running setup.py install for requests 04:33:02 INFO - Running setup.py install for docopt 04:33:02 INFO - Successfully installed blobuploader requests docopt 04:33:02 INFO - Cleaning up... 04:33:02 INFO - Return code: 0 04:33:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:33:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:02 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 0x10c127a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdeeb593970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c08bd30>, '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 0x10bdf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c083b20>, '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.xLdOETKv81/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.HjXel97mCA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 04:33:02 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 04:33:02 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 04:33:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLdOETKv81/Render', 04:33:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:02 INFO - 'HOME': '/Users/cltbld', 04:33:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:02 INFO - 'LOGNAME': 'cltbld', 04:33:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:02 INFO - 'MOZ_NO_REMOTE': '1', 04:33:02 INFO - 'NO_EM_RESTART': '1', 04:33:02 INFO - 'PAGER': '/bin/cat', 04:33:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:02 INFO - 'PWD': '/builds/slave/test', 04:33:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:02 INFO - 'SHELL': '/bin/bash', 04:33:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HjXel97mCA/Listeners', 04:33:02 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 04:33:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:02 INFO - 'USER': 'cltbld', 04:33:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:02 INFO - 'XPC_FLAGS': '0x0', 04:33:02 INFO - 'XPC_SERVICE_NAME': '0', 04:33:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:33:02 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-PJBfC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:33:02 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-UnwSoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:33:02 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-HFkRb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:33:02 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Yk87MT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:33:03 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-rYqPbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:33:03 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-wmM_J6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:33:03 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-UZda79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:33:03 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-WJB_bw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:33:03 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-62eFCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:33:03 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-EG50BB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:33:03 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-n4Oi7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:33:03 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-BrL96V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:33:03 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-0BDUzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:33:04 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-E3w70C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:33:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:33:04 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-V38R0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:33:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:33:04 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-2Nz4vS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:33:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:33:04 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-gPHyY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:33:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:33:04 INFO - Running setup.py install for manifestparser 04:33:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:33:04 INFO - Running setup.py install for mozcrash 04:33:04 INFO - Running setup.py install for mozdebug 04:33:04 INFO - Running setup.py install for mozdevice 04:33:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:33:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:33:05 INFO - Running setup.py install for mozfile 04:33:05 INFO - Running setup.py install for mozhttpd 04:33:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:33:05 INFO - Running setup.py install for mozinfo 04:33:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:33:05 INFO - Running setup.py install for mozInstall 04:33:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:33:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:33:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:33:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:33:05 INFO - Running setup.py install for mozleak 04:33:05 INFO - Running setup.py install for mozlog 04:33:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:33:05 INFO - Running setup.py install for moznetwork 04:33:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:33:06 INFO - Running setup.py install for mozprocess 04:33:06 INFO - Running setup.py install for mozprofile 04:33:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:33:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:33:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:33:06 INFO - Running setup.py install for mozrunner 04:33:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:33:06 INFO - Running setup.py install for mozscreenshot 04:33:06 INFO - Running setup.py install for moztest 04:33:06 INFO - Running setup.py install for mozversion 04:33:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:33:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:33:06 INFO - Cleaning up... 04:33:06 INFO - Return code: 0 04:33:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:33:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:33:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:06 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 0x10c127a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdeeb593970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c08bd30>, '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 0x10bdf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c083b20>, '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.xLdOETKv81/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.HjXel97mCA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 04:33:06 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 04:33:06 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 04:33:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLdOETKv81/Render', 04:33:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:06 INFO - 'HOME': '/Users/cltbld', 04:33:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:06 INFO - 'LOGNAME': 'cltbld', 04:33:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:06 INFO - 'MOZ_NO_REMOTE': '1', 04:33:06 INFO - 'NO_EM_RESTART': '1', 04:33:06 INFO - 'PAGER': '/bin/cat', 04:33:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:06 INFO - 'PWD': '/builds/slave/test', 04:33:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:06 INFO - 'SHELL': '/bin/bash', 04:33:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HjXel97mCA/Listeners', 04:33:06 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 04:33:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:06 INFO - 'USER': 'cltbld', 04:33:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:06 INFO - 'XPC_FLAGS': '0x0', 04:33:06 INFO - 'XPC_SERVICE_NAME': '0', 04:33:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:33:07 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-gCeRfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:33:07 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)) 04:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:33:07 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-ktuu5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:33:07 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)) 04:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:33:07 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-kuOkW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:33:07 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)) 04:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:33:07 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-4G_h78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:33:07 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)) 04:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:33:07 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-PpJJd7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:33:07 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)) 04:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:33:07 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-I416Tk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:33:07 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)) 04:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:33:07 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-MHaVa9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:33:07 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)) 04:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:33:07 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-HXfA5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:33:08 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)) 04:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:33:08 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-r8pxuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:33:08 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)) 04:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:33:08 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-WtnrkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:33:08 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)) 04:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:33:08 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-mxjNNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:33:08 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)) 04:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:33:08 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-n4pNV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:33:08 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)) 04:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:33:08 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-q1cHuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:33:08 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)) 04:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:33:08 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-avZyk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:33:08 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)) 04:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:33:08 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Qca18Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:33:08 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)) 04:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:33:08 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-uhLXpt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:33:08 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)) 04:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:33:08 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-s9EcBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:33:09 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)) 04:33:09 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)) 04:33:09 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)) 04:33:09 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)) 04:33:09 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)) 04:33:09 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)) 04:33:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:33:09 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 04:33:09 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 04:33:09 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 04:33:09 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 04:33:09 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 04:33:09 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 04:33:11 INFO - Downloading blessings-1.6.tar.gz 04:33:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:33:11 INFO - Installing collected packages: blessings 04:33:11 INFO - Running setup.py install for blessings 04:33:11 INFO - Successfully installed blessings 04:33:11 INFO - Cleaning up... 04:33:11 INFO - Return code: 0 04:33:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:33:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:33:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:33:12 INFO - Reading from file tmpfile_stdout 04:33:12 INFO - Current package versions: 04:33:12 INFO - blessings == 1.6 04:33:12 INFO - blobuploader == 1.2.4 04:33:12 INFO - docopt == 0.6.1 04:33:12 INFO - functools32 == 3.2.3-2 04:33:12 INFO - jsonschema == 2.5.1 04:33:12 INFO - manifestparser == 1.1 04:33:12 INFO - mozInstall == 1.12 04:33:12 INFO - mozcrash == 0.17 04:33:12 INFO - mozdebug == 0.1 04:33:12 INFO - mozdevice == 0.48 04:33:12 INFO - mozfile == 1.2 04:33:12 INFO - mozhttpd == 0.7 04:33:12 INFO - mozinfo == 0.9 04:33:12 INFO - mozleak == 0.1 04:33:12 INFO - mozlog == 3.2 04:33:12 INFO - moznetwork == 0.27 04:33:12 INFO - mozprocess == 0.22 04:33:12 INFO - mozprofile == 0.28 04:33:12 INFO - mozrunner == 6.11 04:33:12 INFO - mozscreenshot == 0.1 04:33:12 INFO - mozsystemmonitor == 0.3 04:33:12 INFO - moztest == 0.7 04:33:12 INFO - mozversion == 1.4 04:33:12 INFO - psutil == 3.1.1 04:33:12 INFO - requests == 1.2.3 04:33:12 INFO - wsgiref == 0.1.2 04:33:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:33: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')]} 04:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33: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')]} 04:33:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:12 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 0x10c127a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdeeb593970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c08bd30>, '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 0x10bdf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c083b20>, '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.xLdOETKv81/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.HjXel97mCA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 04:33:12 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 04:33:12 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 04:33:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLdOETKv81/Render', 04:33:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:12 INFO - 'HOME': '/Users/cltbld', 04:33:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:12 INFO - 'LOGNAME': 'cltbld', 04:33:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:12 INFO - 'MOZ_NO_REMOTE': '1', 04:33:12 INFO - 'NO_EM_RESTART': '1', 04:33:12 INFO - 'PAGER': '/bin/cat', 04:33:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:12 INFO - 'PWD': '/builds/slave/test', 04:33:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:12 INFO - 'SHELL': '/bin/bash', 04:33:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HjXel97mCA/Listeners', 04:33:12 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 04:33:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:12 INFO - 'USER': 'cltbld', 04:33:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:12 INFO - 'XPC_FLAGS': '0x0', 04:33:12 INFO - 'XPC_SERVICE_NAME': '0', 04:33:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:12 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)) 04:33:12 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)) 04:33:12 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)) 04:33:12 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)) 04:33:12 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)) 04:33:12 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)) 04:33:12 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)) 04:33:12 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)) 04:33:12 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)) 04:33:12 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)) 04:33:12 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)) 04:33:12 INFO - Cleaning up... 04:33:12 INFO - Return code: 0 04:33:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:33: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')]} 04:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33: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')]} 04:33:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:12 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 0x10c127a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdeeb593970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c08bd30>, '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 0x10bdf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c083b20>, '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.xLdOETKv81/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.HjXel97mCA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 04:33:12 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 04:33:12 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 04:33:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLdOETKv81/Render', 04:33:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:12 INFO - 'HOME': '/Users/cltbld', 04:33:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:12 INFO - 'LOGNAME': 'cltbld', 04:33:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:12 INFO - 'MOZ_NO_REMOTE': '1', 04:33:12 INFO - 'NO_EM_RESTART': '1', 04:33:12 INFO - 'PAGER': '/bin/cat', 04:33:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:12 INFO - 'PWD': '/builds/slave/test', 04:33:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:12 INFO - 'SHELL': '/bin/bash', 04:33:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HjXel97mCA/Listeners', 04:33:12 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 04:33:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:12 INFO - 'USER': 'cltbld', 04:33:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:12 INFO - 'XPC_FLAGS': '0x0', 04:33:12 INFO - 'XPC_SERVICE_NAME': '0', 04:33:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:33:12 INFO - Cleaning up... 04:33:12 INFO - Return code: 0 04:33:12 INFO - Running post-action listener: _resource_record_post_action 04:33:12 INFO - Running post-action listener: _start_resource_monitoring 04:33:12 INFO - Starting resource monitoring. 04:33:12 INFO - ##### 04:33:12 INFO - ##### Finished create-virtualenv step (success) 04:33:12 INFO - ##### 04:33:12 INFO - ##### 04:33:12 INFO - ##### Running install step. 04:33:12 INFO - ##### 04:33:12 INFO - Running pre-action listener: _resource_record_pre_action 04:33:12 INFO - Running main action method: install 04:33:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:33:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:33:13 INFO - Reading from file tmpfile_stdout 04:33:13 INFO - Detecting whether we're running mozinstall >=1.0... 04:33:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:33:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:33:13 INFO - Reading from file tmpfile_stdout 04:33:13 INFO - Output received: 04:33:13 INFO - Usage: mozinstall [options] installer 04:33:13 INFO - Options: 04:33:13 INFO - -h, --help show this help message and exit 04:33:13 INFO - -d DEST, --destination=DEST 04:33:13 INFO - Directory to install application into. [default: 04:33:13 INFO - "/builds/slave/test"] 04:33:13 INFO - --app=APP Application being installed. [default: firefox] 04:33:13 INFO - mkdir: /builds/slave/test/build/application 04:33:13 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'] 04:33:13 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 04:33:47 INFO - Reading from file tmpfile_stdout 04:33:47 INFO - Output received: 04:33:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:33:47 INFO - Running post-action listener: _resource_record_post_action 04:33:47 INFO - ##### 04:33:47 INFO - ##### Finished install step (success) 04:33:47 INFO - ##### 04:33:47 INFO - ##### 04:33:47 INFO - ##### Running run-tests step. 04:33:47 INFO - ##### 04:33:47 INFO - Running pre-action listener: _resource_record_pre_action 04:33:47 INFO - Running main action method: run_tests 04:33:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:33:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:33:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:33:47 INFO - 2016-07-02 04:33:47.587 screenresolution[1643:7691] starting screenresolution argv=screenresolution get 04:33:47 INFO - 2016-07-02 04:33:47.590 screenresolution[1643:7691] Display 0: 1600x1200x32@60 04:33:47 INFO - 2016-07-02 04:33:47.596 screenresolution[1644:7693] starting screenresolution argv=screenresolution list 04:33:47 INFO - Available Modes on Display 0 04:33:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:33:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:33:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:33:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:33:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:33:47 INFO - Intel Iris: 04:33:47 INFO - Chipset Model: Intel Iris 04:33:47 INFO - Type: GPU 04:33:47 INFO - Bus: Built-In 04:33:47 INFO - VRAM (Dynamic, Max): 1536 MB 04:33:47 INFO - Vendor: Intel (0x8086) 04:33:47 INFO - Device ID: 0x0a2e 04:33:47 INFO - Revision ID: 0x0009 04:33:47 INFO - Displays: 04:33:47 INFO - Display: 04:33:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:33:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:33:47 INFO - Display Serial Number: 1600x1200 60 04:33:47 INFO - Main Display: Yes 04:33:47 INFO - Mirror: Off 04:33:47 INFO - Online: Yes 04:33:47 INFO - Rotation: Supported 04:33:47 INFO - Return code: 0 04:33:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:33:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:33:47 INFO - Python 2.7.3 04:33:47 INFO - Return code: 0 04:33:47 INFO - grabbing minidump binary from tooltool 04:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:33:47 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 0x10c08bd30>, '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 0x10bdf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c083b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:33:47 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 04:33:47 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 04:33:47 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 04:33:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:33:47 INFO - Return code: 0 04:33:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:33:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:33:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:33:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:33:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:33:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0292', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:33:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0292 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:33:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLdOETKv81/Render', 04:33:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:47 INFO - 'HOME': '/Users/cltbld', 04:33:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:47 INFO - 'LOGNAME': 'cltbld', 04:33:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:33:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:33:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:47 INFO - 'MOZ_NO_REMOTE': '1', 04:33:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:33:47 INFO - 'NO_EM_RESTART': '1', 04:33:47 INFO - 'PAGER': '/bin/cat', 04:33:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:47 INFO - 'PWD': '/builds/slave/test', 04:33:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:33:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:47 INFO - 'SHELL': '/bin/bash', 04:33:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HjXel97mCA/Listeners', 04:33:47 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 04:33:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:47 INFO - 'USER': 'cltbld', 04:33:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:47 INFO - 'XPC_FLAGS': '0x0', 04:33:47 INFO - 'XPC_SERVICE_NAME': '0', 04:33:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0292', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:33:48 INFO - mozversion application_buildid: 20160702022223 04:33:48 INFO - mozversion application_changeset: 39dffbba764210b25bfc1e749b4f16db77fa0d46 04:33:48 INFO - mozversion application_display_name: Nightly 04:33:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:33:48 INFO - mozversion application_name: Firefox 04:33:48 INFO - mozversion application_remotingname: firefox 04:33:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:33:48 INFO - mozversion application_vendor: Mozilla 04:33:48 INFO - mozversion application_version: 50.0a1 04:33:48 INFO - mozversion platform_buildid: 20160702022223 04:33:48 INFO - mozversion platform_changeset: 39dffbba764210b25bfc1e749b4f16db77fa0d46 04:33:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:33:48 INFO - mozversion platform_version: 50.0a1 04:33:48 INFO - using testdate: 1467459228 04:33:48 INFO - actual date: 1467459228 04:33:48 INFO - starting webserver on 'localhost:49269' 04:33:48 INFO - SUITE-START | Running 2 tests 04:33:48 INFO - TEST-START | dromaeo_css 04:33:48 INFO - operating with platform_type : mac_ 04:33:48 INFO - Initialising browser for dromaeo_css test... 04:33:48 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpQCJpvI/profile http://localhost:49269/getInfo.html) 04:33:50 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 04:33:50 INFO - PROCESS | 1654 | colorDepth:24 04:33:50 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 04:33:50 INFO - PROCESS | 1654 | __metrics 04:33:50 INFO - PROCESS | 1654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:33:50 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:33:51 INFO - TEST-INFO | 1654: exit 0 04:33:51 INFO - Browser initialized. 04:33:51 INFO - Running cycle 1/1 for dromaeo_css test... 04:33:51 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpQCJpvI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:34:03 INFO - PROCESS | 1656 | RSS: Main: 243920896 04:34:03 INFO - PROCESS | 1656 | 04:34:54 INFO - PROCESS | 1656 | 1467459294447 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] 04:37:06 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49269/tests/dromaeo/cssquery-ext.html) 04:37:07 INFO - PROCESS | 1656 | RSS: Main: 360013824 04:37:07 INFO - PROCESS | 1656 | 04:40:10 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-ext.html (next: http://localhost:49269/tests/dromaeo/cssquery-jquery.html) 04:40:11 INFO - PROCESS | 1656 | RSS: Main: 378740736 04:40:11 INFO - PROCESS | 1656 | 04:43:13 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49269/tests/dromaeo/cssquery-mootools.html) 04:43:14 INFO - PROCESS | 1656 | RSS: Main: 371036160 04:43:14 INFO - PROCESS | 1656 | 04:46:16 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49269/tests/dromaeo/cssquery-prototype.html) 04:46:17 INFO - PROCESS | 1656 | RSS: Main: 369315840 04:46:17 INFO - PROCESS | 1656 | 04:49:20 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49269/tests/dromaeo/cssquery-yui.html) 04:49:21 INFO - PROCESS | 1656 | RSS: Main: 337719296 04:49:21 INFO - PROCESS | 1656 | 04:52:24 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-yui.html (next: http://localhost:49269/tests/dromaeo/cssquery-dojo.html) 04:52:24 INFO - PROCESS | 1656 | RSS: Main: 407453696 04:52:24 INFO - PROCESS | 1656 | 04:52:24 INFO - PROCESS | 1656 | __start_tp_report 04:52:24 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 04:52:24 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 04:52:24 INFO - PROCESS | 1656 | |i|pagename|runs| 04:52:24 INFO - PROCESS | 1656 | |0;dojo.html;3173.206698325419;3407.3526030054286;3427.5886893572765;3440.3291358185165;3468.9479657805136;5722.227499287595;5775.191473193756;5818.679972601505;5927.792527261547;5929.258842644669;5782.074868021117;5849.444302791249;5917.408259174081;5925.34821169671;5996.980513312737;5166.225066240076;5219.660722053073;5262.526372626463;5270.262163297122;5397.136458166694;5373.623846330758;5389.218563308318;5395.541378982791;5425.050616142179;5460.290162278918;5115.846524604268;5193.740312984354;5242.397124330712;5249.8425047248265;5283.894322113536;5282.603804714662;5286.629935904488;5288.762005709752;5292.179712144623;5299.390570084451;5233.215017747369;5269.499397557226;5293.258943747878;5295.973520132413;5320.281761962125;4899.583535399495;5848.590598658096;5849.38581448946;5857.619254748471;5864.384239654817;6218.440340369389;6236.345183755686;6251.562390632657;6253.6873156342;6259;4994.850154495371;6260.749570017194;6281.026440901667;6287.402696743802;6330.177076979963;5885.793997210163;6011.30869949957;6083.66539840313;6092.238470191226;6101.969490152521;6091.66495842724;6276.246850377893;6316.494680425555;6322.873542529123;6391.07329437229;6273.247210334789;6281.780137695158;6285.717142728588;6295.748170073238;6313.905291420632;6208.627482351073;6298.307186209513;6334.493240540745;6336.714847831859;6338.334474880164;6198.473129783929;6217.347178546278;6252.874942501216;6276.152719382825;6277.591956522902;5798.985177593904;5898.64608123514;6249.218847644044;6264.060390941395;6276.780312689034;5043.445221025684;5053.2420136979745;5062;5087.592992560586;5103.132467480537;4566.589006989387;4574.88562785933;4589;4622.098690755338;4627.167109920179;5907.586468947133;5914.58597898143;5923.822285331447;5994.730237139339;6015.368386319461;6232.846923319201;6279.623222606659;6288.5598008138995;6300.30696623371;6316.873662526724;5806.332271788714;5820.592558520948;5864.9736296148;5927.8221653350465;5973.522118230531;5557.805476808292;5557.833265002057;5570.108782594765;5586.329640443091;5636.577256705681;6375.936240637627;6436.7425302988295;6452.580582262138;6454.644994525158;6473.741050357933;6162.476189523941;6238.126662267377;6288.905666415099;6306;6342.334054924167;6424.614523128627;6444.194475690539;6456;6463.030545418225;6480.189976252968;6332.905006424815;6353.52348573868;6372.522060845363;6373.553851230369;6379.904301435389;6169.383061693795;6198;6241.750329986749;6263.154474145932;6331.493480521455;6260.624362538262;6313.2424109107205;6313.463355614824;6314.210723659543;6329.968350158219;6247.843803904939;6253.093301471352;6265.624062556261;6274;6278.0269058296035;6001.579889407874;6081.209442772411;6086.573939824347;6103.216452666926;6152.015677491759;6279.309275979547;6288.434040936338;6290.182276304051;6292.748290068346;6297.590656607306;6018.518518518596;6137.3862613738265;6190.628562286277;6194.938050619436;6214.689265536615 04:52:24 INFO - PROCESS | 1656 | |1;ext.html;473.495727050691;503.40598094248793;530.7903378165624;538.4534697282256;552.0174090119588;30156.547651785215;43277;44643;44667.32999005014;44703.77648111763;43938.7803060985;44111.897202569955;44189.558104418946;44244.55755442445;44251;8616;9609.759756006104;9788.804223915517;9801.460919649417;9804.803903921917;8559.614817333204;11776.7644647107;11998.160128790989;12005.759884802299;12249.693757656041;12061.819072713872;12131.514739410457;12145.939270303636;12148.3318417487;12154.33151176685;8681.652733890636;8847.24798392131;11774.764504709901;11906.22609530382;11960.401979901057;8750.781230469289;8809.691660791841;11454.599089031928;11765;11772.705682357924;41301.76094717133;41630;41684.58315416837;41793;41847.1630567387;3550.7336949728874;3583.008677831166;3588.4796704477703;3755.3525902824135;4156.7921603919685;31523.527147092813;32670.836645816857;33378.1655458613;33380.33239335224;33406.49890251648;3256.2510622556706;3257.837108144607;3423.863045478178;3454.101933497284;3455.0498612881406;512276;513374;513509.4324528391;513855;514270;56652.15021774677;59748;59882.401175988554;59973;60160;64096;64259.35740642534;64380.034299485545;64478.677606611665;64532.35467645357;15957.484039016275;16395.42616008434;16437.67124657524;16476.588085297724;16503.6699266014;11535.826962595567;11773.99921006724;12619.179753316004;12705.809412858815;12887.355632218352;14042.929785351007;16697.831151819257;16742.83257167437;16847.663046738995;16878.578035548962;6897.827554311182;6917.717153339386;7206.639668016578;7236.986821844946;7246.384057355184;889.7375274294099;989.3322007644811;1002.3735165521549;1018.4754851251612;1026.045777427;26659;30266.54600180999;30738.077857664306;31256;31275.499034768174;1034.5189486888482;1057.582255009267;1064.7125276175389;1095.917806164541;1210.5399948019697;12185.573504927463;15341.539753807403;15792.973456725276;16144.75782863258;16170.676586468206;650741;654150;659438;659707;660622.6968865125;59833.401665983656;60060.39939600635;60290;60300.39699602948;60614.696926515964;1249.631358749171;1377.2012232905092;1395.288402914521;1425.2018869432736;1425.3016022149072;31685.841570792;31752;32044.878429254863;32828.835855820565;32945.505817412275;51267.974640507724;57010.714946425;58345.416545834;58476.707616461645;61294.69352653387;606789;612765;618359;619809;619884;23880.04479820857;26604.201873943814;26714.59928101041;27081.593776093767;27304.453910922068;48774.512254876994;49806.75096624494;50799.49200508095;50890.74554627203;51008;38311;40872.59127408807;40890.591094088675;41595.37606935959;41679.95162442947;38599.42100868433;40562.9859253521;40800.3879941807;42423.57576424196;43961;35052.29895402129;36410.63589364073;38801;39335.803320983214;39511.01222469461;34244;35424.46863297104;35755.46366804552;35810.0215794579;36707.81646091752;22600.208992685177;23878.04487820537;25561.105361312264;25903.35241618899;26330 04:52:24 INFO - PROCESS | 1656 | |2;jquery.html;373.43238277817716;382.5963608393145;383.20102586107964;391.2175648702595;404.47216382620684;19815.20739170433;22105.778942210574;22200.88899555504;22521.324360269176;22762;21075.26236581716;21530.677039844413;21783.45541361467;22217.333479995625;22257.55484890301;5561.443855614437;5779.133130030508;5871.706414679249;5877.412258774131;5961.225040744697;4120.464339635843;4334.046509767846;4339.305711086226;4354.869353919387;5787.50806181475;4229.836794881417;6478.935210647904;6498.090267362573;6532.738690452376;6552.672366381686;5645.774169033213;6403.48772098231;6507.186601674767;6510.837229069288;6522.836929076763;3665.9816700916326;5528.530074943635;5630.577706672016;5663.235463212455;5675.120356344773;24929;25975;26142.6078608821;28026.719732802616;28056;2184.159098746994;2202.867827930313;2214.8228141748623;2256.1030224017954;3096.6593674695764;15755.44855930037;16153.83846161535;16350.75473867893;16382.590435239095;16735.995840249623;1864.8974306413143;1867.253098760493;1869.6634605770955;1923.5536165782642;1985.4540001499558;120817.79182208153;123719;126311.68441577884;127791;128819;9251.67619133327;9361.719148425558;9372.343935924557;10080.949595251977;10840.78318433627;10503.894961050444;12535;12586.43361048755;12587.30769807651;12620.621381358575;7899.763007109796;8060.556669383123;8063.314618257396;8176.713815016446;8182.263596276363;8466.74599762008;8472.872906906401;8491.065982741893;8589.098144694843;8635.568221589021;3266.2161081340782;3929.2927273090545;3948.3287841067086;3987.5613682494904;3998.4402183694306;1880.6896862017888;1940.282095624628;1950.590376021023;1956.8728032678116;1957.6574099532525;864.822711344173;901.9368644194975;917.4082716647858;975.5024937282037;996.496769131584;21375.46561335979;22298.331050068526;22328.218512352316;22361.664575031387;22469.550608987727;929.8837645294338;992.0922356044139;999.5402115027024;1006.9848952265722;1037.4138611684375;9620.71137865865;11582.420878956018;11730.12024098196;11916.046716262852;11976.520939162336;517660;590936;595747;603239;612641;91635.1672966537;113242;130290.34854825665;130631;133076.33461832628;940.8541676040225;941.5339406993445;968.2205824311496;970.684527528556;972.9221662267001;18880.867147971167;19774.295107406604;20121.59756804885;20211.393658190278;20286.59426811426;44621.77689111534;54058.18912716391;54653.1802022962;55452.44547554473;56187.438125618224;80768.59615701884;80829.19170808217;81986.72147323306;82287;83065;8044.959775201087;8056.033276006917;8227.177282271725;8250.793730156794;8374.790630234293;10801.297915635458;12202.75594488123;13090.345482725636;13149.276789776271;13214.603561893158;10665.520051597852;11463.885361146615;11468.598599048993;11505.769884602094;11569.7107572308;11189.944050280023;11243.437828108725;11295.95009347488;11440.427978601203;11569.958703716706;11400.828987565028;11624.244424112405;11773.234739741889;11781.116416268986;11789;8095.2714255717265;8130.105688374392;8162.18378162179;8170.223828736261;8304.501729896225;11131.10951123892;11348.978591926767;11415.315081095161;11495.597654082067;11559.364234967157 04:52:24 INFO - PROCESS | 1656 | |3;mootools.html;1535.6007438066106;1565.4971227621484;1774.3878361965117;1913.674675305198;1922.826945574898;8537.658493660254;8862.91137088629;9051.411658242227;9057.773555661104;9057.864132038025;7875.330596899271;8117.63470643821;8121.593920303991;8220.424570280084;8878.689245876396;2161.3948094533516;2199.230269405703;2326.3951372643064;2413.8551686898754;2414.4326083370484;2399.8200134989856;2418.9879050604723;2421.2010036687852;2421.370443684646;2421.4430680943306;2353.6116540770754;2412.8190385721055;2415.722191947922;2416.1664225842055;2416.395901024744;1947.8539109566768;2104.442322784456;2411.4212588978694;2412.7949124324455;2420.1771397724683;2403.6154215325464;2404.110479122736;2420.697412823397;2427.077710470028;2430.939226519351;8977.775555611097;9050.140236677571;9056.864147037799;9057.547122643842;9076.954615226881;5267.762950667191;5391.81128660495;5449.455054494559;5449.836504904859;5452.91820622691;3579.570451545805;3686.705063594906;3706.944395834065;3712.4988126602834;3746.6253374662588;1075.1936047964027;1086.5816660585651;1094.4637127807316;1121.624255874283;1122.6688127002471;11919.284842909454;11995.28028318295;11996.58011969577;12003.399830008464;12017.93991030048;3851.6533511983494;4702.3416721659;4820.4456487503685;4852.223644216979;4852.272159176152;9962.800743985079;10023.348482348623;10032.949835250776;10043.849342259871;10046.447445390428;5391.78432862689;5393.6763794172475;5394.298741163623;5394.91907621386;5402.6758394496455;4795.664303498791;4958.107540642647;4959.404871415453;4979.850604481871;4980.825671101494;3539.0444579963264;3647.507586475845;3780.546334439885;3819.1597848473293;3890.6109389061435;1168.3048586091263;1170.3504554971903;1177.2818580665787;1178.5816035307514;1178.7406770510474;830.4021104804531;846.2933450568721;855.4354126276628;877.7322916510526;973.1484950668165;5826.359100498942;6114.296855861633;6120.632762034204;6170.81487555374;6216.409441103088;951.4974426645947;973.1339108193713;980.529345913951;981.9018098190123;1021.7598864266891;4061.817218225187;4099.651529619956;4130.896727581774;4183;4215.409842622035;10399.688009359732;11151.553937842558;11158.442077896072;11158.884111588775;11159.442027898573;10379.325343852626;10951;11096.278741881752;11104.389258590694;11116.66650000485;8182.427230093956;8397.328213743009;8413.284870786052;8415.284700800501;8426.789330266793;5969.462748352669;5970.164177015135;6103.542234332354;6257.9687101564205;6354.110424540476;9872;9875.950620246853;10321.845172322272;10371.118454931415;10397.53211105487;10413.583456661649;10448.05967462932;10452.111570516592;10467.685969420929;10473.790524189626;6974.267701891228;7532.058492688414;7578.43161762881;7589.734359297398;7732.961335193288;7846.803829904298;8306.335493160654;8538.231559159705;8540.871886921577;8653.17794809492;7578.886316705364;7645.311921927053;7676.193999629959;7775.261350171724;7811.984442022728;7488.288612581796;7517.2482751726575;7556.0177176966645;7649.847003060019;7700.460967732205;7083.610401427764;7117.82205444868;7117.964410177916;7199.8560028800175;7202.351788339075;4924.276503223986;5164.431912489548;5200.089984252665;5203.869903252449;5229.790808367775;4445.599896009375;5150.098732721834;5180.533751962342;5222.660527065728;5350.732463376893 04:52:24 INFO - PROCESS | 1656 | |4;prototype.html;296.87531236880494;302.750231059376;312.1976520342719;314.2505125276218;314.3571396494169;4400.559944005598;5530.281063461749;5536;5537.972310138443;5546.334439867216;4976.452590215083;5266;5435.847208619001;5461.644993075447;5469.316335458067;3936.881893543198;4142.606452387018;4147.523034850986;4185.162967406515;4193.62257396835;3802.9619703803023;3865.8067096645195;4124.484439445069;4180.435641188431;4210.5578914213875;4048.9797551012202;4064.5732198119217;4073.40935564343;4088.5093788745394;4091.4476545666253;3473.888355726169;3779.7888235382197;4076.3885417187657;4082.5509193988637;4105.486814148231;3239.72462340699;3479.408500554912;3543.291341731664;3550.6626870447526;3561.5370001899846;4411.602955733999;5050.686821426419;5306.787728490855;5360.2227676987;5366.543843773284;4034.4553485279407;4141.64795992341;4545.840895568639;4634.520531515623;4694.460137084211;4569.8521694803385;5718.141603978724;5752.36723960364;5757.740901659394;5772.855678608026;3669.9266014679556;3889.4749208856724;3911.3741801311653;3913.471681323042;3924.960750392488;4861.878453038702;6013.669248191348;6024.578279520435;6075.362086980847;6141.495333643286;3998.140399814054;4006.779627120536;4007.338789099824;4011.6590089842107;4028.8388464461095;3541.3448512025357;3767.773933563995;3985.900352491211;4011.1376054147913;4039;4425.203463376559;4629.837955671535;4667.603253723403;4688.507706690816;4746.715197088186;5504.834854954358;5859.970700146472;5860.208871802337;5875.823725288248;5882.735276912549;1284.113961366654;1329.7008173160962;1342.4764341906664;1343.4626149540068;1370.6093763277418;810.7878721311677;890.8173824365989;892.9776755581163;912.2018234045211;923.6259314977444;4906.1414252505665;4916.926246106311;5015.598752099823;5360.437154098763;5365.919511207335;5849.736761845727;5861.384554621789;5873.735681894325;5907;5927.3776253493625;5152.536271735562;5312.362516498045;5327.600429967768;5352.652077614943;5446.863828404243;3079.414911166871;3120.6567277599447;3137.5607414961923;3137.623485181747;3184.710192372093;5877.676727779927;6194.4115309045565;6225.0431045254045;6232.283287421914;6271.874562508816;5196.246544251062;5632.690202038845;5694.231278777312;5737.454941780524;6203;5194.792208311701;5796.536277097822;6238.344973777869;6243.43809057187;6296.33888441707;4224.810268247454;5655.575831812713;5768.971155144198;5990.7304171311525;6065.4541091302;5000.4749501303295;5415.377231618257;5720.8283751487525;5909.409059094056;5976.820695379146;5236.816711415082;5695.316562012585;5758.596898217084;6155.599886007395;6230.657313847782;3746.5878753337656;3920.9019774505864;3927.312720273998;3972.9006774829704;3987.268720926175;5733.913991290217;6028.854517641809;6054.878902422015;6093.299270583762;6116.847078822887;4698.436187657503;4997.225430058346;5045;5403.270558474555;5687.459691329317;4592.517785632461;4801.975990120027;5730.017637741729;5749.7125143741805;5752;5443.972780136074;5633.549316054624;5658;5709.628874123168;5754.194412782297;5108.084468324379;5347.705876176848;5798.94201057984;5821.835632873527;5844.3571207168025;5706.9714651426475;5722.941255867678;5741.569382296428;5747.626404283708;5792 04:52:24 INFO - PROCESS | 1656 | |5;yui.html;702.4871843554205;776.1036003416053;807.5154907055768;819.066264458517;823.2837431434651;14939.17834519101;16197.595060123504;16220.83779162208;16246.918765406186;16258.430954916581;14554.345054472547;14679.706405871877;14751.114933103994;14767.778483322732;14791;674.1135406939883;697.3549466743268;698.0157977252076;830.1159265382379;869.4174902815129;861.0700443521014;865.9610317535695;867.9479231246114;868.3791089371099;868.695956415256;896.5069211933443;916.5600511754362;916.5966974531218;917.179124683408;917.9082091790801;693.5769180799708;729.3472342253706;730.1749023603347;912.4798864647154;913.5067063785548;547.0481362429384;636.424036247197;674.9595024298509;677.474956908395;684.6713577482786;14666.120032798066;14690.045147065406;14716.969812113157;14721.779173312409;14721.779173312409;13620.659483512893;13776.311184440838;13818.447262109503;13864.376103075285;13963.58109256724;12732.299723515203;12779.361031948365;12844.972402207894;12943.741125177443;12979.610611681665;4522.547745225484;4587.8623641290815;4594.19601569724;4753.881152971168;4769.570738633505;111417;113818;116286.83713162845;116341.83658163395;118584.62830743424;10996.340219586851;11079.612213572485;11082.501287442085;11194.552217911356;11575.652730418102;21759.782402176093;21763.911804409716;21772.45568860791;21781.019854106602;21798.56402871934;7938.920610793818;7968.163342849033;7984.321332686786;7985.041794984523;8157.306628936488;4428.1365133799245;4457.68796184264;4478.104379124123;4627.93557481781;4631.675782695179;3353.3796247693767;3459.8270086496077;3468.289000754839;3469.6703813138215;3471.2536804587135;602.2532060245264;627.7645882793952;636.9936300636934;642.0593830039015;658.8649326887977;592.4786188154396;621.6984762390238;629.6662768732579;637.7576520922113;645.3997782062727;8585.785355366166;8976.730698079067;8979;8996.145366190201;9009.549522523848;518.390890703422;537.771447134966;597.8116893178635;611.9632822030692;634.2294112653149;4264.211120942636;4388.407564978751;4403.8018289176425;4477.70894891831;4495.213337665896;150150;154412;155336.22331888493;156601.2169939143;161389.19305403632;119878;137098;137643;138251;138709;106514.40228396426;113686.43156784163;117855;118683.99999999827;122011;8051.637676304464;8547;8550.914490855012;8623.827523449621;8855.468671879707;63584.3641563597;64385.67807160934;65550.67224663847;65934;66374;76413.61793190999;81635;83392;84969;89926;11230.438478075901;11287.830682539605;11383.772324553629;11426.657200284004;11909.940450297692;12117.57588484434;12178.45196966121;12252.693682657648;12262.202956808136;12784.61646150617;8757.91242087571;11648.708782280512;11813.885137818785;12303.446344914679;12433.626991190278;11574.421278935852;12659.367031648197;13403.530876419278;14293.213873237071;14475.638109047359;13347.933260333637;13435.992300577691;13796.310184490534;13973.930130349283;14113.364898579794;11197.992180703775;11321.207515473838;11407.657770266906;11470.71323216893;11637.88362116368;9744.951275243578;10014.898361180118;11500.712482188012;11610.88389116098;11637.941810290895 04:52:24 INFO - PROCESS | 1656 | __end_tp_report 04:52:24 INFO - PROCESS | 1656 | __start_cc_report 04:52:24 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1228 04:52:24 INFO - PROCESS | 1656 | __end_cc_report 04:52:24 INFO - PROCESS | 1656 | __startTimestamp1467460344397__endTimestamp 04:52:24 INFO - PROCESS | 1656 | 04:52:24 INFO - PROCESS | 1656 | ------- Summary: start ------- 04:52:24 INFO - PROCESS | 1656 | Number of tests: 6 04:52:24 INFO - PROCESS | 1656 | 04:52:24 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5847.95 Median:6101.97 stddev:646.95 (10.6%) stddev-sans-first:613.97 04:52:24 INFO - PROCESS | 1656 | Values: 3173.2 3407.4 3427.6 3440.3 3468.9 5722.2 5775.2 5818.7 5927.8 5929.3 5782.1 5849.4 5917.4 5925.3 5997.0 5166.2 5219.7 5262.5 5270.3 5397.1 5373.6 5389.2 5395.5 5425.1 5460.3 5115.8 5193.7 5242.4 5249.8 5283.9 5282.6 5286.6 5288.8 5292.2 5299.4 5233.2 5269.5 5293.3 5296.0 5320.3 4899.6 5848.6 5849.4 5857.6 5864.4 6218.4 6236.3 6251.6 6253.7 6259.0 4994.9 6260.7 6281.0 6287.4 6330.2 5885.8 6011.3 6083.7 6092.2 6102.0 6091.7 6276.2 6316.5 6322.9 6391.1 6273.2 6281.8 6285.7 6295.7 6313.9 6208.6 6298.3 6334.5 6336.7 6338.3 6198.5 6217.3 6252.9 6276.2 6277.6 5799.0 5898.6 6249.2 6264.1 6276.8 5043.4 5053.2 5062.0 5087.6 5103.1 4566.6 4574.9 4589.0 4622.1 4627.2 5907.6 5914.6 5923.8 5994.7 6015.4 6232.8 6279.6 6288.6 6300.3 6316.9 5806.3 5820.6 5865.0 5927.8 5973.5 5557.8 5557.8 5570.1 5586.3 5636.6 6375.9 6436.7 6452.6 6454.6 6473.7 6162.5 6238.1 6288.9 6306.0 6342.3 6424.6 6444.2 6456.0 6463.0 6480.2 6332.9 6353.5 6372.5 6373.6 6379.9 6169.4 6198.0 6241.8 6263.2 6331.5 6260.6 6313.2 6313.5 6314.2 6330.0 6247.8 6253.1 6265.6 6274.0 6278.0 6001.6 6081.2 6086.6 6103.2 6152.0 6279.3 6288.4 6290.2 6292.7 6297.6 6018.5 6137.4 6190.6 6194.9 6214.7 04:52:24 INFO - PROCESS | 1656 | 04:52:24 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:73293.92 Median:38455.21 stddev:159892.34 (415.8%) stddev-sans-first:160247.40 04:52:24 INFO - PROCESS | 1656 | Values: 473.5 503.4 530.8 538.5 552.0 30156.5 43277.0 44643.0 44667.3 44703.8 43938.8 44111.9 44189.6 44244.6 44251.0 8616.0 9609.8 9788.8 9801.5 9804.8 8559.6 11776.8 11998.2 12005.8 12249.7 12061.8 12131.5 12145.9 12148.3 12154.3 8681.7 8847.2 11774.8 11906.2 11960.4 8750.8 8809.7 11454.6 11765.0 11772.7 41301.8 41630.0 41684.6 41793.0 41847.2 3550.7 3583.0 3588.5 3755.4 4156.8 31523.5 32670.8 33378.2 33380.3 33406.5 3256.3 3257.8 3423.9 3454.1 3455.0 512276.0 513374.0 513509.4 513855.0 514270.0 56652.2 59748.0 59882.4 59973.0 60160.0 64096.0 64259.4 64380.0 64478.7 64532.4 15957.5 16395.4 16437.7 16476.6 16503.7 11535.8 11774.0 12619.2 12705.8 12887.4 14042.9 16697.8 16742.8 16847.7 16878.6 6897.8 6917.7 7206.6 7237.0 7246.4 889.7 989.3 1002.4 1018.5 1026.0 26659.0 30266.5 30738.1 31256.0 31275.5 1034.5 1057.6 1064.7 1095.9 1210.5 12185.6 15341.5 15793.0 16144.8 16170.7 650741.0 654150.0 659438.0 659707.0 660622.7 59833.4 60060.4 60290.0 60300.4 60614.7 1249.6 1377.2 1395.3 1425.2 1425.3 31685.8 31752.0 32044.9 32828.8 32945.5 51268.0 57010.7 58345.4 58476.7 61294.7 606789.0 612765.0 618359.0 619809.0 619884.0 23880.0 26604.2 26714.6 27081.6 27304.5 48774.5 49806.8 50799.5 50890.7 51008.0 38311.0 40872.6 40890.6 41595.4 41680.0 38599.4 40563.0 40800.4 42423.6 43961.0 35052.3 36410.6 38801.0 39335.8 39511.0 34244.0 35424.5 35755.5 35810.0 36707.8 22600.2 23878.0 25561.1 25903.4 26330.0 04:52:24 INFO - PROCESS | 1656 | 04:52:24 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:34959.04 Median:15576.33 stddev:97806.66 (627.9%) stddev-sans-first:98046.56 04:52:24 INFO - PROCESS | 1656 | Values: 373.4 382.6 383.2 391.2 404.5 19815.2 22105.8 22200.9 22521.3 22762.0 21075.3 21530.7 21783.5 22217.3 22257.6 5561.4 5779.1 5871.7 5877.4 5961.2 4120.5 4334.0 4339.3 4354.9 5787.5 4229.8 6478.9 6498.1 6532.7 6552.7 5645.8 6403.5 6507.2 6510.8 6522.8 3666.0 5528.5 5630.6 5663.2 5675.1 24929.0 25975.0 26142.6 28026.7 28056.0 2184.2 2202.9 2214.8 2256.1 3096.7 15755.4 16153.8 16350.8 16382.6 16736.0 1864.9 1867.3 1869.7 1923.6 1985.5 120817.8 123719.0 126311.7 127791.0 128819.0 9251.7 9361.7 9372.3 10080.9 10840.8 10503.9 12535.0 12586.4 12587.3 12620.6 7899.8 8060.6 8063.3 8176.7 8182.3 8466.7 8472.9 8491.1 8589.1 8635.6 3266.2 3929.3 3948.3 3987.6 3998.4 1880.7 1940.3 1950.6 1956.9 1957.7 864.8 901.9 917.4 975.5 996.5 21375.5 22298.3 22328.2 22361.7 22469.6 929.9 992.1 999.5 1007.0 1037.4 9620.7 11582.4 11730.1 11916.0 11976.5 517660.0 590936.0 595747.0 603239.0 612641.0 91635.2 113242.0 130290.3 130631.0 133076.3 940.9 941.5 968.2 970.7 972.9 18880.9 19774.3 20121.6 20211.4 20286.6 44621.8 54058.2 54653.2 55452.4 56187.4 80768.6 80829.2 81986.7 82287.0 83065.0 8045.0 8056.0 8227.2 8250.8 8374.8 10801.3 12202.8 13090.3 13149.3 13214.6 10665.5 11463.9 11468.6 11505.8 11569.7 11189.9 11243.4 11296.0 11440.4 11570.0 11400.8 11624.2 11773.2 11781.1 11789.0 8095.3 8130.1 8162.2 8170.2 8304.5 11131.1 11349.0 11415.3 11495.6 11559.4 04:52:24 INFO - PROCESS | 1656 | 04:52:24 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5817.51 Median:4941.19 stddev:3273.96 (66.3%) stddev-sans-first:3267.33 04:52:24 INFO - PROCESS | 1656 | Values: 1535.6 1565.5 1774.4 1913.7 1922.8 8537.7 8862.9 9051.4 9057.8 9057.9 7875.3 8117.6 8121.6 8220.4 8878.7 2161.4 2199.2 2326.4 2413.9 2414.4 2399.8 2419.0 2421.2 2421.4 2421.4 2353.6 2412.8 2415.7 2416.2 2416.4 1947.9 2104.4 2411.4 2412.8 2420.2 2403.6 2404.1 2420.7 2427.1 2430.9 8977.8 9050.1 9056.9 9057.5 9077.0 5267.8 5391.8 5449.5 5449.8 5452.9 3579.6 3686.7 3706.9 3712.5 3746.6 1075.2 1086.6 1094.5 1121.6 1122.7 11919.3 11995.3 11996.6 12003.4 12017.9 3851.7 4702.3 4820.4 4852.2 4852.3 9962.8 10023.3 10032.9 10043.8 10046.4 5391.8 5393.7 5394.3 5394.9 5402.7 4795.7 4958.1 4959.4 4979.9 4980.8 3539.0 3647.5 3780.5 3819.2 3890.6 1168.3 1170.4 1177.3 1178.6 1178.7 830.4 846.3 855.4 877.7 973.1 5826.4 6114.3 6120.6 6170.8 6216.4 951.5 973.1 980.5 981.9 1021.8 4061.8 4099.7 4130.9 4183.0 4215.4 10399.7 11151.6 11158.4 11158.9 11159.4 10379.3 10951.0 11096.3 11104.4 11116.7 8182.4 8397.3 8413.3 8415.3 8426.8 5969.5 5970.2 6103.5 6258.0 6354.1 9872.0 9876.0 10321.8 10371.1 10397.5 10413.6 10448.1 10452.1 10467.7 10473.8 6974.3 7532.1 7578.4 7589.7 7733.0 7846.8 8306.3 8538.2 8540.9 8653.2 7578.9 7645.3 7676.2 7775.3 7812.0 7488.3 7517.2 7556.0 7649.8 7700.5 7083.6 7117.8 7118.0 7199.9 7202.4 4924.3 5164.4 5200.1 5203.9 5229.8 4445.6 5150.1 5180.5 5222.7 5350.7 04:52:24 INFO - PROCESS | 1656 | 04:52:24 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4649.35 Median:5251.41 stddev:1443.69 (27.5%) stddev-sans-first:1410.29 04:52:24 INFO - PROCESS | 1656 | Values: 296.9 302.8 312.2 314.3 314.4 4400.6 5530.3 5536.0 5538.0 5546.3 4976.5 5266.0 5435.8 5461.6 5469.3 3936.9 4142.6 4147.5 4185.2 4193.6 3803.0 3865.8 4124.5 4180.4 4210.6 4049.0 4064.6 4073.4 4088.5 4091.4 3473.9 3779.8 4076.4 4082.6 4105.5 3239.7 3479.4 3543.3 3550.7 3561.5 4411.6 5050.7 5306.8 5360.2 5366.5 4034.5 4141.6 4545.8 4634.5 4694.5 4569.9 5718.1 5752.4 5757.7 5772.9 3669.9 3889.5 3911.4 3913.5 3925.0 4861.9 6013.7 6024.6 6075.4 6141.5 3998.1 4006.8 4007.3 4011.7 4028.8 3541.3 3767.8 3985.9 4011.1 4039.0 4425.2 4629.8 4667.6 4688.5 4746.7 5504.8 5860.0 5860.2 5875.8 5882.7 1284.1 1329.7 1342.5 1343.5 1370.6 810.8 890.8 893.0 912.2 923.6 4906.1 4916.9 5015.6 5360.4 5365.9 5849.7 5861.4 5873.7 5907.0 5927.4 5152.5 5312.4 5327.6 5352.7 5446.9 3079.4 3120.7 3137.6 3137.6 3184.7 5877.7 6194.4 6225.0 6232.3 6271.9 5196.2 5632.7 5694.2 5737.5 6203.0 5194.8 5796.5 6238.3 6243.4 6296.3 4224.8 5655.6 5769.0 5990.7 6065.5 5000.5 5415.4 5720.8 5909.4 5976.8 5236.8 5695.3 5758.6 6155.6 6230.7 3746.6 3920.9 3927.3 3972.9 3987.3 5733.9 6028.9 6054.9 6093.3 6116.8 4698.4 4997.2 5045.0 5403.3 5687.5 4592.5 4802.0 5730.0 5749.7 5752.0 5444.0 5633.5 5658.0 5709.6 5754.2 5108.1 5347.7 5798.9 5821.8 5844.4 5707.0 5722.9 5741.6 5747.6 5792.0 04:52:24 INFO - PROCESS | 1656 | 04:52:24 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:25219.97 Median:3468.98 stddev:41042.94 (1183.1%) stddev-sans-first:41116.80 04:52:24 INFO - PROCESS | 1656 | Values: 702.5 776.1 807.5 819.1 823.3 14939.2 16197.6 16220.8 16246.9 16258.4 14554.3 14679.7 14751.1 14767.8 14791.0 674.1 697.4 698.0 830.1 869.4 861.1 866.0 867.9 868.4 868.7 896.5 916.6 916.6 917.2 917.9 693.6 729.3 730.2 912.5 913.5 547.0 636.4 675.0 677.5 684.7 14666.1 14690.0 14717.0 14721.8 14721.8 13620.7 13776.3 13818.4 13864.4 13963.6 12732.3 12779.4 12845.0 12943.7 12979.6 4522.5 4587.9 4594.2 4753.9 4769.6 111417.0 113818.0 116286.8 116341.8 118584.6 10996.3 11079.6 11082.5 11194.6 11575.7 21759.8 21763.9 21772.5 21781.0 21798.6 7938.9 7968.2 7984.3 7985.0 8157.3 4428.1 4457.7 4478.1 4627.9 4631.7 3353.4 3459.8 3468.3 3469.7 3471.3 602.3 627.8 637.0 642.1 658.9 592.5 621.7 629.7 637.8 645.4 8585.8 8976.7 8979.0 8996.1 9009.5 518.4 537.8 597.8 612.0 634.2 4264.2 4388.4 4403.8 4477.7 4495.2 150150.0 154412.0 155336.2 156601.2 161389.2 119878.0 137098.0 137643.0 138251.0 138709.0 106514.4 113686.4 117855.0 118684.0 122011.0 8051.6 8547.0 8550.9 8623.8 8855.5 63584.4 64385.7 65550.7 65934.0 66374.0 76413.6 81635.0 83392.0 84969.0 89926.0 11230.4 11287.8 11383.8 11426.7 11909.9 12117.6 12178.5 12252.7 12262.2 12784.6 8757.9 11648.7 11813.9 12303.4 12433.6 11574.4 12659.4 13403.5 14293.2 14475.6 13347.9 13436.0 13796.3 13973.9 14113.4 11198.0 11321.2 11407.7 11470.7 11637.9 9745.0 10014.9 11500.7 11610.9 11637.9 04:52:24 INFO - PROCESS | 1656 | -------- Summary: end -------- 04:52:24 INFO - PROCESS | 1656 | 04:52:25 INFO - TEST-INFO | 1656: exit 0 04:52:25 INFO - TEST-OK | dromaeo_css | took 1116846ms 04:52:25 INFO - TEST-START | kraken 04:52:25 INFO - operating with platform_type : mac_ 04:52:25 INFO - Initialising browser for kraken test... 04:52:25 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpeYSBDv/profile http://localhost:49269/getInfo.html) 04:52:27 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 04:52:27 INFO - PROCESS | 1663 | colorDepth:24 04:52:27 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 04:52:27 INFO - PROCESS | 1663 | __metrics 04:52:27 INFO - PROCESS | 1663 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:52:27 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:52:28 INFO - TEST-INFO | 1663: exit 0 04:52:28 INFO - Browser initialized. 04:52:28 INFO - Running cycle 1/1 for kraken test... 04:52:28 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpeYSBDv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:52:39 INFO - PROCESS | 1664 | RSS: Main: 244805632 04:52:39 INFO - PROCESS | 1664 | 04:52:42 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection) 04:52:43 INFO - PROCESS | 1664 | RSS: Main: 294318080 04:52:43 INFO - PROCESS | 1664 | 04:52:46 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-dft) 04:52:46 INFO - PROCESS | 1664 | RSS: Main: 304422912 04:52:46 INFO - PROCESS | 1664 | 04:52:50 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-fft) 04:52:50 INFO - PROCESS | 1664 | RSS: Main: 390496256 04:52:50 INFO - PROCESS | 1664 | 04:52:52 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator) 04:52:53 INFO - PROCESS | 1664 | RSS: Main: 291913728 04:52:53 INFO - PROCESS | 1664 | 04:52:55 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:52:56 INFO - PROCESS | 1664 | RSS: Main: 296501248 04:52:56 INFO - PROCESS | 1664 | 04:52:58 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom) 04:52:59 INFO - PROCESS | 1664 | RSS: Main: 332345344 04:52:59 INFO - PROCESS | 1664 | 04:53:02 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate) 04:53:02 INFO - PROCESS | 1664 | RSS: Main: 334565376 04:53:02 INFO - PROCESS | 1664 | 04:53:05 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial) 04:53:06 INFO - PROCESS | 1664 | RSS: Main: 332513280 04:53:06 INFO - PROCESS | 1664 | 04:53:07 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:53:08 INFO - PROCESS | 1664 | RSS: Main: 311910400 04:53:08 INFO - PROCESS | 1664 | 04:53:10 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:53:11 INFO - PROCESS | 1664 | RSS: Main: 319242240 04:53:11 INFO - PROCESS | 1664 | 04:53:13 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:53:13 INFO - PROCESS | 1664 | RSS: Main: 323297280 04:53:13 INFO - PROCESS | 1664 | 04:53:16 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:53:17 INFO - PROCESS | 1664 | RSS: Main: 322912256 04:53:17 INFO - PROCESS | 1664 | 04:53:20 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:53:21 INFO - PROCESS | 1664 | RSS: Main: 323497984 04:53:21 INFO - PROCESS | 1664 | 04:53:22 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49269/tests/kraken/driver.html?testName=ai-astar) 04:53:23 INFO - PROCESS | 1664 | RSS: Main: 367390720 04:53:23 INFO - PROCESS | 1664 | 04:53:23 INFO - PROCESS | 1664 | __start_tp_report 04:53:23 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 04:53:23 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 04:53:23 INFO - PROCESS | 1664 | |i|pagename|runs| 04:53:23 INFO - PROCESS | 1664 | |0;ai-astar;101;112;112;112;112;110;113;113;113;113 04:53:23 INFO - PROCESS | 1664 | |1;audio-beat-detection;147;91;124;197;94;93;133;131;174;134 04:53:23 INFO - PROCESS | 1664 | |2;audio-dft;183;185;182;182;184;184;183;181;183;184 04:53:23 INFO - PROCESS | 1664 | |3;audio-fft;81;71;58;108;58;58;62;64;95;56 04:53:23 INFO - PROCESS | 1664 | |4;audio-oscillator;86;87;91;72;72;70;98;70;67;100 04:53:23 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;89;87;91;86;89;85;91;87;89;86 04:53:23 INFO - PROCESS | 1664 | |6;imaging-darkroom;95;95;94;96;95;94;95;97;95;95 04:53:23 INFO - PROCESS | 1664 | |7;imaging-desaturate;87;84;85;85;84;84;86;85;86;86 04:53:23 INFO - PROCESS | 1664 | |8;json-parse-financial;55;55;57;55;57;56;57;54;54;54 04:53:23 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;64;65;77;81;73;75;75;69;69;71 04:53:23 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;81;84;83;86;85;84;84;87;88;107 04:53:23 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;154;153;156;133;136;135;135;140;149;149 04:53:23 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;163;163;165;162;145;201;218;212;160;162 04:53:23 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;66;66;66;66;65;67;63;75;82;82 04:53:23 INFO - PROCESS | 1664 | __end_tp_report 04:53:23 INFO - PROCESS | 1664 | __start_cc_report 04:53:23 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,2031 04:53:23 INFO - PROCESS | 1664 | __end_cc_report 04:53:23 INFO - PROCESS | 1664 | __startTimestamp1467460403122__endTimestamp 04:53:23 INFO - PROCESS | 1664 | 04:53:23 INFO - PROCESS | 1664 | ------- Summary: start ------- 04:53:23 INFO - PROCESS | 1664 | Number of tests: 14 04:53:23 INFO - PROCESS | 1664 | 04:53:23 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:111.10 Median:112.50 stddev:3.67 (3.3%) stddev-sans-first:0.97 04:53:23 INFO - PROCESS | 1664 | Values: 101.0 112.0 112.0 112.0 112.0 110.0 113.0 113.0 113.0 113.0 04:53:23 INFO - PROCESS | 1664 | 04:53:23 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:131.80 Median:185.50 stddev:34.85 (18.8%) stddev-sans-first:36.53 04:53:23 INFO - PROCESS | 1664 | Values: 147.0 91.0 124.0 197.0 94.0 93.0 133.0 131.0 174.0 134.0 04:53:23 INFO - PROCESS | 1664 | 04:53:23 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:183.10 Median:183.50 stddev:1.20 (0.7%) stddev-sans-first:1.27 04:53:23 INFO - PROCESS | 1664 | Values: 183.0 185.0 182.0 182.0 184.0 184.0 183.0 181.0 183.0 184.0 04:53:23 INFO - PROCESS | 1664 | 04:53:23 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:71.10 Median:63.00 stddev:17.97 (28.5%) stddev-sans-first:18.70 04:53:23 INFO - PROCESS | 1664 | Values: 81.0 71.0 58.0 108.0 58.0 58.0 62.0 64.0 95.0 56.0 04:53:23 INFO - PROCESS | 1664 | 04:53:23 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:81.30 Median:79.00 stddev:12.52 (15.8%) stddev-sans-first:13.16 04:53:23 INFO - PROCESS | 1664 | Values: 86.0 87.0 91.0 72.0 72.0 70.0 98.0 70.0 67.0 100.0 04:53:23 INFO - PROCESS | 1664 | 04:53:23 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:88.00 Median:89.00 stddev:2.11 (2.4%) stddev-sans-first:2.20 04:53:23 INFO - PROCESS | 1664 | Values: 89.0 87.0 91.0 86.0 89.0 85.0 91.0 87.0 89.0 86.0 04:53:23 INFO - PROCESS | 1664 | 04:53:23 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:95.10 Median:95.00 stddev:0.88 (0.9%) stddev-sans-first:0.93 04:53:23 INFO - PROCESS | 1664 | Values: 95.0 95.0 94.0 96.0 95.0 94.0 95.0 97.0 95.0 95.0 04:53:23 INFO - PROCESS | 1664 | 04:53:23 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:85.50 stddev:1.03 (1.2%) stddev-sans-first:0.87 04:53:23 INFO - PROCESS | 1664 | Values: 87.0 84.0 85.0 85.0 84.0 84.0 86.0 85.0 86.0 86.0 04:53:23 INFO - PROCESS | 1664 | 04:53:23 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.50 stddev:1.26 (2.3%) stddev-sans-first:1.33 04:53:23 INFO - PROCESS | 1664 | Values: 55.0 55.0 57.0 55.0 57.0 56.0 57.0 54.0 54.0 54.0 04:53:23 INFO - PROCESS | 1664 | 04:53:23 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:74.00 stddev:5.34 (7.2%) stddev-sans-first:4.84 04:53:23 INFO - PROCESS | 1664 | Values: 64.0 65.0 77.0 81.0 73.0 75.0 75.0 69.0 69.0 71.0 04:53:23 INFO - PROCESS | 1664 | 04:53:23 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:84.50 stddev:7.34 (8.7%) stddev-sans-first:7.47 04:53:23 INFO - PROCESS | 1664 | Values: 81.0 84.0 83.0 86.0 85.0 84.0 84.0 87.0 88.0 107.0 04:53:23 INFO - PROCESS | 1664 | 04:53:23 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:144.00 Median:149.00 stddev:9.06 (6.1%) stddev-sans-first:8.85 04:53:23 INFO - PROCESS | 1664 | Values: 154.0 153.0 156.0 133.0 136.0 135.0 135.0 140.0 149.0 149.0 04:53:23 INFO - PROCESS | 1664 | 04:53:23 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.10 Median:164.00 stddev:25.27 (15.4%) stddev-sans-first:26.42 04:53:23 INFO - PROCESS | 1664 | Values: 163.0 163.0 165.0 162.0 145.0 201.0 218.0 212.0 160.0 162.0 04:53:23 INFO - PROCESS | 1664 | 04:53:23 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:66.50 stddev:7.15 (10.7%) stddev-sans-first:7.45 04:53:23 INFO - PROCESS | 1664 | Values: 66.0 66.0 66.0 66.0 65.0 67.0 63.0 75.0 82.0 82.0 04:53:23 INFO - PROCESS | 1664 | -------- Summary: end -------- 04:53:23 INFO - PROCESS | 1664 | 04:53:23 INFO - TEST-INFO | 1664: exit 0 04:53:23 INFO - TEST-OK | kraken | took 58903ms 04:53:23 INFO - SUITE-END | took 1175s 04:53:24 INFO - Completed test suite (00:19:36) 04:53:24 INFO - javascript benchmark 04:53:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3173.206698325419, 3407.3526030054286, 3427.5886893572765, 3440.3291358185165, 3468.9479657805136, 5722.227499287595, 5775.191473193756, 5818.679972601505, 5927.792527261547, 5929.258842644669, 5782.074868021117, 5849.444302791249, 5917.408259174081, 5925.34821169671, 5996.980513312737, 5166.225066240076, 5219.660722053073, 5262.526372626463, 5270.262163297122, 5397.136458166694, 5373.623846330758, 5389.218563308318, 5395.541378982791, 5425.050616142179, 5460.290162278918, 5115.846524604268, 5193.740312984354, 5242.397124330712, 5249.8425047248265, 5283.894322113536, 5282.603804714662, 5286.629935904488, 5288.762005709752, 5292.179712144623, 5299.390570084451, 5233.215017747369, 5269.499397557226, 5293.258943747878, 5295.973520132413, 5320.281761962125, 4899.583535399495, 5848.590598658096, 5849.38581448946, 5857.619254748471, 5864.384239654817, 6218.440340369389, 6236.345183755686, 6251.562390632657, 6253.6873156342, 6259.0, 4994.850154495371, 6260.749570017194, 6281.026440901667, 6287.402696743802, 6330.177076979963, 5885.793997210163, 6011.30869949957, 6083.66539840313, 6092.238470191226, 6101.969490152521, 6091.66495842724, 6276.246850377893, 6316.494680425555, 6322.873542529123, 6391.07329437229, 6273.247210334789, 6281.780137695158, 6285.717142728588, 6295.748170073238, 6313.905291420632, 6208.627482351073, 6298.307186209513, 6334.493240540745, 6336.714847831859, 6338.334474880164, 6198.473129783929, 6217.347178546278, 6252.874942501216, 6276.152719382825, 6277.591956522902, 5798.985177593904, 5898.64608123514, 6249.218847644044, 6264.060390941395, 6276.780312689034, 5043.445221025684, 5053.2420136979745, 5062.0, 5087.592992560586, 5103.132467480537, 4566.589006989387, 4574.88562785933, 4589.0, 4622.098690755338, 4627.167109920179, 5907.586468947133, 5914.58597898143, 5923.822285331447, 5994.730237139339, 6015.368386319461, 6232.846923319201, 6279.623222606659, 6288.5598008138995, 6300.30696623371, 6316.873662526724, 5806.332271788714, 5820.592558520948, 5864.9736296148, 5927.8221653350465, 5973.522118230531, 5557.805476808292, 5557.833265002057, 5570.108782594765, 5586.329640443091, 5636.577256705681, 6375.936240637627, 6436.7425302988295, 6452.580582262138, 6454.644994525158, 6473.741050357933, 6162.476189523941, 6238.126662267377, 6288.905666415099, 6306.0, 6342.334054924167, 6424.614523128627, 6444.194475690539, 6456.0, 6463.030545418225, 6480.189976252968, 6332.905006424815, 6353.52348573868, 6372.522060845363, 6373.553851230369, 6379.904301435389, 6169.383061693795, 6198.0, 6241.750329986749, 6263.154474145932, 6331.493480521455, 6260.624362538262, 6313.2424109107205, 6313.463355614824, 6314.210723659543, 6329.968350158219, 6247.843803904939, 6253.093301471352, 6265.624062556261, 6274.0, 6278.0269058296035, 6001.579889407874, 6081.209442772411, 6086.573939824347, 6103.216452666926, 6152.015677491759, 6279.309275979547, 6288.434040936338, 6290.182276304051, 6292.748290068346, 6297.590656607306, 6018.518518518596, 6137.3862613738265, 6190.628562286277, 6194.938050619436, 6214.689265536615], "value": 5806.660896220295, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.495727050691, 503.40598094248793, 530.7903378165624, 538.4534697282256, 552.0174090119588, 30156.547651785215, 43277.0, 44643.0, 44667.32999005014, 44703.77648111763, 43938.7803060985, 44111.897202569955, 44189.558104418946, 44244.55755442445, 44251.0, 8616.0, 9609.759756006104, 9788.804223915517, 9801.460919649417, 9804.803903921917, 8559.614817333204, 11776.7644647107, 11998.160128790989, 12005.759884802299, 12249.693757656041, 12061.819072713872, 12131.514739410457, 12145.939270303636, 12148.3318417487, 12154.33151176685, 8681.652733890636, 8847.24798392131, 11774.764504709901, 11906.22609530382, 11960.401979901057, 8750.781230469289, 8809.691660791841, 11454.599089031928, 11765.0, 11772.705682357924, 41301.76094717133, 41630.0, 41684.58315416837, 41793.0, 41847.1630567387, 3550.7336949728874, 3583.008677831166, 3588.4796704477703, 3755.3525902824135, 4156.7921603919685, 31523.527147092813, 32670.836645816857, 33378.1655458613, 33380.33239335224, 33406.49890251648, 3256.2510622556706, 3257.837108144607, 3423.863045478178, 3454.101933497284, 3455.0498612881406, 512276.0, 513374.0, 513509.4324528391, 513855.0, 514270.0, 56652.15021774677, 59748.0, 59882.401175988554, 59973.0, 60160.0, 64096.0, 64259.35740642534, 64380.034299485545, 64478.677606611665, 64532.35467645357, 15957.484039016275, 16395.42616008434, 16437.67124657524, 16476.588085297724, 16503.6699266014, 11535.826962595567, 11773.99921006724, 12619.179753316004, 12705.809412858815, 12887.355632218352, 14042.929785351007, 16697.831151819257, 16742.83257167437, 16847.663046738995, 16878.578035548962, 6897.827554311182, 6917.717153339386, 7206.639668016578, 7236.986821844946, 7246.384057355184, 889.7375274294099, 989.3322007644811, 1002.3735165521549, 1018.4754851251612, 1026.045777427, 26659.0, 30266.54600180999, 30738.077857664306, 31256.0, 31275.499034768174, 1034.5189486888482, 1057.582255009267, 1064.7125276175389, 1095.917806164541, 1210.5399948019697, 12185.573504927463, 15341.539753807403, 15792.973456725276, 16144.75782863258, 16170.676586468206, 650741.0, 654150.0, 659438.0, 659707.0, 660622.6968865125, 59833.401665983656, 60060.39939600635, 60290.0, 60300.39699602948, 60614.696926515964, 1249.631358749171, 1377.2012232905092, 1395.288402914521, 1425.2018869432736, 1425.3016022149072, 31685.841570792, 31752.0, 32044.878429254863, 32828.835855820565, 32945.505817412275, 51267.974640507724, 57010.714946425, 58345.416545834, 58476.707616461645, 61294.69352653387, 606789.0, 612765.0, 618359.0, 619809.0, 619884.0, 23880.04479820857, 26604.201873943814, 26714.59928101041, 27081.593776093767, 27304.453910922068, 48774.512254876994, 49806.75096624494, 50799.49200508095, 50890.74554627203, 51008.0, 38311.0, 40872.59127408807, 40890.591094088675, 41595.37606935959, 41679.95162442947, 38599.42100868433, 40562.9859253521, 40800.3879941807, 42423.57576424196, 43961.0, 35052.29895402129, 36410.63589364073, 38801.0, 39335.803320983214, 39511.01222469461, 34244.0, 35424.46863297104, 35755.46366804552, 35810.0215794579, 36707.81646091752, 22600.208992685177, 23878.04487820537, 25561.105361312264, 25903.35241618899, 26330.0], "value": 20719.600573775453, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.43238277817716, 382.5963608393145, 383.20102586107964, 391.2175648702595, 404.47216382620684, 19815.20739170433, 22105.778942210574, 22200.88899555504, 22521.324360269176, 22762.0, 21075.26236581716, 21530.677039844413, 21783.45541361467, 22217.333479995625, 22257.55484890301, 5561.443855614437, 5779.133130030508, 5871.706414679249, 5877.412258774131, 5961.225040744697, 4120.464339635843, 4334.046509767846, 4339.305711086226, 4354.869353919387, 5787.50806181475, 4229.836794881417, 6478.935210647904, 6498.090267362573, 6532.738690452376, 6552.672366381686, 5645.774169033213, 6403.48772098231, 6507.186601674767, 6510.837229069288, 6522.836929076763, 3665.9816700916326, 5528.530074943635, 5630.577706672016, 5663.235463212455, 5675.120356344773, 24929.0, 25975.0, 26142.6078608821, 28026.719732802616, 28056.0, 2184.159098746994, 2202.867827930313, 2214.8228141748623, 2256.1030224017954, 3096.6593674695764, 15755.44855930037, 16153.83846161535, 16350.75473867893, 16382.590435239095, 16735.995840249623, 1864.8974306413143, 1867.253098760493, 1869.6634605770955, 1923.5536165782642, 1985.4540001499558, 120817.79182208153, 123719.0, 126311.68441577884, 127791.0, 128819.0, 9251.67619133327, 9361.719148425558, 9372.343935924557, 10080.949595251977, 10840.78318433627, 10503.894961050444, 12535.0, 12586.43361048755, 12587.30769807651, 12620.621381358575, 7899.763007109796, 8060.556669383123, 8063.314618257396, 8176.713815016446, 8182.263596276363, 8466.74599762008, 8472.872906906401, 8491.065982741893, 8589.098144694843, 8635.568221589021, 3266.2161081340782, 3929.2927273090545, 3948.3287841067086, 3987.5613682494904, 3998.4402183694306, 1880.6896862017888, 1940.282095624628, 1950.590376021023, 1956.8728032678116, 1957.6574099532525, 864.822711344173, 901.9368644194975, 917.4082716647858, 975.5024937282037, 996.496769131584, 21375.46561335979, 22298.331050068526, 22328.218512352316, 22361.664575031387, 22469.550608987727, 929.8837645294338, 992.0922356044139, 999.5402115027024, 1006.9848952265722, 1037.4138611684375, 9620.71137865865, 11582.420878956018, 11730.12024098196, 11916.046716262852, 11976.520939162336, 517660.0, 590936.0, 595747.0, 603239.0, 612641.0, 91635.1672966537, 113242.0, 130290.34854825665, 130631.0, 133076.33461832628, 940.8541676040225, 941.5339406993445, 968.2205824311496, 970.684527528556, 972.9221662267001, 18880.867147971167, 19774.295107406604, 20121.59756804885, 20211.393658190278, 20286.59426811426, 44621.77689111534, 54058.18912716391, 54653.1802022962, 55452.44547554473, 56187.438125618224, 80768.59615701884, 80829.19170808217, 81986.72147323306, 82287.0, 83065.0, 8044.959775201087, 8056.033276006917, 8227.177282271725, 8250.793730156794, 8374.790630234293, 10801.297915635458, 12202.75594488123, 13090.345482725636, 13149.276789776271, 13214.603561893158, 10665.520051597852, 11463.885361146615, 11468.598599048993, 11505.769884602094, 11569.7107572308, 11189.944050280023, 11243.437828108725, 11295.95009347488, 11440.427978601203, 11569.958703716706, 11400.828987565028, 11624.244424112405, 11773.234739741889, 11781.116416268986, 11789.0, 8095.2714255717265, 8130.105688374392, 8162.18378162179, 8170.223828736261, 8304.501729896225, 11131.10951123892, 11348.978591926767, 11415.315081095161, 11495.597654082067, 11559.364234967157], "value": 9658.537587958966, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1535.6007438066106, 1565.4971227621484, 1774.3878361965117, 1913.674675305198, 1922.826945574898, 8537.658493660254, 8862.91137088629, 9051.411658242227, 9057.773555661104, 9057.864132038025, 7875.330596899271, 8117.63470643821, 8121.593920303991, 8220.424570280084, 8878.689245876396, 2161.3948094533516, 2199.230269405703, 2326.3951372643064, 2413.8551686898754, 2414.4326083370484, 2399.8200134989856, 2418.9879050604723, 2421.2010036687852, 2421.370443684646, 2421.4430680943306, 2353.6116540770754, 2412.8190385721055, 2415.722191947922, 2416.1664225842055, 2416.395901024744, 1947.8539109566768, 2104.442322784456, 2411.4212588978694, 2412.7949124324455, 2420.1771397724683, 2403.6154215325464, 2404.110479122736, 2420.697412823397, 2427.077710470028, 2430.939226519351, 8977.775555611097, 9050.140236677571, 9056.864147037799, 9057.547122643842, 9076.954615226881, 5267.762950667191, 5391.81128660495, 5449.455054494559, 5449.836504904859, 5452.91820622691, 3579.570451545805, 3686.705063594906, 3706.944395834065, 3712.4988126602834, 3746.6253374662588, 1075.1936047964027, 1086.5816660585651, 1094.4637127807316, 1121.624255874283, 1122.6688127002471, 11919.284842909454, 11995.28028318295, 11996.58011969577, 12003.399830008464, 12017.93991030048, 3851.6533511983494, 4702.3416721659, 4820.4456487503685, 4852.223644216979, 4852.272159176152, 9962.800743985079, 10023.348482348623, 10032.949835250776, 10043.849342259871, 10046.447445390428, 5391.78432862689, 5393.6763794172475, 5394.298741163623, 5394.91907621386, 5402.6758394496455, 4795.664303498791, 4958.107540642647, 4959.404871415453, 4979.850604481871, 4980.825671101494, 3539.0444579963264, 3647.507586475845, 3780.546334439885, 3819.1597848473293, 3890.6109389061435, 1168.3048586091263, 1170.3504554971903, 1177.2818580665787, 1178.5816035307514, 1178.7406770510474, 830.4021104804531, 846.2933450568721, 855.4354126276628, 877.7322916510526, 973.1484950668165, 5826.359100498942, 6114.296855861633, 6120.632762034204, 6170.81487555374, 6216.409441103088, 951.4974426645947, 973.1339108193713, 980.529345913951, 981.9018098190123, 1021.7598864266891, 4061.817218225187, 4099.651529619956, 4130.896727581774, 4183.0, 4215.409842622035, 10399.688009359732, 11151.553937842558, 11158.442077896072, 11158.884111588775, 11159.442027898573, 10379.325343852626, 10951.0, 11096.278741881752, 11104.389258590694, 11116.66650000485, 8182.427230093956, 8397.328213743009, 8413.284870786052, 8415.284700800501, 8426.789330266793, 5969.462748352669, 5970.164177015135, 6103.542234332354, 6257.9687101564205, 6354.110424540476, 9872.0, 9875.950620246853, 10321.845172322272, 10371.118454931415, 10397.53211105487, 10413.583456661649, 10448.05967462932, 10452.111570516592, 10467.685969420929, 10473.790524189626, 6974.267701891228, 7532.058492688414, 7578.43161762881, 7589.734359297398, 7732.961335193288, 7846.803829904298, 8306.335493160654, 8538.231559159705, 8540.871886921577, 8653.17794809492, 7578.886316705364, 7645.311921927053, 7676.193999629959, 7775.261350171724, 7811.984442022728, 7488.288612581796, 7517.2482751726575, 7556.0177176966645, 7649.847003060019, 7700.460967732205, 7083.610401427764, 7117.82205444868, 7117.964410177916, 7199.8560028800175, 7202.351788339075, 4924.276503223986, 5164.431912489548, 5200.089984252665, 5203.869903252449, 5229.790808367775, 4445.599896009375, 5150.098732721834, 5180.533751962342, 5222.660527065728, 5350.732463376893], "value": 4661.502406273645, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.87531236880494, 302.750231059376, 312.1976520342719, 314.2505125276218, 314.3571396494169, 4400.559944005598, 5530.281063461749, 5536.0, 5537.972310138443, 5546.334439867216, 4976.452590215083, 5266.0, 5435.847208619001, 5461.644993075447, 5469.316335458067, 3936.881893543198, 4142.606452387018, 4147.523034850986, 4185.162967406515, 4193.62257396835, 3802.9619703803023, 3865.8067096645195, 4124.484439445069, 4180.435641188431, 4210.5578914213875, 4048.9797551012202, 4064.5732198119217, 4073.40935564343, 4088.5093788745394, 4091.4476545666253, 3473.888355726169, 3779.7888235382197, 4076.3885417187657, 4082.5509193988637, 4105.486814148231, 3239.72462340699, 3479.408500554912, 3543.291341731664, 3550.6626870447526, 3561.5370001899846, 4411.602955733999, 5050.686821426419, 5306.787728490855, 5360.2227676987, 5366.543843773284, 4034.4553485279407, 4141.64795992341, 4545.840895568639, 4634.520531515623, 4694.460137084211, 4569.8521694803385, 5718.141603978724, 5752.36723960364, 5757.740901659394, 5772.855678608026, 3669.9266014679556, 3889.4749208856724, 3911.3741801311653, 3913.471681323042, 3924.960750392488, 4861.878453038702, 6013.669248191348, 6024.578279520435, 6075.362086980847, 6141.495333643286, 3998.140399814054, 4006.779627120536, 4007.338789099824, 4011.6590089842107, 4028.8388464461095, 3541.3448512025357, 3767.773933563995, 3985.900352491211, 4011.1376054147913, 4039.0, 4425.203463376559, 4629.837955671535, 4667.603253723403, 4688.507706690816, 4746.715197088186, 5504.834854954358, 5859.970700146472, 5860.208871802337, 5875.823725288248, 5882.735276912549, 1284.113961366654, 1329.7008173160962, 1342.4764341906664, 1343.4626149540068, 1370.6093763277418, 810.7878721311677, 890.8173824365989, 892.9776755581163, 912.2018234045211, 923.6259314977444, 4906.1414252505665, 4916.926246106311, 5015.598752099823, 5360.437154098763, 5365.919511207335, 5849.736761845727, 5861.384554621789, 5873.735681894325, 5907.0, 5927.3776253493625, 5152.536271735562, 5312.362516498045, 5327.600429967768, 5352.652077614943, 5446.863828404243, 3079.414911166871, 3120.6567277599447, 3137.5607414961923, 3137.623485181747, 3184.710192372093, 5877.676727779927, 6194.4115309045565, 6225.0431045254045, 6232.283287421914, 6271.874562508816, 5196.246544251062, 5632.690202038845, 5694.231278777312, 5737.454941780524, 6203.0, 5194.792208311701, 5796.536277097822, 6238.344973777869, 6243.43809057187, 6296.33888441707, 4224.810268247454, 5655.575831812713, 5768.971155144198, 5990.7304171311525, 6065.4541091302, 5000.4749501303295, 5415.377231618257, 5720.8283751487525, 5909.409059094056, 5976.820695379146, 5236.816711415082, 5695.316562012585, 5758.596898217084, 6155.599886007395, 6230.657313847782, 3746.5878753337656, 3920.9019774505864, 3927.312720273998, 3972.9006774829704, 3987.268720926175, 5733.913991290217, 6028.854517641809, 6054.878902422015, 6093.299270583762, 6116.847078822887, 4698.436187657503, 4997.225430058346, 5045.0, 5403.270558474555, 5687.459691329317, 4592.517785632461, 4801.975990120027, 5730.017637741729, 5749.7125143741805, 5752.0, 5443.972780136074, 5633.549316054624, 5658.0, 5709.628874123168, 5754.194412782297, 5108.084468324379, 5347.705876176848, 5798.94201057984, 5821.835632873527, 5844.3571207168025, 5706.9714651426475, 5722.941255867678, 5741.569382296428, 5747.626404283708, 5792.0], "value": 4186.4782300065335, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.4871843554205, 776.1036003416053, 807.5154907055768, 819.066264458517, 823.2837431434651, 14939.17834519101, 16197.595060123504, 16220.83779162208, 16246.918765406186, 16258.430954916581, 14554.345054472547, 14679.706405871877, 14751.114933103994, 14767.778483322732, 14791.0, 674.1135406939883, 697.3549466743268, 698.0157977252076, 830.1159265382379, 869.4174902815129, 861.0700443521014, 865.9610317535695, 867.9479231246114, 868.3791089371099, 868.695956415256, 896.5069211933443, 916.5600511754362, 916.5966974531218, 917.179124683408, 917.9082091790801, 693.5769180799708, 729.3472342253706, 730.1749023603347, 912.4798864647154, 913.5067063785548, 547.0481362429384, 636.424036247197, 674.9595024298509, 677.474956908395, 684.6713577482786, 14666.120032798066, 14690.045147065406, 14716.969812113157, 14721.779173312409, 14721.779173312409, 13620.659483512893, 13776.311184440838, 13818.447262109503, 13864.376103075285, 13963.58109256724, 12732.299723515203, 12779.361031948365, 12844.972402207894, 12943.741125177443, 12979.610611681665, 4522.547745225484, 4587.8623641290815, 4594.19601569724, 4753.881152971168, 4769.570738633505, 111417.0, 113818.0, 116286.83713162845, 116341.83658163395, 118584.62830743424, 10996.340219586851, 11079.612213572485, 11082.501287442085, 11194.552217911356, 11575.652730418102, 21759.782402176093, 21763.911804409716, 21772.45568860791, 21781.019854106602, 21798.56402871934, 7938.920610793818, 7968.163342849033, 7984.321332686786, 7985.041794984523, 8157.306628936488, 4428.1365133799245, 4457.68796184264, 4478.104379124123, 4627.93557481781, 4631.675782695179, 3353.3796247693767, 3459.8270086496077, 3468.289000754839, 3469.6703813138215, 3471.2536804587135, 602.2532060245264, 627.7645882793952, 636.9936300636934, 642.0593830039015, 658.8649326887977, 592.4786188154396, 621.6984762390238, 629.6662768732579, 637.7576520922113, 645.3997782062727, 8585.785355366166, 8976.730698079067, 8979.0, 8996.145366190201, 9009.549522523848, 518.390890703422, 537.771447134966, 597.8116893178635, 611.9632822030692, 634.2294112653149, 4264.211120942636, 4388.407564978751, 4403.8018289176425, 4477.70894891831, 4495.213337665896, 150150.0, 154412.0, 155336.22331888493, 156601.2169939143, 161389.19305403632, 119878.0, 137098.0, 137643.0, 138251.0, 138709.0, 106514.40228396426, 113686.43156784163, 117855.0, 118683.99999999827, 122011.0, 8051.637676304464, 8547.0, 8550.914490855012, 8623.827523449621, 8855.468671879707, 63584.3641563597, 64385.67807160934, 65550.67224663847, 65934.0, 66374.0, 76413.61793190999, 81635.0, 83392.0, 84969.0, 89926.0, 11230.438478075901, 11287.830682539605, 11383.772324553629, 11426.657200284004, 11909.940450297692, 12117.57588484434, 12178.45196966121, 12252.693682657648, 12262.202956808136, 12784.61646150617, 8757.91242087571, 11648.708782280512, 11813.885137818785, 12303.446344914679, 12433.626991190278, 11574.421278935852, 12659.367031648197, 13403.530876419278, 14293.213873237071, 14475.638109047359, 13347.933260333637, 13435.992300577691, 13796.310184490534, 13973.930130349283, 14113.364898579794, 11197.992180703775, 11321.207515473838, 11407.657770266906, 11470.71323216893, 11637.88362116368, 9744.951275243578, 10014.898361180118, 11500.712482188012, 11610.88389116098, 11637.941810290895], "value": 7717.862960730177, "unit": "score"}], "name": "dromaeo_css", "value": 7479.216158287859, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 112.0, 112.0, 112.0, 110.0, 113.0, 113.0, 113.0, 113.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 91.0, 124.0, 197.0, 94.0, 93.0, 133.0, 131.0, 174.0, 134.0], "value": 131.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 182.0, 182.0, 184.0, 184.0, 183.0, 181.0, 183.0, 184.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 71.0, 58.0, 108.0, 58.0, 58.0, 62.0, 64.0, 95.0, 56.0], "value": 71.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 91.0, 72.0, 72.0, 70.0, 98.0, 70.0, 67.0, 100.0], "value": 81.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 91.0, 86.0, 89.0, 85.0, 91.0, 87.0, 89.0, 86.0], "value": 88.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 96.0, 95.0, 94.0, 95.0, 97.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 85.0, 85.0, 84.0, 84.0, 86.0, 85.0, 86.0, 86.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 57.0, 55.0, 57.0, 56.0, 57.0, 54.0, 54.0, 54.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 77.0, 81.0, 73.0, 75.0, 75.0, 69.0, 69.0, 71.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 83.0, 86.0, 85.0, 84.0, 84.0, 87.0, 88.0, 107.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 153.0, 156.0, 133.0, 136.0, 135.0, 135.0, 140.0, 149.0, 149.0], "value": 144.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 165.0, 162.0, 145.0, 201.0, 218.0, 212.0, 160.0, 162.0], "value": 175.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 66.0, 65.0, 67.0, 63.0, 75.0, 82.0, 82.0], "value": 69.8, "unit": "score"}], "name": "kraken", "value": 1449.8, "alertThreshold": 2.0}]} 04:53:24 INFO - Return code: 0 04:53:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:53:24 INFO - # TBPL SUCCESS # 04:53:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:53:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:53:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:53:24 INFO - 2016-07-02 04:53:24.376 screenresolution[1668:9928] starting screenresolution argv=screenresolution get 04:53:24 INFO - 2016-07-02 04:53:24.378 screenresolution[1668:9928] Display 0: 1600x1200x32@60 04:53:24 INFO - 2016-07-02 04:53:24.384 screenresolution[1669:9932] starting screenresolution argv=screenresolution list 04:53:24 INFO - Available Modes on Display 0 04:53:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:53:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:53:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:53:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:53:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:53:24 INFO - Intel Iris: 04:53:24 INFO - Chipset Model: Intel Iris 04:53:24 INFO - Type: GPU 04:53:24 INFO - Bus: Built-In 04:53:24 INFO - VRAM (Dynamic, Max): 1536 MB 04:53:24 INFO - Vendor: Intel (0x8086) 04:53:24 INFO - Device ID: 0x0a2e 04:53:24 INFO - Revision ID: 0x0009 04:53:24 INFO - Displays: 04:53:24 INFO - Display: 04:53:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:53:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:53:24 INFO - Display Serial Number: 1600x1200 60 04:53:24 INFO - Main Display: Yes 04:53:24 INFO - Mirror: Off 04:53:24 INFO - Online: Yes 04:53:24 INFO - Rotation: Supported 04:53:24 INFO - Return code: 0 04:53:24 INFO - Running post-action listener: _resource_record_post_action 04:53:24 INFO - ##### 04:53:24 INFO - ##### Finished run-tests step (success) 04:53:24 INFO - ##### 04:53:24 INFO - Running post-run listener: _resource_record_post_run 04:53:24 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 31641088; Write bytes: 703787520; Read time: 197; Write time: 1961 04:53:24 INFO - TinderboxPrint: CPU usage
53.4% 04:53:24 INFO - TinderboxPrint: I/O read bytes / time
31,641,088 / 197 04:53:24 INFO - TinderboxPrint: I/O write bytes / time
703,787,520 / 1,961 04:53:24 INFO - TinderboxPrint: CPU idle
2,260.3 (46.6%) 04:53:24 INFO - TinderboxPrint: CPU system
966.2 (19.9%) 04:53:24 INFO - TinderboxPrint: CPU user
1,619.1 (33.4%) 04:53:24 INFO - TinderboxPrint: Swap in / out
434,782,208 / 0 04:53:24 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392239616; Write bytes: 386102784; Read time: 29188; Write time: 806 04:53:24 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30719488; Write bytes: 294472704; Read time: 186; Write time: 1098 04:53:25 INFO - Running post-run listener: _upload_blobber_files 04:53:25 INFO - Blob upload gear active. 04:53:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:53:25 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 04:53:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:53:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:53:25 INFO - (blobuploader) - INFO - Open directory for files ... 04:53:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:53:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:53:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:53:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:53:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:53:26 INFO - (blobuploader) - INFO - Done attempting. 04:53:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:53:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:53:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:53:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:53:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:53:27 INFO - (blobuploader) - INFO - Done attempting. 04:53:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:53:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:53:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:53:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:53:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:53:29 INFO - (blobuploader) - INFO - Done attempting. 04:53:29 INFO - (blobuploader) - INFO - Iteration through files over. 04:53:29 INFO - Return code: 0 04:53:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:53:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:53:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ebba6ace17f354ebb337ec316ad91a37e75b3e398aa17a9ccdd10db30f652a367ada058c2b67e6d5be6b7f0616bbd9aba372c72ecbfa6edfedda909d166d8488", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1d1131fccb0fd01a66c95aef5769ac6ad81a769dbfa4b2f074898d0ae8b23372abe030d9636c852a4c7740ba17f1618b9f368c2220a293b40c6cf467e4ba8d2a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e31caadcfd0767ff7f50953730de39b7d9656ca3b935b53bbd221aa1317ff3f8bd306d635c12de78b29579867972b457aecf8e04d56dcff8464cdbf93fa23b31"} 04:53:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:53:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:53:29 INFO - Contents: 04:53:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ebba6ace17f354ebb337ec316ad91a37e75b3e398aa17a9ccdd10db30f652a367ada058c2b67e6d5be6b7f0616bbd9aba372c72ecbfa6edfedda909d166d8488", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1d1131fccb0fd01a66c95aef5769ac6ad81a769dbfa4b2f074898d0ae8b23372abe030d9636c852a4c7740ba17f1618b9f368c2220a293b40c6cf467e4ba8d2a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e31caadcfd0767ff7f50953730de39b7d9656ca3b935b53bbd221aa1317ff3f8bd306d635c12de78b29579867972b457aecf8e04d56dcff8464cdbf93fa23b31"} 04:53:29 INFO - Running post-run listener: copy_logs_to_upload_dir 04:53:29 INFO - Copying logs to upload dir... 04:53:29 INFO - mkdir: /builds/slave/test/build/upload/logs 04:53:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.976604 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-07-02 04:53:29.490837) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-02 04:53:29.494273) ========= 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.xLdOETKv81/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.HjXel97mCA/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ebba6ace17f354ebb337ec316ad91a37e75b3e398aa17a9ccdd10db30f652a367ada058c2b67e6d5be6b7f0616bbd9aba372c72ecbfa6edfedda909d166d8488", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1d1131fccb0fd01a66c95aef5769ac6ad81a769dbfa4b2f074898d0ae8b23372abe030d9636c852a4c7740ba17f1618b9f368c2220a293b40c6cf467e4ba8d2a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e31caadcfd0767ff7f50953730de39b7d9656ca3b935b53bbd221aa1317ff3f8bd306d635c12de78b29579867972b457aecf8e04d56dcff8464cdbf93fa23b31"} build_url:https://queue.taskcluster.net/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011018 build_url: 'https://queue.taskcluster.net/v1/task/BmHuL9IsQKy25VXgknLigQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ebba6ace17f354ebb337ec316ad91a37e75b3e398aa17a9ccdd10db30f652a367ada058c2b67e6d5be6b7f0616bbd9aba372c72ecbfa6edfedda909d166d8488", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1d1131fccb0fd01a66c95aef5769ac6ad81a769dbfa4b2f074898d0ae8b23372abe030d9636c852a4c7740ba17f1618b9f368c2220a293b40c6cf467e4ba8d2a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e31caadcfd0767ff7f50953730de39b7d9656ca3b935b53bbd221aa1317ff3f8bd306d635c12de78b29579867972b457aecf8e04d56dcff8464cdbf93fa23b31"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-02 04:53:29.530364) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 04:53:29.531142) ========= 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.xLdOETKv81/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.HjXel97mCA/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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.004950 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 04:53:29.583079) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-02 04:53:29.583778) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-02 04:53:29.584577) ========= ========= Total master_lag: 0.19 =========