builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0356 starttime: 1464609313.24 results: success (0) buildid: 20160530034637 builduid: 0b3ff36e268143bebae2b558b3180e94 revision: 3fa0f58b23395a7f9f95384b18607ff10c581c00 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 04:55:13.235358) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 04:55:13.235755) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 04:55:13.236027) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 04:55:13.263717) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 04:55:13.264019) ========= 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.UOs9vQPzh3/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.rSUWVnnxFY/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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-05-30 04:55:13-- 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% 14.0M=0.001s 2016-05-30 04:55:13 (14.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.143646 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 04:55:13.427374) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 04:55:13.427675) ========= 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.UOs9vQPzh3/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.rSUWVnnxFY/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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.029191 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 04:55:13.476329) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 04:55:13.476662) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3fa0f58b23395a7f9f95384b18607ff10c581c00 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3fa0f58b23395a7f9f95384b18607ff10c581c00 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UOs9vQPzh3/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.rSUWVnnxFY/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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-05-30 04:55:13,558 truncating revision to first 12 chars 2016-05-30 04:55:13,559 Setting DEBUG logging. 2016-05-30 04:55:13,559 attempt 1/10 2016-05-30 04:55:13,559 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3fa0f58b2339?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 04:55:14,592 unpacking tar archive at: mozilla-aurora-3fa0f58b2339/testing/mozharness/ program finished with exit code 0 elapsedTime=1.316255 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 04:55:14.812520) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 04:55:14.812835) ========= script_repo_revision: 3fa0f58b23395a7f9f95384b18607ff10c581c00 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 04:55:14.813228) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 04:55:14.813491) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 04:55:14.831937) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-05-30 04:55:14.832202) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UOs9vQPzh3/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.rSUWVnnxFY/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:55:15 INFO - MultiFileLogger online at 20160530 04:55:15 in /builds/slave/test 04:55:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 04:55:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:55:15 INFO - {'append_to_log': False, 04:55:15 INFO - 'base_work_dir': '/builds/slave/test', 04:55:15 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:55:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:55:15 INFO - 'branch': 'Mozilla-Aurora', 04:55:15 INFO - 'buildbot_json_path': 'buildprops.json', 04:55:15 INFO - 'config_files': ('talos/mac_config.py',), 04:55:15 INFO - 'default_actions': ('clobber', 04:55:15 INFO - 'read-buildbot-config', 04:55:15 INFO - 'download-and-extract', 04:55:15 INFO - 'populate-webroot', 04:55:15 INFO - 'create-virtualenv', 04:55:15 INFO - 'install', 04:55:15 INFO - 'run-tests'), 04:55:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:55:15 INFO - 'download_minidump_stackwalk': True, 04:55:15 INFO - 'download_symbols': 'ondemand', 04:55:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:55:15 INFO - 'tooltool.py': '/tools/tooltool.py', 04:55:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:55:15 INFO - '/tools/misc-python/virtualenv.py')}, 04:55:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:55:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:55:15 INFO - 'installer_path': 'installer.exe', 04:55:15 INFO - 'log_level': 'info', 04:55:15 INFO - 'log_name': 'talos', 04:55:15 INFO - 'log_to_console': True, 04:55:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:55:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:55:15 INFO - 'opt_config_files': (), 04:55:15 INFO - 'pip_index': False, 04:55:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:55:15 INFO - 'cmd': ('bash', 04:55:15 INFO - '-c', 04:55:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:55:15 INFO - 'enabled': True, 04:55:15 INFO - 'halt_on_failure': False, 04:55:15 INFO - 'name': 'check_screen_resolution'},), 04:55:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:55:15 INFO - 'cmd': ('bash', 04:55:15 INFO - '-c', 04:55:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:55:15 INFO - 'enabled': True, 04:55:15 INFO - 'halt_on_failure': False, 04:55:15 INFO - 'name': 'check_screen_resolution'},), 04:55:15 INFO - 'run_cmd_checks_enabled': True, 04:55:15 INFO - 'sps_profile': False, 04:55:15 INFO - 'sps_profile_interval': 0, 04:55:15 INFO - 'suite': 'dromaeojs', 04:55:15 INFO - 'system_bits': '32', 04:55:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:55:15 INFO - 'title': 't-yosemite-r7-0356', 04:55:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:55:15 INFO - 'use_talos_json': True, 04:55:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:55:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:55:15 INFO - 'work_dir': 'build'} 04:55:15 INFO - ##### 04:55:15 INFO - ##### Running clobber step. 04:55:15 INFO - ##### 04:55:15 INFO - Running pre-action listener: _resource_record_pre_action 04:55:15 INFO - Running main action method: clobber 04:55:15 INFO - rmtree: /builds/slave/test/build 04:55:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:55:17 INFO - Running post-action listener: _resource_record_post_action 04:55:17 INFO - ##### 04:55:17 INFO - ##### Running read-buildbot-config step. 04:55:17 INFO - ##### 04:55:17 INFO - Running pre-action listener: _resource_record_pre_action 04:55:17 INFO - Running main action method: read_buildbot_config 04:55:17 INFO - Using buildbot properties: 04:55:17 INFO - { 04:55:17 INFO - "project": "", 04:55:17 INFO - "product": "firefox", 04:55:17 INFO - "script_repo_revision": "production", 04:55:17 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 04:55:17 INFO - "repository": "", 04:55:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 04:55:17 INFO - "buildid": "20160530034637", 04:55:17 INFO - "pgo_build": "False", 04:55:17 INFO - "basedir": "/builds/slave/test", 04:55:17 INFO - "buildnumber": 363, 04:55:17 INFO - "slavename": "t-yosemite-r7-0356", 04:55:17 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:55:17 INFO - "platform": "yosemite_r7", 04:55:17 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 04:55:17 INFO - "branch": "mozilla-aurora", 04:55:17 INFO - "repo_path": "releases/mozilla-aurora", 04:55:17 INFO - "revision": "3fa0f58b23395a7f9f95384b18607ff10c581c00", 04:55:17 INFO - "stage_platform": "macosx64", 04:55:17 INFO - "builduid": "0b3ff36e268143bebae2b558b3180e94", 04:55:17 INFO - "slavebuilddir": "test" 04:55:17 INFO - } 04:55:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 04:55:17 INFO - Running post-action listener: _resource_record_post_action 04:55:17 INFO - ##### 04:55:17 INFO - ##### Running download-and-extract step. 04:55:17 INFO - ##### 04:55:17 INFO - Running pre-action listener: _resource_record_pre_action 04:55:17 INFO - Running main action method: download_and_extract 04:55:17 INFO - mkdir: /builds/slave/test/build/tests 04:55:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:55:17 INFO - https://queue.taskcluster.net/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:55:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 04:55:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 04:55:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 04:55:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 04:55:25 INFO - Downloaded 1199 bytes. 04:55:25 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 04:55:25 INFO - Using the following test package requirements: 04:55:25 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 04:55:25 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:55:25 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 04:55:25 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:55:25 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 04:55:25 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:55:25 INFO - u'jsshell-mac.zip'], 04:55:25 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:55:25 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 04:55:25 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 04:55:25 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:55:25 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 04:55:25 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:55:25 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 04:55:25 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:55:25 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 04:55:25 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:55:25 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 04:55:25 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 04:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:55:25 INFO - https://queue.taskcluster.net/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:55:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:55:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:55:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:55:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:55:27 INFO - Downloaded 18425488 bytes. 04:55:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:55:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:55:30 INFO - Return code: 0 04:55:30 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 04:55: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')]} 04:55:30 INFO - https://queue.taskcluster.net/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:55:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:55:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:55:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:55:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:55:34 INFO - Downloaded 18425488 bytes. 04:55:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:55:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:55:37 INFO - Return code: 0 04:55:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:55:37 INFO - https://queue.taskcluster.net/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:55:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 04:55:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 04:55:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 04:55:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 04:55:38 INFO - Downloaded 10840145 bytes. 04:55:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:55:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:55:38 INFO - Return code: 0 04:55:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:55:38 INFO - https://queue.taskcluster.net/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 04:55:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 04:55:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 04:55:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 04:55:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 04:55:45 INFO - Downloaded 114138679 bytes. 04:55:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 04:55:45 INFO - mkdir: /builds/slave/test/properties 04:55:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:55:45 INFO - Writing to file /builds/slave/test/properties/build_url 04:55:45 INFO - Contents: 04:55:45 INFO - build_url:https://queue.taskcluster.net/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 04:55:46 INFO - Running post-action listener: _resource_record_post_action 04:55:46 INFO - Running post-action listener: set_extra_try_arguments 04:55:46 INFO - ##### 04:55:46 INFO - ##### Running populate-webroot step. 04:55:46 INFO - ##### 04:55:46 INFO - Running pre-action listener: _resource_record_pre_action 04:55:46 INFO - Running main action method: populate_webroot 04:55:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:55:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:55:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:55:46 INFO - '--tppagecycles', 04:55:46 INFO - '7'], 04:55:46 INFO - 'tests': ['tsvgm']}}, 04:55:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:55:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:55:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:55:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:55:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:55:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:55:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:46 INFO - 'talos_options': ['--disable-e10s'], 04:55:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:55:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:55:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:46 INFO - 'talos_options': ['--disable-e10s'], 04:55:46 INFO - 'tests': ['damp', 'tps']}, 04:55:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:46 INFO - 'tests': ['damp', 'tps']}, 04:55:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:55:46 INFO - 'tests': ['dromaeo_dom']}, 04:55:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:55:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:55:46 INFO - 'tests': ['a11yr', 04:55:46 INFO - 'ts_paint', 04:55:46 INFO - 'tpaint', 04:55:46 INFO - 'sessionrestore', 04:55:46 INFO - 'sessionrestore_no_auto_restore', 04:55:46 INFO - 'tabpaint']}, 04:55:46 INFO - 'other-e10s': {'tests': ['a11yr', 04:55:46 INFO - 'ts_paint', 04:55:46 INFO - 'tpaint', 04:55:46 INFO - 'sessionrestore', 04:55:46 INFO - 'sessionrestore_no_auto_restore', 04:55:46 INFO - 'tabpaint']}, 04:55:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:55:46 INFO - 'ts_paint', 04:55:46 INFO - 'tpaint', 04:55:46 INFO - 'sessionrestore', 04:55:46 INFO - 'sessionrestore_no_auto_restore', 04:55:46 INFO - 'tabpaint']}, 04:55:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:55:46 INFO - 'ts_paint', 04:55:46 INFO - 'tpaint', 04:55:46 INFO - 'sessionrestore', 04:55:46 INFO - 'sessionrestore_no_auto_restore', 04:55:46 INFO - 'tabpaint']}, 04:55:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:55:46 INFO - 'tests': ['a11yr', 04:55:46 INFO - 'ts_paint', 04:55:46 INFO - 'tpaint', 04:55:46 INFO - 'sessionrestore', 04:55:46 INFO - 'sessionrestore_no_auto_restore', 04:55:46 INFO - 'tabpaint']}, 04:55:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:55:46 INFO - 'tests': ['a11yr', 04:55:46 INFO - 'ts_paint', 04:55:46 INFO - 'tpaint', 04:55:46 INFO - 'sessionrestore', 04:55:46 INFO - 'sessionrestore_no_auto_restore', 04:55:46 INFO - 'tabpaint']}, 04:55:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:55:46 INFO - 'tests': ['tsvgx', 04:55:46 INFO - 'tsvgr_opacity', 04:55:46 INFO - 'tart', 04:55:46 INFO - 'tscrollx', 04:55:46 INFO - 'cart']}, 04:55:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:55:46 INFO - 'tsvgr_opacity', 04:55:46 INFO - 'tart', 04:55:46 INFO - 'tscrollx', 04:55:46 INFO - 'cart']}, 04:55:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:46 INFO - 'talos_options': ['--disable-e10s'], 04:55:46 INFO - 'tests': ['tp5o']}, 04:55:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:46 INFO - 'tests': ['tp5o']}, 04:55:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:46 INFO - 'talos_options': ['--disable-e10s', 04:55:46 INFO - '--xperf_path', 04:55:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:55:46 INFO - 'tests': ['tp5n']}, 04:55:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:46 INFO - 'talos_options': ['--xperf_path', 04:55:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:55:46 INFO - 'tests': ['tp5n']}}, 04:55:46 INFO - 'talos.zip': {'path': '', 04:55:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:55:46 INFO - Running post-action listener: _resource_record_post_action 04:55:46 INFO - ##### 04:55:46 INFO - ##### Running create-virtualenv step. 04:55:46 INFO - ##### 04:55:46 INFO - Running pre-action listener: _resource_record_pre_action 04:55:46 INFO - Running main action method: create_virtualenv 04:55:46 INFO - Creating virtualenv /builds/slave/test/build/venv 04:55:46 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:55:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:55:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:55:46 INFO - Using real prefix '/tools/python27' 04:55:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:55:47 INFO - Installing distribute.............................................................................................................................................................................................done. 04:55:50 INFO - Installing pip.................done. 04:55:50 INFO - Return code: 0 04:55:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:55:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:55:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c073a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c083420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff80958d210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf12e00>, '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 0x10bd7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf0db20>, '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.UOs9vQPzh3/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.rSUWVnnxFY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:55: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', 'pip>=1.5'] in /builds/slave/test/build 04:55: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 pip>=1.5 04:55:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UOs9vQPzh3/Render', 04:55:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:50 INFO - 'HOME': '/Users/cltbld', 04:55:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:50 INFO - 'LOGNAME': 'cltbld', 04:55:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:50 INFO - 'MOZ_NO_REMOTE': '1', 04:55:50 INFO - 'NO_EM_RESTART': '1', 04:55:50 INFO - 'PAGER': '/bin/cat', 04:55:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:50 INFO - 'PWD': '/builds/slave/test', 04:55:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:50 INFO - 'SHELL': '/bin/bash', 04:55:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rSUWVnnxFY/Listeners', 04:55:50 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:55:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:50 INFO - 'USER': 'cltbld', 04:55:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:50 INFO - 'XPC_FLAGS': '0x0', 04:55:50 INFO - 'XPC_SERVICE_NAME': '0', 04:55:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:50 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:55:50 INFO - Cleaning up... 04:55:50 INFO - Return code: 0 04:55:50 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:55:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:55:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55: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', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c073a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c083420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff80958d210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf12e00>, '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 0x10bd7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf0db20>, '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.UOs9vQPzh3/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.rSUWVnnxFY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:55: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', 'psutil>=0.7.1'] in /builds/slave/test/build 04:55: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 psutil>=0.7.1 04:55:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UOs9vQPzh3/Render', 04:55:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:50 INFO - 'HOME': '/Users/cltbld', 04:55:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:50 INFO - 'LOGNAME': 'cltbld', 04:55:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:50 INFO - 'MOZ_NO_REMOTE': '1', 04:55:50 INFO - 'NO_EM_RESTART': '1', 04:55:50 INFO - 'PAGER': '/bin/cat', 04:55:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:50 INFO - 'PWD': '/builds/slave/test', 04:55:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:50 INFO - 'SHELL': '/bin/bash', 04:55:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rSUWVnnxFY/Listeners', 04:55:50 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:55:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:50 INFO - 'USER': 'cltbld', 04:55:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:50 INFO - 'XPC_FLAGS': '0x0', 04:55:50 INFO - 'XPC_SERVICE_NAME': '0', 04:55:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:50 INFO - Downloading/unpacking psutil>=0.7.1 04:55: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 04:55: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 04:55: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 04:55: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 04:55: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 04:55: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 04:55:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:55:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:55:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:55:54 INFO - Installing collected packages: psutil 04:55:54 INFO - Running setup.py install for psutil 04:55:54 INFO - building 'psutil._psutil_osx' extension 04:55:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:55:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:55:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:55:54 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:55:55 INFO - building 'psutil._psutil_posix' extension 04:55:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:55:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:55:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:55:55 INFO - ^ 04:55:55 INFO - 1 warning generated. 04:55:55 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:55:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:55:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:55:55 INFO - Successfully installed psutil 04:55:55 INFO - Cleaning up... 04:55:55 INFO - Return code: 0 04:55:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:55: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')]} 04:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55: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')]} 04:55:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:55 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c073a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c083420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff80958d210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf12e00>, '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 0x10bd7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf0db20>, '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.UOs9vQPzh3/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.rSUWVnnxFY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:55:55 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.0.0'] in /builds/slave/test/build 04:55:55 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.0.0 04:55:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UOs9vQPzh3/Render', 04:55:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:55 INFO - 'HOME': '/Users/cltbld', 04:55:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:55 INFO - 'LOGNAME': 'cltbld', 04:55:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:55 INFO - 'MOZ_NO_REMOTE': '1', 04:55:55 INFO - 'NO_EM_RESTART': '1', 04:55:55 INFO - 'PAGER': '/bin/cat', 04:55:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:55 INFO - 'PWD': '/builds/slave/test', 04:55:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:55 INFO - 'SHELL': '/bin/bash', 04:55:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rSUWVnnxFY/Listeners', 04:55:55 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:55:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:55 INFO - 'USER': 'cltbld', 04:55:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:55 INFO - 'XPC_FLAGS': '0x0', 04:55:55 INFO - 'XPC_SERVICE_NAME': '0', 04:55:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:55 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:55:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:55:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:55:58 INFO - Installing collected packages: mozsystemmonitor 04:55:58 INFO - Running setup.py install for mozsystemmonitor 04:55:58 INFO - Successfully installed mozsystemmonitor 04:55:58 INFO - Cleaning up... 04:55:58 INFO - Return code: 0 04:55:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:55:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:55:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:58 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 0x10c073a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c083420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff80958d210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf12e00>, '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 0x10bd7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf0db20>, '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.UOs9vQPzh3/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.rSUWVnnxFY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:55:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:55:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:55:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UOs9vQPzh3/Render', 04:55:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:58 INFO - 'HOME': '/Users/cltbld', 04:55:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:58 INFO - 'LOGNAME': 'cltbld', 04:55:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:58 INFO - 'MOZ_NO_REMOTE': '1', 04:55:58 INFO - 'NO_EM_RESTART': '1', 04:55:58 INFO - 'PAGER': '/bin/cat', 04:55:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:58 INFO - 'PWD': '/builds/slave/test', 04:55:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:58 INFO - 'SHELL': '/bin/bash', 04:55:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rSUWVnnxFY/Listeners', 04:55:58 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:55:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:58 INFO - 'USER': 'cltbld', 04:55:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:58 INFO - 'XPC_FLAGS': '0x0', 04:55:58 INFO - 'XPC_SERVICE_NAME': '0', 04:55:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:58 INFO - Downloading/unpacking blobuploader==1.2.4 04:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:00 INFO - Downloading blobuploader-1.2.4.tar.gz 04:56:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:56:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:56:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:02 INFO - Downloading docopt-0.6.1.tar.gz 04:56:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:56:02 INFO - Installing collected packages: blobuploader, requests, docopt 04:56:02 INFO - Running setup.py install for blobuploader 04:56:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:56:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:56:02 INFO - Running setup.py install for requests 04:56:02 INFO - Running setup.py install for docopt 04:56:03 INFO - Successfully installed blobuploader requests docopt 04:56:03 INFO - Cleaning up... 04:56:03 INFO - Return code: 0 04:56:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:56: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')]} 04:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56: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')]} 04:56:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:03 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 0x10c073a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c083420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff80958d210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf12e00>, '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 0x10bd7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf0db20>, '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.UOs9vQPzh3/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.rSUWVnnxFY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:56:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:56:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:56:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UOs9vQPzh3/Render', 04:56:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:03 INFO - 'HOME': '/Users/cltbld', 04:56:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:03 INFO - 'LOGNAME': 'cltbld', 04:56:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:03 INFO - 'MOZ_NO_REMOTE': '1', 04:56:03 INFO - 'NO_EM_RESTART': '1', 04:56:03 INFO - 'PAGER': '/bin/cat', 04:56:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:03 INFO - 'PWD': '/builds/slave/test', 04:56:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:03 INFO - 'SHELL': '/bin/bash', 04:56:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rSUWVnnxFY/Listeners', 04:56:03 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:56:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:03 INFO - 'USER': 'cltbld', 04:56:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:03 INFO - 'XPC_FLAGS': '0x0', 04:56:03 INFO - 'XPC_SERVICE_NAME': '0', 04:56:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:56:03 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-Drtobf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:56:03 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-w5NKt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:56:03 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-uRai11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:56:03 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-yuEEyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:56:03 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-Pj7Rp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:56:03 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-IkDAWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:56:04 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-xN9Hey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:56:04 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-sx5Zap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:56:04 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-zGKSzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:56:04 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-h3C9pz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:56:04 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-_U6_pC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:56:04 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-7fFl4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:56:04 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-YUWtqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:56:04 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-fHGGlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:04 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-OBREar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:56:05 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-5Rt3W1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:56:05 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-gVPdA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:56:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:56:05 INFO - Running setup.py install for manifestparser 04:56:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:56:05 INFO - Running setup.py install for mozcrash 04:56:05 INFO - Running setup.py install for mozdebug 04:56:05 INFO - Running setup.py install for mozdevice 04:56:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:56:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:56:05 INFO - Running setup.py install for mozfile 04:56:05 INFO - Running setup.py install for mozhttpd 04:56:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:56:06 INFO - Running setup.py install for mozinfo 04:56:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:56:06 INFO - Running setup.py install for mozInstall 04:56:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:56:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:56:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:56:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:56:06 INFO - Running setup.py install for mozleak 04:56:06 INFO - Running setup.py install for mozlog 04:56:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:56:06 INFO - Running setup.py install for moznetwork 04:56:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:56:06 INFO - Running setup.py install for mozprocess 04:56:07 INFO - Running setup.py install for mozprofile 04:56:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:56:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:56:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:56:07 INFO - Running setup.py install for mozrunner 04:56:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:56:07 INFO - Running setup.py install for mozscreenshot 04:56:07 INFO - Running setup.py install for moztest 04:56:07 INFO - Running setup.py install for mozversion 04:56:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:56:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:56:07 INFO - Cleaning up... 04:56:07 INFO - Return code: 0 04:56:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:07 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 0x10c073a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c083420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff80958d210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf12e00>, '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 0x10bd7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf0db20>, '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.UOs9vQPzh3/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.rSUWVnnxFY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:56:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:56:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:56:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UOs9vQPzh3/Render', 04:56:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:07 INFO - 'HOME': '/Users/cltbld', 04:56:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:07 INFO - 'LOGNAME': 'cltbld', 04:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:07 INFO - 'MOZ_NO_REMOTE': '1', 04:56:07 INFO - 'NO_EM_RESTART': '1', 04:56:07 INFO - 'PAGER': '/bin/cat', 04:56:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:07 INFO - 'PWD': '/builds/slave/test', 04:56:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:07 INFO - 'SHELL': '/bin/bash', 04:56:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rSUWVnnxFY/Listeners', 04:56:07 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:56:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:07 INFO - 'USER': 'cltbld', 04:56:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:07 INFO - 'XPC_FLAGS': '0x0', 04:56:07 INFO - 'XPC_SERVICE_NAME': '0', 04:56:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:56:08 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-l_sdmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:56:08 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-VSrZWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:56:08 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-xhDPYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:56:08 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-aMuLXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:56:08 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-ZPDj5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:56:08 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-CBaNKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:56:08 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-aE34oQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:56:08 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-GRzAq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:56:08 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-HCtMqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:56:09 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-G05BWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:56:09 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-98uDQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:56:09 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-jiMnNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:56:09 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-xedCkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:56:09 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-FGSwBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:09 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-t3oNhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:56:09 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-rl0HG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:56:09 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-ULrwup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:56:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:12 INFO - Downloading blessings-1.6.tar.gz 04:56:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:56:12 INFO - Installing collected packages: blessings 04:56:12 INFO - Running setup.py install for blessings 04:56:12 INFO - Successfully installed blessings 04:56:12 INFO - Cleaning up... 04:56:12 INFO - Return code: 0 04:56:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:56:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:56:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:56:12 INFO - Reading from file tmpfile_stdout 04:56:12 INFO - Current package versions: 04:56:12 INFO - blessings == 1.6 04:56:12 INFO - blobuploader == 1.2.4 04:56:12 INFO - docopt == 0.6.1 04:56:12 INFO - manifestparser == 1.1 04:56:12 INFO - mozInstall == 1.12 04:56:12 INFO - mozcrash == 0.17 04:56:12 INFO - mozdebug == 0.1 04:56:12 INFO - mozdevice == 0.48 04:56:12 INFO - mozfile == 1.2 04:56:12 INFO - mozhttpd == 0.7 04:56:12 INFO - mozinfo == 0.9 04:56:12 INFO - mozleak == 0.1 04:56:12 INFO - mozlog == 3.1 04:56:12 INFO - moznetwork == 0.27 04:56:12 INFO - mozprocess == 0.22 04:56:12 INFO - mozprofile == 0.28 04:56:12 INFO - mozrunner == 6.11 04:56:12 INFO - mozscreenshot == 0.1 04:56:12 INFO - mozsystemmonitor == 0.0 04:56:12 INFO - moztest == 0.7 04:56:12 INFO - mozversion == 1.4 04:56:12 INFO - psutil == 3.1.1 04:56:12 INFO - requests == 1.2.3 04:56:12 INFO - wsgiref == 0.1.2 04:56:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:56:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:13 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 0x10c073a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c083420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff80958d210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf12e00>, '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 0x10bd7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf0db20>, '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.UOs9vQPzh3/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.rSUWVnnxFY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:56:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:56:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:56:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UOs9vQPzh3/Render', 04:56:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:13 INFO - 'HOME': '/Users/cltbld', 04:56:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:13 INFO - 'LOGNAME': 'cltbld', 04:56:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:13 INFO - 'MOZ_NO_REMOTE': '1', 04:56:13 INFO - 'NO_EM_RESTART': '1', 04:56:13 INFO - 'PAGER': '/bin/cat', 04:56:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:13 INFO - 'PWD': '/builds/slave/test', 04:56:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:13 INFO - 'SHELL': '/bin/bash', 04:56:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rSUWVnnxFY/Listeners', 04:56:13 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:56:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:13 INFO - 'USER': 'cltbld', 04:56:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:13 INFO - 'XPC_FLAGS': '0x0', 04:56:13 INFO - 'XPC_SERVICE_NAME': '0', 04:56:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:56:13 INFO - Cleaning up... 04:56:13 INFO - Return code: 0 04:56:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:56:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c073a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c083420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff80958d210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf12e00>, '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 0x10bd7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf0db20>, '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.UOs9vQPzh3/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.rSUWVnnxFY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:56:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:56:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:56:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UOs9vQPzh3/Render', 04:56:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:13 INFO - 'HOME': '/Users/cltbld', 04:56:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:13 INFO - 'LOGNAME': 'cltbld', 04:56:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:13 INFO - 'MOZ_NO_REMOTE': '1', 04:56:13 INFO - 'NO_EM_RESTART': '1', 04:56:13 INFO - 'PAGER': '/bin/cat', 04:56:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:13 INFO - 'PWD': '/builds/slave/test', 04:56:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:13 INFO - 'SHELL': '/bin/bash', 04:56:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rSUWVnnxFY/Listeners', 04:56:13 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:56:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:13 INFO - 'USER': 'cltbld', 04:56:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:13 INFO - 'XPC_FLAGS': '0x0', 04:56:13 INFO - 'XPC_SERVICE_NAME': '0', 04:56:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:13 INFO - Downloading/unpacking jsonschema 04:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:56:15 INFO - Downloading/unpacking functools32 (from jsonschema) 04:56:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:16 INFO - Downloading functools32-3.2.3-2.tar.gz 04:56:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:56:16 INFO - warning: no files found matching '*.txt' 04:56:16 INFO - no previously-included directories found matching 'build' 04:56:16 INFO - no previously-included directories found matching 'dist' 04:56:16 INFO - no previously-included directories found matching '.git*' 04:56:16 INFO - Installing collected packages: jsonschema, functools32 04:56:16 INFO - Running setup.py install for functools32 04:56:16 INFO - warning: no files found matching '*.txt' 04:56:16 INFO - no previously-included directories found matching 'build' 04:56:16 INFO - no previously-included directories found matching 'dist' 04:56:16 INFO - no previously-included directories found matching '.git*' 04:56:16 INFO - Successfully installed jsonschema functools32 04:56:16 INFO - Cleaning up... 04:56:16 INFO - Return code: 0 04:56:16 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:16 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c073a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c083420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff80958d210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf12e00>, '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 0x10bd7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf0db20>, '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.UOs9vQPzh3/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.rSUWVnnxFY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:56:16 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', 'flake8'] in /builds/slave/test/build 04:56:16 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 flake8 04:56:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UOs9vQPzh3/Render', 04:56:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:16 INFO - 'HOME': '/Users/cltbld', 04:56:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:16 INFO - 'LOGNAME': 'cltbld', 04:56:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:16 INFO - 'MOZ_NO_REMOTE': '1', 04:56:16 INFO - 'NO_EM_RESTART': '1', 04:56:16 INFO - 'PAGER': '/bin/cat', 04:56:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:16 INFO - 'PWD': '/builds/slave/test', 04:56:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:16 INFO - 'SHELL': '/bin/bash', 04:56:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rSUWVnnxFY/Listeners', 04:56:16 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:56:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:16 INFO - 'USER': 'cltbld', 04:56:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:16 INFO - 'XPC_FLAGS': '0x0', 04:56:16 INFO - 'XPC_SERVICE_NAME': '0', 04:56:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:16 INFO - Downloading/unpacking flake8 04:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:56:19 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:56:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:20 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:56:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:56:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:56:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:56:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:56:21 INFO - no previously-included directories found matching 'docs/_build' 04:56:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:56:21 INFO - Running setup.py install for pep8 04:56:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:56:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:56:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:56:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:56:21 INFO - no previously-included directories found matching 'docs/_build' 04:56:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:56:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:56:21 INFO - Cleaning up... 04:56:21 INFO - Return code: 0 04:56:21 INFO - Running post-action listener: _resource_record_post_action 04:56:21 INFO - Running post-action listener: _start_resource_monitoring 04:56:21 INFO - Starting resource monitoring. 04:56:21 INFO - ##### 04:56:21 INFO - ##### Running install step. 04:56:21 INFO - ##### 04:56:21 INFO - Running pre-action listener: _resource_record_pre_action 04:56:21 INFO - Running main action method: install 04:56:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:56:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:56:21 INFO - Reading from file tmpfile_stdout 04:56:21 INFO - Detecting whether we're running mozinstall >=1.0... 04:56:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:56:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:56:21 INFO - Reading from file tmpfile_stdout 04:56:21 INFO - Output received: 04:56:21 INFO - Usage: mozinstall [options] installer 04:56:21 INFO - Options: 04:56:21 INFO - -h, --help show this help message and exit 04:56:21 INFO - -d DEST, --destination=DEST 04:56:21 INFO - Directory to install application into. [default: 04:56:21 INFO - "/builds/slave/test"] 04:56:21 INFO - --app=APP Application being installed. [default: firefox] 04:56:21 INFO - mkdir: /builds/slave/test/build/application 04:56:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:56:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 04:56:53 INFO - Reading from file tmpfile_stdout 04:56:53 INFO - Output received: 04:56:53 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 04:56:53 INFO - Running post-action listener: _resource_record_post_action 04:56:53 INFO - ##### 04:56:53 INFO - ##### Running run-tests step. 04:56:53 INFO - ##### 04:56:53 INFO - Running pre-action listener: _resource_record_pre_action 04:56:53 INFO - Running main action method: run_tests 04:56:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:56:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:56:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:56:53 INFO - 2016-05-30 04:56:53.345 screenresolution[1643:7598] starting screenresolution argv=screenresolution get 04:56:53 INFO - 2016-05-30 04:56:53.348 screenresolution[1643:7598] Display 0: 1600x1200x32@60 04:56:53 INFO - 2016-05-30 04:56:53.354 screenresolution[1644:7600] starting screenresolution argv=screenresolution list 04:56:53 INFO - Available Modes on Display 0 04:56:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:56:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:56:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:56:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:56:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:56:53 INFO - Intel Iris: 04:56:53 INFO - Chipset Model: Intel Iris 04:56:53 INFO - Type: GPU 04:56:53 INFO - Bus: Built-In 04:56:53 INFO - VRAM (Dynamic, Max): 1536 MB 04:56:53 INFO - Vendor: Intel (0x8086) 04:56:53 INFO - Device ID: 0x0a2e 04:56:53 INFO - Revision ID: 0x0009 04:56:53 INFO - Displays: 04:56:53 INFO - Display: 04:56:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:56:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:56:53 INFO - Display Serial Number: 1600x1200 60 04:56:53 INFO - Main Display: Yes 04:56:53 INFO - Mirror: Off 04:56:53 INFO - Online: Yes 04:56:53 INFO - Rotation: Supported 04:56:53 INFO - Return code: 0 04:56:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:56:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:56:53 INFO - Python 2.7.3 04:56:53 INFO - Return code: 0 04:56:53 INFO - grabbing minidump binary from tooltool 04:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:53 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf12e00>, '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 0x10bd7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf0db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:56:53 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:56:53 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:56:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:56:53 INFO - Return code: 0 04:56:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:56:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:56:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:56:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:56:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:56:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:56:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:56:54 INFO - Return code: 0 04:56:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0356', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:56:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0356 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:56:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UOs9vQPzh3/Render', 04:56:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:54 INFO - 'HOME': '/Users/cltbld', 04:56:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:54 INFO - 'LOGNAME': 'cltbld', 04:56:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:56:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:56:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:54 INFO - 'MOZ_NO_REMOTE': '1', 04:56:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:56:54 INFO - 'NO_EM_RESTART': '1', 04:56:54 INFO - 'PAGER': '/bin/cat', 04:56:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:54 INFO - 'PWD': '/builds/slave/test', 04:56:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:56:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:54 INFO - 'SHELL': '/bin/bash', 04:56:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rSUWVnnxFY/Listeners', 04:56:54 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:56:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:54 INFO - 'USER': 'cltbld', 04:56:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:54 INFO - 'XPC_FLAGS': '0x0', 04:56:54 INFO - 'XPC_SERVICE_NAME': '0', 04:56:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0356', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:56:55 INFO - mozversion application_buildid: 20160530034637 04:56:55 INFO - mozversion application_changeset: 3fa0f58b23395a7f9f95384b18607ff10c581c00 04:56:55 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:56:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:56:55 INFO - mozversion application_name: Firefox 04:56:55 INFO - mozversion application_remotingname: firefox-dev 04:56:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:56:55 INFO - mozversion application_vendor: Mozilla 04:56:55 INFO - mozversion application_version: 48.0a2 04:56:55 INFO - mozversion platform_buildid: 20160530034637 04:56:55 INFO - mozversion platform_changeset: 3fa0f58b23395a7f9f95384b18607ff10c581c00 04:56:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:56:55 INFO - mozversion platform_version: 48.0a2 04:56:55 INFO - using testdate: 1464609415 04:56:55 INFO - actual date: 1464609415 04:56:55 INFO - starting webserver on 'localhost:49272' 04:56:55 INFO - SUITE-START | Running 2 tests 04:56:55 INFO - TEST-START | dromaeo_css 04:56:55 INFO - operating with platform_type : mac_ 04:56:55 INFO - Initialising browser for dromaeo_css test... 04:56:55 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmpm4R9aV/profile http://localhost:49272/getInfo.html) 04:56:57 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 04:56:57 INFO - PROCESS | 1662 | colorDepth:24 04:56:57 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 04:56:57 INFO - PROCESS | 1662 | __metrics 04:56:57 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" 04:56:57 ERROR - PROCESS | 1662 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:56:58 INFO - TEST-INFO | 1662: exit 0 04:56:58 INFO - Browser initialized. 04:56:58 INFO - Running cycle 1/1 for dromaeo_css test... 04:56:58 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmpm4R9aV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:57:10 INFO - PROCESS | 1664 | RSS: Main: 211529728 04:57:10 INFO - PROCESS | 1664 | 04:58:01 INFO - PROCESS | 1664 | 1464609481692 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:00:12 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 05:00:13 INFO - PROCESS | 1664 | RSS: Main: 344739840 05:00:13 INFO - PROCESS | 1664 | 05:03:16 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 05:03:17 INFO - PROCESS | 1664 | RSS: Main: 355799040 05:03:17 INFO - PROCESS | 1664 | 05:06:19 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 05:06:19 INFO - PROCESS | 1664 | RSS: Main: 355397632 05:06:19 INFO - PROCESS | 1664 | 05:09:22 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 05:09:22 INFO - PROCESS | 1664 | RSS: Main: 356593664 05:09:22 INFO - PROCESS | 1664 | 05:12:25 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 05:12:26 INFO - PROCESS | 1664 | RSS: Main: 322121728 05:12:26 INFO - PROCESS | 1664 | 05:15:29 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 05:15:29 INFO - PROCESS | 1664 | RSS: Main: 365232128 05:15:29 INFO - PROCESS | 1664 | 05:15:29 INFO - PROCESS | 1664 | __start_tp_report 05:15:29 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 05:15:29 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 05:15:29 INFO - PROCESS | 1664 | |i|pagename|runs| 05:15:29 INFO - PROCESS | 1664 | |0;dojo.html;3340.632530421653;3377.105067157203;3391.525186473894;3412.686115845399;3436.295559410321;5478;5500.42245564216;5508.421615730351;5517.200005999128;5562.332520097589;5528.612997090206;5537.972310138453;5547.25112109865;5557.798613291855;5616.185653080301;4186.79066046698;4983.30233767272;5076.060928728179;5084.593232541406;5196.532312091911;5130.153524668425;5208.5051920067535;5209.166533354664;5215.139501982168;5218.243354713564;5155.793768249266;5183.067047931371;5196.76614552346;5207.297014902978;5209.817656382027;5190.92213616796;5223.6343455958095;5244.737763111829;5246.501582349671;5283.815066472655;5082.4663410341755;5097.643164978453;5168.483151684839;5169.586433085344;5175.275461435402;5572.219889215513;5601.971990140023;5604.299462567179;5606.859828504279;5615.213870058195;6198.6280823150755;6264.1543391642;6275.6548389838545;6284.12022316876;6286;6101.328853826076;6180.227471566054;6308.653024083674;6319.146915166486;6328.778492752777;5803.738831752581;5814.854628634318;5827.3298570664065;5849.590528662995;5876.177335173079;6231.719572619243;6287.402696743893;6298.464630506367;6300.527460440485;6312.463440607508;6019.668918209541;6100.23747031621;6135.171751813448;6208.410201030895;6224.719887605069;6265.27949285838;6286.685665716787;6300.1494798202575;6313.463355614824;6315.147455093595;6200.348963358872;6230.190075290183;6230.43926046658;6233.34549872266;6239.126522286883;6106.267247930276;6116.761878222105;6165.167702360213;6171.783987560414;6256.781012664626;4226.852060177879;4274.850380236677;4601.010782681673;4915.65590408668;4919.557239848431;4342.5006124296115;4393.626541743924;4421.579949904753;4485.977570112129;4510.548945105464;5643.717814109278;5644.633098848643;5654;5682;5712.485876271156;6126.731429653714;6129.328227221949;6240.781572644935;6264.123022776815;6265.27949285838;5697;5732.914006289909;5747.281589801275;5758.74085666146;5762.567807414377;5389.083855744533;5399.001184780828;5413.675179489164;5415.620906536505;5417.4311697272005;5266.8946621068135;6134.467201660938;6310.716017779303;6346.2384513857705;6352.904706429407;6253.624782513064;6264.34224406449;6271.122042913905;6289.339619339903;6298.905516417166;5287.57699384056;6337.207849018872;6346.841328966812;6359.7456101755415;6374;5967.671778052249;6089.878202436015;6145.938540614537;6170.50635949114;6174.598651087665;5960.791372301948;6264.34224406449;6264.436200741955;6268.780592679234;6282.120503129474;6102.9694851525455;6111.113888486056;6120.938790612036;6130.417610327012;6139.2325959255095;5925.259342582177;5997.250343707036;6058.3335833057445;6062;6075.1798507200965;5925.970370148121;5931.258592675916;5947;5957.702114894151;5972.283326000907;6115.785947491817;6135.631862088289;6139.088729016679;6139.662318572343;6147.323794382525;5796.94203057964;5860.267466566679;5900.763969441346;5942.584019118794;5981.252343457068 05:15:29 INFO - PROCESS | 1664 | |1;ext.html;537.5833728860135;542.6933782412937;560.1765404854862;604.3774911840806;623.877021361549;31084;31564.526532102038;31655.683443165562;31693.683063169363;38790.418143727795;36777;41501.79249103758;42581.935451613645;43234.702958911206;43266;9480.857787133198;9734.756631084208;9736.367136136163;9740.123388894997;9741.269404794633;8694.434861733998;8905.866412003825;8939.731808045737;9677.274204434696;11664.533418663243;11622.476988535536;11707;11707.121965852553;11711.53153873844;11721.296722196654;11602.941985290061;11656.125790565742;11668;11668.00821930137;11673.824892626617;8635.179657932485;11655.41722913851;11903.345316007617;11921;11922.403879806061;38784.80607596972;39182.41226381606;39247;39248.41127383091;39325.60674393248;3180.3003339265324;3199.1842080269616;3267.036224313833;3371.780834245766;3519.4544845548726;27873.721262787316;28032.579511307347;28244.435111297866;28501;29063;2746.6429364182527;2758.1036163246827;2770.2104900103577;2784.053421836585;2806.2984253936515;428226;430348;433061.8346908277;433390;434631;63007.054894176625;65022;65034;65069.34930650633;65185.674071629335;70252.64873675599;70312.6484367585;70382.64808675923;70481;70524;17349.2192851322;17675.027873466825;17679.20443580042;17705.026223557827;17724.20241089154;12526.937365313115;13263.801042984363;13282.335883205995;13288.534901278408;13331.266780326981;16898.986060836145;16961.91519042397;19887;20375.796242037686;20402.89798550998;7712.884306735404;7905.090914544786;7936.3254123399;7938.841223175504;7974.800629984296;875.3478658399533;984.9261305402124;1010.368519675203;1017.7811770469236;1085.782843431317;26179.083732069284;28906.27734306659;30340.544891826197;30607.387852243275;31308;1016.4409574733867;1064.5262858028102;1116.6482557994207;1208.7945049341456;1264.5510843650557;12653.936730316474;14340.928295358457;14838.554843354501;15041.774373384407;15164.848351516564;628286;630099;650217.7489112619;652656;653911;60495.69752151211;60787.088193676245;60815;60858.47853803602;60943.69528152331;1254.3038613569556;1377.882879955195;1389.6873203529126;1501.9699606008037;1572.1981789287545;28883;29066.56400153957;29067.12798616045;29381.26546836346;29646.555301670924;50276;51011;51060.48939510557;51073.744631276604;51087.74456127696;511317;526425;527282;528810;649789;23987.880060599586;24369.878150609133;24416.87791561031;26989.73010269951;27309.453810924068;42153.78923105365;42451;43941.78029109834;43956.78021609871;43962;34518.65481345255;35650.643493564734;35691.82154089213;36217.275654486235;36304.81847590745;38480;39143.41284880672;39314.606853931095;39383.60616393799;39636.80181599074;36357.63642363543;36577.81711091534;36905.44641830321;36995.8150209258;37258.81370593238;25253.621195681713;31473.52789708202;32146.51780223345;32297.51553726743;32352.191195220308;24708;25316.98732050771;25521.744782551938;25774.09790657318;25799.742002579733 05:15:29 INFO - PROCESS | 1664 | |2;jquery.html;397.49518111997764;407.93473044312896;408.5118283651036;408.8344220590662;422.76113995592505;13803;19608.411747647577;20154.395368138943;20314;20318.288859889908;12916.483340666386;17297.913510432463;20168.899155504238;20582.38252852417;20591.588168236627;3004.6995300469944;5039.143345631252;5834.9124763128375;5857.619254748428;5884.176215329857;5641.887162256773;5819.24349834523;5828.125781132821;5831.854203644901;5842.824715258528;5517.751701173457;5789.334226563957;5790.478856902878;5792.971035144839;5801.187833703285;5683.857903552403;5725;5732.312122545301;6114.144019837227;6497.317781632908;5731.684757338345;5765.711714414262;5766.452187042224;5773.509251713609;5783.2192653992015;23598.29205123849;24212.273631790897;24294.514109717882;24335.02659893602;24763.5047299053;2004.4387571479863;2049.918003279867;2531.6708827852444;2784.2621705248125;2845.331347133422;9315.906840931571;9661.46861922594;9994.650187243484;12410.441530131166;14753.262336883114;1559.8440155984424;1636.4108920788506;1684.3178512702375;1731.3680506615121;1748.5628592851788;98173;112518.74962500704;115782;119759;124168.37915810363;11724.120690948213;11750.823737643772;11761.353125578004;11796.233244839059;11804;14728.484503042342;14794.778078328834;14813.777793333109;14814.25928703582;14827.629309267139;5853.560982926212;7247.456440766858;7507.549547027196;7706.884396734054;8819.779505512286;8615.22462978335;8641.438306510057;8682.741002554594;8799.956000219958;8889.244414224733;4555.658325625592;4555.794989225493;4586.082783443325;4587.632989360844;4610.492845786961;1680.4958512446217;1739.015439547741;1890.461218552706;1914.176903931295;1967.1639553189837;842.653210807897;943.8489841625444;946.3753922411175;1010.9191264698807;1013.6097602423121;15136.394544218108;18564.350247741266;19220.327288544835;19568.51078723015;20423.897880510503;894.7226359828474;957.913787759105;990.2028866762325;1000.6597756762735;1013.4208439521288;9589.750742194516;10531.94734026325;10547.367157970393;10573.20700947432;10677.733056673645;515928;606804;608394;626856;627004;98181;127253.36373318074;133632.66367336398;134226;136132;1013.8681971343678;1040.8907064758243;1057.413135709673;1059.2055958031476;1065.898739619735;17573.297068117132;17716.557086072953;17774.46676599704;18156.909215453838;18433.90783046076;52625.473745262054;53163;55378.44621553894;56320.436795633155;57287;74119;74827.62586187034;77184.22815771995;77744;79431;7766.650500727368;7803.453758236869;7967.123616402076;8176.918230817616;8265;10268.229882758673;12326.691832704255;12472.501099956264;12569.43437545331;12672.493100275886;10332.53503592325;10397.792044159225;10643.148548115978;10900.56397744113;10995.505202265756;10834.512446940063;10851;10877.238593298393;10974.506147223234;11110.777784444428;9095.727128186165;10978.835317470404;11149.219554631423;11418.543258269909;11444.713882153013;8162.306203972491;8189.385796065438;8242.134575869448;8277.917220827714;8297.377696672895;10195.388276703421;10943.398113103856;11166.274192177483;11282;11289.71775705614 05:15:29 INFO - PROCESS | 1664 | |3;mootools.html;1425.1662777275294;1569.1291333310014;1596.0663012137895;1820.526663067602;1955.3645065353764;7814;8561.272291855192;8703.782405439868;8709.34679899007;8726.127387261271;8398;8597.355198360117;8601.956990215056;8604.666276727101;8614.353923455734;2158.0504577985635;2385.60637494814;2442.951140977184;2459.1638842793445;2470.3700556358103;2468.049800826677;2470.950580988379;2473.418746594549;2476.232367965927;2477.1453848422266;2371.087131454394;2415.867127307997;2433.561958847407;2438.548868459332;2444.2422848916804;2159.5464952359944;2386.200622791367;2414.61366181411;2434.8417352872007;2435.622478515833;2411.638254261875;2433.3916520869784;2437.9634305485433;2438.878056097188;2442.3161514775893;8691.826163476695;8696.04343522212;8700.869486957701;8708.17272359125;8757.912420875773;4331.45856767904;5222.399424066243;5327.7336133193185;5356.758945847446;5370.731463426813;3558.3594952908466;3567.839447224881;3581.767185132958;3635.327464419091;3665.890023299305;942.1755963531909;988.8763904511936;1018.6791160784403;1035.073609119841;1045.2369770067894;11841.88158118425;11862.051035917106;11874.881251187551;11881.108916831186;11891.94054029733;4742.549457801503;4748.240281554935;4819.759012049383;4853.39332583427;4882.414110306715;10100.090991810772;10117.392956422638;10121.1903047756;10131.544080516394;10134.23993200498;5395.487428694268;5434.239206511092;5482.930828488486;5487.341519017744;5489.204065410493;4370.519242883281;4540.318952157137;4757.286407038971;4901.166801643658;4916.237983112624;4028.637422631977;4053.0759951748746;4113.814878330422;4140.3168477201525;4149.854755083618;1323.4185148747347;1350.1628990026247;1351.0893717285153;1353.1407556201882;1360.1227208450493;811.5820352518458;877.8156587116777;895.2077411490748;932.0958670090001;936.9013409961636;5628.774849005994;5699.658020518699;5837.9124313135335;5888.440598143169;5930.28836539618;771.7800426878313;851.3317046118767;929.5631053404888;939.182910867536;974.5955428497236;3828.559715632682;3908.2965066288343;3968.464257325282;3974.741641793274;3980.840766369313;8369.372297077743;10509.159267258601;10601.416922069206;10623.52194151265;10628;10208.846867296996;10379.896201038044;10476.371417714961;10494.42280674555;10654.733631659115;8175;8232.464889782144;8249.958750206331;8250.216229458192;8325.83348333042;5505.779768809202;5598.888022239451;5607.971960140173;5703.058995265735;5711.48596626289;9224.584893679934;9342.53287335644;9439.008904064975;9553.092456216647;9557.856632150662;8818.294536437197;9444.622215111318;9619.422834629944;9631.325807193429;9717.951410243186;6824.897626535705;6984.196817365864;7304.926950730637;7437.070366204224;7581.203973582695;8177.836443270982;8186.590670466572;8249.381296402913;8263.049749278909;8264.669413223404;7116.146062472537;7212.89180662301;7243.0946131733535;7323.267673232847;7378.262173782578;6066.666333351708;7079.2566780487305;7098;7225.6387180641805;7244.384227340735;6770.627615480998;6890.2420733720255;6906.965465172642;6984.755533556301;6989.405900498515;4663.603593694574;4756.405449318835;4790.4251489821445;4876.609871210224;4907.705537667752;4542.591166795004;4577.176108300538;4625.907481850411;4632.652551058617;4768.880777980578 05:15:29 INFO - PROCESS | 1664 | |4;prototype.html;323.56480533682196;326.56566766200956;332.38508758796223;334.01632193191045;337.5679130712689;5444.863878403042;6544.770933017346;6574.737010519573;6575.901361479576;6595.4393876520435;6462.321456247097;6471.9029214561815;6487.578307410015;6489.123968264283;6515.218173819137;4577.816887324511;4646.25659894417;4664.416947881515;4712.740799256039;4745.525447455261;3862.439946207798;4653.162430762462;4715.009847931939;4751.429828420594;4776;4721.362616046841;4765.809367625291;4778.235482322829;4782.210935195689;4785.186518291899;4261.296886013835;4553.521880202581;4561.133384656904;4658.557437043475;4666.2533994561045;4041.4948131483566;4043.534993475729;4047.494063242095;4050.696197785178;4056.5334986476637;6178.38216178383;6197.101420294077;6249.187605611241;6252.093446450284;6253.687315634245;5653.095504719226;5654.151877218409;5662.17898404729;5664;5665.5184309333345;6974.860502789967;6975.79072627822;6978.302169783032;6991.510594258404;6991.930080699179;4355.12897420514;4514.074614704011;4519.299508576176;4537.591616754508;4540.704854184501;7235.927640723578;7307.561546307185;7383.520071195355;7398.038255026812;7409.110906691231;3600.85596576134;4011.398290256485;4590.98998220391;4592.805870473634;4605.746683932416;4644.326572646947;4646.303054541846;4650.069986002813;4651.441826980784;4661.627069834405;5550.805721799798;5555.388907220203;5569.582281328917;5572.359178694502;5577.972110139423;6672.199336079701;6983.790486285419;7002.369786719118;7009.894851577231;7017.052697885822;1598.696247712931;1610.790597222377;1624.4964061141084;1625.2605064695535;1633.6569320442602;872.9825403492022;1012.018342208057;1015.4668798880647;1028.454918892988;1039.4542864995938;5952.613080149777;6382.489400847921;6532.542722009509;6553.475721942234;6641.402273795285;7264.200937896827;7330.157031941395;7334.486585938933;7370.815729606803;7473.775786726407;6650.767223147167;6661.967395053774;6800.115984921928;6807.285235050347;6807.285235050347;3829.21501092281;3869.1101046759404;3873.3996230584303;3878.5733569307354;3880.320943834874;7397.593132377771;7647.235276472308;7672.923270767333;7684.231576842271;7692.46152769312;7479.626018699043;7524.887126693104;7534.095908491016;7556.093268807778;7581.090269167625;7624.847503049908;7641.006669132978;7654.387648988068;7657.846843063219;7670.539767613849;7267.890981635385;7271.781846544613;7277;7285;7306.4520160989205;6913.896291555731;7191.748288809867;7203;7221.747238846615;7230.927690723025;7607.695692172251;7650.961745191238;7654.387648988179;7665.923340766521;7676.232376762279;4761.928566072745;4828.4688684244;4832.8791780205775;4846.345743324606;4853.9029219416125;7202.279772022755;7216.3505284524645;7220.638968051681;7226.638668066681;7238.746643867439;6674.16572928384;6957.547759395623;6969.372756451944;6969.54697944632;6981.476389270723;6882.7591034313555;6923.515353925177;6924.169099707866;6925.411340036153;6930.48021398387;5449.836504904859;6750.831229219309;6771.187457505131;6783.762568310085;6789.694463749044;6682.097916781172;6936.809067828855;6937.7571784987285;6946;6946.409555187865;6577.901331479936;6759.493038022267;6803.455723542006;6832.692528836314;6843.418309443753 05:15:29 INFO - PROCESS | 1664 | |5;yui.html;613.2855223664433;616.5560796226717;656.510899379962;668.9565178263416;746.3581320064745;14577;14772.335244913978;14840.628984275398;14847.925760371196;14851.331690073946;11165.441727913612;13694.178349299049;13709.45162193511;13726.039177257597;13752.793708094387;715.8317795318096;718.7915504503687;833.9833203335929;912.1061359867317;917.8623206519009;899.4198741811527;911.5578944212085;912.8493798523235;914.3416739947228;919.880415545978;965.1747755668907;970.8495183246573;970.9854352184723;971.6259240192542;979.6277414582485;754.6113751417996;955.4028732042474;972.9221662267001;975.707287813653;975.8438649815995;721.3147509865648;723.7864829875199;727.4871215792854;728.1807966038207;731.4258307228853;13511.66220844477;14687;14688.926555367261;14735.92632036833;14754.704905901823;12109.818352724717;12399.75200495994;12423.689407764788;12506.687332816662;12682.048846336469;10077.748056298578;10083.092521673085;10093.747656308651;10095.444750538718;10444.477776111164;3469.167399824024;3518.36669399508;3544.8759293424864;3578.5169002184634;3612.837422316002;76388;77335;78262;78288.60855695742;78304.60847695725;8245.667014856652;8815;9436.649775920632;9480.573374198178;9543.379680320757;17025.914870425815;21427.79377851688;21531;21639.891800540896;21995.340139795833;7304;7366.77899663011;7412.814679632945;7421.183669796318;7429.888551671729;4141.068259641556;4206.0115872769875;4217.051163488252;4329.960809405783;4399.23013472641;3500.632433594487;3549.3256281306467;3561.910952226215;3563.590187128513;3576.4277715565904;530.7903378165603;610.81370182094;627.5544363501965;635.5678138865616;647.5208345824057;474.7786270156968;554.4178612456905;560.5207547546789;568.4798409455302;595.4581330988866;7112.182099058675;7580.507267027626;7643.121041080347;7822.8435431291055;7927.910036491843;503.07434320849814;505.0379027951758;544.9700266485381;547.453399517015;556.4769117029979;3061.5560743692486;3580.12286989684;3659.7255205859665;3659.9451008234896;3745.2696724139078;83103;84302.5784871084;84393.15606843976;84400;87465;52225.73887130616;69089;69976.60046799036;70106.64946675234;70514;63846.6807665968;64357.67821160958;64655.67672161609;64777.704445910815;65837.67081164563;7241.131064272321;7437.962810185914;7478.214787447458;7508.887366689613;7550.207228240955;46252.306215406126;47107.764461177474;47140.585782426584;47365.81585460391;47736.52263477321;61518;63671;63920;64490.35509644844;64785.7324993623;9599.47595912885;9802.607895684092;9830.361026533252;9833.262505312274;10165.542550585093;9793.951030245089;10139.949300253451;10569.471526423802;10585.735356616146;10631.681049568526;9233.307501937516;9619;9767.658131965347;9822.55798489084;9904.108630223314;9330.486823224517;9812.018798120416;9814.803703926023;10001.099901008944;10029.097381235724;9312.161905428544;9716.125548700651;9734.464604446823;9747.122758951728;10246.333988290737;8283.751487455385;8367.916320836714;8520.446170998865;8639;8647.783805404915;7933.3653307736395;8389.538575378412;8497.2777313929;8673.82652346937;8821.250193733347 05:15:29 INFO - PROCESS | 1664 | __end_tp_report 05:15:29 INFO - PROCESS | 1664 | __start_cc_report 05:15:29 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1281 05:15:29 INFO - PROCESS | 1664 | __end_cc_report 05:15:29 INFO - PROCESS | 1664 | __startTimestamp1464610529545__endTimestamp 05:15:29 INFO - PROCESS | 1664 | 05:15:29 INFO - PROCESS | 1664 | ------- Summary: start ------- 05:15:29 INFO - PROCESS | 1664 | Number of tests: 6 05:15:29 INFO - PROCESS | 1664 | 05:15:29 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5701.90 Median:5931.26 stddev:666.87 (11.2%) stddev-sans-first:642.67 05:15:29 INFO - PROCESS | 1664 | Values: 3340.6 3377.1 3391.5 3412.7 3436.3 5478.0 5500.4 5508.4 5517.2 5562.3 5528.6 5538.0 5547.3 5557.8 5616.2 4186.8 4983.3 5076.1 5084.6 5196.5 5130.2 5208.5 5209.2 5215.1 5218.2 5155.8 5183.1 5196.8 5207.3 5209.8 5190.9 5223.6 5244.7 5246.5 5283.8 5082.5 5097.6 5168.5 5169.6 5175.3 5572.2 5602.0 5604.3 5606.9 5615.2 6198.6 6264.2 6275.7 6284.1 6286.0 6101.3 6180.2 6308.7 6319.1 6328.8 5803.7 5814.9 5827.3 5849.6 5876.2 6231.7 6287.4 6298.5 6300.5 6312.5 6019.7 6100.2 6135.2 6208.4 6224.7 6265.3 6286.7 6300.1 6313.5 6315.1 6200.3 6230.2 6230.4 6233.3 6239.1 6106.3 6116.8 6165.2 6171.8 6256.8 4226.9 4274.9 4601.0 4915.7 4919.6 4342.5 4393.6 4421.6 4486.0 4510.5 5643.7 5644.6 5654.0 5682.0 5712.5 6126.7 6129.3 6240.8 6264.1 6265.3 5697.0 5732.9 5747.3 5758.7 5762.6 5389.1 5399.0 5413.7 5415.6 5417.4 5266.9 6134.5 6310.7 6346.2 6352.9 6253.6 6264.3 6271.1 6289.3 6298.9 5287.6 6337.2 6346.8 6359.7 6374.0 5967.7 6089.9 6145.9 6170.5 6174.6 5960.8 6264.3 6264.4 6268.8 6282.1 6103.0 6111.1 6120.9 6130.4 6139.2 5925.3 5997.3 6058.3 6062.0 6075.2 5926.0 5931.3 5947.0 5957.7 5972.3 6115.8 6135.6 6139.1 6139.7 6147.3 5796.9 5860.3 5900.8 5942.6 5981.3 05:15:29 INFO - PROCESS | 1664 | 05:15:29 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:67876.40 Median:18945.22 stddev:146689.59 (774.3%) stddev-sans-first:147013.96 05:15:29 INFO - PROCESS | 1664 | Values: 537.6 542.7 560.2 604.4 623.9 31084.0 31564.5 31655.7 31693.7 38790.4 36777.0 41501.8 42581.9 43234.7 43266.0 9480.9 9734.8 9736.4 9740.1 9741.3 8694.4 8905.9 8939.7 9677.3 11664.5 11622.5 11707.0 11707.1 11711.5 11721.3 11602.9 11656.1 11668.0 11668.0 11673.8 8635.2 11655.4 11903.3 11921.0 11922.4 38784.8 39182.4 39247.0 39248.4 39325.6 3180.3 3199.2 3267.0 3371.8 3519.5 27873.7 28032.6 28244.4 28501.0 29063.0 2746.6 2758.1 2770.2 2784.1 2806.3 428226.0 430348.0 433061.8 433390.0 434631.0 63007.1 65022.0 65034.0 65069.3 65185.7 70252.6 70312.6 70382.6 70481.0 70524.0 17349.2 17675.0 17679.2 17705.0 17724.2 12526.9 13263.8 13282.3 13288.5 13331.3 16899.0 16961.9 19887.0 20375.8 20402.9 7712.9 7905.1 7936.3 7938.8 7974.8 875.3 984.9 1010.4 1017.8 1085.8 26179.1 28906.3 30340.5 30607.4 31308.0 1016.4 1064.5 1116.6 1208.8 1264.6 12653.9 14340.9 14838.6 15041.8 15164.8 628286.0 630099.0 650217.7 652656.0 653911.0 60495.7 60787.1 60815.0 60858.5 60943.7 1254.3 1377.9 1389.7 1502.0 1572.2 28883.0 29066.6 29067.1 29381.3 29646.6 50276.0 51011.0 51060.5 51073.7 51087.7 511317.0 526425.0 527282.0 528810.0 649789.0 23987.9 24369.9 24416.9 26989.7 27309.5 42153.8 42451.0 43941.8 43956.8 43962.0 34518.7 35650.6 35691.8 36217.3 36304.8 38480.0 39143.4 39314.6 39383.6 39636.8 36357.6 36577.8 36905.4 36995.8 37258.8 25253.6 31473.5 32146.5 32297.5 32352.2 24708.0 25317.0 25521.7 25774.1 25799.7 05:15:29 INFO - PROCESS | 1664 | 05:15:29 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:34576.69 Median:24253.39 stddev:99827.87 (411.6%) stddev-sans-first:100074.92 05:15:29 INFO - PROCESS | 1664 | Values: 397.5 407.9 408.5 408.8 422.8 13803.0 19608.4 20154.4 20314.0 20318.3 12916.5 17297.9 20168.9 20582.4 20591.6 3004.7 5039.1 5834.9 5857.6 5884.2 5641.9 5819.2 5828.1 5831.9 5842.8 5517.8 5789.3 5790.5 5793.0 5801.2 5683.9 5725.0 5732.3 6114.1 6497.3 5731.7 5765.7 5766.5 5773.5 5783.2 23598.3 24212.3 24294.5 24335.0 24763.5 2004.4 2049.9 2531.7 2784.3 2845.3 9315.9 9661.5 9994.7 12410.4 14753.3 1559.8 1636.4 1684.3 1731.4 1748.6 98173.0 112518.7 115782.0 119759.0 124168.4 11724.1 11750.8 11761.4 11796.2 11804.0 14728.5 14794.8 14813.8 14814.3 14827.6 5853.6 7247.5 7507.5 7706.9 8819.8 8615.2 8641.4 8682.7 8800.0 8889.2 4555.7 4555.8 4586.1 4587.6 4610.5 1680.5 1739.0 1890.5 1914.2 1967.2 842.7 943.8 946.4 1010.9 1013.6 15136.4 18564.4 19220.3 19568.5 20423.9 894.7 957.9 990.2 1000.7 1013.4 9589.8 10531.9 10547.4 10573.2 10677.7 515928.0 606804.0 608394.0 626856.0 627004.0 98181.0 127253.4 133632.7 134226.0 136132.0 1013.9 1040.9 1057.4 1059.2 1065.9 17573.3 17716.6 17774.5 18156.9 18433.9 52625.5 53163.0 55378.4 56320.4 57287.0 74119.0 74827.6 77184.2 77744.0 79431.0 7766.7 7803.5 7967.1 8176.9 8265.0 10268.2 12326.7 12472.5 12569.4 12672.5 10332.5 10397.8 10643.1 10900.6 10995.5 10834.5 10851.0 10877.2 10974.5 11110.8 9095.7 10978.8 11149.2 11418.5 11444.7 8162.3 8189.4 8242.1 8277.9 8297.4 10195.4 10943.4 11166.3 11282.0 11289.7 05:15:29 INFO - PROCESS | 1664 | 05:15:29 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5624.71 Median:4904.44 stddev:3111.74 (63.4%) stddev-sans-first:3104.46 05:15:29 INFO - PROCESS | 1664 | Values: 1425.2 1569.1 1596.1 1820.5 1955.4 7814.0 8561.3 8703.8 8709.3 8726.1 8398.0 8597.4 8602.0 8604.7 8614.4 2158.1 2385.6 2443.0 2459.2 2470.4 2468.0 2471.0 2473.4 2476.2 2477.1 2371.1 2415.9 2433.6 2438.5 2444.2 2159.5 2386.2 2414.6 2434.8 2435.6 2411.6 2433.4 2438.0 2438.9 2442.3 8691.8 8696.0 8700.9 8708.2 8757.9 4331.5 5222.4 5327.7 5356.8 5370.7 3558.4 3567.8 3581.8 3635.3 3665.9 942.2 988.9 1018.7 1035.1 1045.2 11841.9 11862.1 11874.9 11881.1 11891.9 4742.5 4748.2 4819.8 4853.4 4882.4 10100.1 10117.4 10121.2 10131.5 10134.2 5395.5 5434.2 5482.9 5487.3 5489.2 4370.5 4540.3 4757.3 4901.2 4916.2 4028.6 4053.1 4113.8 4140.3 4149.9 1323.4 1350.2 1351.1 1353.1 1360.1 811.6 877.8 895.2 932.1 936.9 5628.8 5699.7 5837.9 5888.4 5930.3 771.8 851.3 929.6 939.2 974.6 3828.6 3908.3 3968.5 3974.7 3980.8 8369.4 10509.2 10601.4 10623.5 10628.0 10208.8 10379.9 10476.4 10494.4 10654.7 8175.0 8232.5 8250.0 8250.2 8325.8 5505.8 5598.9 5608.0 5703.1 5711.5 9224.6 9342.5 9439.0 9553.1 9557.9 8818.3 9444.6 9619.4 9631.3 9718.0 6824.9 6984.2 7304.9 7437.1 7581.2 8177.8 8186.6 8249.4 8263.0 8264.7 7116.1 7212.9 7243.1 7323.3 7378.3 6066.7 7079.3 7098.0 7225.6 7244.4 6770.6 6890.2 6907.0 6984.8 6989.4 4663.6 4756.4 4790.4 4876.6 4907.7 4542.6 4577.2 4625.9 4632.7 4768.9 05:15:29 INFO - PROCESS | 1664 | 05:15:29 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:5716.56 Median:6564.11 stddev:1860.39 (28.3%) stddev-sans-first:1821.04 05:15:29 INFO - PROCESS | 1664 | Values: 323.6 326.6 332.4 334.0 337.6 5444.9 6544.8 6574.7 6575.9 6595.4 6462.3 6471.9 6487.6 6489.1 6515.2 4577.8 4646.3 4664.4 4712.7 4745.5 3862.4 4653.2 4715.0 4751.4 4776.0 4721.4 4765.8 4778.2 4782.2 4785.2 4261.3 4553.5 4561.1 4658.6 4666.3 4041.5 4043.5 4047.5 4050.7 4056.5 6178.4 6197.1 6249.2 6252.1 6253.7 5653.1 5654.2 5662.2 5664.0 5665.5 6974.9 6975.8 6978.3 6991.5 6991.9 4355.1 4514.1 4519.3 4537.6 4540.7 7235.9 7307.6 7383.5 7398.0 7409.1 3600.9 4011.4 4591.0 4592.8 4605.7 4644.3 4646.3 4650.1 4651.4 4661.6 5550.8 5555.4 5569.6 5572.4 5578.0 6672.2 6983.8 7002.4 7009.9 7017.1 1598.7 1610.8 1624.5 1625.3 1633.7 873.0 1012.0 1015.5 1028.5 1039.5 5952.6 6382.5 6532.5 6553.5 6641.4 7264.2 7330.2 7334.5 7370.8 7473.8 6650.8 6662.0 6800.1 6807.3 6807.3 3829.2 3869.1 3873.4 3878.6 3880.3 7397.6 7647.2 7672.9 7684.2 7692.5 7479.6 7524.9 7534.1 7556.1 7581.1 7624.8 7641.0 7654.4 7657.8 7670.5 7267.9 7271.8 7277.0 7285.0 7306.5 6913.9 7191.7 7203.0 7221.7 7230.9 7607.7 7651.0 7654.4 7665.9 7676.2 4761.9 4828.5 4832.9 4846.3 4853.9 7202.3 7216.4 7220.6 7226.6 7238.7 6674.2 6957.5 6969.4 6969.5 6981.5 6882.8 6923.5 6924.2 6925.4 6930.5 5449.8 6750.8 6771.2 6783.8 6789.7 6682.1 6936.8 6937.8 6946.0 6946.4 6577.9 6759.5 6803.5 6832.7 6843.4 05:15:29 INFO - PROCESS | 1664 | 05:15:29 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:16933.70 Median:33247.09 stddev:23670.17 (71.2%) stddev-sans-first:23704.85 05:15:29 INFO - PROCESS | 1664 | Values: 613.3 616.6 656.5 669.0 746.4 14577.0 14772.3 14840.6 14847.9 14851.3 11165.4 13694.2 13709.5 13726.0 13752.8 715.8 718.8 834.0 912.1 917.9 899.4 911.6 912.8 914.3 919.9 965.2 970.8 971.0 971.6 979.6 754.6 955.4 972.9 975.7 975.8 721.3 723.8 727.5 728.2 731.4 13511.7 14687.0 14688.9 14735.9 14754.7 12109.8 12399.8 12423.7 12506.7 12682.0 10077.7 10083.1 10093.7 10095.4 10444.5 3469.2 3518.4 3544.9 3578.5 3612.8 76388.0 77335.0 78262.0 78288.6 78304.6 8245.7 8815.0 9436.6 9480.6 9543.4 17025.9 21427.8 21531.0 21639.9 21995.3 7304.0 7366.8 7412.8 7421.2 7429.9 4141.1 4206.0 4217.1 4330.0 4399.2 3500.6 3549.3 3561.9 3563.6 3576.4 530.8 610.8 627.6 635.6 647.5 474.8 554.4 560.5 568.5 595.5 7112.2 7580.5 7643.1 7822.8 7927.9 503.1 505.0 545.0 547.5 556.5 3061.6 3580.1 3659.7 3659.9 3745.3 83103.0 84302.6 84393.2 84400.0 87465.0 52225.7 69089.0 69976.6 70106.6 70514.0 63846.7 64357.7 64655.7 64777.7 65837.7 7241.1 7438.0 7478.2 7508.9 7550.2 46252.3 47107.8 47140.6 47365.8 47736.5 61518.0 63671.0 63920.0 64490.4 64785.7 9599.5 9802.6 9830.4 9833.3 10165.5 9794.0 10139.9 10569.5 10585.7 10631.7 9233.3 9619.0 9767.7 9822.6 9904.1 9330.5 9812.0 9814.8 10001.1 10029.1 9312.2 9716.1 9734.5 9747.1 10246.3 8283.8 8367.9 8520.4 8639.0 8647.8 7933.4 8389.5 8497.3 8673.8 8821.3 05:15:29 INFO - PROCESS | 1664 | -------- Summary: end -------- 05:15:29 INFO - PROCESS | 1664 | 05:15:30 INFO - TEST-INFO | 1664: exit 0 05:15:30 INFO - TEST-OK | dromaeo_css | took 1115080ms 05:15:30 INFO - TEST-START | kraken 05:15:30 INFO - operating with platform_type : mac_ 05:15:30 INFO - Initialising browser for kraken test... 05:15:30 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmp7ETSAa/profile http://localhost:49272/getInfo.html) 05:15:32 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 05:15:32 INFO - PROCESS | 1671 | colorDepth:24 05:15:32 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 05:15:32 INFO - PROCESS | 1671 | __metrics 05:15:32 INFO - PROCESS | 1671 | 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" 05:15:32 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:15:33 INFO - TEST-INFO | 1671: exit 0 05:15:33 INFO - Browser initialized. 05:15:33 INFO - Running cycle 1/1 for kraken test... 05:15:33 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmp7ETSAa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:15:44 INFO - PROCESS | 1673 | RSS: Main: 211357696 05:15:44 INFO - PROCESS | 1673 | 05:15:47 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 05:15:48 INFO - PROCESS | 1673 | RSS: Main: 272089088 05:15:48 INFO - PROCESS | 1673 | 05:15:51 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 05:15:51 INFO - PROCESS | 1673 | RSS: Main: 286154752 05:15:51 INFO - PROCESS | 1673 | 05:15:54 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 05:15:55 INFO - PROCESS | 1673 | RSS: Main: 375291904 05:15:55 INFO - PROCESS | 1673 | 05:15:57 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 05:15:57 INFO - PROCESS | 1673 | RSS: Main: 278745088 05:15:57 INFO - PROCESS | 1673 | 05:16:00 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:16:00 INFO - PROCESS | 1673 | RSS: Main: 280363008 05:16:00 INFO - PROCESS | 1673 | 05:16:03 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 05:16:04 INFO - PROCESS | 1673 | RSS: Main: 318128128 05:16:04 INFO - PROCESS | 1673 | 05:16:06 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 05:16:07 INFO - PROCESS | 1673 | RSS: Main: 318914560 05:16:07 INFO - PROCESS | 1673 | 05:16:10 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 05:16:10 INFO - PROCESS | 1673 | RSS: Main: 317108224 05:16:10 INFO - PROCESS | 1673 | 05:16:12 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:16:13 INFO - PROCESS | 1673 | RSS: Main: 309686272 05:16:13 INFO - PROCESS | 1673 | 05:16:15 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:16:15 INFO - PROCESS | 1673 | RSS: Main: 315867136 05:16:15 INFO - PROCESS | 1673 | 05:16:17 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:16:18 INFO - PROCESS | 1673 | RSS: Main: 319057920 05:16:18 INFO - PROCESS | 1673 | 05:16:21 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:16:21 INFO - PROCESS | 1673 | RSS: Main: 324550656 05:16:21 INFO - PROCESS | 1673 | 05:16:24 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:16:25 INFO - PROCESS | 1673 | RSS: Main: 322551808 05:16:25 INFO - PROCESS | 1673 | 05:16:27 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 05:16:27 INFO - PROCESS | 1673 | RSS: Main: 387403776 05:16:27 INFO - PROCESS | 1673 | 05:16:27 INFO - PROCESS | 1673 | __start_tp_report 05:16:27 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 05:16:27 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 05:16:27 INFO - PROCESS | 1673 | |i|pagename|runs| 05:16:27 INFO - PROCESS | 1673 | |0;ai-astar;114;115;117;113;114;113;115;115;115;133 05:16:27 INFO - PROCESS | 1673 | |1;audio-beat-detection;154;122;147;172;114;139;142;117;171;118 05:16:27 INFO - PROCESS | 1673 | |2;audio-dft;184;184;187;185;184;185;185;184;183;184 05:16:27 INFO - PROCESS | 1673 | |3;audio-fft;70;77;75;151;55;56;58;66;132;55 05:16:27 INFO - PROCESS | 1673 | |4;audio-oscillator;95;90;145;78;79;88;127;80;77;133 05:16:27 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;87;84;86;84;89;86;86;83;85;83 05:16:27 INFO - PROCESS | 1673 | |6;imaging-darkroom;133;136;93;92;92;93;93;93;92;109 05:16:27 INFO - PROCESS | 1673 | |7;imaging-desaturate;84;84;86;87;84;85;86;83;83;85 05:16:27 INFO - PROCESS | 1673 | |8;json-parse-financial;61;64;64;66;64;65;66;84;61;58 05:16:27 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;63;69;74;73;72;70;67;72;84;100 05:16:27 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;80;80;81;81;80;80;81;79;82;84 05:16:27 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;129;121;129;127;128;123;142;147;165;136 05:16:27 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;166;162;164;167;166;166;163;166;162;162 05:16:27 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;67;68;67;67;68;68;67;68;68;66 05:16:27 INFO - PROCESS | 1673 | __end_tp_report 05:16:27 INFO - PROCESS | 1673 | __start_cc_report 05:16:27 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,2010 05:16:27 INFO - PROCESS | 1673 | __end_cc_report 05:16:27 INFO - PROCESS | 1673 | __startTimestamp1464610587319__endTimestamp 05:16:27 INFO - PROCESS | 1673 | 05:16:27 INFO - PROCESS | 1673 | ------- Summary: start ------- 05:16:27 INFO - PROCESS | 1673 | Number of tests: 14 05:16:27 INFO - PROCESS | 1673 | 05:16:27 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:116.40 Median:115.00 stddev:5.95 (5.2%) stddev-sans-first:6.24 05:16:27 INFO - PROCESS | 1673 | Values: 114.0 115.0 117.0 113.0 114.0 113.0 115.0 115.0 115.0 133.0 05:16:27 INFO - PROCESS | 1673 | 05:16:27 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:139.60 Median:144.50 stddev:21.72 (15.0%) stddev-sans-first:22.41 05:16:27 INFO - PROCESS | 1673 | Values: 154.0 122.0 147.0 172.0 114.0 139.0 142.0 117.0 171.0 118.0 05:16:27 INFO - PROCESS | 1673 | 05:16:27 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:184.50 Median:184.50 stddev:1.08 (0.6%) stddev-sans-first:1.13 05:16:27 INFO - PROCESS | 1673 | Values: 184.0 184.0 187.0 185.0 184.0 185.0 185.0 184.0 183.0 184.0 05:16:27 INFO - PROCESS | 1673 | 05:16:27 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:79.50 Median:62.00 stddev:33.96 (54.8%) stddev-sans-first:35.85 05:16:27 INFO - PROCESS | 1673 | Values: 70.0 77.0 75.0 151.0 55.0 56.0 58.0 66.0 132.0 55.0 05:16:27 INFO - PROCESS | 1673 | 05:16:27 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:99.20 Median:79.50 stddev:25.73 (32.4%) stddev-sans-first:27.25 05:16:27 INFO - PROCESS | 1673 | Values: 95.0 90.0 145.0 78.0 79.0 88.0 127.0 80.0 77.0 133.0 05:16:27 INFO - PROCESS | 1673 | 05:16:27 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:85.30 Median:86.00 stddev:1.89 (2.2%) stddev-sans-first:1.90 05:16:27 INFO - PROCESS | 1673 | Values: 87.0 84.0 86.0 84.0 89.0 86.0 86.0 83.0 85.0 83.0 05:16:27 INFO - PROCESS | 1673 | 05:16:27 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:102.60 Median:92.50 stddev:17.60 (19.0%) stddev-sans-first:14.83 05:16:27 INFO - PROCESS | 1673 | Values: 133.0 136.0 93.0 92.0 92.0 93.0 93.0 93.0 92.0 109.0 05:16:27 INFO - PROCESS | 1673 | 05:16:27 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:1.34 (1.6%) stddev-sans-first:1.39 05:16:27 INFO - PROCESS | 1673 | Values: 84.0 84.0 86.0 87.0 84.0 85.0 86.0 83.0 83.0 85.0 05:16:27 INFO - PROCESS | 1673 | 05:16:27 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:65.30 Median:64.50 stddev:7.04 (10.9%) stddev-sans-first:7.29 05:16:27 INFO - PROCESS | 1673 | Values: 61.0 64.0 64.0 66.0 64.0 65.0 66.0 84.0 61.0 58.0 05:16:27 INFO - PROCESS | 1673 | 05:16:27 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:74.40 Median:72.00 stddev:10.51 (14.6%) stddev-sans-first:10.31 05:16:27 INFO - PROCESS | 1673 | Values: 63.0 69.0 74.0 73.0 72.0 70.0 67.0 72.0 84.0 100.0 05:16:27 INFO - PROCESS | 1673 | 05:16:27 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:80.80 Median:81.00 stddev:1.40 (1.7%) stddev-sans-first:1.45 05:16:27 INFO - PROCESS | 1673 | Values: 80.0 80.0 81.0 81.0 80.0 80.0 81.0 79.0 82.0 84.0 05:16:27 INFO - PROCESS | 1673 | 05:16:27 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:134.70 Median:132.50 stddev:13.41 (10.1%) stddev-sans-first:14.06 05:16:27 INFO - PROCESS | 1673 | Values: 129.0 121.0 129.0 127.0 128.0 123.0 142.0 147.0 165.0 136.0 05:16:27 INFO - PROCESS | 1673 | 05:16:27 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.40 Median:166.00 stddev:2.01 (1.2%) stddev-sans-first:2.05 05:16:27 INFO - PROCESS | 1673 | Values: 166.0 162.0 164.0 167.0 166.0 166.0 163.0 166.0 162.0 162.0 05:16:27 INFO - PROCESS | 1673 | 05:16:27 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:0.70 (1.0%) stddev-sans-first:0.73 05:16:27 INFO - PROCESS | 1673 | Values: 67.0 68.0 67.0 67.0 68.0 68.0 67.0 68.0 68.0 66.0 05:16:27 INFO - PROCESS | 1673 | -------- Summary: end -------- 05:16:27 INFO - PROCESS | 1673 | 05:16:28 INFO - TEST-INFO | 1673: exit 0 05:16:28 INFO - TEST-OK | kraken | took 58024ms 05:16:28 INFO - SUITE-END | took 1173s 05:16:28 INFO - Completed test suite (00:19:33) 05:16:28 INFO - javascript benchmark 05:16:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3340.632530421653, 3377.105067157203, 3391.525186473894, 3412.686115845399, 3436.295559410321, 5478.0, 5500.42245564216, 5508.421615730351, 5517.200005999128, 5562.332520097589, 5528.612997090206, 5537.972310138453, 5547.25112109865, 5557.798613291855, 5616.185653080301, 4186.79066046698, 4983.30233767272, 5076.060928728179, 5084.593232541406, 5196.532312091911, 5130.153524668425, 5208.5051920067535, 5209.166533354664, 5215.139501982168, 5218.243354713564, 5155.793768249266, 5183.067047931371, 5196.76614552346, 5207.297014902978, 5209.817656382027, 5190.92213616796, 5223.6343455958095, 5244.737763111829, 5246.501582349671, 5283.815066472655, 5082.4663410341755, 5097.643164978453, 5168.483151684839, 5169.586433085344, 5175.275461435402, 5572.219889215513, 5601.971990140023, 5604.299462567179, 5606.859828504279, 5615.213870058195, 6198.6280823150755, 6264.1543391642, 6275.6548389838545, 6284.12022316876, 6286.0, 6101.328853826076, 6180.227471566054, 6308.653024083674, 6319.146915166486, 6328.778492752777, 5803.738831752581, 5814.854628634318, 5827.3298570664065, 5849.590528662995, 5876.177335173079, 6231.719572619243, 6287.402696743893, 6298.464630506367, 6300.527460440485, 6312.463440607508, 6019.668918209541, 6100.23747031621, 6135.171751813448, 6208.410201030895, 6224.719887605069, 6265.27949285838, 6286.685665716787, 6300.1494798202575, 6313.463355614824, 6315.147455093595, 6200.348963358872, 6230.190075290183, 6230.43926046658, 6233.34549872266, 6239.126522286883, 6106.267247930276, 6116.761878222105, 6165.167702360213, 6171.783987560414, 6256.781012664626, 4226.852060177879, 4274.850380236677, 4601.010782681673, 4915.65590408668, 4919.557239848431, 4342.5006124296115, 4393.626541743924, 4421.579949904753, 4485.977570112129, 4510.548945105464, 5643.717814109278, 5644.633098848643, 5654.0, 5682.0, 5712.485876271156, 6126.731429653714, 6129.328227221949, 6240.781572644935, 6264.123022776815, 6265.27949285838, 5697.0, 5732.914006289909, 5747.281589801275, 5758.74085666146, 5762.567807414377, 5389.083855744533, 5399.001184780828, 5413.675179489164, 5415.620906536505, 5417.4311697272005, 5266.8946621068135, 6134.467201660938, 6310.716017779303, 6346.2384513857705, 6352.904706429407, 6253.624782513064, 6264.34224406449, 6271.122042913905, 6289.339619339903, 6298.905516417166, 5287.57699384056, 6337.207849018872, 6346.841328966812, 6359.7456101755415, 6374.0, 5967.671778052249, 6089.878202436015, 6145.938540614537, 6170.50635949114, 6174.598651087665, 5960.791372301948, 6264.34224406449, 6264.436200741955, 6268.780592679234, 6282.120503129474, 6102.9694851525455, 6111.113888486056, 6120.938790612036, 6130.417610327012, 6139.2325959255095, 5925.259342582177, 5997.250343707036, 6058.3335833057445, 6062.0, 6075.1798507200965, 5925.970370148121, 5931.258592675916, 5947.0, 5957.702114894151, 5972.283326000907, 6115.785947491817, 6135.631862088289, 6139.088729016679, 6139.662318572343, 6147.323794382525, 5796.94203057964, 5860.267466566679, 5900.763969441346, 5942.584019118794, 5981.252343457068], "value": 5658.377698219214, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.5833728860135, 542.6933782412937, 560.1765404854862, 604.3774911840806, 623.877021361549, 31084.0, 31564.526532102038, 31655.683443165562, 31693.683063169363, 38790.418143727795, 36777.0, 41501.79249103758, 42581.935451613645, 43234.702958911206, 43266.0, 9480.857787133198, 9734.756631084208, 9736.367136136163, 9740.123388894997, 9741.269404794633, 8694.434861733998, 8905.866412003825, 8939.731808045737, 9677.274204434696, 11664.533418663243, 11622.476988535536, 11707.0, 11707.121965852553, 11711.53153873844, 11721.296722196654, 11602.941985290061, 11656.125790565742, 11668.0, 11668.00821930137, 11673.824892626617, 8635.179657932485, 11655.41722913851, 11903.345316007617, 11921.0, 11922.403879806061, 38784.80607596972, 39182.41226381606, 39247.0, 39248.41127383091, 39325.60674393248, 3180.3003339265324, 3199.1842080269616, 3267.036224313833, 3371.780834245766, 3519.4544845548726, 27873.721262787316, 28032.579511307347, 28244.435111297866, 28501.0, 29063.0, 2746.6429364182527, 2758.1036163246827, 2770.2104900103577, 2784.053421836585, 2806.2984253936515, 428226.0, 430348.0, 433061.8346908277, 433390.0, 434631.0, 63007.054894176625, 65022.0, 65034.0, 65069.34930650633, 65185.674071629335, 70252.64873675599, 70312.6484367585, 70382.64808675923, 70481.0, 70524.0, 17349.2192851322, 17675.027873466825, 17679.20443580042, 17705.026223557827, 17724.20241089154, 12526.937365313115, 13263.801042984363, 13282.335883205995, 13288.534901278408, 13331.266780326981, 16898.986060836145, 16961.91519042397, 19887.0, 20375.796242037686, 20402.89798550998, 7712.884306735404, 7905.090914544786, 7936.3254123399, 7938.841223175504, 7974.800629984296, 875.3478658399533, 984.9261305402124, 1010.368519675203, 1017.7811770469236, 1085.782843431317, 26179.083732069284, 28906.27734306659, 30340.544891826197, 30607.387852243275, 31308.0, 1016.4409574733867, 1064.5262858028102, 1116.6482557994207, 1208.7945049341456, 1264.5510843650557, 12653.936730316474, 14340.928295358457, 14838.554843354501, 15041.774373384407, 15164.848351516564, 628286.0, 630099.0, 650217.7489112619, 652656.0, 653911.0, 60495.69752151211, 60787.088193676245, 60815.0, 60858.47853803602, 60943.69528152331, 1254.3038613569556, 1377.882879955195, 1389.6873203529126, 1501.9699606008037, 1572.1981789287545, 28883.0, 29066.56400153957, 29067.12798616045, 29381.26546836346, 29646.555301670924, 50276.0, 51011.0, 51060.48939510557, 51073.744631276604, 51087.74456127696, 511317.0, 526425.0, 527282.0, 528810.0, 649789.0, 23987.880060599586, 24369.878150609133, 24416.87791561031, 26989.73010269951, 27309.453810924068, 42153.78923105365, 42451.0, 43941.78029109834, 43956.78021609871, 43962.0, 34518.65481345255, 35650.643493564734, 35691.82154089213, 36217.275654486235, 36304.81847590745, 38480.0, 39143.41284880672, 39314.606853931095, 39383.60616393799, 39636.80181599074, 36357.63642363543, 36577.81711091534, 36905.44641830321, 36995.8150209258, 37258.81370593238, 25253.621195681713, 31473.52789708202, 32146.51780223345, 32297.51553726743, 32352.191195220308, 24708.0, 25316.98732050771, 25521.744782551938, 25774.09790657318, 25799.742002579733], "value": 20041.183188140712, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.49518111997764, 407.93473044312896, 408.5118283651036, 408.8344220590662, 422.76113995592505, 13803.0, 19608.411747647577, 20154.395368138943, 20314.0, 20318.288859889908, 12916.483340666386, 17297.913510432463, 20168.899155504238, 20582.38252852417, 20591.588168236627, 3004.6995300469944, 5039.143345631252, 5834.9124763128375, 5857.619254748428, 5884.176215329857, 5641.887162256773, 5819.24349834523, 5828.125781132821, 5831.854203644901, 5842.824715258528, 5517.751701173457, 5789.334226563957, 5790.478856902878, 5792.971035144839, 5801.187833703285, 5683.857903552403, 5725.0, 5732.312122545301, 6114.144019837227, 6497.317781632908, 5731.684757338345, 5765.711714414262, 5766.452187042224, 5773.509251713609, 5783.2192653992015, 23598.29205123849, 24212.273631790897, 24294.514109717882, 24335.02659893602, 24763.5047299053, 2004.4387571479863, 2049.918003279867, 2531.6708827852444, 2784.2621705248125, 2845.331347133422, 9315.906840931571, 9661.46861922594, 9994.650187243484, 12410.441530131166, 14753.262336883114, 1559.8440155984424, 1636.4108920788506, 1684.3178512702375, 1731.3680506615121, 1748.5628592851788, 98173.0, 112518.74962500704, 115782.0, 119759.0, 124168.37915810363, 11724.120690948213, 11750.823737643772, 11761.353125578004, 11796.233244839059, 11804.0, 14728.484503042342, 14794.778078328834, 14813.777793333109, 14814.25928703582, 14827.629309267139, 5853.560982926212, 7247.456440766858, 7507.549547027196, 7706.884396734054, 8819.779505512286, 8615.22462978335, 8641.438306510057, 8682.741002554594, 8799.956000219958, 8889.244414224733, 4555.658325625592, 4555.794989225493, 4586.082783443325, 4587.632989360844, 4610.492845786961, 1680.4958512446217, 1739.015439547741, 1890.461218552706, 1914.176903931295, 1967.1639553189837, 842.653210807897, 943.8489841625444, 946.3753922411175, 1010.9191264698807, 1013.6097602423121, 15136.394544218108, 18564.350247741266, 19220.327288544835, 19568.51078723015, 20423.897880510503, 894.7226359828474, 957.913787759105, 990.2028866762325, 1000.6597756762735, 1013.4208439521288, 9589.750742194516, 10531.94734026325, 10547.367157970393, 10573.20700947432, 10677.733056673645, 515928.0, 606804.0, 608394.0, 626856.0, 627004.0, 98181.0, 127253.36373318074, 133632.66367336398, 134226.0, 136132.0, 1013.8681971343678, 1040.8907064758243, 1057.413135709673, 1059.2055958031476, 1065.898739619735, 17573.297068117132, 17716.557086072953, 17774.46676599704, 18156.909215453838, 18433.90783046076, 52625.473745262054, 53163.0, 55378.44621553894, 56320.436795633155, 57287.0, 74119.0, 74827.62586187034, 77184.22815771995, 77744.0, 79431.0, 7766.650500727368, 7803.453758236869, 7967.123616402076, 8176.918230817616, 8265.0, 10268.229882758673, 12326.691832704255, 12472.501099956264, 12569.43437545331, 12672.493100275886, 10332.53503592325, 10397.792044159225, 10643.148548115978, 10900.56397744113, 10995.505202265756, 10834.512446940063, 10851.0, 10877.238593298393, 10974.506147223234, 11110.777784444428, 9095.727128186165, 10978.835317470404, 11149.219554631423, 11418.543258269909, 11444.713882153013, 8162.306203972491, 8189.385796065438, 8242.134575869448, 8277.917220827714, 8297.377696672895, 10195.388276703421, 10943.398113103856, 11166.274192177483, 11282.0, 11289.71775705614], "value": 9419.920321152977, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1425.1662777275294, 1569.1291333310014, 1596.0663012137895, 1820.526663067602, 1955.3645065353764, 7814.0, 8561.272291855192, 8703.782405439868, 8709.34679899007, 8726.127387261271, 8398.0, 8597.355198360117, 8601.956990215056, 8604.666276727101, 8614.353923455734, 2158.0504577985635, 2385.60637494814, 2442.951140977184, 2459.1638842793445, 2470.3700556358103, 2468.049800826677, 2470.950580988379, 2473.418746594549, 2476.232367965927, 2477.1453848422266, 2371.087131454394, 2415.867127307997, 2433.561958847407, 2438.548868459332, 2444.2422848916804, 2159.5464952359944, 2386.200622791367, 2414.61366181411, 2434.8417352872007, 2435.622478515833, 2411.638254261875, 2433.3916520869784, 2437.9634305485433, 2438.878056097188, 2442.3161514775893, 8691.826163476695, 8696.04343522212, 8700.869486957701, 8708.17272359125, 8757.912420875773, 4331.45856767904, 5222.399424066243, 5327.7336133193185, 5356.758945847446, 5370.731463426813, 3558.3594952908466, 3567.839447224881, 3581.767185132958, 3635.327464419091, 3665.890023299305, 942.1755963531909, 988.8763904511936, 1018.6791160784403, 1035.073609119841, 1045.2369770067894, 11841.88158118425, 11862.051035917106, 11874.881251187551, 11881.108916831186, 11891.94054029733, 4742.549457801503, 4748.240281554935, 4819.759012049383, 4853.39332583427, 4882.414110306715, 10100.090991810772, 10117.392956422638, 10121.1903047756, 10131.544080516394, 10134.23993200498, 5395.487428694268, 5434.239206511092, 5482.930828488486, 5487.341519017744, 5489.204065410493, 4370.519242883281, 4540.318952157137, 4757.286407038971, 4901.166801643658, 4916.237983112624, 4028.637422631977, 4053.0759951748746, 4113.814878330422, 4140.3168477201525, 4149.854755083618, 1323.4185148747347, 1350.1628990026247, 1351.0893717285153, 1353.1407556201882, 1360.1227208450493, 811.5820352518458, 877.8156587116777, 895.2077411490748, 932.0958670090001, 936.9013409961636, 5628.774849005994, 5699.658020518699, 5837.9124313135335, 5888.440598143169, 5930.28836539618, 771.7800426878313, 851.3317046118767, 929.5631053404888, 939.182910867536, 974.5955428497236, 3828.559715632682, 3908.2965066288343, 3968.464257325282, 3974.741641793274, 3980.840766369313, 8369.372297077743, 10509.159267258601, 10601.416922069206, 10623.52194151265, 10628.0, 10208.846867296996, 10379.896201038044, 10476.371417714961, 10494.42280674555, 10654.733631659115, 8175.0, 8232.464889782144, 8249.958750206331, 8250.216229458192, 8325.83348333042, 5505.779768809202, 5598.888022239451, 5607.971960140173, 5703.058995265735, 5711.48596626289, 9224.584893679934, 9342.53287335644, 9439.008904064975, 9553.092456216647, 9557.856632150662, 8818.294536437197, 9444.622215111318, 9619.422834629944, 9631.325807193429, 9717.951410243186, 6824.897626535705, 6984.196817365864, 7304.926950730637, 7437.070366204224, 7581.203973582695, 8177.836443270982, 8186.590670466572, 8249.381296402913, 8263.049749278909, 8264.669413223404, 7116.146062472537, 7212.89180662301, 7243.0946131733535, 7323.267673232847, 7378.262173782578, 6066.666333351708, 7079.2566780487305, 7098.0, 7225.6387180641805, 7244.384227340735, 6770.627615480998, 6890.2420733720255, 6906.965465172642, 6984.755533556301, 6989.405900498515, 4663.603593694574, 4756.405449318835, 4790.4251489821445, 4876.609871210224, 4907.705537667752, 4542.591166795004, 4577.176108300538, 4625.907481850411, 4632.652551058617, 4768.880777980578], "value": 4548.95590795235, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.56480533682196, 326.56566766200956, 332.38508758796223, 334.01632193191045, 337.5679130712689, 5444.863878403042, 6544.770933017346, 6574.737010519573, 6575.901361479576, 6595.4393876520435, 6462.321456247097, 6471.9029214561815, 6487.578307410015, 6489.123968264283, 6515.218173819137, 4577.816887324511, 4646.25659894417, 4664.416947881515, 4712.740799256039, 4745.525447455261, 3862.439946207798, 4653.162430762462, 4715.009847931939, 4751.429828420594, 4776.0, 4721.362616046841, 4765.809367625291, 4778.235482322829, 4782.210935195689, 4785.186518291899, 4261.296886013835, 4553.521880202581, 4561.133384656904, 4658.557437043475, 4666.2533994561045, 4041.4948131483566, 4043.534993475729, 4047.494063242095, 4050.696197785178, 4056.5334986476637, 6178.38216178383, 6197.101420294077, 6249.187605611241, 6252.093446450284, 6253.687315634245, 5653.095504719226, 5654.151877218409, 5662.17898404729, 5664.0, 5665.5184309333345, 6974.860502789967, 6975.79072627822, 6978.302169783032, 6991.510594258404, 6991.930080699179, 4355.12897420514, 4514.074614704011, 4519.299508576176, 4537.591616754508, 4540.704854184501, 7235.927640723578, 7307.561546307185, 7383.520071195355, 7398.038255026812, 7409.110906691231, 3600.85596576134, 4011.398290256485, 4590.98998220391, 4592.805870473634, 4605.746683932416, 4644.326572646947, 4646.303054541846, 4650.069986002813, 4651.441826980784, 4661.627069834405, 5550.805721799798, 5555.388907220203, 5569.582281328917, 5572.359178694502, 5577.972110139423, 6672.199336079701, 6983.790486285419, 7002.369786719118, 7009.894851577231, 7017.052697885822, 1598.696247712931, 1610.790597222377, 1624.4964061141084, 1625.2605064695535, 1633.6569320442602, 872.9825403492022, 1012.018342208057, 1015.4668798880647, 1028.454918892988, 1039.4542864995938, 5952.613080149777, 6382.489400847921, 6532.542722009509, 6553.475721942234, 6641.402273795285, 7264.200937896827, 7330.157031941395, 7334.486585938933, 7370.815729606803, 7473.775786726407, 6650.767223147167, 6661.967395053774, 6800.115984921928, 6807.285235050347, 6807.285235050347, 3829.21501092281, 3869.1101046759404, 3873.3996230584303, 3878.5733569307354, 3880.320943834874, 7397.593132377771, 7647.235276472308, 7672.923270767333, 7684.231576842271, 7692.46152769312, 7479.626018699043, 7524.887126693104, 7534.095908491016, 7556.093268807778, 7581.090269167625, 7624.847503049908, 7641.006669132978, 7654.387648988068, 7657.846843063219, 7670.539767613849, 7267.890981635385, 7271.781846544613, 7277.0, 7285.0, 7306.4520160989205, 6913.896291555731, 7191.748288809867, 7203.0, 7221.747238846615, 7230.927690723025, 7607.695692172251, 7650.961745191238, 7654.387648988179, 7665.923340766521, 7676.232376762279, 4761.928566072745, 4828.4688684244, 4832.8791780205775, 4846.345743324606, 4853.9029219416125, 7202.279772022755, 7216.3505284524645, 7220.638968051681, 7226.638668066681, 7238.746643867439, 6674.16572928384, 6957.547759395623, 6969.372756451944, 6969.54697944632, 6981.476389270723, 6882.7591034313555, 6923.515353925177, 6924.169099707866, 6925.411340036153, 6930.48021398387, 5449.836504904859, 6750.831229219309, 6771.187457505131, 6783.762568310085, 6789.694463749044, 6682.097916781172, 6936.809067828855, 6937.7571784987285, 6946.0, 6946.409555187865, 6577.901331479936, 6759.493038022267, 6803.455723542006, 6832.692528836314, 6843.418309443753], "value": 5090.522594135732, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [613.2855223664433, 616.5560796226717, 656.510899379962, 668.9565178263416, 746.3581320064745, 14577.0, 14772.335244913978, 14840.628984275398, 14847.925760371196, 14851.331690073946, 11165.441727913612, 13694.178349299049, 13709.45162193511, 13726.039177257597, 13752.793708094387, 715.8317795318096, 718.7915504503687, 833.9833203335929, 912.1061359867317, 917.8623206519009, 899.4198741811527, 911.5578944212085, 912.8493798523235, 914.3416739947228, 919.880415545978, 965.1747755668907, 970.8495183246573, 970.9854352184723, 971.6259240192542, 979.6277414582485, 754.6113751417996, 955.4028732042474, 972.9221662267001, 975.707287813653, 975.8438649815995, 721.3147509865648, 723.7864829875199, 727.4871215792854, 728.1807966038207, 731.4258307228853, 13511.66220844477, 14687.0, 14688.926555367261, 14735.92632036833, 14754.704905901823, 12109.818352724717, 12399.75200495994, 12423.689407764788, 12506.687332816662, 12682.048846336469, 10077.748056298578, 10083.092521673085, 10093.747656308651, 10095.444750538718, 10444.477776111164, 3469.167399824024, 3518.36669399508, 3544.8759293424864, 3578.5169002184634, 3612.837422316002, 76388.0, 77335.0, 78262.0, 78288.60855695742, 78304.60847695725, 8245.667014856652, 8815.0, 9436.649775920632, 9480.573374198178, 9543.379680320757, 17025.914870425815, 21427.79377851688, 21531.0, 21639.891800540896, 21995.340139795833, 7304.0, 7366.77899663011, 7412.814679632945, 7421.183669796318, 7429.888551671729, 4141.068259641556, 4206.0115872769875, 4217.051163488252, 4329.960809405783, 4399.23013472641, 3500.632433594487, 3549.3256281306467, 3561.910952226215, 3563.590187128513, 3576.4277715565904, 530.7903378165603, 610.81370182094, 627.5544363501965, 635.5678138865616, 647.5208345824057, 474.7786270156968, 554.4178612456905, 560.5207547546789, 568.4798409455302, 595.4581330988866, 7112.182099058675, 7580.507267027626, 7643.121041080347, 7822.8435431291055, 7927.910036491843, 503.07434320849814, 505.0379027951758, 544.9700266485381, 547.453399517015, 556.4769117029979, 3061.5560743692486, 3580.12286989684, 3659.7255205859665, 3659.9451008234896, 3745.2696724139078, 83103.0, 84302.5784871084, 84393.15606843976, 84400.0, 87465.0, 52225.73887130616, 69089.0, 69976.60046799036, 70106.64946675234, 70514.0, 63846.6807665968, 64357.67821160958, 64655.67672161609, 64777.704445910815, 65837.67081164563, 7241.131064272321, 7437.962810185914, 7478.214787447458, 7508.887366689613, 7550.207228240955, 46252.306215406126, 47107.764461177474, 47140.585782426584, 47365.81585460391, 47736.52263477321, 61518.0, 63671.0, 63920.0, 64490.35509644844, 64785.7324993623, 9599.47595912885, 9802.607895684092, 9830.361026533252, 9833.262505312274, 10165.542550585093, 9793.951030245089, 10139.949300253451, 10569.471526423802, 10585.735356616146, 10631.681049568526, 9233.307501937516, 9619.0, 9767.658131965347, 9822.55798489084, 9904.108630223314, 9330.486823224517, 9812.018798120416, 9814.803703926023, 10001.099901008944, 10029.097381235724, 9312.161905428544, 9716.125548700651, 9734.464604446823, 9747.122758951728, 10246.333988290737, 8283.751487455385, 8367.916320836714, 8520.446170998865, 8639.0, 8647.783805404915, 7933.3653307736395, 8389.538575378412, 8497.2777313929, 8673.82652346937, 8821.250193733347], "value": 6494.799219449608, "unit": "score"}], "name": "dromaeo_css", "value": 7373.214206611346, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 117.0, 113.0, 114.0, 113.0, 115.0, 115.0, 115.0, 133.0], "value": 116.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 122.0, 147.0, 172.0, 114.0, 139.0, 142.0, 117.0, 171.0, 118.0], "value": 139.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 187.0, 185.0, 184.0, 185.0, 185.0, 184.0, 183.0, 184.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 77.0, 75.0, 151.0, 55.0, 56.0, 58.0, 66.0, 132.0, 55.0], "value": 79.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 90.0, 145.0, 78.0, 79.0, 88.0, 127.0, 80.0, 77.0, 133.0], "value": 99.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 84.0, 89.0, 86.0, 86.0, 83.0, 85.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 136.0, 93.0, 92.0, 92.0, 93.0, 93.0, 93.0, 92.0, 109.0], "value": 102.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 86.0, 87.0, 84.0, 85.0, 86.0, 83.0, 83.0, 85.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 64.0, 66.0, 64.0, 65.0, 66.0, 84.0, 61.0, 58.0], "value": 65.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 69.0, 74.0, 73.0, 72.0, 70.0, 67.0, 72.0, 84.0, 100.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 81.0, 81.0, 80.0, 80.0, 81.0, 79.0, 82.0, 84.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 121.0, 129.0, 127.0, 128.0, 123.0, 142.0, 147.0, 165.0, 136.0], "value": 134.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 162.0, 164.0, 167.0, 166.0, 166.0, 163.0, 166.0, 162.0, 162.0], "value": 164.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 68.0, 68.0, 67.0, 68.0, 68.0, 66.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1478.8000000000002, "alertThreshold": 2.0}]} 05:16:28 INFO - Return code: 0 05:16:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:16:28 ERROR - # TBPL SUCCESS # 05:16:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:16:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:16:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:16:28 INFO - 2016-05-30 05:16:28.644 screenresolution[1677:9749] starting screenresolution argv=screenresolution get 05:16:28 INFO - 2016-05-30 05:16:28.647 screenresolution[1677:9749] Display 0: 1600x1200x32@60 05:16:28 INFO - 2016-05-30 05:16:28.652 screenresolution[1678:9752] starting screenresolution argv=screenresolution list 05:16:28 INFO - Available Modes on Display 0 05:16:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:16:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:16:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:16:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:16:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:16:28 INFO - Intel Iris: 05:16:28 INFO - Chipset Model: Intel Iris 05:16:28 INFO - Type: GPU 05:16:28 INFO - Bus: Built-In 05:16:28 INFO - VRAM (Dynamic, Max): 1536 MB 05:16:28 INFO - Vendor: Intel (0x8086) 05:16:28 INFO - Device ID: 0x0a2e 05:16:28 INFO - Revision ID: 0x0009 05:16:28 INFO - Displays: 05:16:28 INFO - Display: 05:16:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:16:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:16:28 INFO - Display Serial Number: 1600x1200 60 05:16:28 INFO - Main Display: Yes 05:16:28 INFO - Mirror: Off 05:16:28 INFO - Online: Yes 05:16:28 INFO - Rotation: Supported 05:16:28 INFO - Return code: 0 05:16:28 INFO - Running post-action listener: _resource_record_post_action 05:16:28 INFO - Running post-run listener: _resource_record_post_run 05:16:29 INFO - Total resource usage - Wall time: 1206s; CPU: 52.0%; Read bytes: 31174144; Write bytes: 652207616; Read time: 195; Write time: 1894 05:16:29 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344742400; Write bytes: 337122816; Read time: 26465; Write time: 745 05:16:29 INFO - run-tests - Wall time: 1176s; CPU: 52.0%; Read bytes: 30252544; Write bytes: 295747584; Read time: 185; Write time: 1093 05:16:29 INFO - Running post-run listener: _upload_blobber_files 05:16:29 INFO - Blob upload gear active. 05:16:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:16:29 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:16:29 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:16:29 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:16:30 INFO - (blobuploader) - INFO - Open directory for files ... 05:16:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:16:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:16:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:16:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:16:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:16:31 INFO - (blobuploader) - INFO - Done attempting. 05:16:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:16:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:16:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:16:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:16:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:16:32 INFO - (blobuploader) - INFO - Done attempting. 05:16:32 INFO - (blobuploader) - INFO - Iteration through files over. 05:16:32 INFO - Return code: 0 05:16:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:16:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:16:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/379020704de813d038407eb0a65caa95b56ec038fbef64165ddef22e3a88c2dd890c904ed2d503a82d95ff9bdfe4be1df0718f923b9489ac6ac013ba93d9924a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ca8ded3953e630e0918e83233b60d14b22085e36379cf6021484cd40b8b54e8aa38effd9e0eb13c567c8c2e1ac3380f195781d0c7a98d4d4d88ad80b459f9db3"} 05:16:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:16:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:16:32 INFO - Contents: 05:16:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/379020704de813d038407eb0a65caa95b56ec038fbef64165ddef22e3a88c2dd890c904ed2d503a82d95ff9bdfe4be1df0718f923b9489ac6ac013ba93d9924a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ca8ded3953e630e0918e83233b60d14b22085e36379cf6021484cd40b8b54e8aa38effd9e0eb13c567c8c2e1ac3380f195781d0c7a98d4d4d88ad80b459f9db3"} 05:16:32 INFO - Running post-run listener: copy_logs_to_upload_dir 05:16:32 INFO - Copying logs to upload dir... 05:16:32 INFO - mkdir: /builds/slave/test/build/upload/logs 05:16:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.668612 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-05-30 05:16:32.535036) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 05:16:32.538149) ========= 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.UOs9vQPzh3/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.rSUWVnnxFY/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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/Mozilla-Aurora/sha512/379020704de813d038407eb0a65caa95b56ec038fbef64165ddef22e3a88c2dd890c904ed2d503a82d95ff9bdfe4be1df0718f923b9489ac6ac013ba93d9924a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ca8ded3953e630e0918e83233b60d14b22085e36379cf6021484cd40b8b54e8aa38effd9e0eb13c567c8c2e1ac3380f195781d0c7a98d4d4d88ad80b459f9db3"} build_url:https://queue.taskcluster.net/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011367 build_url: 'https://queue.taskcluster.net/v1/task/R_xEPeS0St-PtPILhgjnyQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/379020704de813d038407eb0a65caa95b56ec038fbef64165ddef22e3a88c2dd890c904ed2d503a82d95ff9bdfe4be1df0718f923b9489ac6ac013ba93d9924a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ca8ded3953e630e0918e83233b60d14b22085e36379cf6021484cd40b8b54e8aa38effd9e0eb13c567c8c2e1ac3380f195781d0c7a98d4d4d88ad80b459f9db3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 05:16:32.569220) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:16:32.569537) ========= 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.UOs9vQPzh3/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.rSUWVnnxFY/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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.005039 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:16:32.617632) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 05:16:32.617955) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 05:16:32.618292) ========= ========= Total master_lag: 0.16 =========