builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0109 starttime: 1467995459.2 results: success (0) buildid: 20160708072926 builduid: 21cb6001df0c4e4e800df5bea85b3500 revision: 6cb3dc4bdbae8a9e36c2be441a59e39c8a391a03 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 09:30:59.199865) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 09:30:59.200435) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 09:30:59.200728) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 09:30:59.236281) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 09:30:59.236589) ========= 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.FSbHansihQ/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.fNMtZDMfb6/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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-08 09:30:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 11.8M=0.001s 2016-07-08 09:30:59 (11.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.361904 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 09:30:59.621290) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 09:30:59.621607) ========= 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.FSbHansihQ/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.fNMtZDMfb6/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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.028075 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 09:30:59.670992) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 09:30:59.671355) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 6cb3dc4bdbae8a9e36c2be441a59e39c8a391a03 --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 6cb3dc4bdbae8a9e36c2be441a59e39c8a391a03 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FSbHansihQ/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.fNMtZDMfb6/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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-08 09:30:59,745 truncating revision to first 12 chars 2016-07-08 09:30:59,745 Setting DEBUG logging. 2016-07-08 09:30:59,745 attempt 1/10 2016-07-08 09:30:59,745 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/6cb3dc4bdbae?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-08 09:31:01,032 unpacking tar archive at: fx-team-6cb3dc4bdbae/testing/mozharness/ program finished with exit code 0 elapsedTime=1.552091 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 09:31:01.245406) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 09:31:01.245716) ========= script_repo_revision: 6cb3dc4bdbae8a9e36c2be441a59e39c8a391a03 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 09:31:01.246194) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 09:31:01.246481) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 09:31:01.264821) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2016-07-08 09:31:01.265163) ========= /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.FSbHansihQ/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.fNMtZDMfb6/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:31:01 INFO - MultiFileLogger online at 20160708 09:31:01 in /builds/slave/test 09:31:01 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 09:31:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:31:01 INFO - {'append_to_log': False, 09:31:01 INFO - 'base_work_dir': '/builds/slave/test', 09:31:01 INFO - 'blob_upload_branch': 'Fx-Team', 09:31:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:31:01 INFO - 'branch': 'Fx-Team', 09:31:01 INFO - 'buildbot_json_path': 'buildprops.json', 09:31:01 INFO - 'config_files': ('talos/mac_config.py',), 09:31:01 INFO - 'default_actions': ('clobber', 09:31:01 INFO - 'read-buildbot-config', 09:31:01 INFO - 'download-and-extract', 09:31:01 INFO - 'populate-webroot', 09:31:01 INFO - 'create-virtualenv', 09:31:01 INFO - 'install', 09:31:01 INFO - 'run-tests'), 09:31:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:31:01 INFO - 'download_minidump_stackwalk': True, 09:31:01 INFO - 'download_symbols': 'ondemand', 09:31:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:31:01 INFO - 'tooltool.py': '/tools/tooltool.py', 09:31:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:31:01 INFO - '/tools/misc-python/virtualenv.py')}, 09:31:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:31:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:31:01 INFO - 'installer_path': 'installer.exe', 09:31:01 INFO - 'log_level': 'info', 09:31:01 INFO - 'log_name': 'talos', 09:31:01 INFO - 'log_to_console': True, 09:31:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:31:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:31:01 INFO - 'opt_config_files': (), 09:31:01 INFO - 'pip_index': False, 09:31:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:31:01 INFO - 'cmd': ('bash', 09:31:01 INFO - '-c', 09:31:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:31:01 INFO - 'enabled': True, 09:31:01 INFO - 'halt_on_failure': False, 09:31:01 INFO - 'name': 'check_screen_resolution'},), 09:31:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:31:01 INFO - 'cmd': ('bash', 09:31:01 INFO - '-c', 09:31:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:31:01 INFO - 'enabled': True, 09:31:01 INFO - 'halt_on_failure': False, 09:31:01 INFO - 'name': 'check_screen_resolution'},), 09:31:01 INFO - 'run_cmd_checks_enabled': True, 09:31:01 INFO - 'sps_profile': False, 09:31:01 INFO - 'sps_profile_interval': 0, 09:31:01 INFO - 'suite': 'dromaeojs', 09:31:01 INFO - 'system_bits': '32', 09:31:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:31:01 INFO - 'title': 't-yosemite-r7-0109', 09:31:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:31:01 INFO - 'use_talos_json': True, 09:31:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:31:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:31:01 INFO - 'work_dir': 'build'} 09:31:01 INFO - ##### 09:31:01 INFO - ##### Running clobber step. 09:31:01 INFO - ##### 09:31:01 INFO - Running pre-action listener: _resource_record_pre_action 09:31:01 INFO - Running main action method: clobber 09:31:01 INFO - rmtree: /builds/slave/test/build 09:31:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:31:03 INFO - Running post-action listener: _resource_record_post_action 09:31:03 INFO - ##### 09:31:03 INFO - ##### Finished clobber step (success) 09:31:03 INFO - ##### 09:31:03 INFO - ##### 09:31:03 INFO - ##### Running read-buildbot-config step. 09:31:03 INFO - ##### 09:31:03 INFO - Running pre-action listener: _resource_record_pre_action 09:31:03 INFO - Running main action method: read_buildbot_config 09:31:03 INFO - Using buildbot properties: 09:31:03 INFO - { 09:31:03 INFO - "project": "", 09:31:03 INFO - "product": "firefox", 09:31:03 INFO - "script_repo_revision": "production", 09:31:03 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:31:03 INFO - "repository": "", 09:31:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:31:03 INFO - "buildid": "20160708072926", 09:31:03 INFO - "pgo_build": "False", 09:31:03 INFO - "basedir": "/builds/slave/test", 09:31:03 INFO - "buildnumber": 839, 09:31:03 INFO - "slavename": "t-yosemite-r7-0109", 09:31:03 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:31:03 INFO - "platform": "yosemite_r7", 09:31:03 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:31:03 INFO - "branch": "fx-team", 09:31:03 INFO - "repo_path": "integration/fx-team", 09:31:03 INFO - "revision": "6cb3dc4bdbae8a9e36c2be441a59e39c8a391a03", 09:31:03 INFO - "stage_platform": "macosx64", 09:31:03 INFO - "builduid": "21cb6001df0c4e4e800df5bea85b3500", 09:31:03 INFO - "slavebuilddir": "test" 09:31:03 INFO - } 09:31:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:31:03 INFO - Running post-action listener: _resource_record_post_action 09:31:03 INFO - ##### 09:31:03 INFO - ##### Finished read-buildbot-config step (success) 09:31:03 INFO - ##### 09:31:03 INFO - ##### 09:31:03 INFO - ##### Running download-and-extract step. 09:31:03 INFO - ##### 09:31:03 INFO - Running pre-action listener: _resource_record_pre_action 09:31:03 INFO - Running main action method: download_and_extract 09:31:03 INFO - mkdir: /builds/slave/test/build/tests 09:31:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:03 INFO - https://queue.taskcluster.net/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:31:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:31:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:31:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:31:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 09:31:09 INFO - Downloaded 1199 bytes. 09:31:09 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:31:09 INFO - Using the following test package requirements: 09:31:09 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:31:09 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:31:09 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:31:09 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:31:09 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:31:09 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:31:09 INFO - u'jsshell-mac.zip'], 09:31:09 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:31:09 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:31:09 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:31:09 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:31:09 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:31:09 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:31:09 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:31:09 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:31:09 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:31:09 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:31:09 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:31:09 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:31:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:09 INFO - https://queue.taskcluster.net/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:31:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:31:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:31:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:31:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:31:21 INFO - Downloaded 18806395 bytes. 09:31:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:31:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:31:24 INFO - Return code: 0 09:31:24 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:31:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:24 INFO - https://queue.taskcluster.net/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:31:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:31:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:31:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:31:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:31:27 INFO - Downloaded 18806395 bytes. 09:31:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:31:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:31:30 INFO - Return code: 0 09:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:30 INFO - https://queue.taskcluster.net/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:31:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:31:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:31:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:31:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:31:31 INFO - Downloaded 14403333 bytes. 09:31:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:31:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:31:32 INFO - Return code: 0 09:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:32 INFO - https://queue.taskcluster.net/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:31:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:31:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:31:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:31:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 09:31:35 INFO - Downloaded 112470320 bytes. 09:31:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:31:35 INFO - mkdir: /builds/slave/test/properties 09:31:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:31:35 INFO - Writing to file /builds/slave/test/properties/build_url 09:31:35 INFO - Contents: 09:31:35 INFO - build_url:https://queue.taskcluster.net/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:31:35 INFO - Running post-action listener: _resource_record_post_action 09:31:35 INFO - Running post-action listener: set_extra_try_arguments 09:31:35 INFO - ##### 09:31:35 INFO - ##### Finished download-and-extract step (success) 09:31:35 INFO - ##### 09:31:35 INFO - ##### 09:31:35 INFO - ##### Running populate-webroot step. 09:31:35 INFO - ##### 09:31:35 INFO - Running pre-action listener: _resource_record_pre_action 09:31:35 INFO - Running main action method: populate_webroot 09:31:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:31:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:31:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:31:35 INFO - '--tppagecycles', 09:31:35 INFO - '7'], 09:31:35 INFO - 'tests': ['tsvgm']}}, 09:31:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:31:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:31:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:31:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:31:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:35 INFO - 'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:31:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:31:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:35 INFO - 'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['damp', 'tps']}, 09:31:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:35 INFO - 'tests': ['damp', 'tps']}, 09:31:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['dromaeo_dom']}, 09:31:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:31:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['basic_compositor_video']}, 09:31:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:31:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['a11yr', 09:31:35 INFO - 'ts_paint', 09:31:35 INFO - 'tpaint', 09:31:35 INFO - 'sessionrestore', 09:31:35 INFO - 'sessionrestore_no_auto_restore', 09:31:35 INFO - 'tabpaint']}, 09:31:35 INFO - 'other-e10s': {'tests': ['a11yr', 09:31:35 INFO - 'ts_paint', 09:31:35 INFO - 'tpaint', 09:31:35 INFO - 'sessionrestore', 09:31:35 INFO - 'sessionrestore_no_auto_restore', 09:31:35 INFO - 'tabpaint']}, 09:31:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:31:35 INFO - 'ts_paint', 09:31:35 INFO - 'tpaint', 09:31:35 INFO - 'sessionrestore', 09:31:35 INFO - 'sessionrestore_no_auto_restore', 09:31:35 INFO - 'tabpaint']}, 09:31:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:31:35 INFO - 'ts_paint', 09:31:35 INFO - 'tpaint', 09:31:35 INFO - 'sessionrestore', 09:31:35 INFO - 'sessionrestore_no_auto_restore', 09:31:35 INFO - 'tabpaint']}, 09:31:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['a11yr', 09:31:35 INFO - 'ts_paint', 09:31:35 INFO - 'tpaint', 09:31:35 INFO - 'sessionrestore', 09:31:35 INFO - 'sessionrestore_no_auto_restore', 09:31:35 INFO - 'tabpaint']}, 09:31:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['a11yr', 09:31:35 INFO - 'ts_paint', 09:31:35 INFO - 'tpaint', 09:31:35 INFO - 'sessionrestore', 09:31:35 INFO - 'sessionrestore_no_auto_restore', 09:31:35 INFO - 'tabpaint']}, 09:31:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['tsvgx', 09:31:35 INFO - 'tsvgr_opacity', 09:31:35 INFO - 'tart', 09:31:35 INFO - 'tscrollx', 09:31:35 INFO - 'cart']}, 09:31:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:31:35 INFO - 'tsvgr_opacity', 09:31:35 INFO - 'tart', 09:31:35 INFO - 'tscrollx', 09:31:35 INFO - 'cart']}, 09:31:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:35 INFO - 'talos_options': ['--disable-e10s'], 09:31:35 INFO - 'tests': ['tp5o']}, 09:31:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:35 INFO - 'tests': ['tp5o']}, 09:31:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:35 INFO - 'talos_options': ['--disable-e10s', 09:31:35 INFO - '--xperf_path', 09:31:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:31:35 INFO - 'tests': ['tp5n']}, 09:31:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:35 INFO - 'talos_options': ['--xperf_path', 09:31:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:31:35 INFO - 'tests': ['tp5n']}}, 09:31:35 INFO - 'talos.zip': {'path': '', 09:31:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:31:35 INFO - Running post-action listener: _resource_record_post_action 09:31:35 INFO - ##### 09:31:35 INFO - ##### Finished populate-webroot step (success) 09:31:35 INFO - ##### 09:31:35 INFO - ##### 09:31:35 INFO - ##### Running create-virtualenv step. 09:31:35 INFO - ##### 09:31:35 INFO - Running pre-action listener: _resource_record_pre_action 09:31:35 INFO - Running main action method: create_virtualenv 09:31:35 INFO - Creating virtualenv /builds/slave/test/build/venv 09:31:35 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:31:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:31:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:31:36 INFO - Using real prefix '/tools/python27' 09:31:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:31:36 INFO - Installing distribute.............................................................................................................................................................................................done. 09:31:39 INFO - Installing pip.................done. 09:31:39 INFO - Return code: 0 09:31:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:39 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 0x102803a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102898618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93a0c5be30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102767d30>, '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 0x1024ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10275fb20>, '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.FSbHansihQ/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.fNMtZDMfb6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:31:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:31:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FSbHansihQ/Render', 09:31:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:39 INFO - 'HOME': '/Users/cltbld', 09:31:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:39 INFO - 'LOGNAME': 'cltbld', 09:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:39 INFO - 'MOZ_NO_REMOTE': '1', 09:31:39 INFO - 'NO_EM_RESTART': '1', 09:31:39 INFO - 'PAGER': '/bin/cat', 09:31:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:39 INFO - 'PWD': '/builds/slave/test', 09:31:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:39 INFO - 'SHELL': '/bin/bash', 09:31:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNMtZDMfb6/Listeners', 09:31:39 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 09:31:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:39 INFO - 'USER': 'cltbld', 09:31:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:39 INFO - 'XPC_FLAGS': '0x0', 09:31:39 INFO - 'XPC_SERVICE_NAME': '0', 09:31:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:31:39 INFO - Cleaning up... 09:31:39 INFO - Return code: 0 09:31:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:39 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 0x102803a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102898618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93a0c5be30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102767d30>, '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 0x1024ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10275fb20>, '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.FSbHansihQ/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.fNMtZDMfb6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:31:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:31:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FSbHansihQ/Render', 09:31:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:39 INFO - 'HOME': '/Users/cltbld', 09:31:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:39 INFO - 'LOGNAME': 'cltbld', 09:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:39 INFO - 'MOZ_NO_REMOTE': '1', 09:31:39 INFO - 'NO_EM_RESTART': '1', 09:31:39 INFO - 'PAGER': '/bin/cat', 09:31:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:39 INFO - 'PWD': '/builds/slave/test', 09:31:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:39 INFO - 'SHELL': '/bin/bash', 09:31:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNMtZDMfb6/Listeners', 09:31:39 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 09:31:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:39 INFO - 'USER': 'cltbld', 09:31:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:39 INFO - 'XPC_FLAGS': '0x0', 09:31:39 INFO - 'XPC_SERVICE_NAME': '0', 09:31:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:40 INFO - Downloading/unpacking psutil>=3.1.1 09:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:31:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:31:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:31:43 INFO - Installing collected packages: psutil 09:31:43 INFO - Running setup.py install for psutil 09:31:43 INFO - building 'psutil._psutil_osx' extension 09:31:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:31:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:31:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:31:43 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:31:44 INFO - building 'psutil._psutil_posix' extension 09:31:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:31:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:31:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:31:44 INFO - ^ 09:31:44 INFO - 1 warning generated. 09:31:44 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:31:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:31:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:31:44 INFO - Successfully installed psutil 09:31:44 INFO - Cleaning up... 09:31:44 INFO - Return code: 0 09:31:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:31:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102803a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102898618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93a0c5be30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102767d30>, '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 0x1024ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10275fb20>, '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.FSbHansihQ/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.fNMtZDMfb6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:31:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:31:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FSbHansihQ/Render', 09:31:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:44 INFO - 'HOME': '/Users/cltbld', 09:31:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:44 INFO - 'LOGNAME': 'cltbld', 09:31:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:44 INFO - 'MOZ_NO_REMOTE': '1', 09:31:44 INFO - 'NO_EM_RESTART': '1', 09:31:44 INFO - 'PAGER': '/bin/cat', 09:31:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:44 INFO - 'PWD': '/builds/slave/test', 09:31:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:44 INFO - 'SHELL': '/bin/bash', 09:31:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNMtZDMfb6/Listeners', 09:31:44 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 09:31:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:44 INFO - 'USER': 'cltbld', 09:31:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:44 INFO - 'XPC_FLAGS': '0x0', 09:31:44 INFO - 'XPC_SERVICE_NAME': '0', 09:31:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:31:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:31:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:31:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:31:47 INFO - Installing collected packages: mozsystemmonitor 09:31:47 INFO - Running setup.py install for mozsystemmonitor 09:31:47 INFO - Successfully installed mozsystemmonitor 09:31:47 INFO - Cleaning up... 09:31:47 INFO - Return code: 0 09:31:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:31:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:47 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 0x102803a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102898618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93a0c5be30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102767d30>, '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 0x1024ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10275fb20>, '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.FSbHansihQ/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.fNMtZDMfb6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:31:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:31:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FSbHansihQ/Render', 09:31:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:47 INFO - 'HOME': '/Users/cltbld', 09:31:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:47 INFO - 'LOGNAME': 'cltbld', 09:31:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:47 INFO - 'MOZ_NO_REMOTE': '1', 09:31:47 INFO - 'NO_EM_RESTART': '1', 09:31:47 INFO - 'PAGER': '/bin/cat', 09:31:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:47 INFO - 'PWD': '/builds/slave/test', 09:31:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:47 INFO - 'SHELL': '/bin/bash', 09:31:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNMtZDMfb6/Listeners', 09:31:47 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 09:31:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:47 INFO - 'USER': 'cltbld', 09:31:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:47 INFO - 'XPC_FLAGS': '0x0', 09:31:47 INFO - 'XPC_SERVICE_NAME': '0', 09:31:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:47 INFO - Downloading/unpacking jsonschema==2.5.1 09:31:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:31:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:50 INFO - Downloading functools32-3.2.3-2.tar.gz 09:31:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:31:50 INFO - warning: no files found matching '*.txt' 09:31:50 INFO - no previously-included directories found matching 'build' 09:31:50 INFO - no previously-included directories found matching 'dist' 09:31:50 INFO - no previously-included directories found matching '.git*' 09:31:50 INFO - Installing collected packages: jsonschema, functools32 09:31:50 INFO - Running setup.py install for functools32 09:31:50 INFO - warning: no files found matching '*.txt' 09:31:50 INFO - no previously-included directories found matching 'build' 09:31:50 INFO - no previously-included directories found matching 'dist' 09:31:50 INFO - no previously-included directories found matching '.git*' 09:31:50 INFO - Successfully installed jsonschema functools32 09:31:50 INFO - Cleaning up... 09:31:50 INFO - Return code: 0 09:31:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:31: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')]} 09:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31: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')]} 09:31:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31: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', '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 0x102803a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102898618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93a0c5be30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102767d30>, '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 0x1024ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10275fb20>, '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.FSbHansihQ/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.fNMtZDMfb6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:31: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 blobuploader==1.2.4 09:31:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FSbHansihQ/Render', 09:31:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:50 INFO - 'HOME': '/Users/cltbld', 09:31:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:50 INFO - 'LOGNAME': 'cltbld', 09:31:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:50 INFO - 'MOZ_NO_REMOTE': '1', 09:31:50 INFO - 'NO_EM_RESTART': '1', 09:31:50 INFO - 'PAGER': '/bin/cat', 09:31:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:50 INFO - 'PWD': '/builds/slave/test', 09:31:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:50 INFO - 'SHELL': '/bin/bash', 09:31:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNMtZDMfb6/Listeners', 09:31:50 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 09:31:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:50 INFO - 'USER': 'cltbld', 09:31:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:50 INFO - 'XPC_FLAGS': '0x0', 09:31:50 INFO - 'XPC_SERVICE_NAME': '0', 09:31:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:51 INFO - Downloading/unpacking blobuploader==1.2.4 09:31: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 09:31: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 09:31: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 09:31: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 09:31: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 09:31: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 09:31:53 INFO - Downloading blobuploader-1.2.4.tar.gz 09:31:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:31:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:31:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:31: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 09:31: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 09:31: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 09:31: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 09:31: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 09:31: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 09:31:54 INFO - Downloading docopt-0.6.1.tar.gz 09:31:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:31:54 INFO - Installing collected packages: blobuploader, requests, docopt 09:31:54 INFO - Running setup.py install for blobuploader 09:31:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:31:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:31:54 INFO - Running setup.py install for requests 09:31:55 INFO - Running setup.py install for docopt 09:31:55 INFO - Successfully installed blobuploader requests docopt 09:31:55 INFO - Cleaning up... 09:31:55 INFO - Return code: 0 09:31:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:55 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 0x102803a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102898618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93a0c5be30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102767d30>, '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 0x1024ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10275fb20>, '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.FSbHansihQ/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.fNMtZDMfb6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:31:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FSbHansihQ/Render', 09:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:55 INFO - 'HOME': '/Users/cltbld', 09:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:55 INFO - 'LOGNAME': 'cltbld', 09:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:55 INFO - 'MOZ_NO_REMOTE': '1', 09:31:55 INFO - 'NO_EM_RESTART': '1', 09:31:55 INFO - 'PAGER': '/bin/cat', 09:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:55 INFO - 'PWD': '/builds/slave/test', 09:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:55 INFO - 'SHELL': '/bin/bash', 09:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNMtZDMfb6/Listeners', 09:31:55 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 09:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:55 INFO - 'USER': 'cltbld', 09:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:55 INFO - 'XPC_FLAGS': '0x0', 09:31:55 INFO - 'XPC_SERVICE_NAME': '0', 09:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:31:55 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-VPoILp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:31:56 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-l_oqkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:31:56 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-iFLC7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:31:56 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-a8NP1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:31:56 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-QZswDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:31:56 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-KBq2dG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:31:56 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-Xb5I6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:31:56 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-3s2Bvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:31:56 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-T5tTpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:31:56 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-aBK1B1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:31:56 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-V27t9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:31:57 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-BaqDCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:31:57 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-Fqxgcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:31:57 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-3e6GSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:57 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-Kj0qbr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:31:57 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-e3WGz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:31:57 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-1lyMH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:31:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:31:57 INFO - Running setup.py install for manifestparser 09:31:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:31:57 INFO - Running setup.py install for mozcrash 09:31:57 INFO - Running setup.py install for mozdebug 09:31:58 INFO - Running setup.py install for mozdevice 09:31:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:31:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:31:58 INFO - Running setup.py install for mozfile 09:31:58 INFO - Running setup.py install for mozhttpd 09:31:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:31:58 INFO - Running setup.py install for mozinfo 09:31:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:31:58 INFO - Running setup.py install for mozInstall 09:31:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:31:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:31:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:31:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:31:58 INFO - Running setup.py install for mozleak 09:31:59 INFO - Running setup.py install for mozlog 09:31:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:31:59 INFO - Running setup.py install for moznetwork 09:31:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:31:59 INFO - Running setup.py install for mozprocess 09:31:59 INFO - Running setup.py install for mozprofile 09:31:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:31:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:31:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:31:59 INFO - Running setup.py install for mozrunner 09:31:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:31:59 INFO - Running setup.py install for mozscreenshot 09:31:59 INFO - Running setup.py install for moztest 09:32:00 INFO - Running setup.py install for mozversion 09:32:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:32:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:32:00 INFO - Cleaning up... 09:32:00 INFO - Return code: 0 09:32:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:32:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:32:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:00 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 0x102803a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102898618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93a0c5be30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102767d30>, '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 0x1024ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10275fb20>, '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.FSbHansihQ/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.fNMtZDMfb6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:32:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:32:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FSbHansihQ/Render', 09:32:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:00 INFO - 'HOME': '/Users/cltbld', 09:32:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:00 INFO - 'LOGNAME': 'cltbld', 09:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:00 INFO - 'MOZ_NO_REMOTE': '1', 09:32:00 INFO - 'NO_EM_RESTART': '1', 09:32:00 INFO - 'PAGER': '/bin/cat', 09:32:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:00 INFO - 'PWD': '/builds/slave/test', 09:32:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:00 INFO - 'SHELL': '/bin/bash', 09:32:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNMtZDMfb6/Listeners', 09:32:00 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 09:32:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:00 INFO - 'USER': 'cltbld', 09:32:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:00 INFO - 'XPC_FLAGS': '0x0', 09:32:00 INFO - 'XPC_SERVICE_NAME': '0', 09:32:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:32:00 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-zvrrIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:32:00 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:32:00 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-ss7aSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:32:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:32:00 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-rOw77c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:32:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:32:00 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-yHxqAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:32:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:32:00 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-dXR9rg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:32:01 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-UCmdJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:32:01 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-Cbe_Mz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:32:01 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-Ae4i97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:32:01 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-C2UXfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:32:01 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-dzUtF0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:32:01 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-oRQBR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:32:01 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-xaGaxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:32:01 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-W0WZZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:32:01 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-lBc4X6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:32:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:02 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-YqENGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:32:02 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-xB06ib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:32:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:32:02 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-UDfN0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:32:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:32:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:32:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:32:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:32:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:32:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:32:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:05 INFO - Downloading blessings-1.6.tar.gz 09:32:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:32:05 INFO - Installing collected packages: blessings 09:32:05 INFO - Running setup.py install for blessings 09:32:05 INFO - Successfully installed blessings 09:32:05 INFO - Cleaning up... 09:32:05 INFO - Return code: 0 09:32:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:32:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:32:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:32:06 INFO - Reading from file tmpfile_stdout 09:32:06 INFO - Current package versions: 09:32:06 INFO - blessings == 1.6 09:32:06 INFO - blobuploader == 1.2.4 09:32:06 INFO - docopt == 0.6.1 09:32:06 INFO - functools32 == 3.2.3-2 09:32:06 INFO - jsonschema == 2.5.1 09:32:06 INFO - manifestparser == 1.1 09:32:06 INFO - mozInstall == 1.12 09:32:06 INFO - mozcrash == 0.17 09:32:06 INFO - mozdebug == 0.1 09:32:06 INFO - mozdevice == 0.48 09:32:06 INFO - mozfile == 1.2 09:32:06 INFO - mozhttpd == 0.7 09:32:06 INFO - mozinfo == 0.9 09:32:06 INFO - mozleak == 0.1 09:32:06 INFO - mozlog == 3.2 09:32:06 INFO - moznetwork == 0.27 09:32:06 INFO - mozprocess == 0.23 09:32:06 INFO - mozprofile == 0.28 09:32:06 INFO - mozrunner == 6.12 09:32:06 INFO - mozscreenshot == 0.1 09:32:06 INFO - mozsystemmonitor == 0.3 09:32:06 INFO - moztest == 0.7 09:32:06 INFO - mozversion == 1.4 09:32:06 INFO - psutil == 3.1.1 09:32:06 INFO - requests == 1.2.3 09:32:06 INFO - wsgiref == 0.1.2 09:32:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:32: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')]} 09:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32: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')]} 09:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:06 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 0x102803a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102898618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93a0c5be30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102767d30>, '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 0x1024ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10275fb20>, '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.FSbHansihQ/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.fNMtZDMfb6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:32:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FSbHansihQ/Render', 09:32:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:06 INFO - 'HOME': '/Users/cltbld', 09:32:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:06 INFO - 'LOGNAME': 'cltbld', 09:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:06 INFO - 'MOZ_NO_REMOTE': '1', 09:32:06 INFO - 'NO_EM_RESTART': '1', 09:32:06 INFO - 'PAGER': '/bin/cat', 09:32:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:06 INFO - 'PWD': '/builds/slave/test', 09:32:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:06 INFO - 'SHELL': '/bin/bash', 09:32:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNMtZDMfb6/Listeners', 09:32:06 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 09:32:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:06 INFO - 'USER': 'cltbld', 09:32:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:06 INFO - 'XPC_FLAGS': '0x0', 09:32:06 INFO - 'XPC_SERVICE_NAME': '0', 09:32:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:32:06 INFO - Cleaning up... 09:32:06 INFO - Return code: 0 09:32:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:32: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')]} 09:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32: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')]} 09:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:06 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 0x102803a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102898618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93a0c5be30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102767d30>, '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 0x1024ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10275fb20>, '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.FSbHansihQ/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.fNMtZDMfb6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:32:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FSbHansihQ/Render', 09:32:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:06 INFO - 'HOME': '/Users/cltbld', 09:32:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:06 INFO - 'LOGNAME': 'cltbld', 09:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:06 INFO - 'MOZ_NO_REMOTE': '1', 09:32:06 INFO - 'NO_EM_RESTART': '1', 09:32:06 INFO - 'PAGER': '/bin/cat', 09:32:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:06 INFO - 'PWD': '/builds/slave/test', 09:32:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:06 INFO - 'SHELL': '/bin/bash', 09:32:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNMtZDMfb6/Listeners', 09:32:06 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 09:32:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:06 INFO - 'USER': 'cltbld', 09:32:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:06 INFO - 'XPC_FLAGS': '0x0', 09:32:06 INFO - 'XPC_SERVICE_NAME': '0', 09:32:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:32:06 INFO - Cleaning up... 09:32:06 INFO - Return code: 0 09:32:06 INFO - Running post-action listener: _resource_record_post_action 09:32:06 INFO - Running post-action listener: _start_resource_monitoring 09:32:07 INFO - Starting resource monitoring. 09:32:07 INFO - ##### 09:32:07 INFO - ##### Finished create-virtualenv step (success) 09:32:07 INFO - ##### 09:32:07 INFO - ##### 09:32:07 INFO - ##### Running install step. 09:32:07 INFO - ##### 09:32:07 INFO - Running pre-action listener: _resource_record_pre_action 09:32:07 INFO - Running main action method: install 09:32:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:32:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:32:07 INFO - Reading from file tmpfile_stdout 09:32:07 INFO - Detecting whether we're running mozinstall >=1.0... 09:32:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:32:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:32:07 INFO - Reading from file tmpfile_stdout 09:32:07 INFO - Output received: 09:32:07 INFO - Usage: mozinstall [options] installer 09:32:07 INFO - Options: 09:32:07 INFO - -h, --help show this help message and exit 09:32:07 INFO - -d DEST, --destination=DEST 09:32:07 INFO - Directory to install application into. [default: 09:32:07 INFO - "/builds/slave/test"] 09:32:07 INFO - --app=APP Application being installed. [default: firefox] 09:32:07 INFO - mkdir: /builds/slave/test/build/application 09:32:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:32:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:32:41 INFO - Reading from file tmpfile_stdout 09:32:41 INFO - Output received: 09:32:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:32:41 INFO - Running post-action listener: _resource_record_post_action 09:32:41 INFO - ##### 09:32:41 INFO - ##### Finished install step (success) 09:32:41 INFO - ##### 09:32:41 INFO - ##### 09:32:41 INFO - ##### Running run-tests step. 09:32:41 INFO - ##### 09:32:41 INFO - Running pre-action listener: _resource_record_pre_action 09:32:41 INFO - Running main action method: run_tests 09:32:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:32:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:32:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:32:41 INFO - 2016-07-08 09:32:41.476 screenresolution[1643:8215] starting screenresolution argv=screenresolution get 09:32:41 INFO - 2016-07-08 09:32:41.479 screenresolution[1643:8215] Display 0: 1600x1200x32@60 09:32:41 INFO - 2016-07-08 09:32:41.484 screenresolution[1644:8217] starting screenresolution argv=screenresolution list 09:32:41 INFO - Available Modes on Display 0 09:32:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:32:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:32:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:32:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:32:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:32:41 INFO - Intel Iris: 09:32:41 INFO - Chipset Model: Intel Iris 09:32:41 INFO - Type: GPU 09:32:41 INFO - Bus: Built-In 09:32:41 INFO - VRAM (Dynamic, Max): 1536 MB 09:32:41 INFO - Vendor: Intel (0x8086) 09:32:41 INFO - Device ID: 0x0a2e 09:32:41 INFO - Revision ID: 0x0009 09:32:41 INFO - Displays: 09:32:41 INFO - Display: 09:32:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:32:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:32:41 INFO - Display Serial Number: 1600x1200 60 09:32:41 INFO - Main Display: Yes 09:32:41 INFO - Mirror: Off 09:32:41 INFO - Online: Yes 09:32:41 INFO - Rotation: Supported 09:32:41 INFO - Return code: 0 09:32:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:32:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:32:41 INFO - Python 2.7.3 09:32:41 INFO - Return code: 0 09:32:41 INFO - grabbing minidump binary from tooltool 09:32:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:32:41 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 0x102767d30>, '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 0x1024ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10275fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:32:41 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:32:41 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:32:41 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:32:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:32:41 INFO - Return code: 0 09:32:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:32:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:32:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:32:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:32:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:32:41 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/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0109', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:32:41 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/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0109 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:32:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FSbHansihQ/Render', 09:32:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:41 INFO - 'HOME': '/Users/cltbld', 09:32:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:41 INFO - 'LOGNAME': 'cltbld', 09:32:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:32:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:32:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:41 INFO - 'MOZ_NO_REMOTE': '1', 09:32:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:32:41 INFO - 'NO_EM_RESTART': '1', 09:32:41 INFO - 'PAGER': '/bin/cat', 09:32:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:41 INFO - 'PWD': '/builds/slave/test', 09:32:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:32:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:41 INFO - 'SHELL': '/bin/bash', 09:32:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fNMtZDMfb6/Listeners', 09:32:41 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 09:32:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:41 INFO - 'USER': 'cltbld', 09:32:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:41 INFO - 'XPC_FLAGS': '0x0', 09:32:41 INFO - 'XPC_SERVICE_NAME': '0', 09:32:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:41 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/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0109', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:32:42 INFO - mozversion application_buildid: 20160708072926 09:32:42 INFO - mozversion application_changeset: 6cb3dc4bdbae8a9e36c2be441a59e39c8a391a03 09:32:42 INFO - mozversion application_display_name: Nightly 09:32:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:32:42 INFO - mozversion application_name: Firefox 09:32:42 INFO - mozversion application_remotingname: firefox 09:32:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:32:42 INFO - mozversion application_vendor: Mozilla 09:32:42 INFO - mozversion application_version: 50.0a1 09:32:42 INFO - mozversion platform_buildid: 20160708072926 09:32:42 INFO - mozversion platform_changeset: 6cb3dc4bdbae8a9e36c2be441a59e39c8a391a03 09:32:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:32:42 INFO - mozversion platform_version: 50.0a1 09:32:42 INFO - using testdate: 1467995562 09:32:42 INFO - actual date: 1467995562 09:32:42 INFO - starting webserver on 'localhost:49271' 09:32:42 INFO - SUITE-START | Running 2 tests 09:32:42 INFO - TEST-START | dromaeo_css 09:32:42 INFO - operating with platform_type : mac_ 09:32:42 INFO - Initialising browser for dromaeo_css test... 09:32:42 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpPg_FRn/profile http://localhost:49271/getInfo.html) 09:32:44 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 09:32:44 INFO - PROCESS | 1654 | colorDepth:24 09:32:44 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 09:32:44 INFO - PROCESS | 1654 | __metrics 09:32:44 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" 09:32:44 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] 09:32:45 INFO - TEST-INFO | 1654: exit 0 09:32:45 INFO - Browser initialized. 09:32:45 INFO - Running cycle 1/1 for dromaeo_css test... 09:32:45 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpPg_FRn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:32:57 INFO - PROCESS | 1657 | RSS: Main: 243601408 09:32:57 INFO - PROCESS | 1657 | 09:33:47 INFO - PROCESS | 1657 | 1467995627947 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:35:59 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 09:36:00 INFO - PROCESS | 1657 | RSS: Main: 385077248 09:36:00 INFO - PROCESS | 1657 | 09:39:04 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 09:39:04 INFO - PROCESS | 1657 | RSS: Main: 375398400 09:39:04 INFO - PROCESS | 1657 | 09:42:06 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 09:42:07 INFO - PROCESS | 1657 | RSS: Main: 373440512 09:42:07 INFO - PROCESS | 1657 | 09:45:10 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 09:45:10 INFO - PROCESS | 1657 | RSS: Main: 372994048 09:45:10 INFO - PROCESS | 1657 | 09:48:13 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 09:48:14 INFO - PROCESS | 1657 | RSS: Main: 335384576 09:48:14 INFO - PROCESS | 1657 | 09:51:17 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 09:51:17 INFO - PROCESS | 1657 | RSS: Main: 385875968 09:51:17 INFO - PROCESS | 1657 | 09:51:17 INFO - PROCESS | 1657 | __start_tp_report 09:51:17 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 09:51:17 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 09:51:17 INFO - PROCESS | 1657 | |i|pagename|runs| 09:51:17 INFO - PROCESS | 1657 | |0;dojo.html;3324.384988777076;3345.4814503751913;3345.983270083649;3361.554531551435;3408.982955085224;5505.504504594585;5588.077967135423;5595.4124816894255;5677.801276955307;5703.230063941362;5591.41290164533;5645;5667.801626943058;5681.914771278423;5702.942970570293;5104.05574968631;5127.717975511346;5159.535641792238;5184.222366644995;5221.347331583552;5179.974100129494;5187.48125187482;5211.5830733541225;5224.869378265555;5236.476352364771;5159.76781044852;5174.197999310113;5206.869828254286;5210.426853046162;5228.058949389108;5290.391604965402;5295.761690723907;5317.8936421271355;5324.1747529133045;5348.51863332298;5081.085404627165;5083.949160508385;5097.8725531862;5210.088234558975;5217.608679349026;5616.326040875121;5632.155176723483;5649.604527683064;5714.571407144439;5722.570807189478;6295.181626388586;6300.87398252037;6306.810795676137;6309.968450157719;6312.779052733133;6130.5095592352955;6219;6301.464375528086;6320.778772742932;6331.018692102685;5826.592138550261;5847.9415205848245;5858.355580886099;5871.354151043424;5912.113183022581;6264.342244064399;6265.0602409638;6265.342139075423;6272.6236425814595;6274.9686251569365;5953.464188223081;6028.306744724325;6108.847278817976;6145.586515101462;6194.5044396448175;6308.495320374359;6314.273858506239;6317.873642527124;6325;6341.841453963688;6244.781432649836;6281.183446151971;6287.685615719196;6296.087067375205;6298.7480500780375;6104.084387341845;6128.380904898417;6168.814935551941;6304.338044505261;6309.747610095545;4030.858919937788;4971.304017437562;5009.874753131128;5016.598672106223;5056.519630635157;4451.065276292015;4465.732056076646;4469.5306992765945;4473.3960915276675;4496.145732310851;5588.692621905834;5635.267415235993;5794.739236734357;5824.650520968756;5891.7054147292465;6213.347598502092;6282.120503129566;6292.433680968735;6305.779297724649;6314.747410103544;5777.220075289866;5786.71066446676;5797.333306669702;5802.100674395476;5887.941120588739;5303.655262408009;5378.650387724863;5423.810166644149;5434.347878254634;5459.972700136554;6195.132681424604;6244.0321750128805;6253.749850005949;6323.367663233824;6340.7463701451425;5181.404138524118;6307.684615769102;6308.053791931247;6320.5891617044745;6325.272593651604;6171.938280617136;6186.288576813724;6300.432961033529;6306.400891915261;6312.684365781784;5087.491250874883;6258.655773932477;6259.749610015549;6260.655663938527;6271.372862713874;6269.529785266214;6309.873802524015;6312.873742525216;6325.683715814283;6326.240851097898;5948.2862056553495;5970.462658360769;6015.0676645120075;6081.361457046946;6111.083337499233;6114.631938157225;6127.494965205145;6128.961487065963;6133.701927635241;6141.792871882774;5903.468687818117;5960.5827592068135;5968.104784282393;5990.940090599038;6011.6994150293185;5971.820845374646;6061.787837425669;6105.095857752984;6120.348535113257;6127.944784664208;5915.053591425351;5956.76172953077;5969.492593129459;5998.3401825798255;6021.879562408815 09:51:17 INFO - PROCESS | 1657 | |1;ext.html;533.2880604393135;534.1533669134421;536.758458693588;542.5280006394437;567.9148127780834;30469.390612187744;31929.840350798244;42195.78902105493;43327.133457330834;43428.34857477133;44208.557914420846;44492.77753611236;44580.77709611447;44590.55409445905;44693.7765311173;9661.468619225958;9856.90143098567;9859.260555458333;9865.1614612758;9867.753306167331;8835.911640883573;8989.64041438345;9019.819603607924;9154.908450915473;9166.954165229165;11810.350430726307;11988.520459181666;11994.700132496713;12280.263184208932;12369.381530923463;11927.986121179667;11985.940070299594;11986.460609272517;11987;12002;8747.387682862201;9871.457069861155;11540.94229528847;11730.413479326086;11947.342896140708;41546.58453415457;41800;41890;41968.58031419677;42001.94995125116;3640.8907732767793;3841.386627532231;4047.433359329667;4284.485861696585;4419.53594872537;29419;30236.395272094436;32492;32580.85966991144;33000;3276.6559511251453;3288.5067239913965;3369.107186595554;3441.8107004114763;3442.896713098611;480579;481051.5947420276;482582;483899.70752193726;487253;60720;61821;61860;61887.6905615478;61947.38052619416;63717;64266.036009459895;64269;64317.35682643114;64398.034029489594;16044.518664440086;16572.50282491527;16582.75125873113;16590.585235369213;16611.91694041522;12843.165194262343;12903.225806451643;12904.096713230172;12908.483660653468;12933;14153.575392738234;15373.001509803878;15541.98977066487;17464.825351746575;17600.383986560664;6579.736810527621;6663.566868153554;6675.699593518303;6694.899576506356;6728.629925354055;881.3389957531851;955.3599088610508;972.6498460554195;1013.1337706261188;1155.416514660108;28684.713152868622;30329.090127296215;30924;31119.533207001914;31135.509159267658;993.1905497019906;1100.5652767156928;1137.0448822988728;1152.9077673786078;1203.7532305877273;15560.501417222145;16007.199640017952;16043.518694439186;16238.75641865373;16407.261673224733;623352;623829;627197;627463;628609;60041.09938350928;60299;60429.697851510464;60554.697226513585;60560.09159862606;1260.521002019227;1395.302348825567;1440.5390275111863;1461.3643065266642;1462.5319897632655;30120.397592047597;30246.54630180505;30346;30816.075517734505;30908.69091309058;51647.22529161991;51841.740791297314;52120.478795211566;52149.95700086053;52269.215961761365;573094;574013;574321;574967;576800;27515.44969100647;28237.717622823508;28342.574861376685;28402.289942751595;41831.58168418277;40223.597764021986;40997.38503922503;43943;49136;53038;39764.20471590494;40461;40873;41148.94399992015;41756.373654395815;37951;40496.392554111124;40504.3924341141;40681.98295042648;41189.58810411977;38913.805430972665;39000.414993774546;39072.609273908034;39412.60587394204;39419.80290098531;33710.157246069044;35016.12459688426;35574.82212588921;35616.10959725924;35707.82146089253;25591.744082558936;26032.609510857732;26112.73887261103;26354.4729105413;26559.734402655726 09:51:17 INFO - PROCESS | 1657 | |2;jquery.html;381.22991557054735;382.5371300726121;392.33107551624477;393.8562424714978;414.44878311845247;20219.595608087828;21227.36317910465;21872.45318867029;22191.778082219153;22196.334109976706;21482.570348593017;21742.130314787388;21811.3456596302;22210.11159553616;22215;5002.849914502571;5631.690257035862;6071.969640151793;6076.908846367308;6086.908696369558;4208.768517731523;4432.933505997413;5917.2307600012155;6378.808635740936;6683.030960510723;6403.519736019817;6689.130413046297;6694.665266736668;6696.397324240817;6747.898781518281;6621.337866213388;6630.767923122716;6649.235337936103;6652.434543063821;6694.464442844561;5253.3958594761325;5568.916466253009;5573.832785016456;5765.279340082489;5768.077107662753;13688.041837071409;13921.651958701012;13957.511487097903;17184;26263.73736262632;2324.3956571291587;2503.4492411669403;2677.919662410131;3016.75865930725;3095.303556699747;10276.229282803819;10329.638462653771;10430.113440357503;10598.31110977784;10650.520726567323;1873.175802646831;1931.33368987699;1941.990290048555;1959.1183967214668;1962.460323411059;124968;129581;129779;130943.99999999905;130999.34500327533;11575.42122893852;11801.468933897995;11863.644090677293;12004.099692523096;12054.758904821854;14950.401983920521;14997.17515536656;15014.498550144897;15018.624534386729;15034.774478382833;7261.963690181621;8547.57262136903;8705.91176102987;8834.983976842745;8882.955585222033;8668.739937801876;8671.6531338747;8677.21905028538;8776.824463510695;8795.296376289882;3711.740178187555;4626.537346265347;4628.0562163891245;4679.9298010529865;4686.27362758773;1781.6347648731978;1823.575949367094;1831.6767652648084;1953.3163392812687;1956.3348461522867;865.6234537976001;890.5057692980329;906.0712769411406;908.9772755681029;993.6323560282742;21054.156095807095;21954.670679939813;22058.88970555137;22099.668504972436;22131;937.315759495572;942.495764765847;999.4053538144792;1026.2200727447193;1031.63377001165;8572.52851093196;9896.476398518333;11025.681990653717;11266.94366528162;11440.970312671732;616411;626508.8674556598;628321;636902;654400;79140.20859791443;109908.90091098986;116615;130752;138199;974.7222041718077;1005.6480231918916;1014.2595904989399;1025.5487585462372;1026.2559644257854;18748.156332964776;19310.806891931465;19384.321548745727;19951.501212469804;20529.081625057664;60134.69932650309;60906.69546652238;61351;61374.85188018398;61994.69002654958;82824.75762863683;83208;83227.16772832195;85917;87909;7815.53106813593;7861.2531809478005;8410.116937721452;8665.610047547749;8810.559472026245;11735.354555499529;13573;13699.794503082263;13794.172349659053;14099.365528551034;5583.385827558884;6047.637141771508;11895.881041189823;11998.100142489522;12079.637610871689;11596.884031159918;11766.941165294118;11909.583164589198;11944.950920398423;11982.820257695967;11136.276142050741;11774.587889423829;11927.403629818647;12120.212186207867;12144.514219431478;8460.830783384421;8687.218150366498;10336.586536538454;12402.75194496087;12710.237385756884;11207.66377008691;11356.545738170711;12006.459709313276;12109.69725756863;12172.513099476277 09:51:17 INFO - PROCESS | 1657 | |3;mootools.html;1555.6033211531058;1590.3956496531316;1846.5568263616735;1967.9114439850205;1992.0736857361323;8628.223459888608;8911.68809091682;9077.591508382122;9079.046700096485;9084.8637270441;7762;7996.760097197079;8001.19988001201;8127.796805079876;8565.743027709179;2348.2720356689397;2380.642903564462;2386.4153282445736;2387.069043073203;2392.4018995251186;2327.325075728037;2332.6734257204007;2352.3413444235644;2371.762823717632;2373.881305934705;2099.2862426774896;2166.1876796201423;2168.6855405966126;2355.340504658698;2358.2099996501192;2136.75427325858;2280.144945645391;2333.195047708538;2344.2615576093644;2351.3298709867777;2328;2340.73081595617;2351.318117745852;2352.6118190498546;2360.4570948681726;8960.955195224047;9020.594073266719;9030.774230644221;9040.41237319572;9080.182783549446;5209.817656382009;5305.257263983045;5398.974194902956;5405.7567409466665;5406.216098665711;3480.6519348065244;3596.514470546469;3646.7994260315672;3650.5984341722387;3652.3243200008083;1075.0808059109427;1086.869575650919;1101.0420933787575;1108.0636861851722;1110.0176343935675;12012.279263244232;12021.39893005355;12044.096692748079;12055.33695646739;12068.336241506713;4515.209838278288;4679.836205732851;4773.307870358779;4808.06242782654;4813.374261346073;10041.497925103715;10063.19494440443;10063.496825158712;10068.44623545712;10081.69754907354;5328.547073498796;5330.38700549434;5340.946590534123;5343.812966546152;5344.893102137936;4230.915381692349;4397.1205758848355;4521.231390663595;4879.853604391874;4891.168501354707;3874.3994680824517;3939.330313846653;3976.3240249157134;3976.991619232327;4055.9797201013807;1268.099649249025;1291.283337747559;1299.2009913902882;1309.5482058689736;1310.0633047370984;901.1304091551684;912.8356895758694;916.1571354354011;927.5471585808;954.0745476887547;5795.449432303924;5840.094785308284;5965.671888046112;6024.186734790835;6135.754569817157;427.2523084601947;440.537435692528;446.38176126065633;458.0358345682357;462.6437643014927;3350.5476760637002;3878.825452854628;3910.452536644872;3960.8415663373717;3967.821448034845;10429;10674.985876341736;10886.074683651977;10898.291611045257;10919.454027298603;10305.021023002802;10684.198685098649;10725.785484290427;10840.457977101114;10973.286736362112;7852.725154619675;8022.719204827803;8045.597720113971;8045.678172873019;8053.275205231557;4949.901001979868;5368.919466207932;6023.909641355295;6163.568550201622;6300.495960323072;8902.109789021306;9899.851502227328;10019.949900250453;10026.598936042768;10042.246831487522;7689.92310076892;9687.370320929118;10099.495025248854;10231.232657550565;10232.23258255619;7481.551106933602;7647.961760191163;7692.615369231404;7728.884066739116;7740.612969351398;8291.129431409616;8325.625346859284;8489.363297752816;8501.022382425852;8503.617337219715;7629.351505121905;7678.884816727866;7701.730439434593;7750.496217745828;7782.455228133977;7455.552666840007;7482.289182527651;7566.35685966699;7621.809454763676;7632.389408847168;6921.89617155733;7089.929100708927;7142.535735177197;7171.892421613576;7242.203357630762;5063.291139240583;5079.263506791568;5118.3857937046305;5122.974385128051;5197.636165468381;5087.313212716236;5116.079105760999;5168.741562921764;5188.299579556711;5322.068637988414 09:51:17 INFO - PROCESS | 1657 | |4;prototype.html;302.01693487697554;306.9109958112148;317.8728508596562;320.7434052757793;320.9630892447369;4600.746958917262;4732.290156476531;5224.947750522494;5257.00116977774;5590.965671350803;5235.816746413876;5405.108157154074;5419.620626556142;5501.752421141048;5509.3664228613725;3454.844531996066;4081.5918408159246;4132.876013719593;4133.400656904747;4227.450431443924;3963.2271707017144;4160.5007399112;4170.436991006206;4184.727992680466;4198.20234155511;4092.4679791627045;4106.753594784308;4125.669946404296;4142.150859073882;4159.625633692967;3586.6413358664186;3884.495015647976;4098.016476045757;4116.958830411688;4140.441040459529;3532.0993146747414;3576.5886923003927;3579.3020361029608;3579.9642003579893;3599.6760291573623;5146.150885103953;5242.55438287746;5255.842324730264;5267.28891599633;5288.894222115536;3655.9268814623824;4435.62297204738;4666.136764698542;4681.391419115494;4727.45634252062;4922.556969872693;5483.506484416422;5705.74324155414;5749.9712501437225;5761.798337058225;3210.887618933326;3725.37945993491;3883.2815929053218;3888.8833334999995;3902.804859757001;5227.242049902781;5969.164316995624;6008.639481631116;6008.789692360747;6053.667048312341;3076.9384612308077;3459.5469902640953;3745.382011968049;3870.651641352292;4016.3373043447505;3371.561696979377;4020.19596080785;4026.6108192673482;4035.5964403559997;4051.0959848870984;4619.792109355087;4632.86101416958;4666.510016448292;4694.835680751157;4709.175894218567;5473.370562385377;5747.959474386616;5769.769209231584;5810.622309549865;5821.359650438449;1168.0071938851909;1320.2408615046386;1325.3704490367;1343.3417625363697;1347.7425312601354;680.6018479189729;837.7863644770545;879.9792241000537;883.3286702106445;921.6267411698273;5190.506901844319;5200.375954885438;5223.869403264949;5231.686098834082;5236.397814251333;5689;5716.199732037435;5720.2849643794525;5826.79606213789;5843.5325173985975;4421.911561768747;4960.553550180501;5236.345456817898;5239.554637855825;5277.182036784305;2260.6609008648834;2281.7147856517936;2514.3588384961718;2522.41984343602;2533.7702821516946;4272.209641216385;4447.3773671685985;4588.862334129982;4880.6095512358925;4883.361895387888;3839.310703290543;4282.807273672693;4315.525292217853;4808.8797780055775;4891.584215341664;4306.978465107717;4898.510148985073;4931.139938835467;4981.708414129541;5061.848144555669;4227.365895115758;4403.75779332127;4716.089983556665;4797.784099715452;4806.173654011453;4199.635118586532;4707.646926480596;4719.799378553159;4721.4570324413135;5217.6086793491395;5242.711650859239;6008.218931538872;6164.630122192682;6166.608365399103;6184.567080304336;3470.7331823884606;3906.941395879121;3924.7252692311263;3960.188161426843;3972.2849887020616;4733.9053218936115;5450.31871016123;5483.698396588066;5901.763929442946;5984.970075149597;5071.822486212965;5285.392179899361;5520.337559492726;5773.942260577341;5782.45066718661;4623;5738.340090889601;5768.221290125779;5780.393058728773;5809.477147056785;5029.893423446836;5261.73691315425;5530.336359636869;5543.085390910454;5546.528545073553;4684.508126646653;4696.38946936896;5377.838664840061;5741.5119714824705;5756.827295181151;5544.726662068532;5621.971890140523;5626.521745651666;5628.099504079491;5661.518770904519 09:51:17 INFO - PROCESS | 1657 | |5;yui.html;746.7498388040007;764.5794812852932;795.8209402884349;817.87731840224;845.4124383553433;17502.737458938125;18128;18148.274069037256;18160.36438724645;18241.726374104364;17223.569410764736;17327.740083898752;17417.303307867704;17437.3025078997;17447.91276043618;723.8805597076476;749.2470067582072;908.5956749246587;932.2169377722712;938.7090002099337;735.9806667765157;740.7111226621622;740.7333359990399;888.2094935507404;920.8987011428737;720.9675564599579;920.4707293306342;933.5425641435716;934.8550974598915;936.8688383626297;928.1971095002774;929.1730359979623;929.9907000930006;930.2976252929063;932.4405356785942;553.6180035775302;603.2730559675624;700.1248439450686;700.3556727810376;722.1911459165716;15563;15614.531564053097;15632.14023228722;15633.687326253412;15638.843611563852;13725.862741372559;14080.577582672433;14097.365618547088;14107.153570785786;14114.435422583085;13233.206007639572;13237.881938889732;13382.598522044354;13651.249181295025;13924.025318227728;4578.221702310582;4584.335271385664;4620.584147426748;4801.375821143229;4806.831760888387;111474.99999999919;111692;112058;112802.43598782035;113108;10804.405757683246;10875.673729788119;10913.727156821144;10913.945430272797;10997.670069897917;21638.75418687178;21671.783282167293;21679.241226556995;21687.67468487974;21698;6688.83277918056;7717.498362606412;7842.803929901798;7864.331531819745;8054.395920306;4395.340698895127;4433.667474939392;4484.506704262529;4493.550644935545;4647.651426143053;3555.7510974231554;3677.1358730698266;3679.3193259247123;3683.7605555638797;3947.3289540777564;726.0488759724777;735.5108852612952;741.6959046790897;758.0145810446397;759.0436050576313;573.4323020210046;683.7504310926575;694.0907411291224;713.7537549545399;726.9491135620456;9520.23838092951;9824.607015719292;9883.456409897524;9971.00289970997;10107.69676909694;664.2858926653867;685.6811582614008;686.3822559696214;693.9514234003572;707.4835370179798;4527.660425468103;4914.33656456381;4928.162212423825;4943.134951383566;4963.280324352949;102624;134892;136215;160571;169740;96577.06845863043;123507;142149;142651;144338;96768;118058;118331;119941.40029299798;122642;9429.764255893657;9528.95235523818;9529.03283707121;9539.855217373959;9578.952105239428;51982;69039.65480172567;70132.64933675299;70587;71070.64464677643;88157.55921220353;98647;99737.50131249588;101125;101744.4912775461;12212.938935305621;12300.323482208565;12306.692332691755;12358.938205308916;12745.235285882505;13517.256550889795;13864.653383665489;13987.160770353812;14068.718625627225;14158.43366265379;10801.567937282422;12801.359932003177;12911.5480958166;12936.288504131986;12982.415791289226;12689.175203611736;12780.096959973342;12897.097203195686;12899.548515801902;13327.466901324226;10865.348079115278;12476.57589410286;12851;13042.54351097707;13254.072214944861;10747.408892510673;10864.348139111678;10921.39932303699;10959.178061645249;11477.253978491372;10289.794204116026;11041.227114101936;11072.39101849406;11154.55381784752;11201.551937922392 09:51:17 INFO - PROCESS | 1657 | __end_tp_report 09:51:17 INFO - PROCESS | 1657 | __start_cc_report 09:51:17 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1506 09:51:17 INFO - PROCESS | 1657 | __end_cc_report 09:51:17 INFO - PROCESS | 1657 | __startTimestamp1467996677745__endTimestamp 09:51:17 INFO - PROCESS | 1657 | 09:51:17 INFO - PROCESS | 1657 | ------- Summary: start ------- 09:51:17 INFO - PROCESS | 1657 | Number of tests: 6 09:51:17 INFO - PROCESS | 1657 | 09:51:17 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5744.04 Median:5960.58 stddev:655.07 (11.0%) stddev-sans-first:628.98 09:51:17 INFO - PROCESS | 1657 | Values: 3324.4 3345.5 3346.0 3361.6 3409.0 5505.5 5588.1 5595.4 5677.8 5703.2 5591.4 5645.0 5667.8 5681.9 5702.9 5104.1 5127.7 5159.5 5184.2 5221.3 5180.0 5187.5 5211.6 5224.9 5236.5 5159.8 5174.2 5206.9 5210.4 5228.1 5290.4 5295.8 5317.9 5324.2 5348.5 5081.1 5083.9 5097.9 5210.1 5217.6 5616.3 5632.2 5649.6 5714.6 5722.6 6295.2 6300.9 6306.8 6310.0 6312.8 6130.5 6219.0 6301.5 6320.8 6331.0 5826.6 5847.9 5858.4 5871.4 5912.1 6264.3 6265.1 6265.3 6272.6 6275.0 5953.5 6028.3 6108.8 6145.6 6194.5 6308.5 6314.3 6317.9 6325.0 6341.8 6244.8 6281.2 6287.7 6296.1 6298.7 6104.1 6128.4 6168.8 6304.3 6309.7 4030.9 4971.3 5009.9 5016.6 5056.5 4451.1 4465.7 4469.5 4473.4 4496.1 5588.7 5635.3 5794.7 5824.7 5891.7 6213.3 6282.1 6292.4 6305.8 6314.7 5777.2 5786.7 5797.3 5802.1 5887.9 5303.7 5378.7 5423.8 5434.3 5460.0 6195.1 6244.0 6253.7 6323.4 6340.7 5181.4 6307.7 6308.1 6320.6 6325.3 6171.9 6186.3 6300.4 6306.4 6312.7 5087.5 6258.7 6259.7 6260.7 6271.4 6269.5 6309.9 6312.9 6325.7 6326.2 5948.3 5970.5 6015.1 6081.4 6111.1 6114.6 6127.5 6129.0 6133.7 6141.8 5903.5 5960.6 5968.1 5990.9 6011.7 5971.8 6061.8 6105.1 6120.3 6127.9 5915.1 5956.8 5969.5 5998.3 6021.9 09:51:17 INFO - PROCESS | 1657 | 09:51:17 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:70281.27 Median:22254.22 stddev:150560.13 (676.5%) stddev-sans-first:150891.42 09:51:17 INFO - PROCESS | 1657 | Values: 533.3 534.2 536.8 542.5 567.9 30469.4 31929.8 42195.8 43327.1 43428.3 44208.6 44492.8 44580.8 44590.6 44693.8 9661.5 9856.9 9859.3 9865.2 9867.8 8835.9 8989.6 9019.8 9154.9 9167.0 11810.4 11988.5 11994.7 12280.3 12369.4 11928.0 11985.9 11986.5 11987.0 12002.0 8747.4 9871.5 11540.9 11730.4 11947.3 41546.6 41800.0 41890.0 41968.6 42001.9 3640.9 3841.4 4047.4 4284.5 4419.5 29419.0 30236.4 32492.0 32580.9 33000.0 3276.7 3288.5 3369.1 3441.8 3442.9 480579.0 481051.6 482582.0 483899.7 487253.0 60720.0 61821.0 61860.0 61887.7 61947.4 63717.0 64266.0 64269.0 64317.4 64398.0 16044.5 16572.5 16582.8 16590.6 16611.9 12843.2 12903.2 12904.1 12908.5 12933.0 14153.6 15373.0 15542.0 17464.8 17600.4 6579.7 6663.6 6675.7 6694.9 6728.6 881.3 955.4 972.6 1013.1 1155.4 28684.7 30329.1 30924.0 31119.5 31135.5 993.2 1100.6 1137.0 1152.9 1203.8 15560.5 16007.2 16043.5 16238.8 16407.3 623352.0 623829.0 627197.0 627463.0 628609.0 60041.1 60299.0 60429.7 60554.7 60560.1 1260.5 1395.3 1440.5 1461.4 1462.5 30120.4 30246.5 30346.0 30816.1 30908.7 51647.2 51841.7 52120.5 52150.0 52269.2 573094.0 574013.0 574321.0 574967.0 576800.0 27515.4 28237.7 28342.6 28402.3 41831.6 40223.6 40997.4 43943.0 49136.0 53038.0 39764.2 40461.0 40873.0 41148.9 41756.4 37951.0 40496.4 40504.4 40682.0 41189.6 38913.8 39000.4 39072.6 39412.6 39419.8 33710.2 35016.1 35574.8 35616.1 35707.8 25591.7 26032.6 26112.7 26354.5 26559.7 09:51:17 INFO - PROCESS | 1657 | 09:51:17 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:36413.95 Median:22203.22 stddev:105397.94 (474.7%) stddev-sans-first:105658.88 09:51:17 INFO - PROCESS | 1657 | Values: 381.2 382.5 392.3 393.9 414.4 20219.6 21227.4 21872.5 22191.8 22196.3 21482.6 21742.1 21811.3 22210.1 22215.0 5002.8 5631.7 6072.0 6076.9 6086.9 4208.8 4432.9 5917.2 6378.8 6683.0 6403.5 6689.1 6694.7 6696.4 6747.9 6621.3 6630.8 6649.2 6652.4 6694.5 5253.4 5568.9 5573.8 5765.3 5768.1 13688.0 13921.7 13957.5 17184.0 26263.7 2324.4 2503.4 2677.9 3016.8 3095.3 10276.2 10329.6 10430.1 10598.3 10650.5 1873.2 1931.3 1942.0 1959.1 1962.5 124968.0 129581.0 129779.0 130944.0 130999.3 11575.4 11801.5 11863.6 12004.1 12054.8 14950.4 14997.2 15014.5 15018.6 15034.8 7262.0 8547.6 8705.9 8835.0 8883.0 8668.7 8671.7 8677.2 8776.8 8795.3 3711.7 4626.5 4628.1 4679.9 4686.3 1781.6 1823.6 1831.7 1953.3 1956.3 865.6 890.5 906.1 909.0 993.6 21054.2 21954.7 22058.9 22099.7 22131.0 937.3 942.5 999.4 1026.2 1031.6 8572.5 9896.5 11025.7 11266.9 11441.0 616411.0 626508.9 628321.0 636902.0 654400.0 79140.2 109908.9 116615.0 130752.0 138199.0 974.7 1005.6 1014.3 1025.5 1026.3 18748.2 19310.8 19384.3 19951.5 20529.1 60134.7 60906.7 61351.0 61374.9 61994.7 82824.8 83208.0 83227.2 85917.0 87909.0 7815.5 7861.3 8410.1 8665.6 8810.6 11735.4 13573.0 13699.8 13794.2 14099.4 5583.4 6047.6 11895.9 11998.1 12079.6 11596.9 11766.9 11909.6 11945.0 11982.8 11136.3 11774.6 11927.4 12120.2 12144.5 8460.8 8687.2 10336.6 12402.8 12710.2 11207.7 11356.5 12006.5 12109.7 12172.5 09:51:17 INFO - PROCESS | 1657 | 09:51:17 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5722.00 Median:4726.57 stddev:3234.24 (68.4%) stddev-sans-first:3228.16 09:51:17 INFO - PROCESS | 1657 | Values: 1555.6 1590.4 1846.6 1967.9 1992.1 8628.2 8911.7 9077.6 9079.0 9084.9 7762.0 7996.8 8001.2 8127.8 8565.7 2348.3 2380.6 2386.4 2387.1 2392.4 2327.3 2332.7 2352.3 2371.8 2373.9 2099.3 2166.2 2168.7 2355.3 2358.2 2136.8 2280.1 2333.2 2344.3 2351.3 2328.0 2340.7 2351.3 2352.6 2360.5 8961.0 9020.6 9030.8 9040.4 9080.2 5209.8 5305.3 5399.0 5405.8 5406.2 3480.7 3596.5 3646.8 3650.6 3652.3 1075.1 1086.9 1101.0 1108.1 1110.0 12012.3 12021.4 12044.1 12055.3 12068.3 4515.2 4679.8 4773.3 4808.1 4813.4 10041.5 10063.2 10063.5 10068.4 10081.7 5328.5 5330.4 5340.9 5343.8 5344.9 4230.9 4397.1 4521.2 4879.9 4891.2 3874.4 3939.3 3976.3 3977.0 4056.0 1268.1 1291.3 1299.2 1309.5 1310.1 901.1 912.8 916.2 927.5 954.1 5795.4 5840.1 5965.7 6024.2 6135.8 427.3 440.5 446.4 458.0 462.6 3350.5 3878.8 3910.5 3960.8 3967.8 10429.0 10675.0 10886.1 10898.3 10919.5 10305.0 10684.2 10725.8 10840.5 10973.3 7852.7 8022.7 8045.6 8045.7 8053.3 4949.9 5368.9 6023.9 6163.6 6300.5 8902.1 9899.9 10019.9 10026.6 10042.2 7689.9 9687.4 10099.5 10231.2 10232.2 7481.6 7648.0 7692.6 7728.9 7740.6 8291.1 8325.6 8489.4 8501.0 8503.6 7629.4 7678.9 7701.7 7750.5 7782.5 7455.6 7482.3 7566.4 7621.8 7632.4 6921.9 7089.9 7142.5 7171.9 7242.2 5063.3 5079.3 5118.4 5123.0 5197.6 5087.3 5116.1 5168.7 5188.3 5322.1 09:51:17 INFO - PROCESS | 1657 | 09:51:17 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4434.82 Median:4765.84 stddev:1378.22 (28.9%) stddev-sans-first:1346.73 09:51:17 INFO - PROCESS | 1657 | Values: 302.0 306.9 317.9 320.7 321.0 4600.7 4732.3 5224.9 5257.0 5591.0 5235.8 5405.1 5419.6 5501.8 5509.4 3454.8 4081.6 4132.9 4133.4 4227.5 3963.2 4160.5 4170.4 4184.7 4198.2 4092.5 4106.8 4125.7 4142.2 4159.6 3586.6 3884.5 4098.0 4117.0 4140.4 3532.1 3576.6 3579.3 3580.0 3599.7 5146.2 5242.6 5255.8 5267.3 5288.9 3655.9 4435.6 4666.1 4681.4 4727.5 4922.6 5483.5 5705.7 5750.0 5761.8 3210.9 3725.4 3883.3 3888.9 3902.8 5227.2 5969.2 6008.6 6008.8 6053.7 3076.9 3459.5 3745.4 3870.7 4016.3 3371.6 4020.2 4026.6 4035.6 4051.1 4619.8 4632.9 4666.5 4694.8 4709.2 5473.4 5748.0 5769.8 5810.6 5821.4 1168.0 1320.2 1325.4 1343.3 1347.7 680.6 837.8 880.0 883.3 921.6 5190.5 5200.4 5223.9 5231.7 5236.4 5689.0 5716.2 5720.3 5826.8 5843.5 4421.9 4960.6 5236.3 5239.6 5277.2 2260.7 2281.7 2514.4 2522.4 2533.8 4272.2 4447.4 4588.9 4880.6 4883.4 3839.3 4282.8 4315.5 4808.9 4891.6 4307.0 4898.5 4931.1 4981.7 5061.8 4227.4 4403.8 4716.1 4797.8 4806.2 4199.6 4707.6 4719.8 4721.5 5217.6 5242.7 6008.2 6164.6 6166.6 6184.6 3470.7 3906.9 3924.7 3960.2 3972.3 4733.9 5450.3 5483.7 5901.8 5985.0 5071.8 5285.4 5520.3 5773.9 5782.5 4623.0 5738.3 5768.2 5780.4 5809.5 5029.9 5261.7 5530.3 5543.1 5546.5 4684.5 4696.4 5377.8 5741.5 5756.8 5544.7 5622.0 5626.5 5628.1 5661.5 09:51:17 INFO - PROCESS | 1657 | 09:51:17 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:25320.03 Median:12626.88 stddev:40167.11 (318.1%) stddev-sans-first:40237.41 09:51:17 INFO - PROCESS | 1657 | Values: 746.7 764.6 795.8 817.9 845.4 17502.7 18128.0 18148.3 18160.4 18241.7 17223.6 17327.7 17417.3 17437.3 17447.9 723.9 749.2 908.6 932.2 938.7 736.0 740.7 740.7 888.2 920.9 721.0 920.5 933.5 934.9 936.9 928.2 929.2 930.0 930.3 932.4 553.6 603.3 700.1 700.4 722.2 15563.0 15614.5 15632.1 15633.7 15638.8 13725.9 14080.6 14097.4 14107.2 14114.4 13233.2 13237.9 13382.6 13651.2 13924.0 4578.2 4584.3 4620.6 4801.4 4806.8 111475.0 111692.0 112058.0 112802.4 113108.0 10804.4 10875.7 10913.7 10913.9 10997.7 21638.8 21671.8 21679.2 21687.7 21698.0 6688.8 7717.5 7842.8 7864.3 8054.4 4395.3 4433.7 4484.5 4493.6 4647.7 3555.8 3677.1 3679.3 3683.8 3947.3 726.0 735.5 741.7 758.0 759.0 573.4 683.8 694.1 713.8 726.9 9520.2 9824.6 9883.5 9971.0 10107.7 664.3 685.7 686.4 694.0 707.5 4527.7 4914.3 4928.2 4943.1 4963.3 102624.0 134892.0 136215.0 160571.0 169740.0 96577.1 123507.0 142149.0 142651.0 144338.0 96768.0 118058.0 118331.0 119941.4 122642.0 9429.8 9529.0 9529.0 9539.9 9579.0 51982.0 69039.7 70132.6 70587.0 71070.6 88157.6 98647.0 99737.5 101125.0 101744.5 12212.9 12300.3 12306.7 12358.9 12745.2 13517.3 13864.7 13987.2 14068.7 14158.4 10801.6 12801.4 12911.5 12936.3 12982.4 12689.2 12780.1 12897.1 12899.5 13327.5 10865.3 12476.6 12851.0 13042.5 13254.1 10747.4 10864.3 10921.4 10959.2 11477.3 10289.8 11041.2 11072.4 11154.6 11201.6 09:51:17 INFO - PROCESS | 1657 | -------- Summary: end -------- 09:51:17 INFO - PROCESS | 1657 | 09:51:18 INFO - TEST-INFO | 1657: exit 0 09:51:18 INFO - TEST-OK | dromaeo_css | took 1116260ms 09:51:18 INFO - TEST-START | kraken 09:51:18 INFO - operating with platform_type : mac_ 09:51:18 INFO - Initialising browser for kraken test... 09:51:18 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpC48lzb/profile http://localhost:49271/getInfo.html) 09:51:20 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 09:51:20 INFO - PROCESS | 1662 | colorDepth:24 09:51:20 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 09:51:20 INFO - PROCESS | 1662 | __metrics 09:51:20 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:51:20 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:51:21 INFO - TEST-INFO | 1662: exit 0 09:51:21 INFO - Browser initialized. 09:51:21 INFO - Running cycle 1/1 for kraken test... 09:51:21 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpC48lzb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:51:33 INFO - PROCESS | 1663 | RSS: Main: 243572736 09:51:33 INFO - PROCESS | 1663 | 09:51:36 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 09:51:36 INFO - PROCESS | 1663 | RSS: Main: 294285312 09:51:36 INFO - PROCESS | 1663 | 09:51:39 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 09:51:40 INFO - PROCESS | 1663 | RSS: Main: 304873472 09:51:40 INFO - PROCESS | 1663 | 09:51:43 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 09:51:44 INFO - PROCESS | 1663 | RSS: Main: 389701632 09:51:44 INFO - PROCESS | 1663 | 09:51:46 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 09:51:47 INFO - PROCESS | 1663 | RSS: Main: 290086912 09:51:47 INFO - PROCESS | 1663 | 09:51:49 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:51:49 INFO - PROCESS | 1663 | RSS: Main: 294227968 09:51:49 INFO - PROCESS | 1663 | 09:51:52 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 09:51:53 INFO - PROCESS | 1663 | RSS: Main: 330579968 09:51:53 INFO - PROCESS | 1663 | 09:51:56 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 09:51:56 INFO - PROCESS | 1663 | RSS: Main: 333000704 09:51:56 INFO - PROCESS | 1663 | 09:51:59 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 09:52:00 INFO - PROCESS | 1663 | RSS: Main: 332128256 09:52:00 INFO - PROCESS | 1663 | 09:52:01 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:52:02 INFO - PROCESS | 1663 | RSS: Main: 311812096 09:52:02 INFO - PROCESS | 1663 | 09:52:04 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:52:05 INFO - PROCESS | 1663 | RSS: Main: 318590976 09:52:05 INFO - PROCESS | 1663 | 09:52:07 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:52:08 INFO - PROCESS | 1663 | RSS: Main: 315977728 09:52:08 INFO - PROCESS | 1663 | 09:52:11 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:52:11 INFO - PROCESS | 1663 | RSS: Main: 325517312 09:52:11 INFO - PROCESS | 1663 | 09:52:14 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:52:15 INFO - PROCESS | 1663 | RSS: Main: 327712768 09:52:15 INFO - PROCESS | 1663 | 09:52:16 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 09:52:17 INFO - PROCESS | 1663 | RSS: Main: 371073024 09:52:17 INFO - PROCESS | 1663 | 09:52:17 INFO - PROCESS | 1663 | __start_tp_report 09:52:17 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 09:52:17 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 09:52:17 INFO - PROCESS | 1663 | |i|pagename|runs| 09:52:17 INFO - PROCESS | 1663 | |0;ai-astar;99;113;113;114;114;113;113;113;112;113 09:52:17 INFO - PROCESS | 1663 | |1;audio-beat-detection;146;116;103;156;107;106;126;108;140;106 09:52:17 INFO - PROCESS | 1663 | |2;audio-dft;212;187;185;184;183;182;196;262;184;183 09:52:17 INFO - PROCESS | 1663 | |3;audio-fft;80;93;48;124;76;81;88;92;82;58 09:52:17 INFO - PROCESS | 1663 | |4;audio-oscillator;81;91;92;71;72;67;96;70;68;101 09:52:17 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;93;87;95;132;124;85;90;124;93;84 09:52:17 INFO - PROCESS | 1663 | |6;imaging-darkroom;94;94;94;94;94;94;93;93;94;110 09:52:17 INFO - PROCESS | 1663 | |7;imaging-desaturate;85;84;84;90;132;84;85;85;86;98 09:52:17 INFO - PROCESS | 1663 | |8;json-parse-financial;54;53;54;55;55;53;53;54;53;53 09:52:17 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;65;65;72;111;111;108;70;70;70;72 09:52:17 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;80;83;82;83;83;85;81;86;85;85 09:52:17 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;134;137;134;155;120;142;145;157;233;136 09:52:17 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;160;164;163;162;166;229;177;161;161;163 09:52:17 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;67;66;67;66;66;67;65;65;67;65 09:52:17 INFO - PROCESS | 1663 | __end_tp_report 09:52:17 INFO - PROCESS | 1663 | __start_cc_report 09:52:17 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,2027 09:52:17 INFO - PROCESS | 1663 | __end_cc_report 09:52:17 INFO - PROCESS | 1663 | __startTimestamp1467996737097__endTimestamp 09:52:17 INFO - PROCESS | 1663 | 09:52:17 INFO - PROCESS | 1663 | ------- Summary: start ------- 09:52:17 INFO - PROCESS | 1663 | Number of tests: 14 09:52:17 INFO - PROCESS | 1663 | 09:52:17 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:4.50 (4.0%) stddev-sans-first:0.60 09:52:17 INFO - PROCESS | 1663 | Values: 99.0 113.0 113.0 114.0 114.0 113.0 113.0 113.0 112.0 113.0 09:52:17 INFO - PROCESS | 1663 | 09:52:17 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:121.40 Median:121.00 stddev:19.43 (16.1%) stddev-sans-first:18.46 09:52:17 INFO - PROCESS | 1663 | Values: 146.0 116.0 103.0 156.0 107.0 106.0 126.0 108.0 140.0 106.0 09:52:17 INFO - PROCESS | 1663 | 09:52:17 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:195.80 Median:186.00 stddev:25.02 (13.5%) stddev-sans-first:25.85 09:52:17 INFO - PROCESS | 1663 | Values: 212.0 187.0 185.0 184.0 183.0 182.0 196.0 262.0 184.0 183.0 09:52:17 INFO - PROCESS | 1663 | 09:52:17 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:82.20 Median:81.50 stddev:20.53 (25.2%) stddev-sans-first:21.76 09:52:17 INFO - PROCESS | 1663 | Values: 80.0 93.0 48.0 124.0 76.0 81.0 88.0 92.0 82.0 58.0 09:52:17 INFO - PROCESS | 1663 | 09:52:17 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:80.90 Median:76.50 stddev:12.97 (16.9%) stddev-sans-first:13.75 09:52:17 INFO - PROCESS | 1663 | Values: 81.0 91.0 92.0 71.0 72.0 67.0 96.0 70.0 68.0 101.0 09:52:17 INFO - PROCESS | 1663 | 09:52:17 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:100.70 Median:88.50 stddev:18.39 (20.8%) stddev-sans-first:19.29 09:52:17 INFO - PROCESS | 1663 | Values: 93.0 87.0 95.0 132.0 124.0 85.0 90.0 124.0 93.0 84.0 09:52:17 INFO - PROCESS | 1663 | 09:52:17 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:95.40 Median:94.00 stddev:5.15 (5.5%) stddev-sans-first:5.43 09:52:17 INFO - PROCESS | 1663 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 93.0 93.0 94.0 110.0 09:52:17 INFO - PROCESS | 1663 | 09:52:17 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:91.30 Median:85.00 stddev:14.94 (17.6%) stddev-sans-first:15.68 09:52:17 INFO - PROCESS | 1663 | Values: 85.0 84.0 84.0 90.0 132.0 84.0 85.0 85.0 86.0 98.0 09:52:17 INFO - PROCESS | 1663 | 09:52:17 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:0.82 (1.5%) stddev-sans-first:0.87 09:52:17 INFO - PROCESS | 1663 | Values: 54.0 53.0 54.0 55.0 55.0 53.0 53.0 54.0 53.0 53.0 09:52:17 INFO - PROCESS | 1663 | 09:52:17 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:81.40 Median:70.00 stddev:19.90 (28.4%) stddev-sans-first:20.20 09:52:17 INFO - PROCESS | 1663 | Values: 65.0 65.0 72.0 111.0 111.0 108.0 70.0 70.0 70.0 72.0 09:52:17 INFO - PROCESS | 1663 | 09:52:17 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:84.00 stddev:1.95 (2.3%) stddev-sans-first:1.66 09:52:17 INFO - PROCESS | 1663 | Values: 80.0 83.0 82.0 83.0 83.0 85.0 81.0 86.0 85.0 85.0 09:52:17 INFO - PROCESS | 1663 | 09:52:17 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:149.30 Median:143.50 stddev:31.31 (21.8%) stddev-sans-first:32.72 09:52:17 INFO - PROCESS | 1663 | Values: 134.0 137.0 134.0 155.0 120.0 142.0 145.0 157.0 233.0 136.0 09:52:17 INFO - PROCESS | 1663 | 09:52:17 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.60 Median:163.50 stddev:21.09 (12.9%) stddev-sans-first:22.02 09:52:17 INFO - PROCESS | 1663 | Values: 160.0 164.0 163.0 162.0 166.0 229.0 177.0 161.0 161.0 163.0 09:52:17 INFO - PROCESS | 1663 | 09:52:17 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.50 stddev:0.88 (1.3%) stddev-sans-first:0.87 09:52:17 INFO - PROCESS | 1663 | Values: 67.0 66.0 67.0 66.0 66.0 67.0 65.0 65.0 67.0 65.0 09:52:17 INFO - PROCESS | 1663 | -------- Summary: end -------- 09:52:17 INFO - PROCESS | 1663 | 09:52:17 INFO - TEST-INFO | 1663: exit 0 09:52:17 INFO - TEST-OK | kraken | took 59568ms 09:52:17 INFO - SUITE-END | took 1175s 09:52:18 INFO - Completed test suite (00:19:36) 09:52:18 INFO - javascript benchmark 09:52:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3324.384988777076, 3345.4814503751913, 3345.983270083649, 3361.554531551435, 3408.982955085224, 5505.504504594585, 5588.077967135423, 5595.4124816894255, 5677.801276955307, 5703.230063941362, 5591.41290164533, 5645.0, 5667.801626943058, 5681.914771278423, 5702.942970570293, 5104.05574968631, 5127.717975511346, 5159.535641792238, 5184.222366644995, 5221.347331583552, 5179.974100129494, 5187.48125187482, 5211.5830733541225, 5224.869378265555, 5236.476352364771, 5159.76781044852, 5174.197999310113, 5206.869828254286, 5210.426853046162, 5228.058949389108, 5290.391604965402, 5295.761690723907, 5317.8936421271355, 5324.1747529133045, 5348.51863332298, 5081.085404627165, 5083.949160508385, 5097.8725531862, 5210.088234558975, 5217.608679349026, 5616.326040875121, 5632.155176723483, 5649.604527683064, 5714.571407144439, 5722.570807189478, 6295.181626388586, 6300.87398252037, 6306.810795676137, 6309.968450157719, 6312.779052733133, 6130.5095592352955, 6219.0, 6301.464375528086, 6320.778772742932, 6331.018692102685, 5826.592138550261, 5847.9415205848245, 5858.355580886099, 5871.354151043424, 5912.113183022581, 6264.342244064399, 6265.0602409638, 6265.342139075423, 6272.6236425814595, 6274.9686251569365, 5953.464188223081, 6028.306744724325, 6108.847278817976, 6145.586515101462, 6194.5044396448175, 6308.495320374359, 6314.273858506239, 6317.873642527124, 6325.0, 6341.841453963688, 6244.781432649836, 6281.183446151971, 6287.685615719196, 6296.087067375205, 6298.7480500780375, 6104.084387341845, 6128.380904898417, 6168.814935551941, 6304.338044505261, 6309.747610095545, 4030.858919937788, 4971.304017437562, 5009.874753131128, 5016.598672106223, 5056.519630635157, 4451.065276292015, 4465.732056076646, 4469.5306992765945, 4473.3960915276675, 4496.145732310851, 5588.692621905834, 5635.267415235993, 5794.739236734357, 5824.650520968756, 5891.7054147292465, 6213.347598502092, 6282.120503129566, 6292.433680968735, 6305.779297724649, 6314.747410103544, 5777.220075289866, 5786.71066446676, 5797.333306669702, 5802.100674395476, 5887.941120588739, 5303.655262408009, 5378.650387724863, 5423.810166644149, 5434.347878254634, 5459.972700136554, 6195.132681424604, 6244.0321750128805, 6253.749850005949, 6323.367663233824, 6340.7463701451425, 5181.404138524118, 6307.684615769102, 6308.053791931247, 6320.5891617044745, 6325.272593651604, 6171.938280617136, 6186.288576813724, 6300.432961033529, 6306.400891915261, 6312.684365781784, 5087.491250874883, 6258.655773932477, 6259.749610015549, 6260.655663938527, 6271.372862713874, 6269.529785266214, 6309.873802524015, 6312.873742525216, 6325.683715814283, 6326.240851097898, 5948.2862056553495, 5970.462658360769, 6015.0676645120075, 6081.361457046946, 6111.083337499233, 6114.631938157225, 6127.494965205145, 6128.961487065963, 6133.701927635241, 6141.792871882774, 5903.468687818117, 5960.5827592068135, 5968.104784282393, 5990.940090599038, 6011.6994150293185, 5971.820845374646, 6061.787837425669, 6105.095857752984, 6120.348535113257, 6127.944784664208, 5915.053591425351, 5956.76172953077, 5969.492593129459, 5998.3401825798255, 6021.879562408815], "value": 5701.868189554438, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [533.2880604393135, 534.1533669134421, 536.758458693588, 542.5280006394437, 567.9148127780834, 30469.390612187744, 31929.840350798244, 42195.78902105493, 43327.133457330834, 43428.34857477133, 44208.557914420846, 44492.77753611236, 44580.77709611447, 44590.55409445905, 44693.7765311173, 9661.468619225958, 9856.90143098567, 9859.260555458333, 9865.1614612758, 9867.753306167331, 8835.911640883573, 8989.64041438345, 9019.819603607924, 9154.908450915473, 9166.954165229165, 11810.350430726307, 11988.520459181666, 11994.700132496713, 12280.263184208932, 12369.381530923463, 11927.986121179667, 11985.940070299594, 11986.460609272517, 11987.0, 12002.0, 8747.387682862201, 9871.457069861155, 11540.94229528847, 11730.413479326086, 11947.342896140708, 41546.58453415457, 41800.0, 41890.0, 41968.58031419677, 42001.94995125116, 3640.8907732767793, 3841.386627532231, 4047.433359329667, 4284.485861696585, 4419.53594872537, 29419.0, 30236.395272094436, 32492.0, 32580.85966991144, 33000.0, 3276.6559511251453, 3288.5067239913965, 3369.107186595554, 3441.8107004114763, 3442.896713098611, 480579.0, 481051.5947420276, 482582.0, 483899.70752193726, 487253.0, 60720.0, 61821.0, 61860.0, 61887.6905615478, 61947.38052619416, 63717.0, 64266.036009459895, 64269.0, 64317.35682643114, 64398.034029489594, 16044.518664440086, 16572.50282491527, 16582.75125873113, 16590.585235369213, 16611.91694041522, 12843.165194262343, 12903.225806451643, 12904.096713230172, 12908.483660653468, 12933.0, 14153.575392738234, 15373.001509803878, 15541.98977066487, 17464.825351746575, 17600.383986560664, 6579.736810527621, 6663.566868153554, 6675.699593518303, 6694.899576506356, 6728.629925354055, 881.3389957531851, 955.3599088610508, 972.6498460554195, 1013.1337706261188, 1155.416514660108, 28684.713152868622, 30329.090127296215, 30924.0, 31119.533207001914, 31135.509159267658, 993.1905497019906, 1100.5652767156928, 1137.0448822988728, 1152.9077673786078, 1203.7532305877273, 15560.501417222145, 16007.199640017952, 16043.518694439186, 16238.75641865373, 16407.261673224733, 623352.0, 623829.0, 627197.0, 627463.0, 628609.0, 60041.09938350928, 60299.0, 60429.697851510464, 60554.697226513585, 60560.09159862606, 1260.521002019227, 1395.302348825567, 1440.5390275111863, 1461.3643065266642, 1462.5319897632655, 30120.397592047597, 30246.54630180505, 30346.0, 30816.075517734505, 30908.69091309058, 51647.22529161991, 51841.740791297314, 52120.478795211566, 52149.95700086053, 52269.215961761365, 573094.0, 574013.0, 574321.0, 574967.0, 576800.0, 27515.44969100647, 28237.717622823508, 28342.574861376685, 28402.289942751595, 41831.58168418277, 40223.597764021986, 40997.38503922503, 43943.0, 49136.0, 53038.0, 39764.20471590494, 40461.0, 40873.0, 41148.94399992015, 41756.373654395815, 37951.0, 40496.392554111124, 40504.3924341141, 40681.98295042648, 41189.58810411977, 38913.805430972665, 39000.414993774546, 39072.609273908034, 39412.60587394204, 39419.80290098531, 33710.157246069044, 35016.12459688426, 35574.82212588921, 35616.10959725924, 35707.82146089253, 25591.744082558936, 26032.609510857732, 26112.73887261103, 26354.4729105413, 26559.734402655726], "value": 20651.61499780066, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.22991557054735, 382.5371300726121, 392.33107551624477, 393.8562424714978, 414.44878311845247, 20219.595608087828, 21227.36317910465, 21872.45318867029, 22191.778082219153, 22196.334109976706, 21482.570348593017, 21742.130314787388, 21811.3456596302, 22210.11159553616, 22215.0, 5002.849914502571, 5631.690257035862, 6071.969640151793, 6076.908846367308, 6086.908696369558, 4208.768517731523, 4432.933505997413, 5917.2307600012155, 6378.808635740936, 6683.030960510723, 6403.519736019817, 6689.130413046297, 6694.665266736668, 6696.397324240817, 6747.898781518281, 6621.337866213388, 6630.767923122716, 6649.235337936103, 6652.434543063821, 6694.464442844561, 5253.3958594761325, 5568.916466253009, 5573.832785016456, 5765.279340082489, 5768.077107662753, 13688.041837071409, 13921.651958701012, 13957.511487097903, 17184.0, 26263.73736262632, 2324.3956571291587, 2503.4492411669403, 2677.919662410131, 3016.75865930725, 3095.303556699747, 10276.229282803819, 10329.638462653771, 10430.113440357503, 10598.31110977784, 10650.520726567323, 1873.175802646831, 1931.33368987699, 1941.990290048555, 1959.1183967214668, 1962.460323411059, 124968.0, 129581.0, 129779.0, 130943.99999999905, 130999.34500327533, 11575.42122893852, 11801.468933897995, 11863.644090677293, 12004.099692523096, 12054.758904821854, 14950.401983920521, 14997.17515536656, 15014.498550144897, 15018.624534386729, 15034.774478382833, 7261.963690181621, 8547.57262136903, 8705.91176102987, 8834.983976842745, 8882.955585222033, 8668.739937801876, 8671.6531338747, 8677.21905028538, 8776.824463510695, 8795.296376289882, 3711.740178187555, 4626.537346265347, 4628.0562163891245, 4679.9298010529865, 4686.27362758773, 1781.6347648731978, 1823.575949367094, 1831.6767652648084, 1953.3163392812687, 1956.3348461522867, 865.6234537976001, 890.5057692980329, 906.0712769411406, 908.9772755681029, 993.6323560282742, 21054.156095807095, 21954.670679939813, 22058.88970555137, 22099.668504972436, 22131.0, 937.315759495572, 942.495764765847, 999.4053538144792, 1026.2200727447193, 1031.63377001165, 8572.52851093196, 9896.476398518333, 11025.681990653717, 11266.94366528162, 11440.970312671732, 616411.0, 626508.8674556598, 628321.0, 636902.0, 654400.0, 79140.20859791443, 109908.90091098986, 116615.0, 130752.0, 138199.0, 974.7222041718077, 1005.6480231918916, 1014.2595904989399, 1025.5487585462372, 1026.2559644257854, 18748.156332964776, 19310.806891931465, 19384.321548745727, 19951.501212469804, 20529.081625057664, 60134.69932650309, 60906.69546652238, 61351.0, 61374.85188018398, 61994.69002654958, 82824.75762863683, 83208.0, 83227.16772832195, 85917.0, 87909.0, 7815.53106813593, 7861.2531809478005, 8410.116937721452, 8665.610047547749, 8810.559472026245, 11735.354555499529, 13573.0, 13699.794503082263, 13794.172349659053, 14099.365528551034, 5583.385827558884, 6047.637141771508, 11895.881041189823, 11998.100142489522, 12079.637610871689, 11596.884031159918, 11766.941165294118, 11909.583164589198, 11944.950920398423, 11982.820257695967, 11136.276142050741, 11774.587889423829, 11927.403629818647, 12120.212186207867, 12144.514219431478, 8460.830783384421, 8687.218150366498, 10336.586536538454, 12402.75194496087, 12710.237385756884, 11207.66377008691, 11356.545738170711, 12006.459709313276, 12109.69725756863, 12172.513099476277], "value": 9833.07067115823, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1555.6033211531058, 1590.3956496531316, 1846.5568263616735, 1967.9114439850205, 1992.0736857361323, 8628.223459888608, 8911.68809091682, 9077.591508382122, 9079.046700096485, 9084.8637270441, 7762.0, 7996.760097197079, 8001.19988001201, 8127.796805079876, 8565.743027709179, 2348.2720356689397, 2380.642903564462, 2386.4153282445736, 2387.069043073203, 2392.4018995251186, 2327.325075728037, 2332.6734257204007, 2352.3413444235644, 2371.762823717632, 2373.881305934705, 2099.2862426774896, 2166.1876796201423, 2168.6855405966126, 2355.340504658698, 2358.2099996501192, 2136.75427325858, 2280.144945645391, 2333.195047708538, 2344.2615576093644, 2351.3298709867777, 2328.0, 2340.73081595617, 2351.318117745852, 2352.6118190498546, 2360.4570948681726, 8960.955195224047, 9020.594073266719, 9030.774230644221, 9040.41237319572, 9080.182783549446, 5209.817656382009, 5305.257263983045, 5398.974194902956, 5405.7567409466665, 5406.216098665711, 3480.6519348065244, 3596.514470546469, 3646.7994260315672, 3650.5984341722387, 3652.3243200008083, 1075.0808059109427, 1086.869575650919, 1101.0420933787575, 1108.0636861851722, 1110.0176343935675, 12012.279263244232, 12021.39893005355, 12044.096692748079, 12055.33695646739, 12068.336241506713, 4515.209838278288, 4679.836205732851, 4773.307870358779, 4808.06242782654, 4813.374261346073, 10041.497925103715, 10063.19494440443, 10063.496825158712, 10068.44623545712, 10081.69754907354, 5328.547073498796, 5330.38700549434, 5340.946590534123, 5343.812966546152, 5344.893102137936, 4230.915381692349, 4397.1205758848355, 4521.231390663595, 4879.853604391874, 4891.168501354707, 3874.3994680824517, 3939.330313846653, 3976.3240249157134, 3976.991619232327, 4055.9797201013807, 1268.099649249025, 1291.283337747559, 1299.2009913902882, 1309.5482058689736, 1310.0633047370984, 901.1304091551684, 912.8356895758694, 916.1571354354011, 927.5471585808, 954.0745476887547, 5795.449432303924, 5840.094785308284, 5965.671888046112, 6024.186734790835, 6135.754569817157, 427.2523084601947, 440.537435692528, 446.38176126065633, 458.0358345682357, 462.6437643014927, 3350.5476760637002, 3878.825452854628, 3910.452536644872, 3960.8415663373717, 3967.821448034845, 10429.0, 10674.985876341736, 10886.074683651977, 10898.291611045257, 10919.454027298603, 10305.021023002802, 10684.198685098649, 10725.785484290427, 10840.457977101114, 10973.286736362112, 7852.725154619675, 8022.719204827803, 8045.597720113971, 8045.678172873019, 8053.275205231557, 4949.901001979868, 5368.919466207932, 6023.909641355295, 6163.568550201622, 6300.495960323072, 8902.109789021306, 9899.851502227328, 10019.949900250453, 10026.598936042768, 10042.246831487522, 7689.92310076892, 9687.370320929118, 10099.495025248854, 10231.232657550565, 10232.23258255619, 7481.551106933602, 7647.961760191163, 7692.615369231404, 7728.884066739116, 7740.612969351398, 8291.129431409616, 8325.625346859284, 8489.363297752816, 8501.022382425852, 8503.617337219715, 7629.351505121905, 7678.884816727866, 7701.730439434593, 7750.496217745828, 7782.455228133977, 7455.552666840007, 7482.289182527651, 7566.35685966699, 7621.809454763676, 7632.389408847168, 6921.89617155733, 7089.929100708927, 7142.535735177197, 7171.892421613576, 7242.203357630762, 5063.291139240583, 5079.263506791568, 5118.3857937046305, 5122.974385128051, 5197.636165468381, 5087.313212716236, 5116.079105760999, 5168.741562921764, 5188.299579556711, 5322.068637988414], "value": 4523.116430099969, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.01693487697554, 306.9109958112148, 317.8728508596562, 320.7434052757793, 320.9630892447369, 4600.746958917262, 4732.290156476531, 5224.947750522494, 5257.00116977774, 5590.965671350803, 5235.816746413876, 5405.108157154074, 5419.620626556142, 5501.752421141048, 5509.3664228613725, 3454.844531996066, 4081.5918408159246, 4132.876013719593, 4133.400656904747, 4227.450431443924, 3963.2271707017144, 4160.5007399112, 4170.436991006206, 4184.727992680466, 4198.20234155511, 4092.4679791627045, 4106.753594784308, 4125.669946404296, 4142.150859073882, 4159.625633692967, 3586.6413358664186, 3884.495015647976, 4098.016476045757, 4116.958830411688, 4140.441040459529, 3532.0993146747414, 3576.5886923003927, 3579.3020361029608, 3579.9642003579893, 3599.6760291573623, 5146.150885103953, 5242.55438287746, 5255.842324730264, 5267.28891599633, 5288.894222115536, 3655.9268814623824, 4435.62297204738, 4666.136764698542, 4681.391419115494, 4727.45634252062, 4922.556969872693, 5483.506484416422, 5705.74324155414, 5749.9712501437225, 5761.798337058225, 3210.887618933326, 3725.37945993491, 3883.2815929053218, 3888.8833334999995, 3902.804859757001, 5227.242049902781, 5969.164316995624, 6008.639481631116, 6008.789692360747, 6053.667048312341, 3076.9384612308077, 3459.5469902640953, 3745.382011968049, 3870.651641352292, 4016.3373043447505, 3371.561696979377, 4020.19596080785, 4026.6108192673482, 4035.5964403559997, 4051.0959848870984, 4619.792109355087, 4632.86101416958, 4666.510016448292, 4694.835680751157, 4709.175894218567, 5473.370562385377, 5747.959474386616, 5769.769209231584, 5810.622309549865, 5821.359650438449, 1168.0071938851909, 1320.2408615046386, 1325.3704490367, 1343.3417625363697, 1347.7425312601354, 680.6018479189729, 837.7863644770545, 879.9792241000537, 883.3286702106445, 921.6267411698273, 5190.506901844319, 5200.375954885438, 5223.869403264949, 5231.686098834082, 5236.397814251333, 5689.0, 5716.199732037435, 5720.2849643794525, 5826.79606213789, 5843.5325173985975, 4421.911561768747, 4960.553550180501, 5236.345456817898, 5239.554637855825, 5277.182036784305, 2260.6609008648834, 2281.7147856517936, 2514.3588384961718, 2522.41984343602, 2533.7702821516946, 4272.209641216385, 4447.3773671685985, 4588.862334129982, 4880.6095512358925, 4883.361895387888, 3839.310703290543, 4282.807273672693, 4315.525292217853, 4808.8797780055775, 4891.584215341664, 4306.978465107717, 4898.510148985073, 4931.139938835467, 4981.708414129541, 5061.848144555669, 4227.365895115758, 4403.75779332127, 4716.089983556665, 4797.784099715452, 4806.173654011453, 4199.635118586532, 4707.646926480596, 4719.799378553159, 4721.4570324413135, 5217.6086793491395, 5242.711650859239, 6008.218931538872, 6164.630122192682, 6166.608365399103, 6184.567080304336, 3470.7331823884606, 3906.941395879121, 3924.7252692311263, 3960.188161426843, 3972.2849887020616, 4733.9053218936115, 5450.31871016123, 5483.698396588066, 5901.763929442946, 5984.970075149597, 5071.822486212965, 5285.392179899361, 5520.337559492726, 5773.942260577341, 5782.45066718661, 4623.0, 5738.340090889601, 5768.221290125779, 5780.393058728773, 5809.477147056785, 5029.893423446836, 5261.73691315425, 5530.336359636869, 5543.085390910454, 5546.528545073553, 4684.508126646653, 4696.38946936896, 5377.838664840061, 5741.5119714824705, 5756.827295181151, 5544.726662068532, 5621.971890140523, 5626.521745651666, 5628.099504079491, 5661.518770904519], "value": 3997.747041442841, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.7498388040007, 764.5794812852932, 795.8209402884349, 817.87731840224, 845.4124383553433, 17502.737458938125, 18128.0, 18148.274069037256, 18160.36438724645, 18241.726374104364, 17223.569410764736, 17327.740083898752, 17417.303307867704, 17437.3025078997, 17447.91276043618, 723.8805597076476, 749.2470067582072, 908.5956749246587, 932.2169377722712, 938.7090002099337, 735.9806667765157, 740.7111226621622, 740.7333359990399, 888.2094935507404, 920.8987011428737, 720.9675564599579, 920.4707293306342, 933.5425641435716, 934.8550974598915, 936.8688383626297, 928.1971095002774, 929.1730359979623, 929.9907000930006, 930.2976252929063, 932.4405356785942, 553.6180035775302, 603.2730559675624, 700.1248439450686, 700.3556727810376, 722.1911459165716, 15563.0, 15614.531564053097, 15632.14023228722, 15633.687326253412, 15638.843611563852, 13725.862741372559, 14080.577582672433, 14097.365618547088, 14107.153570785786, 14114.435422583085, 13233.206007639572, 13237.881938889732, 13382.598522044354, 13651.249181295025, 13924.025318227728, 4578.221702310582, 4584.335271385664, 4620.584147426748, 4801.375821143229, 4806.831760888387, 111474.99999999919, 111692.0, 112058.0, 112802.43598782035, 113108.0, 10804.405757683246, 10875.673729788119, 10913.727156821144, 10913.945430272797, 10997.670069897917, 21638.75418687178, 21671.783282167293, 21679.241226556995, 21687.67468487974, 21698.0, 6688.83277918056, 7717.498362606412, 7842.803929901798, 7864.331531819745, 8054.395920306, 4395.340698895127, 4433.667474939392, 4484.506704262529, 4493.550644935545, 4647.651426143053, 3555.7510974231554, 3677.1358730698266, 3679.3193259247123, 3683.7605555638797, 3947.3289540777564, 726.0488759724777, 735.5108852612952, 741.6959046790897, 758.0145810446397, 759.0436050576313, 573.4323020210046, 683.7504310926575, 694.0907411291224, 713.7537549545399, 726.9491135620456, 9520.23838092951, 9824.607015719292, 9883.456409897524, 9971.00289970997, 10107.69676909694, 664.2858926653867, 685.6811582614008, 686.3822559696214, 693.9514234003572, 707.4835370179798, 4527.660425468103, 4914.33656456381, 4928.162212423825, 4943.134951383566, 4963.280324352949, 102624.0, 134892.0, 136215.0, 160571.0, 169740.0, 96577.06845863043, 123507.0, 142149.0, 142651.0, 144338.0, 96768.0, 118058.0, 118331.0, 119941.40029299798, 122642.0, 9429.764255893657, 9528.95235523818, 9529.03283707121, 9539.855217373959, 9578.952105239428, 51982.0, 69039.65480172567, 70132.64933675299, 70587.0, 71070.64464677643, 88157.55921220353, 98647.0, 99737.50131249588, 101125.0, 101744.4912775461, 12212.938935305621, 12300.323482208565, 12306.692332691755, 12358.938205308916, 12745.235285882505, 13517.256550889795, 13864.653383665489, 13987.160770353812, 14068.718625627225, 14158.43366265379, 10801.567937282422, 12801.359932003177, 12911.5480958166, 12936.288504131986, 12982.415791289226, 12689.175203611736, 12780.096959973342, 12897.097203195686, 12899.548515801902, 13327.466901324226, 10865.348079115278, 12476.57589410286, 12851.0, 13042.54351097707, 13254.072214944861, 10747.408892510673, 10864.348139111678, 10921.39932303699, 10959.178061645249, 11477.253978491372, 10289.794204116026, 11041.227114101936, 11072.39101849406, 11154.55381784752, 11201.551937922392], "value": 8043.71123682814, "unit": "score"}], "name": "dromaeo_css", "value": 7431.386949891031, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 113.0, 113.0, 114.0, 114.0, 113.0, 113.0, 113.0, 112.0, 113.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 116.0, 103.0, 156.0, 107.0, 106.0, 126.0, 108.0, 140.0, 106.0], "value": 121.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 187.0, 185.0, 184.0, 183.0, 182.0, 196.0, 262.0, 184.0, 183.0], "value": 195.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 93.0, 48.0, 124.0, 76.0, 81.0, 88.0, 92.0, 82.0, 58.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 91.0, 92.0, 71.0, 72.0, 67.0, 96.0, 70.0, 68.0, 101.0], "value": 80.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 87.0, 95.0, 132.0, 124.0, 85.0, 90.0, 124.0, 93.0, 84.0], "value": 100.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 110.0], "value": 95.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 90.0, 132.0, 84.0, 85.0, 85.0, 86.0, 98.0], "value": 91.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 55.0, 55.0, 53.0, 53.0, 54.0, 53.0, 53.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 72.0, 111.0, 111.0, 108.0, 70.0, 70.0, 70.0, 72.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 82.0, 83.0, 83.0, 85.0, 81.0, 86.0, 85.0, 85.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 137.0, 134.0, 155.0, 120.0, 142.0, 145.0, 157.0, 233.0, 136.0], "value": 149.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 164.0, 163.0, 162.0, 166.0, 229.0, 177.0, 161.0, 161.0, 163.0], "value": 170.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 66.0, 66.0, 67.0, 65.0, 65.0, 67.0, 65.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1483.7999999999997, "alertThreshold": 2.0}]} 09:52:18 INFO - Return code: 0 09:52:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:52:18 INFO - # TBPL SUCCESS # 09:52:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:52:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:52:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:52:18 INFO - 2016-07-08 09:52:18.436 screenresolution[1667:10341] starting screenresolution argv=screenresolution get 09:52:18 INFO - 2016-07-08 09:52:18.439 screenresolution[1667:10341] Display 0: 1600x1200x32@60 09:52:18 INFO - 2016-07-08 09:52:18.445 screenresolution[1668:10344] starting screenresolution argv=screenresolution list 09:52:18 INFO - Available Modes on Display 0 09:52:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:52:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:52:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:52:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:52:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:52:18 INFO - Intel Iris: 09:52:18 INFO - Chipset Model: Intel Iris 09:52:18 INFO - Type: GPU 09:52:18 INFO - Bus: Built-In 09:52:18 INFO - VRAM (Dynamic, Max): 1536 MB 09:52:18 INFO - Vendor: Intel (0x8086) 09:52:18 INFO - Device ID: 0x0a2e 09:52:18 INFO - Revision ID: 0x0009 09:52:18 INFO - Displays: 09:52:18 INFO - Display: 09:52:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:52:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:52:18 INFO - Display Serial Number: 1600x1200 60 09:52:18 INFO - Main Display: Yes 09:52:18 INFO - Mirror: Off 09:52:18 INFO - Online: Yes 09:52:18 INFO - Rotation: Supported 09:52:18 INFO - Return code: 0 09:52:18 INFO - Running post-action listener: _resource_record_post_action 09:52:18 INFO - ##### 09:52:18 INFO - ##### Finished run-tests step (success) 09:52:18 INFO - ##### 09:52:18 INFO - Running post-run listener: _resource_record_post_run 09:52:18 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 32726528; Write bytes: 707417088; Read time: 317; Write time: 1946 09:52:18 INFO - TinderboxPrint: CPU usage
53.5% 09:52:18 INFO - TinderboxPrint: I/O read bytes / time
32,726,528 / 317 09:52:18 INFO - TinderboxPrint: I/O write bytes / time
707,417,088 / 1,946 09:52:18 INFO - TinderboxPrint: CPU idle
2,254.9 (46.5%) 09:52:18 INFO - TinderboxPrint: CPU system
969.1 (20.0%) 09:52:18 INFO - TinderboxPrint: CPU user
1,621.6 (33.5%) 09:52:18 INFO - TinderboxPrint: Swap in / out
435,560,448 / 0 09:52:18 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 400859648; Write bytes: 396666880; Read time: 29485; Write time: 769 09:52:18 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30723584; Write bytes: 295197696; Read time: 293; Write time: 1139 09:52:19 INFO - Running post-run listener: _upload_blobber_files 09:52:19 INFO - Blob upload gear active. 09:52:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:52:19 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 09:52:19 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'] 09:52:19 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 09:52:19 INFO - (blobuploader) - INFO - Open directory for files ... 09:52:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:52:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:52:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:52:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:52:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:52:20 INFO - (blobuploader) - INFO - Done attempting. 09:52:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:52:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:52:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:52:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:52:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:52:21 INFO - (blobuploader) - INFO - Done attempting. 09:52:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:52:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:52:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:52:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:52:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:52:24 INFO - (blobuploader) - INFO - Done attempting. 09:52:24 INFO - (blobuploader) - INFO - Iteration through files over. 09:52:24 INFO - Return code: 0 09:52:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:52:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:52:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a0291a5a9fa805305884f7ca2bc79f86c3d5df9febb0f44939ddb21bf985996fd0e9e78f0beb6656fd3f6a785133ab73e989467c613b56ca1db83b93dd7c8539", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/058dc784936b33a9001a85ce73a0f530616dde3a3eb619b244d3bd1f5414374a7e7df8ce0bd388e756182a4e904d1094ebf8e9fe166b006a3c169b3c333a0db6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c7191258ba48174441c7a95b05f9ee0a3a1b26609ff1da474e807de77b1306b43f48c19f4aa3e47c39c617cc05f513b7fe848757daaef48ac1ce317e16aaadd4"} 09:52:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:52:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:52:24 INFO - Contents: 09:52:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a0291a5a9fa805305884f7ca2bc79f86c3d5df9febb0f44939ddb21bf985996fd0e9e78f0beb6656fd3f6a785133ab73e989467c613b56ca1db83b93dd7c8539", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/058dc784936b33a9001a85ce73a0f530616dde3a3eb619b244d3bd1f5414374a7e7df8ce0bd388e756182a4e904d1094ebf8e9fe166b006a3c169b3c333a0db6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c7191258ba48174441c7a95b05f9ee0a3a1b26609ff1da474e807de77b1306b43f48c19f4aa3e47c39c617cc05f513b7fe848757daaef48ac1ce317e16aaadd4"} 09:52:24 INFO - Running post-run listener: copy_logs_to_upload_dir 09:52:24 INFO - Copying logs to upload dir... 09:52:24 INFO - mkdir: /builds/slave/test/build/upload/logs 09:52:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.685224 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2016-07-08 09:52:25.068335) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 09:52:25.069690) ========= 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.FSbHansihQ/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.fNMtZDMfb6/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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/a0291a5a9fa805305884f7ca2bc79f86c3d5df9febb0f44939ddb21bf985996fd0e9e78f0beb6656fd3f6a785133ab73e989467c613b56ca1db83b93dd7c8539", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/058dc784936b33a9001a85ce73a0f530616dde3a3eb619b244d3bd1f5414374a7e7df8ce0bd388e756182a4e904d1094ebf8e9fe166b006a3c169b3c333a0db6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c7191258ba48174441c7a95b05f9ee0a3a1b26609ff1da474e807de77b1306b43f48c19f4aa3e47c39c617cc05f513b7fe848757daaef48ac1ce317e16aaadd4"} build_url:https://queue.taskcluster.net/v1/task/ez393ieLS86xU7rQRtWd_g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010538 build_url: 'https://queue.taskcluster.net/v1/task/ez393ieLS86xU7rQRtWd_g/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/a0291a5a9fa805305884f7ca2bc79f86c3d5df9febb0f44939ddb21bf985996fd0e9e78f0beb6656fd3f6a785133ab73e989467c613b56ca1db83b93dd7c8539", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/058dc784936b33a9001a85ce73a0f530616dde3a3eb619b244d3bd1f5414374a7e7df8ce0bd388e756182a4e904d1094ebf8e9fe166b006a3c169b3c333a0db6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c7191258ba48174441c7a95b05f9ee0a3a1b26609ff1da474e807de77b1306b43f48c19f4aa3e47c39c617cc05f513b7fe848757daaef48ac1ce317e16aaadd4"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 09:52:25.106420) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 09:52:25.106748) ========= 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.FSbHansihQ/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.fNMtZDMfb6/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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.004847 ========= master_lag: 1.98 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 09:52:27.091244) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 09:52:27.091590) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 09:52:27.093395) ========= ========= Total master_lag: 2.19 =========